"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2024/04/17 01:02:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0" 2024/04/17 01:02:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/17 01:02:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0.bin" 2024/04/17 01:02:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0" 2024/04/17 01:02:58 DEBUG : Creating backend with remote "/tmp/rclone1275999257" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:02:58 NOTICE: sub dir: Skipped make directory as --dry-run is set 2024/04/17 01:02:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:02:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:02:58 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/04/17 01:02:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:02:59 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/17 01:02:59 DEBUG : sub dir: Making directory 2024/04/17 01:02:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:02:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:02:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:02:59 DEBUG : sub dir/hello world: md5 = 47bcc598219b958420e4204f2be0d4eb OK 2024/04/17 01:02:59 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:02:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.39s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:00 DEBUG : Creating backend with remote "/non-existing" 2024/04/17 01:03:00 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/04/17 01:03:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.34s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:00 DEBUG : sub dir: Making directory 2024/04/17 01:03:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:01 DEBUG : sub dir/hello world: md5 = 6f0036b5d134b15b5e83828cb1d0a4f7 OK 2024/04/17 01:03:01 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyNoTraverse (1.18s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:02 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Running all checks before starting transfers 2024/04/17 01:03:02 DEBUG : sub dir: Making directory 2024/04/17 01:03:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:02 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Checks finished, now starting transfers 2024/04/17 01:03:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:02 DEBUG : sub dir/hello world: md5 = 38613c50636172fcfa1e74233527f022 OK 2024/04/17 01:03:02 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyCheckFirst (1.21s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:03 ERROR : Ignoring --no-traverse with sync 2024/04/17 01:03:03 DEBUG : sub dir: Making directory 2024/04/17 01:03:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:03 DEBUG : sub dir/hello world: md5 = dd1c28bdedfaa32022819e2e6baa2f25 OK 2024/04/17 01:03:03 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:03:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncNoTraverse (1.25s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:04 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/04/17 01:03:04 DEBUG : sub dir: Making directory 2024/04/17 01:03:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:05 DEBUG : hello world2: md5 = 506f0a71bfa19fcbc3ebf27acca6b99c OK 2024/04/17 01:03:05 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyWithDepth (1.31s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:06 DEBUG : potato2: md5 = f9ac323d53cbf808d074fabf39b9a232 OK 2024/04/17 01:03:06 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.41s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:07 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:07 DEBUG : potato2: md5 = 8eab018d473db30fff28b4ffe45915fd OK 2024/04/17 01:03:07 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (0.96s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:08 DEBUG : sub dir2: Making directory with metadata 2024/04/17 01:03:08 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/17 01:03:08 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/17 01:03:08 DEBUG : sub dir: Making directory 2024/04/17 01:03:08 DEBUG : sub dir2: Making directory 2024/04/17 01:03:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:08 DEBUG : sub dir/hello world: md5 = 99579d842858ff3132397af9b8be2d10 OK 2024/04/17 01:03:08 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:03:08 DEBUG : sub dir2: Making directory 2024/04/17 01:03:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': copied 1 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/17 01:03:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (0.99s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:09 DEBUG : sub dir2: Making directory with metadata 2024/04/17 01:03:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/17 01:03:09 DEBUG : sub dir: Making directory 2024/04/17 01:03:09 DEBUG : sub dir2: Making directory 2024/04/17 01:03:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:09 DEBUG : sub dir/hello world: md5 = a557730cc99b3f87307613aaf03265af OK 2024/04/17 01:03:09 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:03:09 INFO : sub dir/hello world: Deleted 2024/04/17 01:03:09 DEBUG : sub dir: Making directory 2024/04/17 01:03:09 DEBUG : sub dir2: Making directory 2024/04/17 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': copied 2 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/17 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/04/17 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.25s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:351: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.10s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:10 DEBUG : sub dir2: Making directory with metadata 2024/04/17 01:03:10 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/17 01:03:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/17 01:03:10 DEBUG : sub dir: Making directory 2024/04/17 01:03:10 DEBUG : sub dir2: Making directory 2024/04/17 01:03:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:10 DEBUG : sub dir/hello world: md5 = cf81cf41cb9bf445d4f0aaed5c8c5bf3 OK 2024/04/17 01:03:10 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:03:10 DEBUG : sub dir2: Making directory 2024/04/17 01:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': copied 1 directories 2024/04/17 01:03:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/17 01:03:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (0.93s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.32s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:12 DEBUG : sub dir/hello world: md5 = fca43b70c7e14aeb29f572f978026771 OK 2024/04/17 01:03:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fapepiz0fisatit1pewesan5" 2024/04/17 01:03:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fapepiz0fisatit1pewesan5.bin" 2024/04/17 01:03:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fapepiz0fisatit1pewesan5" sync_test.go:487: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0' -> Encrypted drive 'TestCryptSwift:rclone-test-fapepiz0fisatit1pewesan5' 2024/04/17 01:03:13 DEBUG : sub dir: Making directory 2024/04/17 01:03:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fapepiz0fisatit1pewesan5': Waiting for checks to finish 2024/04/17 01:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fapepiz0fisatit1pewesan5': Waiting for transfers to finish 2024/04/17 01:03:14 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fapepiz0fisatit1pewesan5': Purge remote 2024/04/17 01:03:14 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:14 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (4.09s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:16 DEBUG : sub dir/hello world: md5 = ad767298b4c0a0c3cb673ad25204b04a OK 2024/04/17 01:03:16 ERROR : : error listing: directory not found 2024/04/17 01:03:16 DEBUG : Local file system at /tmp/rclone1275999257: Making directory 2024/04/17 01:03:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyAfterDelete (1.20s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:17 DEBUG : sub dir/hello world: md5 = bb4d49d96cda5f9d2f774be47449ad7e OK 2024/04/17 01:03:17 DEBUG : sub dir: Making directory 2024/04/17 01:03:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:03:17 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for checks to finish 2024/04/17 01:03:17 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for transfers to finish 2024/04/17 01:03:17 DEBUG : sub dir/hello world.zolabal8.partial: renamed to: sub dir/hello world 2024/04/17 01:03:17 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyRedownload (1.32s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:18 DEBUG : check sum: Need to transfer - File not found at Destination 2024/04/17 01:03:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:18 DEBUG : check sum: md5 = 519a690caa9a4136389a59c5af79acbd OK 2024/04/17 01:03:18 INFO : check sum: Copied (new) 2024/04/17 01:03:18 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:19 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/04/17 01:03:19 DEBUG : check sum: Size of src and dst objects identical 2024/04/17 01:03:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:19 DEBUG : check sum: Unchanged skipping 2024/04/17 01:03:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:19 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (1.67s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:20 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/04/17 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:20 DEBUG : sizeonly: md5 = 2006d39c5228fb4d1a58f26943630826 OK 2024/04/17 01:03:20 INFO : sizeonly: Copied (new) 2024/04/17 01:03:20 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:20 DEBUG : sizeonly: Sizes identical 2024/04/17 01:03:20 DEBUG : sizeonly: Unchanged skipping 2024/04/17 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:20 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:20 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncSizeOnly (1.54s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:21 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/04/17 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:21 DEBUG : ignore-size: md5 = 51df0ee7d88e6de8d520022b7a2aa035 OK 2024/04/17 01:03:21 INFO : ignore-size: Copied (new) 2024/04/17 01:03:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:22 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:22 DEBUG : ignore-size: Unchanged skipping 2024/04/17 01:03:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:22 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:22 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.70s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:23 DEBUG : existing: md5 = 65b1eabdf83de6454ebc7423ea385fce OK 2024/04/17 01:03:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:24 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:24 DEBUG : existing: Unchanged skipping 2024/04/17 01:03:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:24 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:24 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:24 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/04/17 01:03:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:24 DEBUG : existing: md5 = 83f7dee270c997d4ef1c95867d1617b2 OK 2024/04/17 01:03:24 INFO : existing: Copied (replaced existing) 2024/04/17 01:03:24 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (2.01s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:25 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/17 01:03:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:25 DEBUG : existing: md5 = 168af4e5e23a2b8ec9c20a4d53700def OK 2024/04/17 01:03:25 INFO : existing: Copied (new) 2024/04/17 01:03:25 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:26 DEBUG : existing: Destination exists, skipping 2024/04/17 01:03:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:26 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:26 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.59s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:27 DEBUG : b/potato: md5 = 89bcf38318c6b75e71a3ebfb9c629756 OK 2024/04/17 01:03:27 DEBUG : c/non empty space: md5 = a5b03a74451bbf730f93e9bda1be7c10 OK 2024/04/17 01:03:27 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/17 01:03:27 DEBUG : a: Making directory 2024/04/17 01:03:27 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:27 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:27 DEBUG : c/non empty space: Unchanged skipping 2024/04/17 01:03:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:28 DEBUG : a/potato2: md5 = 90030f198159dff794220fa2eb75435b OK 2024/04/17 01:03:28 INFO : a/potato2: Copied (new) 2024/04/17 01:03:28 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:28 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (2.41s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:29 DEBUG : empty space: md5 = 0199591a79f7d8e7e1a70a7a2e3d6061 OK 2024/04/17 01:03:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:30 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/17 01:03:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:30 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/04/17 01:03:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:30 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/17 01:03:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:30 DEBUG : empty space: md5 = 522287876ccdcb73099c46683ed9d142 OK 2024/04/17 01:03:30 INFO : empty space: Copied (replaced existing) 2024/04/17 01:03:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.57s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:811: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.10s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:32 DEBUG : foo: md5 = 91ed17f6beef796dacf0e475725ff7cf OK 2024/04/17 01:03:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:32 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/17 01:03:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:32 DEBUG : foo: md5 = b870fd2b012ad18d49211945b0ba9b88 OK 2024/04/17 01:03:32 INFO : foo: Copied (replaced existing) 2024/04/17 01:03:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (1.93s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:34 DEBUG : empty space: md5 = bf7b7103a036e53973db29cfab94af70 OK 2024/04/17 01:03:34 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/17 01:03:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:34 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:34 DEBUG : potato: md5 = 04643cc06af95b3e7c736eb250ac3548 OK 2024/04/17 01:03:34 INFO : potato: Copied (new) 2024/04/17 01:03:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (1.95s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:36 DEBUG : potato: md5 = 7b92f7eeaa7dbb294592c39eafbef493 OK 2024/04/17 01:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:36 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/04/17 01:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:37 DEBUG : potato: md5 = 1e4d3da5395c867f2c8e57e96b98418b OK 2024/04/17 01:03:37 INFO : potato: Copied (replaced existing) 2024/04/17 01:03:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (2.07s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:38 DEBUG : potato: md5 = e71c7e1769e489b5e1b7b086c827d2f2 OK 2024/04/17 01:03:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:38 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/17 01:03:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:38 DEBUG : potato: md5 = a3fd6ec489361122ff202a501d456e71 OK 2024/04/17 01:03:38 INFO : potato: Copied (replaced existing) 2024/04/17 01:03:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.53s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:39 DEBUG : potato: md5 = 656fb87081b2c0bae622a0eefcb08d59 OK 2024/04/17 01:03:40 DEBUG : empty space: md5 = 357c6121986171c3363e5c3fcf895c5c OK 2024/04/17 01:03:40 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:40 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/04/17 01:03:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:40 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:40 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:40 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.79s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:41 DEBUG : potato: md5 = d047647c8a120144ea39944a15b41be7 OK 2024/04/17 01:03:41 DEBUG : empty space: md5 = ef0141376f2181fb11db2b1d44af8d8d OK 2024/04/17 01:03:42 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:42 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:42 DEBUG : potato2: md5 = ececa2a6895b39a7e3e48f53f8957749 OK 2024/04/17 01:03:42 INFO : potato2: Copied (new) 2024/04/17 01:03:42 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:42 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.23s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:43 DEBUG : b/potato: md5 = 45870eeb9395e1fcda59e3b0bcfceec9 OK 2024/04/17 01:03:43 DEBUG : c/non empty space: md5 = 9add65193e53aaedf9cdd839b0c97cf4 OK 2024/04/17 01:03:43 DEBUG : d: Making directory 2024/04/17 01:03:43 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/04/17 01:03:44 DEBUG : a: Making directory 2024/04/17 01:03:44 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:44 DEBUG : a/potato2: md5 = 274592f441c4e545ddb7844032c13ac5 OK 2024/04/17 01:03:44 INFO : a/potato2: Copied (new) 2024/04/17 01:03:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:44 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:44 DEBUG : c/non empty space: Unchanged skipping 2024/04/17 01:03:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:44 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:44 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.18s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:45 DEBUG : b/potato: md5 = 363f1fb6f31bb29375ef6b9f6b9df64e OK 2024/04/17 01:03:46 DEBUG : c/non empty space: md5 = a6f21b5a0ae5ab82d46f81d632c1086e OK 2024/04/17 01:03:46 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/17 01:03:46 DEBUG : a: Making directory 2024/04/17 01:03:46 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:46 DEBUG : a/potato2: md5 = 78685fa10250573eea1171c1a9ccf7ae OK 2024/04/17 01:03:46 INFO : a/potato2: Copied (new) 2024/04/17 01:03:46 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:46 DEBUG : c/non empty space: Unchanged skipping 2024/04/17 01:03:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:46 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': not deleting files as there were IO errors 2024/04/17 01:03:46 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.31s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:48 DEBUG : potato: md5 = e15913a4ca1259b727d3262c36de5512 OK 2024/04/17 01:03:48 DEBUG : empty space: md5 = 37cd530214e680467cc060d01c58d2aa OK 2024/04/17 01:03:48 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:48 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:49 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:49 INFO : potato: Deleted 2024/04/17 01:03:49 DEBUG : potato2: md5 = b10df58015d9b99beb15da3cbd881bf1 OK 2024/04/17 01:03:49 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (2.27s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:50 DEBUG : potato: md5 = 1b68a1bf864a475d58c087ae13819587 OK 2024/04/17 01:03:50 DEBUG : empty space: md5 = a08a52a52a92197995aedda3e916646d OK 2024/04/17 01:03:50 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:51 INFO : potato: Deleted 2024/04/17 01:03:51 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:51 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:51 DEBUG : potato2: md5 = a1b5e9ecf69b40eb2c714182bcdae397 OK 2024/04/17 01:03:51 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (2.78s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:53 DEBUG : potato: md5 = 5989150417ca9f3936abe210b99fc8ae OK 2024/04/17 01:03:53 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:03:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:53 DEBUG : potato2: md5 = d70c9072d2d1e89ff523ee6c8d0e5265 OK 2024/04/17 01:03:53 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.88s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:55 DEBUG : potato2: md5 = dd97f007027f4f3c19fd9089c316423c OK 2024/04/17 01:03:55 DEBUG : empty space: md5 = ccad313af30c6f5e542eb283c13cc827 OK 2024/04/17 01:03:55 DEBUG : enormous: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : enormous: Excluded 2024/04/17 01:03:55 DEBUG : potato2: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : potato2: Excluded 2024/04/17 01:03:55 DEBUG : potato2: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : potato2: Excluded 2024/04/17 01:03:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:55 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:55 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:55 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:55 DEBUG : enormous: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : enormous: Excluded 2024/04/17 01:03:55 DEBUG : potato2: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : potato2: Excluded 2024/04/17 01:03:55 DEBUG : potato2: Excluded (Size Filter) 2024/04/17 01:03:55 DEBUG : potato2: Excluded 2024/04/17 01:03:55 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for checks to finish 2024/04/17 01:03:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:56 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:56 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for transfers to finish 2024/04/17 01:03:56 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:56 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncWithExclude (1.93s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:57 DEBUG : potato2: md5 = ed65367a3f54d1f540a961963183bf9c OK 2024/04/17 01:03:57 DEBUG : empty space: md5 = 7616e5545cae86e0cd971d3bae294b6a OK 2024/04/17 01:03:57 DEBUG : enormous: md5 = 7f21c4134e5d1e51cd9d1e0dfb72000a OK 2024/04/17 01:03:57 DEBUG : enormous: Excluded (Size Filter) 2024/04/17 01:03:57 DEBUG : enormous: Excluded 2024/04/17 01:03:57 DEBUG : potato2: Excluded (Size Filter) 2024/04/17 01:03:57 DEBUG : potato2: Excluded 2024/04/17 01:03:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:03:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:58 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:03:58 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:58 INFO : enormous: Deleted 2024/04/17 01:03:58 INFO : potato2: Deleted 2024/04/17 01:03:58 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:03:58 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for checks to finish 2024/04/17 01:03:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:03:58 DEBUG : empty space: Unchanged skipping 2024/04/17 01:03:58 DEBUG : Local file system at /tmp/rclone1275999257: Waiting for transfers to finish 2024/04/17 01:03:58 DEBUG : Waiting for deletions to finish 2024/04/17 01:03:58 INFO : enormous: Deleted 2024/04/17 01:03:58 INFO : potato2: Deleted 2024/04/17 01:03:58 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.96s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:00 DEBUG : one: md5 = 4b36e582f5ef7210c6588526ccf498ec OK 2024/04/17 01:04:00 DEBUG : two: md5 = 0e7fc670649c97ee8e7978c92f4101d1 OK 2024/04/17 01:04:00 DEBUG : three: md5 = f25eacb75c5d5c153b2df8a53d6bc8a1 OK 2024/04/17 01:04:01 DEBUG : four: md5 = 9e140dbdd74f1c66d0f8921c6a4d5da2 OK 2024/04/17 01:04:02 DEBUG : five: Need to transfer - File not found at Destination 2024/04/17 01:04:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:02 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/04/17 01:04:02 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/04/17 01:04:02 DEBUG : one: Destination is newer than source, skipping 2024/04/17 01:04:02 DEBUG : three: Sizes identical 2024/04/17 01:04:02 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/04/17 01:04:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:02 DEBUG : five: md5 = 5af8c1cb3713a6d4af608bc1d7c9bb6e OK 2024/04/17 01:04:02 INFO : five: Copied (new) 2024/04/17 01:04:02 DEBUG : four: md5 = dc899087b581e0153179b85b84d769e3 OK 2024/04/17 01:04:02 INFO : four: Copied (replaced existing) 2024/04/17 01:04:02 DEBUG : two: md5 = a2c84e922f1dbe9f718f6c668a23082d OK 2024/04/17 01:04:02 INFO : two: Copied (replaced existing) 2024/04/17 01:04:02 DEBUG : Waiting for deletions to finish sync_test.go:1244: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.53s) === 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-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:1327: Can track renames: false 2024/04/17 01:04:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/17 01:04:04 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/17 01:04:04 DEBUG : yam: Need to transfer - File not found at Destination 2024/04/17 01:04:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:04 DEBUG : potato: md5 = 17742f6d84b7c8e001d6de91c94d84df OK 2024/04/17 01:04:04 INFO : potato: Copied (new) 2024/04/17 01:04:05 DEBUG : yam: md5 = a184c186e8f024cfe79e4f787040f690 OK 2024/04/17 01:04:05 INFO : yam: Copied (new) 2024/04/17 01:04:05 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:05 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/17 01:04:05 DEBUG : yaml: Need to transfer - File not found at Destination 2024/04/17 01:04:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:05 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:05 DEBUG : potato: Unchanged skipping 2024/04/17 01:04:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:05 DEBUG : yaml: md5 = b1a6a481a9769ea077ed4991b5c8fbbf OK 2024/04/17 01:04:05 INFO : yaml: Copied (new) 2024/04/17 01:04:05 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:06 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (3.01s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:1399: Can track renames: true 2024/04/17 01:04:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Making map for --track-renames 2024/04/17 01:04:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Finished making map for --track-renames 2024/04/17 01:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:07 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/17 01:04:07 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/04/17 01:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for renames to finish 2024/04/17 01:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:07 DEBUG : potato: md5 = 57ccb5c3ce3726e738d007c6f353252f OK 2024/04/17 01:04:07 INFO : potato: Copied (new) 2024/04/17 01:04:07 DEBUG : yam: md5 = 3209dc777f49c569d36ea4cd1c1c52d6 OK 2024/04/17 01:04:07 INFO : yam: Copied (new) 2024/04/17 01:04:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Making map for --track-renames 2024/04/17 01:04:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Finished making map for --track-renames 2024/04/17 01:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:07 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:07 DEBUG : potato: Unchanged skipping 2024/04/17 01:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for renames to finish 2024/04/17 01:04:08 INFO : yam: Copied (server-side copy) to: yaml 2024/04/17 01:04:08 INFO : yam: Deleted 2024/04/17 01:04:08 INFO : yaml: Renamed from "yam" 2024/04/17 01:04:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:08 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.08s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:1438: Can track renames: true 2024/04/17 01:04:09 DEBUG : sub: Making directory 2024/04/17 01:04:09 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Making map for --track-renames 2024/04/17 01:04:09 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Finished making map for --track-renames 2024/04/17 01:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:09 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/17 01:04:09 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/04/17 01:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for renames to finish 2024/04/17 01:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:09 DEBUG : potato: md5 = f6f4debdd956ced91bcc6626dcab937c OK 2024/04/17 01:04:09 INFO : potato: Copied (new) 2024/04/17 01:04:10 DEBUG : sub/yam: md5 = 247935271d9f388b599bc34e0fbd6c8f OK 2024/04/17 01:04:10 INFO : sub/yam: Copied (new) 2024/04/17 01:04:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:10 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:10 DEBUG : potato: Unchanged skipping 2024/04/17 01:04:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Making map for --track-renames 2024/04/17 01:04:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Finished making map for --track-renames 2024/04/17 01:04:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for renames to finish 2024/04/17 01:04:10 INFO : sub/yam: Copied (server-side copy) to: yam 2024/04/17 01:04:10 INFO : sub/yam: Deleted 2024/04/17 01:04:10 INFO : yam: Renamed from "sub/yam" 2024/04/17 01:04:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.12s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:12 DEBUG : Creating backend with remote "/tmp/rclone1275999257/dir1" 2024/04/17 01:04:12 DEBUG : Creating backend with remote "/tmp/rclone1275999257/dir2" 2024/04/17 01:04:12 DEBUG : Local file system at /tmp/rclone1275999257/dir2: Using server-side directory move 2024/04/17 01:04:12 INFO : Local file system at /tmp/rclone1275999257/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/17 01:04:12 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/17 01:04:12 DEBUG : Local file system at /tmp/rclone1275999257/dir2: Waiting for checks to finish 2024/04/17 01:04:12 INFO : file1.txt: Moved (server-side) 2024/04/17 01:04:12 DEBUG : Local file system at /tmp/rclone1275999257/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.11s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:12 DEBUG : nested: Making directory 2024/04/17 01:04:12 DEBUG : sub dir: Making directory 2024/04/17 01:04:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:04:12 DEBUG : nested/sub dir: Making directory 2024/04/17 01:04:12 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/17 01:04:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:13 DEBUG : sub dir/hello world: md5 = 30dab9529c5c8dbb329090a5b5d05815 OK 2024/04/17 01:04:13 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:04:13 INFO : sub dir/hello world: Deleted 2024/04/17 01:04:13 DEBUG : nested/sub dir/file: md5 = 6a69431a5a035fb2d3843ba8db999dd2 OK 2024/04/17 01:04:13 INFO : nested/sub dir/file: Copied (new) 2024/04/17 01:04:13 INFO : nested/sub dir/file: Deleted 2024/04/17 01:04:13 INFO : sub dir: Removing directory 2024/04/17 01:04:13 INFO : nested/sub dir: Removing directory 2024/04/17 01:04:13 INFO : nested: Removing directory 2024/04/17 01:04:13 DEBUG : Local file system at /tmp/rclone1275999257: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (1.34s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:14 DEBUG : nested: Making directory 2024/04/17 01:04:14 DEBUG : sub dir: Making directory 2024/04/17 01:04:14 DEBUG : nested/sub dir: Making directory 2024/04/17 01:04:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/17 01:04:14 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/17 01:04:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:14 DEBUG : nested/sub dir/file: md5 = 96a6823902e9af2fac8e83d95632c3fd OK 2024/04/17 01:04:14 INFO : nested/sub dir/file: Copied (new) 2024/04/17 01:04:14 INFO : nested/sub dir/file: Deleted 2024/04/17 01:04:14 DEBUG : sub dir/hello world: md5 = e0f76ddc9c568b6241a44ccdc8044234 OK 2024/04/17 01:04:14 INFO : sub dir/hello world: Copied (new) 2024/04/17 01:04:14 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.55s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:15 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/17 01:04:15 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/04/17 01:04:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:15 DEBUG : existing: md5 = 7efb4f8f0f4fd397a1ca9401b2384234 OK 2024/04/17 01:04:15 INFO : existing: Copied (new) 2024/04/17 01:04:15 INFO : existing: Deleted 2024/04/17 01:04:16 DEBUG : existing-b: md5 = 6cec33b0e06cb57fedb9076fc93e2b62 OK 2024/04/17 01:04:16 INFO : existing-b: Copied (new) 2024/04/17 01:04:16 INFO : existing-b: Deleted 2024/04/17 01:04:16 DEBUG : existing: Destination exists, skipping 2024/04/17 01:04:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:16 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/04/17 01:04:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:16 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (1.86s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-talofuq0batutaq3dufukod7" 2024/04/17 01:04:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-talofuq0batutaq3dufukod7.bin" 2024/04/17 01:04:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-talofuq0batutaq3dufukod7" 2024/04/17 01:04:19 DEBUG : potato2: md5 = 57f1f7d46d5a1a1557c760e90fe76f70 OK 2024/04/17 01:04:19 DEBUG : empty space: md5 = f0e7ec29fb80a798a43ea8d08e16db9d OK 2024/04/17 01:04:19 DEBUG : potato3: md5 = db19b8a0f98c99e4ee3f296ce9bbc0ec OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0' -> Encrypted drive 'TestCryptSwift:rclone-test-talofuq0batutaq3dufukod7' 2024/04/17 01:04:20 DEBUG : empty space: md5 = c4224dbd5e2b852f7abb8ad88af51bf4 OK 2024/04/17 01:04:20 DEBUG : potato3: md5 = c7429ffbdd78ed099a8466235461ca34 OK 2024/04/17 01:04:21 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:21 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/17 01:04:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-talofuq0batutaq3dufukod7': Waiting for checks to finish 2024/04/17 01:04:21 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:21 DEBUG : empty space: Unchanged skipping 2024/04/17 01:04:21 INFO : empty space: Deleted 2024/04/17 01:04:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-talofuq0batutaq3dufukod7': Waiting for transfers to finish 2024/04/17 01:04:21 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:21 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:21 INFO : potato3: Deleted 2024/04/17 01:04:21 INFO : potato2: Deleted 2024/04/17 01:04:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qacidiq1ruvonax9gadukid8" 2024/04/17 01:04:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qacidiq1ruvonax9gadukid8.bin" 2024/04/17 01:04:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qacidiq1ruvonax9gadukid8" 2024/04/17 01:04:23 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/17 01:04:23 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:23 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/17 01:04:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qacidiq1ruvonax9gadukid8': Waiting for checks to finish 2024/04/17 01:04:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qacidiq1ruvonax9gadukid8': Waiting for transfers to finish 2024/04/17 01:04:24 INFO : empty space: Copied (server-side copy) 2024/04/17 01:04:24 INFO : empty space: Deleted 2024/04/17 01:04:24 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:24 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:24 INFO : potato2: Deleted 2024/04/17 01:04:24 INFO : potato3: Deleted 2024/04/17 01:04:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qacidiq1ruvonax9gadukid8': Purge remote 2024/04/17 01:04:24 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:25 INFO : empty space.bin: Deleted 2024/04/17 01:04:25 INFO : potato2.bin: Deleted 2024/04/17 01:04:25 INFO : potato3.bin: Deleted 2024/04/17 01:04:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-talofuq0batutaq3dufukod7': Purge remote 2024/04/17 01:04:25 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (8.05s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wisitub9qeyomid6madovaj8" 2024/04/17 01:04:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wisitub9qeyomid6madovaj8.bin" 2024/04/17 01:04:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wisitub9qeyomid6madovaj8" 2024/04/17 01:04:27 DEBUG : potato2: md5 = 809b38315ae463b188169ea03912fbc2 OK 2024/04/17 01:04:27 DEBUG : empty space: md5 = 63185c75711715a9db5fa43b00eea0a3 OK 2024/04/17 01:04:27 DEBUG : potato3: md5 = 2c697cedaa6e3b2d42e06cb006e7b8f3 OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0' -> Encrypted drive 'TestCryptSwift:rclone-test-wisitub9qeyomid6madovaj8' 2024/04/17 01:04:28 DEBUG : empty space: md5 = 523eee746fa5118d0067755801907f25 OK 2024/04/17 01:04:28 DEBUG : potato3: md5 = 6533700b54b37365205769987d079831 OK 2024/04/17 01:04:28 DEBUG : empty space: Excluded (Size Filter) 2024/04/17 01:04:28 DEBUG : empty space: Excluded 2024/04/17 01:04:28 DEBUG : empty space: Excluded (Size Filter) 2024/04/17 01:04:28 DEBUG : empty space: Excluded 2024/04/17 01:04:28 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:28 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/17 01:04:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wisitub9qeyomid6madovaj8': Waiting for checks to finish 2024/04/17 01:04:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wisitub9qeyomid6madovaj8': Waiting for transfers to finish 2024/04/17 01:04:29 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:29 INFO : potato3: Deleted 2024/04/17 01:04:29 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:29 INFO : potato2: Deleted 2024/04/17 01:04:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tupoqot0qituxet1paxataj3" 2024/04/17 01:04:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tupoqot0qituxet1paxataj3.bin" 2024/04/17 01:04:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tupoqot0qituxet1paxataj3" 2024/04/17 01:04:31 DEBUG : empty space: Excluded (Size Filter) 2024/04/17 01:04:31 DEBUG : empty space: Excluded 2024/04/17 01:04:31 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:31 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/17 01:04:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tupoqot0qituxet1paxataj3': Waiting for checks to finish 2024/04/17 01:04:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tupoqot0qituxet1paxataj3': Waiting for transfers to finish 2024/04/17 01:04:31 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:31 INFO : potato2: Deleted 2024/04/17 01:04:31 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:32 INFO : potato3: Deleted 2024/04/17 01:04:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tupoqot0qituxet1paxataj3': Purge remote 2024/04/17 01:04:32 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:32 INFO : potato2.bin: Deleted 2024/04/17 01:04:32 INFO : potato3.bin: Deleted 2024/04/17 01:04:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wisitub9qeyomid6madovaj8': Purge remote 2024/04/17 01:04:32 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:33 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (8.27s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-roriluh6duyeyaw2tocakud9" 2024/04/17 01:04:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-roriluh6duyeyaw2tocakud9.bin" 2024/04/17 01:04:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-roriluh6duyeyaw2tocakud9" 2024/04/17 01:04:35 DEBUG : potato2: md5 = 96d143fd0062e5d71e8942b291c9410a OK 2024/04/17 01:04:35 DEBUG : empty space: md5 = 2c11163ff3b888135b4e4070eaff66d6 OK 2024/04/17 01:04:35 DEBUG : potato3: md5 = 863cc22a18e68184b255c6f13cbf741d OK 2024/04/17 01:04:35 DEBUG : tomatoDir: Making directory sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0' -> Encrypted drive 'TestCryptSwift:rclone-test-roriluh6duyeyaw2tocakud9' 2024/04/17 01:04:36 DEBUG : empty space: md5 = 4e93155827a99b9b443b4a1bd72045e7 OK 2024/04/17 01:04:36 DEBUG : potato3: md5 = 8bce74fb290d98ad12fe9bbae1b081da OK 2024/04/17 01:04:37 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/17 01:04:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-roriluh6duyeyaw2tocakud9': Waiting for checks to finish 2024/04/17 01:04:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:37 DEBUG : empty space: Unchanged skipping 2024/04/17 01:04:37 INFO : empty space: Deleted 2024/04/17 01:04:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-roriluh6duyeyaw2tocakud9': Waiting for transfers to finish 2024/04/17 01:04:37 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:37 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:37 INFO : potato2: Deleted 2024/04/17 01:04:37 INFO : potato3: Deleted 2024/04/17 01:04:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lawanos5gaqonim1rugehoh3" 2024/04/17 01:04:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lawanos5gaqonim1rugehoh3.bin" 2024/04/17 01:04:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lawanos5gaqonim1rugehoh3" 2024/04/17 01:04:39 DEBUG : tomatoDir: Making directory 2024/04/17 01:04:39 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/17 01:04:39 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/17 01:04:39 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/17 01:04:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lawanos5gaqonim1rugehoh3': Waiting for checks to finish 2024/04/17 01:04:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lawanos5gaqonim1rugehoh3': Waiting for transfers to finish 2024/04/17 01:04:39 INFO : potato2: Copied (server-side copy) 2024/04/17 01:04:39 INFO : empty space: Copied (server-side copy) 2024/04/17 01:04:39 INFO : potato2: Deleted 2024/04/17 01:04:40 INFO : potato3: Copied (server-side copy) 2024/04/17 01:04:40 INFO : empty space: Deleted 2024/04/17 01:04:40 INFO : potato3: Deleted 2024/04/17 01:04:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lawanos5gaqonim1rugehoh3': Purge remote 2024/04/17 01:04:40 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:41 INFO : potato3.bin: Deleted 2024/04/17 01:04:41 INFO : empty space.bin: Deleted 2024/04/17 01:04:41 INFO : potato2.bin: Deleted 2024/04/17 01:04:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-roriluh6duyeyaw2tocakud9': Purge remote 2024/04/17 01:04:41 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (7.82s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-move-test" 2024/04/17 01:04:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-move-test.bin" 2024/04/17 01:04:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-move-test" 2024/04/17 01:04:42 DEBUG : potato2: md5 = 5438d23e78cb3aca1ba51e2c866c5495 OK --- PASS: TestServerSideMoveOverlap (2.29s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test" 2024/04/17 01:04:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test.bin" 2024/04/17 01:04:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncOverlap (1.80s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test" 2024/04/17 01:04:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test.bin" 2024/04/17 01:04:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test" 2024/04/17 01:04:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-include/layer2" 2024/04/17 01:04:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-include/layer2.bin" 2024/04/17 01:04:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-include/layer2" 2024/04/17 01:04:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-ignore-file" 2024/04/17 01:04:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-ignore-file.bin" 2024/04/17 01:04:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-ignore-file" 2024/04/17 01:04:49 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = a77b90a9c0ef447b245b503dad0f82f2 OK 2024/04/17 01:04:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test': Waiting for checks to finish 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test': Waiting for transfers to finish 2024/04/17 01:04:50 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:50 INFO : There was nothing to transfer 2024/04/17 01:04:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:50 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/04/17 01:04:50 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:04:50 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:51 DEBUG : : Excluded 2024/04/17 01:04:51 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/17 01:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/04/17 01:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/04/17 01:04:51 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:51 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (8.77s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:04:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:04:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:04:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:04:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/CompareDest" 2024/04/17 01:04:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/CompareDest.bin" 2024/04/17 01:04:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/CompareDest" 2024/04/17 01:04:57 DEBUG : one: Need to transfer - File not found at Destination 2024/04/17 01:04:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:04:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:04:57 DEBUG : one: md5 = 4a68a54604df460e5ce742c3676601ec OK 2024/04/17 01:04:57 INFO : one: Copied (new) 2024/04/17 01:04:57 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:04:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:04:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:04:58 DEBUG : one: md5 = 67ee058d66ba1d7c485543ba3fa10931 OK 2024/04/17 01:04:58 INFO : one: Copied (replaced existing) 2024/04/17 01:04:58 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:59 DEBUG : dst/one: md5 = 2571d68b7cb7213b23ffb03acdb060df OK 2024/04/17 01:04:59 DEBUG : CompareDest/one: md5 = 6225b2adbed5a47bb5d0b7844c7cef79 OK 2024/04/17 01:04:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:04:59 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:04:59 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:04:59 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/17 01:04:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:04:59 DEBUG : Waiting for deletions to finish 2024/04/17 01:04:59 INFO : There was nothing to transfer 2024/04/17 01:05:00 DEBUG : CompareDest/two: md5 = 21f3e0f63b26ed67013398d5c579a8d7 OK 2024/04/17 01:05:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:01 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/17 01:05:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:01 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:01 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/17 01:05:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:01 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:01 INFO : There was nothing to transfer 2024/04/17 01:05:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:01 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/17 01:05:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:01 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:01 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/17 01:05:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:01 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:01 INFO : There was nothing to transfer sync_test.go:1964: No hash on uploaded file so skipping compare timestamp test 2024/04/17 01:05:02 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:02 DEBUG : two: Need to transfer - File not found at Destination 2024/04/17 01:05:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:02 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:02 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/17 01:05:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:02 DEBUG : two: md5 = 84bde156a2752a37c4ce9c0c5050d088 OK 2024/04/17 01:05:02 INFO : two: Copied (new) 2024/04/17 01:05:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (9.38s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:04 DEBUG : pre-dest1/1: md5 = 9906f1e4793ff4d6858ee52fa3e0f958 OK 2024/04/17 01:05:04 DEBUG : pre-dest2/2: md5 = 68db35fbdbddcefd69bf62ea8878d38e OK 2024/04/17 01:05:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dest" 2024/04/17 01:05:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dest.bin" 2024/04/17 01:05:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dest" 2024/04/17 01:05:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/pre-dest1" 2024/04/17 01:05:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/pre-dest1.bin" 2024/04/17 01:05:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/pre-dest1" 2024/04/17 01:05:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/pre-dest2" 2024/04/17 01:05:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/pre-dest2.bin" 2024/04/17 01:05:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/pre-dest2" 2024/04/17 01:05:08 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:08 DEBUG : 1: Destination found in --compare-dest, skipping 2024/04/17 01:05:08 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:08 DEBUG : 2: Destination found in --compare-dest, skipping 2024/04/17 01:05:08 DEBUG : 3: Need to transfer - File not found at Destination 2024/04/17 01:05:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dest': Waiting for checks to finish 2024/04/17 01:05:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dest': Waiting for transfers to finish 2024/04/17 01:05:09 DEBUG : 3: md5 = d66c5b279af4d3f6d287c6aca87d04eb OK 2024/04/17 01:05:09 INFO : 3: Copied (new) 2024/04/17 01:05:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (6.29s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/CopyDest" 2024/04/17 01:05:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/CopyDest.bin" 2024/04/17 01:05:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/CopyDest" 2024/04/17 01:05:12 DEBUG : one: Need to transfer - File not found at Destination 2024/04/17 01:05:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:12 DEBUG : one: md5 = 841128c8873d069e30930b203dae7316 OK 2024/04/17 01:05:12 INFO : one: Copied (new) 2024/04/17 01:05:12 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:14 DEBUG : one: md5 = 0d1ce1b97a34c12620c38456e5eb3cd6 OK 2024/04/17 01:05:14 INFO : one: Copied (replaced existing) 2024/04/17 01:05:14 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:14 DEBUG : dst/one: md5 = 48673915cf2cca8da33ce26a7d19b5dd OK 2024/04/17 01:05:14 DEBUG : CopyDest/one: md5 = ec6b7d5a05777f8f6632425e2c176f17 OK 2024/04/17 01:05:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/BackupDir" 2024/04/17 01:05:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/BackupDir.bin" 2024/04/17 01:05:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/BackupDir" 2024/04/17 01:05:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/17 01:05:17 INFO : one: Copied (server-side copy) 2024/04/17 01:05:17 INFO : one: Deleted 2024/04/17 01:05:17 INFO : one: Copied (server-side copy) 2024/04/17 01:05:17 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/17 01:05:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:17 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:17 DEBUG : CopyDest/two: md5 = 87d4a8a2e646e7d8a89e30c8804b1b4a OK 2024/04/17 01:05:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:18 INFO : two: Copied (server-side copy) 2024/04/17 01:05:18 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/17 01:05:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:18 DEBUG : one: Unchanged skipping 2024/04/17 01:05:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:18 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:19 DEBUG : one: Unchanged skipping 2024/04/17 01:05:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:19 DEBUG : two: Unchanged skipping 2024/04/17 01:05:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:19 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:19 INFO : There was nothing to transfer 2024/04/17 01:05:20 DEBUG : CopyDest/three: md5 = 1da517ebe3e86f76a85280769f19059a OK 2024/04/17 01:05:20 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/17 01:05:20 DEBUG : three: Destination not found in --copy-dest 2024/04/17 01:05:20 DEBUG : three: Need to transfer - File not found at Destination 2024/04/17 01:05:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:21 DEBUG : one: Unchanged skipping 2024/04/17 01:05:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:21 DEBUG : two: Unchanged skipping 2024/04/17 01:05:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:21 DEBUG : three: md5 = 819c905547b9092516ad2bbf46c96835 OK 2024/04/17 01:05:21 INFO : three: Copied (new) 2024/04/17 01:05:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (12.87s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:23 DEBUG : dst/one: md5 = a3359e848f45f92e8e1f8306f1343786 OK 2024/04/17 01:05:23 DEBUG : dst/two: md5 = 75eb43355cfe826e72d9404b64e9be80 OK 2024/04/17 01:05:23 DEBUG : dst/three.txt: md5 = c0a046ed9b3f1d29a0d15c2e2631fdd8 OK 2024/04/17 01:05:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup.bin" 2024/04/17 01:05:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:05:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:26 DEBUG : two: Unchanged skipping 2024/04/17 01:05:27 INFO : one: Copied (server-side copy) 2024/04/17 01:05:27 INFO : one: Deleted 2024/04/17 01:05:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:27 DEBUG : one: md5 = 515bc3fce8578c85cd538f6531c4eae3 OK 2024/04/17 01:05:27 INFO : one: Copied (new) 2024/04/17 01:05:27 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:28 INFO : three.txt: Copied (server-side copy) 2024/04/17 01:05:28 INFO : three.txt: Deleted 2024/04/17 01:05:28 INFO : three.txt: Moved into backup dir 2024/04/17 01:05:29 DEBUG : dst/three.txt: md5 = 9184f7a2f07a401ecb906141fb6d135d OK 2024/04/17 01:05:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:29 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:05:29 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:29 DEBUG : two: Unchanged skipping 2024/04/17 01:05:29 INFO : one: Copied (server-side copy) 2024/04/17 01:05:29 INFO : one: Deleted 2024/04/17 01:05:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:30 DEBUG : one: md5 = 48e9c91c35e0f4ffe428302da79705e8 OK 2024/04/17 01:05:30 INFO : one: Copied (new) 2024/04/17 01:05:30 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:30 INFO : three.txt: Copied (server-side copy) 2024/04/17 01:05:30 INFO : three.txt: Deleted 2024/04/17 01:05:30 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (9.05s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:32 DEBUG : dst/one: md5 = c1dd3a72733b91101e4028f61f02c9c0 OK 2024/04/17 01:05:32 DEBUG : dst/two: md5 = 94914de95f6581c5ac1f5f0ee8d0fda1 OK 2024/04/17 01:05:32 DEBUG : dst/three.txt: md5 = a0e2546aaaf37100c03572f6d1491876 OK 2024/04/17 01:05:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup.bin" 2024/04/17 01:05:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:05:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:35 DEBUG : two: Unchanged skipping 2024/04/17 01:05:35 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:05:36 INFO : one: Deleted 2024/04/17 01:05:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:36 DEBUG : one: md5 = 06c92f89e728eaad2932c93b0bcfc967 OK 2024/04/17 01:05:36 INFO : one: Copied (new) 2024/04/17 01:05:36 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:36 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:05:36 INFO : three.txt: Deleted 2024/04/17 01:05:36 INFO : three.txt: Moved into backup dir 2024/04/17 01:05:37 DEBUG : dst/three.txt: md5 = e4652410fc2f453060fbc7cb3862543b OK 2024/04/17 01:05:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:05:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:37 DEBUG : two: Unchanged skipping 2024/04/17 01:05:38 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:05:38 INFO : one: Deleted 2024/04/17 01:05:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:38 DEBUG : one: md5 = f43e423044e4fa025b7e17b09781f628 OK 2024/04/17 01:05:38 INFO : one: Copied (new) 2024/04/17 01:05:38 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:39 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:05:39 INFO : three.txt: Deleted 2024/04/17 01:05:39 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (8.35s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:40 DEBUG : dst/one: md5 = 05c1198fa8c80826d520cc19a32fbb36 OK 2024/04/17 01:05:40 DEBUG : dst/two: md5 = 2aa7e6f17c1f1b46fb5d2df03ecb2892 OK 2024/04/17 01:05:40 DEBUG : dst/three.txt: md5 = b6c65611bd5436904d8fa9e03285fd31 OK 2024/04/17 01:05:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup.bin" 2024/04/17 01:05:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/backup" 2024/04/17 01:05:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:05:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:43 DEBUG : two: Unchanged skipping 2024/04/17 01:05:44 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/17 01:05:44 INFO : one: Deleted 2024/04/17 01:05:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:44 DEBUG : one: md5 = eb6bdcf9996e6cf391de150545a20499 OK 2024/04/17 01:05:44 INFO : one: Copied (new) 2024/04/17 01:05:44 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:44 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/17 01:05:45 INFO : three.txt: Deleted 2024/04/17 01:05:45 INFO : three.txt: Moved into backup dir 2024/04/17 01:05:45 DEBUG : dst/three.txt: md5 = 4f5845758254a7b0e7126875d9753c14 OK 2024/04/17 01:05:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:05:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:46 DEBUG : two: Unchanged skipping 2024/04/17 01:05:46 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/17 01:05:46 INFO : one: Deleted 2024/04/17 01:05:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:46 DEBUG : one: md5 = fb811a4ff2e8528c7aed7b2490bb2919 OK 2024/04/17 01:05:46 INFO : one: Copied (new) 2024/04/17 01:05:46 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/17 01:05:47 INFO : three.txt: Deleted 2024/04/17 01:05:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (8.41s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:49 DEBUG : dst/one: md5 = a0a3ddb94f47f327f131890610967f56 OK 2024/04/17 01:05:49 DEBUG : dst/two: md5 = 21df60880a151c7fe9363b9497ac4164 OK 2024/04/17 01:05:49 DEBUG : dst/three.txt: md5 = b41505f38a8a7548f12c8d941abe7dbc OK 2024/04/17 01:05:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:05:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:51 DEBUG : two: Unchanged skipping 2024/04/17 01:05:51 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:05:51 INFO : one: Deleted 2024/04/17 01:05:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:51 DEBUG : one: md5 = 0f00b8a45c15b0ca8cb00344dacaebb1 OK 2024/04/17 01:05:51 INFO : one: Copied (new) 2024/04/17 01:05:51 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:05:52 INFO : three.txt: Deleted 2024/04/17 01:05:52 INFO : three.txt: Moved into backup dir 2024/04/17 01:05:52 DEBUG : dst/three.txt: md5 = c86cbd37977c0d4e466eba9ae0bb74dc OK 2024/04/17 01:05:53 DEBUG : one.bak: Excluded (Path Filter) 2024/04/17 01:05:53 DEBUG : one.bak: Excluded 2024/04/17 01:05:53 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/04/17 01:05:53 DEBUG : three.txt.bak: Excluded 2024/04/17 01:05:53 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:05:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for checks to finish 2024/04/17 01:05:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:53 DEBUG : two: Unchanged skipping 2024/04/17 01:05:53 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:05:53 INFO : one: Deleted 2024/04/17 01:05:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst': Waiting for transfers to finish 2024/04/17 01:05:54 DEBUG : one: md5 = 2b8d276bb44686707b2b0daaa58a7c00 OK 2024/04/17 01:05:54 INFO : one: Copied (new) 2024/04/17 01:05:54 DEBUG : Waiting for deletions to finish 2024/04/17 01:05:54 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:05:54 INFO : three.txt: Deleted 2024/04/17 01:05:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (7.35s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:05:56 DEBUG : dst/one: md5 = f549c4ccaf8b5627076eb0149761965f OK 2024/04/17 01:05:56 DEBUG : dst/two: md5 = 1f06f7ef9182d41d22e6da496acd4861 OK 2024/04/17 01:05:56 DEBUG : dst/three.txt: md5 = d588be49c17dc167f46205828c95ab23 OK 2024/04/17 01:05:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:05:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:05:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:05:58 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:05:58 INFO : one: Deleted 2024/04/17 01:05:58 DEBUG : one: md5 = 84fa026cf998c497bac4e0390b5ca1db OK 2024/04/17 01:05:58 INFO : one: Copied (new) 2024/04/17 01:05:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:05:58 DEBUG : two: Unchanged skipping 2024/04/17 01:05:58 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/17 01:05:59 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:05:59 INFO : three.txt: Deleted 2024/04/17 01:05:59 DEBUG : three.txt: md5 = 373f4f8dd14e9ec54cec355413ee8155 OK 2024/04/17 01:05:59 INFO : three.txt: Copied (new) 2024/04/17 01:06:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:06:00 INFO : one: Copied (server-side copy) to: one.bak 2024/04/17 01:06:01 INFO : one: Deleted 2024/04/17 01:06:01 DEBUG : one: md5 = ee148f1f4e1c965f597a03f21a7eabc2 OK 2024/04/17 01:06:01 INFO : one: Copied (new) 2024/04/17 01:06:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:01 DEBUG : two: Unchanged skipping 2024/04/17 01:06:01 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/17 01:06:01 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/17 01:06:01 INFO : three.txt: Deleted 2024/04/17 01:06:01 DEBUG : three.txt: md5 = 08aa0e6105642336ba28c06f5384ba9a OK 2024/04/17 01:06:01 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (7.31s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:03 DEBUG : dst/one: md5 = 53c45d9837386cbad21ac0f3076198ed OK 2024/04/17 01:06:03 DEBUG : dst/two: md5 = 68dc8382e73753d565be4746e105c76c OK 2024/04/17 01:06:04 DEBUG : dst/three.txt: md5 = b78d28306b442a0b6bff97b83240fecd OK 2024/04/17 01:06:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:06:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst.bin" 2024/04/17 01:06:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yubuniz2dimewaf1takewuc0/dst" 2024/04/17 01:06:05 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/17 01:06:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/17 01:06:06 INFO : one: Deleted 2024/04/17 01:06:06 DEBUG : one: md5 = 449f849da17c209fbfa99dd742ec704c OK 2024/04/17 01:06:06 INFO : one: Copied (new) 2024/04/17 01:06:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:06 DEBUG : two: Unchanged skipping 2024/04/17 01:06:06 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/17 01:06:06 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/17 01:06:06 INFO : three.txt: Deleted 2024/04/17 01:06:07 DEBUG : three.txt: md5 = ad888d1da8f71bb03dc25db61b10aa8f OK 2024/04/17 01:06:07 INFO : three.txt: Copied (new) 2024/04/17 01:06:07 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/17 01:06:08 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/17 01:06:08 INFO : one: Deleted 2024/04/17 01:06:08 DEBUG : one: md5 = 9e3e246b84c835a03de5277c9c476251 OK 2024/04/17 01:06:08 INFO : one: Copied (new) 2024/04/17 01:06:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:08 DEBUG : two: Unchanged skipping 2024/04/17 01:06:08 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/17 01:06:09 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/17 01:06:09 INFO : three.txt: Deleted 2024/04/17 01:06:09 DEBUG : three.txt: md5 = 2db0b033f31bd898ed13a629cdba2f1e OK 2024/04/17 01:06:09 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (7.22s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:11 DEBUG : Testêé: md5 = c00efdb5ac503f5aed8218d36497c062 OK 2024/04/17 01:06:11 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/04/17 01:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:11 DEBUG : Testêé: md5 = 9b22e1edaf9d1748653f4dce93c8e79b OK 2024/04/17 01:06:11 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/04/17 01:06:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.69s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:12 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/17 01:06:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:12 DEBUG : existing: md5 = 66d9be497a90d7e2e3bd2e0609c1fcae OK 2024/04/17 01:06:12 INFO : existing: Copied (new) 2024/04/17 01:06:12 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:13 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/04/17 01:06:13 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/04/17 01:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:13 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': not deleting files as there were IO errors 2024/04/17 01:06:13 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncImmutable (1.80s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:14 DEBUG : EXISTING: md5 = 3b60b318c08da32ef0fe315f9bf244ee OK 2024/04/17 01:06:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:14 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:14 DEBUG : existing: Unchanged skipping 2024/04/17 01:06:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:14 DEBUG : Waiting for deletions to finish 2024/04/17 01:06:14 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.09s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:2420: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.21s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", 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-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", 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-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" sync_test.go:2459: This test only runs on local --- PASS: TestMaxTransfer (0.54s) --- SKIP: TestMaxTransfer/Hard (0.14s) --- SKIP: TestMaxTransfer/Soft (0.25s) --- SKIP: TestMaxTransfer/Cautious (0.15s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:16 DEBUG : both0: md5 = b4d9cd184029f86035e318c2053e9464 OK 2024/04/17 01:06:16 DEBUG : only0: md5 = 2ce24052ab8f41d69deb94702fde2ae5 OK 2024/04/17 01:06:16 DEBUG : both1: md5 = cc815e2e811c32c394850ff9519c563a OK 2024/04/17 01:06:17 DEBUG : only1: md5 = ea33009cb0d3d27d7c2ad51af0a3d9fc OK 2024/04/17 01:06:17 DEBUG : both2: md5 = c522ea0a461075235b34b1b86f7506f7 OK 2024/04/17 01:06:17 DEBUG : only2: md5 = 0d6f8d61aa019bbe6aadd0c008d5b3e6 OK 2024/04/17 01:06:18 DEBUG : both3: md5 = 73ef26890685789fc7ba68c977528dbe OK 2024/04/17 01:06:18 DEBUG : only3: md5 = 74c30e400d996f66414b32ec98e14b7f OK 2024/04/17 01:06:18 DEBUG : both4: md5 = 411af6ea6e8d43806b9da2b6aaee5395 OK 2024/04/17 01:06:18 DEBUG : only4: md5 = 628fa1e27e5a2a41c5434063c651e2be OK 2024/04/17 01:06:19 DEBUG : both5: md5 = 5229d9186d93efb5586d1a8f5a148c41 OK 2024/04/17 01:06:19 DEBUG : only5: md5 = 78e8c4dff64c094be7d681cac334952d OK 2024/04/17 01:06:19 DEBUG : both6: md5 = ea7fdaa65e37c8af6df9c0d7dc8edd51 OK 2024/04/17 01:06:20 DEBUG : only6: md5 = 7c20cfa5781aa392646f90801c53c925 OK 2024/04/17 01:06:20 DEBUG : both7: md5 = 3885635fbfe95c9d3f326a05c46590b4 OK 2024/04/17 01:06:20 DEBUG : only7: md5 = 8849d986a10129f886f594bc5f020563 OK 2024/04/17 01:06:21 DEBUG : both8: md5 = 1e59292517b7f7b53049c4f5fa4d6b56 OK 2024/04/17 01:06:21 DEBUG : only8: md5 = 85c51175c43eaa0e73de108ba5af98ff OK 2024/04/17 01:06:22 DEBUG : both9: md5 = d365e8c601d0c911d7b19b3cc7322082 OK 2024/04/17 01:06:22 DEBUG : only9: md5 = 3734d284312c167b5108400a449366ee OK 2024/04/17 01:06:22 DEBUG : both10: md5 = 678d197360f9011793867d03177bf5a8 OK 2024/04/17 01:06:22 DEBUG : only10: md5 = 0547ea51cdea7c8e823452f9f956878d OK 2024/04/17 01:06:23 DEBUG : both11: md5 = d9a69b0cd46c6220be2dbaaef06f6f66 OK 2024/04/17 01:06:23 DEBUG : only11: md5 = a890691e9baeec51dfd97caa8ebec910 OK 2024/04/17 01:06:24 DEBUG : both12: md5 = fb8efbcbf91f50454411672ee613200f OK 2024/04/17 01:06:24 DEBUG : only12: md5 = 66ff6615efcdfbffc7c3c99bf649effc OK 2024/04/17 01:06:24 DEBUG : both13: md5 = 2e4f1b228c4be7e1bbb94365515a1de3 OK 2024/04/17 01:06:24 DEBUG : only13: md5 = 8f5777f3082e56326890e389953ae533 OK 2024/04/17 01:06:25 DEBUG : both14: md5 = 3f96951f74f853560e781d29ca5c7c9d OK 2024/04/17 01:06:25 DEBUG : only14: md5 = 1c05bb997a854be3d284da0a6fed7ba1 OK 2024/04/17 01:06:25 DEBUG : both15: md5 = 898ea1c042afd546d679927f84cf939a OK 2024/04/17 01:06:26 DEBUG : only15: md5 = b21e577c41ad31acdfe9570ef4c1719d OK 2024/04/17 01:06:26 DEBUG : both16: md5 = 4dd09bca3c1247665d55c94f6a9bd266 OK 2024/04/17 01:06:26 DEBUG : only16: md5 = 0bdef0e59232344f6cc571e83de8f972 OK 2024/04/17 01:06:26 DEBUG : both17: md5 = fe751044277776c307e9f446eeb84b84 OK 2024/04/17 01:06:27 DEBUG : only17: md5 = f525aa13b2dcf677d2f34d2d287715b5 OK 2024/04/17 01:06:27 DEBUG : both18: md5 = d2811d98f208900b19500a5942b2f102 OK 2024/04/17 01:06:27 DEBUG : only18: md5 = b473b77c1e30190f5627ad7f49e7196a OK 2024/04/17 01:06:28 DEBUG : both19: md5 = fec37b3727f0fd36fef40b9506a25cd4 OK 2024/04/17 01:06:28 DEBUG : only19: md5 = 9b5b80e79a7490e49b07b57c783c9024 OK 2024/04/17 01:06:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:30 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:30 DEBUG : both0: Unchanged skipping 2024/04/17 01:06:30 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:30 DEBUG : both10: Unchanged skipping 2024/04/17 01:06:30 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:30 DEBUG : both1: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both11: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both13: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both12: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both14: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both15: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both16: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both17: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both18: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both19: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both2: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both3: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both4: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both5: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both6: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both7: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both8: Unchanged skipping 2024/04/17 01:06:31 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:31 DEBUG : both9: Unchanged skipping 2024/04/17 01:06:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:31 DEBUG : Waiting for deletions to finish 2024/04/17 01:06:31 INFO : only17: Deleted 2024/04/17 01:06:31 INFO : only12: Deleted 2024/04/17 01:06:31 INFO : only16: Deleted 2024/04/17 01:06:31 INFO : only15: Deleted 2024/04/17 01:06:31 INFO : only19: Deleted 2024/04/17 01:06:31 INFO : only18: Deleted 2024/04/17 01:06:31 INFO : only7: Deleted 2024/04/17 01:06:31 INFO : only1: Deleted 2024/04/17 01:06:31 INFO : only10: Deleted 2024/04/17 01:06:31 INFO : only11: Deleted 2024/04/17 01:06:31 INFO : only14: Deleted 2024/04/17 01:06:31 INFO : only0: Deleted 2024/04/17 01:06:31 INFO : only8: Deleted 2024/04/17 01:06:31 INFO : only5: Deleted 2024/04/17 01:06:31 INFO : only2: Deleted 2024/04/17 01:06:31 INFO : only3: Deleted 2024/04/17 01:06:31 INFO : only4: Deleted 2024/04/17 01:06:31 INFO : only6: Deleted 2024/04/17 01:06:32 INFO : only9: Deleted 2024/04/17 01:06:32 INFO : only13: Deleted 2024/04/17 01:06:32 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (21.59s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:06:37 DEBUG : both0: md5 = 8e8c7d5cbaf87cf58353cd97787d6fac OK 2024/04/17 01:06:37 DEBUG : only0: md5 = 28afab31a65959fc9272388d9acfd0ba OK 2024/04/17 01:06:38 DEBUG : both1: md5 = 8267eb4471e2ff56f3938d8a634ca9dd OK 2024/04/17 01:06:38 DEBUG : only1: md5 = 62bb480b266bf79f323984816e100c18 OK 2024/04/17 01:06:38 DEBUG : both2: md5 = 9cfd270afbe1d7ba5f8d34ee02e8daf9 OK 2024/04/17 01:06:39 DEBUG : only2: md5 = 9df8c381193bceb46583188ab2b6e445 OK 2024/04/17 01:06:39 DEBUG : both3: md5 = acad2c25f29fe470ffe7a8a3d0b165d2 OK 2024/04/17 01:06:39 DEBUG : only3: md5 = 13a61de2d9e4598e6732bbbd5b1e923b OK 2024/04/17 01:06:39 DEBUG : both4: md5 = cf684330702622f511eceeb83a3b3f08 OK 2024/04/17 01:06:39 DEBUG : only4: md5 = b979fc85cc294c0c7835019040001461 OK 2024/04/17 01:06:40 DEBUG : both5: md5 = 0fb8e2db8a93f66c3e0ccc14a49d013f OK 2024/04/17 01:06:40 DEBUG : only5: md5 = 1fedfa8daeec42ce09030218f8b1c2e1 OK 2024/04/17 01:06:40 DEBUG : both6: md5 = f5d49f908fd8df2fb3bceeeefe451398 OK 2024/04/17 01:06:40 DEBUG : only6: md5 = 93ac3aa5a5aa469fd373f99de8baad34 OK 2024/04/17 01:06:40 DEBUG : both7: md5 = 5d5d4110cc5e7a6c8dcaea0921895d96 OK 2024/04/17 01:06:40 DEBUG : only7: md5 = eea77a15eeba68c941411a113344c05c OK 2024/04/17 01:06:41 DEBUG : both8: md5 = 31b437ef23947fb43bd5f2add3b97c54 OK 2024/04/17 01:06:41 DEBUG : only8: md5 = ca31f954271d1c58b07babdfda25ee01 OK 2024/04/17 01:06:41 DEBUG : both9: md5 = febd171fc81e2ed5b82d67d4f127ee44 OK 2024/04/17 01:06:41 DEBUG : only9: md5 = c6af169084d7208268dc78fc94ff2063 OK 2024/04/17 01:06:41 DEBUG : both10: md5 = db70d3482c674511cce998d162437acb OK 2024/04/17 01:06:42 DEBUG : only10: md5 = 8e9a7fa2d0b27c2d8769fa7d28ab4ec4 OK 2024/04/17 01:06:42 DEBUG : both11: md5 = 797d4319d441af2596049477855fe809 OK 2024/04/17 01:06:42 DEBUG : only11: md5 = 3b78f28d9492bca48902ffc5aae4a9be OK 2024/04/17 01:06:42 DEBUG : both12: md5 = 86645c9446c0d4bc37d92ef7330b348c OK 2024/04/17 01:06:42 DEBUG : only12: md5 = 39c6adb0f6c827550d50c6d4795a2173 OK 2024/04/17 01:06:43 DEBUG : both13: md5 = f9e2ef34e33360eb189013381e0e51c1 OK 2024/04/17 01:06:43 DEBUG : only13: md5 = 418b2a2553320308463d57d41625b816 OK 2024/04/17 01:06:43 DEBUG : both14: md5 = 875699e56dbb521303b71a1982ac6aa4 OK 2024/04/17 01:06:43 DEBUG : only14: md5 = 472cf05711c6c9f8348d73af035aeabd OK 2024/04/17 01:06:43 DEBUG : both15: md5 = da84549649d7b3cd163caf7630186514 OK 2024/04/17 01:06:43 DEBUG : only15: md5 = e71dcc0430d429197b9340ca56bc611a OK 2024/04/17 01:06:44 DEBUG : both16: md5 = 143df03f07a76e6b416a10a1e0d20d27 OK 2024/04/17 01:06:44 DEBUG : only16: md5 = 946a23b2fd36e6223a6ed12a7e559695 OK 2024/04/17 01:06:44 DEBUG : both17: md5 = 5313a6126d19de1f863ada04fa328323 OK 2024/04/17 01:06:44 DEBUG : only17: md5 = 7a5133ec93509ae7f31ac152247399da OK 2024/04/17 01:06:44 DEBUG : both18: md5 = 41fdf81521e40e7875122e88f751cd3a OK 2024/04/17 01:06:45 DEBUG : only18: md5 = 5a9fd1fcd87a452a94f4438c808a9464 OK 2024/04/17 01:06:45 DEBUG : both19: md5 = dfaf8f149153f49323310a35bd34fb4e OK 2024/04/17 01:06:45 DEBUG : only19: md5 = 245127d18e3a2c627b931810479c347f OK 2024/04/17 01:06:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for checks to finish 2024/04/17 01:06:47 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both0: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both10: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both11: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both1: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both12: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both13: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both14: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both15: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both16: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both17: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both18: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both19: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both2: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both3: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both4: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both5: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both6: Unchanged skipping 2024/04/17 01:06:47 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/04/17 01:06:47 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both7: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both8: Unchanged skipping 2024/04/17 01:06:47 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/17 01:06:47 DEBUG : both9: Unchanged skipping 2024/04/17 01:06:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Waiting for transfers to finish 2024/04/17 01:06:48 DEBUG : only10: md5 = ef32f88768af603d327f1ee284e3ef0f OK 2024/04/17 01:06:48 INFO : only10: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only1: md5 = 91dfb77bf4a90da90784b05db703749b OK 2024/04/17 01:06:48 INFO : only1: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only0: md5 = ba04201f075181633a8de8a05ec653dc OK 2024/04/17 01:06:48 INFO : only0: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only11: md5 = a5af71dbe17e3feb599835dcfa709fda OK 2024/04/17 01:06:48 INFO : only11: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only12: md5 = 31c7c00578ca7e1e40fa46121f104ea2 OK 2024/04/17 01:06:48 INFO : only12: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only13: md5 = ce71293e09c8f3246692b452839dd82e OK 2024/04/17 01:06:48 INFO : only13: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only14: md5 = f21a6acd544da2bc046cc51be5b5a298 OK 2024/04/17 01:06:48 INFO : only14: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only15: md5 = 0b6dcac40b17a25ebef23f1edc356b53 OK 2024/04/17 01:06:48 INFO : only15: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only16: md5 = f1a3378c14044ab026baef1cb4f69582 OK 2024/04/17 01:06:48 INFO : only16: Copied (replaced existing) 2024/04/17 01:06:48 DEBUG : only17: md5 = 2f2adfd05fcf5dbe6f36db5ab27a8d05 OK 2024/04/17 01:06:48 INFO : only17: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only18: md5 = d82209c9de9dd52ae22341525bf4650e OK 2024/04/17 01:06:49 INFO : only18: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only2: md5 = 1808bd691a7424f1743d8e805f98f059 OK 2024/04/17 01:06:49 INFO : only2: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only3: md5 = 75703fb1147e7361a90e4c95715a754e OK 2024/04/17 01:06:49 INFO : only3: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only19: md5 = da1fae44e22be9225b10e4ed134a6efa OK 2024/04/17 01:06:49 INFO : only19: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only4: md5 = 05e348b0001f7d2a77f3aeb0fff0541a OK 2024/04/17 01:06:49 INFO : only4: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only6: md5 = b01d5e180382ef0cd968ea6ebd1e3aa4 OK 2024/04/17 01:06:49 INFO : only6: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only5: md5 = 5dbdf961ffa1e165b4c7a63930138536 OK 2024/04/17 01:06:49 INFO : only5: Copied (replaced existing) 2024/04/17 01:06:49 DEBUG : only7: md5 = a8d3b919db0e37fdeb9636f2a831fa6c OK 2024/04/17 01:06:49 INFO : only7: Copied (replaced existing) 2024/04/17 01:06:50 DEBUG : only9: md5 = bca1910528ded4d6721a1b10b472ea73 OK 2024/04/17 01:06:50 INFO : only9: Copied (replaced existing) 2024/04/17 01:06:50 DEBUG : only8: md5 = 2a71203bc80532193011eff9e6f3230f OK 2024/04/17 01:06:50 INFO : only8: Copied (replaced existing) 2024/04/17 01:06:50 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (22.75s) === RUN TestNothingToTransfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" 2024/04/17 01:07:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/17 01:07:00 DEBUG : sub dir: Making directory 2024/04/17 01:07:00 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-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0'", Local "Local file system at /tmp/rclone1275999257", Modify Window "1ns" --- PASS: TestNothingToTransfer (2.28s) PASS 2024/04/17 01:07:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yubuniz2dimewaf1takewuc0': Purge remote 2024/04/17 01:07:02 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m5.477812451s (try 1/5)