"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2025/06/18 05:05:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu" 2025/06/18 05:05:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/18 05:05:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu.bin" 2025/06/18 05:05:41 DEBUG : Swift container crypt path rclone-test-poruneh4pisu.bin: Auto set use_segments_container to true 2025/06/18 05:05:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu" 2025/06/18 05:05:46 DEBUG : Swift container crypt path rclone-test-poruneh4pisu: Auto set use_segments_container to true 2025/06/18 05:05:52 DEBUG : Creating backend with remote "/tmp/rclone2612227628" === 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-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:05:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:05:52 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/06/18 05:05:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:05:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.39s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:05:52 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 05:05:52 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:05:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:05:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:05:52 DEBUG : sub dir/hello world: md5 = 5e17429eaf210ed1f51c848588d2c3e0 OK 2025/06/18 05:05:52 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:05:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.04s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.39s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.09s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:05:54 DEBUG : Creating backend with remote "/non-existing" 2025/06/18 05:05:54 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/06/18 05:05:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:05:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.14s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:05:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:05:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:05:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:05:59 DEBUG : sub dir/hello world: md5 = 157f7861ca5db8290efa1097fae4fae1 OK 2025/06/18 05:05:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (5.89s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:00 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Running all checks before starting transfers 2025/06/18 05:06:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:00 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Checks finished, now starting transfers 2025/06/18 05:06:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:00 DEBUG : sub dir/hello world: md5 = 53ccdd49e454963fb9698c0ec539981a OK 2025/06/18 05:06:00 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.85s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:00 ERROR : Ignoring --no-traverse with sync 2025/06/18 05:06:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:01 DEBUG : sub dir/hello world: md5 = fbdd82362a89f1c29552a0a3a2a8ba2c OK 2025/06/18 05:06:01 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:06:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.02s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:02 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/06/18 05:06:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:02 DEBUG : hello world2: md5 = de068c260b8874ae4f4ceca8586e8ce8 OK 2025/06/18 05:06:02 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.59s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:03 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:06:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:03 DEBUG : potato2: md5 = 019cf714a07af012ad5a1e9fffd779d0 OK 2025/06/18 05:06:03 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.36s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:06:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:10 DEBUG : potato2: md5 = 0cf11b845d31ebc68ad45a88069b9504 OK 2025/06/18 05:06:10 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (5.96s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:10 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/18 05:06:10 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 05:06:10 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/06/18 05:06:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 05:06:11 INFO : sub dir2: Making directory 2025/06/18 05:06:11 INFO : sub dir: Making directory 2025/06/18 05:06:11 INFO : sub dir2/sub sub dir2: Making directory 2025/06/18 05:06:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:11 DEBUG : sub dir/hello world: md5 = b919cd3adff32f7b25780ea5f2e64501 OK 2025/06/18 05:06:11 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/06/18 05:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.23s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:12 INFO : sub dir2: Making directory 2025/06/18 05:06:12 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/18 05:06:12 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 05:06:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:12 DEBUG : sub dir/hello world: md5 = ce8a95c6ea4448d3e9ca32389b7532dd OK 2025/06/18 05:06:12 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (0.99s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:13 DEBUG : sub dir2: Making directory with metadata 2025/06/18 05:06:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 05:06:13 INFO : sub dir2: Making directory 2025/06/18 05:06:13 INFO : sub dir: Making directory 2025/06/18 05:06:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:13 DEBUG : sub dir/hello world: md5 = ad5378ad6a9a4f907404092b67038cd4 OK 2025/06/18 05:06:13 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:06:13 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/06/18 05:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/06/18 05:06:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (0.94s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:409: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.33s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:14 INFO : sub dir2: Making directory 2025/06/18 05:06:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:14 DEBUG : sub dir/hello world: md5 = 942f23c6eff8461586890f02916b7640 OK 2025/06/18 05:06:14 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:06:14 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (0.91s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:15 DEBUG : sub dir2: Making directory with metadata 2025/06/18 05:06:15 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 05:06:15 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 05:06:15 INFO : sub dir2: Making directory 2025/06/18 05:06:15 INFO : sub dir: Making directory 2025/06/18 05:06:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:15 DEBUG : sub dir/hello world: md5 = b6f98ed97fc5bf10c3fd831cfeba5780 OK 2025/06/18 05:06:15 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:06:15 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/06/18 05:06:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (0.96s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.28s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:16 INFO : sub dir2: Making directory 2025/06/18 05:06:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:16 DEBUG : sub dir/hello world: md5 = d9cfc314f3b5ff152e2c8251ea59f871 OK 2025/06/18 05:06:16 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:06:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.74s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:17 DEBUG : sub dir/hello world: md5 = c4c0215eb862da60f2cbd84aa937f841 OK 2025/06/18 05:06:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fuxohof2qida" 2025/06/18 05:06:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fuxohof2qida.bin" 2025/06/18 05:06:18 DEBUG : Swift container crypt path rclone-test-fuxohof2qida.bin: Auto set use_segments_container to true 2025/06/18 05:06:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fuxohof2qida" 2025/06/18 05:06:23 DEBUG : Swift container crypt path rclone-test-fuxohof2qida: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-fuxohof2qida' 2025/06/18 05:06:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fuxohof2qida': Waiting for checks to finish 2025/06/18 05:06:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fuxohof2qida': Waiting for transfers to finish 2025/06/18 05:06:35 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:06:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fuxohof2qida': Purge remote 2025/06/18 05:06:35 DEBUG : Waiting for deletions to finish 2025/06/18 05:06:35 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (18.55s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:35 DEBUG : sub dir/hello world: md5 = 6e78603b66e5650d40ca1a0a72b7899b OK 2025/06/18 05:06:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:06:36 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/18 05:06:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:06:36 DEBUG : sub dir/hello world: md5 = 8d608d3b109b4205be20d539fa324447 OK 2025/06/18 05:06:36 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.50s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:06:37 DEBUG : sub dir/hello world: md5 = febb9b21459ac089c2acf47d2a42cb79 OK 2025/06/18 05:06:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xifikaf3duge" 2025/06/18 05:06:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xifikaf3duge.bin" 2025/06/18 05:06:38 DEBUG : Swift container crypt path rclone-test-xifikaf3duge.bin: Auto set use_segments_container to true 2025/06/18 05:06:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xifikaf3duge" 2025/06/18 05:06:43 DEBUG : Swift container crypt path rclone-test-xifikaf3duge: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge' 2025/06/18 05:06:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:06:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge': Waiting for checks to finish 2025/06/18 05:06:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge': Waiting for transfers to finish 2025/06/18 05:07:04 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:07:10 DEBUG : sub dir/hello world: md5 = a0baa27fb5f2a7a849fa3f2da0e11251 OK 2025/06/18 05:07:10 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/18 05:07:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge': Waiting for checks to finish 2025/06/18 05:07:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge': Waiting for transfers to finish 2025/06/18 05:07:25 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:07:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xifikaf3duge': Purge remote 2025/06/18 05:07:31 DEBUG : Waiting for deletions to finish 2025/06/18 05:07:41 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (64.46s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:07:41 DEBUG : sub dir/hello world: md5 = 5819537b2dfdddde90488af6ae1a9848 OK 2025/06/18 05:07:42 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/18 05:07:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:07:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:07:42 DEBUG : sub dir/hello world: md5 = 622359ad14173dea6b8bf691185f7247 OK 2025/06/18 05:07:42 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/18 05:07:42 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.34s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:07:43 DEBUG : sub dir/hello world: md5 = 4af9d9be6cfa57974013a41a57ab08d5 OK 2025/06/18 05:07:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tazabin6sehi" 2025/06/18 05:07:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tazabin6sehi.bin" 2025/06/18 05:07:43 DEBUG : Swift container crypt path rclone-test-tazabin6sehi.bin: Auto set use_segments_container to true 2025/06/18 05:07:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tazabin6sehi" 2025/06/18 05:07:49 DEBUG : Swift container crypt path rclone-test-tazabin6sehi: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi' 2025/06/18 05:07:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:07:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for checks to finish 2025/06/18 05:07:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for transfers to finish 2025/06/18 05:08:05 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:08:06 DEBUG : sub dir/hello world: md5 = ba84ce4b6b79204471b8f49b0c5a9d3d OK 2025/06/18 05:08:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for checks to finish 2025/06/18 05:08:06 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/18 05:08:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for transfers to finish 2025/06/18 05:08:16 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:08:17 INFO : sub dir/hello world: Deleted 2025/06/18 05:08:22 DEBUG : sub dir/hello world: md5 = d1d1c99021b4bddc59a4e9a3f7e78ba8 OK 2025/06/18 05:08:22 DEBUG : testing file moves 2025/06/18 05:08:23 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/18 05:08:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for checks to finish 2025/06/18 05:08:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Waiting for transfers to finish 2025/06/18 05:08:33 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/18 05:08:33 INFO : sub dir/hello world: Deleted 2025/06/18 05:08:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tazabin6sehi': Purge remote 2025/06/18 05:08:38 DEBUG : Waiting for deletions to finish 2025/06/18 05:08:48 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (65.91s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:08:49 DEBUG : sub dir/hello world: md5 = 89d232ace30c6d0baeace6bff32536bd OK 2025/06/18 05:08:49 ERROR : error listing: directory not found 2025/06/18 05:08:49 INFO : Local file system at /tmp/rclone2612227628: Making directory 2025/06/18 05:08:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:08:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.22s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:08:50 DEBUG : sub dir/hello world: md5 = 2f76b5c984546aa6a097508733dc596d OK 2025/06/18 05:08:50 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:08:50 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for checks to finish 2025/06/18 05:08:50 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for transfers to finish 2025/06/18 05:08:50 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/06/18 05:08:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.05s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:08:51 DEBUG : check sum: Need to transfer - File not found at Destination 2025/06/18 05:08:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:08:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:08:56 DEBUG : check sum: md5 = 09118a6aee5bf4a9eb7e80dfa3e5085f OK 2025/06/18 05:08:56 INFO : check sum: Copied (new) 2025/06/18 05:08:56 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:01 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/06/18 05:09:01 DEBUG : check sum: Size of src and dst objects identical 2025/06/18 05:09:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:01 DEBUG : check sum: Unchanged skipping 2025/06/18 05:09:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:01 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:01 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (26.03s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:09:17 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/06/18 05:09:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:17 DEBUG : sizeonly: md5 = 350ac09a78eb636050b6b0bae380ecf0 OK 2025/06/18 05:09:17 INFO : sizeonly: Copied (new) 2025/06/18 05:09:17 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:18 DEBUG : sizeonly: Sizes identical 2025/06/18 05:09:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:18 DEBUG : sizeonly: Unchanged skipping 2025/06/18 05:09:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:18 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:18 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.27s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:09:18 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/06/18 05:09:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:20 DEBUG : ignore-size: md5 = e1360662b1e1f76a2b806d79b8b7d85f OK 2025/06/18 05:09:20 INFO : ignore-size: Copied (new) 2025/06/18 05:09:20 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:20 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:09:20 DEBUG : ignore-size: Unchanged skipping 2025/06/18 05:09:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:20 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:20 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (2.32s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:09:26 DEBUG : existing: md5 = 157c325dbcf5e9a77587dd63e13642b4 OK 2025/06/18 05:09:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:31 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:09:31 DEBUG : existing: Unchanged skipping 2025/06/18 05:09:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:31 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:31 INFO : There was nothing to transfer 2025/06/18 05:09:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:31 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/06/18 05:09:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:37 DEBUG : existing: md5 = f8330f98697559d0e6c152b5c0716d2a OK 2025/06/18 05:09:37 INFO : existing: Copied (replaced existing) 2025/06/18 05:09:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (22.03s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:09:43 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/18 05:09:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:48 DEBUG : existing: md5 = 6bf1832614ced7aa38327595fa7e755f OK 2025/06/18 05:09:48 INFO : existing: Copied (new) 2025/06/18 05:09:48 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:53 DEBUG : existing: Destination exists, skipping 2025/06/18 05:09:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:09:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:09:53 DEBUG : Waiting for deletions to finish 2025/06/18 05:09:53 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (16.10s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:10:04 DEBUG : b/potato: md5 = 0d19271b6e89247d074cd28dfcf273b5 OK 2025/06/18 05:10:05 DEBUG : c/non empty space: md5 = d5f053783a889889843636f08d838ba7 OK 2025/06/18 05:10:05 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/18 05:10:05 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/18 05:10:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:05 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:10:05 DEBUG : c/non empty space: Unchanged skipping 2025/06/18 05:10:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:06 DEBUG : a/potato2: md5 = 7e67b1eed211e6060c8726e834293db0 OK 2025/06/18 05:10:06 INFO : a/potato2: Copied (new) 2025/06/18 05:10:06 DEBUG : Waiting for deletions to finish 2025/06/18 05:10:11 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (13.36s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:10:12 DEBUG : empty space: md5 = 827be8d395b83e1d5825e40b6c523963 OK 2025/06/18 05:10:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:13 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/18 05:10:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:13 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/06/18 05:10:13 DEBUG : Waiting for deletions to finish 2025/06/18 05:10:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:13 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/18 05:10:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:13 DEBUG : empty space: md5 = ee7e4e160d2190a96a6b0a6b745c1e03 OK 2025/06/18 05:10:13 INFO : empty space: Copied (replaced existing) 2025/06/18 05:10:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (1.81s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.21s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:10:14 DEBUG : foo: md5 = 2eaa53e1af7bd08c8a9621f16719c0c5 OK 2025/06/18 05:10:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:15 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/18 05:10:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:15 DEBUG : foo: md5 = 1faec20c4138ccb32a85fd90643758d6 OK 2025/06/18 05:10:15 INFO : foo: Copied (replaced existing) 2025/06/18 05:10:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (1.45s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:10:16 DEBUG : empty space: md5 = e94d57a569d1754bb4a5b65c2e347b68 OK 2025/06/18 05:10:16 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/18 05:10:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:10:16 DEBUG : empty space: Unchanged skipping 2025/06/18 05:10:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:21 DEBUG : potato: md5 = 3d43156ee1949330cfa763d3d609f977 OK 2025/06/18 05:10:21 INFO : potato: Copied (new) 2025/06/18 05:10:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (11.78s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:10:32 DEBUG : potato: md5 = 0612acca1d8b6720fd56d13a0d20be09 OK 2025/06/18 05:10:33 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/06/18 05:10:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:10:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:10:43 DEBUG : potato: md5 = 070add66df9fd53eddc30273c829d4e4 OK 2025/06/18 05:10:43 INFO : potato: Copied (replaced existing) 2025/06/18 05:10:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (31.14s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:11:03 DEBUG : potato: md5 = 5ef211fded97069cdfe7215657434e1c OK 2025/06/18 05:11:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:11:09 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/06/18 05:11:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:11:14 DEBUG : potato: md5 = f48f88998f300e8c38e854384c4b6a5c OK 2025/06/18 05:11:14 INFO : potato: Copied (replaced existing) 2025/06/18 05:11:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (26.22s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:11:30 DEBUG : potato: md5 = cf61a6113532aee857d33acce535c1d3 OK 2025/06/18 05:11:30 DEBUG : empty space: md5 = 03466567ff28b07c192f7e5a89eadb04 OK 2025/06/18 05:11:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:11:30 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/06/18 05:11:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:11:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:11:30 DEBUG : empty space: Unchanged skipping 2025/06/18 05:11:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:11:30 DEBUG : Waiting for deletions to finish 2025/06/18 05:11:30 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (16.34s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:11:46 DEBUG : potato: md5 = 082f316b3d5f778aa22f6afe292211e6 OK 2025/06/18 05:11:46 DEBUG : empty space: md5 = d24adf7b87a1830535ed10c254dcc9a8 OK 2025/06/18 05:11:52 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:11:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:11:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:11:52 DEBUG : empty space: Unchanged skipping 2025/06/18 05:11:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:11:52 DEBUG : potato2: md5 = 9f7456a57bf016acef79710eb29c8172 OK 2025/06/18 05:11:52 INFO : potato2: Copied (new) 2025/06/18 05:11:52 DEBUG : Waiting for deletions to finish 2025/06/18 05:11:57 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (16.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:12:03 DEBUG : b/potato: md5 = 2720d1e895377c56e235d6cde3cb6471 OK 2025/06/18 05:12:03 DEBUG : c/non empty space: md5 = 27cffd2cebd405a7213e8cbb50acb3e5 OK 2025/06/18 05:12:03 INFO : d: Making directory 2025/06/18 05:12:03 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/06/18 05:12:03 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/18 05:12:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:12:04 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:12:04 DEBUG : c/non empty space: Unchanged skipping 2025/06/18 05:12:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:12:04 DEBUG : a/potato2: md5 = 03a183f1b5883752aebc0d023877c439 OK 2025/06/18 05:12:04 INFO : a/potato2: Copied (new) 2025/06/18 05:12:04 DEBUG : Waiting for deletions to finish 2025/06/18 05:12:09 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (11.79s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:12:15 DEBUG : b/potato: md5 = 16142d3e1c432158e69378dd89bd595a OK 2025/06/18 05:12:15 DEBUG : c/non empty space: md5 = 03f6cf25b885d81903e9e387ddc232ee OK 2025/06/18 05:12:15 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/18 05:12:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/18 05:12:15 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:12:15 DEBUG : c/non empty space: Unchanged skipping 2025/06/18 05:12:16 DEBUG : a/potato2: md5 = 0010769b3bf388e596ddcd7ffb960f2d OK 2025/06/18 05:12:16 INFO : a/potato2: Copied (new) 2025/06/18 05:12:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:12:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:12:16 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': not deleting files as there were IO errors 2025/06/18 05:12:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (16.84s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:12:32 DEBUG : potato: md5 = a38e977e2aa9dc93576774bc8ae11aa4 OK 2025/06/18 05:12:32 DEBUG : empty space: md5 = 00ca58eb3d20dfc9c476e026b796867d OK 2025/06/18 05:12:32 DEBUG : Waiting for deletions to finish 2025/06/18 05:12:32 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:12:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:12:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:12:32 DEBUG : empty space: Unchanged skipping 2025/06/18 05:12:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:12:32 DEBUG : potato2: md5 = 4c40346346981fea5ea5943c4c1e29f8 OK 2025/06/18 05:12:32 INFO : potato2: Copied (new) 2025/06/18 05:12:42 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (17.22s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:12:49 DEBUG : potato: md5 = 8671b72b80a70055295e18cc0b922ca0 OK 2025/06/18 05:12:49 DEBUG : empty space: md5 = 02866c1a74708b6fcf5f32658820e270 OK 2025/06/18 05:12:49 DEBUG : Waiting for deletions to finish 2025/06/18 05:12:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:12:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:12:55 INFO : potato: Deleted 2025/06/18 05:12:55 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:12:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:12:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:12:55 DEBUG : empty space: Unchanged skipping 2025/06/18 05:12:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:12:55 DEBUG : potato2: md5 = 6b013bb9d82c01d8cd1537cc12167536 OK 2025/06/18 05:12:55 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (11.87s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:13:01 DEBUG : potato: md5 = d8996d84505746ee9e7cb2d2c06a5d2a OK 2025/06/18 05:13:06 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:13:06 DEBUG : potato2: md5 = 995a6085a503424a3fb87673cd7ec753 OK 2025/06/18 05:13:06 INFO : potato2: Copied (new) 2025/06/18 05:13:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:13:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish --- PASS: TestCopyDeleteBefore (21.10s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:13:17 DEBUG : potato2: md5 = 9fa1ea0fcdd1e022a25a55c0e6724db8 OK 2025/06/18 05:13:17 DEBUG : empty space: md5 = a6c8e3d2e016117d95c19c962f0bd2d0 OK 2025/06/18 05:13:17 DEBUG : enormous: Excluded (Size Filter) 2025/06/18 05:13:17 DEBUG : enormous: Excluded 2025/06/18 05:13:17 DEBUG : potato2: Excluded (Size Filter) 2025/06/18 05:13:17 DEBUG : potato2: Excluded 2025/06/18 05:13:17 DEBUG : potato2: Excluded (Size Filter) 2025/06/18 05:13:17 DEBUG : potato2: Excluded 2025/06/18 05:13:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:13:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:13:17 DEBUG : empty space: Unchanged skipping 2025/06/18 05:13:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:13:17 DEBUG : Waiting for deletions to finish 2025/06/18 05:13:17 INFO : There was nothing to transfer 2025/06/18 05:13:18 DEBUG : enormous: Excluded (Size Filter) 2025/06/18 05:13:18 DEBUG : enormous: Excluded 2025/06/18 05:13:18 DEBUG : potato2: Excluded (Size Filter) 2025/06/18 05:13:18 DEBUG : potato2: Excluded 2025/06/18 05:13:18 DEBUG : potato2: Excluded (Size Filter) 2025/06/18 05:13:18 DEBUG : potato2: Excluded 2025/06/18 05:13:18 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for checks to finish 2025/06/18 05:13:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:13:18 DEBUG : empty space: Unchanged skipping 2025/06/18 05:13:18 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for transfers to finish 2025/06/18 05:13:18 DEBUG : Waiting for deletions to finish 2025/06/18 05:13:18 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (1.90s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:13:19 DEBUG : potato2: md5 = ef57f051fe20b78a526f11966c81ed79 OK 2025/06/18 05:13:19 DEBUG : empty space: md5 = fc6f0e0c67c79c183cb507c932859fca OK 2025/06/18 05:13:20 DEBUG : enormous: md5 = b42e1bef41bb8d4794125ec964044430 OK 2025/06/18 05:13:20 DEBUG : enormous: Excluded (Size Filter) 2025/06/18 05:13:20 DEBUG : enormous: Excluded 2025/06/18 05:13:20 DEBUG : potato2: Excluded (Size Filter) 2025/06/18 05:13:20 DEBUG : potato2: Excluded 2025/06/18 05:13:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:13:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:13:20 DEBUG : empty space: Unchanged skipping 2025/06/18 05:13:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:13:20 DEBUG : Waiting for deletions to finish 2025/06/18 05:13:20 INFO : potato2: Deleted 2025/06/18 05:13:20 INFO : enormous: Deleted 2025/06/18 05:13:20 INFO : There was nothing to transfer 2025/06/18 05:13:21 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for checks to finish 2025/06/18 05:13:21 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:13:21 DEBUG : empty space: Unchanged skipping 2025/06/18 05:13:21 DEBUG : Local file system at /tmp/rclone2612227628: Waiting for transfers to finish 2025/06/18 05:13:21 DEBUG : Waiting for deletions to finish 2025/06/18 05:13:21 INFO : potato2: Deleted 2025/06/18 05:13:21 INFO : enormous: Deleted 2025/06/18 05:13:21 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.51s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:13:26 DEBUG : one: md5 = e262d7c5d8c979d151427cfcdf32b061 OK 2025/06/18 05:13:27 DEBUG : two: md5 = 1df47b2759b59490ce33ef0ad8debb09 OK 2025/06/18 05:13:32 DEBUG : three: md5 = afc251dd6a66f185545e452897da69f2 OK 2025/06/18 05:13:38 DEBUG : four: md5 = 91762dec655acaa8ecf33c4142985fb2 OK 2025/06/18 05:13:48 DEBUG : five: Need to transfer - File not found at Destination 2025/06/18 05:13:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:13:48 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/06/18 05:13:48 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/06/18 05:13:49 DEBUG : five: md5 = 52260e44903b8d3885ca5ea716c60a56 OK 2025/06/18 05:13:49 INFO : five: Copied (new) 2025/06/18 05:13:49 DEBUG : two: md5 = cc29a596a04b562b1b41396a333e8262 OK 2025/06/18 05:13:49 INFO : two: Copied (replaced existing) 2025/06/18 05:13:53 DEBUG : three: Sizes identical 2025/06/18 05:13:53 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/06/18 05:13:53 DEBUG : one: Destination is newer than source, skipping 2025/06/18 05:13:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:13:54 DEBUG : four: md5 = 9eedd8b43ef7e7f97c957fe1c30c7057 OK 2025/06/18 05:13:54 INFO : four: Copied (replaced existing) 2025/06/18 05:13:54 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (59.19s) === 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-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/06/18 05:14:20 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/18 05:14:20 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/18 05:14:20 DEBUG : yam: Need to transfer - File not found at Destination 2025/06/18 05:14:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:14:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:14:26 DEBUG : potato: md5 = b0957904662331ccc3456100c986e9a9 OK 2025/06/18 05:14:26 INFO : potato: Copied (new) 2025/06/18 05:14:26 DEBUG : yam: md5 = 486a91975fd026afcbc842ad0a52e29c OK 2025/06/18 05:14:26 INFO : yam: Copied (new) 2025/06/18 05:14:26 DEBUG : Waiting for deletions to finish 2025/06/18 05:14:31 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/18 05:14:32 DEBUG : yaml: Need to transfer - File not found at Destination 2025/06/18 05:14:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:14:32 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:14:32 DEBUG : potato: Unchanged skipping 2025/06/18 05:14:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:14:37 DEBUG : yaml: md5 = 93cb3188590dd05832a52728a3e47fb4 OK 2025/06/18 05:14:37 INFO : yaml: Copied (new) 2025/06/18 05:14:37 DEBUG : Waiting for deletions to finish 2025/06/18 05:14:42 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (47.66s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/06/18 05:15:08 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Making map for --track-renames 2025/06/18 05:15:08 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Finished making map for --track-renames 2025/06/18 05:15:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:15:08 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/18 05:15:08 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/18 05:15:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for renames to finish 2025/06/18 05:15:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:15:13 DEBUG : potato: md5 = 7d03b331cd60de5f8eecb475b024b30b OK 2025/06/18 05:15:13 INFO : potato: Copied (new) 2025/06/18 05:15:13 DEBUG : yam: md5 = 10f9530a155fe6e3f68dbf96990ac0d3 OK 2025/06/18 05:15:13 INFO : yam: Copied (new) 2025/06/18 05:15:13 DEBUG : Waiting for deletions to finish 2025/06/18 05:15:14 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Making map for --track-renames 2025/06/18 05:15:14 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Finished making map for --track-renames 2025/06/18 05:15:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:15:14 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:15:14 DEBUG : potato: Unchanged skipping 2025/06/18 05:15:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for renames to finish 2025/06/18 05:16:05 INFO : yam: Copied (server-side copy) to: yaml 2025/06/18 05:16:25 INFO : yam: Deleted 2025/06/18 05:16:25 INFO : yaml: Renamed from "yam" 2025/06/18 05:16:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:16:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (88.24s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/06/18 05:16:36 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Making map for --track-renames 2025/06/18 05:16:36 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Finished making map for --track-renames 2025/06/18 05:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for renames to finish 2025/06/18 05:16:36 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/18 05:16:36 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/18 05:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:16:42 DEBUG : sub/yam: md5 = 2d8db33a6b2fa479e5b21dc69fa2d916 OK 2025/06/18 05:16:42 INFO : sub/yam: Copied (new) 2025/06/18 05:16:42 DEBUG : potato: md5 = d768f9895b87b0b528d2615a46e63ddf OK 2025/06/18 05:16:42 INFO : potato: Copied (new) 2025/06/18 05:16:42 DEBUG : Waiting for deletions to finish 2025/06/18 05:16:42 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:16:42 DEBUG : potato: Unchanged skipping 2025/06/18 05:16:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Making map for --track-renames 2025/06/18 05:16:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Finished making map for --track-renames 2025/06/18 05:16:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:16:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for renames to finish 2025/06/18 05:17:03 INFO : sub/yam: Copied (server-side copy) to: yam 2025/06/18 05:17:08 INFO : sub/yam: Deleted 2025/06/18 05:17:08 INFO : yam: Renamed from "sub/yam" 2025/06/18 05:17:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:17:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (70.33s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:17:46 DEBUG : Creating backend with remote "/tmp/rclone2612227628/dir1" 2025/06/18 05:17:46 DEBUG : Config file has changed externally - reloading 2025/06/18 05:17:46 DEBUG : Creating backend with remote "/tmp/rclone2612227628/dir2" 2025/06/18 05:17:46 DEBUG : Local file system at /tmp/rclone2612227628/dir2: Using server-side directory move 2025/06/18 05:17:46 INFO : Local file system at /tmp/rclone2612227628/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/18 05:17:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/06/18 05:17:46 DEBUG : Local file system at /tmp/rclone2612227628/dir2: Waiting for checks to finish 2025/06/18 05:17:46 DEBUG : Local file system at /tmp/rclone2612227628/dir2: Waiting for transfers to finish 2025/06/18 05:17:46 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.22s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:17:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:17:47 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/18 05:17:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:17:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:17:47 DEBUG : sub dir/hello world: md5 = 6cca07340c263e9416d900ace9dce4d1 OK 2025/06/18 05:17:47 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:17:47 INFO : sub dir/hello world: Deleted 2025/06/18 05:17:47 DEBUG : nested/sub dir/file: md5 = a2db15673d0c244f096ee1cb0e986f22 OK 2025/06/18 05:17:47 INFO : nested/sub dir/file: Copied (new) 2025/06/18 05:17:47 INFO : nested/sub dir/file: Deleted 2025/06/18 05:17:47 INFO : sub dir: Removing directory 2025/06/18 05:17:47 INFO : nested/sub dir: Removing directory 2025/06/18 05:17:47 INFO : nested: Removing directory 2025/06/18 05:17:47 DEBUG : Local file system at /tmp/rclone2612227628: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (1.69s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:17:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/18 05:17:48 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/18 05:17:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:17:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:17:49 DEBUG : sub dir/hello world: md5 = 3eb852242c76033d2f29bdbad2fe7fd8 OK 2025/06/18 05:17:49 INFO : sub dir/hello world: Copied (new) 2025/06/18 05:17:49 INFO : sub dir/hello world: Deleted 2025/06/18 05:17:49 DEBUG : nested/sub dir/file: md5 = d43facb2497f9b6d2206c5bbab274226 OK 2025/06/18 05:17:49 INFO : nested/sub dir/file: Copied (new) 2025/06/18 05:17:49 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.13s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:17:50 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/06/18 05:17:50 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/18 05:17:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:17:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:17:55 DEBUG : existing: md5 = 6405fc2bd0f3fd3248817241ddc5b54b OK 2025/06/18 05:17:55 INFO : existing: Copied (new) 2025/06/18 05:17:55 INFO : existing: Deleted 2025/06/18 05:17:55 DEBUG : existing-b: md5 = 61ac932419e4cad37f074f4dc89377b6 OK 2025/06/18 05:17:55 INFO : existing-b: Copied (new) 2025/06/18 05:17:55 INFO : existing-b: Deleted 2025/06/18 05:18:11 DEBUG : existing: Destination exists, skipping 2025/06/18 05:18:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:18:11 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/06/18 05:18:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:18:11 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (46.74s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:18:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-pumekiw2vaxa" 2025/06/18 05:18:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pumekiw2vaxa.bin" 2025/06/18 05:18:37 DEBUG : Swift container crypt path rclone-test-pumekiw2vaxa.bin: Auto set use_segments_container to true 2025/06/18 05:18:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pumekiw2vaxa" 2025/06/18 05:18:42 DEBUG : Swift container crypt path rclone-test-pumekiw2vaxa: Auto set use_segments_container to true 2025/06/18 05:18:48 DEBUG : potato2: md5 = 2e9a1a9a029985cc86321b4567b3dd4e OK 2025/06/18 05:18:48 DEBUG : empty space: md5 = bbe3dc71bd255a1fdb6c846fb78cbdce OK 2025/06/18 05:18:53 DEBUG : potato3: md5 = a26771416c114fcf1f1d5fe9fd02fe19 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-pumekiw2vaxa' 2025/06/18 05:18:59 DEBUG : empty space: md5 = 24b88b5c14a5a1378d7bf1e9395b88ee OK 2025/06/18 05:19:05 DEBUG : potato3: md5 = 1c8686127ab0f2d4e91a5735b26e0cb8 OK 2025/06/18 05:19:10 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:19:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pumekiw2vaxa': Waiting for checks to finish 2025/06/18 05:19:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/18 05:19:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:19:10 DEBUG : empty space: Unchanged skipping 2025/06/18 05:19:10 INFO : empty space: Deleted 2025/06/18 05:19:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pumekiw2vaxa': Waiting for transfers to finish 2025/06/18 05:19:16 INFO : potato2: Copied (server-side copy) 2025/06/18 05:19:16 INFO : potato2: Deleted 2025/06/18 05:19:20 INFO : potato3: Copied (server-side copy) 2025/06/18 05:19:26 INFO : potato3: Deleted 2025/06/18 05:19:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qonokop1peji" 2025/06/18 05:19:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qonokop1peji.bin" 2025/06/18 05:19:26 DEBUG : Swift container crypt path rclone-test-qonokop1peji.bin: Auto set use_segments_container to true 2025/06/18 05:19:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qonokop1peji" 2025/06/18 05:19:32 DEBUG : Swift container crypt path rclone-test-qonokop1peji: Auto set use_segments_container to true 2025/06/18 05:19:38 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/18 05:19:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:19:38 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/18 05:19:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qonokop1peji': Waiting for checks to finish 2025/06/18 05:19:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qonokop1peji': Waiting for transfers to finish 2025/06/18 05:19:53 INFO : empty space: Copied (server-side copy) 2025/06/18 05:19:53 INFO : potato2: Copied (server-side copy) 2025/06/18 05:19:53 INFO : empty space: Deleted 2025/06/18 05:19:53 INFO : potato2: Deleted 2025/06/18 05:19:58 INFO : potato3: Copied (server-side copy) 2025/06/18 05:20:03 INFO : potato3: Deleted 2025/06/18 05:20:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qonokop1peji': Purge remote 2025/06/18 05:20:14 DEBUG : Waiting for deletions to finish 2025/06/18 05:20:19 INFO : empty space.bin: Deleted 2025/06/18 05:20:19 INFO : potato2.bin: Deleted 2025/06/18 05:20:19 INFO : potato3.bin: Deleted 2025/06/18 05:20:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pumekiw2vaxa': Purge remote 2025/06/18 05:20:19 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (103.33s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:20:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dikogeq1momo" 2025/06/18 05:20:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dikogeq1momo.bin" 2025/06/18 05:20:20 DEBUG : Swift container crypt path rclone-test-dikogeq1momo.bin: Auto set use_segments_container to true 2025/06/18 05:20:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dikogeq1momo" 2025/06/18 05:20:26 DEBUG : Swift container crypt path rclone-test-dikogeq1momo: Auto set use_segments_container to true 2025/06/18 05:20:31 DEBUG : potato2: md5 = 322414edf48ed54bd4592f48e1247bd6 OK 2025/06/18 05:20:31 DEBUG : empty space: md5 = d33b17afbcf7f0862625b940dcd20c4e OK 2025/06/18 05:20:37 DEBUG : potato3: md5 = 3a27c16aefc5880fb630826715389ea3 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-dikogeq1momo' 2025/06/18 05:20:38 DEBUG : empty space: md5 = 97beda34a88b42cd88d5751961447fe3 OK 2025/06/18 05:20:38 DEBUG : potato3: md5 = 06c76d646e38834d345376737ef851bc OK 2025/06/18 05:20:38 DEBUG : empty space: Excluded (Size Filter) 2025/06/18 05:20:38 DEBUG : empty space: Excluded 2025/06/18 05:20:38 DEBUG : empty space: Excluded (Size Filter) 2025/06/18 05:20:38 DEBUG : empty space: Excluded 2025/06/18 05:20:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:20:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/18 05:20:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dikogeq1momo': Waiting for checks to finish 2025/06/18 05:20:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dikogeq1momo': Waiting for transfers to finish 2025/06/18 05:20:44 INFO : potato2: Copied (server-side copy) 2025/06/18 05:20:44 INFO : potato2: Deleted 2025/06/18 05:20:49 INFO : potato3: Copied (server-side copy) 2025/06/18 05:20:54 INFO : potato3: Deleted 2025/06/18 05:20:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-razihok0wiza" 2025/06/18 05:20:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-razihok0wiza.bin" 2025/06/18 05:20:55 DEBUG : Swift container crypt path rclone-test-razihok0wiza.bin: Auto set use_segments_container to true 2025/06/18 05:21:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-razihok0wiza" 2025/06/18 05:21:00 DEBUG : Swift container crypt path rclone-test-razihok0wiza: Auto set use_segments_container to true 2025/06/18 05:21:06 DEBUG : empty space: Excluded (Size Filter) 2025/06/18 05:21:06 DEBUG : empty space: Excluded 2025/06/18 05:21:06 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:21:06 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/18 05:21:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-razihok0wiza': Waiting for checks to finish 2025/06/18 05:21:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-razihok0wiza': Waiting for transfers to finish 2025/06/18 05:21:11 INFO : potato2: Copied (server-side copy) 2025/06/18 05:21:12 INFO : potato2: Deleted 2025/06/18 05:21:12 INFO : potato3: Copied (server-side copy) 2025/06/18 05:21:12 INFO : potato3: Deleted 2025/06/18 05:21:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-razihok0wiza': Purge remote 2025/06/18 05:21:12 DEBUG : Waiting for deletions to finish 2025/06/18 05:21:13 INFO : potato2.bin: Deleted 2025/06/18 05:21:13 INFO : potato3.bin: Deleted 2025/06/18 05:21:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dikogeq1momo': Purge remote 2025/06/18 05:21:13 DEBUG : Waiting for deletions to finish 2025/06/18 05:21:13 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (54.01s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:21:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ciqapur5guqa" 2025/06/18 05:21:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ciqapur5guqa.bin" 2025/06/18 05:21:14 DEBUG : Swift container crypt path rclone-test-ciqapur5guqa.bin: Auto set use_segments_container to true 2025/06/18 05:21:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ciqapur5guqa" 2025/06/18 05:21:20 DEBUG : Swift container crypt path rclone-test-ciqapur5guqa: Auto set use_segments_container to true 2025/06/18 05:21:25 DEBUG : potato2: md5 = 89b9358431425553962f673a9f402c08 OK 2025/06/18 05:21:25 DEBUG : empty space: md5 = dcbf332d3491be5c5a8e16aa739b1c3e OK 2025/06/18 05:21:30 DEBUG : potato3: md5 = cb49f5cfbd91ec2d2faea24331a98800 OK 2025/06/18 05:21:30 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu' -> Encrypted drive 'TestCryptSwift:rclone-test-ciqapur5guqa' 2025/06/18 05:21:36 DEBUG : empty space: md5 = f28cb9074f020ca33d7ce6b2aa1cec69 OK 2025/06/18 05:21:37 DEBUG : potato3: md5 = 98464b7254f52e1cc9b14bd1faaee4fc OK 2025/06/18 05:21:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:21:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/18 05:21:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciqapur5guqa': Waiting for checks to finish 2025/06/18 05:21:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:21:37 DEBUG : empty space: Unchanged skipping 2025/06/18 05:21:37 INFO : empty space: Deleted 2025/06/18 05:21:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciqapur5guqa': Waiting for transfers to finish 2025/06/18 05:21:42 INFO : potato3: Copied (server-side copy) 2025/06/18 05:21:47 INFO : potato3: Deleted 2025/06/18 05:21:47 INFO : potato2: Copied (server-side copy) 2025/06/18 05:21:48 INFO : potato2: Deleted 2025/06/18 05:21:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nukucit0kucu" 2025/06/18 05:21:48 DEBUG : Config file has changed externally - reloading 2025/06/18 05:21:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nukucit0kucu.bin" 2025/06/18 05:21:48 DEBUG : Swift container crypt path rclone-test-nukucit0kucu.bin: Auto set use_segments_container to true 2025/06/18 05:21:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nukucit0kucu" 2025/06/18 05:21:54 DEBUG : Config file has changed externally - reloading 2025/06/18 05:21:54 DEBUG : Swift container crypt path rclone-test-nukucit0kucu: Auto set use_segments_container to true 2025/06/18 05:21:59 INFO : tomatoDir: Making directory 2025/06/18 05:22:00 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/18 05:22:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/18 05:22:00 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/18 05:22:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nukucit0kucu': Waiting for checks to finish 2025/06/18 05:22:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nukucit0kucu': Waiting for transfers to finish 2025/06/18 05:22:10 INFO : potato2: Copied (server-side copy) 2025/06/18 05:22:15 INFO : potato2: Deleted 2025/06/18 05:22:15 INFO : potato3: Copied (server-side copy) 2025/06/18 05:22:15 INFO : empty space: Copied (server-side copy) 2025/06/18 05:22:15 INFO : potato3: Deleted 2025/06/18 05:22:20 INFO : empty space: Deleted 2025/06/18 05:22:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nukucit0kucu': Purge remote 2025/06/18 05:22:21 DEBUG : Waiting for deletions to finish 2025/06/18 05:22:26 INFO : potato3.bin: Deleted 2025/06/18 05:22:26 INFO : empty space.bin: Deleted 2025/06/18 05:22:26 INFO : potato2.bin: Deleted 2025/06/18 05:22:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciqapur5guqa': Purge remote 2025/06/18 05:22:26 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (73.27s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:22:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/rclone-move-test" 2025/06/18 05:22:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-move-test.bin" 2025/06/18 05:22:27 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-move-test.bin: Auto set use_segments_container to true 2025/06/18 05:22:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-move-test" 2025/06/18 05:22:33 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-move-test: Auto set use_segments_container to true 2025/06/18 05:22:38 DEBUG : potato2: md5 = f9bfdc8000b6772d8115d6d839b9ab3d OK --- PASS: TestServerSideMoveOverlap (12.18s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:22:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test" 2025/06/18 05:22:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test.bin" 2025/06/18 05:22:39 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/18 05:22:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test" 2025/06/18 05:22:45 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.46s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:22:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test" 2025/06/18 05:22:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test.bin" 2025/06/18 05:22:51 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/18 05:22:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test" 2025/06/18 05:22:56 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test: Auto set use_segments_container to true 2025/06/18 05:23:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-include/layer2" 2025/06/18 05:23:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test-include/layer2.bin" 2025/06/18 05:23:02 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/06/18 05:23:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test-include/layer2" 2025/06/18 05:23:07 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/06/18 05:23:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-ignore-file" 2025/06/18 05:23:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test-ignore-file.bin" 2025/06/18 05:23:13 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/06/18 05:23:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/rclone-sync-test-ignore-file" 2025/06/18 05:23:19 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/06/18 05:23:25 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 87ceb499c23e440c29ac3df272273a1a OK 2025/06/18 05:23:30 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 05:23:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test': Waiting for checks to finish 2025/06/18 05:23:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test': Waiting for transfers to finish 2025/06/18 05:23:30 DEBUG : Waiting for deletions to finish 2025/06/18 05:23:30 INFO : There was nothing to transfer 2025/06/18 05:23:35 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 05:23:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:23:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:23:35 DEBUG : Waiting for deletions to finish 2025/06/18 05:23:35 INFO : There was nothing to transfer 2025/06/18 05:23:40 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 05:23:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/18 05:23:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/18 05:23:40 DEBUG : Waiting for deletions to finish 2025/06/18 05:23:40 INFO : There was nothing to transfer 2025/06/18 05:23:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:23:46 DEBUG : Waiting for deletions to finish 2025/06/18 05:23:46 INFO : There was nothing to transfer 2025/06/18 05:23:46 DEBUG : Excluded 2025/06/18 05:23:46 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/18 05:23:46 DEBUG : Waiting for deletions to finish 2025/06/18 05:23:46 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (55.55s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:23:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:23:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:23:46 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:23:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:23:52 DEBUG : Config file has changed externally - reloading 2025/06/18 05:23:52 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:24:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/CompareDest" 2025/06/18 05:24:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/CompareDest.bin" 2025/06/18 05:24:03 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/CompareDest.bin: Auto set use_segments_container to true 2025/06/18 05:24:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/CompareDest" 2025/06/18 05:24:08 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/CompareDest: Auto set use_segments_container to true 2025/06/18 05:24:19 DEBUG : one: Need to transfer - File not found at Destination 2025/06/18 05:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:24:24 DEBUG : one: md5 = a8cde9466264d07f545ca9501e8e9146 OK 2025/06/18 05:24:24 INFO : one: Copied (new) 2025/06/18 05:24:24 DEBUG : Waiting for deletions to finish 2025/06/18 05:24:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:24:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:24:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:24:35 DEBUG : one: md5 = 6a3338a305da474398f3577ade53ea26 OK 2025/06/18 05:24:35 INFO : one: Copied (replaced existing) 2025/06/18 05:24:35 DEBUG : Waiting for deletions to finish 2025/06/18 05:24:41 DEBUG : dst/one: md5 = d8694711aa764cc2d6f10cad643e8383 OK 2025/06/18 05:24:46 DEBUG : CompareDest/one: md5 = b15781455ad502baf2eb24fb42cf86b5 OK 2025/06/18 05:24:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:24:46 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:24:46 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:24:46 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/18 05:24:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:24:46 DEBUG : Waiting for deletions to finish 2025/06/18 05:24:46 INFO : There was nothing to transfer 2025/06/18 05:24:52 DEBUG : CompareDest/two: md5 = d85ac8acfe632bc2b7267e4d2d8a3253 OK 2025/06/18 05:24:52 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:24:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:24:52 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/18 05:24:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:24:52 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:24:52 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/18 05:24:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:24:52 DEBUG : Waiting for deletions to finish 2025/06/18 05:24:52 INFO : There was nothing to transfer 2025/06/18 05:24:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:24:58 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:24:58 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/18 05:25:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:25:03 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/18 05:25:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:25:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:25:03 DEBUG : Waiting for deletions to finish 2025/06/18 05:25:03 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/06/18 05:25:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:25:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:25:18 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/18 05:25:18 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/06/18 05:25:18 DEBUG : two: Need to transfer - File not found at Destination 2025/06/18 05:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:25:23 DEBUG : two: md5 = 1d04d87c86e8635df150d363ea94dd2e OK 2025/06/18 05:25:23 INFO : two: Copied (new) 2025/06/18 05:25:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (133.58s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:26:05 DEBUG : pre-dest1/1: md5 = 70a972db3299357ef9b294f515adc650 OK 2025/06/18 05:26:05 DEBUG : pre-dest2/2: md5 = a22f4d7f371df2fdd819561caf79db6b OK 2025/06/18 05:26:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dest" 2025/06/18 05:26:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dest.bin" 2025/06/18 05:26:11 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dest.bin: Auto set use_segments_container to true 2025/06/18 05:26:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dest" 2025/06/18 05:26:17 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dest: Auto set use_segments_container to true 2025/06/18 05:26:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/pre-dest1" 2025/06/18 05:26:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/pre-dest1.bin" 2025/06/18 05:26:22 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/pre-dest1.bin: Auto set use_segments_container to true 2025/06/18 05:26:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/pre-dest1" 2025/06/18 05:26:28 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/pre-dest1: Auto set use_segments_container to true 2025/06/18 05:26:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/pre-dest2" 2025/06/18 05:26:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/pre-dest2.bin" 2025/06/18 05:26:34 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/pre-dest2.bin: Auto set use_segments_container to true 2025/06/18 05:26:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/pre-dest2" 2025/06/18 05:26:39 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/pre-dest2: Auto set use_segments_container to true 2025/06/18 05:26:45 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:26:45 DEBUG : 1: Destination found in --compare-dest, skipping 2025/06/18 05:26:50 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:26:50 DEBUG : 2: Destination found in --compare-dest, skipping 2025/06/18 05:27:00 DEBUG : 3: Need to transfer - File not found at Destination 2025/06/18 05:27:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dest': Waiting for checks to finish 2025/06/18 05:27:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dest': Waiting for transfers to finish 2025/06/18 05:27:01 DEBUG : 3: md5 = 1a15bcbf1c94281e14a34db5a4ec7cb3 OK 2025/06/18 05:27:01 INFO : 3: Copied (new) 2025/06/18 05:27:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (72.13s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:27:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:27:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:27:12 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:27:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:27:18 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:27:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/CopyDest" 2025/06/18 05:27:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/CopyDest.bin" 2025/06/18 05:27:28 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/CopyDest.bin: Auto set use_segments_container to true 2025/06/18 05:27:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/CopyDest" 2025/06/18 05:27:34 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/CopyDest: Auto set use_segments_container to true 2025/06/18 05:27:45 DEBUG : one: Need to transfer - File not found at Destination 2025/06/18 05:27:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:27:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:27:50 DEBUG : one: md5 = 37223f1412c4390a12346b6555d3f3a5 OK 2025/06/18 05:27:50 INFO : one: Copied (new) 2025/06/18 05:27:50 DEBUG : Waiting for deletions to finish 2025/06/18 05:28:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:28:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:28:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:28:16 DEBUG : one: md5 = 39f273c4e5fd68f578d6c9d837fe8e86 OK 2025/06/18 05:28:16 INFO : one: Copied (replaced existing) 2025/06/18 05:28:16 DEBUG : Waiting for deletions to finish 2025/06/18 05:28:21 DEBUG : dst/one: md5 = c2c06ea40d3c4ba25e459b30c56083aa OK 2025/06/18 05:28:26 DEBUG : CopyDest/one: md5 = 46f6f1703626c23a29c32b97ec249de1 OK 2025/06/18 05:28:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/BackupDir" 2025/06/18 05:28:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/BackupDir.bin" 2025/06/18 05:28:32 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/BackupDir.bin: Auto set use_segments_container to true 2025/06/18 05:28:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/BackupDir" 2025/06/18 05:28:37 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/BackupDir: Auto set use_segments_container to true 2025/06/18 05:28:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:28:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:28:43 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:28:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/18 05:29:04 INFO : one: Copied (server-side copy) 2025/06/18 05:29:09 INFO : one: Deleted 2025/06/18 05:29:19 INFO : one: Copied (server-side copy) 2025/06/18 05:29:19 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/06/18 05:29:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:29:19 DEBUG : Waiting for deletions to finish 2025/06/18 05:29:25 DEBUG : CopyDest/two: md5 = 751ffa12a35808472c01d0c4f391188c OK 2025/06/18 05:29:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:26 DEBUG : one: Unchanged skipping 2025/06/18 05:29:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:36 INFO : two: Copied (server-side copy) 2025/06/18 05:29:36 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/06/18 05:29:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:29:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:29:36 DEBUG : Waiting for deletions to finish 2025/06/18 05:29:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:29:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:41 DEBUG : one: Unchanged skipping 2025/06/18 05:29:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:41 DEBUG : two: Unchanged skipping 2025/06/18 05:29:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:29:41 DEBUG : Waiting for deletions to finish 2025/06/18 05:29:41 INFO : There was nothing to transfer 2025/06/18 05:29:47 DEBUG : CopyDest/three: md5 = f2b8bc3cab157da5b91592d62571faf9 OK 2025/06/18 05:29:48 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/06/18 05:29:48 DEBUG : three: Destination not found in --copy-dest 2025/06/18 05:29:48 DEBUG : three: Need to transfer - File not found at Destination 2025/06/18 05:29:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:29:48 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:48 DEBUG : one: Unchanged skipping 2025/06/18 05:29:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:29:48 DEBUG : two: Unchanged skipping 2025/06/18 05:29:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:29:53 DEBUG : three: md5 = 8418732f86bcb693064946131835ffd1 OK 2025/06/18 05:29:53 INFO : three: Copied (new) 2025/06/18 05:29:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (192.76s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:30:30 DEBUG : dst/one: md5 = a79a16f023025116d9a596a23654848d OK 2025/06/18 05:30:35 DEBUG : dst/two: md5 = a379dfc4f4fc889ee0920cb6d1b2f775 OK 2025/06/18 05:30:35 DEBUG : dst/three.txt: md5 = 321f1e5f5b703db9c4a2c4954fb3b0bc OK 2025/06/18 05:30:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:30:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:30:41 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:30:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:30:46 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:30:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/backup" 2025/06/18 05:30:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup.bin" 2025/06/18 05:30:57 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup.bin: Auto set use_segments_container to true 2025/06/18 05:31:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup" 2025/06/18 05:31:03 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup: Auto set use_segments_container to true 2025/06/18 05:31:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:31:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:31:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:31:08 DEBUG : two: Unchanged skipping 2025/06/18 05:31:29 INFO : one: Copied (server-side copy) 2025/06/18 05:31:34 INFO : one: Deleted 2025/06/18 05:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:31:39 DEBUG : one: md5 = 2df644ae7db0eb7325e51835770948f0 OK 2025/06/18 05:31:39 INFO : one: Copied (new) 2025/06/18 05:31:39 DEBUG : Waiting for deletions to finish 2025/06/18 05:31:59 INFO : three.txt: Copied (server-side copy) 2025/06/18 05:31:59 INFO : three.txt: Deleted 2025/06/18 05:31:59 INFO : three.txt: Moved into backup dir 2025/06/18 05:32:00 DEBUG : dst/three.txt: md5 = 52aedc7ede91c08ecb72ce574d262f6f OK 2025/06/18 05:32:10 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:32:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:32:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:32:10 DEBUG : two: Unchanged skipping 2025/06/18 05:32:31 INFO : one: Copied (server-side copy) 2025/06/18 05:32:36 INFO : one: Deleted 2025/06/18 05:32:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:32:41 DEBUG : one: md5 = 87f5bc59f642a8b3f603e2a1c08bacc2 OK 2025/06/18 05:32:41 INFO : one: Copied (new) 2025/06/18 05:32:41 DEBUG : Waiting for deletions to finish 2025/06/18 05:32:56 INFO : three.txt: Copied (server-side copy) 2025/06/18 05:32:56 INFO : three.txt: Deleted 2025/06/18 05:32:56 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (188.04s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:33:38 DEBUG : dst/one: md5 = 96a413bd6110776e77f9801f5ec2c625 OK 2025/06/18 05:33:43 DEBUG : dst/two: md5 = 1ef77dbd6572aa3ab7ff0b3626ad7d98 OK 2025/06/18 05:33:43 DEBUG : dst/three.txt: md5 = cdf118123daf1391ad7c6b034966968e OK 2025/06/18 05:33:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:33:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:33:49 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:33:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:33:54 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:34:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/backup" 2025/06/18 05:34:04 DEBUG : Config file has changed externally - reloading 2025/06/18 05:34:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup.bin" 2025/06/18 05:34:05 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup.bin: Auto set use_segments_container to true 2025/06/18 05:34:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup" 2025/06/18 05:34:10 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup: Auto set use_segments_container to true 2025/06/18 05:34:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:34:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:34:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:34:16 DEBUG : two: Unchanged skipping 2025/06/18 05:34:36 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:34:42 INFO : one: Deleted 2025/06/18 05:34:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:34:47 DEBUG : one: md5 = 3ae5f485fc94634cd29d4ff5a822271c OK 2025/06/18 05:34:47 INFO : one: Copied (new) 2025/06/18 05:34:47 DEBUG : Waiting for deletions to finish 2025/06/18 05:35:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:35:08 INFO : three.txt: Deleted 2025/06/18 05:35:08 INFO : three.txt: Moved into backup dir 2025/06/18 05:36:09 DEBUG : dst/three.txt: md5 = b911caa716d89705dcfa872b9c09a80a OK 2025/06/18 05:36:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:36:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:36:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:36:24 DEBUG : two: Unchanged skipping 2025/06/18 05:36:45 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:36:50 INFO : one: Deleted 2025/06/18 05:36:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:36:55 DEBUG : one: md5 = ea639a4b4534584054195fd40bd8c0c4 OK 2025/06/18 05:36:55 INFO : one: Copied (new) 2025/06/18 05:36:55 DEBUG : Waiting for deletions to finish 2025/06/18 05:37:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:37:06 INFO : three.txt: Deleted 2025/06/18 05:37:06 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (249.13s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:37:47 DEBUG : dst/one: md5 = d00c729d74afce377259378b3c4e2e4a OK 2025/06/18 05:37:52 DEBUG : dst/two: md5 = b12e8654268500fde0da98d4bcf99693 OK 2025/06/18 05:37:52 DEBUG : dst/three.txt: md5 = daae3f0707d00d140e18c5ebac87a4bd OK 2025/06/18 05:37:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:37:52 DEBUG : Config file has changed externally - reloading 2025/06/18 05:37:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:37:53 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:37:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:37:58 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:38:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/backup" 2025/06/18 05:38:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup.bin" 2025/06/18 05:38:09 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup.bin: Auto set use_segments_container to true 2025/06/18 05:38:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/backup" 2025/06/18 05:38:15 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/backup: Auto set use_segments_container to true 2025/06/18 05:38:20 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:38:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:38:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:38:20 DEBUG : two: Unchanged skipping 2025/06/18 05:38:40 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/18 05:38:46 INFO : one: Deleted 2025/06/18 05:38:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:38:51 DEBUG : one: md5 = 09bf7ff8f982ac90f30e670f1bc8311f OK 2025/06/18 05:38:51 INFO : one: Copied (new) 2025/06/18 05:38:51 DEBUG : Waiting for deletions to finish 2025/06/18 05:39:02 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/18 05:39:02 INFO : three.txt: Deleted 2025/06/18 05:39:02 INFO : three.txt: Moved into backup dir 2025/06/18 05:39:17 DEBUG : dst/three.txt: md5 = 3c295ceeb32d7a8dfcbcb4c247c9fd56 OK 2025/06/18 05:39:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:39:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:39:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:39:23 DEBUG : two: Unchanged skipping 2025/06/18 05:39:38 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/18 05:39:43 INFO : one: Deleted 2025/06/18 05:39:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:39:48 DEBUG : one: md5 = 08bc9ae603e7a3c156b75999b9187701 OK 2025/06/18 05:39:48 INFO : one: Copied (new) 2025/06/18 05:39:48 DEBUG : Waiting for deletions to finish 2025/06/18 05:39:54 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/18 05:39:54 INFO : three.txt: Deleted 2025/06/18 05:39:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (163.24s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:40:30 DEBUG : dst/one: md5 = f2c8f944e18ac154bf07719eff272588 OK 2025/06/18 05:40:35 DEBUG : dst/two: md5 = 2ee97a53fcba7a36db3eabeb18306820 OK 2025/06/18 05:40:35 DEBUG : dst/three.txt: md5 = e817713e16dfe94a0db9aee8a27f01cb OK 2025/06/18 05:40:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:40:36 DEBUG : Config file has changed externally - reloading 2025/06/18 05:40:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:40:36 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:40:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:40:42 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:40:52 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:40:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:40:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:40:52 DEBUG : two: Unchanged skipping 2025/06/18 05:41:02 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:41:08 INFO : one: Deleted 2025/06/18 05:41:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:41:13 DEBUG : one: md5 = c45ac185579f0d6cf772b076b785ffce OK 2025/06/18 05:41:13 INFO : one: Copied (new) 2025/06/18 05:41:13 DEBUG : Waiting for deletions to finish 2025/06/18 05:41:24 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:41:24 INFO : three.txt: Deleted 2025/06/18 05:41:24 INFO : three.txt: Moved into backup dir 2025/06/18 05:41:24 DEBUG : dst/three.txt: md5 = 0d51e7dffc3282791df2729973c735e8 OK 2025/06/18 05:41:35 DEBUG : one.bak: Excluded (Path Filter) 2025/06/18 05:41:35 DEBUG : one.bak: Excluded 2025/06/18 05:41:35 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/18 05:41:35 DEBUG : three.txt.bak: Excluded 2025/06/18 05:41:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:41:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for checks to finish 2025/06/18 05:41:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:41:40 DEBUG : two: Unchanged skipping 2025/06/18 05:41:40 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:41:45 INFO : one: Deleted 2025/06/18 05:41:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu/dst': Waiting for transfers to finish 2025/06/18 05:41:50 DEBUG : one: md5 = ebfd6758041c07c2061515958ca3a19a OK 2025/06/18 05:41:50 INFO : one: Copied (new) 2025/06/18 05:41:50 DEBUG : Waiting for deletions to finish 2025/06/18 05:41:56 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:41:56 INFO : three.txt: Deleted 2025/06/18 05:41:56 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (112.28s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:42:22 DEBUG : dst/one: md5 = e3f1e8846f7e2c6811abfb8fc2c46860 OK 2025/06/18 05:42:27 DEBUG : dst/two: md5 = be2544f97c61707dc4425b9620def781 OK 2025/06/18 05:42:28 DEBUG : dst/three.txt: md5 = a50b77cf5c2e6ab0f8f21db437091aa5 OK 2025/06/18 05:42:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:42:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:42:33 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:42:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:42:39 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:42:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:42:59 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:43:05 INFO : one: Deleted 2025/06/18 05:43:10 DEBUG : one: md5 = bc9d349f06386c2a824c92261c07324a OK 2025/06/18 05:43:10 INFO : one: Copied (new) 2025/06/18 05:43:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:43:10 DEBUG : two: Unchanged skipping 2025/06/18 05:43:10 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/18 05:43:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:43:20 INFO : three.txt: Deleted 2025/06/18 05:43:20 DEBUG : three.txt: md5 = 01b12941d4d0b8185f62af55bbf18d45 OK 2025/06/18 05:43:20 INFO : three.txt: Copied (new) 2025/06/18 05:43:36 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:43:41 INFO : one: Copied (server-side copy) to: one.bak 2025/06/18 05:43:47 INFO : one: Deleted 2025/06/18 05:43:52 DEBUG : one: md5 = 40373f7b3556a9cc93abb04324ca459d OK 2025/06/18 05:43:52 INFO : one: Copied (new) 2025/06/18 05:43:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:43:52 DEBUG : two: Unchanged skipping 2025/06/18 05:43:52 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/18 05:43:57 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/18 05:43:57 INFO : three.txt: Deleted 2025/06/18 05:43:57 DEBUG : three.txt: md5 = 517798fd8f01f7c200dbd135edf1be12 OK 2025/06/18 05:43:57 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (121.66s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:44:24 DEBUG : dst/one: md5 = bc735e71a5fde28e88de8f775e6f899b OK 2025/06/18 05:44:29 DEBUG : dst/two: md5 = 061291b0238278f56e3b72d5ae054529 OK 2025/06/18 05:44:29 DEBUG : dst/three.txt: md5 = 056546653453c1d7f430d3d92b9a7692 OK 2025/06/18 05:44:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-poruneh4pisu/dst" 2025/06/18 05:44:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst.bin" 2025/06/18 05:44:30 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst.bin: Auto set use_segments_container to true 2025/06/18 05:44:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-poruneh4pisu/dst" 2025/06/18 05:44:36 DEBUG : Swift container crypt path rclone-test-poruneh4pisu/dst: Auto set use_segments_container to true 2025/06/18 05:44:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/18 05:45:02 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/18 05:45:07 INFO : one: Deleted 2025/06/18 05:45:12 DEBUG : one: md5 = a30345e2b3f82df42b3ad3829234d3e5 OK 2025/06/18 05:45:12 INFO : one: Copied (new) 2025/06/18 05:45:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:45:12 DEBUG : two: Unchanged skipping 2025/06/18 05:45:12 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/18 05:45:28 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/18 05:45:28 INFO : three.txt: Deleted 2025/06/18 05:45:28 DEBUG : three.txt: md5 = fb403e85779d809fbd4da30a5aec106d OK 2025/06/18 05:45:28 INFO : three.txt: Copied (new) 2025/06/18 05:45:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/18 05:45:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/18 05:45:59 INFO : one: Deleted 2025/06/18 05:46:04 DEBUG : one: md5 = e62e3a527a106230cd671f2a92299dfd OK 2025/06/18 05:46:04 INFO : one: Copied (new) 2025/06/18 05:46:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:46:05 DEBUG : two: Unchanged skipping 2025/06/18 05:46:05 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/18 05:46:20 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/18 05:46:20 INFO : three.txt: Deleted 2025/06/18 05:46:20 DEBUG : three.txt: md5 = f5c398454247cc3fcb3f8bede906366c OK 2025/06/18 05:46:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (157.73s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:47:02 DEBUG : Testêé: md5 = 3fa542fec86fe47abe7f096c8b9344d3 OK 2025/06/18 05:47:07 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/06/18 05:47:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:47:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:47:12 DEBUG : Testêé: md5 = 11c1bfe0d8a5d6a371fa55ea703cd89d OK 2025/06/18 05:47:12 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/06/18 05:47:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (26.47s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:47:23 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/18 05:47:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:47:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:47:47 DEBUG : existing: md5 = 0f5fdb007aca8e96bec89e8609b47e05 OK 2025/06/18 05:47:47 INFO : existing: Copied (new) 2025/06/18 05:47:47 DEBUG : Waiting for deletions to finish 2025/06/18 05:47:47 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/06/18 05:47:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:48:07 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/06/18 05:48:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:48:07 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': not deleting files as there were IO errors 2025/06/18 05:48:07 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (84.72s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:48:48 DEBUG : EXISTING: md5 = 64295674009dfff25eeefde0dd06c9c3 OK 2025/06/18 05:48:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:48:49 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:48:49 DEBUG : existing: Unchanged skipping 2025/06/18 05:48:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:48:49 DEBUG : Waiting for deletions to finish 2025/06/18 05:48:49 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.22s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:2636: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.32s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", 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-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", 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-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" sync_test.go:2675: This test only runs on local --- PASS: TestMaxTransfer (0.29s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:48:50 DEBUG : both0: md5 = 22aea50874c0e9655dd793ee6a6e2a89 OK 2025/06/18 05:48:50 DEBUG : only0: md5 = 578fabe2166d64a87d3b55c34830957e OK 2025/06/18 05:48:51 DEBUG : both1: md5 = 282695089d30c2da1c113cfeea38a6ca OK 2025/06/18 05:48:56 DEBUG : only1: md5 = 006e1d1a542e0cc081f0a4d93ee78bf7 OK 2025/06/18 05:49:19 DEBUG : both2: md5 = 787ca78b668d6e13760301637c1db579 OK 2025/06/18 05:49:20 DEBUG : only2: md5 = 6e2ee31f643aebcf2a9509bdfcffcf05 OK 2025/06/18 05:49:40 DEBUG : both3: md5 = 64d167f3c7ed653631587274124c381b OK 2025/06/18 05:49:41 DEBUG : only3: md5 = 8da465299e86fe6e67b8179909977391 OK 2025/06/18 05:49:41 DEBUG : both4: md5 = 2bb1d741108d8747b858bb4fb017395e OK 2025/06/18 05:50:01 DEBUG : only4: md5 = 034450987297b047b440fd7547d5b567 OK 2025/06/18 05:50:22 DEBUG : both5: md5 = 76c0204826708fc7b344931da7f6ca3b OK 2025/06/18 05:50:42 DEBUG : only5: md5 = d278d71f025b00fa354ecaf4439ceba6 OK 2025/06/18 05:50:47 DEBUG : both6: md5 = 1393896eb26083d6904dedafd969abfc OK 2025/06/18 05:50:52 DEBUG : only6: md5 = 8cf0bf198f4e0219935e13739f4637a4 OK 2025/06/18 05:50:53 DEBUG : both7: md5 = 7ee7de6ecb6a162e40177271e35f6207 OK 2025/06/18 05:50:53 DEBUG : only7: md5 = c7920912b2fbf6d2c8f76d683e917e41 OK 2025/06/18 05:50:59 DEBUG : both8: md5 = d0111b01b5925eb012f99bfde0a8f140 OK 2025/06/18 05:50:59 DEBUG : only8: md5 = 0857fb6ec611c8f87f2cb56654511eb3 OK 2025/06/18 05:51:05 DEBUG : both9: md5 = 1b264c1bd6cc59d5f0e69fe019facd11 OK 2025/06/18 05:51:10 DEBUG : only9: md5 = 3480bdd9649da78067348eafca03db80 OK 2025/06/18 05:51:16 DEBUG : both10: md5 = 5519628b52481e296eed2c73ee91e173 OK 2025/06/18 05:51:21 DEBUG : only10: md5 = b4c1a48b74434d9bc4f91d95c8cbb4a4 OK 2025/06/18 05:51:26 DEBUG : both11: md5 = 261173aebe6b2b55364425d708a38dba OK 2025/06/18 05:51:27 DEBUG : only11: md5 = 5aef325ed95373d5cd13c8ff3ce3eca2 OK 2025/06/18 05:51:27 DEBUG : both12: md5 = d67f36794a6581992c326cf8b7e4fc79 OK 2025/06/18 05:51:27 DEBUG : only12: md5 = 37dc5b7fde5355c2d8697d36a6c62613 OK 2025/06/18 05:51:33 DEBUG : both13: md5 = 2157a5679ce273ada1c8ef7f42dd66d5 OK 2025/06/18 05:51:38 DEBUG : only13: md5 = 1315a2d2409b01ba773ccb466b431359 OK 2025/06/18 05:51:39 DEBUG : both14: md5 = a9083688074ab1958c19f33c4fc03d14 OK 2025/06/18 05:51:44 DEBUG : only14: md5 = 8c061740874b6ec9a05ca55a6013fc31 OK 2025/06/18 05:51:49 DEBUG : both15: md5 = d3250253c3ebead1d86437b5b3d97699 OK 2025/06/18 05:51:49 DEBUG : only15: md5 = 7ce3526fda21f6b010f5602a0e639059 OK 2025/06/18 05:51:55 DEBUG : both16: md5 = 08ac40d694863e4171bf10f7d5b5f552 OK 2025/06/18 05:51:55 DEBUG : only16: md5 = 9fad46662b9233bab34433d513e7ea33 OK 2025/06/18 05:51:56 DEBUG : both17: md5 = ed466f67fc60d7b63d6e1074c8525436 OK 2025/06/18 05:51:56 DEBUG : only17: md5 = e1db88ec8ea00eab727635b88742b5c5 OK 2025/06/18 05:51:56 DEBUG : both18: md5 = 0ad6ae711efaa54ca221423eb7059016 OK 2025/06/18 05:51:57 DEBUG : only18: md5 = e03cf0b56d631556b55960b6ba068465 OK 2025/06/18 05:51:58 DEBUG : both19: md5 = 54ab5c4994be5ee1aa758110208c9619 OK 2025/06/18 05:51:58 DEBUG : only19: md5 = db13cbaaab36ec11cd7f60d61d86083e OK 2025/06/18 05:53:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:53:59 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both0: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both11: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both10: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both12: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both13: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both14: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both16: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both17: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both18: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both19: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both1: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both2: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both3: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both4: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both5: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both6: Unchanged skipping 2025/06/18 05:53:59 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:53:59 DEBUG : both7: Unchanged skipping 2025/06/18 05:54:00 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:54:00 DEBUG : both9: Unchanged skipping 2025/06/18 05:54:04 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:54:04 DEBUG : both15: Unchanged skipping 2025/06/18 05:54:04 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:54:04 DEBUG : both8: Unchanged skipping 2025/06/18 05:54:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:54:04 DEBUG : Waiting for deletions to finish 2025/06/18 05:54:05 INFO : only15: Deleted 2025/06/18 05:54:05 INFO : only2: Deleted 2025/06/18 05:54:05 INFO : only0: Deleted 2025/06/18 05:54:25 INFO : only1: Deleted 2025/06/18 05:54:25 INFO : only12: Deleted 2025/06/18 05:54:25 INFO : only4: Deleted 2025/06/18 05:54:25 INFO : only16: Deleted 2025/06/18 05:54:25 INFO : only10: Deleted 2025/06/18 05:54:25 INFO : only18: Deleted 2025/06/18 05:54:25 INFO : only3: Deleted 2025/06/18 05:54:26 INFO : only11: Deleted 2025/06/18 05:54:26 INFO : only17: Deleted 2025/06/18 05:54:26 INFO : only8: Deleted 2025/06/18 05:54:30 INFO : only6: Deleted 2025/06/18 05:54:30 INFO : only19: Deleted 2025/06/18 05:54:30 INFO : only14: Deleted 2025/06/18 05:54:30 INFO : only7: Deleted 2025/06/18 05:54:30 INFO : only5: Deleted 2025/06/18 05:54:35 INFO : only13: Deleted 2025/06/18 05:54:36 INFO : only9: Deleted 2025/06/18 05:54:36 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (515.15s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 05:57:25 DEBUG : both0: md5 = 111cb243efa109ec0ddd6ee0157d91b0 OK 2025/06/18 05:57:25 DEBUG : only0: md5 = 1d4109ebb1b98ffd28940ea4dc15c0a6 OK 2025/06/18 05:57:25 DEBUG : both1: md5 = 2846df33aac5db07dc8d56242d5a8c1c OK 2025/06/18 05:57:45 DEBUG : only1: md5 = ff9e9f151ae7af8757841a79b3047a83 OK 2025/06/18 05:57:51 DEBUG : both2: md5 = 6b1a7da0132f1d7e942e3c9f56a638f3 OK 2025/06/18 05:57:51 DEBUG : only2: md5 = 2592dabd6db49af26dc10cc7ea7205ed OK 2025/06/18 05:57:56 DEBUG : both3: md5 = 31ac2267823384cd0b68ee58d91b3bee OK 2025/06/18 05:57:56 DEBUG : only3: md5 = 71059f9e69bb3bf0764b8d6297685194 OK 2025/06/18 05:57:56 DEBUG : both4: md5 = 632db661e0a59c8620a24873ac3b9b3f OK 2025/06/18 05:58:01 DEBUG : only4: md5 = 647ec9e2448cdf1fd19d9249926d840e OK 2025/06/18 05:58:07 DEBUG : both5: md5 = 47b459e9ad07c789fadac47d1063edad OK 2025/06/18 05:58:12 DEBUG : only5: md5 = 87bcd62473f6866680e9d543996f97db OK 2025/06/18 05:58:17 DEBUG : both6: md5 = 0154a00ba8f4a832fabc219627c1d7cd OK 2025/06/18 05:58:22 DEBUG : only6: md5 = 3203a9228f4a366046661114bb3365f1 OK 2025/06/18 05:58:22 DEBUG : both7: md5 = 75bb6a7426304571ec0468835ad9bd24 OK 2025/06/18 05:58:22 DEBUG : only7: md5 = 980a716a17e57aa04126aef8743435ad OK 2025/06/18 05:58:27 DEBUG : both8: md5 = c2d4ef9de301f7130bc5fbbd1f8ed5e6 OK 2025/06/18 05:58:28 DEBUG : only8: md5 = 7aad96b1f6ef1971141c5426bf3e8940 OK 2025/06/18 05:58:33 DEBUG : both9: md5 = 09f33752c498f7dc12ff522551181587 OK 2025/06/18 05:58:38 DEBUG : only9: md5 = 824974f66544c8d7e1f88ccbff2d7f24 OK 2025/06/18 05:58:43 DEBUG : both10: md5 = e33fc767f4e1b2c19c59c0a974be8d93 OK 2025/06/18 05:58:49 DEBUG : only10: md5 = edf8f71501fdee892544416380bc8881 OK 2025/06/18 05:58:54 DEBUG : both11: md5 = 1fb379f06cb5679fa3773ac23f958ff3 OK 2025/06/18 05:58:54 DEBUG : only11: md5 = 5f887e46a1318c73ed02d149418b3b0d OK 2025/06/18 05:58:54 DEBUG : both12: md5 = 3c9831d55c18f3d938c79e17f2998315 OK 2025/06/18 05:58:54 DEBUG : only12: md5 = 8b45ba3690edf3cde1c74b37f893d702 OK 2025/06/18 05:58:59 DEBUG : both13: md5 = 43b9588f7e036a495844905c217866f3 OK 2025/06/18 05:59:04 DEBUG : only13: md5 = ab23a572dec8f28de17cc99e1fb5c189 OK 2025/06/18 05:59:05 DEBUG : both14: md5 = 888bf4f9e7bafda9e1bac912082e23c7 OK 2025/06/18 05:59:10 DEBUG : only14: md5 = cf9294b60ca09078a6722bc6364a9ca7 OK 2025/06/18 05:59:15 DEBUG : both15: md5 = 64da6fe303163c775487d39772e098ce OK 2025/06/18 05:59:15 DEBUG : only15: md5 = 755d94652e8ac88a4fe9504c97f3e27e OK 2025/06/18 05:59:20 DEBUG : both16: md5 = 1b4aaccd366202852636a8505cd375d3 OK 2025/06/18 05:59:21 DEBUG : only16: md5 = d73ad547f5e7784c68b767c1563eac23 OK 2025/06/18 05:59:21 DEBUG : both17: md5 = de2b3d169a943a467db794fd70aa765f OK 2025/06/18 05:59:21 DEBUG : only17: md5 = b77ed02b55d51b4a3c9da04a64167816 OK 2025/06/18 05:59:21 DEBUG : both18: md5 = 70fe63117c5b1bf7df8c0f562af41266 OK 2025/06/18 05:59:22 DEBUG : only18: md5 = ca1f7162382ae290a467fbd5745bd913 OK 2025/06/18 05:59:22 DEBUG : both19: md5 = 4eda8729fbc077fb65f212a6e502d405 OK 2025/06/18 05:59:22 DEBUG : only19: md5 = 2ba807f2255a5f3d93a4037fd9018f43 OK 2025/06/18 05:59:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 05:59:44 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both0: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both11: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both12: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both13: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both14: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both16: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both17: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both18: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both19: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both1: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both2: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both4: Unchanged skipping 2025/06/18 05:59:44 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:44 DEBUG : both5: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both10: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both7: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both8: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both15: Unchanged skipping 2025/06/18 05:59:49 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/06/18 05:59:49 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both9: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both3: Unchanged skipping 2025/06/18 05:59:49 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 05:59:49 DEBUG : both6: Unchanged skipping 2025/06/18 05:59:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 05:59:49 DEBUG : only0: md5 = 4934763a37b56a4e2dba1fe8024cbab4 OK 2025/06/18 05:59:49 INFO : only0: Copied (replaced existing) 2025/06/18 05:59:50 DEBUG : only11: md5 = 9260907ddef00fc16f842cb362bfcd1b OK 2025/06/18 05:59:50 INFO : only11: Copied (replaced existing) 2025/06/18 05:59:50 DEBUG : only12: md5 = 0d7070d8829d53a1304040c452c50130 OK 2025/06/18 05:59:50 INFO : only12: Copied (replaced existing) 2025/06/18 05:59:50 DEBUG : only15: md5 = 4f9dc678b6348cb35e653a0ccb6b9e6a OK 2025/06/18 05:59:50 INFO : only15: Copied (replaced existing) 2025/06/18 05:59:50 DEBUG : only16: md5 = 6c4ee0ec6d414339029ad30c49277115 OK 2025/06/18 05:59:50 INFO : only16: Copied (replaced existing) 2025/06/18 05:59:51 DEBUG : only17: md5 = c586554c4d103aaf3a74b04dbe9d15e2 OK 2025/06/18 05:59:51 INFO : only17: Copied (replaced existing) 2025/06/18 05:59:51 DEBUG : only18: md5 = 2e3f67a732506375d4f4f44a366c4727 OK 2025/06/18 05:59:51 INFO : only18: Copied (replaced existing) 2025/06/18 05:59:51 DEBUG : only19: md5 = ae2dca24e995a86265889ece8273ad46 OK 2025/06/18 05:59:51 INFO : only19: Copied (replaced existing) 2025/06/18 05:59:55 DEBUG : only10: md5 = e71cd64f8b8ecd536779815058d83801 OK 2025/06/18 05:59:55 INFO : only10: Copied (replaced existing) 2025/06/18 05:59:55 DEBUG : only13: md5 = f555570c894d74ac9dacf7af1e1d0f48 OK 2025/06/18 05:59:55 INFO : only13: Copied (replaced existing) 2025/06/18 05:59:55 DEBUG : only2: md5 = 9b20475510cfbfaca1cac80e790a56f7 OK 2025/06/18 05:59:55 INFO : only2: Copied (replaced existing) 2025/06/18 05:59:55 DEBUG : only14: md5 = 2b2ebd16cce257308f0eaab2548cf325 OK 2025/06/18 05:59:55 INFO : only14: Copied (replaced existing) 2025/06/18 05:59:55 DEBUG : only3: md5 = fc4f685f5b10e7f09f33a60c08b8086b OK 2025/06/18 05:59:55 INFO : only3: Copied (replaced existing) 2025/06/18 06:00:01 DEBUG : only1: md5 = b950a660dd2f2bf9542fefbb039413e0 OK 2025/06/18 06:00:01 INFO : only1: Copied (replaced existing) 2025/06/18 06:00:01 DEBUG : only7: md5 = ea161698afc085b7fa367d2dc3617435 OK 2025/06/18 06:00:01 INFO : only7: Copied (replaced existing) 2025/06/18 06:00:02 DEBUG : only8: md5 = 6e8ae7085859c896c36e2fe701d20653 OK 2025/06/18 06:00:02 INFO : only8: Copied (replaced existing) 2025/06/18 06:00:05 DEBUG : only4: md5 = c972bcaa64d800eded96a09cb6d8b847 OK 2025/06/18 06:00:05 INFO : only4: Copied (replaced existing) 2025/06/18 06:00:05 DEBUG : only5: md5 = ab1c1c335e63a88a39cd186a38b09a34 OK 2025/06/18 06:00:05 INFO : only5: Copied (replaced existing) 2025/06/18 06:00:05 DEBUG : only6: md5 = e4963157e6610618e9523601b6950bfe OK 2025/06/18 06:00:05 INFO : only6: Copied (replaced existing) 2025/06/18 06:00:12 DEBUG : only9: md5 = f66c27787c00368bbb169e180ebd34a2 OK 2025/06/18 06:00:12 INFO : only9: Copied (replaced existing) 2025/06/18 06:00:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (315.32s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 06:02:40 DEBUG : empty_dir: Making directory with metadata 2025/06/18 06:02:40 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 06:02:40 DEBUG : empty_on_remote: Making directory with metadata 2025/06/18 06:02:40 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 06:02:40 INFO : empty_on_remote: Making directory 2025/06/18 06:02:40 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/18 06:02:46 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/18 06:02:46 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/18 06:02:46 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/18 06:02:46 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (16.83s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 06:02:57 DEBUG : empty_dir: Making directory with metadata 2025/06/18 06:02:57 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 06:02:57 DEBUG : empty_on_remote: Making directory with metadata 2025/06/18 06:02:57 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/18 06:02:57 INFO : empty_on_remote: Making directory 2025/06/18 06:02:57 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/18 06:03:02 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:02 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:02 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:02 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:02 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (11.85s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 06:03:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:09 INFO : sub dir: Making directory 2025/06/18 06:03:09 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (2.36s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 06:03:11 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/18 06:03:11 INFO : sub dir: Making directory 2025/06/18 06:03:11 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithoutEmptyDirs (2.03s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu'", Local "Local file system at /tmp/rclone2612227628", Modify Window "1ns" 2025/06/18 06:03:18 DEBUG : dir1/0000-abcdefg.txt: md5 = a3f446e4bbf1fd0330d8d155a6ca8949 OK 2025/06/18 06:03:24 DEBUG : dir1/0001-bcdefgh.txt: md5 = 465f0067720446972f0388f1e0db3e1a OK 2025/06/18 06:03:30 DEBUG : dir1/0002-cdefghi.txt: md5 = 12b2a1a85455ad34e64470377d7c87fd OK 2025/06/18 06:03:35 DEBUG : dir1/0003-defghij.txt: md5 = dcdcecce1637fd61006dad07b657e1c4 OK 2025/06/18 06:03:40 DEBUG : dir1/0004-efghijk.txt: md5 = 4a315ef35dfed7e3c715de2fe09adfb6 OK 2025/06/18 06:03:46 DEBUG : dir1/0005-fghijkl.txt: md5 = e47b95026bf34f557c97ea387978ef8c OK 2025/06/18 06:03:46 DEBUG : dir1/0006-ghijklm.txt: md5 = 63bfdd6e50f5daeb03032e6ac9e0b7e4 OK 2025/06/18 06:03:51 DEBUG : dir1/0007-1234567.txt: md5 = fa72e057c28825660b95c4734d4c1f84 OK 2025/06/18 06:03:57 DEBUG : dir1/0008-2345678.txt: md5 = 78c57a7b257f5cf847ec1093e1f6985c OK 2025/06/18 06:04:02 DEBUG : dir1/0009-3456789.txt: md5 = 94c1a5bc73adf50913b8c219ef318d4f OK 2025/06/18 06:04:02 DEBUG : dir1/0010-456789.txt: md5 = 457fd04f8cd4dd326ec58f3056ea29d1 OK 2025/06/18 06:04:08 DEBUG : dir1/0011-56789;.txt: md5 = 81de66e16676a8d279e6ee0cb8551e0c OK 2025/06/18 06:04:13 DEBUG : dir1/0012-6789;.txt: md5 = e161a25fc9e454ed00a21d28cf69dd06 OK 2025/06/18 06:04:18 DEBUG : dir1/0013-789;=.txt: md5 = bf5c1d2b9a9e00145798310bacf67ad6 OK 2025/06/18 06:04:19 DEBUG : dir1/0014-89;=.txt: md5 = 28eb7ad9663e268bef187f3fef967134 OK 2025/06/18 06:04:24 DEBUG : dir1/0015-9;=.txt: md5 = de0498132f1c93d837c4459327e8f178 OK 2025/06/18 06:04:24 DEBUG : apple: md5 = 6aaab191a6ba67395449fe5fa50d3670 OK 2025/06/18 06:04:25 DEBUG : banana: md5 = 017e6c1baccfd6fa756ecfa5be1abe71 OK 2025/06/18 06:04:30 DEBUG : appleappleapplebanana: md5 = 7dc32bf2d01ffccf54800c063e98b632 OK 2025/06/18 06:04:36 DEBUG : splitbananasplit: md5 = 2ce1eb0cc78852c8524ba2b477c7d5ff OK 2025/06/18 06:04:36 DEBUG : Waiting for deletions to finish 2025/06/18 06:04:36 DEBUG : apple: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : banana: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/18 06:04:36 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/18 06:04:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 06:04:57 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : apple: Unchanged skipping 2025/06/18 06:04:57 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/18 06:04:57 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : banana: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/18 06:04:57 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:04:57 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/18 06:05:02 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:02 DEBUG : splitbananasplit: Unchanged skipping 2025/06/18 06:05:02 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:02 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/18 06:05:02 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:02 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/18 06:05:02 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:02 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/18 06:05:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 06:05:02 DEBUG : Waiting for deletions to finish 2025/06/18 06:05:02 INFO : There was nothing to transfer 2025/06/18 06:05:02 DEBUG : Waiting for deletions to finish 2025/06/18 06:05:02 DEBUG : apple: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : banana: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/18 06:05:02 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/18 06:05:03 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/18 06:05:03 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : splitbananasplit: Unchanged skipping 2025/06/18 06:05:03 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : banana: Unchanged skipping 2025/06/18 06:05:03 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : apple: Unchanged skipping 2025/06/18 06:05:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for checks to finish 2025/06/18 06:05:03 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/18 06:05:03 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:03 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/18 06:05:08 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:08 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/18 06:05:08 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:08 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/18 06:05:08 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/18 06:05:08 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/18 06:05:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-poruneh4pisu': Waiting for transfers to finish 2025/06/18 06:05:08 DEBUG : Waiting for deletions to finish 2025/06/18 06:05:08 INFO : There was nothing to transfer 2025/06/18 06:05:08 DEBUG : Waiting for deletions to finish 2025/06/18 06:05:08 DEBUG : apple: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : banana: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/18 06:05:08 DEBUG : splitbananasplit: Excluded (Path Filter) panic: test timed out after 1h0m0s running tests: TestTransform (2m38s) TestTransform/NFC (2m38s) goroutine 14711 [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(0xc00195cfc0, {0x26944db?, 0xc00045bad8?}, 0x27c0958) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc00195cfc0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc00195cfc0, 0xc00045bc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc001a541f8, {0x3e7c5e0, 0x6b, 0x6b}, {0xedfe44030?, 0x3e925a0?, 0x3e925a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc000714500) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc000714500) /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 34 [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 35 [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 14422 [select]: net/http.(*persistConn).writeLoop(0xc0004d6c60) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14300 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14515 [IO wait]: internal/poll.runtime_pollWait(0x75831d82d8b8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035a980?, 0xc0004f1980?, 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(0xc00035a980, {0xc0004f1980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035a980, {0xc0004f1980?, 0x1973?, 0xc0002fa440?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001bf0258, {0xc0004f1980?, 0xc0004c0c50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019cd020, {0xc0004f1980?, 0x7582d44de3e8?, 0x75831d9c0a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000607548, {0xc0004f1980?, 0x1973?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004c0d38, {0x2ace240, 0xc000607548}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0004c0a88, {0x7582d440f100, 0xc0019cd020}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0004c0a88, 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(0xc0004c0a88, {0xc0007a2000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004d7680, {0xc0007a2000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000a80180) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000a80180, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004d7680) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14486 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14020 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc001ace000, 0xc000510620, 0xc0003a1180) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc001ace000, {0x2af3828, 0x3eb5b60}, {0xc0019db810, 0x4f}, {{0xc0009a5100, 0x5}, {0xc0009a5106, 0x32}, {0x2680b0a, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc001ace000, {0x2af3828, 0x3eb5b60}, {{0xc0009a5100, 0x5}, {0xc0009a5106, 0x32}, {0x2680b0a, 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, 0xc000663008?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001a961e0, 0xc0019d1b30, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001a961e0, 0xc0019d1b30) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x93 github.com/rclone/rclone/backend/swift.(*Object).Remove(0xc000a73560, {0x2af3828, 0x3eb5b60}) /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(0xc001ae6380, {0xc0019f8f30, 0x25}, 0xc0008cf920) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc000766400?, 0xc000766400?, 0x1e022f4?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc000766400, 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({0xc000766400?, 0xc001a94ea0?, 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(0xc0008cfb88) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/swift.(*Fs).ListR(0xc001a9b180, {0x2af3828, 0x3eb5b60}, {0x0?, 0x2415cc0?}, 0xc000434a80) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:881 +0x185 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc001a1eea0, {0x2af3828, 0x3eb5b60}, {0x0, 0x0}, 0xc00054af50) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2af3828, 0x3eb5b60}, {0x2b07fa0, 0xc001a1eea0}, {0x0, 0x0}, 0x1, 0x3, 0xc000434930, 0xc0019cdda0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2af3828, 0x3eb5b60}, {0x2b07fa0, 0xc001a1eea0}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000434930) /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(0xc00049c460) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c github.com/rclone/rclone/fs/sync.TestTransform.func1(0xc001ae6380) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:124 +0x3a5 testing.tRunner(0xc001ae6380, 0xc00054aa50) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 14019 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 14394 [IO wait]: internal/poll.runtime_pollWait(0x75831d82e6f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035a380?, 0xc000903980?, 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(0xc00035a380, {0xc000903980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035a380, {0xc000903980?, 0x1973?, 0xc0005b7340?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001a981a0, {0xc000903980?, 0xc0006641d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000606270, {0xc000903980?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669c50, {0xc000903980?, 0x1973?, 0xc0007b39c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006642b8, {0x2ace240, 0xc000669c50}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000664008, {0x7582d440f100, 0xc000606270}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000664008, 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(0xc000664008, {0xc00046b000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00051c000, {0xc00046b000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000861a40) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000861a40, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00051c000) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14367 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14395 [select]: net/http.(*persistConn).writeLoop(0xc00051c000) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14367 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14019 [chan receive, 2 minutes]: testing.(*T).Run(0xc001ae6000, {0x2678b32?, 0x41bb05?}, 0xc00054aa50) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/fs/sync.TestTransform(0xc001ae6000) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:95 +0x62a testing.tRunner(0xc001ae6000, 0x27c0958) /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 14305 [select]: net/http.(*persistConn).writeLoop(0xc00055b560) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14379 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14303 [select]: net/http.(*persistConn).writeLoop(0xc00072c120) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14334 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14507 [IO wait]: internal/poll.runtime_pollWait(0x7582d44f7440, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0000d3000?, 0xc000736000?, 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(0xc0000d3000, {0xc000736000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0000d3000, {0xc000736000?, 0x1973?, 0xc001c5a300?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001bf01b8, {0xc000736000?, 0xc0001f5a50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019cc9d8, {0xc000736000?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669c08, {0xc000736000?, 0x1973?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001f5b38, {0x2ace240, 0xc000669c08}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001f5888, {0x7582d440f100, 0xc0019cc9d8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0001f5888, 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(0xc0001f5888, {0xc000a1e000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004d7320, {0xc000a1e000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc002362de0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc002362de0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004d7320) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14446 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14304 [IO wait]: internal/poll.runtime_pollWait(0x75831d82e178, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001ec2500?, 0xc000905300?, 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(0xc001ec2500, {0xc000905300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001ec2500, {0xc000905300?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001bf0100, {0xc000905300?, 0x0?, 0x3eb1e60?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0019cc6f0, {0xc000905300?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669bf0, {0xc000905300?, 0x3eb83e0?, 0xc0004789c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000371438, {0x2ace240, 0xc000669bf0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000371188, {0x7582d440f100, 0xc0019cc6f0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000371188, 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(0xc000371188, {0xc000988000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00055b560, {0xc000988000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0002f1c80) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0002f1c80, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00055b560) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14379 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14497 [IO wait]: internal/poll.runtime_pollWait(0x75831d82d9d0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0000d2f00?, 0xc0004f0000?, 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(0xc0000d2f00, {0xc0004f0000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0000d2f00, {0xc0004f0000?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014c148, {0xc0004f0000?, 0x0?, 0x3eb1e60?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc00205e078, {0xc0004f0000?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669c20, {0xc0004f0000?, 0x3eb83e0?, 0xc000ad59c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000a069b8, {0x2ace240, 0xc000669c20}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000a06708, {0x7582d440f100, 0xc00205e078}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000a06708, 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(0xc000a06708, {0xc0007a0000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004d6fc0, {0xc0007a0000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001d59f20) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001d59f20, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004d6fc0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14438 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 2065 [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 2049 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 14468 [IO wait]: internal/poll.runtime_pollWait(0x75831d82de30, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001ec2680?, 0xc00098d980?, 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(0xc001ec2680, {0xc00098d980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001ec2680, {0xc00098d980?, 0x1973?, 0xc0002fb520?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014c6f0, {0xc00098d980?, 0xc0001f5350?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc00205f1d0, {0xc00098d980?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669f50, {0xc00098d980?, 0x1973?, 0xc0008ba9d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001f5438, {0x2ace240, 0xc000669f50}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001f5188, {0x7582d440f100, 0xc00205f1d0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0001f5188, 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(0xc0001f5188, {0xc000937000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00055bb00, {0xc000937000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc002362480) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc002362480, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00055bb00) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14405 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14469 [select]: net/http.(*persistConn).writeLoop(0xc00055bb00) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14405 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14514 [select]: net/http.(*persistConn).writeLoop(0xc0004d6fc0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14438 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14421 [IO wait]: internal/poll.runtime_pollWait(0x75831d82dd18, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001bb1000?, 0xc00098f300?, 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(0xc001bb1000, {0xc00098f300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001bb1000, {0xc00098f300?, 0x1973?, 0xc0002fba60?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a0c0, {0xc00098f300?, 0xc0004c08d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0005a0ba0, {0xc00098f300?, 0x7582d44de3e8?, 0x75831d9c0a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000607560, {0xc00098f300?, 0x1973?, 0xc0008b99d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004c09b8, {0x2ace240, 0xc000607560}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0004c0708, {0x7582d440f100, 0xc0005a0ba0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0004c0708, 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(0xc0004c0708, {0xc000a09000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0004d6c60, {0xc000a09000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a965a0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a965a0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0004d6c60) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14300 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14508 [select]: net/http.(*persistConn).writeLoop(0xc0004d7320) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14446 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14516 [select]: net/http.(*persistConn).writeLoop(0xc0004d7680) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14486 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 13873 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001ace1e0, {0x2af3908, 0xc0001b9ea0}) /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 13724 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 14302 [IO wait]: internal/poll.runtime_pollWait(0x75831d82e3a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0000d2680?, 0xc000902000?, 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(0xc0000d2680, {0xc000902000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0000d2680, {0xc000902000?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014c310, {0xc000902000?, 0x0?, 0x3eb1e60?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0003826a8, {0xc000902000?, 0x7582d451b0a8?, 0x75831d9c0f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000669c38, {0xc000902000?, 0x3eb83e0?, 0xc00047b9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001f42b8, {0x2ace240, 0xc000669c38}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001f4008, {0x7582d440f100, 0xc0003826a8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0001f4008, 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(0xc0001f4008, {0xc000986000, 0x1000, 0xc00043b620?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00072c120, {0xc000986000?, 0x7f4ac5?, 0x2acb160?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0002f1aa0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0002f1aa0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00072c120) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14334 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14710 [select]: net/http.(*persistConn).roundTrip(0xc00055bb00, 0xc0019d1bd0) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc000590ea0, 0xc0003a1180) /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(0xc0000501c0, 0xc0003a1180) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc0003a1180, {0x2accce0, 0xc0000501c0}, {0x1?, 0x2?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001a8e780, 0xc0003a1180, {0x482592?, 0xc0007cbdf8?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001a8e780, 0xc0003a1180) /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 14020 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.400906341s (try 1/5): exit status 2: Failed []