"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 2/5) 2025/06/19 04:36:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa" 2025/06/19 04:36:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/19 04:36:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa.bin" 2025/06/19 04:36:52 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa.bin: Auto set use_segments_container to true 2025/06/19 04:36:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa" 2025/06/19 04:36:58 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa: Auto set use_segments_container to true 2025/06/19 04:37:03 DEBUG : Creating backend with remote "/tmp/rclone2811650836" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:04 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/06/19 04:37:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.79s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 04:37:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:05 DEBUG : sub dir/hello world: md5 = 7c329451815224ebc17a24c377d7d966 OK 2025/06/19 04:37:05 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.30s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.31s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:06 DEBUG : Creating backend with remote "/non-existing" 2025/06/19 04:37:06 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/06/19 04:37:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.20s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:11 DEBUG : sub dir/hello world: md5 = ca9f833b0251611d1eebfeaaa9400952 OK 2025/06/19 04:37:11 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.99s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:12 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Running all checks before starting transfers 2025/06/19 04:37:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:12 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Checks finished, now starting transfers 2025/06/19 04:37:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:12 DEBUG : sub dir/hello world: md5 = 42d154a8198bbb29de1ed55661cfc076 OK 2025/06/19 04:37:12 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.98s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:13 ERROR : Ignoring --no-traverse with sync 2025/06/19 04:37:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:13 DEBUG : sub dir/hello world: md5 = 0129398e83e44255b2c6ed7e86947c32 OK 2025/06/19 04:37:13 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.21s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:14 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/06/19 04:37:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:19 DEBUG : hello world2: md5 = cfd141daf76753bcdfc96438487d51ac OK 2025/06/19 04:37:19 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (15.96s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:37:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:30 DEBUG : potato2: md5 = 1f9f292ec030f0a0e720028a65f865d8 OK 2025/06/19 04:37:30 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.19s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:37:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:36 DEBUG : potato2: md5 = efa0d772cafbe0ede82e2971c451b697 OK 2025/06/19 04:37:36 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (5.88s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:37 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/19 04:37:37 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 04:37:37 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/06/19 04:37:37 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 04:37:37 INFO : sub dir2: Making directory 2025/06/19 04:37:37 INFO : sub dir: Making directory 2025/06/19 04:37:37 INFO : sub dir2/sub sub dir2: Making directory 2025/06/19 04:37:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:38 DEBUG : sub dir/hello world: md5 = 7428ceabc8f27ebb61fdd3b4bc113693 OK 2025/06/19 04:37:38 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/19 04:37:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.01s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:38 INFO : sub dir2: Making directory 2025/06/19 04:37:38 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/19 04:37:38 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 04:37:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:38 DEBUG : sub dir/hello world: md5 = 06b5c2b95e75d9eee8df9feb447cdf0b OK 2025/06/19 04:37:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (0.98s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:39 DEBUG : sub dir2: Making directory with metadata 2025/06/19 04:37:39 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 04:37:39 INFO : sub dir2: Making directory 2025/06/19 04:37:39 INFO : sub dir: Making directory 2025/06/19 04:37:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:39 DEBUG : sub dir/hello world: md5 = 2570c7471e08dc6a5b8a73d9015ee395 OK 2025/06/19 04:37:39 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:39 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/06/19 04:37:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/06/19 04:37:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (0.91s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", 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-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:40 INFO : sub dir2: Making directory 2025/06/19 04:37:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:40 DEBUG : sub dir/hello world: md5 = 7edbc358e8bcfd9d0404c2c8f2eea911 OK 2025/06/19 04:37:40 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:40 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (1.02s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:41 DEBUG : sub dir2: Making directory with metadata 2025/06/19 04:37:41 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 04:37:41 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 04:37:41 INFO : sub dir2: Making directory 2025/06/19 04:37:41 INFO : sub dir: Making directory 2025/06/19 04:37:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:42 DEBUG : sub dir/hello world: md5 = c2fd560e5df109c55b004c14d54d36c9 OK 2025/06/19 04:37:42 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:42 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/06/19 04:37:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.18s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.33s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:43 INFO : sub dir2: Making directory 2025/06/19 04:37:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:37:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:37:43 DEBUG : sub dir/hello world: md5 = 1f8cf5a32a06939838b591091ee541c4 OK 2025/06/19 04:37:43 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:37:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.99s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:37:44 DEBUG : sub dir/hello world: md5 = a536a9294215f43ae846dcce7ab1b995 OK 2025/06/19 04:37:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wijenif4laqu" 2025/06/19 04:37:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wijenif4laqu.bin" 2025/06/19 04:37:44 DEBUG : Swift container crypt path rclone-test-wijenif4laqu.bin: Auto set use_segments_container to true 2025/06/19 04:37:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wijenif4laqu" 2025/06/19 04:37:50 DEBUG : Swift container crypt path rclone-test-wijenif4laqu: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-wijenif4laqu' 2025/06/19 04:37:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:37:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wijenif4laqu': Waiting for checks to finish 2025/06/19 04:37:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wijenif4laqu': Waiting for transfers to finish 2025/06/19 04:38:11 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:38:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wijenif4laqu': Purge remote 2025/06/19 04:38:11 DEBUG : Waiting for deletions to finish 2025/06/19 04:38:17 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (33.34s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:38:17 DEBUG : sub dir/hello world: md5 = e0701c86268e619353a407024142b706 OK 2025/06/19 04:38:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:38:18 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 04:38:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:38:18 DEBUG : sub dir/hello world: md5 = 94ba0199fe38401c293a6e7016032ab0 OK 2025/06/19 04:38:18 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.44s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:38:19 DEBUG : sub dir/hello world: md5 = 032fffb2028f9468365b715854a7fc72 OK 2025/06/19 04:38:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kitacih7coda" 2025/06/19 04:38:19 DEBUG : Config file has changed externally - reloading 2025/06/19 04:38:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kitacih7coda.bin" 2025/06/19 04:38:19 DEBUG : Swift container crypt path rclone-test-kitacih7coda.bin: Auto set use_segments_container to true 2025/06/19 04:38:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kitacih7coda" 2025/06/19 04:38:25 DEBUG : Swift container crypt path rclone-test-kitacih7coda: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda' 2025/06/19 04:38:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:38:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda': Waiting for checks to finish 2025/06/19 04:38:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda': Waiting for transfers to finish 2025/06/19 04:38:36 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:38:36 DEBUG : sub dir/hello world: md5 = fcc89ea8716e40669a58700d51b1805b OK 2025/06/19 04:38:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda': Waiting for checks to finish 2025/06/19 04:38:37 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 04:38:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda': Waiting for transfers to finish 2025/06/19 04:38:42 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:38:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kitacih7coda': Purge remote 2025/06/19 04:38:42 DEBUG : Waiting for deletions to finish 2025/06/19 04:38:43 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (24.57s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:38:43 DEBUG : sub dir/hello world: md5 = 9667f62ac34e3f278c4aa2b7db502c5c OK 2025/06/19 04:38:44 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 04:38:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:38:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:38:44 DEBUG : sub dir/hello world: md5 = a051d66a12c065d98e71b6b43a20510b OK 2025/06/19 04:38:44 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/19 04:38:44 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.37s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:38:45 DEBUG : sub dir/hello world: md5 = 8609b5e946fedcbd9bd019be7e6981c6 OK 2025/06/19 04:38:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qirorob7juzu" 2025/06/19 04:38:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qirorob7juzu.bin" 2025/06/19 04:38:45 DEBUG : Swift container crypt path rclone-test-qirorob7juzu.bin: Auto set use_segments_container to true 2025/06/19 04:38:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qirorob7juzu" 2025/06/19 04:38:51 DEBUG : Swift container crypt path rclone-test-qirorob7juzu: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu' 2025/06/19 04:38:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:38:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for checks to finish 2025/06/19 04:38:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for transfers to finish 2025/06/19 04:39:02 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:39:02 DEBUG : sub dir/hello world: md5 = 02b2cbc1c12588a8d68635c44e59f4b3 OK 2025/06/19 04:39:03 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/19 04:39:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for checks to finish 2025/06/19 04:39:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for transfers to finish 2025/06/19 04:39:08 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:39:08 INFO : sub dir/hello world: Deleted 2025/06/19 04:39:09 DEBUG : sub dir/hello world: md5 = e6721312736208edf33bc01e7480de69 OK 2025/06/19 04:39:09 DEBUG : testing file moves 2025/06/19 04:39:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for checks to finish 2025/06/19 04:39:09 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/19 04:39:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Waiting for transfers to finish 2025/06/19 04:39:14 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/19 04:39:14 INFO : sub dir/hello world: Deleted 2025/06/19 04:39:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qirorob7juzu': Purge remote 2025/06/19 04:39:15 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:15 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (30.79s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:15 DEBUG : sub dir/hello world: md5 = 5aa91195de99c15e859cc12e7b6eb059 OK 2025/06/19 04:39:15 ERROR : error listing: directory not found 2025/06/19 04:39:16 INFO : Local file system at /tmp/rclone2811650836: Making directory 2025/06/19 04:39:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.36s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:17 DEBUG : sub dir/hello world: md5 = 9eda0b98e178f33820d1d7184fe91d8a OK 2025/06/19 04:39:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:39:17 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for checks to finish 2025/06/19 04:39:17 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for transfers to finish 2025/06/19 04:39:17 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/06/19 04:39:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.28s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:18 DEBUG : check sum: Need to transfer - File not found at Destination 2025/06/19 04:39:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:18 DEBUG : check sum: md5 = bc9b675b95381e885dfa264f3436a201 OK 2025/06/19 04:39:18 INFO : check sum: Copied (new) 2025/06/19 04:39:18 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:19 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/06/19 04:39:19 DEBUG : check sum: Size of src and dst objects identical 2025/06/19 04:39:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:19 DEBUG : check sum: Unchanged skipping 2025/06/19 04:39:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:19 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:19 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.32s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:19 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/06/19 04:39:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:25 DEBUG : sizeonly: md5 = 15c62e701139add7ee44126e68403b2d OK 2025/06/19 04:39:25 INFO : sizeonly: Copied (new) 2025/06/19 04:39:25 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:25 DEBUG : sizeonly: Sizes identical 2025/06/19 04:39:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:25 DEBUG : sizeonly: Unchanged skipping 2025/06/19 04:39:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:25 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:25 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (11.05s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:30 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/06/19 04:39:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:36 DEBUG : ignore-size: md5 = 16205fb2d7c06f1608aa9516a211f372 OK 2025/06/19 04:39:36 INFO : ignore-size: Copied (new) 2025/06/19 04:39:36 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:42 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:39:42 DEBUG : ignore-size: Unchanged skipping 2025/06/19 04:39:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:42 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:42 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (22.08s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:39:58 DEBUG : existing: md5 = 64019a4d4d55d146336a9f73eeac6737 OK 2025/06/19 04:39:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:58 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:39:58 DEBUG : existing: Unchanged skipping 2025/06/19 04:39:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:39:58 DEBUG : Waiting for deletions to finish 2025/06/19 04:39:58 INFO : There was nothing to transfer 2025/06/19 04:39:58 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/06/19 04:39:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:39:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:03 DEBUG : existing: md5 = f44e5f0f1a800c72c3714397f9933ec1 OK 2025/06/19 04:40:03 INFO : existing: Copied (replaced existing) 2025/06/19 04:40:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (16.51s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:40:09 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 04:40:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:40:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:14 DEBUG : existing: md5 = 5eaf4498b6f825430f815dc724b80103 OK 2025/06/19 04:40:14 INFO : existing: Copied (new) 2025/06/19 04:40:14 DEBUG : Waiting for deletions to finish 2025/06/19 04:40:15 DEBUG : existing: Destination exists, skipping 2025/06/19 04:40:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:40:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:15 DEBUG : Waiting for deletions to finish 2025/06/19 04:40:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (16.49s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:40:31 DEBUG : b/potato: md5 = 0e83903df6cf228dec6f58dc59412a34 OK 2025/06/19 04:40:36 DEBUG : c/non empty space: md5 = 531f4ee2f136ef0ba36334f369fa7eb1 OK 2025/06/19 04:40:36 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/19 04:40:37 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 04:40:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:40:37 DEBUG : a/potato2: md5 = 019761c259df650160160b51707da40d OK 2025/06/19 04:40:37 INFO : a/potato2: Copied (new) 2025/06/19 04:40:42 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:40:42 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 04:40:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:42 DEBUG : Waiting for deletions to finish 2025/06/19 04:40:47 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (32.23s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:40:58 DEBUG : empty space: md5 = b9a3093c364280f612c795c05644f1db OK 2025/06/19 04:40:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:40:58 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/19 04:40:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:58 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/06/19 04:40:58 DEBUG : Waiting for deletions to finish 2025/06/19 04:40:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:40:59 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/19 04:40:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:40:59 DEBUG : empty space: md5 = d0550c88a7a1d8b081f1c48b9d3de4f2 OK 2025/06/19 04:40:59 INFO : empty space: Copied (replaced existing) 2025/06/19 04:40:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (1.77s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.30s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:41:05 DEBUG : foo: md5 = e8d145583965fe2a41639ed35200f899 OK 2025/06/19 04:41:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:41:10 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/19 04:41:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:41:15 DEBUG : foo: md5 = 3161152e77d6d271c756473d88c1000a OK 2025/06/19 04:41:15 INFO : foo: Copied (replaced existing) 2025/06/19 04:41:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (21.71s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:41:22 DEBUG : empty space: md5 = 10271a001d22a40bccb4bc637eec6806 OK 2025/06/19 04:41:22 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/19 04:41:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:41:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:41:22 DEBUG : empty space: Unchanged skipping 2025/06/19 04:41:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:41:27 DEBUG : potato: md5 = 1ad2906a783ad1c7d56fee88f78d952a OK 2025/06/19 04:41:27 INFO : potato: Copied (new) 2025/06/19 04:41:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (17.33s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:41:44 DEBUG : potato: md5 = 1b366cb214a4ced87420a7619a5cdb79 OK 2025/06/19 04:41:44 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/06/19 04:41:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:41:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:41:49 DEBUG : potato: md5 = 8d7c0f59cd35ece89866637a1b35e781 OK 2025/06/19 04:41:49 INFO : potato: Copied (replaced existing) 2025/06/19 04:41:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (21.35s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:42:05 DEBUG : potato: md5 = 2509ae5cd0b8a22a92c9cd443fd221c7 OK 2025/06/19 04:42:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:42:15 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/19 04:42:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:42:21 DEBUG : potato: md5 = 1214629b1b54752a38ef5d92d158335e OK 2025/06/19 04:42:21 INFO : potato: Copied (replaced existing) 2025/06/19 04:42:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (26.25s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:42:31 DEBUG : potato: md5 = 4a1499be102fe16291da4c767a3a5805 OK 2025/06/19 04:42:32 DEBUG : empty space: md5 = c3f3d742f15e1d59e348399d315dabfe OK 2025/06/19 04:42:32 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:42:32 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/06/19 04:42:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:42:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:42:32 DEBUG : empty space: Unchanged skipping 2025/06/19 04:42:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:42:32 DEBUG : Waiting for deletions to finish 2025/06/19 04:42:32 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (11.55s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:42:43 DEBUG : potato: md5 = 7325a0ba4d3c931a412919312d816d51 OK 2025/06/19 04:42:43 DEBUG : empty space: md5 = de46d9e57daab615c114eabbc406383a OK 2025/06/19 04:42:43 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:42:43 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:42:43 DEBUG : empty space: Unchanged skipping 2025/06/19 04:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:42:44 DEBUG : potato2: md5 = 148edbbe0f7e7fdf75750acfc8a0f790 OK 2025/06/19 04:42:44 INFO : potato2: Copied (new) 2025/06/19 04:42:44 DEBUG : Waiting for deletions to finish 2025/06/19 04:42:49 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (11.75s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:42:55 DEBUG : b/potato: md5 = d2a3174963415f0f7b270553fcd7566f OK 2025/06/19 04:43:00 DEBUG : c/non empty space: md5 = 481c5f51b885fb964f585d52eec29356 OK 2025/06/19 04:43:00 INFO : d: Making directory 2025/06/19 04:43:00 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/06/19 04:43:05 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 04:43:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:43:06 DEBUG : a/potato2: md5 = 223664a85995016d5f61abe9715da722 OK 2025/06/19 04:43:06 INFO : a/potato2: Copied (new) 2025/06/19 04:43:10 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:43:10 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 04:43:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:43:10 DEBUG : Waiting for deletions to finish 2025/06/19 04:43:16 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (36.86s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:43:32 DEBUG : b/potato: md5 = 037233a5ad3a06bd9f0667c40cb2735b OK 2025/06/19 04:43:37 DEBUG : c/non empty space: md5 = 928fa9020bb3ea77db1001be097d6c86 OK 2025/06/19 04:43:37 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/19 04:43:42 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/19 04:43:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:43:43 DEBUG : a/potato2: md5 = 1509f0a4b978374b6e914d231f4afd8e OK 2025/06/19 04:43:43 INFO : a/potato2: Copied (new) 2025/06/19 04:43:47 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:43:47 DEBUG : c/non empty space: Unchanged skipping 2025/06/19 04:43:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:43:47 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': not deleting files as there were IO errors 2025/06/19 04:43:52 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (47.13s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:44:19 DEBUG : potato: md5 = c2bb4ffb2800344b6fa25e1d794855d2 OK 2025/06/19 04:44:19 DEBUG : empty space: md5 = 07c92b7a17bef8aff02ac9cafc1dfc47 OK 2025/06/19 04:44:19 DEBUG : Waiting for deletions to finish 2025/06/19 04:44:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:44:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:44:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:44:20 DEBUG : empty space: Unchanged skipping 2025/06/19 04:44:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:44:20 DEBUG : potato2: md5 = 5c3a5ae3099104666f20e84dacb8d677 OK 2025/06/19 04:44:20 INFO : potato2: Copied (new) 2025/06/19 04:44:25 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (13.10s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:44:32 DEBUG : potato: md5 = 63aa34ee73a1fa2fc7b47483d2ae9d07 OK 2025/06/19 04:44:32 DEBUG : empty space: md5 = 59e909aa07d963718101bec3717e9211 OK 2025/06/19 04:44:32 DEBUG : Waiting for deletions to finish 2025/06/19 04:44:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:44:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:44:37 INFO : potato: Deleted 2025/06/19 04:44:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:44:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:44:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:44:38 DEBUG : empty space: Unchanged skipping 2025/06/19 04:44:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:44:38 DEBUG : potato2: md5 = 0165da3c8a87d244a844f6fc46e32ed8 OK 2025/06/19 04:44:38 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (11.93s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:44:47 DEBUG : potato: md5 = 09a43035dfb2e5944f221dec87e2e630 OK 2025/06/19 04:44:53 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:44:53 DEBUG : potato2: md5 = 36ff5289ad07aa9ff4401c07e6db7ae8 OK 2025/06/19 04:44:53 INFO : potato2: Copied (new) 2025/06/19 04:44:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:44:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish --- PASS: TestCopyDeleteBefore (30.57s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:45:09 DEBUG : potato2: md5 = 3688cc0bd584ea0cccf351a4da88f0a4 OK 2025/06/19 04:45:10 DEBUG : empty space: md5 = ccbac8cb30713f707be858b1d6ec59d3 OK 2025/06/19 04:45:10 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : enormous: Excluded 2025/06/19 04:45:10 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : potato2: Excluded 2025/06/19 04:45:10 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : potato2: Excluded 2025/06/19 04:45:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:45:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:45:10 DEBUG : empty space: Unchanged skipping 2025/06/19 04:45:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:45:10 DEBUG : Waiting for deletions to finish 2025/06/19 04:45:10 INFO : There was nothing to transfer 2025/06/19 04:45:10 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : enormous: Excluded 2025/06/19 04:45:10 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : potato2: Excluded 2025/06/19 04:45:10 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 04:45:10 DEBUG : potato2: Excluded 2025/06/19 04:45:10 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for checks to finish 2025/06/19 04:45:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:45:10 DEBUG : empty space: Unchanged skipping 2025/06/19 04:45:10 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for transfers to finish 2025/06/19 04:45:10 DEBUG : Waiting for deletions to finish 2025/06/19 04:45:10 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (1.90s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:45:11 DEBUG : potato2: md5 = d225c4804a82517989f9e643db82dea1 OK 2025/06/19 04:45:12 DEBUG : empty space: md5 = a5e4e68160bfa01d44eee782a29270aa OK 2025/06/19 04:45:17 DEBUG : enormous: md5 = 95f8bd4b00d708447c40d6f89b0d9257 OK 2025/06/19 04:45:17 DEBUG : enormous: Excluded (Size Filter) 2025/06/19 04:45:17 DEBUG : enormous: Excluded 2025/06/19 04:45:17 DEBUG : potato2: Excluded (Size Filter) 2025/06/19 04:45:17 DEBUG : potato2: Excluded 2025/06/19 04:45:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:45:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:45:17 DEBUG : empty space: Unchanged skipping 2025/06/19 04:45:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:45:17 DEBUG : Waiting for deletions to finish 2025/06/19 04:45:19 INFO : potato2: Deleted 2025/06/19 04:45:22 INFO : enormous: Deleted 2025/06/19 04:45:22 INFO : There was nothing to transfer 2025/06/19 04:45:23 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for checks to finish 2025/06/19 04:45:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:45:23 DEBUG : empty space: Unchanged skipping 2025/06/19 04:45:23 DEBUG : Local file system at /tmp/rclone2811650836: Waiting for transfers to finish 2025/06/19 04:45:23 DEBUG : Waiting for deletions to finish 2025/06/19 04:45:23 INFO : enormous: Deleted 2025/06/19 04:45:23 INFO : potato2: Deleted 2025/06/19 04:45:23 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (12.02s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:45:24 DEBUG : one: md5 = a2d67062db07d96d86f088e652a46d6f OK 2025/06/19 04:45:29 DEBUG : two: md5 = 5d1a283a4af19d2e0c4555c1b6926f3b OK 2025/06/19 04:45:30 DEBUG : three: md5 = 35d1962f289c4094da39f5493928e0d9 OK 2025/06/19 04:45:36 DEBUG : four: md5 = eadef9b2a8fdf09d5f879aae00dbd805 OK 2025/06/19 04:45:36 DEBUG : five: Need to transfer - File not found at Destination 2025/06/19 04:45:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:45:36 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/06/19 04:45:36 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/06/19 04:45:36 DEBUG : three: Sizes identical 2025/06/19 04:45:36 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/06/19 04:45:36 DEBUG : one: Destination is newer than source, skipping 2025/06/19 04:45:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:45:41 DEBUG : five: md5 = 02f01ab8994523ec9c8954d4b4d6e343 OK 2025/06/19 04:45:41 INFO : five: Copied (new) 2025/06/19 04:45:41 DEBUG : two: md5 = 19fd710e324090129f92251a7963d8bf OK 2025/06/19 04:45:41 INFO : two: Copied (replaced existing) 2025/06/19 04:45:42 DEBUG : four: md5 = 681d53b015f983213444ef26a7f232c0 OK 2025/06/19 04:45:42 INFO : four: Copied (replaced existing) 2025/06/19 04:45:42 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (45.16s) === 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-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/06/19 04:46:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/19 04:46:08 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/19 04:46:08 DEBUG : yam: Need to transfer - File not found at Destination 2025/06/19 04:46:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:46:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:46:14 DEBUG : yam: md5 = ef5a7ce2749e73a1b5ec840fe466d832 OK 2025/06/19 04:46:14 INFO : yam: Copied (new) 2025/06/19 04:46:14 DEBUG : potato: md5 = 245456107055ef601622b42f5763cf76 OK 2025/06/19 04:46:14 INFO : potato: Copied (new) 2025/06/19 04:46:14 DEBUG : Waiting for deletions to finish 2025/06/19 04:46:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/19 04:46:19 DEBUG : yaml: Need to transfer - File not found at Destination 2025/06/19 04:46:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:46:19 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:46:19 DEBUG : potato: Unchanged skipping 2025/06/19 04:46:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:46:24 DEBUG : yaml: md5 = 4ca303fd96e1f2a47b2e404cc15b6c9c OK 2025/06/19 04:46:24 INFO : yaml: Copied (new) 2025/06/19 04:46:24 DEBUG : Waiting for deletions to finish 2025/06/19 04:46:34 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (42.04s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/06/19 04:46:50 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Making map for --track-renames 2025/06/19 04:46:50 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Finished making map for --track-renames 2025/06/19 04:46:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:46:50 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 04:46:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for renames to finish 2025/06/19 04:46:50 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/19 04:46:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:46:55 DEBUG : yam: md5 = 32db8654698acb1167dbb872c36b00b4 OK 2025/06/19 04:46:55 INFO : yam: Copied (new) 2025/06/19 04:46:56 DEBUG : potato: md5 = 9702cfb46afc8998253c982094f1674f OK 2025/06/19 04:46:56 INFO : potato: Copied (new) 2025/06/19 04:46:56 DEBUG : Waiting for deletions to finish 2025/06/19 04:47:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Making map for --track-renames 2025/06/19 04:47:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Finished making map for --track-renames 2025/06/19 04:47:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:47:01 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:47:01 DEBUG : potato: Unchanged skipping 2025/06/19 04:47:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for renames to finish 2025/06/19 04:47:26 INFO : yam: Copied (server-side copy) to: yaml 2025/06/19 04:47:32 INFO : yam: Deleted 2025/06/19 04:47:32 INFO : yaml: Renamed from "yam" 2025/06/19 04:47:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:47:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (67.17s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/06/19 04:47:58 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Making map for --track-renames 2025/06/19 04:47:58 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Finished making map for --track-renames 2025/06/19 04:47:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:47:58 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/19 04:47:58 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/19 04:47:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for renames to finish 2025/06/19 04:47:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:03 DEBUG : potato: md5 = 9af968d2a846fd7583015a88e598da8c OK 2025/06/19 04:48:03 INFO : potato: Copied (new) 2025/06/19 04:48:03 DEBUG : sub/yam: md5 = 2c67b568822569e3c84d1015231504c5 OK 2025/06/19 04:48:03 INFO : sub/yam: Copied (new) 2025/06/19 04:48:03 DEBUG : Waiting for deletions to finish 2025/06/19 04:48:04 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Making map for --track-renames 2025/06/19 04:48:04 INFO : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Finished making map for --track-renames 2025/06/19 04:48:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:48:09 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:48:09 DEBUG : potato: Unchanged skipping 2025/06/19 04:48:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for renames to finish 2025/06/19 04:48:19 INFO : sub/yam: Copied (server-side copy) to: yam 2025/06/19 04:48:24 INFO : sub/yam: Deleted 2025/06/19 04:48:24 INFO : yam: Renamed from "sub/yam" 2025/06/19 04:48:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (47.74s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:48:45 DEBUG : Creating backend with remote "/tmp/rclone2811650836/dir1" 2025/06/19 04:48:45 DEBUG : Config file has changed externally - reloading 2025/06/19 04:48:45 DEBUG : Creating backend with remote "/tmp/rclone2811650836/dir2" 2025/06/19 04:48:45 DEBUG : Local file system at /tmp/rclone2811650836/dir2: Using server-side directory move 2025/06/19 04:48:45 INFO : Local file system at /tmp/rclone2811650836/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/19 04:48:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/06/19 04:48:45 DEBUG : Local file system at /tmp/rclone2811650836/dir2: Waiting for checks to finish 2025/06/19 04:48:45 DEBUG : Local file system at /tmp/rclone2811650836/dir2: Waiting for transfers to finish 2025/06/19 04:48:45 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.20s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:48:46 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:48:46 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/19 04:48:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:48:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:46 DEBUG : sub dir/hello world: md5 = edf8d12195a1d2a97c7d39c671e4630e OK 2025/06/19 04:48:46 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:48:46 INFO : sub dir/hello world: Deleted 2025/06/19 04:48:46 DEBUG : nested/sub dir/file: md5 = 83de37c1fcd8e4d7305e384edef0361e OK 2025/06/19 04:48:46 INFO : nested/sub dir/file: Copied (new) 2025/06/19 04:48:46 INFO : nested/sub dir/file: Deleted 2025/06/19 04:48:46 INFO : sub dir: Removing directory 2025/06/19 04:48:46 INFO : nested/sub dir: Removing directory 2025/06/19 04:48:46 INFO : nested: Removing directory 2025/06/19 04:48:46 DEBUG : Local file system at /tmp/rclone2811650836: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (1.34s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:48:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/19 04:48:47 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/19 04:48:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:48:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:47 DEBUG : nested/sub dir/file: md5 = 8a8e22313a65d56d4015c97b2a916d6a OK 2025/06/19 04:48:47 INFO : nested/sub dir/file: Copied (new) 2025/06/19 04:48:47 INFO : nested/sub dir/file: Deleted 2025/06/19 04:48:47 DEBUG : sub dir/hello world: md5 = f2d518aac73316cc9c09c78d9e5035a0 OK 2025/06/19 04:48:47 INFO : sub dir/hello world: Copied (new) 2025/06/19 04:48:47 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.29s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:48:48 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/06/19 04:48:48 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 04:48:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:48:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:48 DEBUG : existing-b: md5 = b57716c1996b840b2bcfebb6630960bc OK 2025/06/19 04:48:48 INFO : existing-b: Copied (new) 2025/06/19 04:48:48 INFO : existing-b: Deleted 2025/06/19 04:48:53 DEBUG : existing: md5 = 90bb43b9954fc762ed1241212c60a911 OK 2025/06/19 04:48:53 INFO : existing: Copied (new) 2025/06/19 04:48:53 INFO : existing: Deleted 2025/06/19 04:48:54 DEBUG : existing: Destination exists, skipping 2025/06/19 04:48:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:48:54 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/06/19 04:48:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:48:54 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (16.28s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:49:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cogivoh2naca" 2025/06/19 04:49:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cogivoh2naca.bin" 2025/06/19 04:49:05 DEBUG : Swift container crypt path rclone-test-cogivoh2naca.bin: Auto set use_segments_container to true 2025/06/19 04:49:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cogivoh2naca" 2025/06/19 04:49:12 DEBUG : Swift container crypt path rclone-test-cogivoh2naca: Auto set use_segments_container to true 2025/06/19 04:49:17 DEBUG : potato2: md5 = cdbb6793b3dd7ad876598798ce74b255 OK 2025/06/19 04:49:17 DEBUG : empty space: md5 = 5fdc3e37db9431429555def6be800629 OK 2025/06/19 04:49:23 DEBUG : potato3: md5 = b200233dd786e0114c4215a1952d558b OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-cogivoh2naca' 2025/06/19 04:49:24 DEBUG : empty space: md5 = acdd24e0341387a0efb12273a7b1786d OK 2025/06/19 04:49:29 DEBUG : potato3: md5 = 5e31f812014e619797a4fe1f87528503 OK 2025/06/19 04:49:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:49:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 04:49:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogivoh2naca': Waiting for checks to finish 2025/06/19 04:49:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:49:30 DEBUG : empty space: Unchanged skipping 2025/06/19 04:49:30 INFO : empty space: Deleted 2025/06/19 04:49:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogivoh2naca': Waiting for transfers to finish 2025/06/19 04:49:40 INFO : potato3: Copied (server-side copy) 2025/06/19 04:49:40 INFO : potato2: Copied (server-side copy) 2025/06/19 04:49:40 INFO : potato2: Deleted 2025/06/19 04:49:45 INFO : potato3: Deleted 2025/06/19 04:49:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vayegaj3zuwi" 2025/06/19 04:49:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vayegaj3zuwi.bin" 2025/06/19 04:49:51 DEBUG : Swift container crypt path rclone-test-vayegaj3zuwi.bin: Auto set use_segments_container to true 2025/06/19 04:49:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vayegaj3zuwi" 2025/06/19 04:49:57 DEBUG : Swift container crypt path rclone-test-vayegaj3zuwi: Auto set use_segments_container to true 2025/06/19 04:50:02 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/19 04:50:02 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:50:02 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 04:50:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayegaj3zuwi': Waiting for checks to finish 2025/06/19 04:50:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayegaj3zuwi': Waiting for transfers to finish 2025/06/19 04:50:08 INFO : potato3: Copied (server-side copy) 2025/06/19 04:50:12 INFO : empty space: Copied (server-side copy) 2025/06/19 04:50:13 INFO : empty space: Deleted 2025/06/19 04:50:13 INFO : potato3: Deleted 2025/06/19 04:50:18 INFO : potato2: Copied (server-side copy) 2025/06/19 04:50:23 INFO : potato2: Deleted 2025/06/19 04:50:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayegaj3zuwi': Purge remote 2025/06/19 04:50:23 DEBUG : Waiting for deletions to finish 2025/06/19 04:50:23 INFO : potato3.bin: Deleted 2025/06/19 04:50:33 INFO : empty space.bin: Deleted 2025/06/19 04:50:33 INFO : potato2.bin: Deleted 2025/06/19 04:50:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cogivoh2naca': Purge remote 2025/06/19 04:50:33 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (89.25s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:50:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jocoheg3tojo" 2025/06/19 04:50:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jocoheg3tojo.bin" 2025/06/19 04:50:34 DEBUG : Swift container crypt path rclone-test-jocoheg3tojo.bin: Auto set use_segments_container to true 2025/06/19 04:50:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jocoheg3tojo" 2025/06/19 04:50:40 DEBUG : Swift container crypt path rclone-test-jocoheg3tojo: Auto set use_segments_container to true 2025/06/19 04:50:45 DEBUG : potato2: md5 = c5899d8ec6f5f72144a6e12d9a91ab6e OK 2025/06/19 04:50:45 DEBUG : empty space: md5 = bf9d4064c0f45dd3083bcd518131edbc OK 2025/06/19 04:50:50 DEBUG : potato3: md5 = d8f2ab0ab850fc47296b37fc5619f778 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-jocoheg3tojo' 2025/06/19 04:50:57 DEBUG : empty space: md5 = e703f09f1787165aee56c38f0cbbc1c2 OK 2025/06/19 04:50:57 DEBUG : potato3: md5 = fe19927c1d1c6e7844f8d56e62b3a4ae OK 2025/06/19 04:50:57 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 04:50:57 DEBUG : empty space: Excluded 2025/06/19 04:50:57 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 04:50:57 DEBUG : empty space: Excluded 2025/06/19 04:50:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:50:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 04:50:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jocoheg3tojo': Waiting for checks to finish 2025/06/19 04:50:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jocoheg3tojo': Waiting for transfers to finish 2025/06/19 04:51:03 INFO : potato2: Copied (server-side copy) 2025/06/19 04:51:03 INFO : potato3: Copied (server-side copy) 2025/06/19 04:51:03 INFO : potato2: Deleted 2025/06/19 04:51:08 INFO : potato3: Deleted 2025/06/19 04:51:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-meziziw7yera" 2025/06/19 04:51:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-meziziw7yera.bin" 2025/06/19 04:51:09 DEBUG : Swift container crypt path rclone-test-meziziw7yera.bin: Auto set use_segments_container to true 2025/06/19 04:51:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-meziziw7yera" 2025/06/19 04:51:14 DEBUG : Swift container crypt path rclone-test-meziziw7yera: Auto set use_segments_container to true 2025/06/19 04:51:20 DEBUG : empty space: Excluded (Size Filter) 2025/06/19 04:51:20 DEBUG : empty space: Excluded 2025/06/19 04:51:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:51:20 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 04:51:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-meziziw7yera': Waiting for checks to finish 2025/06/19 04:51:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-meziziw7yera': Waiting for transfers to finish 2025/06/19 04:51:26 INFO : potato3: Copied (server-side copy) 2025/06/19 04:51:26 INFO : potato3: Deleted 2025/06/19 04:51:30 INFO : potato2: Copied (server-side copy) 2025/06/19 04:51:30 INFO : potato2: Deleted 2025/06/19 04:51:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-meziziw7yera': Purge remote 2025/06/19 04:51:36 DEBUG : Waiting for deletions to finish 2025/06/19 04:51:36 INFO : potato3.bin: Deleted 2025/06/19 04:51:41 INFO : potato2.bin: Deleted 2025/06/19 04:51:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jocoheg3tojo': Purge remote 2025/06/19 04:51:41 DEBUG : Waiting for deletions to finish 2025/06/19 04:51:46 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (73.15s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:51:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sovohar0mewu" 2025/06/19 04:51:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sovohar0mewu.bin" 2025/06/19 04:51:47 DEBUG : Swift container crypt path rclone-test-sovohar0mewu.bin: Auto set use_segments_container to true 2025/06/19 04:51:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sovohar0mewu" 2025/06/19 04:51:53 DEBUG : Swift container crypt path rclone-test-sovohar0mewu: Auto set use_segments_container to true 2025/06/19 04:51:58 DEBUG : potato2: md5 = 777f29548366b0600ddd7669952dd93d OK 2025/06/19 04:51:58 DEBUG : empty space: md5 = 9d13aadb58a2229ffa371182d10ef3e5 OK 2025/06/19 04:52:04 DEBUG : potato3: md5 = 07861300cf7a6c97845b33817ead9789 OK 2025/06/19 04:52:04 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa' -> Encrypted drive 'TestCryptSwift:rclone-test-sovohar0mewu' 2025/06/19 04:52:14 DEBUG : empty space: md5 = d7dfccd30839c5450f17d1fd5af14171 OK 2025/06/19 04:52:15 DEBUG : potato3: md5 = 17b69c41ec4853d397e9503cf5a96c58 OK 2025/06/19 04:52:15 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:52:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/19 04:52:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sovohar0mewu': Waiting for checks to finish 2025/06/19 04:52:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:52:15 DEBUG : empty space: Unchanged skipping 2025/06/19 04:52:15 INFO : empty space: Deleted 2025/06/19 04:52:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sovohar0mewu': Waiting for transfers to finish 2025/06/19 04:52:26 INFO : potato2: Copied (server-side copy) 2025/06/19 04:52:26 INFO : potato3: Copied (server-side copy) 2025/06/19 04:52:26 INFO : potato2: Deleted 2025/06/19 04:52:31 INFO : potato3: Deleted 2025/06/19 04:52:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tobewak9rayi" 2025/06/19 04:52:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tobewak9rayi.bin" 2025/06/19 04:52:37 DEBUG : Swift container crypt path rclone-test-tobewak9rayi.bin: Auto set use_segments_container to true 2025/06/19 04:52:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tobewak9rayi" 2025/06/19 04:52:42 DEBUG : Swift container crypt path rclone-test-tobewak9rayi: Auto set use_segments_container to true 2025/06/19 04:52:48 INFO : tomatoDir: Making directory 2025/06/19 04:52:48 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/19 04:52:48 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/19 04:52:48 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/19 04:52:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tobewak9rayi': Waiting for checks to finish 2025/06/19 04:52:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tobewak9rayi': Waiting for transfers to finish 2025/06/19 04:52:54 INFO : potato3: Copied (server-side copy) 2025/06/19 04:52:54 INFO : potato3: Deleted 2025/06/19 04:52:54 INFO : potato2: Copied (server-side copy) 2025/06/19 04:52:59 INFO : potato2: Deleted 2025/06/19 04:53:04 INFO : empty space: Copied (server-side copy) 2025/06/19 04:53:09 INFO : empty space: Deleted 2025/06/19 04:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tobewak9rayi': Purge remote 2025/06/19 04:53:10 DEBUG : Waiting for deletions to finish 2025/06/19 04:53:10 INFO : potato2.bin: Deleted 2025/06/19 04:53:10 INFO : potato3.bin: Deleted 2025/06/19 04:53:15 INFO : empty space.bin: Deleted 2025/06/19 04:53:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sovohar0mewu': Purge remote 2025/06/19 04:53:15 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (88.58s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:53:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/rclone-move-test" 2025/06/19 04:53:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-move-test.bin" 2025/06/19 04:53:16 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-move-test.bin: Auto set use_segments_container to true 2025/06/19 04:53:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-move-test" 2025/06/19 04:53:21 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-move-test: Auto set use_segments_container to true 2025/06/19 04:53:27 DEBUG : potato2: md5 = 4b08a695fd037e7f57e7b25e7a380fcd OK --- PASS: TestServerSideMoveOverlap (12.11s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:53:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test" 2025/06/19 04:53:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test.bin" 2025/06/19 04:53:28 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/19 04:53:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test" 2025/06/19 04:53:34 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.47s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:53:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test" 2025/06/19 04:53:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test.bin" 2025/06/19 04:53:39 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/19 04:53:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test" 2025/06/19 04:53:44 DEBUG : Config file has changed externally - reloading 2025/06/19 04:53:45 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test: Auto set use_segments_container to true 2025/06/19 04:53:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2" 2025/06/19 04:53:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2.bin" 2025/06/19 04:53:51 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/06/19 04:53:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2" 2025/06/19 04:53:56 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/06/19 04:54:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file" 2025/06/19 04:54:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file.bin" 2025/06/19 04:54:02 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/06/19 04:54:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file" 2025/06/19 04:54:08 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/06/19 04:54:19 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = f9f28c65d31cb0fbe4e0ceb030d44daf OK 2025/06/19 04:54:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test': Waiting for checks to finish 2025/06/19 04:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test': Waiting for transfers to finish 2025/06/19 04:54:24 DEBUG : Waiting for deletions to finish 2025/06/19 04:54:24 INFO : There was nothing to transfer 2025/06/19 04:54:29 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:54:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:54:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:54:29 DEBUG : Waiting for deletions to finish 2025/06/19 04:54:29 INFO : There was nothing to transfer 2025/06/19 04:54:40 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:54:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/19 04:54:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/19 04:54:40 DEBUG : Waiting for deletions to finish 2025/06/19 04:54:40 INFO : There was nothing to transfer 2025/06/19 04:54:50 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:54:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 04:54:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 04:54:50 DEBUG : Waiting for deletions to finish 2025/06/19 04:54:50 INFO : There was nothing to transfer 2025/06/19 04:54:55 DEBUG : Excluded 2025/06/19 04:54:55 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/19 04:54:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/19 04:54:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/19 04:54:55 DEBUG : Waiting for deletions to finish 2025/06/19 04:54:55 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (82.14s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:55:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 04:55:01 DEBUG : Config file has changed externally - reloading 2025/06/19 04:55:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 04:55:01 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 04:55:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 04:55:07 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 04:55:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/CompareDest" 2025/06/19 04:55:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/CompareDest.bin" 2025/06/19 04:55:13 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/CompareDest.bin: Auto set use_segments_container to true 2025/06/19 04:55:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/CompareDest" 2025/06/19 04:55:18 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/CompareDest: Auto set use_segments_container to true 2025/06/19 04:55:34 DEBUG : one: Need to transfer - File not found at Destination 2025/06/19 04:55:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:55:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:55:40 DEBUG : one: md5 = e3aa7c838917c984388a952d5064f1ae OK 2025/06/19 04:55:40 INFO : one: Copied (new) 2025/06/19 04:55:40 DEBUG : Waiting for deletions to finish 2025/06/19 04:55:45 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:55:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:55:55 DEBUG : one: md5 = 476ad3ddfc3a02805953e250f9eee10a OK 2025/06/19 04:55:55 INFO : one: Copied (replaced existing) 2025/06/19 04:55:55 DEBUG : Waiting for deletions to finish 2025/06/19 04:56:01 DEBUG : dst/one: md5 = 11b3d8425673c04be364818ab7ad8893 OK 2025/06/19 04:56:01 DEBUG : CompareDest/one: md5 = 7ffa5b0eafbeb5aca76df4a7f5159852 OK 2025/06/19 04:56:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:56:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:56:07 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:07 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:56:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:56:07 DEBUG : Waiting for deletions to finish 2025/06/19 04:56:07 INFO : There was nothing to transfer 2025/06/19 04:56:12 DEBUG : CompareDest/two: md5 = 7a60f8648fa58ebe79cd5d667fc4be43 OK 2025/06/19 04:56:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:56:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:18 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:56:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/19 04:56:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:56:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:56:22 DEBUG : Waiting for deletions to finish 2025/06/19 04:56:22 INFO : There was nothing to transfer 2025/06/19 04:56:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:56:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:23 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:56:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/19 04:56:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:56:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:56:23 DEBUG : Waiting for deletions to finish 2025/06/19 04:56:23 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/06/19 04:56:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:56:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:56:34 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/19 04:56:39 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/06/19 04:56:39 DEBUG : two: Need to transfer - File not found at Destination 2025/06/19 04:56:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:56:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:56:44 DEBUG : two: md5 = 31d2c1fc6a6898529700511543f234fe OK 2025/06/19 04:56:44 INFO : two: Copied (new) 2025/06/19 04:56:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (128.81s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:57:15 DEBUG : pre-dest1/1: md5 = e3449e67ba198374370e93fa13a91b34 OK 2025/06/19 04:57:20 DEBUG : pre-dest2/2: md5 = 708524c4758d51dea9b69beb216dddda OK 2025/06/19 04:57:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dest" 2025/06/19 04:57:26 DEBUG : Config file has changed externally - reloading 2025/06/19 04:57:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dest.bin" 2025/06/19 04:57:26 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dest.bin: Auto set use_segments_container to true 2025/06/19 04:57:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dest" 2025/06/19 04:57:32 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dest: Auto set use_segments_container to true 2025/06/19 04:57:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/pre-dest1" 2025/06/19 04:57:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/pre-dest1.bin" 2025/06/19 04:57:37 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/pre-dest1.bin: Auto set use_segments_container to true 2025/06/19 04:57:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/pre-dest1" 2025/06/19 04:57:43 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/pre-dest1: Auto set use_segments_container to true 2025/06/19 04:57:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/pre-dest2" 2025/06/19 04:57:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/pre-dest2.bin" 2025/06/19 04:57:49 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/pre-dest2.bin: Auto set use_segments_container to true 2025/06/19 04:57:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/pre-dest2" 2025/06/19 04:57:54 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/pre-dest2: Auto set use_segments_container to true 2025/06/19 04:58:00 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:58:00 DEBUG : 1: Destination found in --compare-dest, skipping 2025/06/19 04:58:10 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 04:58:10 DEBUG : 2: Destination found in --compare-dest, skipping 2025/06/19 04:58:21 DEBUG : 3: Need to transfer - File not found at Destination 2025/06/19 04:58:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dest': Waiting for checks to finish 2025/06/19 04:58:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dest': Waiting for transfers to finish 2025/06/19 04:58:21 DEBUG : 3: md5 = 7e7231f97bfbeff01ead8cc14b6ef00b OK 2025/06/19 04:58:21 INFO : 3: Copied (new) 2025/06/19 04:58:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (92.74s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 04:58:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 04:58:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 04:58:43 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 04:58:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 04:58:48 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 04:58:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/CopyDest" 2025/06/19 04:58:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/CopyDest.bin" 2025/06/19 04:58:54 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/CopyDest.bin: Auto set use_segments_container to true 2025/06/19 04:58:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/CopyDest" 2025/06/19 04:59:00 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/CopyDest: Auto set use_segments_container to true 2025/06/19 04:59:11 DEBUG : one: Need to transfer - File not found at Destination 2025/06/19 04:59:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:59:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:59:16 DEBUG : one: md5 = 8d50666fc08e48c4829df5bf3dab1aee OK 2025/06/19 04:59:16 INFO : one: Copied (new) 2025/06/19 04:59:16 DEBUG : Waiting for deletions to finish 2025/06/19 04:59:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 04:59:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 04:59:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 04:59:32 DEBUG : one: md5 = 81f901fdc4349c9873985e4df14d343f OK 2025/06/19 04:59:32 INFO : one: Copied (replaced existing) 2025/06/19 04:59:32 DEBUG : Waiting for deletions to finish 2025/06/19 04:59:42 DEBUG : dst/one: md5 = b9ca241d51a3f1c8b460b56e6bfd7e94 OK 2025/06/19 04:59:47 DEBUG : CopyDest/one: md5 = fbb72a7dde96e4293e9fffbba4690147 OK 2025/06/19 04:59:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/BackupDir" 2025/06/19 04:59:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/BackupDir.bin" 2025/06/19 04:59:53 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/BackupDir.bin: Auto set use_segments_container to true 2025/06/19 04:59:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/BackupDir" 2025/06/19 04:59:59 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/BackupDir: Auto set use_segments_container to true 2025/06/19 05:00:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 05:00:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:00:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:00:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/19 05:00:25 INFO : one: Copied (server-side copy) 2025/06/19 05:00:30 INFO : one: Deleted 2025/06/19 05:00:46 INFO : one: Copied (server-side copy) 2025/06/19 05:00:46 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/06/19 05:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:00:46 DEBUG : Waiting for deletions to finish 2025/06/19 05:00:51 DEBUG : CopyDest/two: md5 = cf52666f2e02341c68ff4d925f72732b OK 2025/06/19 05:00:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:02 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:02 DEBUG : one: Unchanged skipping 2025/06/19 05:01:17 INFO : two: Copied (server-side copy) 2025/06/19 05:01:17 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/06/19 05:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:01:17 DEBUG : Waiting for deletions to finish 2025/06/19 05:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:01:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:28 DEBUG : one: Unchanged skipping 2025/06/19 05:01:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:28 DEBUG : two: Unchanged skipping 2025/06/19 05:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:01:28 DEBUG : Waiting for deletions to finish 2025/06/19 05:01:28 INFO : There was nothing to transfer 2025/06/19 05:01:38 DEBUG : CopyDest/three: md5 = f42f10c1bce9fe05a4a65f6894655571 OK 2025/06/19 05:01:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:44 DEBUG : one: Unchanged skipping 2025/06/19 05:01:44 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/06/19 05:01:44 DEBUG : three: Destination not found in --copy-dest 2025/06/19 05:01:44 DEBUG : three: Need to transfer - File not found at Destination 2025/06/19 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:01:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:01:44 DEBUG : two: Unchanged skipping 2025/06/19 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:01:45 DEBUG : three: md5 = 239478c767067c16239cdde9c0abd016 OK 2025/06/19 05:01:45 INFO : three: Copied (new) 2025/06/19 05:01:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (223.87s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:02:32 DEBUG : dst/one: md5 = 303e7c48fccda685d66848dd1a7870d5 OK 2025/06/19 05:02:37 DEBUG : dst/two: md5 = 514238bb880fa3ec14bfe7da3fba21f5 OK 2025/06/19 05:02:42 DEBUG : dst/three.txt: md5 = 7913a2013ef4e81d9727587c8c94fa3e OK 2025/06/19 05:02:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:02:47 DEBUG : Config file has changed externally - reloading 2025/06/19 05:02:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:02:48 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:02:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:02:53 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:02:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/backup" 2025/06/19 05:02:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup.bin" 2025/06/19 05:02:59 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup.bin: Auto set use_segments_container to true 2025/06/19 05:03:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup" 2025/06/19 05:03:04 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup: Auto set use_segments_container to true 2025/06/19 05:03:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:03:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:03:15 DEBUG : two: Unchanged skipping 2025/06/19 05:03:26 INFO : one: Copied (server-side copy) 2025/06/19 05:03:31 INFO : one: Deleted 2025/06/19 05:03:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:03:36 DEBUG : one: md5 = a1f23b88a219b7eac04142a1cb19e56a OK 2025/06/19 05:03:36 INFO : one: Copied (new) 2025/06/19 05:03:36 DEBUG : Waiting for deletions to finish 2025/06/19 05:03:57 INFO : three.txt: Copied (server-side copy) 2025/06/19 05:04:02 INFO : three.txt: Deleted 2025/06/19 05:04:02 INFO : three.txt: Moved into backup dir 2025/06/19 05:04:17 DEBUG : dst/three.txt: md5 = acafb91616ebbd0a373bf6b94dae5591 OK 2025/06/19 05:04:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:04:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:04:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:04:33 DEBUG : two: Unchanged skipping 2025/06/19 05:04:33 INFO : one: Copied (server-side copy) 2025/06/19 05:04:38 INFO : one: Deleted 2025/06/19 05:04:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:04:44 DEBUG : one: md5 = d20c2b9b027351d047822a8bf10ee285 OK 2025/06/19 05:04:44 INFO : one: Copied (new) 2025/06/19 05:04:44 DEBUG : Waiting for deletions to finish 2025/06/19 05:04:59 INFO : three.txt: Copied (server-side copy) 2025/06/19 05:05:04 INFO : three.txt: Deleted 2025/06/19 05:05:04 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (183.96s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:05:36 DEBUG : dst/one: md5 = 8ec478a8a8000d49f0cf95c6f036942b OK 2025/06/19 05:05:41 DEBUG : dst/two: md5 = d9e96d3418673e84d4965498855fc06d OK 2025/06/19 05:05:46 DEBUG : dst/three.txt: md5 = 249c412240c01d814716935627f2789e OK 2025/06/19 05:05:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:05:46 DEBUG : Config file has changed externally - reloading 2025/06/19 05:05:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:05:46 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:05:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:05:52 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:05:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/backup" 2025/06/19 05:05:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup.bin" 2025/06/19 05:05:58 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup.bin: Auto set use_segments_container to true 2025/06/19 05:06:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup" 2025/06/19 05:06:03 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup: Auto set use_segments_container to true 2025/06/19 05:06:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:06:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:06:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:06:17 DEBUG : two: Unchanged skipping 2025/06/19 05:06:37 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:06:42 INFO : one: Deleted 2025/06/19 05:06:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:06:48 DEBUG : one: md5 = e70800b8c25c76da3f7eb43635cf1137 OK 2025/06/19 05:06:48 INFO : one: Copied (new) 2025/06/19 05:06:48 DEBUG : Waiting for deletions to finish 2025/06/19 05:07:03 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:07:08 INFO : three.txt: Deleted 2025/06/19 05:07:08 INFO : three.txt: Moved into backup dir 2025/06/19 05:07:14 DEBUG : dst/three.txt: md5 = a8c5bbe036b8b0202126cdf6d8a29636 OK 2025/06/19 05:07:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:07:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:07:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:07:24 DEBUG : two: Unchanged skipping 2025/06/19 05:07:40 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:07:45 INFO : one: Deleted 2025/06/19 05:07:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:07:50 DEBUG : one: md5 = ea2678fbb19441bb69381d88c710a82c OK 2025/06/19 05:07:50 INFO : one: Copied (new) 2025/06/19 05:07:50 DEBUG : Waiting for deletions to finish 2025/06/19 05:07:55 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:08:00 INFO : three.txt: Deleted 2025/06/19 05:08:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (171.21s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:08:27 DEBUG : dst/one: md5 = bc7b12899c2760c2fbcdd3a7bebf5b38 OK 2025/06/19 05:08:32 DEBUG : dst/two: md5 = ac2db0ff0c6b0b740165a80d831550ce OK 2025/06/19 05:08:37 DEBUG : dst/three.txt: md5 = 375580179aa4231dde067c3532151ca5 OK 2025/06/19 05:08:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:08:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:08:48 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:08:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:08:53 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:08:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/backup" 2025/06/19 05:08:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup.bin" 2025/06/19 05:08:59 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup.bin: Auto set use_segments_container to true 2025/06/19 05:09:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/backup" 2025/06/19 05:09:04 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/backup: Auto set use_segments_container to true 2025/06/19 05:09:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:09:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:09:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:09:20 DEBUG : two: Unchanged skipping 2025/06/19 05:09:36 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 05:09:41 INFO : one: Deleted 2025/06/19 05:09:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:09:46 DEBUG : one: md5 = fce635b74dbea9f8e4b37b4bc5403841 OK 2025/06/19 05:09:46 INFO : one: Copied (new) 2025/06/19 05:09:46 DEBUG : Waiting for deletions to finish 2025/06/19 05:10:07 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 05:10:12 INFO : three.txt: Deleted 2025/06/19 05:10:12 INFO : three.txt: Moved into backup dir 2025/06/19 05:10:17 DEBUG : dst/three.txt: md5 = e8fdd1fe9bb3b7e2a50b41e0c356d003 OK 2025/06/19 05:10:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:10:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:10:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:10:28 DEBUG : two: Unchanged skipping 2025/06/19 05:10:39 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 05:10:44 INFO : one: Deleted 2025/06/19 05:10:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:10:49 DEBUG : one: md5 = c4587ae56acf077c009ba0b766b50b20 OK 2025/06/19 05:10:49 INFO : one: Copied (new) 2025/06/19 05:10:49 DEBUG : Waiting for deletions to finish 2025/06/19 05:10:59 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 05:11:04 INFO : three.txt: Deleted 2025/06/19 05:11:04 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (193.91s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:11:41 DEBUG : dst/one: md5 = d074155961e0aa68bc8ca9527004ce8a OK 2025/06/19 05:11:46 DEBUG : dst/two: md5 = da0a1b5fc2d8d8c846a1b73803481aae OK 2025/06/19 05:11:51 DEBUG : dst/three.txt: md5 = 0a7a798cfef6d7e66450225c657dd3d7 OK 2025/06/19 05:11:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:11:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:11:57 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:12:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:12:02 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:12:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:12:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:12:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:12:08 DEBUG : two: Unchanged skipping 2025/06/19 05:12:23 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:12:28 INFO : one: Deleted 2025/06/19 05:12:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:12:34 DEBUG : one: md5 = 3b3e072a49d13f9c4f867fce458e7024 OK 2025/06/19 05:12:34 INFO : one: Copied (new) 2025/06/19 05:12:34 DEBUG : Waiting for deletions to finish 2025/06/19 05:12:54 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:12:59 INFO : three.txt: Deleted 2025/06/19 05:12:59 INFO : three.txt: Moved into backup dir 2025/06/19 05:13:10 DEBUG : dst/three.txt: md5 = 813606906c212bbad38d8bedafbd25ac OK 2025/06/19 05:13:21 DEBUG : one.bak: Excluded (Path Filter) 2025/06/19 05:13:21 DEBUG : one.bak: Excluded 2025/06/19 05:13:21 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/19 05:13:21 DEBUG : three.txt.bak: Excluded 2025/06/19 05:13:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:13:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for checks to finish 2025/06/19 05:13:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:13:26 DEBUG : two: Unchanged skipping 2025/06/19 05:13:31 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:13:36 INFO : one: Deleted 2025/06/19 05:13:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa/dst': Waiting for transfers to finish 2025/06/19 05:13:42 DEBUG : one: md5 = 56d590ccf9cfdac9efc94ecbb308c38f OK 2025/06/19 05:13:42 INFO : one: Copied (new) 2025/06/19 05:13:42 DEBUG : Waiting for deletions to finish 2025/06/19 05:13:57 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:14:02 INFO : three.txt: Deleted 2025/06/19 05:14:02 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (182.38s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:14:43 DEBUG : dst/one: md5 = fbaae2695c2e0c237e4cd16a5cf5a9d2 OK 2025/06/19 05:14:48 DEBUG : dst/two: md5 = c73c803d2557194e2c70b108de5198d9 OK 2025/06/19 05:14:53 DEBUG : dst/three.txt: md5 = fd80484931b6e07e8dac353fa9c0a72a OK 2025/06/19 05:15:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:15:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:15:02 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:15:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:15:23 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:15:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:16:01 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:16:21 INFO : one: Deleted 2025/06/19 05:16:41 DEBUG : one: md5 = 22a8b70265f0c7885246ccd7ac0a7ec2 OK 2025/06/19 05:16:41 INFO : one: Copied (new) 2025/06/19 05:16:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:16:42 DEBUG : two: Unchanged skipping 2025/06/19 05:17:02 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/19 05:17:32 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:17:37 INFO : three.txt: Deleted 2025/06/19 05:17:42 DEBUG : three.txt: md5 = a4d13581f9ce32328a099806441f0cda OK 2025/06/19 05:17:42 INFO : three.txt: Copied (new) 2025/06/19 05:17:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:18:13 INFO : one: Copied (server-side copy) to: one.bak 2025/06/19 05:18:18 INFO : one: Deleted 2025/06/19 05:18:24 DEBUG : one: md5 = 36ff72cfefb3d6fb342e28df08e7746b OK 2025/06/19 05:18:24 INFO : one: Copied (new) 2025/06/19 05:18:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:18:24 DEBUG : two: Unchanged skipping 2025/06/19 05:18:24 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/19 05:18:34 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/19 05:18:39 INFO : three.txt: Deleted 2025/06/19 05:18:44 DEBUG : three.txt: md5 = 097315979770d0054f4f3478747c63b9 OK 2025/06/19 05:18:44 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (287.89s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:19:31 DEBUG : dst/one: md5 = 19bbb91f2b8b5b83a8de7fcdd15d9752 OK 2025/06/19 05:19:36 DEBUG : dst/two: md5 = aea51874837f2bb0e26282c57c5f1ff0 OK 2025/06/19 05:19:41 DEBUG : dst/three.txt: md5 = baf9b0b3be3697d947c20cf826114627 OK 2025/06/19 05:19:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rucocaz0cefa/dst" 2025/06/19 05:19:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst.bin" 2025/06/19 05:19:47 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst.bin: Auto set use_segments_container to true 2025/06/19 05:19:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rucocaz0cefa/dst" 2025/06/19 05:19:52 DEBUG : Swift container crypt path rclone-test-rucocaz0cefa/dst: Auto set use_segments_container to true 2025/06/19 05:19:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/19 05:20:08 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 05:20:13 INFO : one: Deleted 2025/06/19 05:20:19 DEBUG : one: md5 = 6df8f8130a237abb8e96269eb6db3b08 OK 2025/06/19 05:20:19 INFO : one: Copied (new) 2025/06/19 05:20:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:20:19 DEBUG : two: Unchanged skipping 2025/06/19 05:20:24 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/19 05:20:34 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 05:20:39 INFO : three.txt: Deleted 2025/06/19 05:20:45 DEBUG : three.txt: md5 = 4ccab4dcc6edfa2800a0304a1407cce3 OK 2025/06/19 05:20:45 INFO : three.txt: Copied (new) 2025/06/19 05:21:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/19 05:21:06 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/19 05:21:11 INFO : one: Deleted 2025/06/19 05:21:16 DEBUG : one: md5 = 800ed9422f83e022d7291a33947a0e98 OK 2025/06/19 05:21:16 INFO : one: Copied (new) 2025/06/19 05:21:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:21:21 DEBUG : two: Unchanged skipping 2025/06/19 05:21:21 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/19 05:21:26 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/19 05:21:31 INFO : three.txt: Deleted 2025/06/19 05:21:37 DEBUG : three.txt: md5 = d2806a2aa312104517817dacceb24b3d OK 2025/06/19 05:21:37 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (162.12s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:22:08 DEBUG : Testêé: md5 = 47ef92065337b7b6453faaf587fc28a3 OK 2025/06/19 05:22:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:22:09 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/06/19 05:22:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:22:09 DEBUG : Testêé: md5 = 250aa6a8d6b5d1bd5c41f038c1bad5e0 OK 2025/06/19 05:22:09 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/06/19 05:22:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.13s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:22:10 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/19 05:22:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:22:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:22:16 DEBUG : existing: md5 = ec8db618d4ba465c7e3b8144a19177af OK 2025/06/19 05:22:16 INFO : existing: Copied (new) 2025/06/19 05:22:16 DEBUG : Waiting for deletions to finish 2025/06/19 05:22:21 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/06/19 05:22:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:22:26 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/06/19 05:22:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:22:26 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': not deleting files as there were IO errors 2025/06/19 05:22:26 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (31.60s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:22:42 DEBUG : EXISTING: md5 = ca887cef495d5af015d03ddf029ca93c OK 2025/06/19 05:22:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:22:42 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:22:42 DEBUG : existing: Unchanged skipping 2025/06/19 05:22:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:22:42 DEBUG : Waiting for deletions to finish 2025/06/19 05:22:42 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.21s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:2636: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.31s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", 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-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", 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-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" sync_test.go:2675: This test only runs on local --- PASS: TestMaxTransfer (0.29s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:22:49 DEBUG : both0: md5 = e91a268f3b3ceeb9284378b9a6dd6619 OK 2025/06/19 05:22:49 DEBUG : only0: md5 = f0a9722cf652d97ab0b3a5200975322b OK 2025/06/19 05:22:55 DEBUG : both1: md5 = d724a0be5b4eda89bb3aaa63b14441e3 OK 2025/06/19 05:22:55 DEBUG : only1: md5 = 0f9f2b6124bde3b810f6ea1814d88df1 OK 2025/06/19 05:23:00 DEBUG : both2: md5 = a6b6eb464eb735c435b5390a41d5b79c OK 2025/06/19 05:23:01 DEBUG : only2: md5 = f7075049e701005fb7d6098bc42dec41 OK 2025/06/19 05:23:06 DEBUG : both3: md5 = 5dc8315d3c536a50504ef9966e3d118e OK 2025/06/19 05:23:11 DEBUG : only3: md5 = 25b953c52e90e1dc62f695d4fe00ad56 OK 2025/06/19 05:23:12 DEBUG : both4: md5 = 00ae1e165b610543ded44634a64a294a OK 2025/06/19 05:23:13 DEBUG : only4: md5 = 8999f14301d47c25873d895fa48143e7 OK 2025/06/19 05:23:18 DEBUG : both5: md5 = 41bbe1cc9de270be61208db6878d5b23 OK 2025/06/19 05:23:23 DEBUG : only5: md5 = fbcf01db3102d96242b82616ff9fa3cd OK 2025/06/19 05:23:28 DEBUG : both6: md5 = 861e6edb5c035c424c03614f18fa05a5 OK 2025/06/19 05:23:34 DEBUG : only6: md5 = 44c7966ec98c4c8d96c73d64a5142f36 OK 2025/06/19 05:23:34 DEBUG : both7: md5 = 00aac59febfcd39fb4f8cd6d13616f7c OK 2025/06/19 05:23:40 DEBUG : only7: md5 = 974c50b5ecb78a06a755b65fae628cf3 OK 2025/06/19 05:23:40 DEBUG : both8: md5 = 4c6fed68cefd50215c06d5f76af2a786 OK 2025/06/19 05:23:46 DEBUG : only8: md5 = 81e0442f26f07928a9e1006682853d88 OK 2025/06/19 05:23:51 DEBUG : both9: md5 = cd259d793ec88069eaff60293ace986c OK 2025/06/19 05:23:51 DEBUG : only9: md5 = a4147b4c6cbcfda85ab2065c16fe0465 OK 2025/06/19 05:23:52 DEBUG : both10: md5 = b2cca8cc435951ab069920dd8b18aa4a OK 2025/06/19 05:23:52 DEBUG : only10: md5 = 89e7a5ada8963902ae49fd0182372856 OK 2025/06/19 05:23:54 DEBUG : both11: md5 = 208d10a863217d732224889520bd428a OK 2025/06/19 05:23:59 DEBUG : only11: md5 = e3e3c8725e222924e60d00a5673e6a89 OK 2025/06/19 05:24:00 DEBUG : both12: md5 = 8a88f133aec2172388806002091a9ac6 OK 2025/06/19 05:24:00 DEBUG : only12: md5 = 1a460c9c0545bab2acd4aa1a1713fa96 OK 2025/06/19 05:24:01 DEBUG : both13: md5 = 2b1a79728318f1cb5f6a2a4581eaae14 OK 2025/06/19 05:24:06 DEBUG : only13: md5 = ec47f4d4b532ae88a1402f3e921c4152 OK 2025/06/19 05:24:12 DEBUG : both14: md5 = dfb724682e8784ed5dc829c38ce9d16b OK 2025/06/19 05:24:17 DEBUG : only14: md5 = b71f02d426a32fc7712e015d54e83aa3 OK 2025/06/19 05:24:23 DEBUG : both15: md5 = 458d84ff5884e6152ae39527769234e7 OK 2025/06/19 05:24:23 DEBUG : only15: md5 = 754ca1e0bfee6831a4328383fc74daae OK 2025/06/19 05:24:23 DEBUG : both16: md5 = 10e78cf9eb0bf4ac225f99c46b8a293d OK 2025/06/19 05:24:28 DEBUG : only16: md5 = 1c7df6e2545c686e91ae3c9e999de222 OK 2025/06/19 05:24:34 DEBUG : both17: md5 = df50ee8d619f04488d357965ccf21344 OK 2025/06/19 05:24:34 DEBUG : only17: md5 = 2a374e9eb73ef6cd58e9842778d90771 OK 2025/06/19 05:24:39 DEBUG : both18: md5 = 14485a12dfd9f2de18541166caf00b95 OK 2025/06/19 05:24:45 DEBUG : only18: md5 = 6b65a1b1b90ccfd659acb143ee3a26ce OK 2025/06/19 05:24:50 DEBUG : both19: md5 = f4e756f5885df37f0be261e76f91c27e OK 2025/06/19 05:24:50 DEBUG : only19: md5 = 38660768dff69f52726200fb91dc63b4 OK 2025/06/19 05:25:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:25:33 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both10: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both12: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both13: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both11: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both14: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both16: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both17: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both18: Unchanged skipping 2025/06/19 05:25:33 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:33 DEBUG : both1: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both0: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both3: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both4: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both15: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both5: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both6: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both7: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both19: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both8: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both9: Unchanged skipping 2025/06/19 05:25:38 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:25:38 DEBUG : both2: Unchanged skipping 2025/06/19 05:25:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:25:38 DEBUG : Waiting for deletions to finish 2025/06/19 05:25:38 INFO : only17: Deleted 2025/06/19 05:25:43 INFO : only3: Deleted 2025/06/19 05:25:43 INFO : only5: Deleted 2025/06/19 05:25:43 INFO : only0: Deleted 2025/06/19 05:25:43 INFO : only10: Deleted 2025/06/19 05:25:43 INFO : only19: Deleted 2025/06/19 05:25:43 INFO : only1: Deleted 2025/06/19 05:25:48 INFO : only18: Deleted 2025/06/19 05:25:48 INFO : only7: Deleted 2025/06/19 05:25:48 INFO : only6: Deleted 2025/06/19 05:25:49 INFO : only12: Deleted 2025/06/19 05:25:49 INFO : only8: Deleted 2025/06/19 05:25:49 INFO : only15: Deleted 2025/06/19 05:25:49 INFO : only2: Deleted 2025/06/19 05:25:49 INFO : only4: Deleted 2025/06/19 05:25:49 INFO : only9: Deleted 2025/06/19 05:25:54 INFO : only14: Deleted 2025/06/19 05:25:55 INFO : only16: Deleted 2025/06/19 05:25:58 INFO : only11: Deleted 2025/06/19 05:25:59 INFO : only13: Deleted 2025/06/19 05:25:59 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (310.10s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:27:59 DEBUG : both0: md5 = f0a4cd8eea530d6ceac9ef610e59e4bd OK 2025/06/19 05:27:59 DEBUG : only0: md5 = 0022bd1b22be3ed6339995b138ef084a OK 2025/06/19 05:28:04 DEBUG : both1: md5 = 28e2330e82e1a5928a4f620f0450b38e OK 2025/06/19 05:28:04 DEBUG : only1: md5 = 7e8a11a02d8db5db35a9074fbb22309e OK 2025/06/19 05:28:09 DEBUG : both2: md5 = 2442fc1d7ba338e2719ebcf26f8c08f8 OK 2025/06/19 05:28:10 DEBUG : only2: md5 = f66eb85c10bcfe69df154a1913e4d8c6 OK 2025/06/19 05:28:15 DEBUG : both3: md5 = 11182ed4beba90dfa3648703ab706fcb OK 2025/06/19 05:28:20 DEBUG : only3: md5 = 36a565760f7fd9757e75a9640b02f868 OK 2025/06/19 05:28:21 DEBUG : both4: md5 = 6edc8706bf734a205935f5afdfab55ed OK 2025/06/19 05:28:21 DEBUG : only4: md5 = 1f3ad78892597a5a4f1c973103df064c OK 2025/06/19 05:28:26 DEBUG : both5: md5 = 01e7c0d7f4a2e6a64fb8c94fb7a74b4f OK 2025/06/19 05:28:31 DEBUG : only5: md5 = 429ac9f38675f4ba88b8d7f3dcf89beb OK 2025/06/19 05:28:36 DEBUG : both6: md5 = 96c9e945c107b5346ebbb7681b9f860f OK 2025/06/19 05:28:41 DEBUG : only6: md5 = 6f6b6a388324efbe19ced85760f8d1af OK 2025/06/19 05:28:42 DEBUG : both7: md5 = 01652b79258139bac4f99503cbe32ce7 OK 2025/06/19 05:28:47 DEBUG : only7: md5 = e56a3688dc7d6ba2575e2c5003f9b1e7 OK 2025/06/19 05:28:47 DEBUG : both8: md5 = 23bdcf6630c9baa5fc5dd627f3fd3043 OK 2025/06/19 05:28:52 DEBUG : only8: md5 = e79dcc2bea1cdc237b33115015a066ed OK 2025/06/19 05:28:57 DEBUG : both9: md5 = bdb2a2f213cefc5a0516bfc601eaea21 OK 2025/06/19 05:28:57 DEBUG : only9: md5 = 1ad1519276dc9c5905dc38ccfe6aeeea OK 2025/06/19 05:28:58 DEBUG : both10: md5 = 7189f105f38b6f93c3ea736d8dcdc22a OK 2025/06/19 05:28:58 DEBUG : only10: md5 = 13b91bba14421e2c00868ec4e4f40347 OK 2025/06/19 05:28:58 DEBUG : both11: md5 = 01e2753c9d55341e954a6545d54a907e OK 2025/06/19 05:29:03 DEBUG : only11: md5 = 5493ae7c1272c9891288edaa36ff43ca OK 2025/06/19 05:29:03 DEBUG : both12: md5 = 9fa93578e030d95d6bbbfe221a2fd166 OK 2025/06/19 05:29:04 DEBUG : only12: md5 = 354b3526e8b81c3781078e931f2e1b1f OK 2025/06/19 05:29:04 DEBUG : both13: md5 = 575e175fb01ca1a6580a184114df0532 OK 2025/06/19 05:29:09 DEBUG : only13: md5 = 16b72d74a27f214aea876cfd6352d8ec OK 2025/06/19 05:29:14 DEBUG : both14: md5 = d180b63b2c10b95ea5015855b5628326 OK 2025/06/19 05:29:20 DEBUG : only14: md5 = 7f0c203114e10036b734fef45a6a31e0 OK 2025/06/19 05:29:25 DEBUG : both15: md5 = 0408b25b44cbcbdd6fc8f26863ab46bb OK 2025/06/19 05:29:25 DEBUG : only15: md5 = fb9df7c2cef602fe210a4d07f2652cfe OK 2025/06/19 05:29:26 DEBUG : both16: md5 = 85db013539fdef5978d8ecdb99b58e47 OK 2025/06/19 05:29:31 DEBUG : only16: md5 = e71aa4fe9c8b0f290bcdc865cbd7c46b OK 2025/06/19 05:29:36 DEBUG : both17: md5 = febc074f62fe07227a2dad00aee993bd OK 2025/06/19 05:29:36 DEBUG : only17: md5 = 95cbe931d29a39ce8e932bee28b2d437 OK 2025/06/19 05:29:41 DEBUG : both18: md5 = a83d7a51289546a1161b90bece385255 OK 2025/06/19 05:29:46 DEBUG : only18: md5 = 0b49f24336a5f11580dfe094122fe551 OK 2025/06/19 05:29:51 DEBUG : both19: md5 = 40473e267ad4d43494fe2b1a16fe1a0e OK 2025/06/19 05:29:52 DEBUG : only19: md5 = c40f12cff8511bec7c1e1f57d53611b5 OK 2025/06/19 05:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for checks to finish 2025/06/19 05:30:29 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both10: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both12: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both11: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both13: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both15: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both16: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both17: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both18: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both19: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both2: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both3: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both4: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both5: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both6: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both7: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both8: Unchanged skipping 2025/06/19 05:30:29 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:29 DEBUG : both9: Unchanged skipping 2025/06/19 05:30:29 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:29 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/06/19 05:30:30 DEBUG : only0: md5 = c4663a2ab88a436e04ea0b2311df0d7d OK 2025/06/19 05:30:30 INFO : only0: Copied (replaced existing) 2025/06/19 05:30:30 DEBUG : only12: md5 = 7b72b7918f56322d1faa599f83e09c82 OK 2025/06/19 05:30:30 INFO : only12: Copied (replaced existing) 2025/06/19 05:30:30 DEBUG : only10: md5 = a4c3f611544806c71e044f83bfdb4ff6 OK 2025/06/19 05:30:30 INFO : only10: Copied (replaced existing) 2025/06/19 05:30:30 DEBUG : only15: md5 = ac1abf2f83a70db2a90e8f9d3aef3526 OK 2025/06/19 05:30:30 INFO : only15: Copied (replaced existing) 2025/06/19 05:30:34 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:34 DEBUG : both0: Unchanged skipping 2025/06/19 05:30:34 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:34 DEBUG : both14: Unchanged skipping 2025/06/19 05:30:34 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/19 05:30:34 DEBUG : both1: Unchanged skipping 2025/06/19 05:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa': Waiting for transfers to finish 2025/06/19 05:30:35 DEBUG : only11: md5 = f262e08ba673037497537910878fa526 OK 2025/06/19 05:30:35 INFO : only11: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only17: md5 = 0db57cd5f8a342fbaeccde94972ba1ab OK 2025/06/19 05:30:35 INFO : only17: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only13: md5 = 3ca0109684fc3b159b6c52703edbf798 OK 2025/06/19 05:30:35 INFO : only13: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only14: md5 = aeecb383cc7d05bc475172134e4ec05c OK 2025/06/19 05:30:35 INFO : only14: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only1: md5 = 8312dee3924daa548b94ce527d297428 OK 2025/06/19 05:30:35 INFO : only1: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only16: md5 = c5e345a86ae55f6be48aa5da7f2632bb OK 2025/06/19 05:30:35 INFO : only16: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only19: md5 = 244d7cff0bd4f7f3508c4449b9ce7a74 OK 2025/06/19 05:30:35 INFO : only19: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only4: md5 = ed46ae14db09eb70d049269c5c23a7ac OK 2025/06/19 05:30:35 INFO : only4: Copied (replaced existing) 2025/06/19 05:30:35 DEBUG : only2: md5 = c9117b8aa4ceb534d9d3d08fbca28016 OK 2025/06/19 05:30:35 INFO : only2: Copied (replaced existing) 2025/06/19 05:30:45 DEBUG : only18: md5 = 4161c8ee224cdf9ff0f70dbf108d2dd8 OK 2025/06/19 05:30:45 INFO : only18: Copied (replaced existing) 2025/06/19 05:30:45 DEBUG : only3: md5 = 098931f15b2abda19687280039ba110e OK 2025/06/19 05:30:45 INFO : only3: Copied (replaced existing) 2025/06/19 05:30:45 DEBUG : only5: md5 = 4b10a2d50ec77d3cb9f6cd265c839910 OK 2025/06/19 05:30:45 INFO : only5: Copied (replaced existing) 2025/06/19 05:30:45 DEBUG : only6: md5 = 8a9d0b5786f78810458aafa9c4242538 OK 2025/06/19 05:30:45 INFO : only6: Copied (replaced existing) 2025/06/19 05:30:46 DEBUG : only9: md5 = 1adec4ac83896f44de4ba8493c12b59a OK 2025/06/19 05:30:46 INFO : only9: Copied (replaced existing) 2025/06/19 05:30:50 DEBUG : only7: md5 = 8a21b0dd0b9bbcd84693561780a5fb40 OK 2025/06/19 05:30:50 INFO : only7: Copied (replaced existing) 2025/06/19 05:30:56 DEBUG : only8: md5 = fdb64e70e0de90b635af81c874d26218 OK 2025/06/19 05:30:56 INFO : only8: Copied (replaced existing) 2025/06/19 05:30:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (395.99s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:34:30 DEBUG : empty_dir: Making directory with metadata 2025/06/19 05:34:30 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 05:34:30 DEBUG : empty_on_remote: Making directory with metadata 2025/06/19 05:34:30 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 05:34:30 INFO : empty_on_remote: Making directory 2025/06/19 05:34:30 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/19 05:34:35 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/19 05:34:35 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/19 05:34:35 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/19 05:34:35 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (31.82s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:35:01 DEBUG : empty_dir: Making directory with metadata 2025/06/19 05:35:01 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 05:35:01 DEBUG : empty_on_remote: Making directory with metadata 2025/06/19 05:35:01 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/19 05:35:01 INFO : empty_on_remote: Making directory 2025/06/19 05:35:01 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/19 05:35:07 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:07 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:07 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:07 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:07 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (31.70s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:35:33 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:33 INFO : sub dir: Making directory 2025/06/19 05:35:33 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (17.28s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:35:50 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/19 05:35:50 INFO : sub dir: Making directory 2025/06/19 05:35:50 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithoutEmptyDirs (17.54s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-rucocaz0cefa'", Local "Local file system at /tmp/rclone2811650836", Modify Window "1ns" 2025/06/19 05:36:08 DEBUG : dir1/0000-abcdefg.txt: md5 = 3f3d36f539b28f08e4e2b0c45564b189 OK 2025/06/19 05:36:10 DEBUG : dir1/0001-bcdefgh.txt: md5 = 4fa275a0cc12cb301bdda7032319d196 OK 2025/06/19 05:36:15 DEBUG : dir1/0002-cdefghi.txt: md5 = ad1ff2d47f536c75b931d3919ca19b7b OK 2025/06/19 05:36:20 DEBUG : dir1/0003-defghij.txt: md5 = 8a3e6fe47ff6da54d4b2b96da9a2361b OK 2025/06/19 05:36:20 DEBUG : dir1/0004-efghijk.txt: md5 = 28e8aab0c867396cf3c8dc6b9e9b83b1 OK 2025/06/19 05:36:21 DEBUG : dir1/0005-fghijkl.txt: md5 = 4f79c37347d081ed8844df536c3fb5fc OK 2025/06/19 05:36:21 DEBUG : dir1/0006-ghijklm.txt: md5 = 72dd2a9516d29004b42bbbbe357c1286 OK 2025/06/19 05:36:21 DEBUG : dir1/0007-1234567.txt: md5 = 376e06d0c931e377a34aa8caf3a176b4 OK 2025/06/19 05:36:22 DEBUG : dir1/0008-2345678.txt: md5 = 884c3028c9457422e4c432ffd525be11 OK 2025/06/19 05:36:27 DEBUG : dir1/0009-3456789.txt: md5 = 69bba04ceb3d81c3b4bf92d1f547568b OK 2025/06/19 05:36:28 DEBUG : dir1/0010-456789.txt: md5 = ee4cd74410478f0d2f53506f81007494 OK 2025/06/19 05:36:34 DEBUG : dir1/0011-56789;.txt: md5 = 1c9b7a302949025ddb25a637a9057894 OK 2025/06/19 05:36:39 DEBUG : dir1/0012-6789;.txt: md5 = 8f0c22d46c202e889157660721795cc1 OK 2025/06/19 05:36:44 DEBUG : dir1/0013-789;=.txt: md5 = 3b4c127d169a8d3a61aceec1b4d8d90e OK 2025/06/19 05:36:49 DEBUG : dir1/0014-89;=.txt: md5 = 1881fbd893c26312ecc714e091e0243f OK 2025/06/19 05:36:51 DEBUG : dir1/0015-9;=.txt: md5 = 12eb53d100845914c12923af70c18b6e OK 2025/06/19 05:36:56 DEBUG : apple: md5 = 9dee5e75414ba31fe01fc8b43d155c08 OK 2025/06/19 05:37:01 DEBUG : banana: md5 = cf6727906a647906ed6f84832064875a OK 2025/06/19 05:37:02 DEBUG : appleappleapplebanana: md5 = 17f75a235f435ca22a0f5b3835091105 OK panic: test timed out after 1h0m0s running tests: TestTransform (55s) TestTransform/NFC (55s) goroutine 14079 [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(0xc0018d3880, {0x26944db?, 0xc0004cbad8?}, 0x27c0998) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0018d3880) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0018d3880, 0xc0004cbc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0019dfcb0, {0x3e7c5e0, 0x6b, 0x6b}, {0xedfe58aef?, 0x3e925a0?, 0x3e925a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0006a74a0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0006a74a0) /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 30 [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 31 [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 12650 [select]: net/http.(*persistConn).writeLoop(0xc000976d80) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 12556 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14083 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc0002ea780, 0xc00035ca80, 0xc00069a500) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc0002ea780, {0x2af3868, 0x3eb5b60}, {0xc001ad65a0, 0x4f}, {{0xc00015db00, 0x5}, {0xc00015db06, 0x2d}, {0x2678d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc0002ea780, {0x2af3868, 0x3eb5b60}, {{0xc00015db00, 0x5}, {0xc00015db06, 0x2d}, {0x2678d24, 0x3}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectPut(0xc0002ea780, {0x2af3868, 0x3eb5b60}, {0xc00015db00, 0x5}, {0xc00015db06, 0x2d}, {0x2acb4a0, 0xc00050d660}, 0x1, ...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1569 +0x1f5 github.com/ncw/swift/v2.(*Connection).ObjectPut(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1615 github.com/rclone/rclone/backend/swift.(*Object).Update.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1559 +0xb0 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001abe480, 0xc000712800, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:225 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc001f8ef60, {0x2af3868, 0x3eb5b60}, {0x2acb4a0, 0xc00050d660}, {0x2afeae0, 0xc001c5ccc0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1558 +0x7b8 github.com/rclone/rclone/backend/swift.(*Fs).Put(0xc001a8ac40, {0x2af3868, 0x3eb5b60}, {0x2acb4a0, 0xc00050d660}, {0x2afeae0, 0xc001c5ccc0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:937 +0x139 github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001a37ad0, {0x2af3868, 0x3eb5b60}, {0x2acb2a0, 0xc001c5cc60}, {0x2afe950, 0xc00035c8c0}, {0x0, 0x0, 0x0}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Fs).Put(0x461f3c?, {0x2af3868?, 0x3eb5b60?}, {0x2acb2a0?, 0xc001c5cc60?}, {0x2afe950?, 0xc00035c8c0?}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:571 +0x91 github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc00042eb60, {0x2af3868, 0x3eb5b60}, {0x2b07fe0, 0xc001a37ad0}, {0x269ce77, 0x10}, {0x269ce77, 0x10}, {0x1dcd64ff, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:275 +0x3f2 github.com/rclone/rclone/fstest.(*Run).WriteObject(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 github.com/rclone/rclone/fs/sync.makeTestFiles(0xc00042eb60?, 0xc00042eb60, {0x2679a9c, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:161 +0x6e5 github.com/rclone/rclone/fs/sync.TestTransform.func1(0xc001a8ba40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:102 +0xd3 testing.tRunner(0xc001a8ba40, 0xc0004d2320) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 14082 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 13905 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001b8a1e0, {0x2af3948, 0xc00094a0a0}) /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 13777 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 14109 [select]: net/http.(*persistConn).roundTrip(0xc000976d80, 0xc00094b2c0) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0007c8340, 0xc00069a500) /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(0xc000410380, 0xc00069a500) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc00069a500, {0x2accd20, 0xc000410380}, {0xc000078701?, 0xc000445820?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001a050b0, 0xc00069a500, {0xc000952f68?, 0x2?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001a050b0, 0xc00069a500) /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 14083 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 14082 [chan receive]: testing.(*T).Run(0xc001a8a000, {0x2678b32?, 0x41bb05?}, 0xc0004d2320) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/fs/sync.TestTransform(0xc001a8a000) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:95 +0x62a testing.tRunner(0xc001a8a000, 0x27c0998) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 12649 [IO wait]: internal/poll.runtime_pollWait(0x723d6c2cb4c0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001e6c680?, 0xc0006d3500?, 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(0xc001e6c680, {0xc0006d3500, 0x3500, 0x3500}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001e6c680, {0xc0006d3500?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014ebb0, {0xc0006d3500?, 0x0?, 0x3eb1260?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000524e70, {0xc0006d3500?, 0x723d5d6cdb40?, 0x723db47bf108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0022faff0, {0xc0006d3500?, 0x3eb83e0?, 0xc0004cd9d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0003dd438, {0x2ace280, 0xc0022faff0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0003dd188, {0x723d5df47440, 0xc000524e70}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0003dd188, 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(0xc0003dd188, {0xc000a0d000, 0x1000, 0xc0000e56b0?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000976d80, {0xc000a0d000?, 0x7f4ac5?, 0x2acb1a0?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0022ac6c0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0022ac6c0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000976d80) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 12556 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 1632 [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 2051 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.311653315s (try 2/5): exit status 2: Failed []