"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 4/5) 2025/07/02 06:54:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido" 2025/07/02 06:54:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/02 06:54:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido.bin" 2025/07/02 06:54:25 DEBUG : Swift container crypt path rclone-test-bahisos4vido.bin: Auto set use_segments_container to true 2025/07/02 06:54:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido" 2025/07/02 06:54:30 DEBUG : Swift container crypt path rclone-test-bahisos4vido: Auto set use_segments_container to true 2025/07/02 06:54:36 DEBUG : Creating backend with remote "/tmp/rclone4197441192" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:54:36 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/07/02 06:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.40s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:36 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/02 06:54:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:37 DEBUG : sub dir/hello world: md5 = 7efc9a05f4fa1bb20ac7da9fe9328f08 OK 2025/07/02 06:54:37 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:54:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.25s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.33s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:38 DEBUG : Creating backend with remote "/non-existing" 2025/07/02 06:54:38 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/07/02 06:54:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.15s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:54:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:43 DEBUG : sub dir/hello world: md5 = 09dd90d97694f291da838085155d3c2a OK 2025/07/02 06:54:43 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.83s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:44 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Running all checks before starting transfers 2025/07/02 06:54:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:54:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:44 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Checks finished, now starting transfers 2025/07/02 06:54:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:44 DEBUG : sub dir/hello world: md5 = 1620807f5f7889988bd237956cbbd0f1 OK 2025/07/02 06:54:44 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.01s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:45 ERROR : Ignoring --no-traverse with sync 2025/07/02 06:54:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:54:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:45 DEBUG : sub dir/hello world: md5 = 043c0c635215a665949eda7698164c92 OK 2025/07/02 06:54:45 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:54:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.03s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:46 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/07/02 06:54:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:46 DEBUG : hello world2: md5 = d3a3d12faa12050333daff21eb9b4a92 OK 2025/07/02 06:54:46 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.99s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:54:47 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 06:54:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:54:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:54:52 DEBUG : potato2: md5 = 8f72a0734b4b08953a15d63fd8c23bfd OK 2025/07/02 06:54:52 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (16.12s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 06:55:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:13 DEBUG : potato2: md5 = eb66eb8d21e29d34d1dbffb2ff3c9369 OK 2025/07/02 06:55:13 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (20.99s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:24 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/02 06:55:24 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 06:55:24 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/07/02 06:55:24 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/02 06:55:24 INFO : sub dir2: Making directory 2025/07/02 06:55:24 INFO : sub dir: Making directory 2025/07/02 06:55:24 INFO : sub dir2/sub sub dir2: Making directory 2025/07/02 06:55:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:24 DEBUG : sub dir/hello world: md5 = 1fb91f9d7ea19899aa986308a89c968c OK 2025/07/02 06:55:24 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/07/02 06:55:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.29s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:25 INFO : sub dir2: Making directory 2025/07/02 06:55:25 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/02 06:55:25 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 06:55:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:26 DEBUG : sub dir/hello world: md5 = 19fa8d877dee63088dc0b240d52cd422 OK 2025/07/02 06:55:26 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (1.16s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:26 DEBUG : sub dir2: Making directory with metadata 2025/07/02 06:55:26 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 06:55:26 INFO : sub dir2: Making directory 2025/07/02 06:55:26 INFO : sub dir: Making directory 2025/07/02 06:55:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:27 DEBUG : sub dir/hello world: md5 = 23d8d350dfd8e8d9134d472c9fa54ba7 OK 2025/07/02 06:55:27 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:55:27 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/07/02 06:55:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/07/02 06:55:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.40s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:409: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.34s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:28 INFO : sub dir2: Making directory 2025/07/02 06:55:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:28 DEBUG : sub dir/hello world: md5 = c76bedeac83c43983e864a91e0cf694a OK 2025/07/02 06:55:28 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:55:28 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (0.97s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:29 DEBUG : sub dir2: Making directory with metadata 2025/07/02 06:55:29 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 06:55:29 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/02 06:55:29 INFO : sub dir2: Making directory 2025/07/02 06:55:29 INFO : sub dir: Making directory 2025/07/02 06:55:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:29 DEBUG : sub dir/hello world: md5 = 6555ff8425106aeaeba426da0113e2f1 OK 2025/07/02 06:55:29 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:55:29 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/07/02 06:55:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.12s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.20s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:30 INFO : sub dir2: Making directory 2025/07/02 06:55:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:31 DEBUG : sub dir/hello world: md5 = 20cfbab2494f55e8f4828087e795732d OK 2025/07/02 06:55:31 INFO : sub dir/hello world: Copied (new) 2025/07/02 06:55:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.76s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:31 DEBUG : sub dir/hello world: md5 = 4dcfce80dc8a0ea61081ce21a3801781 OK 2025/07/02 06:55:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-quvihim4futu" 2025/07/02 06:55:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quvihim4futu.bin" 2025/07/02 06:55:32 DEBUG : Swift container crypt path rclone-test-quvihim4futu.bin: Auto set use_segments_container to true 2025/07/02 06:55:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quvihim4futu" 2025/07/02 06:55:38 DEBUG : Swift container crypt path rclone-test-quvihim4futu: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-quvihim4futu' 2025/07/02 06:55:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:55:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quvihim4futu': Waiting for checks to finish 2025/07/02 06:55:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quvihim4futu': Waiting for transfers to finish 2025/07/02 06:55:49 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:55:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quvihim4futu': Purge remote 2025/07/02 06:55:49 DEBUG : Waiting for deletions to finish 2025/07/02 06:55:49 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (18.42s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:50 DEBUG : sub dir/hello world: md5 = f52ad873a177abeeaf19b789bdc2cf0c OK 2025/07/02 06:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:55:50 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/02 06:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:55:50 DEBUG : sub dir/hello world: md5 = 1d80477dcdd2faa4ea97e073dd5d0952 OK 2025/07/02 06:55:50 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.39s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:55:51 DEBUG : sub dir/hello world: md5 = 7e8632e56849f426ba070030e9e6f3a0 OK 2025/07/02 06:55:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-juvemap4diwe" 2025/07/02 06:55:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-juvemap4diwe.bin" 2025/07/02 06:55:52 DEBUG : Swift container crypt path rclone-test-juvemap4diwe.bin: Auto set use_segments_container to true 2025/07/02 06:55:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-juvemap4diwe" 2025/07/02 06:55:57 DEBUG : Swift container crypt path rclone-test-juvemap4diwe: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe' 2025/07/02 06:56:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe': Waiting for checks to finish 2025/07/02 06:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe': Waiting for transfers to finish 2025/07/02 06:56:08 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:56:09 DEBUG : sub dir/hello world: md5 = 2ab763bdf8ab7425ff901ec3c172ca41 OK 2025/07/02 06:56:09 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/02 06:56:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe': Waiting for checks to finish 2025/07/02 06:56:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe': Waiting for transfers to finish 2025/07/02 06:56:15 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:56:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juvemap4diwe': Purge remote 2025/07/02 06:56:15 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:15 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (24.80s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:16 DEBUG : sub dir/hello world: md5 = 2a66f51da3759db31a9d7b556985e158 OK 2025/07/02 06:56:16 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/02 06:56:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:16 DEBUG : sub dir/hello world: md5 = 8bb7cfd8c11fbff8b0ab65be0996e861 OK 2025/07/02 06:56:16 INFO : sub dir/hello world: Copied (replaced existing) 2025/07/02 06:56:16 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.38s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:17 DEBUG : sub dir/hello world: md5 = 9c609b91890376805b5e073f970cf8e8 OK 2025/07/02 06:56:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-datebog9hufa" 2025/07/02 06:56:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-datebog9hufa.bin" 2025/07/02 06:56:18 DEBUG : Swift container crypt path rclone-test-datebog9hufa.bin: Auto set use_segments_container to true 2025/07/02 06:56:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-datebog9hufa" 2025/07/02 06:56:23 DEBUG : Swift container crypt path rclone-test-datebog9hufa: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa' 2025/07/02 06:56:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for checks to finish 2025/07/02 06:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for transfers to finish 2025/07/02 06:56:35 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:56:35 DEBUG : sub dir/hello world: md5 = e379b0d370159956ef1ea44df9b4bb09 OK 2025/07/02 06:56:35 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/02 06:56:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for checks to finish 2025/07/02 06:56:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for transfers to finish 2025/07/02 06:56:41 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:56:41 INFO : sub dir/hello world: Deleted 2025/07/02 06:56:41 DEBUG : sub dir/hello world: md5 = de2bd39cc9640f14357e834d10f22420 OK 2025/07/02 06:56:41 DEBUG : testing file moves 2025/07/02 06:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for checks to finish 2025/07/02 06:56:42 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/07/02 06:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Waiting for transfers to finish 2025/07/02 06:56:47 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/02 06:56:47 INFO : sub dir/hello world: Deleted 2025/07/02 06:56:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-datebog9hufa': Purge remote 2025/07/02 06:56:48 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:48 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (31.40s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:49 DEBUG : sub dir/hello world: md5 = 89745b9c0dec07791552bcff0417ad87 OK 2025/07/02 06:56:49 ERROR : error listing: directory not found 2025/07/02 06:56:49 INFO : Local file system at /tmp/rclone4197441192: Making directory 2025/07/02 06:56:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.16s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:50 DEBUG : sub dir/hello world: md5 = bb127570e4d9ea343bd3c91585ccbb06 OK 2025/07/02 06:56:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 06:56:50 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for checks to finish 2025/07/02 06:56:50 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for transfers to finish 2025/07/02 06:56:50 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/07/02 06:56:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.00s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:51 DEBUG : check sum: Need to transfer - File not found at Destination 2025/07/02 06:56:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:52 DEBUG : check sum: md5 = 6a7d4397377a40e32c35bdbe1c6ea855 OK 2025/07/02 06:56:52 INFO : check sum: Copied (new) 2025/07/02 06:56:52 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:52 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/07/02 06:56:52 DEBUG : check sum: Size of src and dst objects identical 2025/07/02 06:56:52 DEBUG : check sum: Unchanged skipping 2025/07/02 06:56:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:52 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:52 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.94s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:53 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/07/02 06:56:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:53 DEBUG : sizeonly: md5 = f4ec699b1d3ee2af9005407c91c75159 OK 2025/07/02 06:56:53 INFO : sizeonly: Copied (new) 2025/07/02 06:56:53 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:53 DEBUG : sizeonly: Sizes identical 2025/07/02 06:56:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:54 DEBUG : sizeonly: Unchanged skipping 2025/07/02 06:56:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:54 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:54 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.66s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:56:54 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/07/02 06:56:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:55 DEBUG : ignore-size: md5 = 5c7261ed17cc92cf39a6a5851270fbc6 OK 2025/07/02 06:56:55 INFO : ignore-size: Copied (new) 2025/07/02 06:56:55 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:56:56 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 06:56:56 DEBUG : ignore-size: Unchanged skipping 2025/07/02 06:56:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:56:56 DEBUG : Waiting for deletions to finish 2025/07/02 06:56:56 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.11s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:57:02 DEBUG : existing: md5 = d7b43960c576b06bb62418c20c9b732a OK 2025/07/02 06:57:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:57:12 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 06:57:12 DEBUG : existing: Unchanged skipping 2025/07/02 06:57:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:57:12 DEBUG : Waiting for deletions to finish 2025/07/02 06:57:12 INFO : There was nothing to transfer 2025/07/02 06:57:12 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/07/02 06:57:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:57:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:57:17 DEBUG : existing: md5 = 1b0e62fe8a725255c644def45fcad545 OK 2025/07/02 06:57:17 INFO : existing: Copied (replaced existing) 2025/07/02 06:57:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (31.69s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:57:28 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/02 06:57:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:57:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:57:33 DEBUG : existing: md5 = 35b66460f51204044f1e32c12db1a2cd OK 2025/07/02 06:57:33 INFO : existing: Copied (new) 2025/07/02 06:57:33 DEBUG : Waiting for deletions to finish 2025/07/02 06:57:39 DEBUG : existing: Destination exists, skipping 2025/07/02 06:57:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:57:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:57:39 DEBUG : Waiting for deletions to finish 2025/07/02 06:57:39 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (21.09s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:57:55 DEBUG : b/potato: md5 = 6db5e3a7c4828362ff51b34f2b810f5f OK 2025/07/02 06:58:00 DEBUG : c/non empty space: md5 = 6a0eaf5728df8cdb6724b07b8453c70c OK 2025/07/02 06:58:00 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/07/02 06:58:05 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/02 06:58:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:58:06 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 06:58:06 DEBUG : c/non empty space: Unchanged skipping 2025/07/02 06:58:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:58:11 DEBUG : a/potato2: md5 = d3f2c0743ac38a244dc8bc7fb5799607 OK 2025/07/02 06:58:11 INFO : a/potato2: Copied (new) 2025/07/02 06:58:11 DEBUG : Waiting for deletions to finish 2025/07/02 06:58:16 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (52.34s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:58:47 DEBUG : empty space: md5 = 5723fa38bd72b7578d29f6f3db338cca OK 2025/07/02 06:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:58:47 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/07/02 06:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:58:47 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/07/02 06:58:47 DEBUG : Waiting for deletions to finish 2025/07/02 06:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:58:47 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/07/02 06:58:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:58:52 DEBUG : empty space: md5 = fa397f93e26bfc010e90e806ffe7985f OK 2025/07/02 06:58:52 INFO : empty space: Copied (replaced existing) 2025/07/02 06:58:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (21.47s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.29s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:59:09 DEBUG : foo: md5 = ffe08a7045f84d4cec1391a44afaca87 OK 2025/07/02 06:59:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:59:14 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/07/02 06:59:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:59:19 DEBUG : foo: md5 = 640993b4cf139da71557105094951c67 OK 2025/07/02 06:59:19 INFO : foo: Copied (replaced existing) 2025/07/02 06:59:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (26.35s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 06:59:35 DEBUG : empty space: md5 = 17af29712dcdfa3017a7c17e3e88e443 OK 2025/07/02 06:59:40 DEBUG : potato: Need to transfer - File not found at Destination 2025/07/02 06:59:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 06:59:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 06:59:45 DEBUG : empty space: Unchanged skipping 2025/07/02 06:59:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 06:59:46 DEBUG : potato: md5 = e3442fdb96932d84693c2411298512d3 OK 2025/07/02 06:59:46 INFO : potato: Copied (new) 2025/07/02 06:59:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (41.69s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:00:16 DEBUG : potato: md5 = e6c094813f5b76cf984526d05c4fa053 OK 2025/07/02 07:00:22 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/07/02 07:00:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:00:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:00:27 DEBUG : potato: md5 = d80110e02db111ebc3d6537054364dcc OK 2025/07/02 07:00:27 INFO : potato: Copied (replaced existing) 2025/07/02 07:00:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (26.11s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:00:43 DEBUG : potato: md5 = d095bc7ba5f44c602d4d46a8742f8208 OK 2025/07/02 07:00:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:00:48 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/07/02 07:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:00:53 DEBUG : potato: md5 = 5deb6cc57f9d15f145400180f7536137 OK 2025/07/02 07:00:53 INFO : potato: Copied (replaced existing) 2025/07/02 07:00:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (26.49s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:01:09 DEBUG : potato: md5 = a8ce8855226f6c1fb12303109aba0b26 OK 2025/07/02 07:01:14 DEBUG : empty space: md5 = 5f6d587d2b2e139e2e948e78b08b8a1d OK 2025/07/02 07:01:14 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:01:14 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/07/02 07:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:01:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:01:14 DEBUG : empty space: Unchanged skipping 2025/07/02 07:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:01:14 DEBUG : Waiting for deletions to finish 2025/07/02 07:01:14 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (21.28s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:01:30 DEBUG : potato: md5 = da2b9380cd257f891682057adb132e5e OK 2025/07/02 07:01:36 DEBUG : empty space: md5 = 2e96204ca7a31be3582b5953348e0bb6 OK 2025/07/02 07:01:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:01:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:01:41 DEBUG : empty space: Unchanged skipping 2025/07/02 07:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:01:47 DEBUG : potato2: md5 = 9053134073b093a1617ec45cb092c5e4 OK 2025/07/02 07:01:47 INFO : potato2: Copied (new) 2025/07/02 07:01:47 DEBUG : Waiting for deletions to finish 2025/07/02 07:01:57 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (42.15s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:02:13 DEBUG : b/potato: md5 = 009f62f12295f265f1cc1e74cf9d0166 OK 2025/07/02 07:02:18 DEBUG : c/non empty space: md5 = 2d56a24d08d1d8ed1e6c533d9de90172 OK 2025/07/02 07:02:18 INFO : d: Making directory 2025/07/02 07:02:18 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/07/02 07:02:18 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/02 07:02:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:02:18 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:02:18 DEBUG : c/non empty space: Unchanged skipping 2025/07/02 07:02:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:02:23 DEBUG : a/potato2: md5 = c786a875d7f04a83ff861d580b52234d OK 2025/07/02 07:02:23 INFO : a/potato2: Copied (new) 2025/07/02 07:02:23 DEBUG : Waiting for deletions to finish 2025/07/02 07:02:29 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (37.02s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:02:50 DEBUG : b/potato: md5 = de90e70f125e4776717fe01ef1143917 OK 2025/07/02 07:02:55 DEBUG : c/non empty space: md5 = 550203dda2da87e31e25245946df8c17 OK 2025/07/02 07:02:55 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/07/02 07:03:06 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/02 07:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:03:06 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:03:06 DEBUG : c/non empty space: Unchanged skipping 2025/07/02 07:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:03:11 DEBUG : a/potato2: md5 = 73c1549e96da2d03d82c8bea96c3272f OK 2025/07/02 07:03:11 INFO : a/potato2: Copied (new) 2025/07/02 07:03:11 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': not deleting files as there were IO errors 2025/07/02 07:03:11 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (52.25s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:03:42 DEBUG : potato: md5 = a410ac7289b5964152caa22b41122856 OK 2025/07/02 07:03:47 DEBUG : empty space: md5 = 8b132b1670484890729f7e4711658b11 OK 2025/07/02 07:03:47 DEBUG : Waiting for deletions to finish 2025/07/02 07:03:47 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:03:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:03:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:03:52 DEBUG : empty space: Unchanged skipping 2025/07/02 07:03:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:03:52 DEBUG : potato2: md5 = c973c0c4aa0d0ea155df673addb98492 OK 2025/07/02 07:03:52 INFO : potato2: Copied (new) 2025/07/02 07:03:52 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (31.79s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:04:14 DEBUG : potato: md5 = 869cf43126e926d2246ae25814042e9c OK 2025/07/02 07:04:19 DEBUG : empty space: md5 = 7afa3f474019137289c5e839856ee25c OK 2025/07/02 07:04:19 DEBUG : Waiting for deletions to finish 2025/07/02 07:04:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:04:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:04:29 INFO : potato: Deleted 2025/07/02 07:04:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:04:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:04:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:04:30 DEBUG : empty space: Unchanged skipping 2025/07/02 07:04:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:04:35 DEBUG : potato2: md5 = 0999ab452f5ea917a00502dcf0a7168f OK 2025/07/02 07:04:35 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (47.04s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:05:01 DEBUG : potato: md5 = d3daf63a8c3f55de18e3821c806f069f OK 2025/07/02 07:05:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:05:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:05:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:05:06 DEBUG : potato2: md5 = 1369a10deb1283cacd4f470d5caa7662 OK 2025/07/02 07:05:06 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (36.20s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:05:37 DEBUG : potato2: md5 = 879ed29c24bb38cc61e7bc72c5014a42 OK 2025/07/02 07:05:42 DEBUG : empty space: md5 = a4d042bec877d2019eca78e5f80c0518 OK 2025/07/02 07:05:47 DEBUG : enormous: Excluded (Size Filter) 2025/07/02 07:05:47 DEBUG : enormous: Excluded 2025/07/02 07:05:47 DEBUG : potato2: Excluded (Size Filter) 2025/07/02 07:05:47 DEBUG : potato2: Excluded 2025/07/02 07:05:47 DEBUG : potato2: Excluded (Size Filter) 2025/07/02 07:05:47 DEBUG : potato2: Excluded 2025/07/02 07:05:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:05:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:05:47 DEBUG : empty space: Unchanged skipping 2025/07/02 07:05:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:05:47 DEBUG : Waiting for deletions to finish 2025/07/02 07:05:47 INFO : There was nothing to transfer 2025/07/02 07:05:53 DEBUG : enormous: Excluded (Size Filter) 2025/07/02 07:05:53 DEBUG : enormous: Excluded 2025/07/02 07:05:53 DEBUG : potato2: Excluded (Size Filter) 2025/07/02 07:05:53 DEBUG : potato2: Excluded 2025/07/02 07:05:53 DEBUG : potato2: Excluded (Size Filter) 2025/07/02 07:05:53 DEBUG : potato2: Excluded 2025/07/02 07:05:53 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for checks to finish 2025/07/02 07:05:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:05:53 DEBUG : empty space: Unchanged skipping 2025/07/02 07:05:53 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for transfers to finish 2025/07/02 07:05:53 DEBUG : Waiting for deletions to finish 2025/07/02 07:05:53 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (36.45s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:06:13 DEBUG : potato2: md5 = 01347863046015b231a9e9fad96241cd OK 2025/07/02 07:06:18 DEBUG : empty space: md5 = 3d808c6a4c72cb46d9ec51cbbf23b6b5 OK 2025/07/02 07:06:19 DEBUG : enormous: md5 = e7741c67bc32783407798165c18fb5ad OK 2025/07/02 07:06:24 DEBUG : enormous: Excluded (Size Filter) 2025/07/02 07:06:24 DEBUG : enormous: Excluded 2025/07/02 07:06:24 DEBUG : potato2: Excluded (Size Filter) 2025/07/02 07:06:24 DEBUG : potato2: Excluded 2025/07/02 07:06:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:06:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:06:29 DEBUG : empty space: Unchanged skipping 2025/07/02 07:06:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:06:29 DEBUG : Waiting for deletions to finish 2025/07/02 07:06:30 INFO : enormous: Deleted 2025/07/02 07:06:40 INFO : potato2: Deleted 2025/07/02 07:06:40 INFO : There was nothing to transfer 2025/07/02 07:06:40 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for checks to finish 2025/07/02 07:06:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:06:40 DEBUG : empty space: Unchanged skipping 2025/07/02 07:06:40 DEBUG : Local file system at /tmp/rclone4197441192: Waiting for transfers to finish 2025/07/02 07:06:40 DEBUG : Waiting for deletions to finish 2025/07/02 07:06:40 INFO : enormous: Deleted 2025/07/02 07:06:40 INFO : potato2: Deleted 2025/07/02 07:06:40 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (37.39s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:06:46 DEBUG : one: md5 = bed18a617b47fa4c393678a396f82546 OK 2025/07/02 07:06:46 DEBUG : two: md5 = 3132aaa55d77c7085ded8099eae6a7aa OK 2025/07/02 07:06:47 DEBUG : three: md5 = a2c5d46e5bd20aba223384fba427fa07 OK 2025/07/02 07:06:52 DEBUG : four: md5 = 3f9ff6dffa5a55b442610acc1cf551c7 OK 2025/07/02 07:06:58 DEBUG : five: Need to transfer - File not found at Destination 2025/07/02 07:06:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:06:58 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/07/02 07:06:58 DEBUG : one: Destination is newer than source, skipping 2025/07/02 07:06:58 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/07/02 07:06:58 DEBUG : three: Sizes identical 2025/07/02 07:06:58 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/07/02 07:06:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:06:58 DEBUG : two: md5 = c20e0211e5bce946199880b73bdb1892 OK 2025/07/02 07:06:58 INFO : two: Copied (replaced existing) 2025/07/02 07:07:03 DEBUG : five: md5 = a9172c805709207948fb662edcb7b3af OK 2025/07/02 07:07:03 INFO : five: Copied (new) 2025/07/02 07:07:04 DEBUG : four: md5 = 76fdf87d357375f6a7b4781ed5bedd23 OK 2025/07/02 07:07:04 INFO : four: Copied (replaced existing) 2025/07/02 07:07:04 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (30.05s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1467: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1467: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/07/02 07:07:16 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Ignoring --track-renames as the source and destination do not have a common hash 2025/07/02 07:07:16 DEBUG : potato: Need to transfer - File not found at Destination 2025/07/02 07:07:16 DEBUG : yam: Need to transfer - File not found at Destination 2025/07/02 07:07:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:07:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:07:16 DEBUG : yam: md5 = 023d18f801acc7c3e7f753b91566a45a OK 2025/07/02 07:07:16 INFO : yam: Copied (new) 2025/07/02 07:07:21 DEBUG : potato: md5 = 6795f3c77f34b46f3d4f4a2074043bd1 OK 2025/07/02 07:07:21 INFO : potato: Copied (new) 2025/07/02 07:07:21 DEBUG : Waiting for deletions to finish 2025/07/02 07:07:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Ignoring --track-renames as the source and destination do not have a common hash 2025/07/02 07:07:21 DEBUG : yaml: Need to transfer - File not found at Destination 2025/07/02 07:07:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:07:21 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:07:21 DEBUG : potato: Unchanged skipping 2025/07/02 07:07:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:07:22 DEBUG : yaml: md5 = 7c240d1ebdc4a87ee2e3a703ef30a37a OK 2025/07/02 07:07:22 INFO : yaml: Copied (new) 2025/07/02 07:07:22 DEBUG : Waiting for deletions to finish 2025/07/02 07:07:22 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (17.63s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/07/02 07:07:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Making map for --track-renames 2025/07/02 07:07:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Finished making map for --track-renames 2025/07/02 07:07:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:07:33 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/02 07:07:33 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/02 07:07:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for renames to finish 2025/07/02 07:07:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:07:34 DEBUG : yam: md5 = 4389d83384d2b301d95786cdd0a60a7c OK 2025/07/02 07:07:34 INFO : yam: Copied (new) 2025/07/02 07:07:39 DEBUG : potato: md5 = d64c0add48c36f718d42c5450e127515 OK 2025/07/02 07:07:39 INFO : potato: Copied (new) 2025/07/02 07:07:39 DEBUG : Waiting for deletions to finish 2025/07/02 07:07:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Making map for --track-renames 2025/07/02 07:07:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Finished making map for --track-renames 2025/07/02 07:07:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:07:39 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:07:39 DEBUG : potato: Unchanged skipping 2025/07/02 07:07:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for renames to finish 2025/07/02 07:07:49 INFO : yam: Copied (server-side copy) to: yaml 2025/07/02 07:07:49 INFO : yam: Deleted 2025/07/02 07:07:49 INFO : yaml: Renamed from "yam" 2025/07/02 07:07:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:07:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (22.07s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/07/02 07:07:55 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Making map for --track-renames 2025/07/02 07:07:55 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Finished making map for --track-renames 2025/07/02 07:07:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:07:55 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/02 07:07:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for renames to finish 2025/07/02 07:07:55 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/07/02 07:07:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:08:01 DEBUG : potato: md5 = dad028f3664d990ad0be4ebb6ab38cb6 OK 2025/07/02 07:08:01 INFO : potato: Copied (new) 2025/07/02 07:08:01 DEBUG : sub/yam: md5 = 3a55233c39bf734d017ceaee971b5455 OK 2025/07/02 07:08:01 INFO : sub/yam: Copied (new) 2025/07/02 07:08:01 DEBUG : Waiting for deletions to finish 2025/07/02 07:08:06 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:08:06 DEBUG : potato: Unchanged skipping 2025/07/02 07:08:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Making map for --track-renames 2025/07/02 07:08:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Finished making map for --track-renames 2025/07/02 07:08:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:08:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for renames to finish 2025/07/02 07:08:20 INFO : sub/yam: Copied (server-side copy) to: yam 2025/07/02 07:08:25 INFO : sub/yam: Deleted 2025/07/02 07:08:25 INFO : yam: Renamed from "sub/yam" 2025/07/02 07:08:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:08:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (40.85s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:08:36 DEBUG : Creating backend with remote "/tmp/rclone4197441192/dir1" 2025/07/02 07:08:36 DEBUG : Config file has changed externally - reloading 2025/07/02 07:08:36 DEBUG : Creating backend with remote "/tmp/rclone4197441192/dir2" 2025/07/02 07:08:36 DEBUG : Local file system at /tmp/rclone4197441192/dir2: Using server-side directory move 2025/07/02 07:08:36 INFO : Local file system at /tmp/rclone4197441192/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/02 07:08:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/07/02 07:08:36 DEBUG : Local file system at /tmp/rclone4197441192/dir2: Waiting for checks to finish 2025/07/02 07:08:36 DEBUG : Local file system at /tmp/rclone4197441192/dir2: Waiting for transfers to finish 2025/07/02 07:08:36 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.20s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:08:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 07:08:36 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/02 07:08:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:08:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:08:37 DEBUG : sub dir/hello world: md5 = babf047ccf14ce10a5a4a4afb3175745 OK 2025/07/02 07:08:37 INFO : sub dir/hello world: Copied (new) 2025/07/02 07:08:37 INFO : sub dir/hello world: Deleted 2025/07/02 07:08:42 DEBUG : nested/sub dir/file: md5 = f3698a96c844cc1cce06493e30b7c686 OK 2025/07/02 07:08:42 INFO : nested/sub dir/file: Copied (new) 2025/07/02 07:08:42 INFO : nested/sub dir/file: Deleted 2025/07/02 07:08:42 INFO : sub dir: Removing directory 2025/07/02 07:08:42 INFO : nested/sub dir: Removing directory 2025/07/02 07:08:42 INFO : nested: Removing directory 2025/07/02 07:08:42 DEBUG : Local file system at /tmp/rclone4197441192: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (11.17s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:08:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/02 07:08:48 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/02 07:08:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:08:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:08:48 DEBUG : sub dir/hello world: md5 = f417b4785ae8c4d9e16f2b3b2279ddf4 OK 2025/07/02 07:08:48 INFO : sub dir/hello world: Copied (new) 2025/07/02 07:08:48 INFO : sub dir/hello world: Deleted 2025/07/02 07:08:53 DEBUG : nested/sub dir/file: md5 = af30f2728b09f6644e0b481883202548 OK 2025/07/02 07:08:53 INFO : nested/sub dir/file: Copied (new) 2025/07/02 07:08:53 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (16.13s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:09:04 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/07/02 07:09:04 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/02 07:09:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:09:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:09:05 DEBUG : existing-b: md5 = bcddf8c18b73e51007188c3abec1e9b4 OK 2025/07/02 07:09:05 INFO : existing-b: Copied (new) 2025/07/02 07:09:05 INFO : existing-b: Deleted 2025/07/02 07:09:09 DEBUG : existing: md5 = 5734b3ec88c99d132434a971bd9b2e3e OK 2025/07/02 07:09:09 INFO : existing: Copied (new) 2025/07/02 07:09:09 INFO : existing: Deleted 2025/07/02 07:09:14 DEBUG : existing: Destination exists, skipping 2025/07/02 07:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:09:14 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/07/02 07:09:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:09:14 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (21.96s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:09:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vozavos4catu" 2025/07/02 07:09:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vozavos4catu.bin" 2025/07/02 07:09:26 DEBUG : Swift container crypt path rclone-test-vozavos4catu.bin: Auto set use_segments_container to true 2025/07/02 07:09:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vozavos4catu" 2025/07/02 07:09:32 DEBUG : Swift container crypt path rclone-test-vozavos4catu: Auto set use_segments_container to true 2025/07/02 07:09:42 DEBUG : potato2: md5 = f5bed384e0c1bbfc5dee372e84167889 OK 2025/07/02 07:09:47 DEBUG : empty space: md5 = 0d0a47323155fef1d686d7cce43ae342 OK 2025/07/02 07:09:53 DEBUG : potato3: md5 = 51182df72a068745bbf5a958e6fe2a5e OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-vozavos4catu' 2025/07/02 07:10:03 DEBUG : empty space: md5 = 754d8b639ee5e8d767035e601d0da0bd OK 2025/07/02 07:10:09 DEBUG : potato3: md5 = 847c603ed69fae75e232fafb105d1418 OK 2025/07/02 07:10:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:10:09 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/02 07:10:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vozavos4catu': Waiting for checks to finish 2025/07/02 07:10:09 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:10:09 DEBUG : empty space: Unchanged skipping 2025/07/02 07:10:14 INFO : empty space: Deleted 2025/07/02 07:10:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vozavos4catu': Waiting for transfers to finish 2025/07/02 07:10:19 INFO : potato2: Copied (server-side copy) 2025/07/02 07:10:24 INFO : potato3: Copied (server-side copy) 2025/07/02 07:10:24 INFO : potato2: Deleted 2025/07/02 07:10:32 INFO : potato3: Deleted 2025/07/02 07:10:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-topayuc1vofi" 2025/07/02 07:10:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-topayuc1vofi.bin" 2025/07/02 07:10:38 DEBUG : Swift container crypt path rclone-test-topayuc1vofi.bin: Auto set use_segments_container to true 2025/07/02 07:10:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-topayuc1vofi" 2025/07/02 07:10:44 DEBUG : Swift container crypt path rclone-test-topayuc1vofi: Auto set use_segments_container to true 2025/07/02 07:11:04 DEBUG : empty space: Need to transfer - File not found at Destination 2025/07/02 07:11:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:11:04 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/02 07:11:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-topayuc1vofi': Waiting for checks to finish 2025/07/02 07:11:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-topayuc1vofi': Waiting for transfers to finish 2025/07/02 07:11:25 INFO : potato3: Copied (server-side copy) 2025/07/02 07:11:30 INFO : potato3: Deleted 2025/07/02 07:11:50 INFO : empty space: Copied (server-side copy) 2025/07/02 07:11:55 INFO : potato2: Copied (server-side copy) 2025/07/02 07:11:55 INFO : empty space: Deleted 2025/07/02 07:12:00 INFO : potato2: Deleted 2025/07/02 07:12:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-topayuc1vofi': Purge remote 2025/07/02 07:12:06 DEBUG : Waiting for deletions to finish 2025/07/02 07:12:06 INFO : potato3.bin: Deleted 2025/07/02 07:12:06 INFO : empty space.bin: Deleted 2025/07/02 07:12:11 INFO : potato2.bin: Deleted 2025/07/02 07:12:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vozavos4catu': Purge remote 2025/07/02 07:12:11 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (165.66s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:12:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nobumox5taqo" 2025/07/02 07:12:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nobumox5taqo.bin" 2025/07/02 07:12:12 DEBUG : Swift container crypt path rclone-test-nobumox5taqo.bin: Auto set use_segments_container to true 2025/07/02 07:12:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nobumox5taqo" 2025/07/02 07:12:17 DEBUG : Swift container crypt path rclone-test-nobumox5taqo: Auto set use_segments_container to true 2025/07/02 07:12:28 DEBUG : potato2: md5 = 0bfb1693fa4ebf31ef008048b200eb59 OK 2025/07/02 07:12:33 DEBUG : empty space: md5 = dd2ea65ff512583e57fce6016d62986e OK 2025/07/02 07:12:38 DEBUG : potato3: md5 = 7ad32f12a1fb550b95403c6a966805f2 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-nobumox5taqo' 2025/07/02 07:12:54 DEBUG : empty space: md5 = 937b791db26a9e29d553d65e6c243d84 OK 2025/07/02 07:12:55 DEBUG : potato3: md5 = 73e4aa7a426878969f48a08a44fbbc0f OK 2025/07/02 07:13:00 DEBUG : empty space: Excluded (Size Filter) 2025/07/02 07:13:00 DEBUG : empty space: Excluded 2025/07/02 07:13:00 DEBUG : empty space: Excluded (Size Filter) 2025/07/02 07:13:00 DEBUG : empty space: Excluded 2025/07/02 07:13:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:13:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nobumox5taqo': Waiting for checks to finish 2025/07/02 07:13:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/02 07:13:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nobumox5taqo': Waiting for transfers to finish 2025/07/02 07:13:06 INFO : potato2: Copied (server-side copy) 2025/07/02 07:13:10 INFO : potato3: Copied (server-side copy) 2025/07/02 07:13:11 INFO : potato2: Deleted 2025/07/02 07:13:15 INFO : potato3: Deleted 2025/07/02 07:13:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kobigob4zomi" 2025/07/02 07:13:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kobigob4zomi.bin" 2025/07/02 07:13:16 DEBUG : Swift container crypt path rclone-test-kobigob4zomi.bin: Auto set use_segments_container to true 2025/07/02 07:13:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kobigob4zomi" 2025/07/02 07:13:22 DEBUG : Swift container crypt path rclone-test-kobigob4zomi: Auto set use_segments_container to true 2025/07/02 07:13:27 DEBUG : empty space: Excluded (Size Filter) 2025/07/02 07:13:27 DEBUG : empty space: Excluded 2025/07/02 07:13:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:13:27 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/02 07:13:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kobigob4zomi': Waiting for checks to finish 2025/07/02 07:13:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kobigob4zomi': Waiting for transfers to finish 2025/07/02 07:13:33 INFO : potato2: Copied (server-side copy) 2025/07/02 07:13:33 INFO : potato2: Deleted 2025/07/02 07:13:33 INFO : potato3: Copied (server-side copy) 2025/07/02 07:13:33 INFO : potato3: Deleted 2025/07/02 07:13:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kobigob4zomi': Purge remote 2025/07/02 07:13:34 DEBUG : Waiting for deletions to finish 2025/07/02 07:13:34 INFO : potato2.bin: Deleted 2025/07/02 07:13:34 INFO : potato3.bin: Deleted 2025/07/02 07:13:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nobumox5taqo': Purge remote 2025/07/02 07:13:34 DEBUG : Waiting for deletions to finish 2025/07/02 07:13:39 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (93.44s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:13:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zawopux2peta" 2025/07/02 07:13:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zawopux2peta.bin" 2025/07/02 07:13:45 DEBUG : Swift container crypt path rclone-test-zawopux2peta.bin: Auto set use_segments_container to true 2025/07/02 07:13:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zawopux2peta" 2025/07/02 07:13:51 DEBUG : Swift container crypt path rclone-test-zawopux2peta: Auto set use_segments_container to true 2025/07/02 07:14:01 DEBUG : potato2: md5 = 1839c5f8efe8d869d8461b24bfb9969c OK 2025/07/02 07:14:06 DEBUG : empty space: md5 = ac14155615ce5fd6a6b7ec8559620200 OK 2025/07/02 07:14:11 DEBUG : potato3: md5 = aa24cd2a723cfb824d4d1458719114f0 OK 2025/07/02 07:14:11 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido' -> Encrypted drive 'TestCryptSwift:rclone-test-zawopux2peta' 2025/07/02 07:14:17 DEBUG : empty space: md5 = ba2eb4ce7d1b57a89a9cb17d8e17bdba OK 2025/07/02 07:14:18 DEBUG : potato3: md5 = f1725ae5e48af43da5f2ade9f11e9c2a OK 2025/07/02 07:14:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:14:18 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/02 07:14:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zawopux2peta': Waiting for checks to finish 2025/07/02 07:14:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:14:18 DEBUG : empty space: Unchanged skipping 2025/07/02 07:14:23 INFO : empty space: Deleted 2025/07/02 07:14:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zawopux2peta': Waiting for transfers to finish 2025/07/02 07:14:23 INFO : potato3: Copied (server-side copy) 2025/07/02 07:14:24 INFO : potato2: Copied (server-side copy) 2025/07/02 07:14:29 INFO : potato3: Deleted 2025/07/02 07:14:29 INFO : potato2: Deleted 2025/07/02 07:14:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qoxumak6dega" 2025/07/02 07:14:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qoxumak6dega.bin" 2025/07/02 07:14:30 DEBUG : Swift container crypt path rclone-test-qoxumak6dega.bin: Auto set use_segments_container to true 2025/07/02 07:14:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qoxumak6dega" 2025/07/02 07:14:35 DEBUG : Swift container crypt path rclone-test-qoxumak6dega: Auto set use_segments_container to true 2025/07/02 07:14:41 INFO : tomatoDir: Making directory 2025/07/02 07:14:41 DEBUG : empty space: Need to transfer - File not found at Destination 2025/07/02 07:14:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/02 07:14:41 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/02 07:14:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoxumak6dega': Waiting for checks to finish 2025/07/02 07:14:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoxumak6dega': Waiting for transfers to finish 2025/07/02 07:14:46 INFO : potato3: Copied (server-side copy) 2025/07/02 07:14:47 INFO : potato3: Deleted 2025/07/02 07:14:51 INFO : empty space: Copied (server-side copy) 2025/07/02 07:14:52 INFO : empty space: Deleted 2025/07/02 07:14:52 INFO : potato2: Copied (server-side copy) 2025/07/02 07:14:52 INFO : potato2: Deleted 2025/07/02 07:14:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoxumak6dega': Purge remote 2025/07/02 07:14:58 DEBUG : Waiting for deletions to finish 2025/07/02 07:14:58 INFO : potato3.bin: Deleted 2025/07/02 07:15:03 INFO : empty space.bin: Deleted 2025/07/02 07:15:03 INFO : potato2.bin: Deleted 2025/07/02 07:15:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zawopux2peta': Purge remote 2025/07/02 07:15:03 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (79.01s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:15:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/rclone-move-test" 2025/07/02 07:15:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-move-test.bin" 2025/07/02 07:15:04 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-move-test.bin: Auto set use_segments_container to true 2025/07/02 07:15:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-move-test" 2025/07/02 07:15:10 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-move-test: Auto set use_segments_container to true 2025/07/02 07:15:20 DEBUG : potato2: md5 = a8a01d50fc2b0314f9572f9d35ddc6f6 OK --- PASS: TestServerSideMoveOverlap (27.25s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:15:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test" 2025/07/02 07:15:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test.bin" 2025/07/02 07:15:31 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test.bin: Auto set use_segments_container to true 2025/07/02 07:15:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test" 2025/07/02 07:15:42 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (16.52s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:15:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test" 2025/07/02 07:15:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test.bin" 2025/07/02 07:15:48 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test.bin: Auto set use_segments_container to true 2025/07/02 07:15:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test" 2025/07/02 07:15:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test: Auto set use_segments_container to true 2025/07/02 07:16:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-include/layer2" 2025/07/02 07:16:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test-include/layer2.bin" 2025/07/02 07:16:04 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/07/02 07:16:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test-include/layer2" 2025/07/02 07:16:09 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/07/02 07:16:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-ignore-file" 2025/07/02 07:16:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test-ignore-file.bin" 2025/07/02 07:16:20 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/07/02 07:16:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/rclone-sync-test-ignore-file" 2025/07/02 07:16:26 DEBUG : Swift container crypt path rclone-test-bahisos4vido/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/07/02 07:16:36 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 161b574fa1d132ebe56f13d217292597 OK 2025/07/02 07:16:47 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/02 07:16:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test': Waiting for checks to finish 2025/07/02 07:16:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test': Waiting for transfers to finish 2025/07/02 07:16:47 DEBUG : Waiting for deletions to finish 2025/07/02 07:16:47 INFO : There was nothing to transfer 2025/07/02 07:16:52 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/02 07:16:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:16:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:16:52 DEBUG : Waiting for deletions to finish 2025/07/02 07:16:52 INFO : There was nothing to transfer 2025/07/02 07:16:57 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/02 07:16:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/07/02 07:16:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/07/02 07:16:57 DEBUG : Waiting for deletions to finish 2025/07/02 07:16:57 INFO : There was nothing to transfer 2025/07/02 07:17:02 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/02 07:17:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:17:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:17:02 DEBUG : Waiting for deletions to finish 2025/07/02 07:17:02 INFO : There was nothing to transfer 2025/07/02 07:17:07 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/02 07:17:07 DEBUG : Excluded 2025/07/02 07:17:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/07/02 07:17:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/07/02 07:17:07 DEBUG : Waiting for deletions to finish 2025/07/02 07:17:07 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (85.33s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:17:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:17:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:17:13 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:17:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:17:19 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:17:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/CompareDest" 2025/07/02 07:17:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/CompareDest.bin" 2025/07/02 07:17:30 DEBUG : Swift container crypt path rclone-test-bahisos4vido/CompareDest.bin: Auto set use_segments_container to true 2025/07/02 07:17:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/CompareDest" 2025/07/02 07:17:35 DEBUG : Swift container crypt path rclone-test-bahisos4vido/CompareDest: Auto set use_segments_container to true 2025/07/02 07:17:46 DEBUG : one: Need to transfer - File not found at Destination 2025/07/02 07:17:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:17:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:17:51 DEBUG : one: md5 = 9db289d2a8ecad7276cfc5d838282285 OK 2025/07/02 07:17:51 INFO : one: Copied (new) 2025/07/02 07:17:51 DEBUG : Waiting for deletions to finish 2025/07/02 07:18:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:18:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:18:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:18:11 DEBUG : one: md5 = e44d28c8ce039225d20b674b4d8832a4 OK 2025/07/02 07:18:11 INFO : one: Copied (replaced existing) 2025/07/02 07:18:11 DEBUG : Waiting for deletions to finish 2025/07/02 07:18:17 DEBUG : dst/one: md5 = 89bc7c921f9267600d6387510ceb7e6d OK 2025/07/02 07:18:22 DEBUG : CompareDest/one: md5 = 30bce678b1f9d604eab891e8544d60e5 OK 2025/07/02 07:18:32 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:18:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:18:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:18:37 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/02 07:18:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:18:37 DEBUG : Waiting for deletions to finish 2025/07/02 07:18:37 INFO : There was nothing to transfer 2025/07/02 07:18:43 DEBUG : CompareDest/two: md5 = 3791da85fc807d4e66840b94dfbc6830 OK 2025/07/02 07:18:53 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:18:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:18:53 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/02 07:18:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:18:53 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:18:53 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/02 07:18:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:18:53 DEBUG : Waiting for deletions to finish 2025/07/02 07:18:53 INFO : There was nothing to transfer 2025/07/02 07:19:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:19:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:19:03 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/02 07:19:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:19:03 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:19:03 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/02 07:19:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:19:03 DEBUG : Waiting for deletions to finish 2025/07/02 07:19:03 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/07/02 07:19:14 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:19:14 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/07/02 07:19:14 DEBUG : two: Need to transfer - File not found at Destination 2025/07/02 07:19:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:19:15 DEBUG : two: md5 = 5059234d21e48d1a9886cebf9715dd3d OK 2025/07/02 07:19:15 INFO : two: Copied (new) 2025/07/02 07:19:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:19:19 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/02 07:19:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:19:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (152.37s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:19:45 DEBUG : pre-dest1/1: md5 = fc654ca78b34f26e9681a438ce0932a4 OK 2025/07/02 07:19:51 DEBUG : pre-dest2/2: md5 = 4f8457764bd0629fe73483181abcb2cf OK 2025/07/02 07:19:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dest" 2025/07/02 07:19:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dest.bin" 2025/07/02 07:19:51 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dest.bin: Auto set use_segments_container to true 2025/07/02 07:19:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dest" 2025/07/02 07:19:57 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dest: Auto set use_segments_container to true 2025/07/02 07:20:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/pre-dest1" 2025/07/02 07:20:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/pre-dest1.bin" 2025/07/02 07:20:03 DEBUG : Swift container crypt path rclone-test-bahisos4vido/pre-dest1.bin: Auto set use_segments_container to true 2025/07/02 07:20:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/pre-dest1" 2025/07/02 07:20:08 DEBUG : Swift container crypt path rclone-test-bahisos4vido/pre-dest1: Auto set use_segments_container to true 2025/07/02 07:20:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/pre-dest2" 2025/07/02 07:20:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/pre-dest2.bin" 2025/07/02 07:20:14 DEBUG : Swift container crypt path rclone-test-bahisos4vido/pre-dest2.bin: Auto set use_segments_container to true 2025/07/02 07:20:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/pre-dest2" 2025/07/02 07:20:20 DEBUG : Swift container crypt path rclone-test-bahisos4vido/pre-dest2: Auto set use_segments_container to true 2025/07/02 07:20:26 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:20:26 DEBUG : 1: Destination found in --compare-dest, skipping 2025/07/02 07:20:36 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:20:36 DEBUG : 2: Destination found in --compare-dest, skipping 2025/07/02 07:20:46 DEBUG : 3: Need to transfer - File not found at Destination 2025/07/02 07:20:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dest': Waiting for checks to finish 2025/07/02 07:20:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dest': Waiting for transfers to finish 2025/07/02 07:20:47 DEBUG : 3: md5 = e30efd0e12942fae58a23afb6142dc9b OK 2025/07/02 07:20:47 INFO : 3: Copied (new) 2025/07/02 07:20:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (72.74s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:20:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:20:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:20:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:21:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:21:04 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:21:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/CopyDest" 2025/07/02 07:21:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/CopyDest.bin" 2025/07/02 07:21:14 DEBUG : Swift container crypt path rclone-test-bahisos4vido/CopyDest.bin: Auto set use_segments_container to true 2025/07/02 07:21:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/CopyDest" 2025/07/02 07:21:20 DEBUG : Swift container crypt path rclone-test-bahisos4vido/CopyDest: Auto set use_segments_container to true 2025/07/02 07:21:31 DEBUG : one: Need to transfer - File not found at Destination 2025/07/02 07:21:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:21:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:21:36 DEBUG : one: md5 = 04b9df893d426b091f2b8100ba16c579 OK 2025/07/02 07:21:36 INFO : one: Copied (new) 2025/07/02 07:21:36 DEBUG : Waiting for deletions to finish 2025/07/02 07:21:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:21:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:21:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:21:47 DEBUG : one: md5 = 15e1d2ceae028bd97bcfb28b32f331b7 OK 2025/07/02 07:21:47 INFO : one: Copied (replaced existing) 2025/07/02 07:21:47 DEBUG : Waiting for deletions to finish 2025/07/02 07:21:52 DEBUG : dst/one: md5 = 769792f5458736ef7fedeedff418aa9a OK 2025/07/02 07:21:52 DEBUG : CopyDest/one: md5 = 45a048e2298a22ab45f83b62bc52d05c OK 2025/07/02 07:21:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/BackupDir" 2025/07/02 07:21:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/BackupDir.bin" 2025/07/02 07:21:53 DEBUG : Swift container crypt path rclone-test-bahisos4vido/BackupDir.bin: Auto set use_segments_container to true 2025/07/02 07:21:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/BackupDir" 2025/07/02 07:21:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/BackupDir: Auto set use_segments_container to true 2025/07/02 07:22:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:22:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:22:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:22:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/02 07:22:15 INFO : one: Copied (server-side copy) 2025/07/02 07:22:21 INFO : one: Deleted 2025/07/02 07:22:36 INFO : one: Copied (server-side copy) 2025/07/02 07:22:36 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/07/02 07:22:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:22:36 DEBUG : Waiting for deletions to finish 2025/07/02 07:22:42 DEBUG : CopyDest/two: md5 = 9be894bbf06df2cffadcf366061f34f2 OK 2025/07/02 07:22:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:22:47 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:22:47 DEBUG : one: Unchanged skipping 2025/07/02 07:22:52 INFO : two: Copied (server-side copy) 2025/07/02 07:22:52 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/07/02 07:22:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:22:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:22:52 DEBUG : Waiting for deletions to finish 2025/07/02 07:22:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:22:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:22:58 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:22:58 DEBUG : two: Unchanged skipping 2025/07/02 07:22:58 DEBUG : one: Unchanged skipping 2025/07/02 07:22:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:22:58 DEBUG : Waiting for deletions to finish 2025/07/02 07:22:58 INFO : There was nothing to transfer 2025/07/02 07:23:04 DEBUG : CopyDest/three: md5 = b9d7f364c4353c2c28a77ea7b831478a OK 2025/07/02 07:23:04 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/07/02 07:23:04 DEBUG : three: Destination not found in --copy-dest 2025/07/02 07:23:04 DEBUG : three: Need to transfer - File not found at Destination 2025/07/02 07:23:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:23:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:23:04 DEBUG : two: Unchanged skipping 2025/07/02 07:23:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:23:09 DEBUG : one: Unchanged skipping 2025/07/02 07:23:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:23:09 DEBUG : three: md5 = a5a71375d06ce4077ef585b2f764e6a0 OK 2025/07/02 07:23:09 INFO : three: Copied (new) 2025/07/02 07:23:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (158.58s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:23:42 DEBUG : dst/one: md5 = 405e20fabd830c16246d5d39e6d648ff OK 2025/07/02 07:23:42 DEBUG : dst/two: md5 = 291c8fba22345b5519b19d0032a9aead OK 2025/07/02 07:23:47 DEBUG : dst/three.txt: md5 = 8f3117e7502c6f86e05d9ae666711f5d OK 2025/07/02 07:23:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:23:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:23:53 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:23:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:23:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:24:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/backup" 2025/07/02 07:24:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup.bin" 2025/07/02 07:24:09 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup.bin: Auto set use_segments_container to true 2025/07/02 07:24:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup" 2025/07/02 07:24:19 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup: Auto set use_segments_container to true 2025/07/02 07:24:25 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:24:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:24:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:24:25 DEBUG : two: Unchanged skipping 2025/07/02 07:24:45 INFO : one: Copied (server-side copy) 2025/07/02 07:24:50 INFO : one: Deleted 2025/07/02 07:24:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:24:56 DEBUG : one: md5 = 0abc3368d2fba778ac1bb1c2849116c8 OK 2025/07/02 07:24:56 INFO : one: Copied (new) 2025/07/02 07:24:56 DEBUG : Waiting for deletions to finish 2025/07/02 07:25:11 INFO : three.txt: Copied (server-side copy) 2025/07/02 07:25:16 INFO : three.txt: Deleted 2025/07/02 07:25:16 INFO : three.txt: Moved into backup dir 2025/07/02 07:25:22 DEBUG : dst/three.txt: md5 = e9ee3f3727e4eaec7dfa4da782c2dc9c OK 2025/07/02 07:25:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:25:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:25:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:25:27 DEBUG : two: Unchanged skipping 2025/07/02 07:25:43 INFO : one: Copied (server-side copy) 2025/07/02 07:25:48 INFO : one: Deleted 2025/07/02 07:25:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:25:53 DEBUG : one: md5 = c42f9a7e3ba6a6e2d229fff100ac7d14 OK 2025/07/02 07:25:53 INFO : one: Copied (new) 2025/07/02 07:25:53 DEBUG : Waiting for deletions to finish 2025/07/02 07:26:04 INFO : three.txt: Copied (server-side copy) 2025/07/02 07:26:09 INFO : three.txt: Deleted 2025/07/02 07:26:09 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (163.51s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:26:25 DEBUG : dst/one: md5 = 83a7bb3118c779895fb4ee8e2463244e OK 2025/07/02 07:26:25 DEBUG : dst/two: md5 = 447723ef2926e2e65d6787a9a7c61023 OK 2025/07/02 07:26:30 DEBUG : dst/three.txt: md5 = 210711f9a23af0933d8ad24e7ef5317d OK 2025/07/02 07:26:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:26:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:26:31 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:26:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:26:37 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:26:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/backup" 2025/07/02 07:26:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup.bin" 2025/07/02 07:26:47 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup.bin: Auto set use_segments_container to true 2025/07/02 07:26:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup" 2025/07/02 07:26:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup: Auto set use_segments_container to true 2025/07/02 07:27:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:27:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:27:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:27:03 DEBUG : two: Unchanged skipping 2025/07/02 07:27:14 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:27:19 INFO : one: Deleted 2025/07/02 07:27:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:27:24 DEBUG : one: md5 = dfab66903ac3115ba06a44f421b08a43 OK 2025/07/02 07:27:24 INFO : one: Copied (new) 2025/07/02 07:27:24 DEBUG : Waiting for deletions to finish 2025/07/02 07:27:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:27:45 INFO : three.txt: Deleted 2025/07/02 07:27:45 INFO : three.txt: Moved into backup dir 2025/07/02 07:27:51 DEBUG : dst/three.txt: md5 = 3f76e5e4549a47adbb1126dcbba050b6 OK 2025/07/02 07:28:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:28:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:28:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:28:06 DEBUG : two: Unchanged skipping 2025/07/02 07:28:17 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:28:22 INFO : one: Deleted 2025/07/02 07:28:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:28:27 DEBUG : one: md5 = 45b07d77a04c72cf29fef94d5e6f2a36 OK 2025/07/02 07:28:27 INFO : one: Copied (new) 2025/07/02 07:28:27 DEBUG : Waiting for deletions to finish 2025/07/02 07:28:42 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:28:47 INFO : three.txt: Deleted 2025/07/02 07:28:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (163.82s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:29:09 DEBUG : dst/one: md5 = 3956cbdca9d22b23d0c425581c715b65 OK 2025/07/02 07:29:09 DEBUG : dst/two: md5 = 1467896a1cb45ed57fdfdba4dedb4670 OK 2025/07/02 07:29:14 DEBUG : dst/three.txt: md5 = 0c8cc3a917a76e16c47e9b13b030a98c OK 2025/07/02 07:29:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:29:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:29:20 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:29:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:29:25 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:29:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/backup" 2025/07/02 07:29:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup.bin" 2025/07/02 07:29:36 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup.bin: Auto set use_segments_container to true 2025/07/02 07:29:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/backup" 2025/07/02 07:29:46 DEBUG : Swift container crypt path rclone-test-bahisos4vido/backup: Auto set use_segments_container to true 2025/07/02 07:29:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:29:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:29:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:29:52 DEBUG : two: Unchanged skipping 2025/07/02 07:30:02 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/02 07:30:08 INFO : one: Deleted 2025/07/02 07:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:30:13 DEBUG : one: md5 = 506b7c312137a8dac160b13a544a55b8 OK 2025/07/02 07:30:13 INFO : one: Copied (new) 2025/07/02 07:30:13 DEBUG : Waiting for deletions to finish 2025/07/02 07:30:29 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/02 07:30:34 INFO : three.txt: Deleted 2025/07/02 07:30:34 INFO : three.txt: Moved into backup dir 2025/07/02 07:30:44 DEBUG : dst/three.txt: md5 = e69dad8a56aac08bf8ac05b70ffcbd9d OK 2025/07/02 07:30:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:30:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:30:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:30:50 DEBUG : two: Unchanged skipping 2025/07/02 07:30:55 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/02 07:31:00 INFO : one: Deleted 2025/07/02 07:31:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:31:05 DEBUG : one: md5 = 9c4b57748b6e1b3c92122d308512fc3d OK 2025/07/02 07:31:05 INFO : one: Copied (new) 2025/07/02 07:31:05 DEBUG : Waiting for deletions to finish 2025/07/02 07:31:21 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/02 07:31:26 INFO : three.txt: Deleted 2025/07/02 07:31:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (158.40s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:31:47 DEBUG : dst/one: md5 = 797af30c388b02c887d6bdba3bac7199 OK 2025/07/02 07:31:48 DEBUG : dst/two: md5 = d5454fce12d3825deb9646fffd718325 OK 2025/07/02 07:31:53 DEBUG : dst/three.txt: md5 = 467eca517d8ebcf0591af05492936456 OK 2025/07/02 07:31:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:31:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:31:58 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:32:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:32:04 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:32:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:32:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:32:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:32:14 DEBUG : two: Unchanged skipping 2025/07/02 07:32:35 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:32:40 INFO : one: Deleted 2025/07/02 07:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:32:45 DEBUG : one: md5 = ea2ce7cd2f72f4fc97089ac689c638fa OK 2025/07/02 07:32:45 INFO : one: Copied (new) 2025/07/02 07:32:45 DEBUG : Waiting for deletions to finish 2025/07/02 07:33:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:33:12 INFO : three.txt: Deleted 2025/07/02 07:33:12 INFO : three.txt: Moved into backup dir 2025/07/02 07:33:22 DEBUG : dst/three.txt: md5 = d0a6aef9698b5b13cdc4a5568d9640b3 OK 2025/07/02 07:33:28 DEBUG : one.bak: Excluded (Path Filter) 2025/07/02 07:33:28 DEBUG : one.bak: Excluded 2025/07/02 07:33:28 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/07/02 07:33:28 DEBUG : three.txt.bak: Excluded 2025/07/02 07:33:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:33:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for checks to finish 2025/07/02 07:33:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:33:28 DEBUG : two: Unchanged skipping 2025/07/02 07:33:43 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:33:48 INFO : one: Deleted 2025/07/02 07:33:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido/dst': Waiting for transfers to finish 2025/07/02 07:33:53 DEBUG : one: md5 = 69fb469b6dbc58cc42e621a66c3eb81b OK 2025/07/02 07:33:53 INFO : one: Copied (new) 2025/07/02 07:33:53 DEBUG : Waiting for deletions to finish 2025/07/02 07:34:09 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:34:14 INFO : three.txt: Deleted 2025/07/02 07:34:14 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (175.80s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:34:43 DEBUG : dst/one: md5 = a7c96295463e2760c268502c828d5f39 OK 2025/07/02 07:34:44 DEBUG : dst/two: md5 = 2114c321c6e82dffc1571afd084193d9 OK 2025/07/02 07:34:49 DEBUG : dst/three.txt: md5 = 21fe5c21531b96ec8cc1921e247e27d0 OK 2025/07/02 07:34:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:34:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:34:59 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:35:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:35:05 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:35:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:35:31 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:35:36 INFO : one: Deleted 2025/07/02 07:35:41 DEBUG : one: md5 = 927b030b0ff6c4018a8c2126892bb623 OK 2025/07/02 07:35:41 INFO : one: Copied (new) 2025/07/02 07:35:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:35:41 DEBUG : two: Unchanged skipping 2025/07/02 07:35:41 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/07/02 07:36:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:36:08 INFO : three.txt: Deleted 2025/07/02 07:36:13 DEBUG : three.txt: md5 = 30aec69104dc4e888cf3e0f0ebe8b3b6 OK 2025/07/02 07:36:13 INFO : three.txt: Copied (new) 2025/07/02 07:36:32 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:36:52 INFO : one: Copied (server-side copy) to: one.bak 2025/07/02 07:36:57 INFO : one: Deleted 2025/07/02 07:37:02 DEBUG : one: md5 = 00c8b864192ea7e737467e5c8bbc7e8c OK 2025/07/02 07:37:02 INFO : one: Copied (new) 2025/07/02 07:37:02 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:37:02 DEBUG : two: Unchanged skipping 2025/07/02 07:37:02 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/07/02 07:37:13 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/02 07:37:18 INFO : three.txt: Deleted 2025/07/02 07:37:23 DEBUG : three.txt: md5 = d048d1724465bdeb016be0920f54f131 OK 2025/07/02 07:37:23 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (216.54s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:38:20 DEBUG : dst/one: md5 = 5c1d44f6c93810e31b5b16d279126776 OK 2025/07/02 07:38:20 DEBUG : dst/two: md5 = d5d943269d56241ca7739a01b8cd8bb9 OK 2025/07/02 07:38:25 DEBUG : dst/three.txt: md5 = f69ba859ff885b282771af78d47eaceb OK 2025/07/02 07:38:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bahisos4vido/dst" 2025/07/02 07:38:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst.bin" 2025/07/02 07:38:36 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst.bin: Auto set use_segments_container to true 2025/07/02 07:38:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bahisos4vido/dst" 2025/07/02 07:38:41 DEBUG : Swift container crypt path rclone-test-bahisos4vido/dst: Auto set use_segments_container to true 2025/07/02 07:38:51 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/02 07:39:02 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/02 07:39:07 INFO : one: Deleted 2025/07/02 07:39:12 DEBUG : one: md5 = 49be008d9584a8c34efa1e6d6a7450f6 OK 2025/07/02 07:39:12 INFO : one: Copied (new) 2025/07/02 07:39:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:39:12 DEBUG : two: Unchanged skipping 2025/07/02 07:39:17 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/07/02 07:39:33 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/02 07:39:38 INFO : three.txt: Deleted 2025/07/02 07:39:43 DEBUG : three.txt: md5 = 63bcc398c5323e403371d059fcee34e4 OK 2025/07/02 07:39:43 INFO : three.txt: Copied (new) 2025/07/02 07:40:04 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/02 07:40:09 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/02 07:40:14 INFO : one: Deleted 2025/07/02 07:40:19 DEBUG : one: md5 = 52fa43694f98d296f04c7d11bd175afe OK 2025/07/02 07:40:19 INFO : one: Copied (new) 2025/07/02 07:40:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:40:20 DEBUG : two: Unchanged skipping 2025/07/02 07:40:20 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/07/02 07:40:30 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/02 07:40:35 INFO : three.txt: Deleted 2025/07/02 07:40:40 DEBUG : three.txt: md5 = b5d90b11c621e4363a846ccb3148bdc5 OK 2025/07/02 07:40:40 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (176.89s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:41:12 DEBUG : Testêé: md5 = 1e6e25ec1e866aa315d1f5cde6204e60 OK 2025/07/02 07:41:12 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/07/02 07:41:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:41:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:41:12 DEBUG : Testêé: md5 = 43d239ed56545fcd42b1dc4ba7400a53 OK 2025/07/02 07:41:12 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/07/02 07:41:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.66s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:41:13 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/02 07:41:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:41:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:41:18 DEBUG : existing: md5 = b5137378502432fc3fdddf84a750068d OK 2025/07/02 07:41:18 INFO : existing: Copied (new) 2025/07/02 07:41:18 DEBUG : Waiting for deletions to finish 2025/07/02 07:41:19 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/07/02 07:41:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:41:19 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/07/02 07:41:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:41:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': not deleting files as there were IO errors 2025/07/02 07:41:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (16.64s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:41:35 DEBUG : EXISTING: md5 = 1bd05d333a5d52b3b8b64f2e9c32e297 OK 2025/07/02 07:41:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:41:35 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:41:35 DEBUG : existing: Unchanged skipping 2025/07/02 07:41:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:41:35 DEBUG : Waiting for deletions to finish 2025/07/02 07:41:35 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (16.38s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:2636: 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-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" sync_test.go:2675: This test only runs on local --- PASS: TestMaxTransfer (0.60s) --- SKIP: TestMaxTransfer/Hard (0.20s) --- SKIP: TestMaxTransfer/Soft (0.20s) --- SKIP: TestMaxTransfer/Cautious (0.20s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:41:47 DEBUG : both0: md5 = 841afb66a1cca404c5bf2ef100be63cc OK 2025/07/02 07:41:48 DEBUG : only0: md5 = 78eee6adcb2baf5866f484050940a6ce OK 2025/07/02 07:41:49 DEBUG : both1: md5 = 5bad466ec63227c7fd99f130c9222d9a OK 2025/07/02 07:41:55 DEBUG : only1: md5 = 1355d6ef9f632676cc6389fdab505153 OK 2025/07/02 07:42:00 DEBUG : both2: md5 = b51393310372cb79fd528cb3da2c111e OK 2025/07/02 07:42:05 DEBUG : only2: md5 = 1f4c20516c1f069b5483f6ee88447922 OK 2025/07/02 07:42:06 DEBUG : both3: md5 = 850a4c38ef3fbe5b40f65e0fb3a5b3b6 OK 2025/07/02 07:42:11 DEBUG : only3: md5 = df0ad4f5be44e82b2f90b1805da7ae23 OK 2025/07/02 07:42:17 DEBUG : both4: md5 = a381388f2a2cea317b6f463fe3f232bb OK 2025/07/02 07:42:22 DEBUG : only4: md5 = cd87e9161114ebfd69a2e0205e088329 OK 2025/07/02 07:42:27 DEBUG : both5: md5 = cf33e90655818b4fdcc59cfed939c745 OK 2025/07/02 07:42:28 DEBUG : only5: md5 = 377cc7f327e669ef95d684d80b650908 OK 2025/07/02 07:42:29 DEBUG : both6: md5 = fd1332ac49739c1e497e5f6581c74dec OK 2025/07/02 07:42:29 DEBUG : only6: md5 = 0bebfbe12ea825c9a2513ff57a682030 OK 2025/07/02 07:42:29 DEBUG : both7: md5 = 74e79b1ebb903f76d01d1e478440b93b OK 2025/07/02 07:42:29 DEBUG : only7: md5 = 3719211f4b3091f828aa7ffb84e130b9 OK 2025/07/02 07:42:30 DEBUG : both8: md5 = 7e86a99a5e72c7fcbcc3ab686c4bcf16 OK 2025/07/02 07:42:36 DEBUG : only8: md5 = dd25cb300881d79ff3b0ced55393afa8 OK 2025/07/02 07:42:36 DEBUG : both9: md5 = ecc851c6f656d00048d563576b428597 OK 2025/07/02 07:42:42 DEBUG : only9: md5 = b172cccdea3108ee3e89de08093ccf1c OK 2025/07/02 07:42:47 DEBUG : both10: md5 = 1f432bda461b868d5a5daacb779ecfb7 OK 2025/07/02 07:42:48 DEBUG : only10: md5 = 899008f97c9f8f5eb4dff61e4168a36d OK 2025/07/02 07:42:48 DEBUG : both11: md5 = 5a00e4386e51869787d34609ed098f30 OK 2025/07/02 07:42:49 DEBUG : only11: md5 = 4ebea96785a882c6ce76805aba1267ea OK 2025/07/02 07:42:54 DEBUG : both12: md5 = 3e071f2d4c7fe9c8865fd73a793741d2 OK 2025/07/02 07:42:55 DEBUG : only12: md5 = c3d30a377b650109eccc6f0fcbfd8b85 OK 2025/07/02 07:42:55 DEBUG : both13: md5 = 177ae9673a29fe252a8cb7425f35d988 OK 2025/07/02 07:42:55 DEBUG : only13: md5 = faacf8ab18060262d733610bd003099c OK 2025/07/02 07:43:01 DEBUG : both14: md5 = 2333c2a7636661c59362769d6b44cbbb OK 2025/07/02 07:43:06 DEBUG : only14: md5 = 60ddcffaae1e90b039eadb7eead8b370 OK 2025/07/02 07:43:12 DEBUG : both15: md5 = 71f9778b274e6286759208dc9b29b368 OK 2025/07/02 07:43:13 DEBUG : only15: md5 = 610c20952f963050064a44dfc76a18ab OK 2025/07/02 07:43:18 DEBUG : both16: md5 = 439b4c76796a915008c673c5f8f64638 OK 2025/07/02 07:43:23 DEBUG : only16: md5 = d7871d439f938c909fab07ec156e6894 OK 2025/07/02 07:43:24 DEBUG : both17: md5 = 3b015ee0eb10a2e83dd51b8ea6c149a2 OK 2025/07/02 07:43:24 DEBUG : only17: md5 = b5e30118c30f8ebd7778cf6a9eb6d5c5 OK 2025/07/02 07:43:29 DEBUG : both18: md5 = 57ec27396b1592e8d9463de02b0e2557 OK 2025/07/02 07:43:30 DEBUG : only18: md5 = 44722a745133c8c85fd3d6d80291bc28 OK 2025/07/02 07:43:35 DEBUG : both19: md5 = 60c926164d791675e85186d68cd08f99 OK 2025/07/02 07:43:35 DEBUG : only19: md5 = 2b32700bae1bc7c75ba14a47a959efb7 OK 2025/07/02 07:44:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:44:18 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both11: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both12: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both0: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both13: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both15: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both17: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both18: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both19: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both1: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both2: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both3: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both4: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both5: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both6: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both7: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both8: Unchanged skipping 2025/07/02 07:44:18 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:18 DEBUG : both9: Unchanged skipping 2025/07/02 07:44:22 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:22 DEBUG : both10: Unchanged skipping 2025/07/02 07:44:23 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:23 DEBUG : both14: Unchanged skipping 2025/07/02 07:44:23 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:44:23 DEBUG : both16: Unchanged skipping 2025/07/02 07:44:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:44:23 DEBUG : Waiting for deletions to finish 2025/07/02 07:44:23 INFO : only18: Deleted 2025/07/02 07:44:23 INFO : only5: Deleted 2025/07/02 07:44:23 INFO : only6: Deleted 2025/07/02 07:44:23 INFO : only10: Deleted 2025/07/02 07:44:23 INFO : only11: Deleted 2025/07/02 07:44:23 INFO : only12: Deleted 2025/07/02 07:44:24 INFO : only7: Deleted 2025/07/02 07:44:24 INFO : only0: Deleted 2025/07/02 07:44:29 INFO : only3: Deleted 2025/07/02 07:44:33 INFO : only14: Deleted 2025/07/02 07:44:33 INFO : only9: Deleted 2025/07/02 07:44:33 INFO : only19: Deleted 2025/07/02 07:44:33 INFO : only2: Deleted 2025/07/02 07:44:33 INFO : only13: Deleted 2025/07/02 07:44:34 INFO : only15: Deleted 2025/07/02 07:44:38 INFO : only1: Deleted 2025/07/02 07:44:38 INFO : only17: Deleted 2025/07/02 07:44:39 INFO : only16: Deleted 2025/07/02 07:44:39 INFO : only4: Deleted 2025/07/02 07:44:43 INFO : only8: Deleted 2025/07/02 07:44:43 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (265.76s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:46:13 DEBUG : both0: md5 = 837834717c74b6aad131bc6fdaba77aa OK 2025/07/02 07:46:13 DEBUG : only0: md5 = cd17b6280da74c6aec0f3e261b8a3646 OK 2025/07/02 07:46:13 DEBUG : both1: md5 = 93ecb4df8d774a493216806952929f9d OK 2025/07/02 07:46:19 DEBUG : only1: md5 = 9fbf937a6f3e44713cd7e5cebc24d4b6 OK 2025/07/02 07:46:24 DEBUG : both2: md5 = 4dc80c75649a263376e37af42b19b7a6 OK 2025/07/02 07:46:29 DEBUG : only2: md5 = 39bebb2183e09e73da04f7e1f2f08f97 OK 2025/07/02 07:46:29 DEBUG : both3: md5 = 476abcf0b92baef763fa3c53c97ee84b OK 2025/07/02 07:46:34 DEBUG : only3: md5 = 6257487eeed34de696afcbed44f83ca2 OK 2025/07/02 07:46:39 DEBUG : both4: md5 = 6290ce0a1682d8881f0bc01b484fb499 OK 2025/07/02 07:46:45 DEBUG : only4: md5 = cc8b67a2d7cc575495602de4d1501547 OK 2025/07/02 07:46:50 DEBUG : both5: md5 = cf3c2b079ed46204cf8dc534f86ee475 OK 2025/07/02 07:46:50 DEBUG : only5: md5 = ff17907821746186f90aeff33bc9a6e4 OK 2025/07/02 07:46:50 DEBUG : both6: md5 = b2d593df57ee25c752e4025616979e63 OK 2025/07/02 07:46:50 DEBUG : only6: md5 = 212388189236b3b1af76966d66162211 OK 2025/07/02 07:46:51 DEBUG : both7: md5 = 949876dc3077b3948330aba2dfbc5fa0 OK 2025/07/02 07:46:51 DEBUG : only7: md5 = 644c712a4a59328b0428809e8c05a7b2 OK 2025/07/02 07:46:51 DEBUG : both8: md5 = 29f7e99f0149bfde8dd80d79a4f7cab1 OK 2025/07/02 07:46:56 DEBUG : only8: md5 = b063ea95936713cd30bf19ffd0320ecc OK 2025/07/02 07:46:57 DEBUG : both9: md5 = 32e73f95c25f8755230891cf8a81547f OK 2025/07/02 07:47:02 DEBUG : only9: md5 = 52d3775893138cb0a11938eb2d380799 OK 2025/07/02 07:47:07 DEBUG : both10: md5 = 87bbb73fc20a0969901e2457e7ca763d OK 2025/07/02 07:47:07 DEBUG : only10: md5 = 23c1d688293d663da00672b71525abb7 OK 2025/07/02 07:47:07 DEBUG : both11: md5 = 725a18617a1305a42bef9888dd9a825c OK 2025/07/02 07:47:07 DEBUG : only11: md5 = 59e193f63512ab17e792a13af11ddf7d OK 2025/07/02 07:47:13 DEBUG : both12: md5 = 2f8da156ea120a9a6c5d37cae4aad20f OK 2025/07/02 07:47:13 DEBUG : only12: md5 = 829da3edf969f017d0b1495fe13211df OK 2025/07/02 07:47:13 DEBUG : both13: md5 = afbd1cad3c1bff95a6f49ba989a53123 OK 2025/07/02 07:47:14 DEBUG : only13: md5 = a7602e5d9b18e707315b23fa40117f9f OK 2025/07/02 07:47:19 DEBUG : both14: md5 = bba843c340579ae2a93431b13a393421 OK 2025/07/02 07:47:24 DEBUG : only14: md5 = 7397f4c885d4df964e9b28660503743c OK 2025/07/02 07:47:29 DEBUG : both15: md5 = c5f227f775c31024c271d1333a4955b8 OK 2025/07/02 07:47:29 DEBUG : only15: md5 = fdcf6ad352da940d82e1a78452286ead OK 2025/07/02 07:47:34 DEBUG : both16: md5 = 06096144a12afda183bd93489ccc0d83 OK 2025/07/02 07:47:40 DEBUG : only16: md5 = 82a74dd87f2b8ca30d80d141b9ea3c9e OK 2025/07/02 07:47:40 DEBUG : both17: md5 = 4da883b2be7a236b55faa5e352cfac5e OK 2025/07/02 07:47:40 DEBUG : only17: md5 = 4fe6f1c55870984b945e93b834fa8675 OK 2025/07/02 07:47:45 DEBUG : both18: md5 = 06dcd250127a6f73d3eef2a4e2180689 OK 2025/07/02 07:47:45 DEBUG : only18: md5 = b1ab53275adeba3d18fe2cb994de0b53 OK 2025/07/02 07:47:51 DEBUG : both19: md5 = 32337f31fcb9ba5ce49463fc4a7a8921 OK 2025/07/02 07:47:51 DEBUG : only19: md5 = fe484dbb55a226533686f5e52845ccb9 OK 2025/07/02 07:48:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:48:23 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both0: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both10: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both11: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both12: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both13: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both14: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both15: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both16: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both17: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both18: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both19: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both1: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both2: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both3: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both4: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both6: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both7: Unchanged skipping 2025/07/02 07:48:23 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/07/02 07:48:23 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both8: Unchanged skipping 2025/07/02 07:48:23 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:23 DEBUG : both9: Unchanged skipping 2025/07/02 07:48:23 DEBUG : only12: md5 = 977ca89831eeffe98480004cdc7bcaf2 OK 2025/07/02 07:48:23 INFO : only12: Copied (replaced existing) 2025/07/02 07:48:23 DEBUG : only10: md5 = cecdc2e95ad4f1ff92e665cdd93484cb OK 2025/07/02 07:48:23 INFO : only10: Copied (replaced existing) 2025/07/02 07:48:23 DEBUG : only11: md5 = 242836c816c3d74012663dfbf8a63c32 OK 2025/07/02 07:48:23 INFO : only11: Copied (replaced existing) 2025/07/02 07:48:23 DEBUG : only0: md5 = ab2f4ebcf3176e2a06521847b4efef2c OK 2025/07/02 07:48:23 INFO : only0: Copied (replaced existing) 2025/07/02 07:48:24 DEBUG : only13: md5 = 5f8a8b38a32fc20ccd6b47714a8f0c38 OK 2025/07/02 07:48:24 INFO : only13: Copied (replaced existing) 2025/07/02 07:48:24 DEBUG : only15: md5 = 4217a10240e1a9d03b16c6145b06b6c1 OK 2025/07/02 07:48:24 INFO : only15: Copied (replaced existing) 2025/07/02 07:48:24 DEBUG : only17: md5 = 514abe45a3312cd03da13dfeda9ac553 OK 2025/07/02 07:48:24 INFO : only17: Copied (replaced existing) 2025/07/02 07:48:24 DEBUG : only18: md5 = 69d4e5e47b472de69e2b072fbc71e10c OK 2025/07/02 07:48:24 INFO : only18: Copied (replaced existing) 2025/07/02 07:48:24 DEBUG : only19: md5 = ab19e093ff3c51f02fed3605ef6d6099 OK 2025/07/02 07:48:24 INFO : only19: Copied (replaced existing) 2025/07/02 07:48:28 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:48:28 DEBUG : both5: Unchanged skipping 2025/07/02 07:48:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:48:29 DEBUG : only16: md5 = f227d93d0371bf82e2b8010a9558dc89 OK 2025/07/02 07:48:29 INFO : only16: Copied (replaced existing) 2025/07/02 07:48:29 DEBUG : only1: md5 = 9339065a729c2c823a687304ff5bb192 OK 2025/07/02 07:48:29 INFO : only1: Copied (replaced existing) 2025/07/02 07:48:29 DEBUG : only2: md5 = 956933a9edd39088d6d1b5bf4b127cc9 OK 2025/07/02 07:48:29 INFO : only2: Copied (replaced existing) 2025/07/02 07:48:29 DEBUG : only5: md5 = c4787c9ed1647d2065834407b2dfed2e OK 2025/07/02 07:48:29 INFO : only5: Copied (replaced existing) 2025/07/02 07:48:30 DEBUG : only6: md5 = e0f4bd02cff1c436eb2418f5987dfe0c OK 2025/07/02 07:48:30 INFO : only6: Copied (replaced existing) 2025/07/02 07:48:30 DEBUG : only7: md5 = 8594b36d76ea577a13c61b699309516f OK 2025/07/02 07:48:30 INFO : only7: Copied (replaced existing) 2025/07/02 07:48:34 DEBUG : only14: md5 = 63f49cb6e2b24fcc2cc93c3e2bee3d2f OK 2025/07/02 07:48:34 INFO : only14: Copied (replaced existing) 2025/07/02 07:48:34 DEBUG : only3: md5 = d90a02ae5218c844e8100e7d15ef7605 OK 2025/07/02 07:48:34 INFO : only3: Copied (replaced existing) 2025/07/02 07:48:35 DEBUG : only8: md5 = 111873b9bf990990bd31e5c4fdcb8ec0 OK 2025/07/02 07:48:35 INFO : only8: Copied (replaced existing) 2025/07/02 07:48:39 DEBUG : only9: md5 = 099bff61c835baf130e366951c09ee3f OK 2025/07/02 07:48:39 INFO : only9: Copied (replaced existing) 2025/07/02 07:48:39 DEBUG : only4: md5 = ea0f65b6c3dd47c237ce5a3def74d0d6 OK 2025/07/02 07:48:39 INFO : only4: Copied (replaced existing) 2025/07/02 07:48:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (315.24s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:51:28 DEBUG : empty_dir: Making directory with metadata 2025/07/02 07:51:28 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 07:51:28 DEBUG : empty_on_remote: Making directory with metadata 2025/07/02 07:51:28 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 07:51:28 INFO : empty_on_remote: Making directory 2025/07/02 07:51:28 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/07/02 07:51:34 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/07/02 07:51:34 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/07/02 07:51:34 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/07/02 07:51:34 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (34.99s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:52:03 DEBUG : empty_dir: Making directory with metadata 2025/07/02 07:52:03 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 07:52:03 DEBUG : empty_on_remote: Making directory with metadata 2025/07/02 07:52:03 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/02 07:52:03 INFO : empty_on_remote: Making directory 2025/07/02 07:52:03 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/07/02 07:52:08 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:08 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:08 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:08 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:08 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (26.73s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:52:30 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:30 INFO : sub dir: Making directory 2025/07/02 07:52:30 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (4.35s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:52:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/02 07:52:34 INFO : sub dir: Making directory 2025/07/02 07:52:34 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithoutEmptyDirs (2.15s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido'", Local "Local file system at /tmp/rclone4197441192", Modify Window "1ns" 2025/07/02 07:52:41 DEBUG : dir1/0000-abcdefg.txt: md5 = f0cef21a14d401eba8017ef575158484 OK 2025/07/02 07:52:42 DEBUG : dir1/0001-bcdefgh.txt: md5 = a4a4a131d52d0fc4c8db53e21ea39581 OK 2025/07/02 07:52:47 DEBUG : dir1/0002-cdefghi.txt: md5 = 7d66258c83a30f0b25edadae212fb9a7 OK 2025/07/02 07:52:48 DEBUG : dir1/0003-defghij.txt: md5 = 9d2591e6a76e5e3283dbddad20edefe0 OK 2025/07/02 07:52:48 DEBUG : dir1/0004-efghijk.txt: md5 = e81d8efb308cd285884a00b60f6d9a2c OK 2025/07/02 07:52:48 DEBUG : dir1/0005-fghijkl.txt: md5 = c2210cdfed351bdf79386c227bd23aeb OK 2025/07/02 07:52:49 DEBUG : dir1/0006-ghijklm.txt: md5 = 4d95dd07d29cd71fdd960bec9db9b34f OK 2025/07/02 07:52:54 DEBUG : dir1/0007-1234567.txt: md5 = 1aeab7c5376225df2730556b3d5ee21e OK 2025/07/02 07:53:00 DEBUG : dir1/0008-2345678.txt: md5 = 60e281c2c67afbef18cafe274c797cd8 OK 2025/07/02 07:53:05 DEBUG : dir1/0009-3456789.txt: md5 = 8f1763b54bd8fed40877b0af04fb9dcc OK 2025/07/02 07:53:06 DEBUG : dir1/0010-456789.txt: md5 = 6d4f52312c9ecea217110eabbdab7b95 OK 2025/07/02 07:53:06 DEBUG : dir1/0011-56789;.txt: md5 = ef414d6ea717d763c5fe0fdf1f8ec9ef OK 2025/07/02 07:53:12 DEBUG : dir1/0012-6789;.txt: md5 = a5c459fbc4734056d1a0f7bb1e5f2183 OK 2025/07/02 07:53:17 DEBUG : dir1/0013-789;=.txt: md5 = f4457708d11e27d14fa541937724957b OK 2025/07/02 07:53:18 DEBUG : dir1/0014-89;=.txt: md5 = 1815aa3384ef0c41272e9a05c61d1fa0 OK 2025/07/02 07:53:23 DEBUG : dir1/0015-9;=.txt: md5 = 63ce9d8bdc0ff4d384e3e4d025b8864f OK 2025/07/02 07:53:24 DEBUG : apple: md5 = daa9f45ad8d659da51821578a87b09dc OK 2025/07/02 07:53:24 DEBUG : banana: md5 = 85591e44875b0ac392371539dd4b602f OK 2025/07/02 07:53:29 DEBUG : appleappleapplebanana: md5 = 7dda983aa9706484a931e55ac7fcb496 OK 2025/07/02 07:53:35 DEBUG : splitbananasplit: md5 = 57e002b4e5cb9c53395655d401fc86a8 OK 2025/07/02 07:53:35 DEBUG : Waiting for deletions to finish 2025/07/02 07:53:35 DEBUG : apple: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : banana: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/02 07:53:35 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/02 07:53:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:53:56 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : apple: Unchanged skipping 2025/07/02 07:53:56 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : banana: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/02 07:53:56 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:53:56 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/02 07:54:01 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:01 DEBUG : splitbananasplit: Unchanged skipping 2025/07/02 07:54:01 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:01 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/02 07:54:01 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:01 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/02 07:54:01 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:01 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/02 07:54:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:54:01 DEBUG : Waiting for deletions to finish 2025/07/02 07:54:01 INFO : There was nothing to transfer 2025/07/02 07:54:01 DEBUG : Waiting for deletions to finish 2025/07/02 07:54:01 DEBUG : apple: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : banana: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/02 07:54:01 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/02 07:54:02 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : apple: Unchanged skipping 2025/07/02 07:54:02 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : banana: Unchanged skipping 2025/07/02 07:54:02 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/02 07:54:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for checks to finish 2025/07/02 07:54:02 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/02 07:54:02 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:02 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/02 07:54:07 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:07 DEBUG : splitbananasplit: Unchanged skipping 2025/07/02 07:54:07 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:07 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/02 07:54:07 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:07 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/02 07:54:07 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/02 07:54:07 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/02 07:54:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bahisos4vido': Waiting for transfers to finish 2025/07/02 07:54:07 DEBUG : Waiting for deletions to finish 2025/07/02 07:54:07 INFO : There was nothing to transfer 2025/07/02 07:54:07 DEBUG : Waiting for deletions to finish 2025/07/02 07:54:07 DEBUG : apple: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : banana: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/02 07:54:07 DEBUG : splitbananasplit: Excluded (Path Filter) panic: test timed out after 1h0m0s running tests: TestTransform (1m59s) TestTransform/NFC (1m59s) goroutine 14690 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 2 minutes]: testing.(*T).Run(0xc001b8c1c0, {0x26964f6?, 0xc00006bad8?}, 0x27c2b98) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc001b8c1c0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc001b8c1c0, 0xc00006bc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc000704918, {0x3e80600, 0x6b, 0x6b}, {0xedff6ceac?, 0x3e965c0?, 0x3e965c0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0006e63c0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0006e63c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:259 +0xa9 goroutine 35 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 36 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 14472 [select]: net/http.(*persistConn).writeLoop(0xc000ce3560) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14415 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14468 [select]: net/http.(*persistConn).writeLoop(0xc00047a120) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14345 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14445 [select]: net/http.(*persistConn).writeLoop(0xc0004bfb00) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14376 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14350 [select]: net/http.(*persistConn).writeLoop(0xc000ce2ea0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14363 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12990 [select]: net/http.(*persistConn).writeLoop(0xc0003c0ea0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 13051 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14657 [select]: net/http.(*persistConn).roundTrip(0xc000cccc60, 0xc0007825a0) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc00056a340, 0xc000b6ac80) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0000504d0, 0xc000b6ac80) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000b6ac80, {0x2acf200, 0xc0000504d0}, {0x101?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001a94ff0, 0xc000b6ac80, {0x412c3e?, 0x482592?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001a94ff0, 0xc000b6ac80) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 14002 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 2076 [chan receive, 58 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 2045 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 14456 [select]: net/http.(*persistConn).writeLoop(0xc000ccd440) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14392 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14483 [select]: net/http.(*persistConn).writeLoop(0xc000cdf440) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14384 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14477 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a7d18, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001fa400?, 0xc000b07300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0001fa400, {0xc000b07300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001fa400, {0xc000b07300?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008ad88, {0xc000b07300?, 0xc00082c2d0?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f81e48, {0xc000b07300?, 0x76b5443d97d8?, 0x76b58bc4cf30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d3278, {0xc000b07300?, 0xc00056a340?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000976638, {0x2ad0760, 0xc0019d3278}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000976388, {0x76b5445c30f8, 0xc001f81e48}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000976388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000976388, {0xc00085c000, 0x1000, 0x76b58bc4cf30?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0003c0240, {0xc00085c000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00206ff20) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00206ff20, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0003c0240) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14400 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14394 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a8060, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344480?, 0xc000913980?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344480, {0xc000913980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344480, {0xc000913980?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0004c4070, {0xc000913980?, 0x5?, 0x3eb5ea8?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019d2240, {0xc000913980?, 0x76b5443d97d8?, 0x76b58bc4cf30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d3b00, {0xc000913980?, 0x3ebc400?, 0xc001acd9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000172638, {0x2ad0760, 0xc0019d3b00}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000172388, {0x76b5445c30f8, 0xc0019d2240}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000172388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000172388, {0xc0003e7000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000ccc000, {0xc0003e7000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000f4cb40) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000f4cb40, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000ccc000) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14336 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14378 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a8178, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc002088400?, 0xc000915300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc002088400, {0xc000915300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc002088400, {0xc000915300?, 0x1973?, 0xc00030b3c0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001d0a0c0, {0xc000915300?, 0xc000654940?, 0x43d367?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000608a80, {0xc000915300?, 0x76b54454d618?, 0x76b58bc4c5c0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001f81ec0, {0xc000915300?, 0xc0006949e8?, 0xc0006549c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006949b8, {0x2ad0760, 0xc001f81ec0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000694708, {0x76b5445c30f8, 0xc000608a80}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000694708, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000694708, {0xc000638000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004bf7a0, {0xc000638000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001ad6fc0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001ad6fc0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004bf7a0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14079 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 12989 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a83a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344080?, 0xc0001e0600?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344080, {0xc0001e0600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344080, {0xc0001e0600?, 0x1973?, 0xc001d086c0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0004c4010, {0xc0001e0600?, 0xc000694550?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019d2030, {0xc0001e0600?, 0x76b5445c8318?, 0x76b58bc4c108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001cdcba0, {0xc0001e0600?, 0x1973?, 0xbf00c000fc19c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000694638, {0x2ad0760, 0xc001cdcba0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000694388, {0x76b5445c30f8, 0xc0019d2030}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000694388, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000694388, {0xc000459000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0003c0ea0, {0xc000459000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0021ccfc0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0021ccfc0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0003c0ea0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 13051 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14471 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a7e30, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00055fd80?, 0xc000b05980?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00055fd80, {0xc000b05980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00055fd80, {0xc000b05980?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008ace0, {0xc000b05980?, 0xc0005b8140?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f81a40, {0xc000b05980?, 0x76b5443d97d8?, 0x76b58bc4cf30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d2900, {0xc000b05980?, 0xc00056a340?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0009762b8, {0x2ad0760, 0xc0019d2900}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000976008, {0x76b5445c30f8, 0xc001f81a40}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000976008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000976008, {0xc0009f7000, 0x1000, 0x76b58bc4cf30?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000ce3560, {0xc0009f7000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00206f0e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00206f0e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000ce3560) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14415 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14485 [select]: net/http.(*persistConn).writeLoop(0xc0001eea20) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14439 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14379 [select]: net/http.(*persistConn).writeLoop(0xc0004bf7a0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14079 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14453 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a7f48, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001fa200?, 0xc00098f980?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0001fa200, {0xc00098f980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001fa200, {0xc00098f980?, 0x1973?, 0xc00060f1c0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0004c40a0, {0xc00098f980?, 0xc000172c50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019d2438, {0xc00098f980?, 0x76b5443d97d8?, 0x76b58bc4cf30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d3ae8, {0xc00098f980?, 0x1973?, 0xc000a0d9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000172d38, {0x2ad0760, 0xc0019d3ae8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000172a88, {0x76b5445c30f8, 0xc0019d2438}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000172a88, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000172a88, {0xc000a01000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000ce3200, {0xc000a01000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000f4db00) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000f4db00, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000ce3200) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14406 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 13793 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001a7e000, {0x2af5e28, 0xc000531310}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 13709 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 14484 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a7ae8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344880?, 0xc000992c80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344880, {0xc000992c80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344880, {0xc000992c80?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0004c42e8, {0xc000992c80?, 0x0?, 0x0?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019d3218, {0xc000992c80?, 0x76b5445bfa08?, 0x76b58bc4ca78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000608300, {0xc000992c80?, 0x2af5e28?, 0xc0005309b0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000b562b8, {0x2ad0760, 0xc000608300}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000b56008, {0x76b5445c30f8, 0xc0019d3218}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000b56008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000b56008, {0xc000b5c000, 0x1000, 0x76b58bc4ca78?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0001eea20, {0xc000b5c000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000f4d0e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000f4d0e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0001eea20) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14439 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14347 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a8290, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00055f980?, 0xc0001dec80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00055f980, {0xc0001dec80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00055f980, {0xc0001dec80?, 0x1973?, 0xc00030ac60?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a308, {0xc0001dec80?, 0xc00035ac50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f80450, {0xc0001dec80?, 0x76b5445bfa08?, 0x76b58bc4ca78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000609d40, {0xc0001dec80?, 0x1973?, 0xc001ad39d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc00035ad38, {0x2ad0760, 0xc000609d40}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00035aa88, {0x76b5445c30f8, 0xc001f80450}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00035aa88, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc00035aa88, {0xc00085d000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000cccc60, {0xc00085d000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00241d260) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00241d260, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000cccc60) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14387 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14348 [select]: net/http.(*persistConn).writeLoop(0xc000cccc60) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14387 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14467 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a85d8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344600?, 0xc00098e000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344600, {0xc00098e000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344600, {0xc00098e000?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0002f0268, {0xc00098e000?, 0x5?, 0x3eb5ea8?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001cdcb88, {0xc00098e000?, 0x76b5445c8318?, 0x76b58bc4c108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001cdcb70, {0xc00098e000?, 0x3ebc400?, 0xc0006559c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc00055a2b8, {0x2ad0760, 0xc001cdcb70}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00055a008, {0x76b5445c30f8, 0xc001cdcb88}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00055a008, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc00055a008, {0xc00098b000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00047a120, {0xc00098b000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001c78540) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001c78540, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00047a120) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14345 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14454 [select]: net/http.(*persistConn).writeLoop(0xc000ce3200) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14406 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14349 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a8808, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00055fa00?, 0xc000912000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00055fa00, {0xc000912000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00055fa00, {0xc000912000?, 0x1973?, 0xc00030af40?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0002f0240, {0xc000912000?, 0xc0001ff350?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001cdca08, {0xc000912000?, 0x76b5443d97d8?, 0x76b58bc4cf30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0019d3b18, {0xc000912000?, 0x1973?, 0xc001acf9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001ff438, {0x2ad0760, 0xc0019d3b18}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001ff188, {0x76b5445c30f8, 0xc001cdca08}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0001ff188, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0001ff188, {0xc000989000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000ce2ea0, {0xc000989000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00241d3e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00241d3e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000ce2ea0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14363 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14444 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a84c0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00055fc80?, 0xc000b04000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00055fc80, {0xc000b04000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00055fc80, {0xc000b04000?, 0x1973?, 0xc001d089a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001d0a0e0, {0xc000b04000?, 0xc000695350?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000608db0, {0xc000b04000?, 0x76b5445c8318?, 0x76b58bc4c108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001cdcbb8, {0xc000b04000?, 0x1973?, 0xc00064e9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000695438, {0x2ad0760, 0xc001cdcbb8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000695188, {0x76b5445c30f8, 0xc000608db0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000695188, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000695188, {0xc000b54000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004bfb00, {0xc000b54000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc00206e420) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc00206e420, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004bfb00) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14376 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 13969 [chan receive, 2 minutes]: testing.(*T).Run(0xc0018dddc0, {0x267ab32?, 0x41bb05?}, 0xc000531e00) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/fs/sync.TestTransform(0xc0018dddc0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:95 +0x62a testing.tRunner(0xc0018dddc0, 0x27c2b98) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 14478 [select]: net/http.(*persistConn).writeLoop(0xc0003c0240) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14400 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14395 [select]: net/http.(*persistConn).writeLoop(0xc000ccc000) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14336 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14002 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc0002d5a40, 0xc00034a930, 0xc000b6ac80) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc0002d5a40, {0x2af5d48, 0x3eb9b80}, {0xc000568f00, 0x4f}, {{0xc00194fe00, 0x5}, {0xc00194fe06, 0x32}, {0x2682b12, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc0002d5a40, {0x2af5d48, 0x3eb9b80}, {{0xc00194fe00, 0x5}, {0xc00194fe06, 0x32}, {0x2682b12, 0x6}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).ObjectDelete(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1902 github.com/rclone/rclone/backend/swift.(*Object).Remove.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1620 +0x105 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc000506008?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0019fa6c0, 0xc000782500, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0019fa6c0, 0xc000782500) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/swift.(*Object).Remove(0xc001c95320, {0x2af5d48, 0x3eb9b80}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1619 +0x29b github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x24 github.com/rclone/rclone/fstest.retry(0xc001a7cc40, {0xc0004e7620, 0x25}, 0xc00072f920) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc000496c00?, 0xc000496c00?, 0x1e03e14?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc000496c00, 0x14, 0x20}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:331 +0x1ca github.com/rclone/rclone/backend/crypt.(*Fs).ListR.func1({0xc000496c00?, 0xc001ff6c20?, 0x5?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:481 +0x5b github.com/rclone/rclone/fs/list.(*Helper).send(...) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:29 github.com/rclone/rclone/fs/list.(*Helper).Flush(0xc00072fb88) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/swift.(*Fs).ListR(0xc0018dd340, {0x2af5d48, 0x3eb9b80}, {0x0?, 0x2417cc0?}, 0xc0022abc50) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:881 +0x185 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc001b82000, {0x2af5d48, 0x3eb9b80}, {0x0, 0x0}, 0xc000435b30) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2af5d48, 0x3eb9b80}, {0x2b0a4c0, 0xc001b82000}, {0x0, 0x0}, 0x1, 0x3, 0xc0022abbc0, 0xc000704540, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2af5d48, 0x3eb9b80}, {0x2b0a4c0, 0xc001b82000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc0022abbc0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:163 +0x1d5 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:150 +0x107 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc000327650) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c github.com/rclone/rclone/fs/sync.TestTransform.func1(0xc001a7cc40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:124 +0x3a5 testing.tRunner(0xc001a7cc40, 0xc000531e00) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 13969 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 14455 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a86f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc002088600?, 0xc000916c80?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc002088600, {0xc000916c80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc002088600, {0xc000916c80?, 0x1973?, 0xc00030bd40?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008aca0, {0xc000916c80?, 0xc00035b350?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f818f0, {0xc000916c80?, 0x76b5445c8318?, 0x76b58bc4c108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc001cdcbd0, {0xc000916c80?, 0x1973?, 0xc001acc9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc00035b438, {0x2ad0760, 0xc001cdcbd0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00035b188, {0x76b5445c30f8, 0xc001f818f0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00035b188, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc00035b188, {0xc000a15000, 0x1000, 0xc0004eb410?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000ccd440, {0xc000a15000?, 0x7f4fc5?, 0x2acd680?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000f4dd40) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000f4dd40, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000ccd440) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14392 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14482 [IO wait]: internal/poll.runtime_pollWait(0x76b5447a7c00, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000344780?, 0xc000991300?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000344780, {0xc000991300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000344780, {0xc000991300?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001d0a260, {0xc000991300?, 0xc00044e090?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000609548, {0xc000991300?, 0x76b5445bfa08?, 0x76b58bc4ca78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0006082a0, {0xc000991300?, 0xc00056a340?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006957b8, {0x2ad0760, 0xc0006082a0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000695508, {0x76b5445c30f8, 0xc000609548}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000695508, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000695508, {0xc000799000, 0x1000, 0x76b58bc4ca78?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000cdf440, {0xc000799000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000f4cf60) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000f4cf60, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000cdf440) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14384 /usr/local/go/src/net/http/transport.go:1944 +0x174f "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.447510652s (try 4/5): exit status 2: Failed []