"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2025/06/25 01:46:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura" 2025/06/25 01:46:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/25 01:46:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura.bin" 2025/06/25 01:46:22 DEBUG : Swift container crypt path rclone-test-sapines8tura.bin: Auto set use_segments_container to true 2025/06/25 01:46:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura" 2025/06/25 01:46:28 DEBUG : Swift container crypt path rclone-test-sapines8tura: Auto set use_segments_container to true 2025/06/25 01:46:33 DEBUG : Creating backend with remote "/tmp/rclone1605875898" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:46:33 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/06/25 01:46:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.76s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/25 01:46:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:46:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:34 DEBUG : sub dir/hello world: md5 = f5198055a384075b6a576dc6423e55b7 OK 2025/06/25 01:46:34 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:46:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.12s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.18s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.09s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:35 DEBUG : Creating backend with remote "/non-existing" 2025/06/25 01:46:35 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/06/25 01:46:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.13s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:46:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:41 DEBUG : sub dir/hello world: md5 = 914006b3db3a637d741f5969491e523a OK 2025/06/25 01:46:41 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.96s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:41 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Running all checks before starting transfers 2025/06/25 01:46:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:41 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Checks finished, now starting transfers 2025/06/25 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:42 DEBUG : sub dir/hello world: md5 = c9e563efca3dcba4ade02c8bd2ab6bac OK 2025/06/25 01:46:42 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.83s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:42 ERROR : Ignoring --no-traverse with sync 2025/06/25 01:46:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:42 DEBUG : sub dir/hello world: md5 = 0fc8eeb5b056b982d95b32325ced4ecf OK 2025/06/25 01:46:42 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:46:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.46s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:44 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/06/25 01:46:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:44 DEBUG : hello world2: md5 = 04130da271bd29edebc3850d733cb4a3 OK 2025/06/25 01:46:44 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.59s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:46:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:46:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:46:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:46:50 DEBUG : potato2: md5 = 89cb705c3334113bec0c69967cf33266 OK 2025/06/25 01:46:50 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (10.80s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:47:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:06 DEBUG : potato2: md5 = 5e87753923d504c4ddc9a59b48e65f92 OK 2025/06/25 01:47:06 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (15.76s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:12 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/25 01:47:12 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 01:47:12 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/06/25 01:47:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/25 01:47:12 INFO : sub dir2: Making directory 2025/06/25 01:47:12 INFO : sub dir: Making directory 2025/06/25 01:47:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:12 INFO : sub dir2/sub sub dir2: Making directory 2025/06/25 01:47:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:12 DEBUG : sub dir/hello world: md5 = dddc5cf175de59ee05b7e95878538338 OK 2025/06/25 01:47:12 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/06/25 01:47:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (0.99s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:13 INFO : sub dir2: Making directory 2025/06/25 01:47:13 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/25 01:47:13 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 01:47:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:13 DEBUG : sub dir/hello world: md5 = 60e3ad341f552be77ab87f1812e3e1e6 OK 2025/06/25 01:47:13 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (0.83s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:13 DEBUG : sub dir2: Making directory with metadata 2025/06/25 01:47:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 01:47:14 INFO : sub dir2: Making directory 2025/06/25 01:47:14 INFO : sub dir: Making directory 2025/06/25 01:47:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:14 DEBUG : sub dir/hello world: md5 = dfd9768157882310f0bc3090e351b0c2 OK 2025/06/25 01:47:14 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:47:14 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/06/25 01:47:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/06/25 01:47:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.03s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:409: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.21s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:15 INFO : sub dir2: Making directory 2025/06/25 01:47:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:15 DEBUG : sub dir/hello world: md5 = 94d949b45a386bfaa9ef1fbaaf10b1b3 OK 2025/06/25 01:47:15 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:47:15 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (0.96s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:16 DEBUG : sub dir2: Making directory with metadata 2025/06/25 01:47:16 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 01:47:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/25 01:47:16 INFO : sub dir2: Making directory 2025/06/25 01:47:16 INFO : sub dir: Making directory 2025/06/25 01:47:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:16 DEBUG : sub dir/hello world: md5 = 33a0ccbda1815982233cfa6f555b5280 OK 2025/06/25 01:47:16 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:47:16 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/06/25 01:47:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (0.83s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.30s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:17 INFO : sub dir2: Making directory 2025/06/25 01:47:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:17 DEBUG : sub dir/hello world: md5 = aa9025f3747617a7e80e1218c0ca6eda OK 2025/06/25 01:47:17 INFO : sub dir/hello world: Copied (new) 2025/06/25 01:47:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.86s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:18 DEBUG : sub dir/hello world: md5 = fb4e2ca7940f0897a3d1eb56c6b5ec69 OK 2025/06/25 01:47:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fipataw6xase" 2025/06/25 01:47:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fipataw6xase.bin" 2025/06/25 01:47:18 DEBUG : Swift container crypt path rclone-test-fipataw6xase.bin: Auto set use_segments_container to true 2025/06/25 01:47:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fipataw6xase" 2025/06/25 01:47:24 DEBUG : Swift container crypt path rclone-test-fipataw6xase: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-fipataw6xase' 2025/06/25 01:47:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:47:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fipataw6xase': Waiting for checks to finish 2025/06/25 01:47:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fipataw6xase': Waiting for transfers to finish 2025/06/25 01:47:46 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:47:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fipataw6xase': Purge remote 2025/06/25 01:47:46 DEBUG : Waiting for deletions to finish 2025/06/25 01:47:56 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (38.81s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:57 DEBUG : sub dir/hello world: md5 = 5d50bf7422397de92dca8e4abd65cd28 OK 2025/06/25 01:47:57 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/25 01:47:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:47:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:47:57 DEBUG : sub dir/hello world: md5 = 3cd8bc217ee887b00c6d61a8e6f924fc OK 2025/06/25 01:47:57 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.48s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:47:58 DEBUG : sub dir/hello world: md5 = bd03cff423fd3d418e5e44e973ce30da OK 2025/06/25 01:47:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jetaluj1hafe" 2025/06/25 01:47:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jetaluj1hafe.bin" 2025/06/25 01:47:59 DEBUG : Swift container crypt path rclone-test-jetaluj1hafe.bin: Auto set use_segments_container to true 2025/06/25 01:48:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jetaluj1hafe" 2025/06/25 01:48:04 DEBUG : Swift container crypt path rclone-test-jetaluj1hafe: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe' 2025/06/25 01:48:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:48:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe': Waiting for checks to finish 2025/06/25 01:48:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe': Waiting for transfers to finish 2025/06/25 01:48:15 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:48:16 DEBUG : sub dir/hello world: md5 = 5b8d455d3beb7db9fa47853a6f583fb5 OK 2025/06/25 01:48:16 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/25 01:48:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe': Waiting for checks to finish 2025/06/25 01:48:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe': Waiting for transfers to finish 2025/06/25 01:48:21 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:48:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jetaluj1hafe': Purge remote 2025/06/25 01:48:22 DEBUG : Waiting for deletions to finish 2025/06/25 01:48:22 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (24.63s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:48:23 DEBUG : sub dir/hello world: md5 = 344466ea28e873cc5fea3ec739b79f3d OK 2025/06/25 01:48:23 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/25 01:48:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:48:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:48:23 DEBUG : sub dir/hello world: md5 = b163c3028e28dcbeda5af6f545b0ec13 OK 2025/06/25 01:48:23 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/25 01:48:23 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.40s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:48:24 DEBUG : sub dir/hello world: md5 = 3a8cf0de2fdd133c0779d2a480c38240 OK 2025/06/25 01:48:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zovapuw0fela" 2025/06/25 01:48:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zovapuw0fela.bin" 2025/06/25 01:48:25 DEBUG : Swift container crypt path rclone-test-zovapuw0fela.bin: Auto set use_segments_container to true 2025/06/25 01:48:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zovapuw0fela" 2025/06/25 01:48:31 DEBUG : Swift container crypt path rclone-test-zovapuw0fela: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela' 2025/06/25 01:48:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:48:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for checks to finish 2025/06/25 01:48:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for transfers to finish 2025/06/25 01:48:47 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:48:52 DEBUG : sub dir/hello world: md5 = 1344c14dca2cadc3ee7ec3a59c2ff827 OK 2025/06/25 01:48:52 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/25 01:48:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for checks to finish 2025/06/25 01:48:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for transfers to finish 2025/06/25 01:49:03 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:49:03 INFO : sub dir/hello world: Deleted 2025/06/25 01:49:03 DEBUG : sub dir/hello world: md5 = cc17944e2080b0f7c65f118cdb24b789 OK 2025/06/25 01:49:03 DEBUG : testing file moves 2025/06/25 01:49:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for checks to finish 2025/06/25 01:49:04 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/25 01:49:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Waiting for transfers to finish 2025/06/25 01:49:19 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/25 01:49:19 INFO : sub dir/hello world: Deleted 2025/06/25 01:49:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovapuw0fela': Purge remote 2025/06/25 01:49:19 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:24 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (60.60s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:49:25 DEBUG : sub dir/hello world: md5 = 8e2263b93c49c8cf7dadf326e6680c6a OK 2025/06/25 01:49:25 ERROR : error listing: directory not found 2025/06/25 01:49:25 INFO : Local file system at /tmp/rclone1605875898: Making directory 2025/06/25 01:49:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.39s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:49:26 DEBUG : sub dir/hello world: md5 = d5a84022cdcc8f2c7d0e48cf624303d0 OK 2025/06/25 01:49:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 01:49:27 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for checks to finish 2025/06/25 01:49:27 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for transfers to finish 2025/06/25 01:49:27 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/06/25 01:49:27 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.18s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:49:27 DEBUG : check sum: Need to transfer - File not found at Destination 2025/06/25 01:49:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:28 DEBUG : check sum: md5 = 00797971bfcb38c990221d0fe5693adf OK 2025/06/25 01:49:28 INFO : check sum: Copied (new) 2025/06/25 01:49:28 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:28 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/06/25 01:49:28 DEBUG : check sum: Size of src and dst objects identical 2025/06/25 01:49:28 DEBUG : check sum: Unchanged skipping 2025/06/25 01:49:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:28 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:28 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.37s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:49:29 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/06/25 01:49:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:34 DEBUG : sizeonly: md5 = 10b2679320f5646953cd18a345946e44 OK 2025/06/25 01:49:34 INFO : sizeonly: Copied (new) 2025/06/25 01:49:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:34 DEBUG : sizeonly: Sizes identical 2025/06/25 01:49:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:34 DEBUG : sizeonly: Unchanged skipping 2025/06/25 01:49:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:34 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (16.24s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:49:45 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/06/25 01:49:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:50 DEBUG : ignore-size: md5 = e5007198b27065f517789d7101b74f56 OK 2025/06/25 01:49:50 INFO : ignore-size: Copied (new) 2025/06/25 01:49:50 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:49:56 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:49:56 DEBUG : ignore-size: Unchanged skipping 2025/06/25 01:49:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:49:56 DEBUG : Waiting for deletions to finish 2025/06/25 01:49:56 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (16.54s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:50:07 DEBUG : existing: md5 = b80d56c49cbba931d3f0412fb1576a6e OK 2025/06/25 01:50:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:50:07 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:50:07 DEBUG : existing: Unchanged skipping 2025/06/25 01:50:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:50:07 DEBUG : Waiting for deletions to finish 2025/06/25 01:50:07 INFO : There was nothing to transfer 2025/06/25 01:50:07 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/06/25 01:50:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:50:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:50:18 DEBUG : existing: md5 = 9acfdad7344be8f4448248a48b0acb68 OK 2025/06/25 01:50:18 INFO : existing: Copied (replaced existing) 2025/06/25 01:50:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (27.00s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:50:29 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/25 01:50:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:50:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:50:34 DEBUG : existing: md5 = ef4065e0bdcae14b57133b887d2c59d3 OK 2025/06/25 01:50:34 INFO : existing: Copied (new) 2025/06/25 01:50:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:50:34 DEBUG : existing: Destination exists, skipping 2025/06/25 01:50:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:50:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:50:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:50:34 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (16.43s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:50:45 DEBUG : b/potato: md5 = 7d969054768697966fc4073227a06223 OK 2025/06/25 01:50:50 DEBUG : c/non empty space: md5 = e8c5d9b08bc9b0c924cbf555fb7d1154 OK 2025/06/25 01:50:50 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/25 01:50:51 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/25 01:50:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:50:56 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:50:56 DEBUG : c/non empty space: Unchanged skipping 2025/06/25 01:50:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:50:56 DEBUG : a/potato2: md5 = 5032f1f76162a3f30c7469512b55edb2 OK 2025/06/25 01:50:56 INFO : a/potato2: Copied (new) 2025/06/25 01:50:56 DEBUG : Waiting for deletions to finish 2025/06/25 01:50:56 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (40.51s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:51:30 DEBUG : empty space: md5 = 729534c84e95f4386333e97538ce5403 OK 2025/06/25 01:51:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:51:36 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/06/25 01:51:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:51:36 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/06/25 01:51:36 DEBUG : Waiting for deletions to finish 2025/06/25 01:51:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:51:49 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/06/25 01:51:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:51:55 DEBUG : empty space: md5 = 06ac5df4359010e046a6172e10d7f2ba OK 2025/06/25 01:51:55 INFO : empty space: Copied (replaced existing) 2025/06/25 01:51:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (39.95s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.20s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:52:06 DEBUG : foo: md5 = 7b0d1c6cd91e3c83233f5c606d46a435 OK 2025/06/25 01:52:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:52:07 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/06/25 01:52:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:52:07 DEBUG : foo: md5 = 362d6544bd77c64df4c4b358eef4ebad OK 2025/06/25 01:52:07 INFO : foo: Copied (replaced existing) 2025/06/25 01:52:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.05s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:52:13 DEBUG : empty space: md5 = 143dcc036a344378a594f914853a6881 OK 2025/06/25 01:52:13 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/25 01:52:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:52:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:52:18 DEBUG : empty space: Unchanged skipping 2025/06/25 01:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:52:18 DEBUG : potato: md5 = 848c8694af043e00e92dddfb00bd84ac OK 2025/06/25 01:52:18 INFO : potato: Copied (new) 2025/06/25 01:52:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (31.59s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:52:44 DEBUG : potato: md5 = 2045e4093c67893df72ceb7d9d1bc001 OK 2025/06/25 01:52:44 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/06/25 01:52:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:52:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:52:50 DEBUG : potato: md5 = 62548d141ec527722d302766f38e3ab5 OK 2025/06/25 01:52:50 INFO : potato: Copied (replaced existing) 2025/06/25 01:52:50 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (21.30s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:53:06 DEBUG : potato: md5 = af024538694f0c5aea88f692714da707 OK 2025/06/25 01:53:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:53:11 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/06/25 01:53:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:53:19 DEBUG : potato: md5 = f5ae66a6759db7e4aa3b628b2ba2462a OK 2025/06/25 01:53:19 INFO : potato: Copied (replaced existing) 2025/06/25 01:53:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (24.39s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:53:30 DEBUG : potato: md5 = f0379238efb66db9cdff1466cd86c9dd OK 2025/06/25 01:53:35 DEBUG : empty space: md5 = bbfe649e4d80dfe5085f7254f66ac415 OK 2025/06/25 01:53:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:53:35 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/06/25 01:53:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:53:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:53:35 DEBUG : empty space: Unchanged skipping 2025/06/25 01:53:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:53:35 DEBUG : Waiting for deletions to finish 2025/06/25 01:53:35 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (26.26s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:53:56 DEBUG : potato: md5 = eeb4430fc6bf19a6b452804fee1e825a OK 2025/06/25 01:54:01 DEBUG : empty space: md5 = e6cb391bb654f70b10a71d706300b5a8 OK 2025/06/25 01:54:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:54:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:54:12 DEBUG : empty space: Unchanged skipping 2025/06/25 01:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:54:17 DEBUG : potato2: md5 = 904ad2b86bf714bbfbd343518d9fc4a2 OK 2025/06/25 01:54:17 INFO : potato2: Copied (new) 2025/06/25 01:54:17 DEBUG : Waiting for deletions to finish 2025/06/25 01:54:27 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (46.48s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:54:38 DEBUG : b/potato: md5 = f530ac7126f51121590655ac494d9579 OK 2025/06/25 01:54:43 DEBUG : c/non empty space: md5 = f28ae89e44e4faf1c9d321e6f5774f58 OK 2025/06/25 01:54:43 INFO : d: Making directory 2025/06/25 01:54:43 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/06/25 01:54:48 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/25 01:54:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:54:48 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:54:48 DEBUG : c/non empty space: Unchanged skipping 2025/06/25 01:54:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:54:53 DEBUG : a/potato2: md5 = e86e3562588a65706219a11d56b28718 OK 2025/06/25 01:54:53 INFO : a/potato2: Copied (new) 2025/06/25 01:54:53 DEBUG : Waiting for deletions to finish 2025/06/25 01:54:53 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (26.82s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:55:04 DEBUG : b/potato: md5 = 9d3e8da8c9c7be4928a690f461dba4cc OK 2025/06/25 01:55:10 DEBUG : c/non empty space: md5 = 6e7362e4aca00cf4e8119b47c3b47f4b OK 2025/06/25 01:55:10 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/25 01:55:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/25 01:55:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:55:16 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:55:16 DEBUG : c/non empty space: Unchanged skipping 2025/06/25 01:55:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:55:21 DEBUG : a/potato2: md5 = b71f7481496e02566626ef0b3cf02feb OK 2025/06/25 01:55:21 INFO : a/potato2: Copied (new) 2025/06/25 01:55:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': not deleting files as there were IO errors 2025/06/25 01:55:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (37.61s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:55:47 DEBUG : potato: md5 = 9a2658cfca49ed67ef80739a2639a554 OK 2025/06/25 01:55:52 DEBUG : empty space: md5 = dcda2b17f5d9333ea315ea6d4059fcb8 OK 2025/06/25 01:55:57 DEBUG : Waiting for deletions to finish 2025/06/25 01:55:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:56:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:56:03 DEBUG : empty space: Unchanged skipping 2025/06/25 01:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:56:03 DEBUG : potato2: md5 = 4fdca62acd64d1aba716f2b04129d79b OK 2025/06/25 01:56:03 INFO : potato2: Copied (new) 2025/06/25 01:56:03 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (41.38s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:56:28 DEBUG : potato: md5 = 261a2f080dd00763c8134627ec9e3b67 OK 2025/06/25 01:56:34 DEBUG : empty space: md5 = 469eb8c8cac3d9089db2c08271f95b6b OK 2025/06/25 01:56:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:56:44 INFO : potato: Deleted 2025/06/25 01:56:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:56:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:56:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:56:49 DEBUG : empty space: Unchanged skipping 2025/06/25 01:56:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:56:49 DEBUG : potato2: md5 = db91ea152253f37576de49818b16c6d0 OK 2025/06/25 01:56:49 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (46.72s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:57:15 DEBUG : potato: md5 = 57e2cb18631cd82cf5221b2507cba097 OK 2025/06/25 01:57:16 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 01:57:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:57:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:57:21 DEBUG : potato2: md5 = 85c2fb4b57736ecd16ca46c2f985ab5f OK 2025/06/25 01:57:21 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (26.42s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:57:42 DEBUG : potato2: md5 = 444a42fcbe760485693f89169d2c65cc OK 2025/06/25 01:57:47 DEBUG : empty space: md5 = db27af3c3b2a8a0e8b2d75741ab64512 OK 2025/06/25 01:57:47 DEBUG : enormous: Excluded (Size Filter) 2025/06/25 01:57:47 DEBUG : enormous: Excluded 2025/06/25 01:57:47 DEBUG : potato2: Excluded (Size Filter) 2025/06/25 01:57:47 DEBUG : potato2: Excluded 2025/06/25 01:57:47 DEBUG : potato2: Excluded (Size Filter) 2025/06/25 01:57:47 DEBUG : potato2: Excluded 2025/06/25 01:57:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:57:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:57:52 DEBUG : empty space: Unchanged skipping 2025/06/25 01:57:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:57:52 DEBUG : Waiting for deletions to finish 2025/06/25 01:57:52 INFO : There was nothing to transfer 2025/06/25 01:57:52 DEBUG : enormous: Excluded (Size Filter) 2025/06/25 01:57:52 DEBUG : enormous: Excluded 2025/06/25 01:57:52 DEBUG : potato2: Excluded (Size Filter) 2025/06/25 01:57:52 DEBUG : potato2: Excluded 2025/06/25 01:57:52 DEBUG : potato2: Excluded (Size Filter) 2025/06/25 01:57:52 DEBUG : potato2: Excluded 2025/06/25 01:57:52 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for checks to finish 2025/06/25 01:57:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:57:57 DEBUG : empty space: Unchanged skipping 2025/06/25 01:57:57 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for transfers to finish 2025/06/25 01:57:57 DEBUG : Waiting for deletions to finish 2025/06/25 01:57:57 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (31.96s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:58:14 DEBUG : potato2: md5 = d9808dafb7b3f4e70684252c77e1ef94 OK 2025/06/25 01:58:19 DEBUG : empty space: md5 = 051813e8892ce313b4c863d29d4e6d95 OK 2025/06/25 01:58:19 DEBUG : enormous: md5 = ad5bdaa6693cc2db2cb13439a817b642 OK 2025/06/25 01:58:24 DEBUG : enormous: Excluded (Size Filter) 2025/06/25 01:58:24 DEBUG : enormous: Excluded 2025/06/25 01:58:24 DEBUG : potato2: Excluded (Size Filter) 2025/06/25 01:58:24 DEBUG : potato2: Excluded 2025/06/25 01:58:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:58:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:58:30 DEBUG : empty space: Unchanged skipping 2025/06/25 01:58:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:58:30 DEBUG : Waiting for deletions to finish 2025/06/25 01:58:30 INFO : enormous: Deleted 2025/06/25 01:58:35 INFO : potato2: Deleted 2025/06/25 01:58:35 INFO : There was nothing to transfer 2025/06/25 01:58:40 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for checks to finish 2025/06/25 01:58:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:58:40 DEBUG : empty space: Unchanged skipping 2025/06/25 01:58:40 DEBUG : Local file system at /tmp/rclone1605875898: Waiting for transfers to finish 2025/06/25 01:58:40 DEBUG : Waiting for deletions to finish 2025/06/25 01:58:40 INFO : enormous: Deleted 2025/06/25 01:58:40 INFO : potato2: Deleted 2025/06/25 01:58:40 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (36.99s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 01:58:46 DEBUG : one: md5 = 0fe8333c9e7af2ee990cd498b70b042e OK 2025/06/25 01:58:51 DEBUG : two: md5 = c806468c02e8275774159a69759a9da9 OK 2025/06/25 01:58:57 DEBUG : three: md5 = c31d5ead8d65c34f064814e215192db3 OK 2025/06/25 01:59:02 DEBUG : four: md5 = 25c9f7e72c0ac6ce44f451ab83dae100 OK 2025/06/25 01:59:02 DEBUG : five: Need to transfer - File not found at Destination 2025/06/25 01:59:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:59:02 DEBUG : three: Sizes identical 2025/06/25 01:59:02 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/06/25 01:59:02 DEBUG : one: Destination is newer than source, skipping 2025/06/25 01:59:03 DEBUG : five: md5 = a0234f0774d2ee5a1829dd0efe23f7d4 OK 2025/06/25 01:59:03 INFO : five: Copied (new) 2025/06/25 01:59:07 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/06/25 01:59:07 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/06/25 01:59:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:59:12 DEBUG : four: md5 = 3913b7dfd795bbdc0a1027694c9a3ec9 OK 2025/06/25 01:59:12 INFO : four: Copied (replaced existing) 2025/06/25 01:59:12 DEBUG : two: md5 = 58bb757b9f5a2f487df13f5e59bd6e6a OK 2025/06/25 01:59:12 INFO : two: Copied (replaced existing) 2025/06/25 01:59:12 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (43.47s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1467: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1467: 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-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/06/25 01:59:29 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/25 01:59:29 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/25 01:59:29 DEBUG : yam: Need to transfer - File not found at Destination 2025/06/25 01:59:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:59:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:59:34 DEBUG : potato: md5 = c4ebfe3c888f5dc149f07db8fb9b6b69 OK 2025/06/25 01:59:34 INFO : potato: Copied (new) 2025/06/25 01:59:34 DEBUG : yam: md5 = 04b109bf0986fae25c8cc46d69464965 OK 2025/06/25 01:59:34 INFO : yam: Copied (new) 2025/06/25 01:59:34 DEBUG : Waiting for deletions to finish 2025/06/25 01:59:35 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/25 01:59:35 DEBUG : yaml: Need to transfer - File not found at Destination 2025/06/25 01:59:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 01:59:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 01:59:35 DEBUG : potato: Unchanged skipping 2025/06/25 01:59:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 01:59:40 DEBUG : yaml: md5 = b4e134aeaf564f51585232f1c7b22b76 OK 2025/06/25 01:59:40 INFO : yaml: Copied (new) 2025/06/25 01:59:40 DEBUG : Waiting for deletions to finish 2025/06/25 01:59:50 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (36.96s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/06/25 02:00:06 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Making map for --track-renames 2025/06/25 02:00:06 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Finished making map for --track-renames 2025/06/25 02:00:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:00:06 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/25 02:00:06 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/25 02:00:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for renames to finish 2025/06/25 02:00:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:00:11 DEBUG : yam: md5 = a9ddeda54b52d268d4c05bad304c7b5c OK 2025/06/25 02:00:11 INFO : yam: Copied (new) 2025/06/25 02:00:11 DEBUG : potato: md5 = 602e4e5a823cab453d48c565957302b1 OK 2025/06/25 02:00:11 INFO : potato: Copied (new) 2025/06/25 02:00:11 DEBUG : Waiting for deletions to finish 2025/06/25 02:00:17 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Making map for --track-renames 2025/06/25 02:00:17 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Finished making map for --track-renames 2025/06/25 02:00:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:00:17 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:00:17 DEBUG : potato: Unchanged skipping 2025/06/25 02:00:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for renames to finish 2025/06/25 02:00:32 INFO : yam: Copied (server-side copy) to: yaml 2025/06/25 02:00:37 INFO : yam: Deleted 2025/06/25 02:00:37 INFO : yaml: Renamed from "yam" 2025/06/25 02:00:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:00:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (51.99s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/06/25 02:00:58 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Making map for --track-renames 2025/06/25 02:00:58 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Finished making map for --track-renames 2025/06/25 02:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:00:58 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/25 02:00:58 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/25 02:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for renames to finish 2025/06/25 02:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:00:58 DEBUG : sub/yam: md5 = ff8dd3fff138a983f7c0abddd90e3d2e OK 2025/06/25 02:00:58 INFO : sub/yam: Copied (new) 2025/06/25 02:01:03 DEBUG : potato: md5 = a1deb8c732a4127d5028e3e35a2e13b6 OK 2025/06/25 02:01:03 INFO : potato: Copied (new) 2025/06/25 02:01:03 DEBUG : Waiting for deletions to finish 2025/06/25 02:01:03 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:01:03 DEBUG : potato: Unchanged skipping 2025/06/25 02:01:03 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Making map for --track-renames 2025/06/25 02:01:03 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Finished making map for --track-renames 2025/06/25 02:01:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:01:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for renames to finish 2025/06/25 02:01:19 INFO : sub/yam: Copied (server-side copy) to: yam 2025/06/25 02:01:19 INFO : sub/yam: Deleted 2025/06/25 02:01:19 INFO : yam: Renamed from "sub/yam" 2025/06/25 02:01:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:01:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (41.76s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:01:40 DEBUG : Creating backend with remote "/tmp/rclone1605875898/dir1" 2025/06/25 02:01:40 DEBUG : Config file has changed externally - reloading 2025/06/25 02:01:40 DEBUG : Creating backend with remote "/tmp/rclone1605875898/dir2" 2025/06/25 02:01:40 DEBUG : Local file system at /tmp/rclone1605875898/dir2: Using server-side directory move 2025/06/25 02:01:40 INFO : Local file system at /tmp/rclone1605875898/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/25 02:01:40 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/06/25 02:01:40 DEBUG : Local file system at /tmp/rclone1605875898/dir2: Waiting for checks to finish 2025/06/25 02:01:40 DEBUG : Local file system at /tmp/rclone1605875898/dir2: Waiting for transfers to finish 2025/06/25 02:01:40 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.29s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:01:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 02:01:40 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/25 02:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:01:40 DEBUG : sub dir/hello world: md5 = 8d5829e6101331e8abf90eb22615a5a6 OK 2025/06/25 02:01:40 INFO : sub dir/hello world: Copied (new) 2025/06/25 02:01:40 INFO : sub dir/hello world: Deleted 2025/06/25 02:01:41 DEBUG : nested/sub dir/file: md5 = c5a67d025344aa0fc63ecbaed0bfc99f OK 2025/06/25 02:01:41 INFO : nested/sub dir/file: Copied (new) 2025/06/25 02:01:41 INFO : nested/sub dir/file: Deleted 2025/06/25 02:01:41 INFO : sub dir: Removing directory 2025/06/25 02:01:41 INFO : nested/sub dir: Removing directory 2025/06/25 02:01:41 INFO : nested: Removing directory 2025/06/25 02:01:41 DEBUG : Local file system at /tmp/rclone1605875898: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (1.54s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:01:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/25 02:01:42 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/25 02:01:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:01:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:01:42 DEBUG : sub dir/hello world: md5 = efcb95fed8d07d8c81fce7679dfea175 OK 2025/06/25 02:01:42 INFO : sub dir/hello world: Copied (new) 2025/06/25 02:01:42 INFO : sub dir/hello world: Deleted 2025/06/25 02:01:42 DEBUG : nested/sub dir/file: md5 = 056303886690222f0b5a3ac5b361af00 OK 2025/06/25 02:01:42 INFO : nested/sub dir/file: Copied (new) 2025/06/25 02:01:42 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.51s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:01:43 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/06/25 02:01:43 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/25 02:01:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:01:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:01:48 DEBUG : existing: md5 = c56f68afe811e9f850ea30c6ae4b4e41 OK 2025/06/25 02:01:48 INFO : existing: Copied (new) 2025/06/25 02:01:48 INFO : existing: Deleted 2025/06/25 02:01:48 DEBUG : existing-b: md5 = 0a58de288b809253ad5b85623a077611 OK 2025/06/25 02:01:48 INFO : existing-b: Copied (new) 2025/06/25 02:01:48 INFO : existing-b: Deleted 2025/06/25 02:01:49 DEBUG : existing: Destination exists, skipping 2025/06/25 02:01:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:01:54 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/06/25 02:01:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:01:54 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (21.41s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:02:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gokitos5copu" 2025/06/25 02:02:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gokitos5copu.bin" 2025/06/25 02:02:05 DEBUG : Swift container crypt path rclone-test-gokitos5copu.bin: Auto set use_segments_container to true 2025/06/25 02:02:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gokitos5copu" 2025/06/25 02:02:10 DEBUG : Swift container crypt path rclone-test-gokitos5copu: Auto set use_segments_container to true 2025/06/25 02:02:21 DEBUG : potato2: md5 = c577d165a6e6a6baf1b3d86e87c7275a OK 2025/06/25 02:02:26 DEBUG : empty space: md5 = 4c276c4663934ab5e639efe74f8979b5 OK 2025/06/25 02:02:31 DEBUG : potato3: md5 = f66f389928c8d5d9c3931e402c26135b OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-gokitos5copu' 2025/06/25 02:02:42 DEBUG : empty space: md5 = 317c82c5ab1752cdb80b240f431f019d OK 2025/06/25 02:02:42 DEBUG : potato3: md5 = 56fdad1ee3232bfb9eb591af440ddfb8 OK 2025/06/25 02:02:48 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:02:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/25 02:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gokitos5copu': Waiting for checks to finish 2025/06/25 02:02:53 INFO : potato3: Copied (server-side copy) 2025/06/25 02:02:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:02:58 DEBUG : empty space: Unchanged skipping 2025/06/25 02:02:58 INFO : potato2: Copied (server-side copy) 2025/06/25 02:02:58 INFO : potato3: Deleted 2025/06/25 02:03:03 INFO : empty space: Deleted 2025/06/25 02:03:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gokitos5copu': Waiting for transfers to finish 2025/06/25 02:03:03 INFO : potato2: Deleted 2025/06/25 02:03:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gejaqel5toyi" 2025/06/25 02:03:09 DEBUG : Config file has changed externally - reloading 2025/06/25 02:03:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gejaqel5toyi.bin" 2025/06/25 02:03:09 DEBUG : Swift container crypt path rclone-test-gejaqel5toyi.bin: Auto set use_segments_container to true 2025/06/25 02:03:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gejaqel5toyi" 2025/06/25 02:03:15 DEBUG : Swift container crypt path rclone-test-gejaqel5toyi: Auto set use_segments_container to true 2025/06/25 02:03:20 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/25 02:03:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:03:20 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/25 02:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejaqel5toyi': Waiting for checks to finish 2025/06/25 02:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejaqel5toyi': Waiting for transfers to finish 2025/06/25 02:03:26 INFO : potato3: Copied (server-side copy) 2025/06/25 02:03:26 INFO : potato3: Deleted 2025/06/25 02:03:31 INFO : potato2: Copied (server-side copy) 2025/06/25 02:03:36 INFO : empty space: Copied (server-side copy) 2025/06/25 02:03:37 INFO : potato2: Deleted 2025/06/25 02:03:41 INFO : empty space: Deleted 2025/06/25 02:03:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gejaqel5toyi': Purge remote 2025/06/25 02:03:41 DEBUG : Waiting for deletions to finish 2025/06/25 02:03:41 INFO : potato2.bin: Deleted 2025/06/25 02:03:41 INFO : potato3.bin: Deleted 2025/06/25 02:03:46 INFO : empty space.bin: Deleted 2025/06/25 02:03:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gokitos5copu': Purge remote 2025/06/25 02:03:46 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (101.77s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:03:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bomuxat3qebi" 2025/06/25 02:03:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bomuxat3qebi.bin" 2025/06/25 02:03:47 DEBUG : Swift container crypt path rclone-test-bomuxat3qebi.bin: Auto set use_segments_container to true 2025/06/25 02:03:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bomuxat3qebi" 2025/06/25 02:03:52 DEBUG : Swift container crypt path rclone-test-bomuxat3qebi: Auto set use_segments_container to true 2025/06/25 02:04:03 DEBUG : potato2: md5 = 1e3d73aeae7d223ae00f41664e373abb OK 2025/06/25 02:04:08 DEBUG : empty space: md5 = 03755695803565c52b0f74997dcf7a43 OK 2025/06/25 02:04:13 DEBUG : potato3: md5 = 7d2712a3dca228a3058ffb0eaa27208c OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-bomuxat3qebi' 2025/06/25 02:04:24 DEBUG : empty space: md5 = fbaf4953a71ca0ea3756c3a3d8bfd75b OK 2025/06/25 02:04:29 DEBUG : potato3: md5 = 0b8e389dca2866eb7e308047b269be9f OK 2025/06/25 02:04:29 DEBUG : empty space: Excluded (Size Filter) 2025/06/25 02:04:29 DEBUG : empty space: Excluded 2025/06/25 02:04:29 DEBUG : empty space: Excluded (Size Filter) 2025/06/25 02:04:29 DEBUG : empty space: Excluded 2025/06/25 02:04:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:04:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/25 02:04:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bomuxat3qebi': Waiting for checks to finish 2025/06/25 02:04:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bomuxat3qebi': Waiting for transfers to finish 2025/06/25 02:04:35 INFO : potato2: Copied (server-side copy) 2025/06/25 02:04:40 INFO : potato3: Copied (server-side copy) 2025/06/25 02:04:40 INFO : potato2: Deleted 2025/06/25 02:04:45 INFO : potato3: Deleted 2025/06/25 02:04:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-muqohet5tuju" 2025/06/25 02:04:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-muqohet5tuju.bin" 2025/06/25 02:04:46 DEBUG : Swift container crypt path rclone-test-muqohet5tuju.bin: Auto set use_segments_container to true 2025/06/25 02:04:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-muqohet5tuju" 2025/06/25 02:04:51 DEBUG : Swift container crypt path rclone-test-muqohet5tuju: Auto set use_segments_container to true 2025/06/25 02:04:57 DEBUG : empty space: Excluded (Size Filter) 2025/06/25 02:04:57 DEBUG : empty space: Excluded 2025/06/25 02:04:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:04:57 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/25 02:04:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-muqohet5tuju': Waiting for checks to finish 2025/06/25 02:04:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-muqohet5tuju': Waiting for transfers to finish 2025/06/25 02:05:02 INFO : potato2: Copied (server-side copy) 2025/06/25 02:05:03 INFO : potato2: Deleted 2025/06/25 02:05:12 INFO : potato3: Copied (server-side copy) 2025/06/25 02:05:17 INFO : potato3: Deleted 2025/06/25 02:05:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-muqohet5tuju': Purge remote 2025/06/25 02:05:23 DEBUG : Waiting for deletions to finish 2025/06/25 02:05:23 INFO : potato2.bin: Deleted 2025/06/25 02:05:33 INFO : potato3.bin: Deleted 2025/06/25 02:05:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bomuxat3qebi': Purge remote 2025/06/25 02:05:33 DEBUG : Waiting for deletions to finish 2025/06/25 02:05:43 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (127.35s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:05:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-noqacan2kafe" 2025/06/25 02:05:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noqacan2kafe.bin" 2025/06/25 02:05:54 DEBUG : Swift container crypt path rclone-test-noqacan2kafe.bin: Auto set use_segments_container to true 2025/06/25 02:05:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noqacan2kafe" 2025/06/25 02:05:59 DEBUG : Swift container crypt path rclone-test-noqacan2kafe: Auto set use_segments_container to true 2025/06/25 02:06:10 DEBUG : potato2: md5 = 7d0db2607a4722b0836c44b9d42b357f OK 2025/06/25 02:06:15 DEBUG : empty space: md5 = ba58cf41bd25ab06cd9d088b419e5250 OK 2025/06/25 02:06:20 DEBUG : potato3: md5 = 545bf1215300f53c11166f76a01bf3b9 OK 2025/06/25 02:06:20 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura' -> Encrypted drive 'TestCryptSwift:rclone-test-noqacan2kafe' 2025/06/25 02:06:26 DEBUG : empty space: md5 = 4c782a75e9f2f832dc9f652dc8b10390 OK 2025/06/25 02:06:27 DEBUG : potato3: md5 = 8e1a005c3954e30e53c1a8b9eae839b9 OK 2025/06/25 02:06:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:06:28 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/25 02:06:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noqacan2kafe': Waiting for checks to finish 2025/06/25 02:06:33 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:06:33 DEBUG : empty space: Unchanged skipping 2025/06/25 02:06:33 INFO : potato3: Copied (server-side copy) 2025/06/25 02:06:33 INFO : potato2: Copied (server-side copy) 2025/06/25 02:06:38 INFO : empty space: Deleted 2025/06/25 02:06:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noqacan2kafe': Waiting for transfers to finish 2025/06/25 02:06:38 INFO : potato3: Deleted 2025/06/25 02:06:39 INFO : potato2: Deleted 2025/06/25 02:06:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sugujaf6viki" 2025/06/25 02:06:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sugujaf6viki.bin" 2025/06/25 02:06:40 DEBUG : Swift container crypt path rclone-test-sugujaf6viki.bin: Auto set use_segments_container to true 2025/06/25 02:06:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sugujaf6viki" 2025/06/25 02:06:45 DEBUG : Swift container crypt path rclone-test-sugujaf6viki: Auto set use_segments_container to true 2025/06/25 02:06:50 INFO : tomatoDir: Making directory 2025/06/25 02:06:51 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/25 02:06:51 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/25 02:06:51 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/25 02:06:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sugujaf6viki': Waiting for checks to finish 2025/06/25 02:06:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sugujaf6viki': Waiting for transfers to finish 2025/06/25 02:06:56 INFO : potato2: Copied (server-side copy) 2025/06/25 02:06:56 INFO : potato2: Deleted 2025/06/25 02:06:56 INFO : potato3: Copied (server-side copy) 2025/06/25 02:06:57 INFO : potato3: Deleted 2025/06/25 02:07:01 INFO : empty space: Copied (server-side copy) 2025/06/25 02:07:01 INFO : empty space: Deleted 2025/06/25 02:07:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sugujaf6viki': Purge remote 2025/06/25 02:07:07 DEBUG : Waiting for deletions to finish 2025/06/25 02:07:07 INFO : potato2.bin: Deleted 2025/06/25 02:07:07 INFO : potato3.bin: Deleted 2025/06/25 02:07:13 INFO : empty space.bin: Deleted 2025/06/25 02:07:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noqacan2kafe': Purge remote 2025/06/25 02:07:13 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (79.56s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:07:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/rclone-move-test" 2025/06/25 02:07:13 DEBUG : Config file has changed externally - reloading 2025/06/25 02:07:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-move-test.bin" 2025/06/25 02:07:13 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-move-test.bin: Auto set use_segments_container to true 2025/06/25 02:07:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-move-test" 2025/06/25 02:07:19 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-move-test: Auto set use_segments_container to true 2025/06/25 02:07:29 DEBUG : potato2: md5 = 2dd0e528346bc8c849bcfa0db03be420 OK --- PASS: TestServerSideMoveOverlap (32.04s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:07:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test" 2025/06/25 02:07:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test.bin" 2025/06/25 02:07:45 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/25 02:07:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test" 2025/06/25 02:07:51 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.48s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:07:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test" 2025/06/25 02:07:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test.bin" 2025/06/25 02:07:57 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/25 02:08:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test" 2025/06/25 02:08:02 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test: Auto set use_segments_container to true 2025/06/25 02:08:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-include/layer2" 2025/06/25 02:08:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test-include/layer2.bin" 2025/06/25 02:08:08 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/06/25 02:08:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test-include/layer2" 2025/06/25 02:08:14 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/06/25 02:08:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-ignore-file" 2025/06/25 02:08:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test-ignore-file.bin" 2025/06/25 02:08:19 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/06/25 02:08:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/rclone-sync-test-ignore-file" 2025/06/25 02:08:25 DEBUG : Swift container crypt path rclone-test-sapines8tura/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/06/25 02:08:31 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = a7300b7ed7bdafb4833f5b6a1768439e OK 2025/06/25 02:08:36 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/25 02:08:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test': Waiting for checks to finish 2025/06/25 02:08:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test': Waiting for transfers to finish 2025/06/25 02:08:36 DEBUG : Waiting for deletions to finish 2025/06/25 02:08:36 INFO : There was nothing to transfer 2025/06/25 02:08:41 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/25 02:08:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:08:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:08:41 DEBUG : Waiting for deletions to finish 2025/06/25 02:08:41 INFO : There was nothing to transfer 2025/06/25 02:08:47 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/25 02:08:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/25 02:08:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/25 02:08:47 DEBUG : Waiting for deletions to finish 2025/06/25 02:08:47 INFO : There was nothing to transfer 2025/06/25 02:08:52 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/25 02:08:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:08:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:08:52 DEBUG : Waiting for deletions to finish 2025/06/25 02:08:52 INFO : There was nothing to transfer 2025/06/25 02:08:52 DEBUG : Excluded 2025/06/25 02:08:52 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/25 02:08:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/25 02:08:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/25 02:08:52 DEBUG : Waiting for deletions to finish 2025/06/25 02:08:52 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (55.93s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:08:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:08:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:08:53 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:08:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:08:58 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:09:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/CompareDest" 2025/06/25 02:09:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/CompareDest.bin" 2025/06/25 02:09:04 DEBUG : Swift container crypt path rclone-test-sapines8tura/CompareDest.bin: Auto set use_segments_container to true 2025/06/25 02:09:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/CompareDest" 2025/06/25 02:09:10 DEBUG : Swift container crypt path rclone-test-sapines8tura/CompareDest: Auto set use_segments_container to true 2025/06/25 02:09:20 DEBUG : one: Need to transfer - File not found at Destination 2025/06/25 02:09:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:09:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:09:26 DEBUG : one: md5 = e7eeac6031df2891cc334396959eaf11 OK 2025/06/25 02:09:26 INFO : one: Copied (new) 2025/06/25 02:09:26 DEBUG : Waiting for deletions to finish 2025/06/25 02:09:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:09:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:09:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:09:41 DEBUG : one: md5 = 8c069c2bcd1768c277866ad7140c09b3 OK 2025/06/25 02:09:41 INFO : one: Copied (replaced existing) 2025/06/25 02:09:41 DEBUG : Waiting for deletions to finish 2025/06/25 02:09:47 DEBUG : dst/one: md5 = 2daf0840dd42d5fb13f29f1cc225d442 OK 2025/06/25 02:09:52 DEBUG : CompareDest/one: md5 = 09a76edd079746442a3aacfaf0473a0e OK 2025/06/25 02:10:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:10:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:10:03 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:03 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/25 02:10:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:10:03 DEBUG : Waiting for deletions to finish 2025/06/25 02:10:03 INFO : There was nothing to transfer 2025/06/25 02:10:08 DEBUG : CompareDest/two: md5 = dafa700ed5243fea938c7da2934cffe6 OK 2025/06/25 02:10:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:10:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:18 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/25 02:10:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:10:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:18 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/25 02:10:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:10:18 DEBUG : Waiting for deletions to finish 2025/06/25 02:10:18 INFO : There was nothing to transfer 2025/06/25 02:10:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:10:29 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:29 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/25 02:10:29 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:29 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/25 02:10:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:10:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:10:29 DEBUG : Waiting for deletions to finish 2025/06/25 02:10:29 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/06/25 02:10:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:10:55 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:10:55 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/25 02:11:00 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/06/25 02:11:00 DEBUG : two: Need to transfer - File not found at Destination 2025/06/25 02:11:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:11:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:11:05 DEBUG : two: md5 = c9b94308df349295726f156ba78c77d5 OK 2025/06/25 02:11:05 INFO : two: Copied (new) 2025/06/25 02:11:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (158.40s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:11:32 DEBUG : pre-dest1/1: md5 = 452637ac1af36a58465eafa8bed5ba37 OK 2025/06/25 02:11:37 DEBUG : pre-dest2/2: md5 = 639a0d006cabbbd8d005614e64920914 OK 2025/06/25 02:11:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dest" 2025/06/25 02:11:42 DEBUG : Config file has changed externally - reloading 2025/06/25 02:11:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dest.bin" 2025/06/25 02:11:42 DEBUG : Swift container crypt path rclone-test-sapines8tura/dest.bin: Auto set use_segments_container to true 2025/06/25 02:11:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dest" 2025/06/25 02:11:49 DEBUG : Swift container crypt path rclone-test-sapines8tura/dest: Auto set use_segments_container to true 2025/06/25 02:11:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/pre-dest1" 2025/06/25 02:11:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/pre-dest1.bin" 2025/06/25 02:11:55 DEBUG : Swift container crypt path rclone-test-sapines8tura/pre-dest1.bin: Auto set use_segments_container to true 2025/06/25 02:12:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/pre-dest1" 2025/06/25 02:12:00 DEBUG : Swift container crypt path rclone-test-sapines8tura/pre-dest1: Auto set use_segments_container to true 2025/06/25 02:12:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/pre-dest2" 2025/06/25 02:12:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/pre-dest2.bin" 2025/06/25 02:12:06 DEBUG : Swift container crypt path rclone-test-sapines8tura/pre-dest2.bin: Auto set use_segments_container to true 2025/06/25 02:12:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/pre-dest2" 2025/06/25 02:12:11 DEBUG : Swift container crypt path rclone-test-sapines8tura/pre-dest2: Auto set use_segments_container to true 2025/06/25 02:12:17 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:12:17 DEBUG : 1: Destination found in --compare-dest, skipping 2025/06/25 02:12:22 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:12:22 DEBUG : 2: Destination found in --compare-dest, skipping 2025/06/25 02:12:33 DEBUG : 3: Need to transfer - File not found at Destination 2025/06/25 02:12:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dest': Waiting for checks to finish 2025/06/25 02:12:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dest': Waiting for transfers to finish 2025/06/25 02:12:38 DEBUG : 3: md5 = 4b569b12c0300e564a1cfad94bf5e722 OK 2025/06/25 02:12:38 INFO : 3: Copied (new) 2025/06/25 02:12:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (83.08s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:12:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:12:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:12:54 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:12:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:13:00 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:13:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/CopyDest" 2025/06/25 02:13:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/CopyDest.bin" 2025/06/25 02:13:05 DEBUG : Swift container crypt path rclone-test-sapines8tura/CopyDest.bin: Auto set use_segments_container to true 2025/06/25 02:13:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/CopyDest" 2025/06/25 02:13:11 DEBUG : Swift container crypt path rclone-test-sapines8tura/CopyDest: Auto set use_segments_container to true 2025/06/25 02:13:22 DEBUG : one: Need to transfer - File not found at Destination 2025/06/25 02:13:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:13:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:13:28 DEBUG : one: md5 = 02b6a7b33526d1f09ae5559b957370ba OK 2025/06/25 02:13:28 INFO : one: Copied (new) 2025/06/25 02:13:28 DEBUG : Waiting for deletions to finish 2025/06/25 02:13:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:13:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:13:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:13:44 DEBUG : one: md5 = 60cfba046d51fc2917d1bb88f44bb5e2 OK 2025/06/25 02:13:44 INFO : one: Copied (replaced existing) 2025/06/25 02:13:44 DEBUG : Waiting for deletions to finish 2025/06/25 02:13:57 DEBUG : dst/one: md5 = 7d53276cddf0a729f9a5b48a2047955c OK 2025/06/25 02:13:57 DEBUG : CopyDest/one: md5 = 13e05a65288d109a645046b20e9c5ecf OK 2025/06/25 02:13:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/BackupDir" 2025/06/25 02:13:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/BackupDir.bin" 2025/06/25 02:13:58 DEBUG : Swift container crypt path rclone-test-sapines8tura/BackupDir.bin: Auto set use_segments_container to true 2025/06/25 02:14:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/BackupDir" 2025/06/25 02:14:04 DEBUG : Swift container crypt path rclone-test-sapines8tura/BackupDir: Auto set use_segments_container to true 2025/06/25 02:14:09 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:14:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:14:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:14:09 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/25 02:14:30 INFO : one: Copied (server-side copy) 2025/06/25 02:14:35 INFO : one: Deleted 2025/06/25 02:14:45 INFO : one: Copied (server-side copy) 2025/06/25 02:14:45 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/06/25 02:14:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:14:45 DEBUG : Waiting for deletions to finish 2025/06/25 02:14:51 DEBUG : CopyDest/two: md5 = 60c4c6b24da0ed7b5d5b366e110aa358 OK 2025/06/25 02:14:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:14:57 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:14:57 DEBUG : one: Unchanged skipping 2025/06/25 02:15:07 INFO : two: Copied (server-side copy) 2025/06/25 02:15:07 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/06/25 02:15:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:15:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:15:07 DEBUG : Waiting for deletions to finish 2025/06/25 02:15:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:15:07 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:15:07 DEBUG : one: Unchanged skipping 2025/06/25 02:15:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:15:12 DEBUG : two: Unchanged skipping 2025/06/25 02:15:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:15:12 DEBUG : Waiting for deletions to finish 2025/06/25 02:15:12 INFO : There was nothing to transfer 2025/06/25 02:15:13 DEBUG : CopyDest/three: md5 = c162016743855418e540b8d78555881c OK 2025/06/25 02:15:14 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/06/25 02:15:14 DEBUG : three: Destination not found in --copy-dest 2025/06/25 02:15:14 DEBUG : three: Need to transfer - File not found at Destination 2025/06/25 02:15:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:15:14 DEBUG : one: Unchanged skipping 2025/06/25 02:15:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:15:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:15:14 DEBUG : two: Unchanged skipping 2025/06/25 02:15:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:15:14 DEBUG : three: md5 = a6612086c14a64d8f73c284f84c76451 OK 2025/06/25 02:15:14 INFO : three: Copied (new) 2025/06/25 02:15:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (181.68s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:16:01 DEBUG : dst/one: md5 = 51ef55120fd7d41ed6c5f18a0d6ba3a9 OK 2025/06/25 02:16:06 DEBUG : dst/two: md5 = c8fee83601587015556f0cc875679cb3 OK 2025/06/25 02:16:11 DEBUG : dst/three.txt: md5 = 75cf352738d05c902145170af7496e2f OK 2025/06/25 02:16:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:16:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:16:17 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:16:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:16:22 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:16:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/backup" 2025/06/25 02:16:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup.bin" 2025/06/25 02:16:28 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup.bin: Auto set use_segments_container to true 2025/06/25 02:16:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup" 2025/06/25 02:16:34 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup: Auto set use_segments_container to true 2025/06/25 02:16:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:16:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:16:39 DEBUG : two: Unchanged skipping 2025/06/25 02:16:50 INFO : one: Copied (server-side copy) 2025/06/25 02:16:55 INFO : one: Deleted 2025/06/25 02:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:17:16 DEBUG : one: md5 = b19711043a12468a7f0499df037c9ef4 OK 2025/06/25 02:17:16 INFO : one: Copied (new) 2025/06/25 02:17:16 DEBUG : Waiting for deletions to finish 2025/06/25 02:18:12 INFO : three.txt: Copied (server-side copy) 2025/06/25 02:18:17 INFO : three.txt: Deleted 2025/06/25 02:18:17 INFO : three.txt: Moved into backup dir 2025/06/25 02:18:33 DEBUG : dst/three.txt: md5 = e15438bc1449bc751cee678aec46c11a OK 2025/06/25 02:18:33 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:18:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:18:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:18:33 DEBUG : two: Unchanged skipping 2025/06/25 02:18:44 INFO : one: Copied (server-side copy) 2025/06/25 02:18:49 INFO : one: Deleted 2025/06/25 02:18:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:18:54 DEBUG : one: md5 = c55ef54512e3ee03e66548f2bde5c5fc OK 2025/06/25 02:18:54 INFO : one: Copied (new) 2025/06/25 02:18:54 DEBUG : Waiting for deletions to finish 2025/06/25 02:19:25 INFO : three.txt: Copied (server-side copy) 2025/06/25 02:19:45 INFO : three.txt: Deleted 2025/06/25 02:19:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (255.42s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:20:16 DEBUG : dst/one: md5 = b68fb9d8afc7613e5d53c5933d1b2203 OK 2025/06/25 02:20:21 DEBUG : dst/two: md5 = 00c092ca85cb2f403ca74565c8d8d12b OK 2025/06/25 02:20:26 DEBUG : dst/three.txt: md5 = 2d87371de36293a6f2607194aee9d35a OK 2025/06/25 02:20:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:20:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:20:32 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:20:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:20:38 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:20:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/backup" 2025/06/25 02:20:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup.bin" 2025/06/25 02:20:43 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup.bin: Auto set use_segments_container to true 2025/06/25 02:20:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup" 2025/06/25 02:20:49 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup: Auto set use_segments_container to true 2025/06/25 02:20:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:20:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:20:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:20:54 DEBUG : two: Unchanged skipping 2025/06/25 02:21:10 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:21:15 INFO : one: Deleted 2025/06/25 02:21:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:21:20 DEBUG : one: md5 = 9df42882ea6b38855625cdf50d207b72 OK 2025/06/25 02:21:20 INFO : one: Copied (new) 2025/06/25 02:21:20 DEBUG : Waiting for deletions to finish 2025/06/25 02:21:31 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:21:36 INFO : three.txt: Deleted 2025/06/25 02:21:36 INFO : three.txt: Moved into backup dir 2025/06/25 02:21:42 DEBUG : dst/three.txt: md5 = e691981181db7ed3a6dc4402ec762954 OK 2025/06/25 02:21:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:21:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:21:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:21:47 DEBUG : two: Unchanged skipping 2025/06/25 02:22:03 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:22:08 INFO : one: Deleted 2025/06/25 02:22:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:22:14 DEBUG : one: md5 = a2860d470148743fcb88f6d52bf36e69 OK 2025/06/25 02:22:14 INFO : one: Copied (new) 2025/06/25 02:22:14 DEBUG : Waiting for deletions to finish 2025/06/25 02:22:19 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:22:24 INFO : three.txt: Deleted 2025/06/25 02:22:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (163.95s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:23:00 DEBUG : dst/one: md5 = 614898a0d29b2935bd6ea79911972c0a OK 2025/06/25 02:23:05 DEBUG : dst/two: md5 = 0fd63156f0ce6c4f1f0c099ca32ceaa8 OK 2025/06/25 02:23:10 DEBUG : dst/three.txt: md5 = bfe6cf7748eaeb23de389769705c4c2f OK 2025/06/25 02:23:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:23:16 DEBUG : Config file has changed externally - reloading 2025/06/25 02:23:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:23:16 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:23:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:23:22 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:23:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/backup" 2025/06/25 02:23:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup.bin" 2025/06/25 02:23:27 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup.bin: Auto set use_segments_container to true 2025/06/25 02:23:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/backup" 2025/06/25 02:23:33 DEBUG : Swift container crypt path rclone-test-sapines8tura/backup: Auto set use_segments_container to true 2025/06/25 02:23:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:23:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:23:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:23:38 DEBUG : two: Unchanged skipping 2025/06/25 02:23:49 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/25 02:23:54 INFO : one: Deleted 2025/06/25 02:23:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:24:00 DEBUG : one: md5 = fda17aef2d2b2158b45e178d1b5f7ec5 OK 2025/06/25 02:24:00 INFO : one: Copied (new) 2025/06/25 02:24:00 DEBUG : Waiting for deletions to finish 2025/06/25 02:24:20 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/25 02:24:25 INFO : three.txt: Deleted 2025/06/25 02:24:25 INFO : three.txt: Moved into backup dir 2025/06/25 02:24:31 DEBUG : dst/three.txt: md5 = 679079b3018662e405f5b00ea8e7b4b3 OK 2025/06/25 02:24:36 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:24:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:24:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:24:36 DEBUG : two: Unchanged skipping 2025/06/25 02:24:42 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/25 02:24:47 INFO : one: Deleted 2025/06/25 02:24:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:24:52 DEBUG : one: md5 = 492cc082c478c6ff3d9ed22d14d46a67 OK 2025/06/25 02:24:52 INFO : one: Copied (new) 2025/06/25 02:24:52 DEBUG : Waiting for deletions to finish 2025/06/25 02:25:12 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/25 02:25:18 INFO : three.txt: Deleted 2025/06/25 02:25:18 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (164.01s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:25:44 DEBUG : dst/one: md5 = 340bb5d08812a7e8302a5c9f30a6abfe OK 2025/06/25 02:25:50 DEBUG : dst/two: md5 = 97bb83dbc19abbd46fba2811093076ff OK 2025/06/25 02:25:55 DEBUG : dst/three.txt: md5 = c2eea5312d009248ff58628579c0526e OK 2025/06/25 02:25:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:25:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:25:55 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:26:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:26:01 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:26:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:26:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:26:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:26:06 DEBUG : two: Unchanged skipping 2025/06/25 02:26:27 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:26:32 INFO : one: Deleted 2025/06/25 02:26:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:26:37 DEBUG : one: md5 = 723bca9d05fc15f111885557a2e319ef OK 2025/06/25 02:26:37 INFO : one: Copied (new) 2025/06/25 02:26:37 DEBUG : Waiting for deletions to finish 2025/06/25 02:26:53 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:26:58 INFO : three.txt: Deleted 2025/06/25 02:26:58 INFO : three.txt: Moved into backup dir 2025/06/25 02:27:14 DEBUG : dst/three.txt: md5 = 737d5c6be11e26e2fc22de373294d9c9 OK 2025/06/25 02:27:24 DEBUG : one.bak: Excluded (Path Filter) 2025/06/25 02:27:24 DEBUG : one.bak: Excluded 2025/06/25 02:27:24 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/25 02:27:24 DEBUG : three.txt.bak: Excluded 2025/06/25 02:27:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:27:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for checks to finish 2025/06/25 02:27:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:27:24 DEBUG : two: Unchanged skipping 2025/06/25 02:27:39 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:27:45 INFO : one: Deleted 2025/06/25 02:27:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura/dst': Waiting for transfers to finish 2025/06/25 02:27:50 DEBUG : one: md5 = a2953e132b0b706939dee1f9b4afdc32 OK 2025/06/25 02:27:50 INFO : one: Copied (new) 2025/06/25 02:27:50 DEBUG : Waiting for deletions to finish 2025/06/25 02:27:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:28:00 INFO : three.txt: Deleted 2025/06/25 02:28:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (182.65s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:28:47 DEBUG : dst/one: md5 = bf4af55dd65a27427ba79b9f6fc032d6 OK 2025/06/25 02:28:52 DEBUG : dst/two: md5 = 4460135bdade2614b2b6ffe69bfa7f8a OK 2025/06/25 02:28:57 DEBUG : dst/three.txt: md5 = e263e149f91c6d5775e3957c344ef464 OK 2025/06/25 02:29:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:29:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:29:03 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:29:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:29:08 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:29:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:29:29 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:29:34 INFO : one: Deleted 2025/06/25 02:29:39 DEBUG : one: md5 = 6227ebd5ea33409253d2fe92e4fbdd36 OK 2025/06/25 02:29:39 INFO : one: Copied (new) 2025/06/25 02:29:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:29:39 DEBUG : two: Unchanged skipping 2025/06/25 02:29:44 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/25 02:29:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:30:00 INFO : three.txt: Deleted 2025/06/25 02:30:05 DEBUG : three.txt: md5 = 4227873d0866784790cfe523c86dd96c OK 2025/06/25 02:30:05 INFO : three.txt: Copied (new) 2025/06/25 02:30:31 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:30:51 INFO : one: Copied (server-side copy) to: one.bak 2025/06/25 02:30:56 INFO : one: Deleted 2025/06/25 02:31:01 DEBUG : one: md5 = e92aa2ae17707ad3d5ffceb61a4aac22 OK 2025/06/25 02:31:01 INFO : one: Copied (new) 2025/06/25 02:31:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:31:01 DEBUG : two: Unchanged skipping 2025/06/25 02:31:01 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/25 02:31:07 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/25 02:31:12 INFO : three.txt: Deleted 2025/06/25 02:31:17 DEBUG : three.txt: md5 = 89f11fe2e499db46c9c91cfaa7c87354 OK 2025/06/25 02:31:17 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (191.78s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:31:59 DEBUG : dst/one: md5 = 0cdb83cf92a66857f817936c45d511df OK 2025/06/25 02:32:04 DEBUG : dst/two: md5 = 4c7cec8d11f2cf5c3f5ba92c0abed484 OK 2025/06/25 02:32:10 DEBUG : dst/three.txt: md5 = 47378e091b39ecd335f80b79f06e3f69 OK 2025/06/25 02:32:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sapines8tura/dst" 2025/06/25 02:32:20 DEBUG : Config file has changed externally - reloading 2025/06/25 02:32:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst.bin" 2025/06/25 02:32:21 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst.bin: Auto set use_segments_container to true 2025/06/25 02:32:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sapines8tura/dst" 2025/06/25 02:32:26 DEBUG : Swift container crypt path rclone-test-sapines8tura/dst: Auto set use_segments_container to true 2025/06/25 02:32:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/25 02:32:42 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/25 02:32:47 INFO : one: Deleted 2025/06/25 02:32:52 DEBUG : one: md5 = c21c42027ec93ba93b6a80587f841e00 OK 2025/06/25 02:32:52 INFO : one: Copied (new) 2025/06/25 02:32:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:32:53 DEBUG : two: Unchanged skipping 2025/06/25 02:32:58 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/25 02:33:18 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/25 02:33:24 INFO : three.txt: Deleted 2025/06/25 02:33:29 DEBUG : three.txt: md5 = ef0983e9f795fcf67e9ef3fd61a5de0e OK 2025/06/25 02:33:29 INFO : three.txt: Copied (new) 2025/06/25 02:33:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/25 02:33:45 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/25 02:33:50 INFO : one: Deleted 2025/06/25 02:33:56 DEBUG : one: md5 = 032bb4068c7227ff5c98d9c8708e5f51 OK 2025/06/25 02:33:56 INFO : one: Copied (new) 2025/06/25 02:33:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:33:56 DEBUG : two: Unchanged skipping 2025/06/25 02:33:56 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/25 02:34:11 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/25 02:34:16 INFO : three.txt: Deleted 2025/06/25 02:34:22 DEBUG : three.txt: md5 = 3d00fa36c1b67548085b4eccc49a358f OK 2025/06/25 02:34:22 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (187.81s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:35:02 DEBUG : Testêé: md5 = 7e76b1a1ec2d1d3b89fc1c83e9cad135 OK 2025/06/25 02:35:03 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/06/25 02:35:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:35:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:35:03 DEBUG : Testêé: md5 = ce373c643d52750840ecc8cb145ee1b6 OK 2025/06/25 02:35:03 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/06/25 02:35:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.25s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:35:04 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/25 02:35:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:35:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:35:09 DEBUG : existing: md5 = 86017f1cde05aef90703d163095b8d74 OK 2025/06/25 02:35:09 INFO : existing: Copied (new) 2025/06/25 02:35:09 DEBUG : Waiting for deletions to finish 2025/06/25 02:35:19 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/06/25 02:35:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:35:25 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/06/25 02:35:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:35:25 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': not deleting files as there were IO errors 2025/06/25 02:35:25 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (31.47s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:35:40 DEBUG : EXISTING: md5 = fe5cf9816ad67d263c40a5034963921d OK 2025/06/25 02:35:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:35:46 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:35:46 DEBUG : existing: Unchanged skipping 2025/06/25 02:35:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:35:46 DEBUG : Waiting for deletions to finish 2025/06/25 02:35:46 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (21.03s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:2636: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.09s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" sync_test.go:2675: 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-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:36:02 DEBUG : both0: md5 = 7a0ede10b4087b3e7d7d19437c0c087b OK 2025/06/25 02:36:03 DEBUG : only0: md5 = e7fb88359774339594c7bfa805269ea6 OK 2025/06/25 02:36:08 DEBUG : both1: md5 = cea062c38e65b49ce3de367d7ffab4ab OK 2025/06/25 02:36:13 DEBUG : only1: md5 = 8eaca853dd24d060593b4796622bf1cc OK 2025/06/25 02:36:19 DEBUG : both2: md5 = 6d0f429a5452dbb2bb2902a02fdbf323 OK 2025/06/25 02:36:24 DEBUG : only2: md5 = 726484ce36db7f4ac19d206b95d2a700 OK 2025/06/25 02:36:24 DEBUG : both3: md5 = 18413ce6cc739a067d01c9b4313e6fc4 OK 2025/06/25 02:36:25 DEBUG : only3: md5 = ba1ccc532b13f2935a688f5231ae5e25 OK 2025/06/25 02:36:30 DEBUG : both4: md5 = 9bebbd98cefb675844480812ff02fea4 OK 2025/06/25 02:36:31 DEBUG : only4: md5 = 1b5e159f31618659ae1b19ef30ea787e OK 2025/06/25 02:36:36 DEBUG : both5: md5 = 324ffcb9ad3d68f50073ff8983af821c OK 2025/06/25 02:36:37 DEBUG : only5: md5 = e74c091f0c1fa32937d968296b1647b6 OK 2025/06/25 02:36:42 DEBUG : both6: md5 = e848a4b9a58d1de89b16b1cc6553e112 OK 2025/06/25 02:36:48 DEBUG : only6: md5 = 03ade9dca627d0e08f8f88166e7eacbd OK 2025/06/25 02:36:53 DEBUG : both7: md5 = aad57538890fffebe64a41e7720176f7 OK 2025/06/25 02:36:53 DEBUG : only7: md5 = bc7ab2a5373408ab9f7788cb43c0f835 OK 2025/06/25 02:36:54 DEBUG : both8: md5 = 798ebd854801034d747b686ccb189248 OK 2025/06/25 02:36:59 DEBUG : only8: md5 = dbc1a01372a1ce7026566c6277955c5f OK 2025/06/25 02:37:05 DEBUG : both9: md5 = 98641f60dd807a4fe1d2bb85e1e79460 OK 2025/06/25 02:37:05 DEBUG : only9: md5 = cbfb718a9690fff0d5ea404cf9d73b41 OK 2025/06/25 02:37:10 DEBUG : both10: md5 = bef63a01029ab7b61e23d4afeb5d71db OK 2025/06/25 02:37:16 DEBUG : only10: md5 = 2d6a365cef100b4757e4422d88267dc9 OK 2025/06/25 02:37:22 DEBUG : both11: md5 = 148caf272f4aab8d2ae94fc964a1d263 OK 2025/06/25 02:37:22 DEBUG : only11: md5 = 0f40c659c5ea743741d629ef173ba79e OK 2025/06/25 02:37:23 DEBUG : both12: md5 = f48f7b6f9d415c5a20b3df9b925e4cc5 OK 2025/06/25 02:37:23 DEBUG : only12: md5 = 41410b56211cf82c7dbfdf3af009f986 OK 2025/06/25 02:37:24 DEBUG : both13: md5 = 7771d0f7513f1e52e3481aace82be1a7 OK 2025/06/25 02:37:29 DEBUG : only13: md5 = f00079942f9e04f06f8b4c2a9303aecb OK 2025/06/25 02:37:34 DEBUG : both14: md5 = 8935bfe44a8f90e7bc4c7cc41e742e80 OK 2025/06/25 02:37:35 DEBUG : only14: md5 = 678efe7ea605db8827a25230ad0409bc OK 2025/06/25 02:37:35 DEBUG : both15: md5 = f863800a7be06d0aeea7572673c38ba5 OK 2025/06/25 02:37:35 DEBUG : only15: md5 = cf5a4263c3a10bc584c8860ae5de1861 OK 2025/06/25 02:37:36 DEBUG : both16: md5 = 26c631538a5640d9adb8b229428eaf0d OK 2025/06/25 02:37:41 DEBUG : only16: md5 = ca099f6cdc5cdad56ff43a17334d694c OK 2025/06/25 02:37:41 DEBUG : both17: md5 = d1c61e9cf570a00a612705f1fe40217a OK 2025/06/25 02:37:42 DEBUG : only17: md5 = b4a734caa0aa2c55b0886feebdd0bd38 OK 2025/06/25 02:37:42 DEBUG : both18: md5 = 16acf4b7ff0284d8799d8d7e83abdfd7 OK 2025/06/25 02:37:42 DEBUG : only18: md5 = 743cf6a99d8ddaa938d3d7f24274c4a5 OK 2025/06/25 02:37:43 DEBUG : both19: md5 = d52c6217da5590768e44f9373d6396c1 OK 2025/06/25 02:37:48 DEBUG : only19: md5 = ed9ba1d979e0b3f1962ea81d9348a8e5 OK 2025/06/25 02:38:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:38:20 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both0: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both10: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both11: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both12: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both13: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both15: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both16: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both17: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both18: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both19: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both1: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both2: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both3: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both4: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both5: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both6: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both7: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both8: Unchanged skipping 2025/06/25 02:38:20 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:20 DEBUG : both9: Unchanged skipping 2025/06/25 02:38:25 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:38:25 DEBUG : both14: Unchanged skipping 2025/06/25 02:38:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:38:25 DEBUG : Waiting for deletions to finish 2025/06/25 02:38:25 INFO : only12: Deleted 2025/06/25 02:38:25 INFO : only3: Deleted 2025/06/25 02:38:25 INFO : only15: Deleted 2025/06/25 02:38:26 INFO : only18: Deleted 2025/06/25 02:38:30 INFO : only8: Deleted 2025/06/25 02:38:30 INFO : only10: Deleted 2025/06/25 02:38:30 INFO : only4: Deleted 2025/06/25 02:38:30 INFO : only16: Deleted 2025/06/25 02:38:30 INFO : only14: Deleted 2025/06/25 02:38:30 INFO : only17: Deleted 2025/06/25 02:38:30 INFO : only5: Deleted 2025/06/25 02:38:31 INFO : only7: Deleted 2025/06/25 02:38:31 INFO : only11: Deleted 2025/06/25 02:38:31 INFO : only9: Deleted 2025/06/25 02:38:31 INFO : only0: Deleted 2025/06/25 02:38:35 INFO : only13: Deleted 2025/06/25 02:38:36 INFO : only19: Deleted 2025/06/25 02:38:40 INFO : only2: Deleted 2025/06/25 02:38:41 INFO : only6: Deleted 2025/06/25 02:38:41 INFO : only1: Deleted 2025/06/25 02:38:41 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (264.00s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:40:26 DEBUG : both0: md5 = 7594ff0d3285b8b087fdfdd3a9bb5d82 OK 2025/06/25 02:40:26 DEBUG : only0: md5 = 380d9ea30ca6cdc4fa0b08074d6439b5 OK 2025/06/25 02:40:31 DEBUG : both1: md5 = 854ac9872b742355fa9cec91094cc6b5 OK 2025/06/25 02:40:37 DEBUG : only1: md5 = e97d3d89eaf304e3b421f72ef0016d5e OK 2025/06/25 02:40:42 DEBUG : both2: md5 = 042a6cdd4b5bbf756ada373d07df9a8c OK 2025/06/25 02:40:47 DEBUG : only2: md5 = f78c76d2dd770ca784da4cdded587650 OK 2025/06/25 02:40:47 DEBUG : both3: md5 = 0387088adba09a761e04aa1b3ab704af OK 2025/06/25 02:40:47 DEBUG : only3: md5 = c4603223b15205eb60010a401d4f7ea4 OK 2025/06/25 02:40:52 DEBUG : both4: md5 = 334d807c3ef248619b9e4eb4c847c1ab OK 2025/06/25 02:40:53 DEBUG : only4: md5 = a2a217363a3e89cb7775f8bed64185a0 OK 2025/06/25 02:40:58 DEBUG : both5: md5 = eca85094345c371df46056271cd42de0 OK 2025/06/25 02:40:58 DEBUG : only5: md5 = 0ac07cb6d149315b3436c0a2bd10833c OK 2025/06/25 02:41:03 DEBUG : both6: md5 = f2788d35f8b869ebc36b4569493ec33f OK 2025/06/25 02:41:09 DEBUG : only6: md5 = 1262841bd1cb5eca61e8df9a85bc9080 OK 2025/06/25 02:41:14 DEBUG : both7: md5 = 068a57c84f9d35fa5f2e0c0f6a36cf95 OK 2025/06/25 02:41:14 DEBUG : only7: md5 = 3a1156dd316b6f000a29647330ba62a2 OK 2025/06/25 02:41:14 DEBUG : both8: md5 = ae75f8bb76c28a08953f40810d84d10e OK 2025/06/25 02:41:19 DEBUG : only8: md5 = d9b3cbdec6fbd664d2df5562e3ec7fd4 OK 2025/06/25 02:41:24 DEBUG : both9: md5 = 3cf0a1c75dff777590a6bd291ce76408 OK 2025/06/25 02:41:25 DEBUG : only9: md5 = 0c3ddf98355b60120d9d12332f21ed23 OK 2025/06/25 02:41:30 DEBUG : both10: md5 = 087f0a98c12dcaac717e179eb90cc193 OK 2025/06/25 02:41:35 DEBUG : only10: md5 = d7b3591c0197d549da76a90085149633 OK 2025/06/25 02:41:40 DEBUG : both11: md5 = 4b7b506c4756cc1d629ab0a945757a23 OK 2025/06/25 02:41:40 DEBUG : only11: md5 = 9af6700d2fe4e284a4fe3324f9d7204f OK 2025/06/25 02:41:41 DEBUG : both12: md5 = 66333126cc77a162e60a0098d1002f1e OK 2025/06/25 02:41:41 DEBUG : only12: md5 = 73f9ac41152345b76c2b1f91f972c2e3 OK 2025/06/25 02:41:41 DEBUG : both13: md5 = 54f467da638d5688881b1553fa0a0503 OK 2025/06/25 02:41:46 DEBUG : only13: md5 = f3e69ec8c2c4894eecac029c5b54b8a9 OK 2025/06/25 02:41:51 DEBUG : both14: md5 = 4b8623f836fc5b9f5601bfba4df4d61b OK 2025/06/25 02:41:51 DEBUG : only14: md5 = 3b574bc7eeb2e2f947a2657cf123b88f OK 2025/06/25 02:41:51 DEBUG : both15: md5 = 31879030fc093f9120e34edf36e4bfc4 OK 2025/06/25 02:41:51 DEBUG : only15: md5 = b6db44a237e825cd5d016593861dc39b OK 2025/06/25 02:41:52 DEBUG : both16: md5 = c1fa9f5924405a997f2a5952eeefbfda OK 2025/06/25 02:41:57 DEBUG : only16: md5 = d07b94bfb91dd242f98ece1fd8e059a8 OK 2025/06/25 02:41:57 DEBUG : both17: md5 = 6219d11898556e80f1042de0acf6195e OK 2025/06/25 02:41:57 DEBUG : only17: md5 = 529f7bb424a6b65b6bbf68cb80eb6f95 OK 2025/06/25 02:41:57 DEBUG : both18: md5 = 7b02a937d89d8a7d681cff5fa5fe8aff OK 2025/06/25 02:41:57 DEBUG : only18: md5 = 57c09e1f80b9f9af1a1c1b2b69fbd08a OK 2025/06/25 02:41:58 DEBUG : both19: md5 = 73f2c7958d3f0bd8d181947445d7eb43 OK 2025/06/25 02:42:03 DEBUG : only19: md5 = 43a6b370d08ff88774f70b087a57d86f OK 2025/06/25 02:42:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for checks to finish 2025/06/25 02:42:45 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both10: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both0: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both11: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both12: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both13: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both15: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both16: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both17: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both18: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both19: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both1: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both2: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both3: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both4: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both6: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both8: Unchanged skipping 2025/06/25 02:42:45 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:45 DEBUG : both9: Unchanged skipping 2025/06/25 02:42:45 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:45 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/06/25 02:42:46 DEBUG : only0: md5 = 2f026e5c443aab88b662ddda0208e1e7 OK 2025/06/25 02:42:46 INFO : only0: Copied (replaced existing) 2025/06/25 02:42:46 DEBUG : only11: md5 = 6ece94079b7bd166370a799f603218ad OK 2025/06/25 02:42:46 INFO : only11: Copied (replaced existing) 2025/06/25 02:42:46 DEBUG : only12: md5 = 0861c6a6f05460c955940ac08f4be58e OK 2025/06/25 02:42:46 INFO : only12: Copied (replaced existing) 2025/06/25 02:42:46 DEBUG : only14: md5 = 4cad22fa283ac7dc9c660585a14a1ceb OK 2025/06/25 02:42:46 INFO : only14: Copied (replaced existing) 2025/06/25 02:42:46 DEBUG : only15: md5 = d760f76f93b5ed2e382f70c20d1bc929 OK 2025/06/25 02:42:46 INFO : only15: Copied (replaced existing) 2025/06/25 02:42:46 DEBUG : only17: md5 = e191a30a099abc157dce03f435549424 OK 2025/06/25 02:42:46 INFO : only17: Copied (replaced existing) 2025/06/25 02:42:47 DEBUG : only18: md5 = fab91f688b88daa9fba0203aefe8f7c1 OK 2025/06/25 02:42:47 INFO : only18: Copied (replaced existing) 2025/06/25 02:42:50 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:50 DEBUG : both14: Unchanged skipping 2025/06/25 02:42:50 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:50 DEBUG : both5: Unchanged skipping 2025/06/25 02:42:50 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/25 02:42:50 DEBUG : both7: Unchanged skipping 2025/06/25 02:42:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura': Waiting for transfers to finish 2025/06/25 02:42:51 DEBUG : only13: md5 = 90622d9e710c540c79d144c29c68ae5f OK 2025/06/25 02:42:51 INFO : only13: Copied (replaced existing) 2025/06/25 02:42:51 DEBUG : only16: md5 = 1d6e8db1df408d82ed6417dc1acd991e OK 2025/06/25 02:42:51 INFO : only16: Copied (replaced existing) 2025/06/25 02:42:52 DEBUG : only19: md5 = 8242e757fe514a39f59f1ad4300f801f OK 2025/06/25 02:42:52 INFO : only19: Copied (replaced existing) 2025/06/25 02:42:52 DEBUG : only3: md5 = 8f0ae6a058534e6e372a6d2ac584309d OK 2025/06/25 02:42:52 INFO : only3: Copied (replaced existing) 2025/06/25 02:42:52 DEBUG : only4: md5 = 5d4633ca0b843a683c3fff89c107565c OK 2025/06/25 02:42:52 INFO : only4: Copied (replaced existing) 2025/06/25 02:42:52 DEBUG : only5: md5 = 0ff7b3f3c3948b1b7778c00c6af9355b OK 2025/06/25 02:42:52 INFO : only5: Copied (replaced existing) 2025/06/25 02:42:55 DEBUG : only10: md5 = 316b2ccdc74d977e15b9c00e19f58e86 OK 2025/06/25 02:42:55 INFO : only10: Copied (replaced existing) 2025/06/25 02:42:56 DEBUG : only7: md5 = 220dda7e5f820bd1adaba8eb58bea913 OK 2025/06/25 02:42:56 INFO : only7: Copied (replaced existing) 2025/06/25 02:42:56 DEBUG : only2: md5 = 889bcf14d1a7604cce2fc89b4353e753 OK 2025/06/25 02:42:56 INFO : only2: Copied (replaced existing) 2025/06/25 02:42:56 DEBUG : only9: md5 = d9c4b5dbf44d8558a26a3c57af2c0696 OK 2025/06/25 02:42:56 INFO : only9: Copied (replaced existing) 2025/06/25 02:42:58 DEBUG : only6: md5 = 8ed0f128c005dd6732d09d642df1ec48 OK 2025/06/25 02:42:58 INFO : only6: Copied (replaced existing) 2025/06/25 02:43:01 DEBUG : only8: md5 = 3c1db60dd80a0c37792a1a9e0840b3f9 OK 2025/06/25 02:43:01 INFO : only8: Copied (replaced existing) 2025/06/25 02:43:01 DEBUG : only1: md5 = f9fa4bc46f5c47ede660ccbb555fdfda OK 2025/06/25 02:43:01 INFO : only1: Copied (replaced existing) 2025/06/25 02:43:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (344.12s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sapines8tura'", Local "Local file system at /tmp/rclone1605875898", Modify Window "1ns" 2025/06/25 02:46:05 DEBUG : empty_dir: Making directory with metadata 2025/06/25 02:46:05 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 02:46:05 DEBUG : empty_on_remote: Making directory with metadata 2025/06/25 02:46:05 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/25 02:46:05 INFO : empty_on_remote: Making directory 2025/06/25 02:46:05 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/25 02:46:10 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/25 02:46:10 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/25 02:46:10 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/25 02:46:10 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) panic: test timed out after 1h0m0s running tests: TestSyncReplaceDirModTime (28s) goroutine 13163 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc0018e5340, {0x26bc076?, 0xc00006dad8?}, 0x27c19e0) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0018e5340) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0018e5340, 0xc00006dc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0019d8c90, {0x3e7e5e0, 0x6b, 0x6b}, {0xedfed4bf9?, 0x3e945a0?, 0x3e945a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0005743c0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0005743c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:259 +0xa9 goroutine 13162 [select]: net/http.(*persistConn).roundTrip(0xc0009b8900, 0xc00084bef0) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0001fc4e0, 0xc0006c4280) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0003f4150, 0xc0006c4280) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc0006c4280, {0x2acdfc0, 0xc0003f4150}, {0x3e9ed01?, 0xc001df0da0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001b1ae70, 0xc0006c4280, {0x0?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001b1ae70, 0xc0006c4280) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 13026 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 23 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 24 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 13026 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc000365a40, 0xc0004c0000, 0xc0006c4280) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc000365a40, {0x2af4b08, 0x3eb7b60}, {0xc00021d180, 0x4f}, {{0xc00012d200, 0x5}, {0xc00012d206, 0x2c}, {0x2681b0a, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc000365a40, {0x2af4b08, 0x3eb7b60}, {{0xc00012d200, 0x5}, {0xc00012d206, 0x2c}, {0x2681b0a, 0x6}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).ObjectDelete(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1902 github.com/rclone/rclone/backend/swift.(*Object).Remove.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1620 +0x105 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc00008f808?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0019c3200, 0xc00084be50, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0019c3200, 0xc00084be50) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).Remove(0xc001e81980, {0x2af4b08, 0x3eb7b60}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1619 +0x29b github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x24 github.com/rclone/rclone/fstest.retry(0xc001c1a1c0, {0xc00240f080, 0x1f}, 0xc00006d8c8) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc00090cd80?, 0xc00090cd80?, 0x1e03374?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc00090cd80, 0x3, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:331 +0x1ca github.com/rclone/rclone/backend/crypt.(*Fs).ListR.func1({0xc00090cd80?, 0xc00240efc0?, 0x5?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:481 +0x5b github.com/rclone/rclone/fs/list.(*Helper).send(...) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:29 github.com/rclone/rclone/fs/list.(*Helper).Flush(0xc00006db30) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/swift.(*Fs).ListR(0xc0018e4fc0, {0x2af4b08, 0x3eb7b60}, {0x0?, 0x2416cc0?}, 0xc0000cd740) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:881 +0x185 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc001a00680, {0x2af4b08, 0x3eb7b60}, {0x0, 0x0}, 0xc00084bb80) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2af4b08, 0x3eb7b60}, {0x2b09280, 0xc001a00680}, {0x0, 0x0}, 0x1, 0x3, 0xc0000cd5c0, 0xc0019d88b8, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2af4b08, 0x3eb7b60}, {0x2b09280, 0xc001a00680}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc0000cd5c0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:163 +0x1d5 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:150 +0x107 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc0003a38f0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc001c1a1c0, 0x4b6b00?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 testing.tRunner(0xc001c1a1c0, 0x27c19e0) /usr/local/go/src/testing/testing.go:1798 +0x11e created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 13122 [select]: net/http.(*persistConn).writeLoop(0xc0009b8900) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 13114 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 13119 [select]: net/http.(*persistConn).writeLoop(0xc000587200) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 13095 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 13118 [IO wait]: internal/poll.runtime_pollWait(0x7507a86266f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001d6c200?, 0xc00024c600?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001d6c200, {0xc00024c600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001d6c200, {0xc00024c600?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000806070, {0xc00024c600?, 0x0?, 0x3eb3e60?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0023804f8, {0xc00024c600?, 0x7507a85df8e8?, 0x7507f14bf108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001715e48, {0xc00024c600?, 0x3eba3e0?, 0xc00234b9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001fe2b8, {0x2acf520, 0xc001715e48}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001fe008, {0x7507a84c0a40, 0xc0023804f8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0001fe008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0001fe008, {0xc0006fc000, 0x1000, 0xc0004f0810?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000587200, {0xc0006fc000?, 0x7f4ea5?, 0x2acc440?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001f13020) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001f13020, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000587200) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 13095 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 13041 [IO wait]: internal/poll.runtime_pollWait(0x7507a8626178, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001c3b080?, 0xc00024ac80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001c3b080, {0xc00024ac80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001c3b080, {0xc00024ac80?, 0x1973?, 0xc002179a80?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0001c2c50, {0xc00024ac80?, 0x0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001714cd8, {0xc00024ac80?, 0x7507a8431dd8?, 0x7507f14bf5c0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d9ea8, {0xc00024ac80?, 0x3eba3e0?, 0xfc0008?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006f62b8, {0x2acf520, 0xc0019d9ea8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006f6008, {0x7507a84c0a40, 0xc001714cd8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006f6008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0006f6008, {0xc000be2000, 0x1000, 0xc0004f0810?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0009b8900, {0xc000be2000?, 0x7f4ea5?, 0x2acc440?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001c0ade0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001c0ade0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0009b8900) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 13114 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12531 [select]: net/http.(*persistConn).writeLoop(0xc0005e3560) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12489 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 2082 [chan receive, 58 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 2066 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 12530 [IO wait]: internal/poll.runtime_pollWait(0x7507a86263a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0003ce680?, 0xc0007cf500?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0003ce680, {0xc0007cf500, 0x3500, 0x3500}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0003ce680, {0xc0007cf500?, 0x34f3?, 0xc0003e2c00?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0001c2ba8, {0xc0007cf500?, 0xc0006f68d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0024074d0, {0xc0007cf500?, 0x7507f14c8170?, 0x7507f14bff30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000010f60, {0xc0007cf500?, 0x34f3?, 0xc0006f39d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006f69b8, {0x2acf520, 0xc000010f60}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006f6708, {0x7507a84c0a40, 0xc0024074d0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006f6708, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0006f6708, {0xc00069c000, 0x1000, 0xc0004f0810?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0005e3560, {0xc00069c000?, 0x7f4ea5?, 0x2acc440?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0019c2000) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0019c2000, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0005e3560) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12489 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 13123 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001cd2000, {0x2af4be8, 0xc000578be0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 13026 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.747279827s (try 1/5): exit status 2: Failed []