"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2025/03/30 01:01:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja" 2025/03/30 01:01:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/03/30 01:01:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja.bin" 2025/03/30 01:01:57 DEBUG : Swift container crypt path rclone-test-sixifit8qoja.bin: Auto set use_segments_container to true 2025/03/30 01:02:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja" 2025/03/30 01:02:03 DEBUG : Swift container crypt path rclone-test-sixifit8qoja: Auto set use_segments_container to true 2025/03/30 01:02:08 DEBUG : Creating backend with remote "/tmp/rclone1730261627" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === 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-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:02:08 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/03/30 01:02:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:02:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:02:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:02:14 DEBUG : sub dir/hello world: md5 = fa6df915a80ab0db79593a576c1cd50b OK 2025/03/30 01:02:14 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (16.18s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.19s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:25 DEBUG : Creating backend with remote "/non-existing" 2025/03/30 01:02:25 DEBUG : Config file has changed externally - reloading 2025/03/30 01:02:25 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/03/30 01:02:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.48s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:02:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:02:36 DEBUG : sub dir/hello world: md5 = 164578a81233eab9a39f1980748b4f1a OK 2025/03/30 01:02:36 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyNoTraverse (15.80s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Running all checks before starting transfers 2025/03/30 01:02:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:02:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Checks finished, now starting transfers 2025/03/30 01:02:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:02:47 DEBUG : sub dir/hello world: md5 = 2f9c2edd1ad17540ba685eac179c0868 OK 2025/03/30 01:02:47 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyCheckFirst (16.06s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:02:58 ERROR : Ignoring --no-traverse with sync 2025/03/30 01:02:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:02:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:02:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:03:03 DEBUG : sub dir/hello world: md5 = 10c026ab86fc3f75f512d10a0532d243 OK 2025/03/30 01:03:03 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:03:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncNoTraverse (10.80s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:03:09 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/03/30 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:03:14 DEBUG : hello world2: md5 = a557bee622c2b936646ec80c9566b3c9 OK 2025/03/30 01:03:14 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyWithDepth (11.10s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:03:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:03:25 DEBUG : potato2: md5 = 127988f65f6dc010c1b3834c273c83d8 OK 2025/03/30 01:03:25 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (11.06s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:03:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:03:41 DEBUG : potato2: md5 = c4c9cae474bb27a18599b636c71e5020 OK 2025/03/30 01:03:41 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (16.10s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:03:47 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/03/30 01:03:47 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:03:47 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/03/30 01:03:47 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:03:47 INFO : sub dir: Making directory 2025/03/30 01:03:47 INFO : sub dir2: Making directory 2025/03/30 01:03:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:03:47 INFO : sub dir2/sub sub dir2: Making directory 2025/03/30 01:03:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:03:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:03:52 DEBUG : sub dir/hello world: md5 = 625c982c778f1ee11c4f41b2244afd1f OK 2025/03/30 01:03:52 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2025/03/30 01:03:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (11.14s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:03:58 INFO : sub dir2: Making directory 2025/03/30 01:03:58 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/03/30 01:03:58 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:03:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:03:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:03:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:04:03 DEBUG : sub dir/hello world: md5 = 087acdebc49786671db68267224508d2 OK 2025/03/30 01:04:03 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (11.08s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:04:09 DEBUG : sub dir2: Making directory with metadata 2025/03/30 01:04:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:04:09 INFO : sub dir: Making directory 2025/03/30 01:04:09 INFO : sub dir2: Making directory 2025/03/30 01:04:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:04:14 DEBUG : sub dir/hello world: md5 = 28508c7470410d9bf4f0da03fc85bdee OK 2025/03/30 01:04:14 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:04:14 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/03/30 01:04:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/03/30 01:04:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (15.83s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.20s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:04:25 INFO : sub dir2: Making directory 2025/03/30 01:04:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:04:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:04:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:04:30 DEBUG : sub dir/hello world: md5 = d692ce3d81f1afc56afc2162ea167d6e OK 2025/03/30 01:04:30 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:04:30 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (10.79s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:04:36 DEBUG : sub dir2: Making directory with metadata 2025/03/30 01:04:36 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:04:36 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:04:36 INFO : sub dir: Making directory 2025/03/30 01:04:36 INFO : sub dir2: Making directory 2025/03/30 01:04:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:04:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:04:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:04:41 DEBUG : sub dir/hello world: md5 = 8829eb37bbcdf9e8786fcb4aeafc92eb OK 2025/03/30 01:04:41 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:04:41 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/03/30 01:04:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (16.11s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.21s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:04:52 INFO : sub dir2: Making directory 2025/03/30 01:04:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:04:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:04:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:04:57 DEBUG : sub dir/hello world: md5 = 31492508e9ddd2357853380a1eaad93b OK 2025/03/30 01:04:57 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:04:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (15.74s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:05:13 DEBUG : sub dir/hello world: md5 = 282a02b8224499c7e7eca1389d8783b8 OK 2025/03/30 01:05:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wuqolax8goni" 2025/03/30 01:05:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wuqolax8goni.bin" 2025/03/30 01:05:19 DEBUG : Swift container crypt path rclone-test-wuqolax8goni.bin: Auto set use_segments_container to true 2025/03/30 01:05:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wuqolax8goni" 2025/03/30 01:05:25 DEBUG : Swift container crypt path rclone-test-wuqolax8goni: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-wuqolax8goni' 2025/03/30 01:05:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:05:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuqolax8goni': Waiting for checks to finish 2025/03/30 01:05:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuqolax8goni': Waiting for transfers to finish 2025/03/30 01:05:41 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:05:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuqolax8goni': Purge remote 2025/03/30 01:05:41 DEBUG : Waiting for deletions to finish 2025/03/30 01:05:46 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (48.74s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:06:02 DEBUG : sub dir/hello world: md5 = d380d4efd73b542e212044e34359dab8 OK 2025/03/30 01:06:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:06:07 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/30 01:06:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:06:12 DEBUG : sub dir/hello world: md5 = 4f259ed9e6178995cb5c4740bf01685f OK 2025/03/30 01:06:12 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyOverSelf (31.18s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:06:33 DEBUG : sub dir/hello world: md5 = ca263e6e75b29e903525205adece4f47 OK 2025/03/30 01:06:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gejamik7loju" 2025/03/30 01:06:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gejamik7loju.bin" 2025/03/30 01:06:34 DEBUG : Swift container crypt path rclone-test-gejamik7loju.bin: Auto set use_segments_container to true 2025/03/30 01:06:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gejamik7loju" 2025/03/30 01:06:40 DEBUG : Swift container crypt path rclone-test-gejamik7loju: Auto set use_segments_container to true sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju' 2025/03/30 01:06:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:06:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju': Waiting for checks to finish 2025/03/30 01:06:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju': Waiting for transfers to finish 2025/03/30 01:07:01 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:07:06 DEBUG : sub dir/hello world: md5 = b70a1f4133b6ef7ca6476f1a41401cdb OK 2025/03/30 01:07:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju': Waiting for checks to finish 2025/03/30 01:07:12 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/30 01:07:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju': Waiting for transfers to finish 2025/03/30 01:07:27 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:07:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejamik7loju': Purge remote 2025/03/30 01:07:27 DEBUG : Waiting for deletions to finish 2025/03/30 01:07:38 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (75.60s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:07:49 DEBUG : sub dir/hello world: md5 = db0e23e10a2f8cd64f28be32066bc20a OK 2025/03/30 01:07:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/30 01:07:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:07:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:07:59 DEBUG : sub dir/hello world: md5 = 340bd9a904974d13d996cf1a86455e02 OK 2025/03/30 01:07:59 INFO : sub dir/hello world: Copied (replaced existing) 2025/03/30 01:07:59 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestMoveOverSelf (31.73s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:08:20 DEBUG : sub dir/hello world: md5 = 1a81599dbf92e2e04b3008d68c2a226d OK 2025/03/30 01:08:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vedaboh8xeza" 2025/03/30 01:08:20 DEBUG : Config file has changed externally - reloading 2025/03/30 01:08:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vedaboh8xeza.bin" 2025/03/30 01:08:21 DEBUG : Swift container crypt path rclone-test-vedaboh8xeza.bin: Auto set use_segments_container to true 2025/03/30 01:08:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vedaboh8xeza" 2025/03/30 01:08:27 DEBUG : Swift container crypt path rclone-test-vedaboh8xeza: Auto set use_segments_container to true sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza' 2025/03/30 01:08:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:08:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for checks to finish 2025/03/30 01:08:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for transfers to finish 2025/03/30 01:08:48 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:08:54 DEBUG : sub dir/hello world: md5 = d39621e4f0de6849c0451c75f61adb53 OK 2025/03/30 01:08:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/03/30 01:08:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for checks to finish 2025/03/30 01:08:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for transfers to finish 2025/03/30 01:09:05 INFO : sub dir/hello world: Copied (server-side copy) 2025/03/30 01:09:10 INFO : sub dir/hello world: Deleted 2025/03/30 01:09:20 DEBUG : sub dir/hello world: md5 = 722a4ff7ceae9fcaa975bafe70a06bdd OK 2025/03/30 01:09:20 DEBUG : testing file moves 2025/03/30 01:09:21 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/03/30 01:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for checks to finish 2025/03/30 01:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Waiting for transfers to finish 2025/03/30 01:09:36 INFO : sub dir/hello world: Copied (server-side copy) 2025/03/30 01:09:41 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:09:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vedaboh8xeza': Purge remote 2025/03/30 01:09:42 DEBUG : Waiting for deletions to finish 2025/03/30 01:09:47 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (92.72s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:09:53 DEBUG : sub dir/hello world: md5 = 5722185d1c7f80f83418c8c55a966770 OK 2025/03/30 01:09:53 ERROR : : error listing: directory not found 2025/03/30 01:09:53 INFO : Local file system at /tmp/rclone1730261627: Making directory 2025/03/30 01:09:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:09:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyAfterDelete (21.70s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:15 DEBUG : sub dir/hello world: md5 = 7735ba774f3b1a5fe873de8b9e7218a1 OK 2025/03/30 01:10:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:10:20 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for checks to finish 2025/03/30 01:10:20 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for transfers to finish 2025/03/30 01:10:20 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/03/30 01:10:20 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyRedownload (16.34s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:26 DEBUG : check sum: Need to transfer - File not found at Destination 2025/03/30 01:10:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:26 DEBUG : check sum: md5 = cd417fa966d091b3bf353c1b974bdb5c OK 2025/03/30 01:10:26 INFO : check sum: Copied (new) 2025/03/30 01:10:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:27 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/03/30 01:10:27 DEBUG : check sum: Size of src and dst objects identical 2025/03/30 01:10:27 DEBUG : check sum: Unchanged skipping 2025/03/30 01:10:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:27 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:27 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (2.03s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:28 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/03/30 01:10:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:33 DEBUG : sizeonly: md5 = 737a4da08a1bd87527e810dad9cf400c OK 2025/03/30 01:10:33 INFO : sizeonly: Copied (new) 2025/03/30 01:10:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:39 DEBUG : sizeonly: Sizes identical 2025/03/30 01:10:39 DEBUG : sizeonly: Unchanged skipping 2025/03/30 01:10:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:39 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:39 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncSizeOnly (16.60s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:45 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/03/30 01:10:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:45 DEBUG : ignore-size: md5 = 2d8b9b4fbe4625e5f902258d96828ea4 OK 2025/03/30 01:10:45 INFO : ignore-size: Copied (new) 2025/03/30 01:10:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:45 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:10:45 DEBUG : ignore-size: Unchanged skipping 2025/03/30 01:10:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:45 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:45 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.62s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:46 DEBUG : existing: md5 = 30a6d19a9679c164d6ec090526405f21 OK 2025/03/30 01:10:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:47 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:10:47 DEBUG : existing: Unchanged skipping 2025/03/30 01:10:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:47 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:47 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:47 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/03/30 01:10:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:47 DEBUG : existing: md5 = c7e30c967ff19dba526bc957a739dff1 OK 2025/03/30 01:10:47 INFO : existing: Copied (replaced existing) 2025/03/30 01:10:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (1.79s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:48 DEBUG : existing: Need to transfer - File not found at Destination 2025/03/30 01:10:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:48 DEBUG : existing: md5 = 8cf58e3583a52c3c3dfd96081eea4dcf OK 2025/03/30 01:10:48 INFO : existing: Copied (new) 2025/03/30 01:10:48 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:48 DEBUG : existing: Destination exists, skipping 2025/03/30 01:10:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:49 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:49 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.37s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:50 DEBUG : b/potato: md5 = 37956d36eb66236e62345343b04352c9 OK 2025/03/30 01:10:50 DEBUG : c/non empty space: md5 = cc5352d14755a4c25aec0c0378666d17 OK 2025/03/30 01:10:50 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/03/30 01:10:50 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/03/30 01:10:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:10:51 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:10:51 DEBUG : c/non empty space: Unchanged skipping 2025/03/30 01:10:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:10:51 DEBUG : a/potato2: md5 = 6ee9d7d3c7646a33ad56c43af23ef493 OK 2025/03/30 01:10:51 INFO : a/potato2: Copied (new) 2025/03/30 01:10:51 DEBUG : Waiting for deletions to finish 2025/03/30 01:10:51 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (3.03s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:10:58 DEBUG : empty space: md5 = 6ab129a6abc653e2b90c7eca5c7a0632 OK 2025/03/30 01:11:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:08 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 2025/03/30 01:11:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:08 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/03/30 01:11:08 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:13 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 2025/03/30 01:11:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:18 DEBUG : empty space: md5 = a1a2d7b21fc62ec6a5908b00f715ec27 OK 2025/03/30 01:11:18 INFO : empty space: Copied (replaced existing) 2025/03/30 01:11:18 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (36.87s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.14s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:30 DEBUG : foo: md5 = a2bea483abdfd1d3b43012cde3f013e6 OK 2025/03/30 01:11:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:31 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 2025/03/30 01:11:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:31 DEBUG : foo: md5 = 3829358e858f5caf11bdb6002c2d127d OK 2025/03/30 01:11:31 INFO : foo: Copied (replaced existing) 2025/03/30 01:11:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (2.49s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:37 DEBUG : empty space: md5 = e2e812b94c5ef42e46f760ecd2ffa269 OK 2025/03/30 01:11:37 DEBUG : potato: Need to transfer - File not found at Destination 2025/03/30 01:11:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:11:37 DEBUG : empty space: Unchanged skipping 2025/03/30 01:11:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:38 DEBUG : potato: md5 = 2effd3ce8bed7f5c885da783f2d7d444 OK 2025/03/30 01:11:38 INFO : potato: Copied (new) 2025/03/30 01:11:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (16.75s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:49 DEBUG : potato: md5 = 3930261f083af2d77e6eabcd5dad5ef4 OK 2025/03/30 01:11:49 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/03/30 01:11:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:49 DEBUG : potato: md5 = 4e696e5da9c52661367e961b5a26f78d OK 2025/03/30 01:11:49 INFO : potato: Copied (replaced existing) 2025/03/30 01:11:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.32s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:50 DEBUG : potato: md5 = fdc73b32632653b4a228ab4be8f9b929 OK 2025/03/30 01:11:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:11:50 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 2025/03/30 01:11:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:11:51 DEBUG : potato: md5 = 1d65a912853e398311223093d0b66d8c OK 2025/03/30 01:11:51 INFO : potato: Copied (replaced existing) 2025/03/30 01:11:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.33s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:11:51 DEBUG : potato: md5 = fb4fd6aac0f89a174e2ca8b799d7fb56 OK 2025/03/30 01:11:57 DEBUG : empty space: md5 = 25ba442f886eed994af5c85396da54de OK 2025/03/30 01:11:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:11:57 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/03/30 01:11:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:12:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:12:02 DEBUG : empty space: Unchanged skipping 2025/03/30 01:12:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:12:02 DEBUG : Waiting for deletions to finish 2025/03/30 01:12:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (21.43s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:12:13 DEBUG : potato: md5 = 9d85e80261bd996742caab096655e593 OK 2025/03/30 01:12:18 DEBUG : empty space: md5 = 35e458cd65591627dfc8ff491ec34f60 OK 2025/03/30 01:12:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:12:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:12:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:12:23 DEBUG : empty space: Unchanged skipping 2025/03/30 01:12:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:12:23 DEBUG : potato2: md5 = 01cc66709a8939987682d1f02828961d OK 2025/03/30 01:12:23 INFO : potato2: Copied (new) 2025/03/30 01:12:23 DEBUG : Waiting for deletions to finish 2025/03/30 01:12:24 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (21.69s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:12:35 DEBUG : b/potato: md5 = 4b3f08955516909534248c6aefec5d33 OK 2025/03/30 01:12:35 DEBUG : c/non empty space: md5 = 6096aa40a7e7e9de474ba68786f749e4 OK 2025/03/30 01:12:35 INFO : d: Making directory 2025/03/30 01:12:35 INFO : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2025/03/30 01:12:35 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/03/30 01:12:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:12:35 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:12:35 DEBUG : c/non empty space: Unchanged skipping 2025/03/30 01:12:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:12:35 DEBUG : a/potato2: md5 = e0f0f7f7a2ab850bba8f73484817aa8b OK 2025/03/30 01:12:35 INFO : a/potato2: Copied (new) 2025/03/30 01:12:35 DEBUG : Waiting for deletions to finish 2025/03/30 01:12:35 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.10s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:12:37 DEBUG : b/potato: md5 = 612a2b623063e6524bbc254a527ddf3b OK 2025/03/30 01:12:37 DEBUG : c/non empty space: md5 = c6eaf4039251befd0ca8b118c0902478 OK 2025/03/30 01:12:37 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/03/30 01:12:37 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/03/30 01:12:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:12:37 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:12:37 DEBUG : c/non empty space: Unchanged skipping 2025/03/30 01:12:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:12:37 DEBUG : a/potato2: md5 = 2a7ee3746e8038d86a7c9d5e96ce01b6 OK 2025/03/30 01:12:37 INFO : a/potato2: Copied (new) 2025/03/30 01:12:37 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': not deleting files as there were IO errors 2025/03/30 01:12:37 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.15s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:12:39 DEBUG : potato: md5 = 95f04fde33aef81fabafe79cf6fa6096 OK 2025/03/30 01:12:44 DEBUG : empty space: md5 = 82fff4cb5a0abc5a7b0bb06b7de6c1bd OK 2025/03/30 01:12:44 DEBUG : Waiting for deletions to finish 2025/03/30 01:12:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:12:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:12:44 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:12:44 DEBUG : empty space: Unchanged skipping 2025/03/30 01:12:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:12:44 INFO : potato: Deleted 2025/03/30 01:12:49 DEBUG : potato2: md5 = 7123dcbad42b70477a3bc1e3962b3907 OK 2025/03/30 01:12:49 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (26.71s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:13:06 DEBUG : potato: md5 = 6f0ab38fe0cd9df705b06181b5fbdbe3 OK 2025/03/30 01:13:11 DEBUG : empty space: md5 = f02b15e322df620a00876d0ded53a136 OK 2025/03/30 01:13:16 DEBUG : Waiting for deletions to finish 2025/03/30 01:13:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:13:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:13:16 INFO : potato: Deleted 2025/03/30 01:13:16 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:13:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:13:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:13:16 DEBUG : empty space: Unchanged skipping 2025/03/30 01:13:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:13:21 DEBUG : potato2: md5 = 5859c41b0c19f3ec2351a58954c3085b OK 2025/03/30 01:13:21 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (31.88s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:13:37 DEBUG : potato: md5 = 0d2097ecfd3635d6be48f237472fa768 OK 2025/03/30 01:13:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:13:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:13:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:13:43 DEBUG : potato2: md5 = 4e5dbf934c0224c2538dba35b0d0eeee OK 2025/03/30 01:13:43 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (21.52s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:14:04 DEBUG : potato2: md5 = a82e4ada02625c3ed96dcf0db1da3b9a OK 2025/03/30 01:14:09 DEBUG : empty space: md5 = ef9d1a946de6eb87b146a10de0911d13 OK 2025/03/30 01:14:19 DEBUG : enormous: Excluded (Size Filter) 2025/03/30 01:14:19 DEBUG : enormous: Excluded 2025/03/30 01:14:19 DEBUG : potato2: Excluded (Size Filter) 2025/03/30 01:14:19 DEBUG : potato2: Excluded 2025/03/30 01:14:19 DEBUG : potato2: Excluded (Size Filter) 2025/03/30 01:14:19 DEBUG : potato2: Excluded 2025/03/30 01:14:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:14:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:14:19 DEBUG : empty space: Unchanged skipping 2025/03/30 01:14:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:14:19 DEBUG : Waiting for deletions to finish 2025/03/30 01:14:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:14:25 DEBUG : enormous: Excluded (Size Filter) 2025/03/30 01:14:25 DEBUG : enormous: Excluded 2025/03/30 01:14:25 DEBUG : potato2: Excluded (Size Filter) 2025/03/30 01:14:25 DEBUG : potato2: Excluded 2025/03/30 01:14:25 DEBUG : potato2: Excluded (Size Filter) 2025/03/30 01:14:25 DEBUG : potato2: Excluded 2025/03/30 01:14:25 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for checks to finish 2025/03/30 01:14:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:14:30 DEBUG : empty space: Unchanged skipping 2025/03/30 01:14:30 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for transfers to finish 2025/03/30 01:14:30 DEBUG : Waiting for deletions to finish 2025/03/30 01:14:30 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncWithExclude (42.27s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:14:46 DEBUG : potato2: md5 = cd6f1a6af9be0645a1db32ffd879e5d4 OK 2025/03/30 01:14:51 DEBUG : empty space: md5 = ea4eb18cf393c70dac415501368c3326 OK 2025/03/30 01:14:57 DEBUG : enormous: md5 = ef3696ddaa5b2fc62267be979985e319 OK 2025/03/30 01:14:57 DEBUG : enormous: Excluded (Size Filter) 2025/03/30 01:14:57 DEBUG : enormous: Excluded 2025/03/30 01:14:57 DEBUG : potato2: Excluded (Size Filter) 2025/03/30 01:14:57 DEBUG : potato2: Excluded 2025/03/30 01:14:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:14:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:14:57 DEBUG : empty space: Unchanged skipping 2025/03/30 01:14:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:14:57 DEBUG : Waiting for deletions to finish 2025/03/30 01:15:03 INFO : enormous: Deleted 2025/03/30 01:15:03 INFO : potato2: Deleted 2025/03/30 01:15:03 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:15:03 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for checks to finish 2025/03/30 01:15:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:15:03 DEBUG : empty space: Unchanged skipping 2025/03/30 01:15:03 DEBUG : Local file system at /tmp/rclone1730261627: Waiting for transfers to finish 2025/03/30 01:15:03 DEBUG : Waiting for deletions to finish 2025/03/30 01:15:03 INFO : enormous: Deleted 2025/03/30 01:15:03 INFO : potato2: Deleted 2025/03/30 01:15:03 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (27.76s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:15:10 DEBUG : one: md5 = 23aae967ea0cc7a682588dba8ab2a5d7 OK 2025/03/30 01:15:10 DEBUG : two: md5 = c8844f338617d3cf510059b981912e9e OK 2025/03/30 01:15:15 DEBUG : three: md5 = 4050b73fe04f5f8734439184983eb73e OK 2025/03/30 01:15:21 DEBUG : four: md5 = 927dc42da470541626f20e4c4ac8ce7b OK 2025/03/30 01:15:26 DEBUG : five: Need to transfer - File not found at Destination 2025/03/30 01:15:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:15:26 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/03/30 01:15:26 DEBUG : three: Sizes identical 2025/03/30 01:15:26 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/03/30 01:15:26 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/03/30 01:15:26 DEBUG : one: Destination is newer than source, skipping 2025/03/30 01:15:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:15:27 DEBUG : five: md5 = 89372afab520fa5e25c57a47089b09cc OK 2025/03/30 01:15:27 INFO : five: Copied (new) 2025/03/30 01:15:27 DEBUG : two: md5 = da2cf99cb5da2fe0b3c1218dddbd7b30 OK 2025/03/30 01:15:27 INFO : two: Copied (replaced existing) 2025/03/30 01:15:32 DEBUG : four: md5 = 6ec6819d2ac423c1e85eecd49f3bae63 OK 2025/03/30 01:15:32 INFO : four: Copied (replaced existing) 2025/03/30 01:15:32 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (39.62s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: 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-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:1538: Can track renames: false 2025/03/30 01:15:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Ignoring --track-renames as the source and destination do not have a common hash 2025/03/30 01:15:49 DEBUG : potato: Need to transfer - File not found at Destination 2025/03/30 01:15:49 DEBUG : yam: Need to transfer - File not found at Destination 2025/03/30 01:15:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:15:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:15:49 DEBUG : potato: md5 = bd69ea91bb2dea5ed37047fcfaac92c2 OK 2025/03/30 01:15:49 INFO : potato: Copied (new) 2025/03/30 01:15:54 DEBUG : yam: md5 = 99d9fa6329d37a210e9b392f53d72ade OK 2025/03/30 01:15:54 INFO : yam: Copied (new) 2025/03/30 01:15:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:15:54 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Ignoring --track-renames as the source and destination do not have a common hash 2025/03/30 01:15:55 DEBUG : yaml: Need to transfer - File not found at Destination 2025/03/30 01:15:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:15:55 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:15:55 DEBUG : potato: Unchanged skipping 2025/03/30 01:15:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:15:55 DEBUG : yaml: md5 = 4daca55b9ec7d33623d13b0da97498dd OK 2025/03/30 01:15:55 INFO : yaml: Copied (new) 2025/03/30 01:15:55 DEBUG : Waiting for deletions to finish 2025/03/30 01:16:00 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (12.62s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/03/30 01:16:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Making map for --track-renames 2025/03/30 01:16:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Finished making map for --track-renames 2025/03/30 01:16:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:16:01 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/03/30 01:16:01 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/03/30 01:16:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for renames to finish 2025/03/30 01:16:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:16:01 DEBUG : potato: md5 = 94b860e8743497ef69832914b762a56e OK 2025/03/30 01:16:01 INFO : potato: Copied (new) 2025/03/30 01:16:06 DEBUG : yam: md5 = 2c4040cc3345417c87d86f668c103832 OK 2025/03/30 01:16:06 INFO : yam: Copied (new) 2025/03/30 01:16:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:16:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Making map for --track-renames 2025/03/30 01:16:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Finished making map for --track-renames 2025/03/30 01:16:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:16:07 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:16:07 DEBUG : potato: Unchanged skipping 2025/03/30 01:16:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for renames to finish 2025/03/30 01:16:17 INFO : yam: Copied (server-side copy) to: yaml 2025/03/30 01:16:22 INFO : yam: Deleted 2025/03/30 01:16:22 INFO : yaml: Renamed from "yam" 2025/03/30 01:16:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:16:22 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (22.01s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:1649: Can track renames: true 2025/03/30 01:16:23 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Making map for --track-renames 2025/03/30 01:16:23 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Finished making map for --track-renames 2025/03/30 01:16:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:16:23 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/03/30 01:16:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for renames to finish 2025/03/30 01:16:23 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/03/30 01:16:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:16:23 DEBUG : potato: md5 = 544c8b09b7d11f3b3ddf7ded7acc22bb OK 2025/03/30 01:16:23 INFO : potato: Copied (new) 2025/03/30 01:16:24 DEBUG : sub/yam: md5 = 90940969d349992f4bdbf2d73fd397c8 OK 2025/03/30 01:16:24 INFO : sub/yam: Copied (new) 2025/03/30 01:16:24 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:16:24 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:16:24 DEBUG : potato: Unchanged skipping 2025/03/30 01:16:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Making map for --track-renames 2025/03/30 01:16:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Finished making map for --track-renames 2025/03/30 01:16:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:16:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for renames to finish 2025/03/30 01:16:39 INFO : sub/yam: Copied (server-side copy) to: yam 2025/03/30 01:16:39 INFO : sub/yam: Deleted 2025/03/30 01:16:39 INFO : yam: Renamed from "sub/yam" 2025/03/30 01:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:16:39 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (22.54s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:16:46 DEBUG : Creating backend with remote "/tmp/rclone1730261627/dir1" 2025/03/30 01:16:46 DEBUG : Config file has changed externally - reloading 2025/03/30 01:16:46 DEBUG : Creating backend with remote "/tmp/rclone1730261627/dir2" 2025/03/30 01:16:46 DEBUG : Local file system at /tmp/rclone1730261627/dir2: Using server-side directory move 2025/03/30 01:16:46 INFO : Local file system at /tmp/rclone1730261627/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/03/30 01:16:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/03/30 01:16:46 DEBUG : Local file system at /tmp/rclone1730261627/dir2: Waiting for checks to finish 2025/03/30 01:16:46 INFO : file1.txt: Moved (server-side) 2025/03/30 01:16:46 DEBUG : Local file system at /tmp/rclone1730261627/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.15s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:16:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:16:46 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/03/30 01:16:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:16:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:16:51 DEBUG : sub dir/hello world: md5 = 84f3c1f40bd5e0ffb92bddaa6dd0a0f2 OK 2025/03/30 01:16:51 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:16:51 INFO : sub dir/hello world: Deleted 2025/03/30 01:16:51 DEBUG : nested/sub dir/file: md5 = b7c998ab11e88c1b82c71035da572be6 OK 2025/03/30 01:16:51 INFO : nested/sub dir/file: Copied (new) 2025/03/30 01:16:51 INFO : nested/sub dir/file: Deleted 2025/03/30 01:16:51 INFO : sub dir: Removing directory 2025/03/30 01:16:51 INFO : nested/sub dir: Removing directory 2025/03/30 01:16:51 INFO : nested: Removing directory 2025/03/30 01:16:51 DEBUG : Local file system at /tmp/rclone1730261627: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (26.33s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:17:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/03/30 01:17:12 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/03/30 01:17:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:17:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:17:17 DEBUG : nested/sub dir/file: md5 = 4994fe9eab4f277bff930bb1d6fe8866 OK 2025/03/30 01:17:17 INFO : nested/sub dir/file: Copied (new) 2025/03/30 01:17:17 INFO : nested/sub dir/file: Deleted 2025/03/30 01:17:17 DEBUG : sub dir/hello world: md5 = 69bb4e82b920561390c10cac054eaf94 OK 2025/03/30 01:17:17 INFO : sub dir/hello world: Copied (new) 2025/03/30 01:17:17 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (26.14s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:17:38 DEBUG : existing: Need to transfer - File not found at Destination 2025/03/30 01:17:38 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/03/30 01:17:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:17:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:17:38 DEBUG : existing: md5 = d09dd8f1df6731f6e0fdf4bbc24385e4 OK 2025/03/30 01:17:38 INFO : existing: Copied (new) 2025/03/30 01:17:38 INFO : existing: Deleted 2025/03/30 01:17:39 DEBUG : existing-b: md5 = 496443de5915b8a79d70a4cc9488b92b OK 2025/03/30 01:17:39 INFO : existing-b: Copied (new) 2025/03/30 01:17:39 INFO : existing-b: Deleted 2025/03/30 01:17:39 DEBUG : existing: Destination exists, skipping 2025/03/30 01:17:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:17:39 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/03/30 01:17:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:17:39 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (2.08s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:17:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dapihut7puhu" 2025/03/30 01:17:40 DEBUG : Config file has changed externally - reloading 2025/03/30 01:17:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dapihut7puhu.bin" 2025/03/30 01:17:41 DEBUG : Swift container crypt path rclone-test-dapihut7puhu.bin: Auto set use_segments_container to true 2025/03/30 01:17:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dapihut7puhu" 2025/03/30 01:17:47 DEBUG : Swift container crypt path rclone-test-dapihut7puhu: Auto set use_segments_container to true 2025/03/30 01:17:58 DEBUG : potato2: md5 = 801b9cde4ac86de3e63bf56e1fb5fa27 OK 2025/03/30 01:18:03 DEBUG : empty space: md5 = e8219d69a6959beacd66b20c0ac62469 OK 2025/03/30 01:18:03 DEBUG : potato3: md5 = ee60e73662730e4fdd511afb649c30cc OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-dapihut7puhu' 2025/03/30 01:18:14 DEBUG : empty space: md5 = 62564f739b91285d74dfb0bab98cd9bf OK 2025/03/30 01:18:15 DEBUG : potato3: md5 = e2918058fcc3bf87c6f87a7dfbdf70ec OK 2025/03/30 01:18:15 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:18:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/03/30 01:18:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dapihut7puhu': Waiting for checks to finish 2025/03/30 01:18:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:18:15 DEBUG : empty space: Unchanged skipping 2025/03/30 01:18:20 INFO : empty space: Deleted 2025/03/30 01:18:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dapihut7puhu': Waiting for transfers to finish 2025/03/30 01:18:20 INFO : potato3: Copied (server-side copy) 2025/03/30 01:18:21 INFO : potato3: Deleted 2025/03/30 01:18:31 INFO : potato2: Copied (server-side copy) 2025/03/30 01:18:36 INFO : potato2: Deleted 2025/03/30 01:18:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lefimip4lija" 2025/03/30 01:18:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lefimip4lija.bin" 2025/03/30 01:18:47 DEBUG : Swift container crypt path rclone-test-lefimip4lija.bin: Auto set use_segments_container to true 2025/03/30 01:18:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lefimip4lija" 2025/03/30 01:18:53 DEBUG : Swift container crypt path rclone-test-lefimip4lija: Auto set use_segments_container to true 2025/03/30 01:18:58 DEBUG : empty space: Need to transfer - File not found at Destination 2025/03/30 01:18:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:18:58 DEBUG : potato3: Need to transfer - File not found at Destination 2025/03/30 01:18:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lefimip4lija': Waiting for checks to finish 2025/03/30 01:18:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lefimip4lija': Waiting for transfers to finish 2025/03/30 01:19:03 INFO : empty space: Copied (server-side copy) 2025/03/30 01:19:04 INFO : potato3: Copied (server-side copy) 2025/03/30 01:19:04 INFO : potato3: Deleted 2025/03/30 01:19:09 INFO : empty space: Deleted 2025/03/30 01:19:24 INFO : potato2: Copied (server-side copy) 2025/03/30 01:19:29 INFO : potato2: Deleted 2025/03/30 01:19:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lefimip4lija': Purge remote 2025/03/30 01:19:34 DEBUG : Waiting for deletions to finish 2025/03/30 01:19:35 INFO : potato3.bin: Deleted 2025/03/30 01:19:35 INFO : empty space.bin: Deleted 2025/03/30 01:19:40 INFO : potato2.bin: Deleted 2025/03/30 01:19:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dapihut7puhu': Purge remote 2025/03/30 01:19:40 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (120.01s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:19:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hufiyop9guti" 2025/03/30 01:19:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hufiyop9guti.bin" 2025/03/30 01:19:41 DEBUG : Swift container crypt path rclone-test-hufiyop9guti.bin: Auto set use_segments_container to true 2025/03/30 01:19:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hufiyop9guti" 2025/03/30 01:19:47 DEBUG : Swift container crypt path rclone-test-hufiyop9guti: Auto set use_segments_container to true 2025/03/30 01:19:57 DEBUG : potato2: md5 = 25384cc7908c1cc65b812f479940eaeb OK 2025/03/30 01:20:03 DEBUG : empty space: md5 = c24c869c3e423c8f6248b4bc9f1b5822 OK 2025/03/30 01:20:03 DEBUG : potato3: md5 = 855d85412fbd8aa4b66f289575a39553 OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-hufiyop9guti' 2025/03/30 01:20:03 DEBUG : empty space: md5 = 60182e898bfe61e060e6dfc8f5b469b5 OK 2025/03/30 01:20:04 DEBUG : potato3: md5 = 2471d7167340916a6e7672988361a06c OK 2025/03/30 01:20:04 DEBUG : empty space: Excluded (Size Filter) 2025/03/30 01:20:04 DEBUG : empty space: Excluded 2025/03/30 01:20:04 DEBUG : empty space: Excluded (Size Filter) 2025/03/30 01:20:04 DEBUG : empty space: Excluded 2025/03/30 01:20:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:20:04 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/03/30 01:20:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hufiyop9guti': Waiting for checks to finish 2025/03/30 01:20:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hufiyop9guti': Waiting for transfers to finish 2025/03/30 01:20:09 INFO : potato3: Copied (server-side copy) 2025/03/30 01:20:09 INFO : potato3: Deleted 2025/03/30 01:20:10 INFO : potato2: Copied (server-side copy) 2025/03/30 01:20:15 INFO : potato2: Deleted 2025/03/30 01:20:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kitoxaz3kanu" 2025/03/30 01:20:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kitoxaz3kanu.bin" 2025/03/30 01:20:16 DEBUG : Swift container crypt path rclone-test-kitoxaz3kanu.bin: Auto set use_segments_container to true 2025/03/30 01:20:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kitoxaz3kanu" 2025/03/30 01:20:22 DEBUG : Swift container crypt path rclone-test-kitoxaz3kanu: Auto set use_segments_container to true 2025/03/30 01:20:28 DEBUG : empty space: Excluded (Size Filter) 2025/03/30 01:20:28 DEBUG : empty space: Excluded 2025/03/30 01:20:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:20:28 DEBUG : potato3: Need to transfer - File not found at Destination 2025/03/30 01:20:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitoxaz3kanu': Waiting for checks to finish 2025/03/30 01:20:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitoxaz3kanu': Waiting for transfers to finish 2025/03/30 01:20:38 INFO : potato2: Copied (server-side copy) 2025/03/30 01:20:38 INFO : potato2: Deleted 2025/03/30 01:20:43 INFO : potato3: Copied (server-side copy) 2025/03/30 01:20:43 INFO : potato3: Deleted 2025/03/30 01:20:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitoxaz3kanu': Purge remote 2025/03/30 01:20:44 DEBUG : Waiting for deletions to finish 2025/03/30 01:20:49 INFO : potato3.bin: Deleted 2025/03/30 01:20:49 INFO : potato2.bin: Deleted 2025/03/30 01:20:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hufiyop9guti': Purge remote 2025/03/30 01:20:49 DEBUG : Waiting for deletions to finish 2025/03/30 01:20:49 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (74.60s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:20:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xiguheh6haco" 2025/03/30 01:20:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xiguheh6haco.bin" 2025/03/30 01:20:55 DEBUG : Swift container crypt path rclone-test-xiguheh6haco.bin: Auto set use_segments_container to true 2025/03/30 01:21:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xiguheh6haco" 2025/03/30 01:21:01 DEBUG : Swift container crypt path rclone-test-xiguheh6haco: Auto set use_segments_container to true 2025/03/30 01:21:12 DEBUG : potato2: md5 = 8de3444e12b4543b31d0a4be0bb70c7d OK 2025/03/30 01:21:17 DEBUG : empty space: md5 = bb83944a8b106a6864e8ee45c1056988 OK 2025/03/30 01:21:17 DEBUG : potato3: md5 = ddfc201198ed39075c2bb9f4d684d9da OK 2025/03/30 01:21:17 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja' -> Encrypted drive 'TestCryptSwift:rclone-test-xiguheh6haco' 2025/03/30 01:21:23 DEBUG : empty space: md5 = ad51b0a9231a1c3aeabe14463233846e OK 2025/03/30 01:21:29 DEBUG : potato3: md5 = ac73607469e05f5420510ab9bb7062ea OK 2025/03/30 01:21:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:21:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/03/30 01:21:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xiguheh6haco': Waiting for checks to finish 2025/03/30 01:21:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:21:30 DEBUG : empty space: Unchanged skipping 2025/03/30 01:21:35 INFO : empty space: Deleted 2025/03/30 01:21:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xiguheh6haco': Waiting for transfers to finish 2025/03/30 01:21:35 INFO : potato2: Copied (server-side copy) 2025/03/30 01:21:40 INFO : potato2: Deleted 2025/03/30 01:21:45 INFO : potato3: Copied (server-side copy) 2025/03/30 01:21:45 INFO : potato3: Deleted 2025/03/30 01:21:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gukasab9riwu" 2025/03/30 01:21:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gukasab9riwu.bin" 2025/03/30 01:21:46 DEBUG : Swift container crypt path rclone-test-gukasab9riwu.bin: Auto set use_segments_container to true 2025/03/30 01:21:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gukasab9riwu" 2025/03/30 01:21:52 DEBUG : Swift container crypt path rclone-test-gukasab9riwu: Auto set use_segments_container to true 2025/03/30 01:21:57 INFO : tomatoDir: Making directory 2025/03/30 01:21:57 DEBUG : empty space: Need to transfer - File not found at Destination 2025/03/30 01:21:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/03/30 01:21:57 DEBUG : potato3: Need to transfer - File not found at Destination 2025/03/30 01:21:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gukasab9riwu': Waiting for checks to finish 2025/03/30 01:21:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gukasab9riwu': Waiting for transfers to finish 2025/03/30 01:22:08 INFO : potato2: Copied (server-side copy) 2025/03/30 01:22:08 INFO : potato3: Copied (server-side copy) 2025/03/30 01:22:08 INFO : empty space: Copied (server-side copy) 2025/03/30 01:22:08 INFO : potato2: Deleted 2025/03/30 01:22:08 INFO : empty space: Deleted 2025/03/30 01:22:13 INFO : potato3: Deleted 2025/03/30 01:22:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gukasab9riwu': Purge remote 2025/03/30 01:22:19 DEBUG : Waiting for deletions to finish 2025/03/30 01:22:24 INFO : potato2.bin: Deleted 2025/03/30 01:22:24 INFO : potato3.bin: Deleted 2025/03/30 01:22:29 INFO : empty space.bin: Deleted 2025/03/30 01:22:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xiguheh6haco': Purge remote 2025/03/30 01:22:29 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (94.35s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:22:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/rclone-move-test" 2025/03/30 01:22:29 DEBUG : Config file has changed externally - reloading 2025/03/30 01:22:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-move-test.bin" 2025/03/30 01:22:30 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-move-test.bin: Auto set use_segments_container to true 2025/03/30 01:22:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-move-test" 2025/03/30 01:22:36 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-move-test: Auto set use_segments_container to true 2025/03/30 01:22:47 DEBUG : potato2: md5 = d94eeb5a06f876634ec90500fb29ddba OK --- PASS: TestServerSideMoveOverlap (27.88s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:22:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test" 2025/03/30 01:22:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test.bin" 2025/03/30 01:22:58 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test.bin: Auto set use_segments_container to true 2025/03/30 01:23:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test" 2025/03/30 01:23:04 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncOverlap (12.97s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:23:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test" 2025/03/30 01:23:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test.bin" 2025/03/30 01:23:11 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test.bin: Auto set use_segments_container to true 2025/03/30 01:23:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test" 2025/03/30 01:23:17 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test: Auto set use_segments_container to true 2025/03/30 01:23:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-include/layer2" 2025/03/30 01:23:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test-include/layer2.bin" 2025/03/30 01:23:22 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/03/30 01:23:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test-include/layer2" 2025/03/30 01:23:28 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/03/30 01:23:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-ignore-file" 2025/03/30 01:23:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test-ignore-file.bin" 2025/03/30 01:23:39 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/03/30 01:23:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/rclone-sync-test-ignore-file" 2025/03/30 01:23:45 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/03/30 01:23:51 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 68e024564336540e05bd85dc4625ae30 OK 2025/03/30 01:23:56 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/30 01:23:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test': Waiting for checks to finish 2025/03/30 01:23:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test': Waiting for transfers to finish 2025/03/30 01:23:56 DEBUG : Waiting for deletions to finish 2025/03/30 01:23:56 INFO : There was nothing to transfer 2025/03/30 01:24:02 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/30 01:24:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:24:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:24:02 DEBUG : Waiting for deletions to finish 2025/03/30 01:24:02 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:24:07 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/30 01:24:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/03/30 01:24:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/03/30 01:24:07 DEBUG : Waiting for deletions to finish 2025/03/30 01:24:07 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:24:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/30 01:24:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:24:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:24:12 DEBUG : Waiting for deletions to finish 2025/03/30 01:24:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:24:12 DEBUG : : Excluded 2025/03/30 01:24:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/03/30 01:24:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/03/30 01:24:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/03/30 01:24:12 DEBUG : Waiting for deletions to finish 2025/03/30 01:24:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (66.13s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:24:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:24:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:24:17 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:24:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:24:23 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:24:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/CompareDest" 2025/03/30 01:24:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/CompareDest.bin" 2025/03/30 01:24:29 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/CompareDest.bin: Auto set use_segments_container to true 2025/03/30 01:24:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/CompareDest" 2025/03/30 01:24:35 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/CompareDest: Auto set use_segments_container to true 2025/03/30 01:24:45 DEBUG : one: Need to transfer - File not found at Destination 2025/03/30 01:24:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:24:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:24:51 DEBUG : one: md5 = f48d97afbabfe4974de26782509c9eff OK 2025/03/30 01:24:51 INFO : one: Copied (new) 2025/03/30 01:24:51 DEBUG : Waiting for deletions to finish 2025/03/30 01:24:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:24:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:24:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:25:06 DEBUG : one: md5 = 5b9ca12ae38b5477abcd621214803aed OK 2025/03/30 01:25:06 INFO : one: Copied (replaced existing) 2025/03/30 01:25:06 DEBUG : Waiting for deletions to finish 2025/03/30 01:25:17 DEBUG : dst/one: md5 = c66300f14aaa619b414eb6509dec9542 OK 2025/03/30 01:25:17 DEBUG : CompareDest/one: md5 = 0784816090751647fd3115c8892f1c22 OK 2025/03/30 01:25:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:25:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:25:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:18 DEBUG : one: Destination found in --compare-dest, skipping 2025/03/30 01:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:25:18 DEBUG : Waiting for deletions to finish 2025/03/30 01:25:18 INFO : There was nothing to transfer 2025/03/30 01:25:18 DEBUG : CompareDest/two: md5 = 6358c14992b1bcfac0f1705d54af0218 OK 2025/03/30 01:25:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:19 DEBUG : two: Destination found in --compare-dest, skipping 2025/03/30 01:25:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:25:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:19 DEBUG : one: Destination found in --compare-dest, skipping 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:25:19 DEBUG : Waiting for deletions to finish 2025/03/30 01:25:19 INFO : There was nothing to transfer 2025/03/30 01:25:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:19 DEBUG : two: Destination found in --compare-dest, skipping 2025/03/30 01:25:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:25:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:19 DEBUG : one: Destination found in --compare-dest, skipping 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:25:19 DEBUG : Waiting for deletions to finish 2025/03/30 01:25:19 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/03/30 01:25:19 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/03/30 01:25:19 DEBUG : two: Need to transfer - File not found at Destination 2025/03/30 01:25:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:25:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:25:19 DEBUG : one: Destination found in --compare-dest, skipping 2025/03/30 01:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:25:25 DEBUG : two: md5 = 7968feb235011bf1923d605251413313 OK 2025/03/30 01:25:25 INFO : two: Copied (new) 2025/03/30 01:25:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (84.65s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:25:42 DEBUG : pre-dest1/1: md5 = c94b16d7954b23f247147899f2079816 OK 2025/03/30 01:25:47 DEBUG : pre-dest2/2: md5 = bc3597ffabe9e6ae7927dde4d11a7d6e OK 2025/03/30 01:25:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dest" 2025/03/30 01:25:47 DEBUG : Config file has changed externally - reloading 2025/03/30 01:25:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dest.bin" 2025/03/30 01:25:48 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dest.bin: Auto set use_segments_container to true 2025/03/30 01:25:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dest" 2025/03/30 01:25:54 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dest: Auto set use_segments_container to true 2025/03/30 01:25:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/pre-dest1" 2025/03/30 01:25:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/pre-dest1.bin" 2025/03/30 01:25:59 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/pre-dest1.bin: Auto set use_segments_container to true 2025/03/30 01:26:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/pre-dest1" 2025/03/30 01:26:05 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/pre-dest1: Auto set use_segments_container to true 2025/03/30 01:26:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/pre-dest2" 2025/03/30 01:26:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/pre-dest2.bin" 2025/03/30 01:26:11 DEBUG : Config file has changed externally - reloading 2025/03/30 01:26:11 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/pre-dest2.bin: Auto set use_segments_container to true 2025/03/30 01:26:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/pre-dest2" 2025/03/30 01:26:17 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/pre-dest2: Auto set use_segments_container to true 2025/03/30 01:26:22 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:26:22 DEBUG : 1: Destination found in --compare-dest, skipping 2025/03/30 01:26:33 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:26:33 DEBUG : 2: Destination found in --compare-dest, skipping 2025/03/30 01:26:43 DEBUG : 3: Need to transfer - File not found at Destination 2025/03/30 01:26:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dest': Waiting for checks to finish 2025/03/30 01:26:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dest': Waiting for transfers to finish 2025/03/30 01:26:43 DEBUG : 3: md5 = f22ded6a02eba915da286bffd5ed6fae OK 2025/03/30 01:26:43 INFO : 3: Copied (new) 2025/03/30 01:26:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (78.50s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:26:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:26:59 DEBUG : Config file has changed externally - reloading 2025/03/30 01:26:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:27:00 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:27:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:27:06 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:27:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/CopyDest" 2025/03/30 01:27:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/CopyDest.bin" 2025/03/30 01:27:12 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/CopyDest.bin: Auto set use_segments_container to true 2025/03/30 01:27:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/CopyDest" 2025/03/30 01:27:17 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/CopyDest: Auto set use_segments_container to true 2025/03/30 01:27:28 DEBUG : one: Need to transfer - File not found at Destination 2025/03/30 01:27:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:27:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:27:33 DEBUG : one: md5 = 2b7d684cd555820520508797ae700203 OK 2025/03/30 01:27:33 INFO : one: Copied (new) 2025/03/30 01:27:33 DEBUG : Waiting for deletions to finish 2025/03/30 01:27:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:27:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:27:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:27:54 DEBUG : one: md5 = c01d711f9430e5d1123af80b1d82456e OK 2025/03/30 01:27:54 INFO : one: Copied (replaced existing) 2025/03/30 01:27:54 DEBUG : Waiting for deletions to finish 2025/03/30 01:27:59 DEBUG : dst/one: md5 = 2f1fd001d62fb7835df8a14a2ae5a987 OK 2025/03/30 01:28:00 DEBUG : CopyDest/one: md5 = 516640bd553b5505dfcac91b704b1a8c OK 2025/03/30 01:28:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/BackupDir" 2025/03/30 01:28:00 DEBUG : Config file has changed externally - reloading 2025/03/30 01:28:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/BackupDir.bin" 2025/03/30 01:28:00 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/BackupDir.bin: Auto set use_segments_container to true 2025/03/30 01:28:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/BackupDir" 2025/03/30 01:28:06 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/BackupDir: Auto set use_segments_container to true 2025/03/30 01:28:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:28:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:28:12 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:28:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/03/30 01:28:22 INFO : one: Copied (server-side copy) 2025/03/30 01:28:27 INFO : one: Deleted 2025/03/30 01:28:43 INFO : one: Copied (server-side copy) 2025/03/30 01:28:43 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/03/30 01:28:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:28:43 DEBUG : Waiting for deletions to finish 2025/03/30 01:28:43 DEBUG : CopyDest/two: md5 = f04bae79b826bf3d838a53baeb1635d7 OK 2025/03/30 01:28:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:28:59 INFO : two: Copied (server-side copy) 2025/03/30 01:28:59 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/03/30 01:28:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:28:59 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:28:59 DEBUG : one: Unchanged skipping 2025/03/30 01:28:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:28:59 DEBUG : Waiting for deletions to finish 2025/03/30 01:29:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:29:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:29:05 DEBUG : one: Unchanged skipping 2025/03/30 01:29:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:29:05 DEBUG : two: Unchanged skipping 2025/03/30 01:29:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:29:05 DEBUG : Waiting for deletions to finish 2025/03/30 01:29:05 INFO : There was nothing to transfer 2025/03/30 01:29:11 DEBUG : CopyDest/three: md5 = dd1352c382cd0512cc915ade4d3cc595 OK 2025/03/30 01:29:26 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/03/30 01:29:26 DEBUG : three: Destination not found in --copy-dest 2025/03/30 01:29:26 DEBUG : three: Need to transfer - File not found at Destination 2025/03/30 01:29:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:29:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:29:26 DEBUG : one: Unchanged skipping 2025/03/30 01:29:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:29:31 DEBUG : two: Unchanged skipping 2025/03/30 01:29:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:29:31 DEBUG : three: md5 = 46b165c8e5e489352412ab1fbb76918b OK 2025/03/30 01:29:31 INFO : three: Copied (new) 2025/03/30 01:29:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (183.99s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:30:09 DEBUG : dst/one: md5 = cbac1a187a5c832f3f5435afda8eb63a OK 2025/03/30 01:30:14 DEBUG : dst/two: md5 = 444a4edad56570fe26692bceba69f144 OK 2025/03/30 01:30:19 DEBUG : dst/three.txt: md5 = 91c8e078fce1b84625b324cd4679c8bb OK 2025/03/30 01:30:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:30:25 DEBUG : Config file has changed externally - reloading 2025/03/30 01:30:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:30:25 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:30:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:30:31 DEBUG : Config file has changed externally - reloading 2025/03/30 01:30:31 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:30:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/backup" 2025/03/30 01:30:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup.bin" 2025/03/30 01:30:37 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup.bin: Auto set use_segments_container to true 2025/03/30 01:30:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup" 2025/03/30 01:30:43 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup: Auto set use_segments_container to true 2025/03/30 01:30:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:30:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:30:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:30:48 DEBUG : two: Unchanged skipping 2025/03/30 01:31:04 INFO : one: Copied (server-side copy) 2025/03/30 01:31:09 INFO : one: Deleted 2025/03/30 01:31:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:31:14 DEBUG : one: md5 = eb898904b78986a7712369737100b1cf OK 2025/03/30 01:31:14 INFO : one: Copied (new) 2025/03/30 01:31:14 DEBUG : Waiting for deletions to finish 2025/03/30 01:31:30 INFO : three.txt: Copied (server-side copy) 2025/03/30 01:31:35 INFO : three.txt: Deleted 2025/03/30 01:31:35 INFO : three.txt: Moved into backup dir 2025/03/30 01:31:46 DEBUG : dst/three.txt: md5 = bf434a4febb51a43b65ed8601a491dad OK 2025/03/30 01:32:01 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:32:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:32:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:32:01 DEBUG : two: Unchanged skipping 2025/03/30 01:32:22 INFO : one: Copied (server-side copy) 2025/03/30 01:32:27 INFO : one: Deleted 2025/03/30 01:32:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:32:32 DEBUG : one: md5 = 1c09ffb204137510cb6cb4f75aa4bb10 OK 2025/03/30 01:32:32 INFO : one: Copied (new) 2025/03/30 01:32:32 DEBUG : Waiting for deletions to finish 2025/03/30 01:32:37 INFO : three.txt: Copied (server-side copy) 2025/03/30 01:32:42 INFO : three.txt: Deleted 2025/03/30 01:32:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (185.48s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:33:14 DEBUG : dst/one: md5 = 3e2fce92b54a365641f12dd0f876927f OK 2025/03/30 01:33:19 DEBUG : dst/two: md5 = 0e5f56b79fc158f4edb0ccd2536f273e OK 2025/03/30 01:33:24 DEBUG : dst/three.txt: md5 = 0db3f47a32f2472673f6538949951b5b OK 2025/03/30 01:33:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:33:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:33:30 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:33:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:33:36 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:33:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/backup" 2025/03/30 01:33:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup.bin" 2025/03/30 01:33:42 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup.bin: Auto set use_segments_container to true 2025/03/30 01:33:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup" 2025/03/30 01:33:48 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup: Auto set use_segments_container to true 2025/03/30 01:33:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:33:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:33:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:33:58 DEBUG : two: Unchanged skipping 2025/03/30 01:34:13 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:34:19 INFO : one: Deleted 2025/03/30 01:34:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:34:24 DEBUG : one: md5 = 5115dda896e7e10ed92480f0b9139455 OK 2025/03/30 01:34:24 INFO : one: Copied (new) 2025/03/30 01:34:24 DEBUG : Waiting for deletions to finish 2025/03/30 01:34:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:34:40 INFO : three.txt: Deleted 2025/03/30 01:34:40 INFO : three.txt: Moved into backup dir 2025/03/30 01:34:55 DEBUG : dst/three.txt: md5 = dd2203420676b7a2c880f4ad6737dcd8 OK 2025/03/30 01:35:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:35:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:35:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:35:11 DEBUG : two: Unchanged skipping 2025/03/30 01:35:26 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:35:31 INFO : one: Deleted 2025/03/30 01:35:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:35:37 DEBUG : one: md5 = 1805c7a5a7252cad109c2e44bf33d1ab OK 2025/03/30 01:35:37 INFO : one: Copied (new) 2025/03/30 01:35:37 DEBUG : Waiting for deletions to finish 2025/03/30 01:35:42 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:35:47 INFO : three.txt: Deleted 2025/03/30 01:35:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (179.17s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:36:13 DEBUG : dst/one: md5 = 3ba97fc9e3f9d47aa2d1aac41b8165e6 OK 2025/03/30 01:36:18 DEBUG : dst/two: md5 = c9a7022e9a89530993facc4870cff611 OK 2025/03/30 01:36:23 DEBUG : dst/three.txt: md5 = 37796aea8205fdeb8274238c3b385dd4 OK 2025/03/30 01:36:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:36:34 DEBUG : Config file has changed externally - reloading 2025/03/30 01:36:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:36:34 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:36:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:36:40 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:36:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/backup" 2025/03/30 01:36:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup.bin" 2025/03/30 01:36:46 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup.bin: Auto set use_segments_container to true 2025/03/30 01:36:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/backup" 2025/03/30 01:36:52 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/backup: Auto set use_segments_container to true 2025/03/30 01:36:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:36:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:37:02 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:37:02 DEBUG : two: Unchanged skipping 2025/03/30 01:37:18 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/03/30 01:37:23 INFO : one: Deleted 2025/03/30 01:37:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:37:28 DEBUG : one: md5 = bd4a98ba38cdbc65b595b4a3c115993f OK 2025/03/30 01:37:28 INFO : one: Copied (new) 2025/03/30 01:37:28 DEBUG : Waiting for deletions to finish 2025/03/30 01:37:39 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/03/30 01:37:44 INFO : three.txt: Deleted 2025/03/30 01:37:44 INFO : three.txt: Moved into backup dir 2025/03/30 01:37:59 DEBUG : dst/three.txt: md5 = 93479d3a73109376f4e6fcbc774ab413 OK 2025/03/30 01:38:05 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:38:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:38:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:38:05 DEBUG : two: Unchanged skipping 2025/03/30 01:38:20 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/03/30 01:38:26 INFO : one: Deleted 2025/03/30 01:38:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:38:31 DEBUG : one: md5 = 6739090edee1c866995d08fe8984d832 OK 2025/03/30 01:38:31 INFO : one: Copied (new) 2025/03/30 01:38:31 DEBUG : Waiting for deletions to finish 2025/03/30 01:38:36 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/03/30 01:38:41 INFO : three.txt: Deleted 2025/03/30 01:38:41 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (174.67s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:39:08 DEBUG : dst/one: md5 = 639a14d2d4c5964860c6ddbc85a9ea74 OK 2025/03/30 01:39:13 DEBUG : dst/two: md5 = 5c784f024c3e6a92fdf8e9b0e5761433 OK 2025/03/30 01:39:18 DEBUG : dst/three.txt: md5 = 48bc78358988d63880818e15b9530b42 OK 2025/03/30 01:39:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:39:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:39:29 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:39:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:39:35 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:39:40 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:39:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:39:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:39:40 DEBUG : two: Unchanged skipping 2025/03/30 01:39:51 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:39:56 INFO : one: Deleted 2025/03/30 01:39:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:40:01 DEBUG : one: md5 = a45400135495f9ac5f8f36d2a0dbce74 OK 2025/03/30 01:40:01 INFO : one: Copied (new) 2025/03/30 01:40:01 DEBUG : Waiting for deletions to finish 2025/03/30 01:40:17 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:40:22 INFO : three.txt: Deleted 2025/03/30 01:40:22 INFO : three.txt: Moved into backup dir 2025/03/30 01:40:38 DEBUG : dst/three.txt: md5 = bafe4568b023ae6c4a3f1b3672d85add OK 2025/03/30 01:40:48 DEBUG : one.bak: Excluded (Path Filter) 2025/03/30 01:40:48 DEBUG : one.bak: Excluded 2025/03/30 01:40:48 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/03/30 01:40:48 DEBUG : three.txt.bak: Excluded 2025/03/30 01:40:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:40:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for checks to finish 2025/03/30 01:40:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:40:48 DEBUG : two: Unchanged skipping 2025/03/30 01:40:53 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:40:59 INFO : one: Deleted 2025/03/30 01:40:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja/dst': Waiting for transfers to finish 2025/03/30 01:41:04 DEBUG : one: md5 = 09cb351e6c50eda71bc843f8f2f6e2d3 OK 2025/03/30 01:41:04 INFO : one: Copied (new) 2025/03/30 01:41:04 DEBUG : Waiting for deletions to finish 2025/03/30 01:41:24 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:41:29 INFO : three.txt: Deleted 2025/03/30 01:41:29 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (162.92s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:41:51 DEBUG : dst/one: md5 = 374413b79485cd7505c4f4a4bf000767 OK 2025/03/30 01:41:56 DEBUG : dst/two: md5 = 435dc51994ac34b110355e163d3c7d57 OK 2025/03/30 01:42:01 DEBUG : dst/three.txt: md5 = 43df83e481c845df08a3d05d0603f013 OK 2025/03/30 01:42:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:42:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:42:02 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:42:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:42:08 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:42:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:42:23 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:42:29 INFO : one: Deleted 2025/03/30 01:42:34 DEBUG : one: md5 = 78a4177636f401ad9c0af7f3502770db OK 2025/03/30 01:42:34 INFO : one: Copied (new) 2025/03/30 01:42:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:42:34 DEBUG : two: Unchanged skipping 2025/03/30 01:42:39 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/03/30 01:42:54 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:42:59 INFO : three.txt: Deleted 2025/03/30 01:43:05 DEBUG : three.txt: md5 = b746b003f3b97f81038128d883455d99 OK 2025/03/30 01:43:05 INFO : three.txt: Copied (new) 2025/03/30 01:43:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:43:36 INFO : one: Copied (server-side copy) to: one.bak 2025/03/30 01:43:41 INFO : one: Deleted 2025/03/30 01:43:46 DEBUG : one: md5 = 82f36a942964ce472ac6bd5a2e88084d OK 2025/03/30 01:43:46 INFO : one: Copied (new) 2025/03/30 01:43:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:43:51 DEBUG : two: Unchanged skipping 2025/03/30 01:43:56 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/03/30 01:44:07 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/03/30 01:44:12 INFO : three.txt: Deleted 2025/03/30 01:44:17 DEBUG : three.txt: md5 = ab85b18aed8aa53f34e80b830dccf239 OK 2025/03/30 01:44:17 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (192.80s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:45:04 DEBUG : dst/one: md5 = 2d055541c9e89ae33324447242b01b4d OK 2025/03/30 01:45:09 DEBUG : dst/two: md5 = 1017af75ab4927033041354a17a49120 OK 2025/03/30 01:45:14 DEBUG : dst/three.txt: md5 = 07632638c4cd95ed36eeae1bfe55519b OK 2025/03/30 01:45:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sixifit8qoja/dst" 2025/03/30 01:45:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst.bin" 2025/03/30 01:45:15 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst.bin: Auto set use_segments_container to true 2025/03/30 01:45:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sixifit8qoja/dst" 2025/03/30 01:45:21 DEBUG : Swift container crypt path rclone-test-sixifit8qoja/dst: Auto set use_segments_container to true 2025/03/30 01:45:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/03/30 01:45:46 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/03/30 01:45:52 INFO : one: Deleted 2025/03/30 01:45:57 DEBUG : one: md5 = c39b4877ae2b6f0463fec8c797e454e4 OK 2025/03/30 01:45:57 INFO : one: Copied (new) 2025/03/30 01:45:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:45:57 DEBUG : two: Unchanged skipping 2025/03/30 01:45:57 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/03/30 01:46:07 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/03/30 01:46:12 INFO : three.txt: Deleted 2025/03/30 01:46:18 DEBUG : three.txt: md5 = a9d3dcd5285d9fedf1e65f6ff60e7d1f OK 2025/03/30 01:46:18 INFO : three.txt: Copied (new) 2025/03/30 01:46:33 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/03/30 01:46:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/03/30 01:46:59 INFO : one: Deleted 2025/03/30 01:47:04 DEBUG : one: md5 = 394e8c861e2ee76ce973d271c78d8872 OK 2025/03/30 01:47:04 INFO : one: Copied (new) 2025/03/30 01:47:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:47:04 DEBUG : two: Unchanged skipping 2025/03/30 01:47:04 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/03/30 01:47:09 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/03/30 01:47:15 INFO : three.txt: Deleted 2025/03/30 01:47:20 DEBUG : three.txt: md5 = 1e4f1e1e2f9413817cb68bfcdc55c470 OK 2025/03/30 01:47:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (187.58s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:48:06 DEBUG : Testêé: md5 = a03b0fbf784bc8e2a1ee3d19182e6999 OK 2025/03/30 01:48:07 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/03/30 01:48:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:48:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:48:07 DEBUG : Testêé: md5 = 94d6946decef86a376513ec5c2527394 OK 2025/03/30 01:48:07 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/03/30 01:48:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.30s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:48:07 DEBUG : existing: Need to transfer - File not found at Destination 2025/03/30 01:48:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:48:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:48:08 DEBUG : existing: md5 = c76c493f40853ef4155d1df5e67f64fe OK 2025/03/30 01:48:08 INFO : existing: Copied (new) 2025/03/30 01:48:08 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:48:08 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/03/30 01:48:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:48:08 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/03/30 01:48:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:48:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': not deleting files as there were IO errors 2025/03/30 01:48:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncImmutable (1.70s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:48:15 DEBUG : EXISTING: md5 = 0c6a8f5bd2ac5f9d61d0daffdfc99715 OK 2025/03/30 01:48:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:48:25 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:48:25 DEBUG : existing: Unchanged skipping 2025/03/30 01:48:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:48:25 DEBUG : Waiting for deletions to finish 2025/03/30 01:48:25 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (26.48s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.20s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (0.60s) --- SKIP: TestMaxTransfer/Hard (0.20s) --- SKIP: TestMaxTransfer/Soft (0.20s) --- SKIP: TestMaxTransfer/Cautious (0.20s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:48:42 DEBUG : both0: md5 = 225d7a3530c58ee6f76f8a3ee98a2276 OK 2025/03/30 01:48:47 DEBUG : only0: md5 = 114862bfd9157fb1ef19667928e9808c OK 2025/03/30 01:48:47 DEBUG : both1: md5 = 020e79e2829c7ba5d49e454573c94bbb OK 2025/03/30 01:48:52 DEBUG : only1: md5 = cf1eb6c7a053a4d101434f6336a55492 OK 2025/03/30 01:48:53 DEBUG : both2: md5 = 211b67a7c5b2fdbba45d5d44283b77e8 OK 2025/03/30 01:48:53 DEBUG : only2: md5 = d900c53bb5685a1cda04011438e83f3c OK 2025/03/30 01:48:58 DEBUG : both3: md5 = 15cc39642f0f70afd7637151203c6f6c OK 2025/03/30 01:48:59 DEBUG : only3: md5 = 9cdf7cf2a872b7082606a6edef86c4b2 OK 2025/03/30 01:49:04 DEBUG : both4: md5 = c01debeb1a1c7b0ff05feb5341034705 OK 2025/03/30 01:49:05 DEBUG : only4: md5 = 5fa34ddf10b73053b1fc79099a353c8e OK 2025/03/30 01:49:05 DEBUG : both5: md5 = bcc7f48ecca992cd6bc638a3ac940e5a OK 2025/03/30 01:49:05 DEBUG : only5: md5 = 8ca5e0ff0399c24165b945454730263a OK 2025/03/30 01:49:06 DEBUG : both6: md5 = c961a61f718318fbec6bf92f37bfc513 OK 2025/03/30 01:49:12 DEBUG : only6: md5 = a53b804f22cd291499e0cd81b21a173c OK 2025/03/30 01:49:12 DEBUG : both7: md5 = 701d1522f53a60928d6b0b4bc3d0bdbd OK 2025/03/30 01:49:13 DEBUG : only7: md5 = 167d2b56c549600d9b575066696f2c60 OK 2025/03/30 01:49:13 DEBUG : both8: md5 = 38218e7b02e742422acf67a3690ba18a OK 2025/03/30 01:49:18 DEBUG : only8: md5 = 5cc50b7cb69d25fbdc08963986c4b544 OK 2025/03/30 01:49:24 DEBUG : both9: md5 = 5b9fc75dc4e9091dbfdf1a418f6052a9 OK 2025/03/30 01:49:24 DEBUG : only9: md5 = 8d2b3b8c999edd94ab28f0996c23d943 OK 2025/03/30 01:49:24 DEBUG : both10: md5 = 959bf0304387164455b72b6cabecba10 OK 2025/03/30 01:49:24 DEBUG : only10: md5 = 352861f0750f6762068240e802f69f22 OK 2025/03/30 01:49:25 DEBUG : both11: md5 = f2886dc0631bf0f6dea450dffad7a6bb OK 2025/03/30 01:49:30 DEBUG : only11: md5 = 9c0f9cb07f443a4f9eea8d21e1a187cf OK 2025/03/30 01:49:31 DEBUG : both12: md5 = 71e60b2d2d479a28519462f9b9af9d61 OK 2025/03/30 01:49:36 DEBUG : only12: md5 = eaca454b6a9d4faf43622b4333e27b07 OK 2025/03/30 01:49:41 DEBUG : both13: md5 = e733bb5312dbd64a59260a6ef9c6e40e OK 2025/03/30 01:49:42 DEBUG : only13: md5 = 94b6fc6eb81bd6fa96fe920a3c9d8103 OK 2025/03/30 01:49:47 DEBUG : both14: md5 = 92a2ef4c4a842ef75e1cb60b992c174d OK 2025/03/30 01:49:53 DEBUG : only14: md5 = 0b2fdc20519f236ee3921d6e77a098cd OK 2025/03/30 01:49:54 DEBUG : both15: md5 = 21ff21b0e677f735e96ff4464a4222e9 OK 2025/03/30 01:49:59 DEBUG : only15: md5 = 6eb5a27771af3d9bb919a16a9b7d5566 OK 2025/03/30 01:50:04 DEBUG : both16: md5 = 6c0c9b426bd8584c891dc6010fe9990f OK 2025/03/30 01:50:04 DEBUG : only16: md5 = b85b47722dcd2b1ea5fcf5ea6b2e4f89 OK 2025/03/30 01:50:05 DEBUG : both17: md5 = 0fbb5c420edb92526485fdf903f5b94f OK 2025/03/30 01:50:05 DEBUG : only17: md5 = 046f46f0d797b572a82dbde1cb838cf9 OK 2025/03/30 01:50:11 DEBUG : both18: md5 = 29ebb04b028fd25e0b598e066b24138d OK 2025/03/30 01:50:16 DEBUG : only18: md5 = a3faeeee3971fc5f56d61324b050cf46 OK 2025/03/30 01:50:17 DEBUG : both19: md5 = c1018bf443e4684f41d0754922d95e6c OK 2025/03/30 01:50:22 DEBUG : only19: md5 = fcd5dd574b361ab30b480601f07a8763 OK 2025/03/30 01:51:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:51:09 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both0: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both1: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both10: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both11: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both12: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both14: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both15: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both16: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both17: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both19: Unchanged skipping 2025/03/30 01:51:09 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:09 DEBUG : both2: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both4: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both5: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both6: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both7: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both8: Unchanged skipping 2025/03/30 01:51:10 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:10 DEBUG : both9: Unchanged skipping 2025/03/30 01:51:14 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:14 DEBUG : both13: Unchanged skipping 2025/03/30 01:51:14 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:14 DEBUG : both18: Unchanged skipping 2025/03/30 01:51:14 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:51:14 DEBUG : both3: Unchanged skipping 2025/03/30 01:51:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:51:14 DEBUG : Waiting for deletions to finish 2025/03/30 01:51:15 INFO : only13: Deleted 2025/03/30 01:51:15 INFO : only10: Deleted 2025/03/30 01:51:15 INFO : only2: Deleted 2025/03/30 01:51:15 INFO : only16: Deleted 2025/03/30 01:51:15 INFO : only3: Deleted 2025/03/30 01:51:16 INFO : only17: Deleted 2025/03/30 01:51:16 INFO : only9: Deleted 2025/03/30 01:51:20 INFO : only11: Deleted 2025/03/30 01:51:20 INFO : only19: Deleted 2025/03/30 01:51:20 INFO : only8: Deleted 2025/03/30 01:51:21 INFO : only5: Deleted 2025/03/30 01:51:21 INFO : only12: Deleted 2025/03/30 01:51:21 INFO : only7: Deleted 2025/03/30 01:51:25 INFO : only15: Deleted 2025/03/30 01:51:25 INFO : only18: Deleted 2025/03/30 01:51:25 INFO : only4: Deleted 2025/03/30 01:51:26 INFO : only0: Deleted 2025/03/30 01:51:30 INFO : only6: Deleted 2025/03/30 01:51:31 INFO : only1: Deleted 2025/03/30 01:51:35 INFO : only14: Deleted 2025/03/30 01:51:35 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (268.61s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:53:10 DEBUG : both0: md5 = d5adfe23e25fc793b55a06c1c8870106 OK 2025/03/30 01:53:15 DEBUG : only0: md5 = 3d047ad16d76816c4f5005823fa6343e OK 2025/03/30 01:53:15 DEBUG : both1: md5 = 6a833049df7c756367f6ee1fb7ff813f OK 2025/03/30 01:53:21 DEBUG : only1: md5 = 4442d7edb6e5ceee104f66c264a91093 OK 2025/03/30 01:53:21 DEBUG : both2: md5 = f81f2ebb4b1d291a7f2241e451fd57b5 OK 2025/03/30 01:53:21 DEBUG : only2: md5 = 50b654386d2bf48b34d223182fcec73f OK 2025/03/30 01:53:26 DEBUG : both3: md5 = f2deafc820e60bc79314409f12970270 OK 2025/03/30 01:53:26 DEBUG : only3: md5 = f412812d11b8b69257c57c2728678dc5 OK 2025/03/30 01:53:31 DEBUG : both4: md5 = 38b6d123cc1e81a83f00399622d7988a OK 2025/03/30 01:53:32 DEBUG : only4: md5 = 08f873c4de4b212386c6d5555a8f2dcf OK 2025/03/30 01:53:32 DEBUG : both5: md5 = b210ddbf25fa9eb98d6d2b90c0d40977 OK 2025/03/30 01:53:32 DEBUG : only5: md5 = ff18978895c4abd93acf71b9c70cde8e OK 2025/03/30 01:53:32 DEBUG : both6: md5 = b97f587933f004ab40a55e56ea1ffabe OK 2025/03/30 01:53:37 DEBUG : only6: md5 = f64516c02608c7b4eaf7ef8bf9062cc5 OK 2025/03/30 01:53:37 DEBUG : both7: md5 = ba4f0222d4b722c0203a012ea075c6e8 OK 2025/03/30 01:53:38 DEBUG : only7: md5 = 5123454683ca886f32fa71e68fc48ccd OK 2025/03/30 01:53:38 DEBUG : both8: md5 = a4b2677616200211c21638032ae59569 OK 2025/03/30 01:53:43 DEBUG : only8: md5 = ac150b2d3a0686b04845184fdb87df43 OK 2025/03/30 01:53:48 DEBUG : both9: md5 = e8f4c990bf3b9c95fdcd7b3280fba4a6 OK 2025/03/30 01:53:48 DEBUG : only9: md5 = 803c119da2607f7499e11fce0dee1cf4 OK 2025/03/30 01:53:49 DEBUG : both10: md5 = 2ccdc4704f5d05b66382beb9a8d0d88e OK 2025/03/30 01:53:49 DEBUG : only10: md5 = 2b6883379627e7836dc66895f98e87bb OK 2025/03/30 01:53:49 DEBUG : both11: md5 = cc9720501066b3667afd4e4c21d28564 OK 2025/03/30 01:53:54 DEBUG : only11: md5 = 8ecdfffb3861815ff9380342c30259df OK 2025/03/30 01:53:54 DEBUG : both12: md5 = 9fc4924902a4ae2b1316040da238c1de OK 2025/03/30 01:54:00 DEBUG : only12: md5 = 1942e3077d3d5958782a9438475fcf25 OK 2025/03/30 01:54:05 DEBUG : both13: md5 = a8327a82e37a53c390932e06ae6dda5d OK 2025/03/30 01:54:05 DEBUG : only13: md5 = ef8cf769da2ef853247faaf56385e6ac OK 2025/03/30 01:54:10 DEBUG : both14: md5 = 7e72590190a533112c78dd43e2589963 OK 2025/03/30 01:54:16 DEBUG : only14: md5 = 973aedab07fa8a54afb99705447cf79a OK 2025/03/30 01:54:16 DEBUG : both15: md5 = 4241d3bdb618dca2e6f6d71d9ed52a91 OK 2025/03/30 01:54:21 DEBUG : only15: md5 = 852055ebf2db9570c0a758265caf715c OK 2025/03/30 01:54:26 DEBUG : both16: md5 = 5093bd801d4dedc9109f09ece580d643 OK 2025/03/30 01:54:26 DEBUG : only16: md5 = 86dac4060113fe87ba02ff15478e5830 OK 2025/03/30 01:54:26 DEBUG : both17: md5 = e9881bd9b37296c5fb323f21d36f7fee OK 2025/03/30 01:54:27 DEBUG : only17: md5 = a21880343592878d3f4db8ba4e3d8866 OK 2025/03/30 01:54:32 DEBUG : both18: md5 = e22b06b5de819bedf6948286e4d3a945 OK 2025/03/30 01:54:37 DEBUG : only18: md5 = 85840f211e5a70346ed88390496205e5 OK 2025/03/30 01:54:37 DEBUG : both19: md5 = 4d799666b23df374ad48cd7f086208a1 OK 2025/03/30 01:54:43 DEBUG : only19: md5 = 43099eefef2dc16c0f58022eb487c9cc OK 2025/03/30 01:55:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for checks to finish 2025/03/30 01:55:40 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both1: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both10: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both11: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both12: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both13: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both14: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both15: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both17: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both18: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both19: Unchanged skipping 2025/03/30 01:55:40 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:40 DEBUG : both2: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both0: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both5: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both6: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both7: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both8: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both16: Unchanged skipping 2025/03/30 01:55:45 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/03/30 01:55:45 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both9: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both3: Unchanged skipping 2025/03/30 01:55:45 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/03/30 01:55:45 DEBUG : both4: Unchanged skipping 2025/03/30 01:55:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Waiting for transfers to finish 2025/03/30 01:55:45 DEBUG : only10: md5 = 107eb6b87a3f17297c28bc762fbb0ce4 OK 2025/03/30 01:55:45 INFO : only10: Copied (replaced existing) 2025/03/30 01:55:50 DEBUG : only11: md5 = 541672e7d60f13127dfa5c7a33b665ac OK 2025/03/30 01:55:50 INFO : only11: Copied (replaced existing) 2025/03/30 01:55:51 DEBUG : only12: md5 = 347ef3d6df20a2da2127b727e5f47f38 OK 2025/03/30 01:55:51 INFO : only12: Copied (replaced existing) 2025/03/30 01:55:51 DEBUG : only13: md5 = 280b8c08a9ac00efe90595e07fae8daa OK 2025/03/30 01:55:51 INFO : only13: Copied (replaced existing) 2025/03/30 01:55:55 DEBUG : only0: md5 = ef87969305d1cd07ac8c2259fbdfcc26 OK 2025/03/30 01:55:55 INFO : only0: Copied (replaced existing) 2025/03/30 01:55:55 DEBUG : only1: md5 = 6030cea38f7f54b4df7cf042188ee720 OK 2025/03/30 01:55:55 INFO : only1: Copied (replaced existing) 2025/03/30 01:55:55 DEBUG : only16: md5 = a4e5d4162135fae7bf1f9233c41b1a1f OK 2025/03/30 01:55:55 INFO : only16: Copied (replaced existing) 2025/03/30 01:55:56 DEBUG : only17: md5 = 580c90ac533372a24ec138b0ae827e3e OK 2025/03/30 01:55:56 INFO : only17: Copied (replaced existing) 2025/03/30 01:55:56 DEBUG : only14: md5 = 7ec442cfe615ed65fc13f10059f73f70 OK 2025/03/30 01:55:56 INFO : only14: Copied (replaced existing) 2025/03/30 01:55:56 DEBUG : only2: md5 = 0d0fb0bdeb1dc99381e5a73fd56c32be OK 2025/03/30 01:55:56 INFO : only2: Copied (replaced existing) 2025/03/30 01:55:56 DEBUG : only3: md5 = 717a5000eee9449548d35d8abd938d5c OK 2025/03/30 01:55:56 INFO : only3: Copied (replaced existing) 2025/03/30 01:55:57 DEBUG : only4: md5 = 5880a8038d45fbb954b4f18bcb834dbb OK 2025/03/30 01:55:57 INFO : only4: Copied (replaced existing) 2025/03/30 01:55:57 DEBUG : only5: md5 = 009c09b4d77e2484037a49a03aa70e12 OK 2025/03/30 01:55:57 INFO : only5: Copied (replaced existing) 2025/03/30 01:56:01 DEBUG : only18: md5 = cd597f0f66bde90735675a163fd214ee OK 2025/03/30 01:56:01 INFO : only18: Copied (replaced existing) 2025/03/30 01:56:01 DEBUG : only19: md5 = 133b21cd4845c73bcff6119c93ca3db9 OK 2025/03/30 01:56:01 INFO : only19: Copied (replaced existing) 2025/03/30 01:56:01 DEBUG : only15: md5 = 5f707db28b1a7db5a6fff72430d968d7 OK 2025/03/30 01:56:01 INFO : only15: Copied (replaced existing) 2025/03/30 01:56:01 DEBUG : only7: md5 = db23b0b27042fd1ac3feb96ea26cce6d OK 2025/03/30 01:56:01 INFO : only7: Copied (replaced existing) 2025/03/30 01:56:01 DEBUG : only9: md5 = d52a62df2220aa53d9efe49ad9633d2d OK 2025/03/30 01:56:01 INFO : only9: Copied (replaced existing) 2025/03/30 01:56:06 DEBUG : only8: md5 = 089a32ec3a94184eca16f9c0b703fe84 OK 2025/03/30 01:56:06 INFO : only8: Copied (replaced existing) 2025/03/30 01:56:07 DEBUG : only6: md5 = 99dbc78670326319ffd8071fa87a54db OK 2025/03/30 01:56:07 INFO : only6: Copied (replaced existing) 2025/03/30 01:56:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (361.24s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:59:06 DEBUG : empty_dir: Making directory with metadata 2025/03/30 01:59:06 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:59:06 DEBUG : empty_on_remote: Making directory with metadata 2025/03/30 01:59:06 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:59:06 INFO : empty_on_remote: Making directory 2025/03/30 01:59:06 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:59:11 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:11 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:11 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:11 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTime (17.01s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:59:23 DEBUG : empty_dir: Making directory with metadata 2025/03/30 01:59:23 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:59:23 DEBUG : empty_on_remote: Making directory with metadata 2025/03/30 01:59:23 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/03/30 01:59:23 INFO : empty_on_remote: Making directory 2025/03/30 01:59:23 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:59:28 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:28 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:28 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:28 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:28 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (17.56s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 01:59:41 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/03/30 01:59:41 INFO : sub dir: Making directory 2025/03/30 01:59:41 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-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestNothingToTransferWithEmptyDirs (32.78s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" 2025/03/30 02:00:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/03/30 02:00:13 INFO : sub dir: Making directory 2025/03/30 02:00:13 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-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja'", Local "Local file system at /tmp/rclone1730261627", Modify Window "1ns" --- PASS: TestNothingToTransferWithoutEmptyDirs (27.37s) PASS 2025/03/30 02:00:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sixifit8qoja': Purge remote 2025/03/30 02:00:41 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 58m45.265696072s (try 1/5)