"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 2/5) 2025/05/22 02:43:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi" 2025/05/22 02:43:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/22 02:43:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi.bin" 2025/05/22 02:43:06 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi.bin: Auto set use_segments_container to true 2025/05/22 02:43:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi" 2025/05/22 02:43:12 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi: Auto set use_segments_container to true 2025/05/22 02:43:17 DEBUG : Creating backend with remote "/tmp/rclone2897061976" 2025/05/22 02:43:17 DEBUG : Config file has changed externally - reloading === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === 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.01s) --- 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.01s) --- 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:43:17 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/05/22 02:43:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:43:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:17 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/22 02:43:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:43:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:43:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:43:23 DEBUG : sub dir/hello world: md5 = 549233723c62b6f5bffbbfa3c2b6c0d7 OK 2025/05/22 02:43:23 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (11.39s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.18s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:29 DEBUG : Creating backend with remote "/non-existing" 2025/05/22 02:43:29 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/05/22 02:43:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:43:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.50s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:43:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:43:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:43:45 DEBUG : sub dir/hello world: md5 = 11e05ffd916dde860a77cba462f6fa62 OK 2025/05/22 02:43:45 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyNoTraverse (26.25s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:43:56 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Running all checks before starting transfers 2025/05/22 02:43:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:43:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:43:56 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Checks finished, now starting transfers 2025/05/22 02:43:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:44:01 DEBUG : sub dir/hello world: md5 = dcac8ebef5f940453d583674b926ca19 OK 2025/05/22 02:44:01 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyCheckFirst (16.12s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:44:12 ERROR : Ignoring --no-traverse with sync 2025/05/22 02:44:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:44:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:44:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:44:17 DEBUG : sub dir/hello world: md5 = 6a113340d230c9aea6087807f2a1a2a9 OK 2025/05/22 02:44:17 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:44:17 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncNoTraverse (21.45s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:44:34 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/05/22 02:44:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:44:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:44:39 DEBUG : hello world2: md5 = 724f6c4601a6e10cd7684579094b31af OK 2025/05/22 02:44:39 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyWithDepth (21.06s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:44:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:44:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:44:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:45:00 DEBUG : potato2: md5 = 2279f74d8c415ca005bcf76c3661400f OK 2025/05/22 02:45:00 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (11.12s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:45:11 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:45:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:45:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:45:16 DEBUG : potato2: md5 = cdecaa4c03334b482f3b8285790c71f0 OK 2025/05/22 02:45:16 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (21.15s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:45:27 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/05/22 02:45:27 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 02:45:27 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/05/22 02:45:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/22 02:45:27 INFO : sub dir: Making directory 2025/05/22 02:45:27 INFO : sub dir2: Making directory 2025/05/22 02:45:27 INFO : sub dir2/sub sub dir2: Making directory 2025/05/22 02:45:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:45:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:45:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:45:32 DEBUG : sub dir/hello world: md5 = 40b4bbf9de5ea9dc3e6c579eb5cbe803 OK 2025/05/22 02:45:32 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2025/05/22 02:45:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (16.40s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:45:43 INFO : sub dir2: Making directory 2025/05/22 02:45:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/05/22 02:45:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 02:45:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:45:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:45:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:45:48 DEBUG : sub dir/hello world: md5 = 7a58899d95b4b0c9e8408595f40790c6 OK 2025/05/22 02:45:48 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (15.91s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:45:59 DEBUG : sub dir2: Making directory with metadata 2025/05/22 02:45:59 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 02:45:59 INFO : sub dir: Making directory 2025/05/22 02:45:59 INFO : sub dir2: Making directory 2025/05/22 02:45:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:45:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:45:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:46:04 DEBUG : sub dir/hello world: md5 = a8d283af7c2b79451b0f4f714305b1dd OK 2025/05/22 02:46:04 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:46:04 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/05/22 02:46:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/05/22 02:46:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (16.25s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.18s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:46:16 INFO : sub dir2: Making directory 2025/05/22 02:46:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:46:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:46:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:46:21 DEBUG : sub dir/hello world: md5 = b8a293ec0c59a100d64c02029ca567c4 OK 2025/05/22 02:46:21 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:46:21 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (11.15s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:46:27 DEBUG : sub dir2: Making directory with metadata 2025/05/22 02:46:27 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 02:46:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/22 02:46:27 INFO : sub dir: Making directory 2025/05/22 02:46:27 INFO : sub dir2: Making directory 2025/05/22 02:46:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:46:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:46:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:46:32 DEBUG : sub dir/hello world: md5 = 8040faa4fdc00518435a43fec6f77ae7 OK 2025/05/22 02:46:32 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:46:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/05/22 02:46:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (16.08s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.20s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:46:43 INFO : sub dir2: Making directory 2025/05/22 02:46:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:46:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:46:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:46:48 DEBUG : sub dir/hello world: md5 = 824150b145012a762e25e616840f14fd OK 2025/05/22 02:46:48 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:46:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (15.96s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:47:04 DEBUG : sub dir/hello world: md5 = 6733de5a70adc8eddb599c9f19b7196f OK 2025/05/22 02:47:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-resosag8gaha" 2025/05/22 02:47:04 DEBUG : Config file has changed externally - reloading 2025/05/22 02:47:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-resosag8gaha.bin" 2025/05/22 02:47:05 DEBUG : Swift container crypt path rclone-test-resosag8gaha.bin: Auto set use_segments_container to true 2025/05/22 02:47:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-resosag8gaha" 2025/05/22 02:47:10 DEBUG : Swift container crypt path rclone-test-resosag8gaha: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-resosag8gaha' 2025/05/22 02:47:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:47:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-resosag8gaha': Waiting for checks to finish 2025/05/22 02:47:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-resosag8gaha': Waiting for transfers to finish 2025/05/22 02:47:37 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:47:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-resosag8gaha': Purge remote 2025/05/22 02:47:37 DEBUG : Waiting for deletions to finish 2025/05/22 02:47:37 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (48.93s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:47:53 DEBUG : sub dir/hello world: md5 = 249b4cc60a6f0fa03298a71fbebfba42 OK 2025/05/22 02:47:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/22 02:47:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:47:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:47:59 DEBUG : sub dir/hello world: md5 = 70c47d13db302957e572f7e5ec05e82f OK 2025/05/22 02:47:59 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyOverSelf (22.29s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:48:15 DEBUG : sub dir/hello world: md5 = a9015e06c56ce95f33e7e9cc1989b064 OK 2025/05/22 02:48:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wexudaq1ruxo" 2025/05/22 02:48:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wexudaq1ruxo.bin" 2025/05/22 02:48:21 DEBUG : Swift container crypt path rclone-test-wexudaq1ruxo.bin: Auto set use_segments_container to true 2025/05/22 02:48:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wexudaq1ruxo" 2025/05/22 02:48:27 DEBUG : Swift container crypt path rclone-test-wexudaq1ruxo: Auto set use_segments_container to true sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo' 2025/05/22 02:48:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:48:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo': Waiting for checks to finish 2025/05/22 02:48:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo': Waiting for transfers to finish 2025/05/22 02:48:43 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:48:49 DEBUG : sub dir/hello world: md5 = 893f66993dca3ff4e96be58c1de9e8af OK 2025/05/22 02:48:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/22 02:48:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo': Waiting for checks to finish 2025/05/22 02:49:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo': Waiting for transfers to finish 2025/05/22 02:49:10 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:49:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wexudaq1ruxo': Purge remote 2025/05/22 02:49:10 DEBUG : Waiting for deletions to finish 2025/05/22 02:49:11 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (71.14s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:49:27 DEBUG : sub dir/hello world: md5 = 8fb8d909f306f8db6fd8c43955997b27 OK 2025/05/22 02:49:32 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/22 02:49:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:49:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:49:37 DEBUG : sub dir/hello world: md5 = 8ec2d821112c47cf21838d60795d8187 OK 2025/05/22 02:49:37 INFO : sub dir/hello world: Copied (replaced existing) 2025/05/22 02:49:37 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestMoveOverSelf (26.99s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:49:54 DEBUG : sub dir/hello world: md5 = 013fa5cc09cd7e120c9f388dcf62e2d1 OK 2025/05/22 02:49:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cazaset3kevu" 2025/05/22 02:49:59 DEBUG : Config file has changed externally - reloading 2025/05/22 02:49:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cazaset3kevu.bin" 2025/05/22 02:49:59 DEBUG : Swift container crypt path rclone-test-cazaset3kevu.bin: Auto set use_segments_container to true 2025/05/22 02:50:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cazaset3kevu" 2025/05/22 02:50:05 DEBUG : Swift container crypt path rclone-test-cazaset3kevu: Auto set use_segments_container to true sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu' 2025/05/22 02:50:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for checks to finish 2025/05/22 02:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for transfers to finish 2025/05/22 02:50:36 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:50:41 DEBUG : sub dir/hello world: md5 = 08a1af3cf2c55bcdf1b749a664f7419b OK 2025/05/22 02:50:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/05/22 02:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for checks to finish 2025/05/22 02:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for transfers to finish 2025/05/22 02:51:02 INFO : sub dir/hello world: Copied (server-side copy) 2025/05/22 02:51:07 INFO : sub dir/hello world: Deleted 2025/05/22 02:51:18 DEBUG : sub dir/hello world: md5 = ca9ad1058309ce4e485185dd3a508293 OK 2025/05/22 02:51:23 DEBUG : testing file moves 2025/05/22 02:51:23 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/05/22 02:51:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for checks to finish 2025/05/22 02:51:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Waiting for transfers to finish 2025/05/22 02:51:43 INFO : sub dir/hello world: Copied (server-side copy) 2025/05/22 02:51:49 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:51:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cazaset3kevu': Purge remote 2025/05/22 02:51:49 DEBUG : Waiting for deletions to finish 2025/05/22 02:51:54 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (126.69s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:00 DEBUG : sub dir/hello world: md5 = 0f3a9bf2c974bdde17628278fa613ca8 OK 2025/05/22 02:52:00 ERROR : : error listing: directory not found 2025/05/22 02:52:00 INFO : Local file system at /tmp/rclone2897061976: Making directory 2025/05/22 02:52:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyAfterDelete (11.33s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:12 DEBUG : sub dir/hello world: md5 = bc06997ff12c86b163d3e3d58ed796e9 OK 2025/05/22 02:52:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:52:13 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for checks to finish 2025/05/22 02:52:13 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for transfers to finish 2025/05/22 02:52:18 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/05/22 02:52:18 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyRedownload (17.00s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:23 DEBUG : check sum: Need to transfer - File not found at Destination 2025/05/22 02:52:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:29 DEBUG : check sum: md5 = 60be274dc5d45df682ceeeeca6533d7e OK 2025/05/22 02:52:29 INFO : check sum: Copied (new) 2025/05/22 02:52:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:29 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/05/22 02:52:29 DEBUG : check sum: Size of src and dst objects identical 2025/05/22 02:52:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:34 DEBUG : check sum: Unchanged skipping 2025/05/22 02:52:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:34 DEBUG : Waiting for deletions to finish 2025/05/22 02:52:34 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (16.48s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:40 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/05/22 02:52:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:45 DEBUG : sizeonly: md5 = b58f7669ab0dfe8b069ecc02f78dc24a OK 2025/05/22 02:52:45 INFO : sizeonly: Copied (new) 2025/05/22 02:52:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:45 DEBUG : sizeonly: Sizes identical 2025/05/22 02:52:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:46 DEBUG : sizeonly: Unchanged skipping 2025/05/22 02:52:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:46 DEBUG : Waiting for deletions to finish 2025/05/22 02:52:46 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncSizeOnly (11.59s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:51 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/05/22 02:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:57 DEBUG : ignore-size: md5 = f1e8763548be525685ae882a513eb6af OK 2025/05/22 02:52:57 INFO : ignore-size: Copied (new) 2025/05/22 02:52:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:52:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:52:57 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:52:57 DEBUG : ignore-size: Unchanged skipping 2025/05/22 02:52:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:52:57 DEBUG : Waiting for deletions to finish 2025/05/22 02:52:57 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (17.75s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:09 DEBUG : existing: md5 = 1a3ab220df39d2c0fdb0be5efc067921 OK 2025/05/22 02:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:10 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:53:10 DEBUG : existing: Unchanged skipping 2025/05/22 02:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:10 DEBUG : Waiting for deletions to finish 2025/05/22 02:53:10 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:10 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/05/22 02:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:10 DEBUG : existing: md5 = 97d87c979faa4af175cf178bd50ca644 OK 2025/05/22 02:53:10 INFO : existing: Copied (replaced existing) 2025/05/22 02:53:10 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (2.36s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:12 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/22 02:53:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:12 DEBUG : existing: md5 = 67dc50e52e4627c6789a462fd2771b24 OK 2025/05/22 02:53:12 INFO : existing: Copied (new) 2025/05/22 02:53:12 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:12 DEBUG : existing: Destination exists, skipping 2025/05/22 02:53:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:12 DEBUG : Waiting for deletions to finish 2025/05/22 02:53:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.81s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:14 DEBUG : b/potato: md5 = 9f1fced7ca3d46fae503d8a919eff469 OK 2025/05/22 02:53:14 DEBUG : c/non empty space: md5 = 9da52952381407f7bf5cf81855180338 OK 2025/05/22 02:53:14 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/05/22 02:53:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/22 02:53:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:15 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:53:15 DEBUG : c/non empty space: Unchanged skipping 2025/05/22 02:53:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:20 DEBUG : a/potato2: md5 = 190b6ee86f2d2d00647970d53d5e160f OK 2025/05/22 02:53:20 INFO : a/potato2: Copied (new) 2025/05/22 02:53:20 DEBUG : Waiting for deletions to finish 2025/05/22 02:53:20 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (23.07s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:37 DEBUG : empty space: md5 = 3bac309a9645f1ec94dc327d3ef3d9a0 OK 2025/05/22 02:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:37 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/22 02:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:37 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/05/22 02:53:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:38 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/22 02:53:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:38 DEBUG : empty space: md5 = 038f5b11239a257206c45096cf4972b9 OK 2025/05/22 02:53:38 INFO : empty space: Copied (replaced existing) 2025/05/22 02:53:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.36s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.20s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:53:45 DEBUG : foo: md5 = 6d69671c461dd4d80259aa25ff009d1d OK 2025/05/22 02:53:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:53:50 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/22 02:53:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:53:55 DEBUG : foo: md5 = 1e6d9d2580d362797c615f7b052105db OK 2025/05/22 02:53:55 INFO : foo: Copied (replaced existing) 2025/05/22 02:53:55 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (26.82s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:06 DEBUG : empty space: md5 = ab8d20d9945121eb85ac54a97d1e1380 OK 2025/05/22 02:54:06 DEBUG : potato: Need to transfer - File not found at Destination 2025/05/22 02:54:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:54:06 DEBUG : empty space: Unchanged skipping 2025/05/22 02:54:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:07 DEBUG : potato: md5 = 7f58dd3d4efe8105d1992f74e523cd8a OK 2025/05/22 02:54:07 INFO : potato: Copied (new) 2025/05/22 02:54:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (2.62s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:09 DEBUG : potato: md5 = 43226d20d7eb901a7cf6a131c5f4cb00 OK 2025/05/22 02:54:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:09 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/05/22 02:54:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:09 DEBUG : potato: md5 = 21acd4477d58d582ca863cf5b1218c75 OK 2025/05/22 02:54:09 INFO : potato: Copied (replaced existing) 2025/05/22 02:54:09 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.54s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:10 DEBUG : potato: md5 = b063ec84c108a402d0f23003b836155e OK 2025/05/22 02:54:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:10 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/22 02:54:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:11 DEBUG : potato: md5 = f77929aa529fc4303e8974e5aadfe3b3 OK 2025/05/22 02:54:11 INFO : potato: Copied (replaced existing) 2025/05/22 02:54:11 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.64s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:12 DEBUG : potato: md5 = 5bf4745d357e2b01945c7fb23bf0c59a OK 2025/05/22 02:54:12 DEBUG : empty space: md5 = fb71139ed65e136b6c094689f5e38a24 OK 2025/05/22 02:54:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:54:12 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/05/22 02:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:54:12 DEBUG : empty space: Unchanged skipping 2025/05/22 02:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:12 DEBUG : Waiting for deletions to finish 2025/05/22 02:54:12 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.75s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:13 DEBUG : potato: md5 = 969c75444452d67bf81ab523f43aca34 OK 2025/05/22 02:54:14 DEBUG : empty space: md5 = ee02f4be2fc3c2034151c9f8ab60ec60 OK 2025/05/22 02:54:14 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:54:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:54:14 DEBUG : empty space: Unchanged skipping 2025/05/22 02:54:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:19 DEBUG : potato2: md5 = c0cf6ad293a5a72d9ba38d5f51653932 OK 2025/05/22 02:54:19 INFO : potato2: Copied (new) 2025/05/22 02:54:19 DEBUG : Waiting for deletions to finish 2025/05/22 02:54:19 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (17.18s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:54:36 DEBUG : b/potato: md5 = 205336e33600d88fe2a812d610d17eea OK 2025/05/22 02:54:36 DEBUG : c/non empty space: md5 = 051f3c60627f9c7f5c1799792585aedc OK 2025/05/22 02:54:36 INFO : d: Making directory 2025/05/22 02:54:36 INFO : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2025/05/22 02:54:36 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/22 02:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:54:36 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:54:36 DEBUG : c/non empty space: Unchanged skipping 2025/05/22 02:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:54:41 DEBUG : a/potato2: md5 = 4baecab7cb24e2fe1377ddd429094d05 OK 2025/05/22 02:54:41 INFO : a/potato2: Copied (new) 2025/05/22 02:54:41 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1218 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1232 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: listing wrong, want a/potato2 (60), c/non empty space (5) got a/potato2 (60), b/potato (21), c/non empty space (5) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1218 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1232 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: Unexpected file "b/potato" fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1218 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1232 Error: Not equal: expected: []string{"a", "c"} actual : []string{"a", "b", "c"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) (len=1) "a", + (string) (len=1) "b", (string) (len=1) "c" Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (33.72s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:55:09 DEBUG : b/potato: md5 = fc0b47d75cb5fc3c23c25ee47574644d OK 2025/05/22 02:55:09 DEBUG : c/non empty space: md5 = d991c2a3703a91e035500ce573115c82 OK 2025/05/22 02:55:09 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/05/22 02:55:10 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/05/22 02:55:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:55:10 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:55:10 DEBUG : c/non empty space: Unchanged skipping 2025/05/22 02:55:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:55:15 DEBUG : a/potato2: md5 = f942029b2249c34595b68936ba555181 OK 2025/05/22 02:55:15 INFO : a/potato2: Copied (new) 2025/05/22 02:55:15 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': not deleting files as there were IO errors 2025/05/22 02:55:15 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (32.26s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:55:37 DEBUG : potato: md5 = cda06f2d94ca69fb8bea26f903c758b5 OK 2025/05/22 02:55:37 DEBUG : empty space: md5 = a4a7f8f2d37632483fca447f46360dce OK 2025/05/22 02:55:37 DEBUG : Waiting for deletions to finish 2025/05/22 02:55:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:55:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:55:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:55:38 DEBUG : empty space: Unchanged skipping 2025/05/22 02:55:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:55:38 INFO : potato: Deleted 2025/05/22 02:55:43 DEBUG : potato2: md5 = 06d2353bcf87e52712f56ef3231b51f6 OK 2025/05/22 02:55:43 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (12.27s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:55:49 DEBUG : potato: md5 = b5370b9e3e719fc67a6ee7c5f0f44279 OK 2025/05/22 02:55:49 DEBUG : empty space: md5 = 66bab225f6bb1808926f77add0976de8 OK 2025/05/22 02:55:49 DEBUG : Waiting for deletions to finish 2025/05/22 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:55:50 INFO : potato: Deleted 2025/05/22 02:55:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:55:50 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:55:50 DEBUG : empty space: Unchanged skipping 2025/05/22 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:55:55 DEBUG : potato2: md5 = 58844922db865aee014cc3dff24d42d5 OK 2025/05/22 02:55:55 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (22.35s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:56:11 DEBUG : potato: md5 = bbee0814a121d1dd6a762698be06ba89 OK 2025/05/22 02:56:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 02:56:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:56:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:56:17 DEBUG : potato2: md5 = bee88228d3ddabdfac9014bda41b1db0 OK 2025/05/22 02:56:17 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (11.76s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:56:29 DEBUG : potato2: md5 = 81333509ae8701f16884218070373660 OK 2025/05/22 02:56:29 DEBUG : empty space: md5 = 4026d9d9391e14935a4fe0bd474a7bbd OK 2025/05/22 02:56:35 DEBUG : enormous: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : enormous: Excluded 2025/05/22 02:56:35 DEBUG : potato2: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : potato2: Excluded 2025/05/22 02:56:35 DEBUG : potato2: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : potato2: Excluded 2025/05/22 02:56:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:56:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:56:35 DEBUG : empty space: Unchanged skipping 2025/05/22 02:56:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:56:35 DEBUG : Waiting for deletions to finish 2025/05/22 02:56:35 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:56:35 DEBUG : enormous: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : enormous: Excluded 2025/05/22 02:56:35 DEBUG : potato2: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : potato2: Excluded 2025/05/22 02:56:35 DEBUG : potato2: Excluded (Size Filter) 2025/05/22 02:56:35 DEBUG : potato2: Excluded 2025/05/22 02:56:35 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for checks to finish 2025/05/22 02:56:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:56:35 DEBUG : empty space: Unchanged skipping 2025/05/22 02:56:35 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for transfers to finish 2025/05/22 02:56:35 DEBUG : Waiting for deletions to finish 2025/05/22 02:56:35 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncWithExclude (22.89s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:56:51 DEBUG : potato2: md5 = 428d019e018cd846aacaa8a399aec45d OK 2025/05/22 02:56:51 DEBUG : empty space: md5 = e3e5c19a116f1e0651bec0d0e0f8e764 OK 2025/05/22 02:56:52 DEBUG : enormous: md5 = 857a12223a0fe6cf6f55c126c4d96936 OK 2025/05/22 02:56:52 DEBUG : enormous: Excluded (Size Filter) 2025/05/22 02:56:52 DEBUG : enormous: Excluded 2025/05/22 02:56:52 DEBUG : potato2: Excluded (Size Filter) 2025/05/22 02:56:52 DEBUG : potato2: Excluded 2025/05/22 02:56:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:56:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:56:53 DEBUG : empty space: Unchanged skipping 2025/05/22 02:56:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:56:53 DEBUG : Waiting for deletions to finish 2025/05/22 02:56:53 INFO : enormous: Deleted 2025/05/22 02:56:58 INFO : potato2: Deleted 2025/05/22 02:56:58 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:56:58 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for checks to finish 2025/05/22 02:56:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:56:58 DEBUG : empty space: Unchanged skipping 2025/05/22 02:56:58 DEBUG : Local file system at /tmp/rclone2897061976: Waiting for transfers to finish 2025/05/22 02:56:58 DEBUG : Waiting for deletions to finish 2025/05/22 02:56:58 INFO : enormous: Deleted 2025/05/22 02:56:58 INFO : potato2: Deleted 2025/05/22 02:56:58 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (13.35s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:57:09 DEBUG : one: md5 = 902d36ae7ce74b3005a5f16c66753793 OK 2025/05/22 02:57:10 DEBUG : two: md5 = 34d41030d588d196733eae7ee897ecbc OK 2025/05/22 02:57:15 DEBUG : three: md5 = 46b5d9cca10b1dd8cf6363392c031294 OK 2025/05/22 02:57:20 DEBUG : four: md5 = 9047b3c8ff394beea367325ff61cf3b3 OK 2025/05/22 02:57:31 DEBUG : five: Need to transfer - File not found at Destination 2025/05/22 02:57:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:57:31 DEBUG : three: Sizes identical 2025/05/22 02:57:31 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/05/22 02:57:31 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/05/22 02:57:31 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/05/22 02:57:31 DEBUG : two: md5 = 888794ded34ddf32afa149af2f2afde5 OK 2025/05/22 02:57:31 INFO : two: Copied (replaced existing) 2025/05/22 02:57:31 DEBUG : five: md5 = 90aacc5bddd02517667d82392a322c96 OK 2025/05/22 02:57:31 INFO : five: Copied (new) 2025/05/22 02:57:36 DEBUG : one: Destination is newer than source, skipping 2025/05/22 02:57:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:57:36 DEBUG : four: md5 = a716786e3f38cca1b5e9720acf3d967a OK 2025/05/22 02:57:36 INFO : four: Copied (replaced existing) 2025/05/22 02:57:36 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (63.59s) === 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:1538: Can track renames: false 2025/05/22 02:58:03 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Ignoring --track-renames as the source and destination do not have a common hash 2025/05/22 02:58:03 DEBUG : potato: Need to transfer - File not found at Destination 2025/05/22 02:58:03 DEBUG : yam: Need to transfer - File not found at Destination 2025/05/22 02:58:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:03 DEBUG : yam: md5 = bc727c920e8c087f6ce126344a15516d OK 2025/05/22 02:58:03 INFO : yam: Copied (new) 2025/05/22 02:58:03 DEBUG : potato: md5 = 4bd91f88ee82facde8eaaa383dc2232a OK 2025/05/22 02:58:03 INFO : potato: Copied (new) 2025/05/22 02:58:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:58:03 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Ignoring --track-renames as the source and destination do not have a common hash 2025/05/22 02:58:04 DEBUG : yaml: Need to transfer - File not found at Destination 2025/05/22 02:58:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:04 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:58:04 DEBUG : potato: Unchanged skipping 2025/05/22 02:58:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:12 DEBUG : yaml: md5 = 11ca5649971ed26f2de4775f1abd29cd OK 2025/05/22 02:58:12 INFO : yaml: Copied (new) 2025/05/22 02:58:12 DEBUG : Waiting for deletions to finish 2025/05/22 02:58:12 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (21.06s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/05/22 02:58:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Making map for --track-renames 2025/05/22 02:58:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Finished making map for --track-renames 2025/05/22 02:58:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:24 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/05/22 02:58:24 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/05/22 02:58:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for renames to finish 2025/05/22 02:58:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:24 DEBUG : yam: md5 = 588bda26ce256155719424996d79dc07 OK 2025/05/22 02:58:24 INFO : yam: Copied (new) 2025/05/22 02:58:24 DEBUG : potato: md5 = 53149845d489e81a227b7e0a0d5d9456 OK 2025/05/22 02:58:24 INFO : potato: Copied (new) 2025/05/22 02:58:24 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:58:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Making map for --track-renames 2025/05/22 02:58:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Finished making map for --track-renames 2025/05/22 02:58:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:25 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:58:25 DEBUG : potato: Unchanged skipping 2025/05/22 02:58:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for renames to finish 2025/05/22 02:58:40 INFO : yam: Copied (server-side copy) to: yaml 2025/05/22 02:58:40 INFO : yam: Deleted 2025/05/22 02:58:40 INFO : yaml: Renamed from "yam" 2025/05/22 02:58:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (22.48s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:1649: Can track renames: true 2025/05/22 02:58:46 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Making map for --track-renames 2025/05/22 02:58:46 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Finished making map for --track-renames 2025/05/22 02:58:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:46 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/05/22 02:58:46 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/05/22 02:58:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for renames to finish 2025/05/22 02:58:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:46 DEBUG : potato: md5 = 2380f12a5140d69b22f3f408c5e24c89 OK 2025/05/22 02:58:46 INFO : potato: Copied (new) 2025/05/22 02:58:47 DEBUG : sub/yam: md5 = 3512ca6195ccc3b4c59669a104eea786 OK 2025/05/22 02:58:47 INFO : sub/yam: Copied (new) 2025/05/22 02:58:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:58:47 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 02:58:47 DEBUG : potato: Unchanged skipping 2025/05/22 02:58:47 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Making map for --track-renames 2025/05/22 02:58:47 INFO : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Finished making map for --track-renames 2025/05/22 02:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for renames to finish 2025/05/22 02:58:58 INFO : sub/yam: Copied (server-side copy) to: yam 2025/05/22 02:58:58 INFO : sub/yam: Deleted 2025/05/22 02:58:58 INFO : yam: Renamed from "sub/yam" 2025/05/22 02:58:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:58:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (13.25s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:58:59 DEBUG : Creating backend with remote "/tmp/rclone2897061976/dir1" 2025/05/22 02:58:59 DEBUG : Config file has changed externally - reloading 2025/05/22 02:58:59 DEBUG : Creating backend with remote "/tmp/rclone2897061976/dir2" 2025/05/22 02:58:59 DEBUG : Local file system at /tmp/rclone2897061976/dir2: Using server-side directory move 2025/05/22 02:58:59 INFO : Local file system at /tmp/rclone2897061976/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/05/22 02:58:59 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/05/22 02:58:59 INFO : file1.txt: Moved (server-side) 2025/05/22 02:58:59 DEBUG : Local file system at /tmp/rclone2897061976/dir2: Waiting for checks to finish 2025/05/22 02:58:59 DEBUG : Local file system at /tmp/rclone2897061976/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.23s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:59:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:59:00 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/05/22 02:59:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:59:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:59:08 DEBUG : sub dir/hello world: md5 = 350efcce75ebe3e5230fce5c88bfacae OK 2025/05/22 02:59:08 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:59:08 INFO : sub dir/hello world: Deleted 2025/05/22 02:59:08 DEBUG : nested/sub dir/file: md5 = baa2af35449779a7d50a2bef93ffdd3e OK 2025/05/22 02:59:08 INFO : nested/sub dir/file: Copied (new) 2025/05/22 02:59:08 INFO : nested/sub dir/file: Deleted 2025/05/22 02:59:08 INFO : sub dir: Removing directory 2025/05/22 02:59:08 INFO : nested/sub dir: Removing directory 2025/05/22 02:59:08 INFO : nested: Removing directory 2025/05/22 02:59:08 DEBUG : Local file system at /tmp/rclone2897061976: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (19.92s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:59:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/22 02:59:20 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/05/22 02:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:59:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:59:25 DEBUG : sub dir/hello world: md5 = f997d603b8d694af7a413ed2fa653e83 OK 2025/05/22 02:59:25 INFO : sub dir/hello world: Copied (new) 2025/05/22 02:59:25 INFO : sub dir/hello world: Deleted 2025/05/22 02:59:25 DEBUG : nested/sub dir/file: md5 = debbdb2bc02aea852c8e20f286795a39 OK 2025/05/22 02:59:25 INFO : nested/sub dir/file: Copied (new) 2025/05/22 02:59:25 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (16.61s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:59:36 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/22 02:59:36 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/05/22 02:59:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:59:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:59:37 DEBUG : existing-b: md5 = c6c7d802a0a271876ff43633cbb54e23 OK 2025/05/22 02:59:37 INFO : existing-b: Copied (new) 2025/05/22 02:59:37 INFO : existing-b: Deleted 2025/05/22 02:59:37 DEBUG : existing: md5 = dbcb6e09d120f9030738a845bdceaa9a OK 2025/05/22 02:59:37 INFO : existing: Copied (new) 2025/05/22 02:59:37 INFO : existing: Deleted 2025/05/22 02:59:37 DEBUG : existing: Destination exists, skipping 2025/05/22 02:59:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 02:59:37 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/05/22 02:59:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 02:59:37 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (2.09s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 02:59:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kaxamuc8jude" 2025/05/22 02:59:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kaxamuc8jude.bin" 2025/05/22 02:59:39 DEBUG : Swift container crypt path rclone-test-kaxamuc8jude.bin: Auto set use_segments_container to true 2025/05/22 02:59:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kaxamuc8jude" 2025/05/22 02:59:44 DEBUG : Swift container crypt path rclone-test-kaxamuc8jude: Auto set use_segments_container to true 2025/05/22 02:59:55 DEBUG : potato2: md5 = f31933bb35480a1de825c0f56c7f1683 OK 2025/05/22 02:59:55 DEBUG : empty space: md5 = e23d4e039ebe7963988f415a70012b11 OK 2025/05/22 03:00:01 DEBUG : potato3: md5 = 16824a7c192dfa107c638c08d60678fd OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-kaxamuc8jude' 2025/05/22 03:00:12 DEBUG : empty space: md5 = 4689146ae4dc8fef875f271f1ceafbe3 OK 2025/05/22 03:00:12 DEBUG : potato3: md5 = c0730f62355afa6d7356a94ec2779de9 OK 2025/05/22 03:00:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:00:12 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/22 03:00:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kaxamuc8jude': Waiting for checks to finish 2025/05/22 03:00:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:00:17 DEBUG : empty space: Unchanged skipping 2025/05/22 03:00:18 INFO : empty space: Deleted 2025/05/22 03:00:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kaxamuc8jude': Waiting for transfers to finish 2025/05/22 03:00:18 INFO : potato3: Copied (server-side copy) 2025/05/22 03:00:23 INFO : potato3: Deleted 2025/05/22 03:00:33 INFO : potato2: Copied (server-side copy) 2025/05/22 03:00:38 INFO : potato2: Deleted 2025/05/22 03:00:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tapayok2tido" 2025/05/22 03:00:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tapayok2tido.bin" 2025/05/22 03:00:43 DEBUG : Swift container crypt path rclone-test-tapayok2tido.bin: Auto set use_segments_container to true 2025/05/22 03:00:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tapayok2tido" 2025/05/22 03:00:49 DEBUG : Swift container crypt path rclone-test-tapayok2tido: Auto set use_segments_container to true 2025/05/22 03:00:55 DEBUG : empty space: Need to transfer - File not found at Destination 2025/05/22 03:00:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:00:55 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/22 03:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tapayok2tido': Waiting for checks to finish 2025/05/22 03:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tapayok2tido': Waiting for transfers to finish 2025/05/22 03:01:00 INFO : potato3: Copied (server-side copy) 2025/05/22 03:01:00 INFO : potato3: Deleted 2025/05/22 03:01:10 INFO : empty space: Copied (server-side copy) 2025/05/22 03:01:15 INFO : potato2: Copied (server-side copy) 2025/05/22 03:01:16 INFO : empty space: Deleted 2025/05/22 03:01:20 INFO : potato2: Deleted 2025/05/22 03:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tapayok2tido': Purge remote 2025/05/22 03:01:26 DEBUG : Waiting for deletions to finish 2025/05/22 03:01:26 INFO : potato3.bin: Deleted 2025/05/22 03:01:31 INFO : empty space.bin: Deleted 2025/05/22 03:01:31 INFO : potato2.bin: Deleted 2025/05/22 03:01:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kaxamuc8jude': Purge remote 2025/05/22 03:01:31 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (112.97s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:01:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cahotik1neve" 2025/05/22 03:01:31 DEBUG : Config file has changed externally - reloading 2025/05/22 03:01:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cahotik1neve.bin" 2025/05/22 03:01:32 DEBUG : Swift container crypt path rclone-test-cahotik1neve.bin: Auto set use_segments_container to true 2025/05/22 03:01:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cahotik1neve" 2025/05/22 03:01:37 DEBUG : Swift container crypt path rclone-test-cahotik1neve: Auto set use_segments_container to true 2025/05/22 03:01:48 DEBUG : potato2: md5 = bcc4f02d5a35ec44d6c6b76a053f23a4 OK 2025/05/22 03:01:48 DEBUG : empty space: md5 = a0556d33c44fdb531c30d9734a20d000 OK 2025/05/22 03:01:53 DEBUG : potato3: md5 = 776e299a2bce867aa9fcf36aecb65f57 OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-cahotik1neve' 2025/05/22 03:02:04 DEBUG : empty space: md5 = 0aba1e9db18de2aea2476ec1e43d1b8b OK 2025/05/22 03:02:04 DEBUG : potato3: md5 = cf13bbecc72a4121a336897e74a1d3cb OK 2025/05/22 03:02:05 DEBUG : empty space: Excluded (Size Filter) 2025/05/22 03:02:05 DEBUG : empty space: Excluded 2025/05/22 03:02:05 DEBUG : empty space: Excluded (Size Filter) 2025/05/22 03:02:05 DEBUG : empty space: Excluded 2025/05/22 03:02:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:02:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/22 03:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cahotik1neve': Waiting for checks to finish 2025/05/22 03:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cahotik1neve': Waiting for transfers to finish 2025/05/22 03:02:10 INFO : potato3: Copied (server-side copy) 2025/05/22 03:02:15 INFO : potato3: Deleted 2025/05/22 03:02:15 INFO : potato2: Copied (server-side copy) 2025/05/22 03:02:20 INFO : potato2: Deleted 2025/05/22 03:02:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tixojum8tapi" 2025/05/22 03:02:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tixojum8tapi.bin" 2025/05/22 03:02:21 DEBUG : Swift container crypt path rclone-test-tixojum8tapi.bin: Auto set use_segments_container to true 2025/05/22 03:02:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tixojum8tapi" 2025/05/22 03:02:42 DEBUG : Swift container crypt path rclone-test-tixojum8tapi: Auto set use_segments_container to true 2025/05/22 03:02:47 DEBUG : empty space: Excluded (Size Filter) 2025/05/22 03:02:47 DEBUG : empty space: Excluded 2025/05/22 03:02:47 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:02:47 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/22 03:02:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tixojum8tapi': Waiting for checks to finish 2025/05/22 03:02:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tixojum8tapi': Waiting for transfers to finish 2025/05/22 03:02:58 INFO : potato3: Copied (server-side copy) 2025/05/22 03:02:58 INFO : potato3: Deleted 2025/05/22 03:03:03 INFO : potato2: Copied (server-side copy) 2025/05/22 03:03:03 INFO : potato2: Deleted 2025/05/22 03:03:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tixojum8tapi': Purge remote 2025/05/22 03:03:14 DEBUG : Waiting for deletions to finish 2025/05/22 03:03:19 INFO : potato2.bin: Deleted 2025/05/22 03:03:19 INFO : potato3.bin: Deleted 2025/05/22 03:03:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cahotik1neve': Purge remote 2025/05/22 03:03:19 DEBUG : Waiting for deletions to finish 2025/05/22 03:03:24 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (113.68s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:03:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kekohat4ruda" 2025/05/22 03:03:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kekohat4ruda.bin" 2025/05/22 03:03:25 DEBUG : Swift container crypt path rclone-test-kekohat4ruda.bin: Auto set use_segments_container to true 2025/05/22 03:03:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kekohat4ruda" 2025/05/22 03:03:31 DEBUG : Swift container crypt path rclone-test-kekohat4ruda: Auto set use_segments_container to true 2025/05/22 03:03:41 DEBUG : potato2: md5 = d0ed36d2502b14bb43291ba384c62c6f OK 2025/05/22 03:03:42 DEBUG : empty space: md5 = b792bc97daee506d35678ab0731357ec OK 2025/05/22 03:03:47 DEBUG : potato3: md5 = 0d4c9ba3b36216806fb2c532fa1123b8 OK 2025/05/22 03:03:47 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi' -> Encrypted drive 'TestCryptSwift:rclone-test-kekohat4ruda' 2025/05/22 03:03:58 DEBUG : empty space: md5 = f57e846248a2c897c9a3190ed11fe520 OK 2025/05/22 03:04:03 DEBUG : potato3: md5 = f830d9cb142b8331da5b89253afa8638 OK 2025/05/22 03:04:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:04:08 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/05/22 03:04:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kekohat4ruda': Waiting for checks to finish 2025/05/22 03:04:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:04:08 DEBUG : empty space: Unchanged skipping 2025/05/22 03:04:09 INFO : empty space: Deleted 2025/05/22 03:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kekohat4ruda': Waiting for transfers to finish 2025/05/22 03:04:19 INFO : potato3: Copied (server-side copy) 2025/05/22 03:04:19 INFO : potato2: Copied (server-side copy) 2025/05/22 03:04:24 INFO : potato3: Deleted 2025/05/22 03:04:24 INFO : potato2: Deleted 2025/05/22 03:04:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kanuwem8qawa" 2025/05/22 03:04:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kanuwem8qawa.bin" 2025/05/22 03:04:25 DEBUG : Swift container crypt path rclone-test-kanuwem8qawa.bin: Auto set use_segments_container to true 2025/05/22 03:04:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kanuwem8qawa" 2025/05/22 03:04:30 DEBUG : Swift container crypt path rclone-test-kanuwem8qawa: Auto set use_segments_container to true 2025/05/22 03:04:51 INFO : tomatoDir: Making directory 2025/05/22 03:04:51 DEBUG : empty space: Need to transfer - File not found at Destination 2025/05/22 03:04:51 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/22 03:04:51 DEBUG : potato3: Need to transfer - File not found at Destination 2025/05/22 03:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanuwem8qawa': Waiting for checks to finish 2025/05/22 03:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanuwem8qawa': Waiting for transfers to finish 2025/05/22 03:05:12 INFO : potato3: Copied (server-side copy) 2025/05/22 03:05:17 INFO : potato3: Deleted 2025/05/22 03:05:31 INFO : empty space: Copied (server-side copy) 2025/05/22 03:05:32 INFO : potato2: Copied (server-side copy) 2025/05/22 03:05:32 INFO : potato2: Deleted 2025/05/22 03:05:36 INFO : empty space: Deleted 2025/05/22 03:05:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanuwem8qawa': Purge remote 2025/05/22 03:05:42 DEBUG : Waiting for deletions to finish 2025/05/22 03:05:42 INFO : potato3.bin: Deleted 2025/05/22 03:05:47 INFO : empty space.bin: Deleted 2025/05/22 03:05:52 INFO : potato2.bin: Deleted 2025/05/22 03:05:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kekohat4ruda': Purge remote 2025/05/22 03:05:52 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (147.21s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:05:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/rclone-move-test" 2025/05/22 03:05:52 DEBUG : Config file has changed externally - reloading 2025/05/22 03:05:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-move-test.bin" 2025/05/22 03:05:53 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-move-test.bin: Auto set use_segments_container to true 2025/05/22 03:05:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-move-test" 2025/05/22 03:05:58 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-move-test: Auto set use_segments_container to true 2025/05/22 03:06:09 DEBUG : potato2: md5 = c9107f8a2988eb6495092adf0596cb60 OK --- PASS: TestServerSideMoveOverlap (27.10s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:06:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test" 2025/05/22 03:06:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test.bin" 2025/05/22 03:06:20 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/05/22 03:06:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test" 2025/05/22 03:06:25 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncOverlap (12.41s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:06:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test" 2025/05/22 03:06:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test.bin" 2025/05/22 03:06:32 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/05/22 03:06:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test" 2025/05/22 03:06:38 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test: Auto set use_segments_container to true 2025/05/22 03:06:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2" 2025/05/22 03:06:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2.bin" 2025/05/22 03:06:44 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/05/22 03:06:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2" 2025/05/22 03:06:55 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/05/22 03:07:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file" 2025/05/22 03:07:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file.bin" 2025/05/22 03:07:00 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/05/22 03:07:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file" 2025/05/22 03:07:06 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/05/22 03:07:17 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = a51ba90ffbcc01f90d91feb66311ca74 OK 2025/05/22 03:07:28 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/22 03:07:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test': Waiting for checks to finish 2025/05/22 03:07:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test': Waiting for transfers to finish 2025/05/22 03:07:28 DEBUG : Waiting for deletions to finish 2025/05/22 03:07:28 INFO : There was nothing to transfer 2025/05/22 03:07:33 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/22 03:07:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:07:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:07:33 DEBUG : Waiting for deletions to finish 2025/05/22 03:07:33 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:07:38 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/22 03:07:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/05/22 03:07:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/05/22 03:07:38 DEBUG : Waiting for deletions to finish 2025/05/22 03:07:38 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:07:43 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/22 03:07:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:07:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:07:43 DEBUG : Waiting for deletions to finish 2025/05/22 03:07:43 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:07:48 DEBUG : : Excluded 2025/05/22 03:07:48 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/05/22 03:07:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/05/22 03:07:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/05/22 03:07:48 DEBUG : Waiting for deletions to finish 2025/05/22 03:07:48 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (85.48s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:07:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:07:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:07:58 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:08:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:08:03 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:08:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/CompareDest" 2025/05/22 03:08:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/CompareDest.bin" 2025/05/22 03:08:09 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/CompareDest.bin: Auto set use_segments_container to true 2025/05/22 03:08:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/CompareDest" 2025/05/22 03:08:14 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/CompareDest: Auto set use_segments_container to true 2025/05/22 03:08:25 DEBUG : one: Need to transfer - File not found at Destination 2025/05/22 03:08:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:25 DEBUG : one: md5 = bfdced6e3722061d9b34bab363b178ef OK 2025/05/22 03:08:25 INFO : one: Copied (new) 2025/05/22 03:08:25 DEBUG : Waiting for deletions to finish 2025/05/22 03:08:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:31 DEBUG : one: md5 = b528d5dd090897bf8434f0433a0b7189 OK 2025/05/22 03:08:31 INFO : one: Copied (replaced existing) 2025/05/22 03:08:31 DEBUG : Waiting for deletions to finish 2025/05/22 03:08:31 DEBUG : dst/one: md5 = 61959b99c93b6a2051fde422a6e1ddc6 OK 2025/05/22 03:08:37 DEBUG : CompareDest/one: md5 = cda3bef4447fdec7c26262c3bef642c0 OK 2025/05/22 03:08:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:37 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/22 03:08:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:37 DEBUG : Waiting for deletions to finish 2025/05/22 03:08:37 INFO : There was nothing to transfer 2025/05/22 03:08:38 DEBUG : CompareDest/two: md5 = 2970d2245b68452dd4f527fa80d34bcf OK 2025/05/22 03:08:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:38 DEBUG : two: Destination found in --compare-dest, skipping 2025/05/22 03:08:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:38 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/22 03:08:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:38 DEBUG : Waiting for deletions to finish 2025/05/22 03:08:38 INFO : There was nothing to transfer 2025/05/22 03:08:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:43 DEBUG : two: Destination found in --compare-dest, skipping 2025/05/22 03:08:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:43 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:43 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/22 03:08:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:43 DEBUG : Waiting for deletions to finish 2025/05/22 03:08:43 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/05/22 03:08:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:49 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/05/22 03:08:49 DEBUG : two: Need to transfer - File not found at Destination 2025/05/22 03:08:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:08:49 DEBUG : two: md5 = 3291bb75721a661c22f024fda43707e1 OK 2025/05/22 03:08:49 INFO : two: Copied (new) 2025/05/22 03:08:54 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:08:54 DEBUG : one: Destination found in --compare-dest, skipping 2025/05/22 03:08:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:08:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (72.92s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:09:15 DEBUG : pre-dest1/1: md5 = 8fabe3591a9ff29ad5d4abb96cf24656 OK 2025/05/22 03:09:21 DEBUG : pre-dest2/2: md5 = caa7e9fac55be2dc1c0c676d63e02891 OK 2025/05/22 03:09:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dest" 2025/05/22 03:09:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dest.bin" 2025/05/22 03:09:21 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dest.bin: Auto set use_segments_container to true 2025/05/22 03:09:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dest" 2025/05/22 03:09:27 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dest: Auto set use_segments_container to true 2025/05/22 03:09:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/pre-dest1" 2025/05/22 03:09:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/pre-dest1.bin" 2025/05/22 03:09:33 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/pre-dest1.bin: Auto set use_segments_container to true 2025/05/22 03:09:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/pre-dest1" 2025/05/22 03:09:40 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/pre-dest1: Auto set use_segments_container to true 2025/05/22 03:09:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/pre-dest2" 2025/05/22 03:09:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/pre-dest2.bin" 2025/05/22 03:09:45 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/pre-dest2.bin: Auto set use_segments_container to true 2025/05/22 03:09:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/pre-dest2" 2025/05/22 03:09:51 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/pre-dest2: Auto set use_segments_container to true 2025/05/22 03:09:56 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:09:56 DEBUG : 1: Destination found in --compare-dest, skipping 2025/05/22 03:10:02 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:10:02 DEBUG : 2: Destination found in --compare-dest, skipping 2025/05/22 03:10:12 DEBUG : 3: Need to transfer - File not found at Destination 2025/05/22 03:10:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dest': Waiting for checks to finish 2025/05/22 03:10:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dest': Waiting for transfers to finish 2025/05/22 03:10:18 DEBUG : 3: md5 = 0a95d4f6f25503ec7d98d22348a0810f OK 2025/05/22 03:10:18 INFO : 3: Copied (new) 2025/05/22 03:10:18 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (83.83s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:10:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:10:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:10:34 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:10:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:10:40 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:10:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/CopyDest" 2025/05/22 03:10:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/CopyDest.bin" 2025/05/22 03:10:45 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/CopyDest.bin: Auto set use_segments_container to true 2025/05/22 03:10:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/CopyDest" 2025/05/22 03:10:51 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/CopyDest: Auto set use_segments_container to true 2025/05/22 03:11:02 DEBUG : one: Need to transfer - File not found at Destination 2025/05/22 03:11:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:11:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:11:02 DEBUG : one: md5 = ba5b7828ea4d28c3dfdfcc526d09d91d OK 2025/05/22 03:11:02 INFO : one: Copied (new) 2025/05/22 03:11:02 DEBUG : Waiting for deletions to finish 2025/05/22 03:11:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:11:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:11:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:11:08 DEBUG : one: md5 = d754c8775a61b226b9f8dc3ea0152316 OK 2025/05/22 03:11:08 INFO : one: Copied (replaced existing) 2025/05/22 03:11:08 DEBUG : Waiting for deletions to finish 2025/05/22 03:11:08 DEBUG : dst/one: md5 = 4aa9121590fc877e4d5b5f30e20222a3 OK 2025/05/22 03:11:11 DEBUG : CopyDest/one: md5 = b0deaf220e367e2095638721fb08d50d OK 2025/05/22 03:11:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/BackupDir" 2025/05/22 03:11:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/BackupDir.bin" 2025/05/22 03:11:12 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/BackupDir.bin: Auto set use_segments_container to true 2025/05/22 03:11:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/BackupDir" 2025/05/22 03:11:18 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/BackupDir: Auto set use_segments_container to true 2025/05/22 03:11:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:11:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:11:25 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:11:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/05/22 03:11:36 INFO : one: Copied (server-side copy) 2025/05/22 03:11:36 INFO : one: Deleted 2025/05/22 03:11:42 INFO : one: Copied (server-side copy) 2025/05/22 03:11:42 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/05/22 03:11:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:11:42 DEBUG : Waiting for deletions to finish 2025/05/22 03:11:42 DEBUG : CopyDest/two: md5 = 2be6bc883e172508c17b49073fa57185 OK 2025/05/22 03:11:43 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:11:43 DEBUG : one: Unchanged skipping 2025/05/22 03:11:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:11:48 INFO : two: Copied (server-side copy) 2025/05/22 03:11:48 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/05/22 03:11:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:11:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:11:48 DEBUG : Waiting for deletions to finish 2025/05/22 03:11:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:11:49 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:11:49 DEBUG : one: Unchanged skipping 2025/05/22 03:11:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:11:49 DEBUG : two: Unchanged skipping 2025/05/22 03:11:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:11:49 DEBUG : Waiting for deletions to finish 2025/05/22 03:11:49 INFO : There was nothing to transfer 2025/05/22 03:11:54 DEBUG : CopyDest/three: md5 = 9ea5a1c01a373b34301ee52dc53aaecb OK 2025/05/22 03:12:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:12:00 DEBUG : one: Unchanged skipping 2025/05/22 03:12:00 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/05/22 03:12:00 DEBUG : three: Destination not found in --copy-dest 2025/05/22 03:12:00 DEBUG : three: Need to transfer - File not found at Destination 2025/05/22 03:12:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:12:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:12:00 DEBUG : two: Unchanged skipping 2025/05/22 03:12:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:12:05 DEBUG : three: md5 = f28fea69def88d5020d9be3baf435662 OK 2025/05/22 03:12:05 INFO : three: Copied (new) 2025/05/22 03:12:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (108.36s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:12:22 DEBUG : dst/one: md5 = bbfb6034bcdfdf7a541f6ae78aae9eff OK 2025/05/22 03:12:23 DEBUG : dst/two: md5 = a1e39f8488faa17351f306532cbdbe4b OK 2025/05/22 03:12:23 DEBUG : dst/three.txt: md5 = ccb33757fa3ba0b36308fa4784de0c36 OK 2025/05/22 03:12:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:12:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:12:24 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:12:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:12:29 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:12:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/backup" 2025/05/22 03:12:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup.bin" 2025/05/22 03:12:35 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup.bin: Auto set use_segments_container to true 2025/05/22 03:12:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup" 2025/05/22 03:12:40 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup: Auto set use_segments_container to true 2025/05/22 03:12:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:12:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:12:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:12:46 DEBUG : two: Unchanged skipping 2025/05/22 03:13:06 INFO : one: Copied (server-side copy) 2025/05/22 03:13:07 INFO : one: Deleted 2025/05/22 03:13:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:13:07 DEBUG : one: md5 = ea04b15a0f709cbcce36ae4920046157 OK 2025/05/22 03:13:07 INFO : one: Copied (new) 2025/05/22 03:13:07 DEBUG : Waiting for deletions to finish 2025/05/22 03:13:22 INFO : three.txt: Copied (server-side copy) 2025/05/22 03:13:23 INFO : three.txt: Deleted 2025/05/22 03:13:23 INFO : three.txt: Moved into backup dir 2025/05/22 03:13:23 DEBUG : dst/three.txt: md5 = 1dca4566f6371d69839865332e85c138 OK 2025/05/22 03:13:29 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:13:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:13:29 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:13:29 DEBUG : two: Unchanged skipping 2025/05/22 03:13:48 INFO : one: Copied (server-side copy) 2025/05/22 03:13:50 INFO : one: Deleted 2025/05/22 03:13:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:13:51 DEBUG : one: md5 = 142764997d15b5a7c1a7cc1750e9d7c0 OK 2025/05/22 03:13:51 INFO : one: Copied (new) 2025/05/22 03:13:51 DEBUG : Waiting for deletions to finish 2025/05/22 03:14:11 INFO : three.txt: Copied (server-side copy) 2025/05/22 03:14:11 INFO : three.txt: Deleted 2025/05/22 03:14:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (125.05s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:14:28 DEBUG : dst/one: md5 = a030c31a2d2d1d2cce43936d5d94e895 OK 2025/05/22 03:14:28 DEBUG : dst/two: md5 = 5356507bbacca66090ca8eab221eb551 OK 2025/05/22 03:14:28 DEBUG : dst/three.txt: md5 = 9beafba13452f9d7ca4f902b0f824743 OK 2025/05/22 03:14:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:14:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:14:29 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:14:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:14:34 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:14:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/backup" 2025/05/22 03:14:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup.bin" 2025/05/22 03:14:40 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup.bin: Auto set use_segments_container to true 2025/05/22 03:14:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup" 2025/05/22 03:14:45 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup: Auto set use_segments_container to true 2025/05/22 03:14:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:14:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:14:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:14:50 DEBUG : two: Unchanged skipping 2025/05/22 03:15:01 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:15:01 INFO : one: Deleted 2025/05/22 03:15:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:15:01 DEBUG : one: md5 = 0c52b4afa1551d39de9bd2a7e20f602f OK 2025/05/22 03:15:01 INFO : one: Copied (new) 2025/05/22 03:15:01 DEBUG : Waiting for deletions to finish 2025/05/22 03:15:17 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:15:17 INFO : three.txt: Deleted 2025/05/22 03:15:17 INFO : three.txt: Moved into backup dir 2025/05/22 03:15:18 DEBUG : dst/three.txt: md5 = 1b6640f4c7fe7056ba35c5b69e597c4b OK 2025/05/22 03:15:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:15:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:15:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:15:23 DEBUG : two: Unchanged skipping 2025/05/22 03:15:28 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:15:29 INFO : one: Deleted 2025/05/22 03:15:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:15:29 DEBUG : one: md5 = 0021fa68cdb033272a40bf5bbb9a3561 OK 2025/05/22 03:15:29 INFO : one: Copied (new) 2025/05/22 03:15:29 DEBUG : Waiting for deletions to finish 2025/05/22 03:15:39 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:15:39 INFO : three.txt: Deleted 2025/05/22 03:15:39 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (82.85s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:15:50 DEBUG : dst/one: md5 = f85202371ed140bb5d06425cc73a83ed OK 2025/05/22 03:15:51 DEBUG : dst/two: md5 = 24b53feb3afef09ed84c5465120da2fd OK 2025/05/22 03:15:51 DEBUG : dst/three.txt: md5 = 2d66aa3a2ba37a4d3f423596146fa3f0 OK 2025/05/22 03:15:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:15:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:15:51 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:15:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:15:57 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:16:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/backup" 2025/05/22 03:16:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup.bin" 2025/05/22 03:16:03 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup.bin: Auto set use_segments_container to true 2025/05/22 03:16:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/backup" 2025/05/22 03:16:08 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/backup: Auto set use_segments_container to true 2025/05/22 03:16:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:16:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:16:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:16:14 DEBUG : two: Unchanged skipping 2025/05/22 03:16:24 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/22 03:16:24 INFO : one: Deleted 2025/05/22 03:16:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:16:24 DEBUG : one: md5 = 59005e9d9a7c8b834794a25431b795ed OK 2025/05/22 03:16:24 INFO : one: Copied (new) 2025/05/22 03:16:24 DEBUG : Waiting for deletions to finish 2025/05/22 03:16:35 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/22 03:16:35 INFO : three.txt: Deleted 2025/05/22 03:16:35 INFO : three.txt: Moved into backup dir 2025/05/22 03:16:36 DEBUG : dst/three.txt: md5 = 230e047a41540476865ffb41e54df89e OK 2025/05/22 03:16:36 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:16:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:16:36 DEBUG : two: Unchanged skipping 2025/05/22 03:16:42 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/22 03:16:42 INFO : one: Deleted 2025/05/22 03:16:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:16:42 DEBUG : one: md5 = ae06d77b0a77187f8db667e169c5e36f OK 2025/05/22 03:16:42 INFO : one: Copied (new) 2025/05/22 03:16:42 DEBUG : Waiting for deletions to finish 2025/05/22 03:16:47 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/22 03:16:47 INFO : three.txt: Deleted 2025/05/22 03:16:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (58.43s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:16:49 DEBUG : dst/one: md5 = cb3433d6fa7ca3e67b54fd31d287b947 OK 2025/05/22 03:16:49 DEBUG : dst/two: md5 = 2fde9ecb84a53dd72237187efa64412a OK 2025/05/22 03:16:49 DEBUG : dst/three.txt: md5 = 797ad0c17d4e15c57d045f9768f787b7 OK 2025/05/22 03:16:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:16:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:16:50 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:16:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:16:55 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:17:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:17:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:17:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:17:01 DEBUG : two: Unchanged skipping 2025/05/22 03:17:11 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:17:11 INFO : one: Deleted 2025/05/22 03:17:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:17:12 DEBUG : one: md5 = 325057f09ea1041aac7c7f2e807d207a OK 2025/05/22 03:17:12 INFO : one: Copied (new) 2025/05/22 03:17:12 DEBUG : Waiting for deletions to finish 2025/05/22 03:17:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:17:27 INFO : three.txt: Deleted 2025/05/22 03:17:27 INFO : three.txt: Moved into backup dir 2025/05/22 03:17:33 DEBUG : dst/three.txt: md5 = 286c048fcba8dffd7255a33483867b46 OK 2025/05/22 03:17:34 DEBUG : one.bak: Excluded (Path Filter) 2025/05/22 03:17:34 DEBUG : one.bak: Excluded 2025/05/22 03:17:34 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/05/22 03:17:34 DEBUG : three.txt.bak: Excluded 2025/05/22 03:17:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:17:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for checks to finish 2025/05/22 03:17:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:17:34 DEBUG : two: Unchanged skipping 2025/05/22 03:17:39 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:17:39 INFO : one: Deleted 2025/05/22 03:17:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi/dst': Waiting for transfers to finish 2025/05/22 03:17:39 DEBUG : one: md5 = 02c49c9505e734c3af3437baa6056451 OK 2025/05/22 03:17:39 INFO : one: Copied (new) 2025/05/22 03:17:39 DEBUG : Waiting for deletions to finish 2025/05/22 03:17:54 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:17:55 INFO : three.txt: Deleted 2025/05/22 03:17:55 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (72.12s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:18:01 DEBUG : dst/one: md5 = 6826ef225f137762d5f315b552d8ad62 OK 2025/05/22 03:18:01 DEBUG : dst/two: md5 = 49bbe7611256a36e3e25ff6cfe16c1ab OK 2025/05/22 03:18:01 DEBUG : dst/three.txt: md5 = ef888e796473826e3c72798cb9ad20af OK 2025/05/22 03:18:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:18:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:18:02 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:18:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:18:07 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:18:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:18:23 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:18:23 INFO : one: Deleted 2025/05/22 03:18:23 DEBUG : one: md5 = 598379f3180944ef969228a5910eb625 OK 2025/05/22 03:18:23 INFO : one: Copied (new) 2025/05/22 03:18:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:18:23 DEBUG : two: Unchanged skipping 2025/05/22 03:18:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/05/22 03:18:38 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:18:39 INFO : three.txt: Deleted 2025/05/22 03:18:39 DEBUG : three.txt: md5 = f577987452fb9a744cfee76278771e72 OK 2025/05/22 03:18:39 INFO : three.txt: Copied (new) 2025/05/22 03:18:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:18:55 INFO : one: Copied (server-side copy) to: one.bak 2025/05/22 03:18:55 INFO : one: Deleted 2025/05/22 03:18:55 DEBUG : one: md5 = 8b1b87f7d38c54d91fd331c59273f4a7 OK 2025/05/22 03:18:55 INFO : one: Copied (new) 2025/05/22 03:18:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:18:55 DEBUG : two: Unchanged skipping 2025/05/22 03:18:55 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/05/22 03:19:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/05/22 03:19:06 INFO : three.txt: Deleted 2025/05/22 03:19:06 DEBUG : three.txt: md5 = 65441ae99030c1129bf9da85d2af7bc8 OK 2025/05/22 03:19:06 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (71.35s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:19:12 DEBUG : dst/one: md5 = 4a71935701092301cedc25644e157b43 OK 2025/05/22 03:19:12 DEBUG : dst/two: md5 = debfdd0fbdc5eb196b57b7240a48001e OK 2025/05/22 03:19:13 DEBUG : dst/three.txt: md5 = 770aecb3ef0a79f19291d74f12eec865 OK 2025/05/22 03:19:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sajoduk4xehi/dst" 2025/05/22 03:19:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst.bin" 2025/05/22 03:19:13 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst.bin: Auto set use_segments_container to true 2025/05/22 03:19:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sajoduk4xehi/dst" 2025/05/22 03:19:19 DEBUG : Swift container crypt path rclone-test-sajoduk4xehi/dst: Auto set use_segments_container to true 2025/05/22 03:19:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/22 03:19:35 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/22 03:19:35 INFO : one: Deleted 2025/05/22 03:19:35 DEBUG : one: md5 = 5e390470670c5b7bcc5bf600aae78677 OK 2025/05/22 03:19:35 INFO : one: Copied (new) 2025/05/22 03:19:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:19:35 DEBUG : two: Unchanged skipping 2025/05/22 03:19:35 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/05/22 03:19:56 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/22 03:19:56 INFO : three.txt: Deleted 2025/05/22 03:19:56 DEBUG : three.txt: md5 = 1db028685cc6082849001dd66ef75d75 OK 2025/05/22 03:19:56 INFO : three.txt: Copied (new) 2025/05/22 03:19:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/22 03:20:02 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/05/22 03:20:02 INFO : one: Deleted 2025/05/22 03:20:03 DEBUG : one: md5 = cb560493273449814a3667e1214160ee OK 2025/05/22 03:20:03 INFO : one: Copied (new) 2025/05/22 03:20:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:20:03 DEBUG : two: Unchanged skipping 2025/05/22 03:20:03 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/05/22 03:20:23 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/05/22 03:20:23 INFO : three.txt: Deleted 2025/05/22 03:20:24 DEBUG : three.txt: md5 = 046d6d6e9288280c1f26372005c13ee5 OK 2025/05/22 03:20:24 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (77.82s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:20:35 DEBUG : Testêé: md5 = dbfc234a1ca50d9014c96e457c2e39eb OK 2025/05/22 03:20:40 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/05/22 03:20:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:20:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:20:46 DEBUG : Testêé: md5 = ca97339e9eab126da5136738eb05930b OK 2025/05/22 03:20:46 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/05/22 03:20:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (21.23s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:20:51 DEBUG : existing: Need to transfer - File not found at Destination 2025/05/22 03:20:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:20:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:20:51 DEBUG : existing: md5 = ca99ffeacebc823aa891773bce4c13e5 OK 2025/05/22 03:20:51 INFO : existing: Copied (new) 2025/05/22 03:20:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:20:52 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/05/22 03:20:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:20:52 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/05/22 03:20:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:20:52 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': not deleting files as there were IO errors 2025/05/22 03:20:52 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncImmutable (1.80s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:20:53 DEBUG : EXISTING: md5 = e41b552bed87ee4fd7ec20c9a4c41837 OK 2025/05/22 03:20:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:20:54 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:20:54 DEBUG : existing: Unchanged skipping 2025/05/22 03:20:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:20:54 DEBUG : Waiting for deletions to finish 2025/05/22 03:20:54 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.13s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", 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-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (0.41s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.21s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:21:00 DEBUG : both0: md5 = c45d1811c6d9503d051cf96a388ee640 OK 2025/05/22 03:21:00 DEBUG : only0: md5 = af0e25a25c057eb9ab84a0679b788c98 OK 2025/05/22 03:21:06 DEBUG : both1: md5 = 5f402fd060ff37b7e3a4bd92c0c2a2d3 OK 2025/05/22 03:21:16 DEBUG : only1: md5 = 74a2caa14681aa4dd9beacc11df4cb43 OK 2025/05/22 03:21:21 DEBUG : both2: md5 = 2cbbe4b670b05c799f442174319a86f1 OK 2025/05/22 03:21:21 DEBUG : only2: md5 = 1175aee5c11276551ee83a2e55946de0 OK 2025/05/22 03:21:22 DEBUG : both3: md5 = 125a96ce72e9d3d71c330edba12e3dac OK 2025/05/22 03:21:27 DEBUG : only3: md5 = 35c6e8160a4c0f6023580d4b30c55a42 OK 2025/05/22 03:21:28 DEBUG : both4: md5 = 40690c5a2d6b24acf697c2ad107f6fb3 OK 2025/05/22 03:21:33 DEBUG : only4: md5 = 292c51af4103e51596d5acdf2e35baf3 OK 2025/05/22 03:21:39 DEBUG : both5: md5 = ff83ae081e7e533399907c8168029101 OK 2025/05/22 03:21:44 DEBUG : only5: md5 = 766066e17b3587f1d74149d7d1dbe62b OK 2025/05/22 03:21:49 DEBUG : both6: md5 = 4cfda6fbbc9480987d1c0f7a4465270f OK 2025/05/22 03:21:50 DEBUG : only6: md5 = cf9c69cab1ebcfa704da06d04b28bc8c OK 2025/05/22 03:21:50 DEBUG : both7: md5 = ed2ce666024d8f220ab685c265ab2bd1 OK 2025/05/22 03:21:55 DEBUG : only7: md5 = ed0a5b9d53863837e6b1c28ce840f5ea OK 2025/05/22 03:22:01 DEBUG : both8: md5 = d5483c58a2c9a7b464161703670b088b OK 2025/05/22 03:22:06 DEBUG : only8: md5 = 5b0f44597f417598f135df5d85ebc8bd OK 2025/05/22 03:22:06 DEBUG : both9: md5 = 19a49255ba3ab8e2eedc250d9c479da7 OK 2025/05/22 03:22:07 DEBUG : only9: md5 = 24dd39e375bd4c9eb8e9c3d7baac2af3 OK 2025/05/22 03:22:07 DEBUG : both10: md5 = d958be442836243c37daf1b7308d0345 OK 2025/05/22 03:22:12 DEBUG : only10: md5 = 964fd0c1185b94e954ce7394fd271ab3 OK 2025/05/22 03:22:18 DEBUG : both11: md5 = 0ce53b900b03b74a161e516be6121290 OK 2025/05/22 03:22:23 DEBUG : only11: md5 = 3de8c183900c22ee4f3897d71e1d8a6c OK 2025/05/22 03:22:28 DEBUG : both12: md5 = da35967c3ab8c935de3dc5bd88e45399 OK 2025/05/22 03:22:34 DEBUG : only12: md5 = 6f9ebf893228231fef71e4982cd6a8af OK 2025/05/22 03:22:34 DEBUG : both13: md5 = 80776ce0bf997ca2b368d183d1b5f0cf OK 2025/05/22 03:22:39 DEBUG : only13: md5 = 2ee40c6b9ac0e65d1a8de25f219ef46f OK 2025/05/22 03:22:45 DEBUG : both14: md5 = 8f0e3bc6aa2805ceb0ec04abd122c3dc OK 2025/05/22 03:22:50 DEBUG : only14: md5 = 7f7887f81046845ab8d2f73042487dd2 OK 2025/05/22 03:22:50 DEBUG : both15: md5 = d33eb63f15f8e06e1466af8d3fef9ea1 OK 2025/05/22 03:22:51 DEBUG : only15: md5 = 33afc7d1c6ddfa24176442d8df289c90 OK 2025/05/22 03:22:56 DEBUG : both16: md5 = 951c267f3db35bf42be097d86c7461ef OK 2025/05/22 03:23:01 DEBUG : only16: md5 = 6d78a590ae8c891a8b7b484f2aaca04a OK 2025/05/22 03:23:06 DEBUG : both17: md5 = 51c86d4569d90697f92a66eff44555b4 OK 2025/05/22 03:23:07 DEBUG : only17: md5 = 3afcc1fbdd1cb0c1aec5525cc419cee7 OK 2025/05/22 03:23:07 DEBUG : both18: md5 = 477e6f55425bf5888555d4ee34461bd4 OK 2025/05/22 03:23:07 DEBUG : only18: md5 = 4fa9a0b07de2648112e89cf5b8445498 OK 2025/05/22 03:23:13 DEBUG : both19: md5 = 4812af0ad708f1133e8b2d7bc2fb39e2 OK 2025/05/22 03:23:13 DEBUG : only19: md5 = 32d2368ea08ae44e34142143751cb435 OK 2025/05/22 03:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:23:48 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both0: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both10: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both1: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both11: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both12: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both13: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both14: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both15: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both16: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both17: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both18: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both19: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both2: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both3: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both4: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both5: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both6: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both7: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both8: Unchanged skipping 2025/05/22 03:23:48 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:23:48 DEBUG : both9: Unchanged skipping 2025/05/22 03:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:23:48 DEBUG : Waiting for deletions to finish 2025/05/22 03:23:49 INFO : only15: Deleted 2025/05/22 03:23:49 INFO : only17: Deleted 2025/05/22 03:23:49 INFO : only2: Deleted 2025/05/22 03:23:49 INFO : only18: Deleted 2025/05/22 03:23:49 INFO : only19: Deleted 2025/05/22 03:23:49 INFO : only9: Deleted 2025/05/22 03:23:54 INFO : only12: Deleted 2025/05/22 03:23:54 INFO : only5: Deleted 2025/05/22 03:23:54 INFO : only13: Deleted 2025/05/22 03:23:54 INFO : only11: Deleted 2025/05/22 03:23:54 INFO : only0: Deleted 2025/05/22 03:23:59 INFO : only14: Deleted 2025/05/22 03:23:59 INFO : only4: Deleted 2025/05/22 03:23:59 INFO : only6: Deleted 2025/05/22 03:24:04 INFO : only3: Deleted 2025/05/22 03:24:04 INFO : only16: Deleted 2025/05/22 03:24:04 INFO : only10: Deleted 2025/05/22 03:24:04 INFO : only7: Deleted 2025/05/22 03:24:09 INFO : only1: Deleted 2025/05/22 03:24:14 INFO : only8: Deleted 2025/05/22 03:24:14 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (275.86s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:25:36 DEBUG : both0: md5 = 77ee20d521e40b08d00df7f562415e13 OK 2025/05/22 03:25:36 DEBUG : only0: md5 = 1445deddc3f0c0444b39157ea8f38108 OK 2025/05/22 03:25:41 DEBUG : both1: md5 = dbea2aa84a9a21aa11861ddb582e1666 OK 2025/05/22 03:25:46 DEBUG : only1: md5 = abbb30613865f17204013c72927ed84e OK 2025/05/22 03:25:46 DEBUG : both2: md5 = 98b54b0e617ff92eb2a29cb991058658 OK 2025/05/22 03:25:46 DEBUG : only2: md5 = da00f2a8f08c17e369599613cbba9edc OK 2025/05/22 03:25:47 DEBUG : both3: md5 = 97adb64c8d0e724e233e0fa948e2fcf2 OK 2025/05/22 03:25:52 DEBUG : only3: md5 = a3ea01658697776db73757fb5916aa97 OK 2025/05/22 03:25:52 DEBUG : both4: md5 = f28f751a0275ad19985c7254bb80acaf OK 2025/05/22 03:25:57 DEBUG : only4: md5 = 6933a986f53e69760915fa564d82b7fc OK 2025/05/22 03:26:02 DEBUG : both5: md5 = d66c62f588b9c79bb0dfac9231ac8ff7 OK 2025/05/22 03:26:07 DEBUG : only5: md5 = eab193dd2647d6c1c82faeb154fc97a9 OK 2025/05/22 03:26:13 DEBUG : both6: md5 = 798d238d7068dc5afe2f3c3cb626e9f5 OK 2025/05/22 03:26:13 DEBUG : only6: md5 = dbae9edc99744a23e967e5456a099472 OK 2025/05/22 03:26:13 DEBUG : both7: md5 = 3639dd0aae73035e911d54ad3db21f5f OK 2025/05/22 03:26:18 DEBUG : only7: md5 = 1694c46d899c82f822eea5b370dac211 OK 2025/05/22 03:26:23 DEBUG : both8: md5 = 0f83bd1d4dbd432bfc79c9dee9e6a34f OK 2025/05/22 03:26:29 DEBUG : only8: md5 = 680c29d45546a36283764f43107e791e OK 2025/05/22 03:26:29 DEBUG : both9: md5 = 88a54f6d8431d82eebdc18cdf93ee68d OK 2025/05/22 03:26:30 DEBUG : only9: md5 = 1e681d04b08705a64c84d94b7b374e64 OK 2025/05/22 03:26:30 DEBUG : both10: md5 = c04ae5692b16e65b817bc1824708b0d3 OK 2025/05/22 03:26:35 DEBUG : only10: md5 = 20ef28cac7ecab96faf1f681ab3f6c7d OK 2025/05/22 03:26:40 DEBUG : both11: md5 = 4cff56608891fb72a90c26132abfb04d OK 2025/05/22 03:26:45 DEBUG : only11: md5 = 33696ea0710346aa7a9d06d23c198121 OK 2025/05/22 03:26:46 DEBUG : both12: md5 = a2871649753cf6e71345d58aa4ef11fa OK 2025/05/22 03:26:51 DEBUG : only12: md5 = 871205ba49a2b7630bc64f6340edb4bb OK 2025/05/22 03:26:51 DEBUG : both13: md5 = dc943a89facc88b2b8d622e9bc425b16 OK 2025/05/22 03:26:56 DEBUG : only13: md5 = 0d4bb4ea143e5b36b96fd6a470e9280e OK 2025/05/22 03:27:01 DEBUG : both14: md5 = 54806134f549ab2af86a8d6adc582916 OK 2025/05/22 03:27:06 DEBUG : only14: md5 = c954b6692ca6e007efeb6da12bfb3582 OK 2025/05/22 03:27:07 DEBUG : both15: md5 = fcbb4af95595a9fb9972130c02bfac38 OK 2025/05/22 03:27:07 DEBUG : only15: md5 = 3a31bbc28845936472f29e27bccc190d OK 2025/05/22 03:27:12 DEBUG : both16: md5 = 876a1c333ca0e48de841e658508b268e OK 2025/05/22 03:27:17 DEBUG : only16: md5 = 37b5ad38e262ab6024ed7297520ae623 OK 2025/05/22 03:27:22 DEBUG : both17: md5 = 51279008e813997d3c1e0e53b0f8cf6b OK 2025/05/22 03:27:22 DEBUG : only17: md5 = 2bb3f27172fb58be17d30a81cd2d7dfa OK 2025/05/22 03:27:23 DEBUG : both18: md5 = a00850d8dd48f8c1b03b7193019ed14d OK 2025/05/22 03:27:23 DEBUG : only18: md5 = a126deae4161830aedc496993f9fa8da OK 2025/05/22 03:27:28 DEBUG : both19: md5 = 0f089ba0ada55cf2a68d063360023054 OK 2025/05/22 03:27:28 DEBUG : only19: md5 = 3045d5c2da6197e202dfee470d280705 OK 2025/05/22 03:28:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for checks to finish 2025/05/22 03:28:11 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both10: Unchanged skipping 2025/05/22 03:28:11 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both12: Unchanged skipping 2025/05/22 03:28:11 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both13: Unchanged skipping 2025/05/22 03:28:11 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both14: Unchanged skipping 2025/05/22 03:28:11 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both15: Unchanged skipping 2025/05/22 03:28:11 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:11 DEBUG : both16: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both1: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both18: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both19: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both11: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both2: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both0: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both3: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both4: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both5: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both6: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both7: Unchanged skipping 2025/05/22 03:28:16 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/05/22 03:28:16 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both9: Unchanged skipping 2025/05/22 03:28:16 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:16 DEBUG : both17: Unchanged skipping 2025/05/22 03:28:16 DEBUG : only0: md5 = 93a2ac25ee1b697b403b05174e46ff91 OK 2025/05/22 03:28:16 INFO : only0: Copied (replaced existing) 2025/05/22 03:28:21 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/05/22 03:28:21 DEBUG : both8: Unchanged skipping 2025/05/22 03:28:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Waiting for transfers to finish 2025/05/22 03:28:21 DEBUG : only1: md5 = d5274c3faa1571aa27fb78b185c733f7 OK 2025/05/22 03:28:21 INFO : only1: Copied (replaced existing) 2025/05/22 03:28:21 DEBUG : only12: md5 = cec3e677640f0a1609729456c772d2b3 OK 2025/05/22 03:28:21 INFO : only12: Copied (replaced existing) 2025/05/22 03:28:26 DEBUG : only10: md5 = 7ed5ddca11906b8ac54f87839507bfaa OK 2025/05/22 03:28:26 INFO : only10: Copied (replaced existing) 2025/05/22 03:28:26 DEBUG : only11: md5 = af8787405a1c1ca93828956e7a860a3f OK 2025/05/22 03:28:26 INFO : only11: Copied (replaced existing) 2025/05/22 03:28:26 DEBUG : only15: md5 = b314430d289b91e99f50d909bf19d761 OK 2025/05/22 03:28:26 INFO : only15: Copied (replaced existing) 2025/05/22 03:28:26 DEBUG : only17: md5 = e197fb48e02b45c1844b4aca50fd9d65 OK 2025/05/22 03:28:26 INFO : only17: Copied (replaced existing) 2025/05/22 03:28:26 DEBUG : only14: md5 = 0193a9f24a584cc32a3565d3bb4101d0 OK 2025/05/22 03:28:26 INFO : only14: Copied (replaced existing) 2025/05/22 03:28:27 DEBUG : only18: md5 = 44f709817d4a517dcecad84b1aa710ae OK 2025/05/22 03:28:27 INFO : only18: Copied (replaced existing) 2025/05/22 03:28:27 DEBUG : only19: md5 = 4113fca93409dbf836393e8682a72d7f OK 2025/05/22 03:28:27 INFO : only19: Copied (replaced existing) 2025/05/22 03:28:27 DEBUG : only2: md5 = f3c70b1b1e514664dd9ab5e424e1fbb9 OK 2025/05/22 03:28:27 INFO : only2: Copied (replaced existing) 2025/05/22 03:28:31 DEBUG : only13: md5 = 3d6a53e2a64f5c1e9cd5f9bc57c06b02 OK 2025/05/22 03:28:31 INFO : only13: Copied (replaced existing) 2025/05/22 03:28:31 DEBUG : only16: md5 = 1d98521bba8bcf2546e744a47417367e OK 2025/05/22 03:28:31 INFO : only16: Copied (replaced existing) 2025/05/22 03:28:32 DEBUG : only6: md5 = 45435362735d112fc7336e07ea55fb4c OK 2025/05/22 03:28:32 INFO : only6: Copied (replaced existing) 2025/05/22 03:28:32 DEBUG : only3: md5 = f724013adb0f7457c670b207dee79c4e OK 2025/05/22 03:28:32 INFO : only3: Copied (replaced existing) 2025/05/22 03:28:36 DEBUG : only5: md5 = 8101f3e255ba74bd13e2cb036d54f7dd OK 2025/05/22 03:28:36 INFO : only5: Copied (replaced existing) 2025/05/22 03:28:37 DEBUG : only9: md5 = 868ae21b35d3ebb6a91dd738a0e9d37d OK 2025/05/22 03:28:37 INFO : only9: Copied (replaced existing) 2025/05/22 03:28:37 DEBUG : only4: md5 = 4a5c91577218b80fcd109cdbbaa3f16b OK 2025/05/22 03:28:37 INFO : only4: Copied (replaced existing) 2025/05/22 03:28:37 DEBUG : only8: md5 = a5de7d5a4f24c83348958baf4c01ab16 OK 2025/05/22 03:28:37 INFO : only8: Copied (replaced existing) 2025/05/22 03:28:42 DEBUG : only7: md5 = 9470f8359b3ead5246119b010cc8f3ab OK 2025/05/22 03:28:42 INFO : only7: Copied (replaced existing) 2025/05/22 03:28:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want got only9 (0) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 Error: Should be true Test: TestSyncConcurrentTruncate Messages: Unexpected file "only9" --- FAIL: TestSyncConcurrentTruncate (402.40s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:32:13 DEBUG : empty_dir: Making directory with metadata 2025/05/22 03:32:13 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 03:32:13 DEBUG : empty_on_remote: Making directory with metadata 2025/05/22 03:32:13 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 03:32:13 INFO : empty_on_remote: Making directory 2025/05/22 03:32:13 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:32:26 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/05/22 03:32:26 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/05/22 03:32:26 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/05/22 03:32:26 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTime (40.58s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:32:54 DEBUG : empty_dir: Making directory with metadata 2025/05/22 03:32:54 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 03:32:54 DEBUG : empty_on_remote: Making directory with metadata 2025/05/22 03:32:54 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/22 03:32:54 INFO : empty_on_remote: Making directory 2025/05/22 03:32:54 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:33:07 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/05/22 03:33:07 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/05/22 03:33:07 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/05/22 03:33:07 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/05/22 03:33:07 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (34.64s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:33:28 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/22 03:33:28 INFO : sub dir: Making directory 2025/05/22 03:33:28 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestNothingToTransferWithEmptyDirs (32.26s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" 2025/05/22 03:34:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/22 03:34:00 INFO : sub dir: Making directory 2025/05/22 03:34:00 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi'", Local "Local file system at /tmp/rclone2897061976", Modify Window "1ns" --- PASS: TestNothingToTransferWithoutEmptyDirs (32.09s) FAIL 2025/05/22 03:34:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sajoduk4xehi': Purge remote 2025/05/22 03:34:32 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 51m27.398713786s (try 2/5): exit status 1: Failed [TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncConcurrentTruncate]