"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 2/5) 2025/05/02 02:47:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu" 2025/05/02 02:47:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/02 02:47:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu.bin" 2025/05/02 02:47:37 DEBUG : Swift container crypt path rclone-test-mexajot9fitu.bin: Auto set use_segments_container to true 2025/05/02 02:47:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu" 2025/05/02 02:47:42 DEBUG : Swift container crypt path rclone-test-mexajot9fitu: Auto set use_segments_container to true 2025/05/02 02:47:48 DEBUG : Creating backend with remote "/tmp/rclone802095826" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:47:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:47:48 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/05/02 02:47:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:47:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:47:48 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/02 02:47:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:47:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:47:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:47:54 DEBUG : sub dir/hello world: md5 = 7298eb9e607aa4e0925d8a8c4878efc7 OK 2025/05/02 02:47:54 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:47:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (11.43s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:48:00 DEBUG : Creating backend with remote "/non-existing" 2025/05/02 02:48:00 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/05/02 02:48:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:48:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.37s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:48:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:48:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:48:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:48:11 DEBUG : sub dir/hello world: md5 = 9894b50a8d804af15f6bc5dc7a88d97d OK 2025/05/02 02:48:11 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyNoTraverse (21.11s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:48:21 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Running all checks before starting transfers 2025/05/02 02:48:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:48:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:48:21 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Checks finished, now starting transfers 2025/05/02 02:48:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:48:27 DEBUG : sub dir/hello world: md5 = 7cd309a07e6936a80c13e512ce392768 OK 2025/05/02 02:48:27 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyCheckFirst (15.93s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:48:37 ERROR : Ignoring --no-traverse with sync 2025/05/02 02:48:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:48:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:48:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:48:43 DEBUG : sub dir/hello world: md5 = decc68859c8031c127091f3f5abe1c74 OK 2025/05/02 02:48:43 INFO : sub dir/hello world: Copied (new) 2025/05/02 02:48:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncNoTraverse (11.17s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:48:49 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/05/02 02:48:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:48:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:48:54 DEBUG : hello world2: md5 = 0b4bf5b6fb54d30dcbb5792709a2007f OK 2025/05/02 02:48:54 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyWithDepth (11.40s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:49:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 02:49:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:49:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:49:06 DEBUG : potato2: md5 = de5cf5fad6b07870cfef8132d1f689f0 OK 2025/05/02 02:49:06 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (16.79s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:49:22 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 02:49:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:49:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:49:27 DEBUG : potato2: md5 = 9c31f5a37f0369bde5b9d8e948138f1d OK 2025/05/02 02:49:27 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (21.04s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:49:38 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/05/02 02:49:38 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 02:49:38 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/05/02 02:49:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/02 02:49:38 INFO : sub dir: Making directory 2025/05/02 02:49:38 INFO : sub dir2: Making directory 2025/05/02 02:49:38 INFO : sub dir2/sub sub dir2: Making directory 2025/05/02 02:49:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:49:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:49:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:49:43 DEBUG : sub dir/hello world: md5 = 94af67106996189a940dbf4b8e19557f OK 2025/05/02 02:49:43 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2025/05/02 02:49:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (16.37s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:49:54 INFO : sub dir2: Making directory 2025/05/02 02:49:54 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/05/02 02:49:54 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 02:49:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:49:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:49:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:49:59 DEBUG : sub dir/hello world: md5 = 6bfe40a2704c918cd51be72a388341a0 OK 2025/05/02 02:49:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (15.89s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:50:10 DEBUG : sub dir2: Making directory with metadata 2025/05/02 02:50:10 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 02:50:10 INFO : sub dir: Making directory 2025/05/02 02:50:10 INFO : sub dir2: Making directory 2025/05/02 02:50:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:50:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:50:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:50:15 DEBUG : sub dir/hello world: md5 = f01013bceed2472024cce85192b50227 OK 2025/05/02 02:50:15 INFO : sub dir/hello world: Copied (new) 2025/05/02 02:50:15 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/05/02 02:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/05/02 02:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (16.60s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.10s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:50:27 INFO : sub dir2: Making directory 2025/05/02 02:50:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:50:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:50:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:50:32 DEBUG : sub dir/hello world: md5 = 7e5f841252852e09c37e15198652f30d OK 2025/05/02 02:50:32 INFO : sub dir/hello world: Copied (new) 2025/05/02 02:50:32 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (10.97s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:50:38 DEBUG : sub dir2: Making directory with metadata 2025/05/02 02:50:38 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 02:50:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/02 02:50:38 INFO : sub dir: Making directory 2025/05/02 02:50:38 INFO : sub dir2: Making directory 2025/05/02 02:50:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:50:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:50:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:50:43 DEBUG : sub dir/hello world: md5 = 50aa8cfa8635626d6354d39913d719d8 OK 2025/05/02 02:50:43 INFO : sub dir/hello world: Copied (new) 2025/05/02 02:50:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/05/02 02:50:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (11.42s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.10s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:50:49 INFO : sub dir2: Making directory 2025/05/02 02:50:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:50:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:50:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:50:54 DEBUG : sub dir/hello world: md5 = 153dca966eb0a8b51685c09491ac5d9b OK 2025/05/02 02:50:54 INFO : sub dir/hello world: Copied (new) 2025/05/02 02:50:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (10.94s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:51:05 DEBUG : sub dir/hello world: md5 = abd5a6775128a2a298d014ed2fd40a7f OK 2025/05/02 02:51:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wemikin5joja" 2025/05/02 02:51:05 DEBUG : Config file has changed externally - reloading 2025/05/02 02:51:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wemikin5joja.bin" 2025/05/02 02:51:06 DEBUG : Swift container crypt path rclone-test-wemikin5joja.bin: Auto set use_segments_container to true 2025/05/02 02:51:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wemikin5joja" 2025/05/02 02:51:12 DEBUG : Swift container crypt path rclone-test-wemikin5joja: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-wemikin5joja' 2025/05/02 02:51:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:51:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wemikin5joja': Waiting for checks to finish 2025/05/02 02:51:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wemikin5joja': Waiting for transfers to finish 2025/05/02 02:51:24 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:51:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wemikin5joja': Purge remote 2025/05/02 02:51:24 DEBUG : Waiting for deletions to finish 2025/05/02 02:51:24 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (29.50s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:51:35 DEBUG : sub dir/hello world: md5 = d99eb59be1239943f218c7e8661577b7 OK 2025/05/02 02:51:35 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/02 02:51:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:51:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:51:41 DEBUG : sub dir/hello world: md5 = 9ebfc2eccecb0acb4b7005e18c893d97 OK 2025/05/02 02:51:41 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyOverSelf (21.75s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:51:57 DEBUG : sub dir/hello world: md5 = 3d8a921830823718bbe511bb4902ca07 OK 2025/05/02 02:51:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hayipat4xace" 2025/05/02 02:51:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hayipat4xace.bin" 2025/05/02 02:51:57 DEBUG : Swift container crypt path rclone-test-hayipat4xace.bin: Auto set use_segments_container to true 2025/05/02 02:52:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hayipat4xace" 2025/05/02 02:52:03 DEBUG : Swift container crypt path rclone-test-hayipat4xace: Auto set use_segments_container to true sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace' 2025/05/02 02:52:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace': Waiting for checks to finish 2025/05/02 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace': Waiting for transfers to finish 2025/05/02 02:52:19 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:52:25 DEBUG : sub dir/hello world: md5 = 506cd92d777ca6198c46f106edf15015 OK 2025/05/02 02:52:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace': Waiting for checks to finish 2025/05/02 02:52:25 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/02 02:52:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace': Waiting for transfers to finish 2025/05/02 02:52:36 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:52:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hayipat4xace': Purge remote 2025/05/02 02:52:41 DEBUG : Waiting for deletions to finish 2025/05/02 02:52:51 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (65.94s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:53:02 DEBUG : sub dir/hello world: md5 = 7ebcdf07b389b973b738ba56251cb747 OK 2025/05/02 02:53:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:53:03 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/02 02:53:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:53:08 DEBUG : sub dir/hello world: md5 = 7baccd3a9a7074e92767d654afab3cfd OK 2025/05/02 02:53:08 INFO : sub dir/hello world: Copied (replaced existing) 2025/05/02 02:53:08 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestMoveOverSelf (22.25s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:53:25 DEBUG : sub dir/hello world: md5 = 875ac3fdd2de39c9bf54b4e2c1938a82 OK 2025/05/02 02:53:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-woziduc8paza" 2025/05/02 02:53:25 DEBUG : Config file has changed externally - reloading 2025/05/02 02:53:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-woziduc8paza.bin" 2025/05/02 02:53:25 DEBUG : Swift container crypt path rclone-test-woziduc8paza.bin: Auto set use_segments_container to true 2025/05/02 02:53:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-woziduc8paza" 2025/05/02 02:53:31 DEBUG : Swift container crypt path rclone-test-woziduc8paza: Auto set use_segments_container to true sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza' 2025/05/02 02:53:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for checks to finish 2025/05/02 02:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for transfers to finish 2025/05/02 02:53:48 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:53:53 DEBUG : sub dir/hello world: md5 = d4af61a5f64c5a58dd05ea578ac5aad2 OK 2025/05/02 02:53:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for checks to finish 2025/05/02 02:53:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/02 02:53:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for transfers to finish 2025/05/02 02:54:09 INFO : sub dir/hello world: Copied (server-side copy) 2025/05/02 02:54:14 INFO : sub dir/hello world: Deleted 2025/05/02 02:54:25 DEBUG : sub dir/hello world: md5 = def4a6a669ff4d51abe63c61fb303f72 OK 2025/05/02 02:54:25 DEBUG : testing file moves 2025/05/02 02:54:25 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/05/02 02:54:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for checks to finish 2025/05/02 02:54:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Waiting for transfers to finish 2025/05/02 02:54:41 INFO : sub dir/hello world: Copied (server-side copy) 2025/05/02 02:54:46 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:54:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woziduc8paza': Purge remote 2025/05/02 02:54:46 DEBUG : Waiting for deletions to finish 2025/05/02 02:54:52 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (92.86s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:54:58 DEBUG : sub dir/hello world: md5 = b39e374d8ec6067b77c9ec9e64d7806d OK 2025/05/02 02:54:58 ERROR : : error listing: directory not found 2025/05/02 02:54:58 INFO : Local file system at /tmp/rclone802095826: Making directory 2025/05/02 02:54:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:54:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyAfterDelete (16.43s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:14 DEBUG : sub dir/hello world: md5 = 078e9f81565d96f0f2f287bd0bffcc72 OK 2025/05/02 02:55:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 02:55:20 DEBUG : Local file system at /tmp/rclone802095826: Waiting for checks to finish 2025/05/02 02:55:20 DEBUG : Local file system at /tmp/rclone802095826: Waiting for transfers to finish 2025/05/02 02:55:20 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/05/02 02:55:20 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyRedownload (16.32s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:25 DEBUG : check sum: Need to transfer - File not found at Destination 2025/05/02 02:55:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:31 DEBUG : check sum: md5 = be3ee354c6cdbc2c825b0853c6856e0f OK 2025/05/02 02:55:31 INFO : check sum: Copied (new) 2025/05/02 02:55:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:36 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/05/02 02:55:36 DEBUG : check sum: Size of src and dst objects identical 2025/05/02 02:55:36 DEBUG : check sum: Unchanged skipping 2025/05/02 02:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:36 DEBUG : Waiting for deletions to finish 2025/05/02 02:55:36 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (26.61s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:52 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/05/02 02:55:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:52 DEBUG : sizeonly: md5 = 921c1f8a539d90df640c32f8965fe04c OK 2025/05/02 02:55:52 INFO : sizeonly: Copied (new) 2025/05/02 02:55:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:53 DEBUG : sizeonly: Sizes identical 2025/05/02 02:55:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:53 DEBUG : sizeonly: Unchanged skipping 2025/05/02 02:55:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:53 DEBUG : Waiting for deletions to finish 2025/05/02 02:55:53 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncSizeOnly (2.03s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:54 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/05/02 02:55:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:59 DEBUG : ignore-size: md5 = a5648854c70da39dda915dc0210bca8a OK 2025/05/02 02:55:59 INFO : ignore-size: Copied (new) 2025/05/02 02:55:59 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:55:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:55:59 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:55:59 DEBUG : ignore-size: Unchanged skipping 2025/05/02 02:55:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:55:59 DEBUG : Waiting for deletions to finish 2025/05/02 02:55:59 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (11.53s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:56:11 DEBUG : existing: md5 = e9d5535f7bad70a2a87c069567f09e47 OK 2025/05/02 02:56:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:56:11 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:56:11 DEBUG : existing: Unchanged skipping 2025/05/02 02:56:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:56:11 DEBUG : Waiting for deletions to finish 2025/05/02 02:56:11 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:56:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:56:11 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/05/02 02:56:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:56:16 DEBUG : existing: md5 = ced62fff48348d3be87533366b3e369b OK 2025/05/02 02:56:16 INFO : existing: Copied (replaced existing) 2025/05/02 02:56:16 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (21.99s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:56:27 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/02 02:56:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:56:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:56:33 DEBUG : existing: md5 = c745aea18f09029f0a0c255fba44ac26 OK 2025/05/02 02:56:33 INFO : existing: Copied (new) 2025/05/02 02:56:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:56:33 DEBUG : existing: Destination exists, skipping 2025/05/02 02:56:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:56:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:56:38 DEBUG : Waiting for deletions to finish 2025/05/02 02:56:38 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (16.55s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:56:45 DEBUG : b/potato: md5 = 7ecf9a659762d0412eefe2aa64fcb1c3 OK 2025/05/02 02:56:50 DEBUG : c/non empty space: md5 = 349034c16e78d1af678a7cffaad64c60 OK 2025/05/02 02:56:50 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/05/02 02:56:50 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/02 02:56:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:56:56 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:56:56 DEBUG : c/non empty space: Unchanged skipping 2025/05/02 02:56:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:56:56 DEBUG : a/potato2: md5 = 27c024d24d271d5e30da882cbd50fe11 OK 2025/05/02 02:56:56 INFO : a/potato2: Copied (new) 2025/05/02 02:56:56 DEBUG : Waiting for deletions to finish 2025/05/02 02:56:56 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (38.19s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:57:22 DEBUG : empty space: md5 = 3395359310c51215e82698e37f7e878b OK 2025/05/02 02:57:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:57:23 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/05/02 02:57:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:57:23 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/05/02 02:57:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:57:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:57:23 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/05/02 02:57:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:57:23 DEBUG : empty space: md5 = 3a4329aa28f697094ebac2de9ef00226 OK 2025/05/02 02:57:23 INFO : empty space: Copied (replaced existing) 2025/05/02 02:57:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.00s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.10s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:57:30 DEBUG : foo: md5 = 84c6f5b200b38dd251f9af8c77e7d19a OK 2025/05/02 02:57:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:57:30 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/05/02 02:57:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:57:35 DEBUG : foo: md5 = 8e392187f35fd24cc52e9b8e8afd167e OK 2025/05/02 02:57:35 INFO : foo: Copied (replaced existing) 2025/05/02 02:57:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (16.78s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:57:41 DEBUG : empty space: md5 = cd0d530683443b973f8afd1f088865a4 OK 2025/05/02 02:57:42 DEBUG : potato: Need to transfer - File not found at Destination 2025/05/02 02:57:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:57:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:57:42 DEBUG : empty space: Unchanged skipping 2025/05/02 02:57:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:57:47 DEBUG : potato: md5 = 3f0fca816d87ea67a3bbbbb6399961a0 OK 2025/05/02 02:57:47 INFO : potato: Copied (new) 2025/05/02 02:57:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (16.71s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:58:03 DEBUG : potato: md5 = 409c568f9af2b59dfaa475be2cc12d3a OK 2025/05/02 02:58:03 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/05/02 02:58:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:58:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:58:14 DEBUG : potato: md5 = 40556ad2bd5daf1a6eb1bf6881c9ee03 OK 2025/05/02 02:58:14 INFO : potato: Copied (replaced existing) 2025/05/02 02:58:14 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (32.13s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:58:35 DEBUG : potato: md5 = 33513b11ae00dc8f48e18d1214fb3e44 OK 2025/05/02 02:58:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:58:35 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/05/02 02:58:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:58:40 DEBUG : potato: md5 = 1b1b15f678c9e2862704c7eb85059c16 OK 2025/05/02 02:58:40 INFO : potato: Copied (replaced existing) 2025/05/02 02:58:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (21.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:58:57 DEBUG : potato: md5 = 8694e7c6cc85b661b6fcfdf6084c54d4 OK 2025/05/02 02:58:57 DEBUG : empty space: md5 = 0fde5c6175282c69b7fc5fa5d4c8a813 OK 2025/05/02 02:58:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 02:58:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:58:58 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/05/02 02:58:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:58:58 DEBUG : empty space: Unchanged skipping 2025/05/02 02:58:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:58:58 DEBUG : Waiting for deletions to finish 2025/05/02 02:58:58 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (16.75s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:59:14 DEBUG : potato: md5 = c19acbd28656f18c2aba4633ec4514af OK 2025/05/02 02:59:14 DEBUG : empty space: md5 = d61e5b3273c326c79e4fce86c5b90b6e OK 2025/05/02 02:59:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 02:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:59:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:59:20 DEBUG : empty space: Unchanged skipping 2025/05/02 02:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:59:25 DEBUG : potato2: md5 = 44e5e4576fbe9e0b3f0b72f31bd36af7 OK 2025/05/02 02:59:25 INFO : potato2: Copied (new) 2025/05/02 02:59:25 DEBUG : Waiting for deletions to finish 2025/05/02 02:59:30 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (27.17s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 02:59:36 DEBUG : b/potato: md5 = 4b9b377ab7da38ccf75aae21f8b6abc7 OK 2025/05/02 02:59:41 DEBUG : c/non empty space: md5 = ad3be3900fb070d5e85b461fef1ce6e1 OK 2025/05/02 02:59:41 INFO : d: Making directory 2025/05/02 02:59:41 INFO : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2025/05/02 02:59:47 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/02 02:59:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 02:59:47 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 02:59:47 DEBUG : c/non empty space: Unchanged skipping 2025/05/02 02:59:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 02:59:52 DEBUG : a/potato2: md5 = 88ba5f432993dbd4b7e1aa2a753d8e7e OK 2025/05/02 02:59:52 INFO : a/potato2: Copied (new) 2025/05/02 02:59:52 DEBUG : Waiting for deletions to finish 2025/05/02 02:59:52 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (32.25s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:00:08 DEBUG : b/potato: md5 = 7e644e2162c0e5b3a5b7504033dd341f OK 2025/05/02 03:00:13 DEBUG : c/non empty space: md5 = 4d6efbfa0182e1d29eabe46afc399079 OK 2025/05/02 03:00:13 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/05/02 03:00:19 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/02 03:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:00:19 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:00:19 DEBUG : c/non empty space: Unchanged skipping 2025/05/02 03:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:00:24 DEBUG : a/potato2: md5 = 927fcf49028a75eff2f28236f2cc2adf OK 2025/05/02 03:00:24 INFO : a/potato2: Copied (new) 2025/05/02 03:00:24 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': not deleting files as there were IO errors 2025/05/02 03:00:24 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (37.44s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:00:51 DEBUG : potato: md5 = 65e162769811b00bcd5d7b526fbbfa28 OK 2025/05/02 03:00:51 DEBUG : empty space: md5 = c487ba18f0b5a27de6543c6638c5c3a4 OK 2025/05/02 03:00:51 DEBUG : Waiting for deletions to finish 2025/05/02 03:00:51 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:00:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:00:51 DEBUG : empty space: Unchanged skipping 2025/05/02 03:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:00:56 INFO : potato: Deleted 2025/05/02 03:00:56 DEBUG : potato2: md5 = e6379a35399d497a6e1fcfb6a9d43528 OK 2025/05/02 03:00:56 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (21.91s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:01:12 DEBUG : potato: md5 = c1b32b479ab2eea7dfea85f740fa1a7e OK 2025/05/02 03:01:13 DEBUG : empty space: md5 = ddf6af0aa7105a2a1f263808f2718584 OK 2025/05/02 03:01:18 DEBUG : Waiting for deletions to finish 2025/05/02 03:01:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:01:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:01:28 INFO : potato: Deleted 2025/05/02 03:01:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:01:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:01:29 DEBUG : empty space: Unchanged skipping 2025/05/02 03:01:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:01:34 DEBUG : potato2: md5 = 6d1c4e3437be18fb24bef8f627e80b65 OK 2025/05/02 03:01:34 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (37.51s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:01:50 DEBUG : potato: md5 = 2299a5715ac72875c20247ccaa0d08aa OK 2025/05/02 03:01:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:01:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:01:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:02:01 DEBUG : potato2: md5 = eb3001b9ae7079a425d6cb74ae96681b OK 2025/05/02 03:02:01 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (32.71s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:02:23 DEBUG : potato2: md5 = 0c722b0a8dc3b53fd8989907b9362126 OK 2025/05/02 03:02:23 DEBUG : empty space: md5 = 5551ba178926e2cf3d500f50475ba17b OK 2025/05/02 03:02:28 DEBUG : enormous: Excluded (Size Filter) 2025/05/02 03:02:28 DEBUG : enormous: Excluded 2025/05/02 03:02:28 DEBUG : potato2: Excluded (Size Filter) 2025/05/02 03:02:28 DEBUG : potato2: Excluded 2025/05/02 03:02:29 DEBUG : potato2: Excluded (Size Filter) 2025/05/02 03:02:29 DEBUG : potato2: Excluded 2025/05/02 03:02:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:02:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:02:29 DEBUG : empty space: Unchanged skipping 2025/05/02 03:02:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:02:29 DEBUG : Waiting for deletions to finish 2025/05/02 03:02:29 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:02:34 DEBUG : enormous: Excluded (Size Filter) 2025/05/02 03:02:34 DEBUG : enormous: Excluded 2025/05/02 03:02:34 DEBUG : potato2: Excluded (Size Filter) 2025/05/02 03:02:34 DEBUG : potato2: Excluded 2025/05/02 03:02:34 DEBUG : potato2: Excluded (Size Filter) 2025/05/02 03:02:34 DEBUG : potato2: Excluded 2025/05/02 03:02:34 DEBUG : Local file system at /tmp/rclone802095826: Waiting for checks to finish 2025/05/02 03:02:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:02:34 DEBUG : empty space: Unchanged skipping 2025/05/02 03:02:34 DEBUG : Local file system at /tmp/rclone802095826: Waiting for transfers to finish 2025/05/02 03:02:34 DEBUG : Waiting for deletions to finish 2025/05/02 03:02:34 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncWithExclude (22.11s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:02:45 DEBUG : potato2: md5 = 02f5beb87e25e546d91536dac06b227f OK 2025/05/02 03:02:45 DEBUG : empty space: md5 = 4d91ad109f5c9bc85aadb95ae4b83f45 OK 2025/05/02 03:02:50 DEBUG : enormous: md5 = 738cab1b964b3d0f5a71e511ee14072a OK 2025/05/02 03:02:56 DEBUG : enormous: Excluded (Size Filter) 2025/05/02 03:02:56 DEBUG : enormous: Excluded 2025/05/02 03:02:56 DEBUG : potato2: Excluded (Size Filter) 2025/05/02 03:02:56 DEBUG : potato2: Excluded 2025/05/02 03:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:02:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:02:56 DEBUG : empty space: Unchanged skipping 2025/05/02 03:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:02:56 DEBUG : Waiting for deletions to finish 2025/05/02 03:03:01 INFO : potato2: Deleted 2025/05/02 03:03:01 INFO : enormous: Deleted 2025/05/02 03:03:01 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:03:01 DEBUG : Local file system at /tmp/rclone802095826: Waiting for checks to finish 2025/05/02 03:03:01 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:03:01 DEBUG : empty space: Unchanged skipping 2025/05/02 03:03:01 DEBUG : Local file system at /tmp/rclone802095826: Waiting for transfers to finish 2025/05/02 03:03:01 DEBUG : Waiting for deletions to finish 2025/05/02 03:03:01 INFO : enormous: Deleted 2025/05/02 03:03:01 INFO : potato2: Deleted 2025/05/02 03:03:01 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (22.38s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:03:07 DEBUG : one: md5 = c71e6d5bb90213b7e1ab8867d6e3feec OK 2025/05/02 03:03:13 DEBUG : two: md5 = 65b2c470e300a81f70b49139b21e395a OK 2025/05/02 03:03:18 DEBUG : three: md5 = 76569fc40c27da88162070784c2256aa OK 2025/05/02 03:03:23 DEBUG : four: md5 = 2d7e3aeab968f2c188a9c84a2167aff9 OK 2025/05/02 03:03:34 DEBUG : five: Need to transfer - File not found at Destination 2025/05/02 03:03:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:03:34 DEBUG : one: Destination is newer than source, skipping 2025/05/02 03:03:34 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/05/02 03:03:34 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/05/02 03:03:34 DEBUG : three: Sizes identical 2025/05/02 03:03:34 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/05/02 03:03:34 DEBUG : five: md5 = 1ce86a918952caf83f520aef2984e8b0 OK 2025/05/02 03:03:34 INFO : five: Copied (new) 2025/05/02 03:03:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:03:39 DEBUG : four: md5 = 205874c3afd8635299d2d753c113e468 OK 2025/05/02 03:03:39 INFO : four: Copied (replaced existing) 2025/05/02 03:03:44 DEBUG : two: md5 = de75384be1d8e5fe67bb8e2a06cef541 OK 2025/05/02 03:03:44 INFO : two: Copied (replaced existing) 2025/05/02 03:03:44 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (73.30s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:1538: Can track renames: false 2025/05/02 03:04:15 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Ignoring --track-renames as the source and destination do not have a common hash 2025/05/02 03:04:15 DEBUG : potato: Need to transfer - File not found at Destination 2025/05/02 03:04:15 DEBUG : yam: Need to transfer - File not found at Destination 2025/05/02 03:04:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:04:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:04:16 DEBUG : yam: md5 = ca08065bf69e02fe02ec3aa14ad8566a OK 2025/05/02 03:04:16 INFO : yam: Copied (new) 2025/05/02 03:04:21 DEBUG : potato: md5 = f68611bbdb6d9c3eacf39243964ce6f7 OK 2025/05/02 03:04:21 INFO : potato: Copied (new) 2025/05/02 03:04:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:04:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Ignoring --track-renames as the source and destination do not have a common hash 2025/05/02 03:04:21 DEBUG : yaml: Need to transfer - File not found at Destination 2025/05/02 03:04:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:04:26 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:04:26 DEBUG : potato: Unchanged skipping 2025/05/02 03:04:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:04:27 DEBUG : yaml: md5 = 546ee31856c7fe75e983052d1e14e01c OK 2025/05/02 03:04:27 INFO : yaml: Copied (new) 2025/05/02 03:04:27 DEBUG : Waiting for deletions to finish 2025/05/02 03:04:27 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (33.26s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/05/02 03:04:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Making map for --track-renames 2025/05/02 03:04:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Finished making map for --track-renames 2025/05/02 03:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:04:49 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/05/02 03:04:49 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/05/02 03:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for renames to finish 2025/05/02 03:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:04:49 DEBUG : yam: md5 = 306a94fb1d78fcdb8616795d7679024e OK 2025/05/02 03:04:49 INFO : yam: Copied (new) 2025/05/02 03:04:54 DEBUG : potato: md5 = 914250eccceea3d3d7fa96ed416f89d6 OK 2025/05/02 03:04:54 INFO : potato: Copied (new) 2025/05/02 03:04:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:04:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Making map for --track-renames 2025/05/02 03:04:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Finished making map for --track-renames 2025/05/02 03:04:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:04:54 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:04:54 DEBUG : potato: Unchanged skipping 2025/05/02 03:04:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for renames to finish 2025/05/02 03:05:10 INFO : yam: Copied (server-side copy) to: yaml 2025/05/02 03:05:10 INFO : yam: Deleted 2025/05/02 03:05:10 INFO : yaml: Renamed from "yam" 2025/05/02 03:05:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:05:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (37.34s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:1649: Can track renames: true 2025/05/02 03:05:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Making map for --track-renames 2025/05/02 03:05:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Finished making map for --track-renames 2025/05/02 03:05:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:05:26 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/05/02 03:05:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for renames to finish 2025/05/02 03:05:26 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/05/02 03:05:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:05:31 DEBUG : sub/yam: md5 = 15cf09f79e296716e297d922598196ab OK 2025/05/02 03:05:31 DEBUG : potato: md5 = 0c1e8df38b24a0850dc412276c5573bd OK 2025/05/02 03:05:31 INFO : potato: Copied (new) 2025/05/02 03:05:31 INFO : sub/yam: Copied (new) 2025/05/02 03:05:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:05:32 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:05:32 DEBUG : potato: Unchanged skipping 2025/05/02 03:05:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Making map for --track-renames 2025/05/02 03:05:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Finished making map for --track-renames 2025/05/02 03:05:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:05:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for renames to finish 2025/05/02 03:05:42 INFO : sub/yam: Copied (server-side copy) to: yam 2025/05/02 03:05:47 INFO : sub/yam: Deleted 2025/05/02 03:05:47 INFO : yam: Renamed from "sub/yam" 2025/05/02 03:05:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:05:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (27.93s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:05:54 DEBUG : Creating backend with remote "/tmp/rclone802095826/dir1" 2025/05/02 03:05:54 DEBUG : Config file has changed externally - reloading 2025/05/02 03:05:54 DEBUG : Creating backend with remote "/tmp/rclone802095826/dir2" 2025/05/02 03:05:54 DEBUG : Local file system at /tmp/rclone802095826/dir2: Using server-side directory move 2025/05/02 03:05:54 INFO : Local file system at /tmp/rclone802095826/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/05/02 03:05:54 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/02 03:05:54 DEBUG : Local file system at /tmp/rclone802095826/dir2: Waiting for checks to finish 2025/05/02 03:05:54 INFO : file1.txt: Moved (server-side) 2025/05/02 03:05:54 DEBUG : Local file system at /tmp/rclone802095826/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.11s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:05:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 03:05:54 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/05/02 03:05:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:05:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:05:59 DEBUG : sub dir/hello world: md5 = faf8ee1e0e9c169b641c4f80bc406c01 OK 2025/05/02 03:05:59 INFO : sub dir/hello world: Copied (new) 2025/05/02 03:05:59 INFO : sub dir/hello world: Deleted 2025/05/02 03:05:59 DEBUG : nested/sub dir/file: md5 = 377f8f8929048acc5d88912582f65164 OK 2025/05/02 03:05:59 INFO : nested/sub dir/file: Copied (new) 2025/05/02 03:05:59 INFO : nested/sub dir/file: Deleted 2025/05/02 03:05:59 INFO : sub dir: Removing directory 2025/05/02 03:05:59 INFO : nested/sub dir: Removing directory 2025/05/02 03:05:59 INFO : nested: Removing directory 2025/05/02 03:05:59 DEBUG : Local file system at /tmp/rclone802095826: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (16.52s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:06:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/02 03:06:11 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/05/02 03:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:06:16 DEBUG : sub dir/hello world: md5 = bddcff194e4163a52d446c150acb8416 OK 2025/05/02 03:06:16 INFO : sub dir/hello world: Copied (new) 2025/05/02 03:06:16 INFO : sub dir/hello world: Deleted 2025/05/02 03:06:16 DEBUG : nested/sub dir/file: md5 = 2f8a4446c0953dd8bdd670995b4f84ad OK 2025/05/02 03:06:16 INFO : nested/sub dir/file: Copied (new) 2025/05/02 03:06:16 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (21.60s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:06:32 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/02 03:06:32 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/05/02 03:06:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:06:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:06:37 DEBUG : existing: md5 = 456683a7e375c181f22783c431245ea7 OK 2025/05/02 03:06:37 INFO : existing: Copied (new) 2025/05/02 03:06:37 INFO : existing: Deleted 2025/05/02 03:06:37 DEBUG : existing-b: md5 = cbedbf594e082b1b9c9369d7085d5c16 OK 2025/05/02 03:06:37 INFO : existing-b: Copied (new) 2025/05/02 03:06:37 INFO : existing-b: Deleted 2025/05/02 03:06:38 DEBUG : existing: Destination exists, skipping 2025/05/02 03:06:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:06:38 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/05/02 03:06:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:06:38 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (21.94s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:06:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vifumot8qido" 2025/05/02 03:06:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vifumot8qido.bin" 2025/05/02 03:06:55 DEBUG : Swift container crypt path rclone-test-vifumot8qido.bin: Auto set use_segments_container to true 2025/05/02 03:07:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vifumot8qido" 2025/05/02 03:07:01 DEBUG : Swift container crypt path rclone-test-vifumot8qido: Auto set use_segments_container to true 2025/05/02 03:07:11 DEBUG : potato2: md5 = 25b021eaf85be8f2c170fe6280fcc3dc OK 2025/05/02 03:07:11 DEBUG : empty space: md5 = 5407a447da62da5283d6ed22b61e9b0f OK 2025/05/02 03:07:16 DEBUG : potato3: md5 = 92c1c696b755e7e21655071f2a283b62 OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-vifumot8qido' 2025/05/02 03:07:22 DEBUG : empty space: md5 = 781900309db424dd6fd8c3496599e739 OK 2025/05/02 03:07:23 DEBUG : potato3: md5 = 4ad7661c1badf8a2695f2fc72cb14989 OK 2025/05/02 03:07:23 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:07:23 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/02 03:07:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vifumot8qido': Waiting for checks to finish 2025/05/02 03:07:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:07:23 DEBUG : empty space: Unchanged skipping 2025/05/02 03:07:23 INFO : empty space: Deleted 2025/05/02 03:07:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vifumot8qido': Waiting for transfers to finish 2025/05/02 03:07:33 INFO : potato3: Copied (server-side copy) 2025/05/02 03:07:38 INFO : potato3: Deleted 2025/05/02 03:07:38 INFO : potato2: Copied (server-side copy) 2025/05/02 03:07:44 INFO : potato2: Deleted 2025/05/02 03:07:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sawiday1wufi" 2025/05/02 03:07:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sawiday1wufi.bin" 2025/05/02 03:07:50 DEBUG : Swift container crypt path rclone-test-sawiday1wufi.bin: Auto set use_segments_container to true 2025/05/02 03:07:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sawiday1wufi" 2025/05/02 03:07:55 DEBUG : Swift container crypt path rclone-test-sawiday1wufi: Auto set use_segments_container to true 2025/05/02 03:08:01 DEBUG : empty space: Need to transfer - File not found at Destination 2025/05/02 03:08:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:08:01 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/02 03:08:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sawiday1wufi': Waiting for checks to finish 2025/05/02 03:08:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sawiday1wufi': Waiting for transfers to finish 2025/05/02 03:08:06 INFO : empty space: Copied (server-side copy) 2025/05/02 03:08:06 INFO : empty space: Deleted 2025/05/02 03:08:12 INFO : potato2: Copied (server-side copy) 2025/05/02 03:08:16 INFO : potato3: Copied (server-side copy) 2025/05/02 03:08:17 INFO : potato3: Deleted 2025/05/02 03:08:17 INFO : potato2: Deleted 2025/05/02 03:08:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sawiday1wufi': Purge remote 2025/05/02 03:08:22 DEBUG : Waiting for deletions to finish 2025/05/02 03:08:23 INFO : empty space.bin: Deleted 2025/05/02 03:08:33 INFO : potato3.bin: Deleted 2025/05/02 03:08:33 INFO : potato2.bin: Deleted 2025/05/02 03:08:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vifumot8qido': Purge remote 2025/05/02 03:08:33 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (99.14s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:08:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-junicoj3fiye" 2025/05/02 03:08:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-junicoj3fiye.bin" 2025/05/02 03:08:34 DEBUG : Swift container crypt path rclone-test-junicoj3fiye.bin: Auto set use_segments_container to true 2025/05/02 03:08:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-junicoj3fiye" 2025/05/02 03:08:40 DEBUG : Swift container crypt path rclone-test-junicoj3fiye: Auto set use_segments_container to true 2025/05/02 03:08:50 DEBUG : potato2: md5 = 6ed784e5d0e627c12befa09b99bab5dc OK 2025/05/02 03:08:51 DEBUG : empty space: md5 = f92df5e1b072ad077c89c045bb88aee0 OK 2025/05/02 03:08:56 DEBUG : potato3: md5 = 4728ead095503ad0c8f4d9802edb694d OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-junicoj3fiye' 2025/05/02 03:09:06 DEBUG : empty space: md5 = 0d757bfd84923b1b2c8034c20a6bcd05 OK 2025/05/02 03:09:12 DEBUG : potato3: md5 = 02a090b62eeaf657d47d8ac2a8f61215 OK 2025/05/02 03:09:12 DEBUG : empty space: Excluded (Size Filter) 2025/05/02 03:09:12 DEBUG : empty space: Excluded 2025/05/02 03:09:12 DEBUG : empty space: Excluded (Size Filter) 2025/05/02 03:09:12 DEBUG : empty space: Excluded 2025/05/02 03:09:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:09:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-junicoj3fiye': Waiting for checks to finish 2025/05/02 03:09:12 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/02 03:09:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-junicoj3fiye': Waiting for transfers to finish 2025/05/02 03:09:23 INFO : potato3: Copied (server-side copy) 2025/05/02 03:09:28 INFO : potato3: Deleted 2025/05/02 03:09:33 INFO : potato2: Copied (server-side copy) 2025/05/02 03:09:38 INFO : potato2: Deleted 2025/05/02 03:09:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yanovof4zeqi" 2025/05/02 03:09:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yanovof4zeqi.bin" 2025/05/02 03:09:39 DEBUG : Swift container crypt path rclone-test-yanovof4zeqi.bin: Auto set use_segments_container to true 2025/05/02 03:09:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yanovof4zeqi" 2025/05/02 03:09:45 DEBUG : Swift container crypt path rclone-test-yanovof4zeqi: Auto set use_segments_container to true 2025/05/02 03:09:50 DEBUG : empty space: Excluded (Size Filter) 2025/05/02 03:09:50 DEBUG : empty space: Excluded 2025/05/02 03:09:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:09:50 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/02 03:09:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yanovof4zeqi': Waiting for checks to finish 2025/05/02 03:09:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yanovof4zeqi': Waiting for transfers to finish 2025/05/02 03:10:06 INFO : potato3: Copied (server-side copy) 2025/05/02 03:10:06 INFO : potato2: Copied (server-side copy) 2025/05/02 03:10:11 INFO : potato3: Deleted 2025/05/02 03:10:11 INFO : potato2: Deleted 2025/05/02 03:10:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yanovof4zeqi': Purge remote 2025/05/02 03:10:17 DEBUG : Waiting for deletions to finish 2025/05/02 03:10:22 INFO : potato3.bin: Deleted 2025/05/02 03:10:22 INFO : potato2.bin: Deleted 2025/05/02 03:10:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-junicoj3fiye': Purge remote 2025/05/02 03:10:22 DEBUG : Waiting for deletions to finish 2025/05/02 03:10:22 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (109.75s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:10:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-larodes8miqa" 2025/05/02 03:10:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-larodes8miqa.bin" 2025/05/02 03:10:24 DEBUG : Swift container crypt path rclone-test-larodes8miqa.bin: Auto set use_segments_container to true 2025/05/02 03:10:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-larodes8miqa" 2025/05/02 03:10:29 DEBUG : Swift container crypt path rclone-test-larodes8miqa: Auto set use_segments_container to true 2025/05/02 03:10:40 DEBUG : potato2: md5 = d7f9e02a1c43648f9db619dcd0fa6876 OK 2025/05/02 03:10:40 DEBUG : empty space: md5 = 11c255cda438297064237fcf45762589 OK 2025/05/02 03:10:45 DEBUG : potato3: md5 = c562b70e1d9587ff9f3369335c923985 OK 2025/05/02 03:10:45 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu' -> Encrypted drive 'TestCryptSwift:rclone-test-larodes8miqa' 2025/05/02 03:10:52 DEBUG : empty space: md5 = 4af30e8403fcd8409b0466b8393ae89b OK 2025/05/02 03:10:57 DEBUG : potato3: md5 = b23f423030fce3c54cbbd7e0ac405d7a OK 2025/05/02 03:11:03 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:11:03 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/02 03:11:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-larodes8miqa': Waiting for checks to finish 2025/05/02 03:11:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:11:03 DEBUG : empty space: Unchanged skipping 2025/05/02 03:11:03 INFO : empty space: Deleted 2025/05/02 03:11:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-larodes8miqa': Waiting for transfers to finish 2025/05/02 03:11:08 INFO : potato2: Copied (server-side copy) 2025/05/02 03:11:13 INFO : potato2: Deleted 2025/05/02 03:11:18 INFO : potato3: Copied (server-side copy) 2025/05/02 03:11:23 INFO : potato3: Deleted 2025/05/02 03:11:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-galolel1siso" 2025/05/02 03:11:24 DEBUG : Config file has changed externally - reloading 2025/05/02 03:11:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-galolel1siso.bin" 2025/05/02 03:11:24 DEBUG : Swift container crypt path rclone-test-galolel1siso.bin: Auto set use_segments_container to true 2025/05/02 03:11:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-galolel1siso" 2025/05/02 03:11:30 DEBUG : Swift container crypt path rclone-test-galolel1siso: Auto set use_segments_container to true 2025/05/02 03:11:36 INFO : tomatoDir: Making directory 2025/05/02 03:11:36 DEBUG : empty space: Need to transfer - File not found at Destination 2025/05/02 03:11:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/02 03:11:36 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/02 03:11:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galolel1siso': Waiting for checks to finish 2025/05/02 03:11:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galolel1siso': Waiting for transfers to finish 2025/05/02 03:11:41 INFO : potato2: Copied (server-side copy) 2025/05/02 03:11:42 INFO : potato2: Deleted 2025/05/02 03:11:46 INFO : potato3: Copied (server-side copy) 2025/05/02 03:11:46 INFO : empty space: Copied (server-side copy) 2025/05/02 03:11:47 INFO : empty space: Deleted 2025/05/02 03:11:51 INFO : potato3: Deleted 2025/05/02 03:11:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galolel1siso': Purge remote 2025/05/02 03:11:52 DEBUG : Waiting for deletions to finish 2025/05/02 03:11:52 INFO : potato3.bin: Deleted 2025/05/02 03:11:52 INFO : potato2.bin: Deleted 2025/05/02 03:11:57 INFO : empty space.bin: Deleted 2025/05/02 03:11:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-larodes8miqa': Purge remote 2025/05/02 03:11:57 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (95.00s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:11:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/rclone-move-test" 2025/05/02 03:11:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-move-test.bin" 2025/05/02 03:11:59 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-move-test.bin: Auto set use_segments_container to true 2025/05/02 03:12:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-move-test" 2025/05/02 03:12:04 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-move-test: Auto set use_segments_container to true 2025/05/02 03:12:15 DEBUG : potato2: md5 = c91a2c7313586d62eccaabdebb537dda OK --- PASS: TestServerSideMoveOverlap (28.16s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:12:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test" 2025/05/02 03:12:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test.bin" 2025/05/02 03:12:27 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/05/02 03:12:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test" 2025/05/02 03:12:33 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncOverlap (12.87s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:12:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test" 2025/05/02 03:12:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test.bin" 2025/05/02 03:12:40 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/05/02 03:12:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test" 2025/05/02 03:12:45 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test: Auto set use_segments_container to true 2025/05/02 03:12:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-include/layer2" 2025/05/02 03:12:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test-include/layer2.bin" 2025/05/02 03:12:51 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/05/02 03:12:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test-include/layer2" 2025/05/02 03:12:57 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/05/02 03:13:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-ignore-file" 2025/05/02 03:13:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test-ignore-file.bin" 2025/05/02 03:13:03 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/05/02 03:13:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/rclone-sync-test-ignore-file" 2025/05/02 03:13:09 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/05/02 03:13:20 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = b2b3e42d54d455281160f5c7ba8fb650 OK 2025/05/02 03:13:25 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/02 03:13:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test': Waiting for checks to finish 2025/05/02 03:13:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test': Waiting for transfers to finish 2025/05/02 03:13:25 DEBUG : Waiting for deletions to finish 2025/05/02 03:13:25 INFO : There was nothing to transfer 2025/05/02 03:13:36 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/02 03:13:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:13:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:13:36 DEBUG : Waiting for deletions to finish 2025/05/02 03:13:36 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:13:41 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/02 03:13:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/05/02 03:13:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/05/02 03:13:41 DEBUG : Waiting for deletions to finish 2025/05/02 03:13:41 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:13:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/02 03:13:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:13:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:13:46 DEBUG : Waiting for deletions to finish 2025/05/02 03:13:46 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:13:51 DEBUG : : Excluded 2025/05/02 03:13:56 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/02 03:13:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/05/02 03:13:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/05/02 03:13:56 DEBUG : Waiting for deletions to finish 2025/05/02 03:13:56 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (85.98s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:14:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:14:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:14:06 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:14:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:14:11 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:14:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/CompareDest" 2025/05/02 03:14:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/CompareDest.bin" 2025/05/02 03:14:17 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/CompareDest.bin: Auto set use_segments_container to true 2025/05/02 03:14:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/CompareDest" 2025/05/02 03:14:23 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/CompareDest: Auto set use_segments_container to true 2025/05/02 03:14:34 DEBUG : one: Need to transfer - File not found at Destination 2025/05/02 03:14:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:14:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:14:39 DEBUG : one: md5 = 03f2f469ab49f36f292a260a90996773 OK 2025/05/02 03:14:39 INFO : one: Copied (new) 2025/05/02 03:14:39 DEBUG : Waiting for deletions to finish 2025/05/02 03:14:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:14:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:14:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:14:55 DEBUG : one: md5 = 2fb42a6c9cc05bcd240d5112510ee5a5 OK 2025/05/02 03:14:55 INFO : one: Copied (replaced existing) 2025/05/02 03:14:55 DEBUG : Waiting for deletions to finish 2025/05/02 03:15:00 DEBUG : dst/one: md5 = 96363b75013bd171ae14181a26b1297e OK 2025/05/02 03:15:00 DEBUG : CompareDest/one: md5 = c9812b698e8cdb8125078fef3fbe8189 OK 2025/05/02 03:15:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:15:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:15:01 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:01 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/02 03:15:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:15:01 DEBUG : Waiting for deletions to finish 2025/05/02 03:15:01 INFO : There was nothing to transfer 2025/05/02 03:15:11 DEBUG : CompareDest/two: md5 = 18a1208ad0087e272509933237c29bd0 OK 2025/05/02 03:15:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:15:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:12 DEBUG : two: Destination found in --compare-dest, skipping 2025/05/02 03:15:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:15:12 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:12 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/02 03:15:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:15:12 DEBUG : Waiting for deletions to finish 2025/05/02 03:15:12 INFO : There was nothing to transfer 2025/05/02 03:15:17 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:15:17 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:17 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/02 03:15:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:22 DEBUG : two: Destination found in --compare-dest, skipping 2025/05/02 03:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:15:22 DEBUG : Waiting for deletions to finish 2025/05/02 03:15:22 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/05/02 03:15:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:15:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:15:38 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/02 03:15:38 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/05/02 03:15:38 DEBUG : two: Need to transfer - File not found at Destination 2025/05/02 03:15:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:15:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:15:43 DEBUG : two: md5 = e34e9613206e87798ff94c177da06365 OK 2025/05/02 03:15:43 INFO : two: Copied (new) 2025/05/02 03:15:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (129.39s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:16:20 DEBUG : pre-dest1/1: md5 = 60150049665f7f79ea6e2f41cb57960d OK 2025/05/02 03:16:25 DEBUG : pre-dest2/2: md5 = c88f3f3d7e08fb8eb76d9cf8d6b2fd1a OK 2025/05/02 03:16:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dest" 2025/05/02 03:16:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dest.bin" 2025/05/02 03:16:31 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dest.bin: Auto set use_segments_container to true 2025/05/02 03:16:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dest" 2025/05/02 03:16:37 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dest: Auto set use_segments_container to true 2025/05/02 03:16:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/pre-dest1" 2025/05/02 03:16:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/pre-dest1.bin" 2025/05/02 03:16:43 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/pre-dest1.bin: Auto set use_segments_container to true 2025/05/02 03:16:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/pre-dest1" 2025/05/02 03:16:49 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/pre-dest1: Auto set use_segments_container to true 2025/05/02 03:16:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/pre-dest2" 2025/05/02 03:16:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/pre-dest2.bin" 2025/05/02 03:16:55 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/pre-dest2.bin: Auto set use_segments_container to true 2025/05/02 03:17:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/pre-dest2" 2025/05/02 03:17:00 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/pre-dest2: Auto set use_segments_container to true 2025/05/02 03:17:06 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:17:06 DEBUG : 1: Destination found in --compare-dest, skipping 2025/05/02 03:17:16 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:17:16 DEBUG : 2: Destination found in --compare-dest, skipping 2025/05/02 03:17:27 DEBUG : 3: Need to transfer - File not found at Destination 2025/05/02 03:17:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dest': Waiting for checks to finish 2025/05/02 03:17:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dest': Waiting for transfers to finish 2025/05/02 03:17:32 DEBUG : 3: md5 = db195a383754645944de2cfe29f153a5 OK 2025/05/02 03:17:32 INFO : 3: Copied (new) 2025/05/02 03:17:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (108.50s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:18:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:18:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:18:03 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:18:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:18:09 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:18:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/CopyDest" 2025/05/02 03:18:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/CopyDest.bin" 2025/05/02 03:18:15 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/CopyDest.bin: Auto set use_segments_container to true 2025/05/02 03:18:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/CopyDest" 2025/05/02 03:18:21 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/CopyDest: Auto set use_segments_container to true 2025/05/02 03:18:31 DEBUG : one: Need to transfer - File not found at Destination 2025/05/02 03:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:18:36 DEBUG : one: md5 = a73170a86957bf923945a87f9ac41a06 OK 2025/05/02 03:18:36 INFO : one: Copied (new) 2025/05/02 03:18:36 DEBUG : Waiting for deletions to finish 2025/05/02 03:18:47 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:18:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:18:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:18:57 DEBUG : one: md5 = 8f400a83a880a7d316de28e3de39e058 OK 2025/05/02 03:18:57 INFO : one: Copied (replaced existing) 2025/05/02 03:18:57 DEBUG : Waiting for deletions to finish 2025/05/02 03:19:08 DEBUG : dst/one: md5 = 2f23216b8f3d72945db47148d1fad877 OK 2025/05/02 03:19:08 DEBUG : CopyDest/one: md5 = cbb4f6c156d732cba3663f888a0b6e6c OK 2025/05/02 03:19:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/BackupDir" 2025/05/02 03:19:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/BackupDir.bin" 2025/05/02 03:19:09 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/BackupDir.bin: Auto set use_segments_container to true 2025/05/02 03:19:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/BackupDir" 2025/05/02 03:19:15 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/BackupDir: Auto set use_segments_container to true 2025/05/02 03:19:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:19:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:19:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:19:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/02 03:19:31 INFO : one: Copied (server-side copy) 2025/05/02 03:19:36 INFO : one: Deleted 2025/05/02 03:19:46 INFO : one: Copied (server-side copy) 2025/05/02 03:19:46 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/05/02 03:19:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:19:46 DEBUG : Waiting for deletions to finish 2025/05/02 03:19:52 DEBUG : CopyDest/two: md5 = d3d529aa1583cda0fb78cb53057377df OK 2025/05/02 03:19:53 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:19:53 DEBUG : one: Unchanged skipping 2025/05/02 03:19:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:20:08 INFO : two: Copied (server-side copy) 2025/05/02 03:20:08 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/05/02 03:20:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:20:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:20:08 DEBUG : Waiting for deletions to finish 2025/05/02 03:20:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:20:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:20:08 DEBUG : two: Unchanged skipping 2025/05/02 03:20:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:20:13 DEBUG : one: Unchanged skipping 2025/05/02 03:20:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:20:13 DEBUG : Waiting for deletions to finish 2025/05/02 03:20:13 INFO : There was nothing to transfer 2025/05/02 03:20:24 DEBUG : CopyDest/three: md5 = 673d7c351c1a52434c5c4b79056b972b OK 2025/05/02 03:20:29 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/05/02 03:20:29 DEBUG : three: Destination not found in --copy-dest 2025/05/02 03:20:29 DEBUG : three: Need to transfer - File not found at Destination 2025/05/02 03:20:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:20:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:20:30 DEBUG : two: Unchanged skipping 2025/05/02 03:20:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:20:34 DEBUG : one: Unchanged skipping 2025/05/02 03:20:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:20:35 DEBUG : three: md5 = b00481f88e057d93adab9cd71e758896 OK 2025/05/02 03:20:35 INFO : three: Copied (new) 2025/05/02 03:20:35 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (173.98s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:21:02 DEBUG : dst/one: md5 = 79875a8ca7a7ffa14f168fe3b82315ae OK 2025/05/02 03:21:07 DEBUG : dst/two: md5 = 778090bea81ced78249e1e2d45d18535 OK 2025/05/02 03:21:13 DEBUG : dst/three.txt: md5 = 330980bb80ff9132a036ac814485002d OK 2025/05/02 03:21:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:21:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:21:19 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:21:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:21:24 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:21:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/backup" 2025/05/02 03:21:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup.bin" 2025/05/02 03:21:30 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup.bin: Auto set use_segments_container to true 2025/05/02 03:21:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup" 2025/05/02 03:21:36 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup: Auto set use_segments_container to true 2025/05/02 03:21:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:21:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:21:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:21:42 DEBUG : two: Unchanged skipping 2025/05/02 03:21:52 INFO : one: Copied (server-side copy) 2025/05/02 03:21:57 INFO : one: Deleted 2025/05/02 03:21:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:22:03 DEBUG : one: md5 = c02df6cb01cbde3565cc845df180d067 OK 2025/05/02 03:22:03 INFO : one: Copied (new) 2025/05/02 03:22:03 DEBUG : Waiting for deletions to finish 2025/05/02 03:22:18 INFO : three.txt: Copied (server-side copy) 2025/05/02 03:22:23 INFO : three.txt: Deleted 2025/05/02 03:22:23 INFO : three.txt: Moved into backup dir 2025/05/02 03:22:39 DEBUG : dst/three.txt: md5 = b41b3b69c399a96964e54a79261b0a67 OK 2025/05/02 03:22:49 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:22:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:22:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:22:54 DEBUG : two: Unchanged skipping 2025/05/02 03:22:55 INFO : one: Copied (server-side copy) 2025/05/02 03:23:00 INFO : one: Deleted 2025/05/02 03:23:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:23:05 DEBUG : one: md5 = c5404b4db7d3d13fbaeb78c12a4ac391 OK 2025/05/02 03:23:05 INFO : one: Copied (new) 2025/05/02 03:23:05 DEBUG : Waiting for deletions to finish 2025/05/02 03:23:15 INFO : three.txt: Copied (server-side copy) 2025/05/02 03:23:20 INFO : three.txt: Deleted 2025/05/02 03:23:20 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (165.24s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:23:47 DEBUG : dst/one: md5 = f949c7ded000695c5971e6c103631417 OK 2025/05/02 03:23:52 DEBUG : dst/two: md5 = 64de4c61c24f5bfd26a02dd65b331a4d OK 2025/05/02 03:23:58 DEBUG : dst/three.txt: md5 = 7cd17ceb5166d178f2b8521758585d6b OK 2025/05/02 03:23:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:23:58 DEBUG : Config file has changed externally - reloading 2025/05/02 03:23:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:23:59 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:24:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:24:04 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:24:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/backup" 2025/05/02 03:24:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup.bin" 2025/05/02 03:24:10 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup.bin: Auto set use_segments_container to true 2025/05/02 03:24:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup" 2025/05/02 03:24:16 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup: Auto set use_segments_container to true 2025/05/02 03:24:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:24:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:24:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:24:26 DEBUG : two: Unchanged skipping 2025/05/02 03:24:42 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:24:47 INFO : one: Deleted 2025/05/02 03:24:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:24:52 DEBUG : one: md5 = 475196f50afdcc81b29e92a07fdcdbb3 OK 2025/05/02 03:24:52 INFO : one: Copied (new) 2025/05/02 03:24:52 DEBUG : Waiting for deletions to finish 2025/05/02 03:25:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:25:13 INFO : three.txt: Deleted 2025/05/02 03:25:13 INFO : three.txt: Moved into backup dir 2025/05/02 03:25:29 DEBUG : dst/three.txt: md5 = ef66ce94ff9c6659890a2b8ec7ed2f94 OK 2025/05/02 03:25:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:25:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:25:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:25:34 DEBUG : two: Unchanged skipping 2025/05/02 03:25:44 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:25:50 INFO : one: Deleted 2025/05/02 03:25:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:25:55 DEBUG : one: md5 = bad6b7b2bacdba180bfa25a74485dc4a OK 2025/05/02 03:25:55 INFO : one: Copied (new) 2025/05/02 03:25:55 DEBUG : Waiting for deletions to finish 2025/05/02 03:26:00 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:26:05 INFO : three.txt: Deleted 2025/05/02 03:26:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (164.29s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:26:31 DEBUG : dst/one: md5 = c42b472a8548d5e41b9834f8c92480b4 OK 2025/05/02 03:26:37 DEBUG : dst/two: md5 = 72b6ebc9d9cc4452fd4329476db83f5b OK 2025/05/02 03:26:42 DEBUG : dst/three.txt: md5 = d6a75747d9a860aa70a9d41845393dec OK 2025/05/02 03:26:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:26:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:26:53 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:26:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:26:58 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:27:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/backup" 2025/05/02 03:27:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup.bin" 2025/05/02 03:27:04 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup.bin: Auto set use_segments_container to true 2025/05/02 03:27:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/backup" 2025/05/02 03:27:10 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/backup: Auto set use_segments_container to true 2025/05/02 03:27:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:27:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:27:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:27:15 DEBUG : two: Unchanged skipping 2025/05/02 03:27:36 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/02 03:27:41 INFO : one: Deleted 2025/05/02 03:27:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:27:46 DEBUG : one: md5 = 406469315fea3364e4b46dc3af8335d7 OK 2025/05/02 03:27:46 INFO : one: Copied (new) 2025/05/02 03:27:46 DEBUG : Waiting for deletions to finish 2025/05/02 03:27:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/02 03:28:02 INFO : three.txt: Deleted 2025/05/02 03:28:02 INFO : three.txt: Moved into backup dir 2025/05/02 03:28:12 DEBUG : dst/three.txt: md5 = 34576b3e454bac8d518b25e1ff8048bf OK 2025/05/02 03:28:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:28:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:28:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:28:23 DEBUG : two: Unchanged skipping 2025/05/02 03:28:38 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/02 03:28:43 INFO : one: Deleted 2025/05/02 03:28:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:28:49 DEBUG : one: md5 = 5c9b39ccd8a6be6e82b7b731befc73f4 OK 2025/05/02 03:28:49 INFO : one: Copied (new) 2025/05/02 03:28:49 DEBUG : Waiting for deletions to finish 2025/05/02 03:28:54 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/02 03:28:59 INFO : three.txt: Deleted 2025/05/02 03:28:59 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (179.08s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:29:31 DEBUG : dst/one: md5 = c2e61a2daf183d6c5163b07772ebfda2 OK 2025/05/02 03:29:36 DEBUG : dst/two: md5 = 4ddbd54ee69f77b0ea78404a79507b82 OK 2025/05/02 03:29:41 DEBUG : dst/three.txt: md5 = be1fb75bc068a83fc3011b0726e2baa6 OK 2025/05/02 03:29:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:29:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:29:42 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:29:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:29:47 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:29:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:29:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:29:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:29:58 DEBUG : two: Unchanged skipping 2025/05/02 03:30:14 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:30:19 INFO : one: Deleted 2025/05/02 03:30:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:30:24 DEBUG : one: md5 = 0825cce98ee50a012eace920baf1908f OK 2025/05/02 03:30:24 INFO : one: Copied (new) 2025/05/02 03:30:24 DEBUG : Waiting for deletions to finish 2025/05/02 03:30:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:30:40 INFO : three.txt: Deleted 2025/05/02 03:30:40 INFO : three.txt: Moved into backup dir 2025/05/02 03:30:45 DEBUG : dst/three.txt: md5 = 8c6b76ea78f7e31db8753c8f9ba13022 OK 2025/05/02 03:30:51 DEBUG : one.bak: Excluded (Path Filter) 2025/05/02 03:30:51 DEBUG : one.bak: Excluded 2025/05/02 03:30:51 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/05/02 03:30:51 DEBUG : three.txt.bak: Excluded 2025/05/02 03:30:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:30:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for checks to finish 2025/05/02 03:30:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:30:51 DEBUG : two: Unchanged skipping 2025/05/02 03:31:01 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:31:06 INFO : one: Deleted 2025/05/02 03:31:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu/dst': Waiting for transfers to finish 2025/05/02 03:31:12 DEBUG : one: md5 = bac70a98988df361c3e4cab765731dc4 OK 2025/05/02 03:31:12 INFO : one: Copied (new) 2025/05/02 03:31:12 DEBUG : Waiting for deletions to finish 2025/05/02 03:31:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:31:27 INFO : three.txt: Deleted 2025/05/02 03:31:27 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (147.84s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:31:58 DEBUG : dst/one: md5 = b5b4dfdf9ae0e016973b229c38d9592b OK 2025/05/02 03:32:04 DEBUG : dst/two: md5 = d2d080298dc2a5ebba82e64c23b34c34 OK 2025/05/02 03:32:09 DEBUG : dst/three.txt: md5 = 90052eda3a4107173c772125d949db37 OK 2025/05/02 03:32:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:32:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:32:10 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:32:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:32:15 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:32:21 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:32:36 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:32:41 INFO : one: Deleted 2025/05/02 03:32:46 DEBUG : one: md5 = c6ef2b12218ae09bbd3fc40c158620bb OK 2025/05/02 03:32:46 INFO : one: Copied (new) 2025/05/02 03:32:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:32:46 DEBUG : two: Unchanged skipping 2025/05/02 03:32:51 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/05/02 03:33:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:33:07 INFO : three.txt: Deleted 2025/05/02 03:33:12 DEBUG : three.txt: md5 = 958b6a51c0f289eecc9a3d1d07d16b84 OK 2025/05/02 03:33:12 INFO : three.txt: Copied (new) 2025/05/02 03:33:33 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:33:43 INFO : one: Copied (server-side copy) to: one.bak 2025/05/02 03:33:48 INFO : one: Deleted 2025/05/02 03:33:53 DEBUG : one: md5 = 9a347ed694443582c16b3548d5217f1b OK 2025/05/02 03:33:53 INFO : one: Copied (new) 2025/05/02 03:33:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:33:59 DEBUG : two: Unchanged skipping 2025/05/02 03:34:04 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/05/02 03:34:09 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/02 03:34:14 INFO : three.txt: Deleted 2025/05/02 03:34:19 DEBUG : three.txt: md5 = 227b23dcde78b7de0319dd8cbf52175c OK 2025/05/02 03:34:19 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (177.61s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:34:56 DEBUG : dst/one: md5 = 287c9466511b999a133b03a412f225b2 OK 2025/05/02 03:35:01 DEBUG : dst/two: md5 = a98dac87f5d9de3cc57eb1168b415485 OK 2025/05/02 03:35:06 DEBUG : dst/three.txt: md5 = 76c60465dc0f4aba09b8a3e613b384f0 OK 2025/05/02 03:35:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mexajot9fitu/dst" 2025/05/02 03:35:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst.bin" 2025/05/02 03:35:07 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst.bin: Auto set use_segments_container to true 2025/05/02 03:35:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mexajot9fitu/dst" 2025/05/02 03:35:13 DEBUG : Swift container crypt path rclone-test-mexajot9fitu/dst: Auto set use_segments_container to true 2025/05/02 03:35:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/02 03:35:29 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/02 03:35:34 INFO : one: Deleted 2025/05/02 03:35:39 DEBUG : one: md5 = 8e6c3db0c6da801fe6894d0619780cb2 OK 2025/05/02 03:35:39 INFO : one: Copied (new) 2025/05/02 03:35:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:35:39 DEBUG : two: Unchanged skipping 2025/05/02 03:35:44 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/05/02 03:35:55 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/02 03:36:00 INFO : three.txt: Deleted 2025/05/02 03:36:05 DEBUG : three.txt: md5 = 8e266ea2b066ca3ba4b4bf34e8c53f9e OK 2025/05/02 03:36:05 INFO : three.txt: Copied (new) 2025/05/02 03:36:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/02 03:36:32 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/02 03:36:37 INFO : one: Deleted 2025/05/02 03:36:42 DEBUG : one: md5 = 5e19290d230c893b5cb88acf9f2008ad OK 2025/05/02 03:36:42 INFO : one: Copied (new) 2025/05/02 03:36:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:36:42 DEBUG : two: Unchanged skipping 2025/05/02 03:36:42 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/05/02 03:36:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/02 03:36:52 INFO : three.txt: Deleted 2025/05/02 03:36:57 DEBUG : three.txt: md5 = 70f88f86d5cf4ab1da5c63a7cc4e7295 OK 2025/05/02 03:36:57 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (152.89s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:37:29 DEBUG : Testêé: md5 = dbf1df9a683376b73ce53bb073908bd9 OK 2025/05/02 03:37:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:37:34 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/05/02 03:37:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:37:45 DEBUG : Testêé: md5 = 7951d1bfe9f3a1cc61b3f03aa1b5a54d OK 2025/05/02 03:37:45 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/05/02 03:37:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (26.47s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:37:51 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/02 03:37:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:37:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:37:56 DEBUG : existing: md5 = c0ac735c20d52c26be4688d69029d808 OK 2025/05/02 03:37:56 INFO : existing: Copied (new) 2025/05/02 03:37:56 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:37:56 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/05/02 03:37:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:37:56 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/05/02 03:37:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:37:56 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': not deleting files as there were IO errors 2025/05/02 03:37:56 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncImmutable (17.00s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:38:13 DEBUG : EXISTING: md5 = 876db44c5e8951d730cf07fcd74f66da OK 2025/05/02 03:38:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:38:18 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:38:18 DEBUG : existing: Unchanged skipping 2025/05/02 03:38:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:38:18 DEBUG : Waiting for deletions to finish 2025/05/02 03:38:18 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (16.46s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.20s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (0.49s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.29s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:38:30 DEBUG : both0: md5 = 333afa700c4a2f9dfae20cbfa4e8a90f OK 2025/05/02 03:38:35 DEBUG : only0: md5 = 865db4889c45ba1bb087bb6610742dbb OK 2025/05/02 03:38:36 DEBUG : both1: md5 = b20ef013c1b5bb1358d8905367fea7e9 OK 2025/05/02 03:38:36 DEBUG : only1: md5 = b7a68b33d2ab1621243cee4ba8a053d8 OK 2025/05/02 03:38:37 DEBUG : both2: md5 = f2ccd7fa0d75e83aaefd4ff1b337d6fd OK 2025/05/02 03:38:42 DEBUG : only2: md5 = ee73371a83b8d1d2e723bf04d72a4c16 OK 2025/05/02 03:38:48 DEBUG : both3: md5 = 20386930ea8aad4d1189d791374ef7ee OK 2025/05/02 03:38:49 DEBUG : only3: md5 = 71bc197809cb84488a5cbb600be4582a OK 2025/05/02 03:38:49 DEBUG : both4: md5 = f58b1ada406c7598865a384d982464ee OK 2025/05/02 03:38:54 DEBUG : only4: md5 = fa8944faff08865e882b9c6b1af3dbcf OK 2025/05/02 03:38:55 DEBUG : both5: md5 = 129da973f230e3bf362291a880476de7 OK 2025/05/02 03:39:00 DEBUG : only5: md5 = 0d76129db4ba93a78ae7f5bbd9980b64 OK 2025/05/02 03:39:06 DEBUG : both6: md5 = 972767333a85d35d52ae707ecea15d4c OK 2025/05/02 03:39:06 DEBUG : only6: md5 = 24ff2eed5b3475465440acfc4f086408 OK 2025/05/02 03:39:07 DEBUG : both7: md5 = 85590a6a632ff840afc5d390c5ffa54e OK 2025/05/02 03:39:12 DEBUG : only7: md5 = 5066900b1ee5452c5e65676a8b0b5050 OK 2025/05/02 03:39:13 DEBUG : both8: md5 = b15bb0d613d61aef2471912159d23ea6 OK 2025/05/02 03:39:13 DEBUG : only8: md5 = 7aa2937df7caf8227885c620d2a22a4d OK 2025/05/02 03:39:13 DEBUG : both9: md5 = 5374c3ddc22fa9bf96843314c463fe96 OK 2025/05/02 03:39:14 DEBUG : only9: md5 = 25881bfa1fb9fc2eb29dc76a937e2f77 OK 2025/05/02 03:39:14 DEBUG : both10: md5 = 47f4d4a7e16c0b0d69b44982005ccdd8 OK 2025/05/02 03:39:19 DEBUG : only10: md5 = 21e2198ac229cbb00d8645bda6f44690 OK 2025/05/02 03:39:25 DEBUG : both11: md5 = 9bbca2dea69c3f1a58579005cd9ff8c7 OK 2025/05/02 03:39:30 DEBUG : only11: md5 = 3247881528cccab3bd4aaecdcf2022eb OK 2025/05/02 03:39:30 DEBUG : both12: md5 = b6b4c7f82d872efe12003b2fbecbfa69 OK 2025/05/02 03:39:36 DEBUG : only12: md5 = a7e41555086f66bd4b9cf1591dfbad38 OK 2025/05/02 03:39:41 DEBUG : both13: md5 = 1c4f9de61917773ebcb182dec7d3e406 OK 2025/05/02 03:39:42 DEBUG : only13: md5 = 7c6a531e61bfad8a1d947b7080662132 OK 2025/05/02 03:39:47 DEBUG : both14: md5 = 580bc06874a0771b619cc4e9d36f67cf OK 2025/05/02 03:39:47 DEBUG : only14: md5 = c1088fbd3037f5a6a55e2c83f14a91f8 OK 2025/05/02 03:39:52 DEBUG : both15: md5 = 12c981ade5a3b592bea52847007f67be OK 2025/05/02 03:39:53 DEBUG : only15: md5 = 5c0fe729531c3c4937b530cd7611f513 OK 2025/05/02 03:39:58 DEBUG : both16: md5 = 8b99d564cc68c134dcb27bd07e675f1e OK 2025/05/02 03:39:59 DEBUG : only16: md5 = c7fad7579bc2ac5615896e8ef9e16849 OK 2025/05/02 03:39:59 DEBUG : both17: md5 = f6aeab883e6c7297e0650b0f00a2a9b0 OK 2025/05/02 03:40:04 DEBUG : only17: md5 = 765ce99712a08abcc201c5f24c4bce26 OK 2025/05/02 03:40:05 DEBUG : both18: md5 = db54f4bd407d28ad6480d8b297f285bf OK 2025/05/02 03:40:11 DEBUG : only18: md5 = 67708e68b1df70efa0948480236f6cb4 OK 2025/05/02 03:40:12 DEBUG : both19: md5 = 7d72c928424dcef3f5d74c21b1865ef8 OK 2025/05/02 03:40:17 DEBUG : only19: md5 = 564b47a06d4e13f3d07b0c62020f56d5 OK 2025/05/02 03:40:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:40:49 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both0: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both10: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both1: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both11: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both12: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both15: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both17: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both18: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both19: Unchanged skipping 2025/05/02 03:40:49 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:49 DEBUG : both2: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both13: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both14: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both16: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both4: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both5: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both7: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both8: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both9: Unchanged skipping 2025/05/02 03:40:54 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:54 DEBUG : both3: Unchanged skipping 2025/05/02 03:40:59 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:40:59 DEBUG : both6: Unchanged skipping 2025/05/02 03:40:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:40:59 DEBUG : Waiting for deletions to finish 2025/05/02 03:40:59 INFO : only13: Deleted 2025/05/02 03:41:00 INFO : only16: Deleted 2025/05/02 03:41:04 INFO : only12: Deleted 2025/05/02 03:41:04 INFO : only8: Deleted 2025/05/02 03:41:05 INFO : only15: Deleted 2025/05/02 03:41:05 INFO : only19: Deleted 2025/05/02 03:41:05 INFO : only9: Deleted 2025/05/02 03:41:05 INFO : only3: Deleted 2025/05/02 03:41:09 INFO : only11: Deleted 2025/05/02 03:41:10 INFO : only4: Deleted 2025/05/02 03:41:10 INFO : only0: Deleted 2025/05/02 03:41:10 INFO : only10: Deleted 2025/05/02 03:41:10 INFO : only1: Deleted 2025/05/02 03:41:10 INFO : only14: Deleted 2025/05/02 03:41:14 INFO : only18: Deleted 2025/05/02 03:41:15 INFO : only6: Deleted 2025/05/02 03:41:15 INFO : only5: Deleted 2025/05/02 03:41:15 INFO : only2: Deleted 2025/05/02 03:41:15 INFO : only17: Deleted 2025/05/02 03:41:25 INFO : only7: Deleted 2025/05/02 03:41:25 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (246.46s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:42:36 DEBUG : both0: md5 = 5d09611d0ab2416ec5dc7e70a2559fc3 OK 2025/05/02 03:42:41 DEBUG : only0: md5 = 8c0eec782d73d0b2302bf8aee45805a3 OK 2025/05/02 03:42:41 DEBUG : both1: md5 = 97e90ac93a1f174492a84f23f39fe2c2 OK 2025/05/02 03:42:42 DEBUG : only1: md5 = 956243db0765812c152788e8f3b1eeef OK 2025/05/02 03:42:42 DEBUG : both2: md5 = b49769ae272743a198a9b206d010a4ac OK 2025/05/02 03:42:47 DEBUG : only2: md5 = a30423ac639b9a5739c9375519ff10b3 OK 2025/05/02 03:42:52 DEBUG : both3: md5 = 5ea5e56e8fa6537132e2ff6585732e9c OK 2025/05/02 03:42:52 DEBUG : only3: md5 = 09e859ce8182cedbf0771b1801554667 OK 2025/05/02 03:42:53 DEBUG : both4: md5 = 8e39ec973ed4cab50f9639a5c7839fbc OK 2025/05/02 03:42:58 DEBUG : only4: md5 = dc1161917f870cc3ccf135227b430047 OK 2025/05/02 03:42:58 DEBUG : both5: md5 = bb54783477eb6901411de06cbc74bfdf OK 2025/05/02 03:43:03 DEBUG : only5: md5 = cbc74f8e1226538056957e1b55ea480c OK 2025/05/02 03:43:09 DEBUG : both6: md5 = ac5909a1c1afffc47b06ca1c2e50a4fe OK 2025/05/02 03:43:09 DEBUG : only6: md5 = 13eb23302bd574addc698a8efa767e73 OK 2025/05/02 03:43:09 DEBUG : both7: md5 = abc217c46c8629dae254e8700748b816 OK 2025/05/02 03:43:14 DEBUG : only7: md5 = 89c3249058497842cb3d6a84c8969168 OK 2025/05/02 03:43:14 DEBUG : both8: md5 = 412110eaa2a28a936d71908c29e0fe01 OK 2025/05/02 03:43:15 DEBUG : only8: md5 = e434b4f1db5580293313f5aff75b920b OK 2025/05/02 03:43:15 DEBUG : both9: md5 = bed1e5768d8f02492d0ded5913aa42cd OK 2025/05/02 03:43:15 DEBUG : only9: md5 = 00a32d0f994c499fac2689a5c469a9a0 OK 2025/05/02 03:43:15 DEBUG : both10: md5 = 9e2efa1871e5ae33f7c40ae43d9af2e7 OK 2025/05/02 03:43:20 DEBUG : only10: md5 = aa4e7cc896c0cf2a2ea57eff129c7be0 OK 2025/05/02 03:43:25 DEBUG : both11: md5 = 41992892e2ac18414871d5f875fb1d67 OK 2025/05/02 03:43:31 DEBUG : only11: md5 = 97f82531601ceb5fcf85a228a155a2cc OK 2025/05/02 03:43:31 DEBUG : both12: md5 = 5e27b0cf7b85c96b3693416cd95574c5 OK 2025/05/02 03:43:36 DEBUG : only12: md5 = c1310eeb15783cbae6f6cf024d376ce2 OK 2025/05/02 03:43:41 DEBUG : both13: md5 = be660f82ebfbaee55b4570e68b3909ba OK 2025/05/02 03:43:41 DEBUG : only13: md5 = 8a7b8acc5a56c6c4956454271febf072 OK 2025/05/02 03:43:46 DEBUG : both14: md5 = d135d8b5bf1c18f9aa62a27702b32037 OK 2025/05/02 03:43:47 DEBUG : only14: md5 = 41ff74434119e8f7ff67747c12ca6667 OK 2025/05/02 03:43:52 DEBUG : both15: md5 = 26494a2510c0d91468d053790983710f OK 2025/05/02 03:43:52 DEBUG : only15: md5 = 904ceb1bdad145e80d500bad0317fdb6 OK 2025/05/02 03:43:57 DEBUG : both16: md5 = 110b0556f558cd6d03d6b93581e284dd OK 2025/05/02 03:43:57 DEBUG : only16: md5 = a3d91f4fd04ad5fa41f4f4c1039570d2 OK 2025/05/02 03:43:58 DEBUG : both17: md5 = 683a605059b4b4a3e257374a05dd9778 OK 2025/05/02 03:44:03 DEBUG : only17: md5 = 5d590cad2362a4d390a2c5b35d6c0679 OK 2025/05/02 03:44:03 DEBUG : both18: md5 = 1c92076c032c53848ab934944b7522a0 OK 2025/05/02 03:44:08 DEBUG : only18: md5 = df015205f6099cb0ffa145971675adb4 OK 2025/05/02 03:44:08 DEBUG : both19: md5 = 6f7bd090ab7532ca631983b85e9e7c74 OK 2025/05/02 03:44:14 DEBUG : only19: md5 = 19d2545fe544e52c56480775869fb981 OK 2025/05/02 03:44:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for checks to finish 2025/05/02 03:44:36 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both0: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both1: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both10: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both12: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both13: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both14: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both15: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both17: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both18: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both19: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both2: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both3: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both5: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both4: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both7: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both8: Unchanged skipping 2025/05/02 03:44:36 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:36 DEBUG : both9: Unchanged skipping 2025/05/02 03:44:36 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/05/02 03:44:36 DEBUG : only1: md5 = b0ae7eb2e2ec452c037e5380efc88776 OK 2025/05/02 03:44:36 INFO : only1: Copied (replaced existing) 2025/05/02 03:44:41 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:41 DEBUG : both11: Unchanged skipping 2025/05/02 03:44:41 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:41 DEBUG : both16: Unchanged skipping 2025/05/02 03:44:41 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/02 03:44:41 DEBUG : both6: Unchanged skipping 2025/05/02 03:44:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu': Waiting for transfers to finish 2025/05/02 03:44:41 DEBUG : only0: md5 = 3d79fdb3ea14644055fd5ab54fe3c660 OK 2025/05/02 03:44:41 INFO : only0: Copied (replaced existing) 2025/05/02 03:44:41 DEBUG : only13: md5 = 4597ade6bcb4a910777a6496492f2898 OK 2025/05/02 03:44:41 INFO : only13: Copied (replaced existing) 2025/05/02 03:44:42 DEBUG : only14: md5 = ca3cd914e66091b54fc8315bccdbb26e OK 2025/05/02 03:44:42 INFO : only14: Copied (replaced existing) 2025/05/02 03:44:42 DEBUG : only15: md5 = 04f3dc3b7351d657cd45534ea43e4b6f OK 2025/05/02 03:44:42 INFO : only15: Copied (replaced existing) 2025/05/02 03:44:42 DEBUG : only16: md5 = eacd9a96bcf17f4c5d25c1f7760ccc3c OK 2025/05/02 03:44:42 INFO : only16: Copied (replaced existing) 2025/05/02 03:44:46 DEBUG : only10: md5 = 81932fb65400d462a403ffb68450070a OK 2025/05/02 03:44:46 INFO : only10: Copied (replaced existing) 2025/05/02 03:44:46 DEBUG : only11: md5 = dafc030d18463c1a69bf44b5dfac1905 OK 2025/05/02 03:44:46 INFO : only11: Copied (replaced existing) 2025/05/02 03:44:47 DEBUG : only12: md5 = e805cc2fa9b3806ee6f6816ffc4c7014 OK 2025/05/02 03:44:47 INFO : only12: Copied (replaced existing) 2025/05/02 03:44:48 DEBUG : only17: md5 = 033b26ebe53278e073017076dd1c013b OK 2025/05/02 03:44:48 INFO : only17: Copied (replaced existing) 2025/05/02 03:44:48 DEBUG : only3: md5 = c09d001e39ba5513d977e80c248fbc6e OK 2025/05/02 03:44:48 INFO : only3: Copied (replaced existing) 2025/05/02 03:44:51 DEBUG : only18: md5 = 1d7f044f22726c20473ea1a9dfdd5e3c OK 2025/05/02 03:44:51 INFO : only18: Copied (replaced existing) 2025/05/02 03:44:52 DEBUG : only19: md5 = 6b8dc0e65c914f26be3ba65c9d7cf539 OK 2025/05/02 03:44:52 INFO : only19: Copied (replaced existing) 2025/05/02 03:44:52 DEBUG : only6: md5 = 7ce230dbcb426490124921647779dfa7 OK 2025/05/02 03:44:52 INFO : only6: Copied (replaced existing) 2025/05/02 03:44:52 DEBUG : only2: md5 = 89878f615fc94a184e9a1b718aa60f1a OK 2025/05/02 03:44:52 INFO : only2: Copied (replaced existing) 2025/05/02 03:44:52 DEBUG : only8: md5 = 561bb91960892dfea6048d12b0a2a581 OK 2025/05/02 03:44:52 INFO : only8: Copied (replaced existing) 2025/05/02 03:44:52 DEBUG : only9: md5 = 194070ee8f940a7280e62349253ae4b7 OK 2025/05/02 03:44:52 INFO : only9: Copied (replaced existing) 2025/05/02 03:44:53 DEBUG : only4: md5 = b5beab51b7b36c5ae8914f5007df93ef OK 2025/05/02 03:44:53 INFO : only4: Copied (replaced existing) 2025/05/02 03:44:57 DEBUG : only5: md5 = 16e1bd44bda950de2651c82cce68d3fb OK 2025/05/02 03:44:57 INFO : only5: Copied (replaced existing) 2025/05/02 03:44:57 DEBUG : only7: md5 = 2d1b2a5f2337f0608f296020801ca9af OK 2025/05/02 03:44:57 INFO : only7: Copied (replaced existing) 2025/05/02 03:44:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (315.70s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-mexajot9fitu'", Local "Local file system at /tmp/rclone802095826", Modify Window "1ns" 2025/05/02 03:47:47 DEBUG : empty_dir: Making directory with metadata 2025/05/02 03:47:47 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 03:47:47 DEBUG : empty_on_remote: Making directory with metadata 2025/05/02 03:47:47 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/02 03:47:47 INFO : empty_on_remote: Making directory 2025/05/02 03:47:47 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC panic: test timed out after 1h0m0s running tests: TestSyncReplaceDirModTime (1s) goroutine 13807 [running]: testing.(*M).startAlarm.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2373 +0x385 created by time.goFunc /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive]: testing.(*T).Run(0xc0001e2000, {0x260972e?, 0x0?}, 0x271fdb0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1751 +0x3ab testing.runTests.func1(0xc0001e2000) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2168 +0x37 testing.tRunner(0xc0001e2000, 0xc00006fc18) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 testing.runTests(0xc000713c38, {0x3cfdae0, 0x5c, 0x5c}, {0x3d148c0?, 0x1?, 0x3d148c0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2166 +0x43d testing.(*M).Run(0xc0005f9400) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2034 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0005f9400) /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:44 main.main() _testmain.go:229 +0xa9 goroutine 51 [syscall, 60 minutes]: os/signal.signal_recv() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal.go:151 +0x1f goroutine 52 [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 12801 [select]: net/http.(*persistConn).writeLoop(0xc0009f1200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 13166 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 2258 [chan receive, 54 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 2222 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 13797 [select]: net/http.(*persistConn).roundTrip(0xc0009f3560, 0xc0004998b0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2755 +0x7b0 net/http.(*Transport).roundTrip(0xc0004b7380, 0xc000574780) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:644 +0xb91 net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0005ee070, 0xc000574780) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000574780, {0x29d5e80, 0xc0005ee070}, {0x1?, 0x410500?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:259 +0x5e4 net/http.(*Client).send(0xc000745cb0, 0xc000574780, {0xc0005f94a0?, 0x2a0f8a0?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:180 +0x98 net/http.(*Client).do(0xc000745cb0, 0xc000574780) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:725 +0x8bc net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:590 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:420 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 13761 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:419 +0xb8 goroutine 13710 [semacquire]: sync.runtime_Semacquire(0x1e724de?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sema.go:71 +0x25 sync.(*WaitGroup).Wait(0xc00068be07?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/sync.(*syncCopyMove).stopTransfers(0xc000460a08) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:540 +0x75 github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0xc000460a08) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:975 +0x332 github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x29fbf48, 0xc000709b00}, {0x2a0f8a0, 0xc001b49c70}, {0x2a113c0, 0xc0005f94a0}, 0x0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1356 +0x208 github.com/rclone/rclone/fs/sync.CopyDir(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1367 github.com/rclone/rclone/fs/sync.testSyncReplaceDirModTime.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2796 +0x51 github.com/rclone/rclone/cmd/bisync/bilib.CaptureOutput(0xc00061fee8) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:18 +0x9a github.com/rclone/rclone/fs/sync.testSyncReplaceDirModTime(0xc001e70000, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2795 +0x66d github.com/rclone/rclone/fs/sync.TestSyncReplaceDirModTime(0xc001e70000?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2833 +0x15 testing.tRunner(0xc001e70000, 0x271fdb0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 created by testing.(*T).Run in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1743 +0x390 goroutine 13773 [chan receive]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 13710 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 13795 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc000af8a00) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 13761 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x438 goroutine 13783 [IO wait]: internal/poll.runtime_pollWait(0x7f52a902a468, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000420780?, 0xc00022a600?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000420780, {0xc00022a600, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000420780, {0xc00022a600?, 0x0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001d6a2c0, {0xc00022a600?, 0x0?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001833e48, {0xc00022a600?, 0x18?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc001b978d8, {0xc00022a600?, 0x0?, 0xc001b978d8?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000ac5438, {0x29d7380, 0xc001b978d8}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000ac5188, {0x7f52a857ccc0, 0xc001833e48}, 0xc00009ea40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000ac5188, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000ac5188, {0xc000902000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc0009f3560, {0xc000902000?, 0x40a8a5?, 0x68?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc001bcfe00) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc001bcfe00, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc0009f3560) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 13798 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 13764 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc001b4a1c0, 0xc001bcfb60, 0xc000400000) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:424 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {0xc00026f310, 0x4f}, {{0xc000abe7b0, 0x5}, {0xc000abe7b6, 0x22}, {0x25c808f, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:776 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {{0xc000abe7b0, 0x5}, {0xc000abe7b6, 0x22}, {0x25c808f, 0x3}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:846 +0x151 github.com/ncw/swift/v2.(*Connection).objectPut(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {0xc000abe7b0, 0x5}, {0xc000abe7b6, 0x22}, {0x29d4dc0, 0xc001b97800}, 0x1, ...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:1568 +0x1f5 github.com/ncw/swift/v2.(*Connection).ObjectPut(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:1614 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, 0xc00096c700?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b45140, 0xc00096c700, 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(0xc001bcfb00, {0x29fbf80, 0xc00068bef0}, {0x29d4dc0, 0xc001b97800}, {0x2a067b0, 0xc001b50240}, {0xc001dbaa00, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1558 +0x7cb github.com/rclone/rclone/backend/swift.(*Fs).Put(0xc001b4a700, {0x29fbf80, 0xc00068bef0}, {0x29d4dc0, 0xc001b97800}, {0x2a067b0, 0xc001b50240}, {0xc001dbaa00, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:937 +0x139 github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b49c70, {0x29fbf80, 0xc00068bef0}, {0x29d4ec0, 0xc001cea300}, {0x7f52a854b4a8, 0xc0009d12c0}, {0xc001dbaa00, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Fs).Put(0x29e7c50?, {0x29fbf80?, 0xc00068bef0?}, {0x29d4ec0?, 0xc001cea300?}, {0x7f52a854b4a8?, 0xc0009d12c0?}, {0xc001dbaa00, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:571 +0x91 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc00078f560, {0x29fbf80, 0xc00068bef0}, {0x29e7c50?, 0xc001ab0540?}, {0xc001dbaa00, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:217 +0x226 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc00078f560, {0x29fbf80, 0xc00068bef0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:270 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc00078f560, {0x29fbf80, 0xc00068bef0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:310 +0x176 github.com/rclone/rclone/fs/operations.Copy({0x29fbf80, 0xc00068bef0}, {0x2a0f8a0, 0xc001b49c70}, {0x0, 0x0}, {0xc0023e07c0, 0x5}, {0x2a11430, 0xc0009d12c0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:412 +0x493 github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000460a08, {0x29fbf80, 0xc00068bef0}, 0xc00068be50, {0x2a0f8a0, 0xc001b49c70}, 0x4b, 0xc000a97200?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:502 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 13710 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:532 +0x7d goroutine 12800 [IO wait]: internal/poll.runtime_pollWait(0x7f52a902a048, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000421200?, 0xc0008a9500?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000421200, {0xc0008a9500, 0x3500, 0x3500}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000421200, {0xc0008a9500?, 0xc000ac4fd0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000144648, {0xc0008a9500?, 0x1?, 0xc0008c0708?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001d6c3a8, {0xc0008a9500?, 0x18?, 0xc00200ddc0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000583338, {0xc0008a9500?, 0x0?, 0xc000583338?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000ac50b8, {0x29d7380, 0xc000583338}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000ac4e08, {0x7f52a857ccc0, 0xc001d6c3a8}, 0xc0008c0778?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000ac4e08, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000ac4e08, {0xc000ab4000, 0x1000, 0xa?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc0009f1200, {0xc000ab4000?, 0x7a2bc6?, 0xc0008c0ca0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000a984e0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).ReadSlice(0xc000a984e0, 0xa) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:376 +0x29 bufio.(*Reader).ReadLine(0xc000a984e0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:405 +0x25 net/textproto.(*Reader).readLineSlice(0xc0008c0ca0, 0xffffffffffffffff) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/textproto/reader.go:64 +0xa5 net/textproto.(*Reader).ReadLine(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/textproto/reader.go:44 net/http.ReadResponse(0xc000a984e0, 0xc0004003c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/response.go:161 +0x9e net/http.(*persistConn).readResponse(0xc0009f1200, {{}, 0xc00077a3c0, 0xc000cb0fc0, 0x1, 0xc000cb0ee0, 0xc000cb0f50}, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2395 +0xc9 net/http.(*persistConn).readLoop(0xc0009f1200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2220 +0x30c created by net/http.(*Transport).dialConn in goroutine 13166 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 13805 [IO wait]: internal/poll.runtime_pollWait(0x7f52a902a150, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00096c800?, 0xc000228c80?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00096c800, {0xc000228c80, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00096c800, {0xc000228c80?, 0xc0003bd6d0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00194a210, {0xc000228c80?, 0x3d148c0?, 0xc00062e9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0005832f0, {0xc000228c80?, 0x18?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000712f90, {0xc000228c80?, 0x0?, 0xc000712f90?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0003bd7b8, {0x29d7380, 0xc000712f90}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0003bd508, {0x7f52a857ccc0, 0xc0005832f0}, 0xc00062ea40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0003bd508, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0003bd508, {0xc0008fa000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc0009f9200, {0xc0008fa000?, 0x81e525?, 0x2143d60?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000bbaea0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc000bbaea0, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc0009f9200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 13811 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 13779 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc001cea300) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 13764 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x438 goroutine 13806 [select]: net/http.(*persistConn).writeLoop(0xc0009f9200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 13811 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 13761 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc001b4a1c0, 0xc001f4a120, 0xc000574780) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:424 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {0xc00026f310, 0x4f}, {{0xc000746a00, 0x5}, {0xc000746a06, 0x34}, {0x25c808f, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:776 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {{0xc000746a00, 0x5}, {0xc000746a06, 0x34}, {0x25c808f, 0x3}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:846 +0x151 github.com/ncw/swift/v2.(*Connection).objectPut(0xc001b4a1c0, {0x29fbf80, 0xc00068bef0}, {0xc000746a00, 0x5}, {0xc000746a06, 0x34}, {0x29d4dc0, 0xc001833d70}, 0x1, ...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:1568 +0x1f5 github.com/ncw/swift/v2.(*Connection).ObjectPut(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:1614 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, 0xc000420680?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b45140, 0xc000420680, 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(0xc001f4a0c0, {0x29fbf80, 0xc00068bef0}, {0x29d4dc0, 0xc001833d70}, {0x2a067b0, 0xc0008d1bc0}, {0xc001d6e1c0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1558 +0x7cb github.com/rclone/rclone/backend/swift.(*Fs).Put(0xc001b4a700, {0x29fbf80, 0xc00068bef0}, {0x29d4dc0, 0xc001833d70}, {0x2a067b0, 0xc0008d1bc0}, {0xc001d6e1c0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:937 +0x139 github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001b49c70, {0x29fbf80, 0xc00068bef0}, {0x29d4ec0, 0xc000af8a00}, {0x7f52a854b4a8, 0xc002011f20}, {0xc001d6e1c0, 0x1, 0x1}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Fs).Put(0x29e7c50?, {0x29fbf80?, 0xc00068bef0?}, {0x29d4ec0?, 0xc000af8a00?}, {0x7f52a854b4a8?, 0xc002011f20?}, {0xc001d6e1c0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:571 +0x91 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0xc0006abef0, {0x29fbf80, 0xc00068bef0}, {0x29e7c50?, 0xc00020c2a0?}, {0xc001d6e1c0, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:217 +0x226 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0xc0006abef0, {0x29fbf80, 0xc00068bef0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:270 +0x6d8 github.com/rclone/rclone/fs/operations.(*copy).copy(0xc0006abef0, {0x29fbf80, 0xc00068bef0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:310 +0x176 github.com/rclone/rclone/fs/operations.Copy({0x29fbf80, 0xc00068bef0}, {0x2a0f8a0, 0xc001b49c70}, {0x0, 0x0}, {0xc001b4c2a0, 0x17}, {0x2a11430, 0xc002011f20}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:412 +0x493 github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0xc000460a08, {0x29fbf80, 0xc00068bef0}, 0xc00068be50, {0x2a0f8a0, 0xc001b49c70}, 0x0, 0x3c8e040?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:502 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 13710 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:532 +0x7d goroutine 13778 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc000bdea40) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 13764 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 13781 [select]: net/http.(*persistConn).roundTrip(0xc0009f1200, 0xc00077a3c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2755 +0x7b0 net/http.(*Transport).roundTrip(0xc0004b7380, 0xc000400000) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:644 +0xb91 net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0005ee070, 0xc000400000) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000400000, {0x29d5e80, 0xc0005ee070}, {0x1?, 0xffffffffffffffff?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:259 +0x5e4 net/http.(*Client).send(0xc000745cb0, 0xc000400000, {0xc000a9b810?, 0xc0003bbdc0?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:180 +0x98 net/http.(*Client).do(0xc000745cb0, 0xc000400000) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:725 +0x8bc net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:590 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:420 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 13764 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:419 +0xb8 goroutine 13794 [chan receive]: github.com/rclone/rclone/backend/local.(*fadvise).worker(0xc0009c0600) /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:114 +0x3f created by github.com/rclone/rclone/backend/local.newFadvise in goroutine 13761 /home/rclone/go/src/github.com/rclone/rclone/backend/local/fadvise_unix.go:74 +0x11f goroutine 13752 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001d541c0) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:343 +0x13d created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 13710 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:408 +0xb6 goroutine 13784 [select]: net/http.(*persistConn).writeLoop.(*persistConn).waitForContinue.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2463 +0xd9 net/http.(*Request).write(0xc0005748c0, {0x29d45e0, 0xc000bdeac0}, 0x0, 0xc001b50600, 0xc000cc1f30) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/request.go:756 +0x9eb net/http.(*persistConn).writeLoop(0xc0009f3560) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2522 +0x174 created by net/http.(*Transport).dialConn in goroutine 13798 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.926238317s (try 2/5): exit status 2: Failed []