"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 3/5) 2025/07/05 06:15:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu" 2025/07/05 06:15:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/05 06:15:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu.bin" 2025/07/05 06:15:20 DEBUG : Swift container crypt path rclone-test-misiyos1gutu.bin: Auto set use_segments_container to true 2025/07/05 06:15:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu" 2025/07/05 06:15:26 DEBUG : Swift container crypt path rclone-test-misiyos1gutu: Auto set use_segments_container to true 2025/07/05 06:15:32 DEBUG : Creating backend with remote "/tmp/rclone540121788" === 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-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:15:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:15:32 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/07/05 06:15:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:15:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.61s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:15:32 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/05 06:15:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:15:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:15:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:15:38 DEBUG : sub dir/hello world: md5 = 4ff6bfc7b60e56fb83996483c5bca68b OK 2025/07/05 06:15:38 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:15:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (21.15s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.20s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.21s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:15:54 DEBUG : Creating backend with remote "/non-existing" 2025/07/05 06:15:54 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/07/05 06:15:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:15:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.14s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:16:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:16:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:16:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:16:09 DEBUG : sub dir/hello world: md5 = a847f3997c651b44ed3369a4e5e4582b OK 2025/07/05 06:16:09 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (21.15s) === RUN TestCopyNoTraverseDeadlock run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:222: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (0.20s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:16:15 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Running all checks before starting transfers 2025/07/05 06:16:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:16:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:16:15 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Checks finished, now starting transfers 2025/07/05 06:16:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:16:21 DEBUG : sub dir/hello world: md5 = a2d0bf79c3371deea6ca3b04d192ff79 OK 2025/07/05 06:16:21 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (11.06s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:16:26 ERROR : Ignoring --no-traverse with sync 2025/07/05 06:16:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:16:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:16:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:16:32 DEBUG : sub dir/hello world: md5 = 1a349e31bd1382eaac2755f80720c996 OK 2025/07/05 06:16:32 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:16:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (16.12s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:16:43 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/07/05 06:16:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:16:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:16:43 DEBUG : hello world2: md5 = d9d30835e8e0adab29906da218c316ff OK 2025/07/05 06:16:43 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.13s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:16:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:16:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:16:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:16:49 DEBUG : potato2: md5 = 162bd59a9f5ac8e7293f27f913d2a15c OK 2025/07/05 06:16:49 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (10.95s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:17:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:17:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:17:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:17:05 DEBUG : potato2: md5 = 4dcc5c64d7fd1d7fa3b81e2586ea8747 OK 2025/07/05 06:17:05 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (15.78s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:17:10 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/05 06:17:10 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 06:17:10 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/07/05 06:17:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/05 06:17:10 INFO : sub dir2: Making directory 2025/07/05 06:17:10 INFO : sub dir: Making directory 2025/07/05 06:17:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:17:10 INFO : sub dir2/sub sub dir2: Making directory 2025/07/05 06:17:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:17:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:17:16 DEBUG : sub dir/hello world: md5 = 4d702f28a140ea05af4087c46fddee62 OK 2025/07/05 06:17:16 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/07/05 06:17:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (16.27s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:17:27 INFO : sub dir2: Making directory 2025/07/05 06:17:27 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/07/05 06:17:27 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 06:17:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:17:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:17:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:17:32 DEBUG : sub dir/hello world: md5 = 2437aeb8e5bbf513e691ade6dedd95b7 OK 2025/07/05 06:17:32 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (15.97s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:17:43 DEBUG : sub dir2: Making directory with metadata 2025/07/05 06:17:43 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 06:17:43 INFO : sub dir2: Making directory 2025/07/05 06:17:43 INFO : sub dir: Making directory 2025/07/05 06:17:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:17:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:17:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:17:48 DEBUG : sub dir/hello world: md5 = 0f305cf83f3c10d67d63c8f44fe3dcd9 OK 2025/07/05 06:17:48 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:17:48 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/07/05 06:17:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/07/05 06:17:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (21.58s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.21s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:18:04 INFO : sub dir2: Making directory 2025/07/05 06:18:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:18:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:18:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:18:10 DEBUG : sub dir/hello world: md5 = dc13a37021215dd58e2172297f6acf2c OK 2025/07/05 06:18:10 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:18:10 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (15.93s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:18:20 DEBUG : sub dir2: Making directory with metadata 2025/07/05 06:18:20 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 06:18:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/05 06:18:20 INFO : sub dir2: Making directory 2025/07/05 06:18:20 INFO : sub dir: Making directory 2025/07/05 06:18:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:18:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:18:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:18:26 DEBUG : sub dir/hello world: md5 = bca99b6a14c787f69221d66bcae31374 OK 2025/07/05 06:18:26 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:18:26 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/07/05 06:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (20.97s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.40s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:18:42 INFO : sub dir2: Making directory 2025/07/05 06:18:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:18:47 DEBUG : sub dir/hello world: md5 = e814e7ef2edd6864fa9f912e22b2d761 OK 2025/07/05 06:18:47 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:18:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (10.90s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:18:58 DEBUG : sub dir/hello world: md5 = 9b527927214700451bd2dcd0270a392e OK 2025/07/05 06:18:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-loyoxac0xebu" 2025/07/05 06:18:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-loyoxac0xebu.bin" 2025/07/05 06:18:58 DEBUG : Swift container crypt path rclone-test-loyoxac0xebu.bin: Auto set use_segments_container to true 2025/07/05 06:19:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-loyoxac0xebu" 2025/07/05 06:19:04 DEBUG : Swift container crypt path rclone-test-loyoxac0xebu: Auto set use_segments_container to true sync_test.go:620: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-loyoxac0xebu' 2025/07/05 06:19:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:19:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-loyoxac0xebu': Waiting for checks to finish 2025/07/05 06:19:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-loyoxac0xebu': Waiting for transfers to finish 2025/07/05 06:19:30 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:19:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-loyoxac0xebu': Purge remote 2025/07/05 06:19:35 DEBUG : Waiting for deletions to finish 2025/07/05 06:19:46 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (58.77s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:19:57 DEBUG : sub dir/hello world: md5 = 76e6534f5f786c635cfea3c9831673d4 OK 2025/07/05 06:19:57 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/05 06:19:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:19:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:20:02 DEBUG : sub dir/hello world: md5 = b1427d8f9cb13434c7a6b18dee5e3816 OK 2025/07/05 06:20:02 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (16.41s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:20:13 DEBUG : sub dir/hello world: md5 = 99b36f89daa50ba810af1cb71083d4d4 OK 2025/07/05 06:20:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-riceqeg7jane" 2025/07/05 06:20:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-riceqeg7jane.bin" 2025/07/05 06:20:19 DEBUG : Swift container crypt path rclone-test-riceqeg7jane.bin: Auto set use_segments_container to true 2025/07/05 06:20:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-riceqeg7jane" 2025/07/05 06:20:24 DEBUG : Swift container crypt path rclone-test-riceqeg7jane: Auto set use_segments_container to true sync_test.go:656: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane' 2025/07/05 06:20:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:20:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane': Waiting for checks to finish 2025/07/05 06:20:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane': Waiting for transfers to finish 2025/07/05 06:20:50 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:21:01 DEBUG : sub dir/hello world: md5 = 36863fa94028ed948c9ccdc3e38361f8 OK 2025/07/05 06:21:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane': Waiting for checks to finish 2025/07/05 06:21:01 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/05 06:21:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane': Waiting for transfers to finish 2025/07/05 06:21:17 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:21:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riceqeg7jane': Purge remote 2025/07/05 06:21:17 DEBUG : Waiting for deletions to finish 2025/07/05 06:21:22 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (79.77s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:21:33 DEBUG : sub dir/hello world: md5 = 94911b5bb74db2f73af97f41e8a7c07d OK 2025/07/05 06:21:33 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/05 06:21:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:21:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:21:38 DEBUG : sub dir/hello world: md5 = f48fd5b996a54177fc5dfe7108b74f74 OK 2025/07/05 06:21:38 INFO : sub dir/hello world: Copied (replaced existing) 2025/07/05 06:21:38 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (27.40s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:22:00 DEBUG : sub dir/hello world: md5 = 2db00675a59af835359100459244165a OK 2025/07/05 06:22:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-faxasub9zize" 2025/07/05 06:22:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-faxasub9zize.bin" 2025/07/05 06:22:06 DEBUG : Swift container crypt path rclone-test-faxasub9zize.bin: Auto set use_segments_container to true 2025/07/05 06:22:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-faxasub9zize" 2025/07/05 06:22:12 DEBUG : Swift container crypt path rclone-test-faxasub9zize: Auto set use_segments_container to true sync_test.go:701: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize' 2025/07/05 06:22:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:22:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for checks to finish 2025/07/05 06:22:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for transfers to finish 2025/07/05 06:22:28 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:22:33 DEBUG : sub dir/hello world: md5 = 9a607aa0d99a3e5577edb5287667ceef OK 2025/07/05 06:22:34 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/07/05 06:22:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for checks to finish 2025/07/05 06:22:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for transfers to finish 2025/07/05 06:22:39 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:22:44 INFO : sub dir/hello world: Deleted 2025/07/05 06:22:50 DEBUG : sub dir/hello world: md5 = 9c3bd8df111d261817e7fa09000e4fea OK 2025/07/05 06:22:55 DEBUG : testing file moves 2025/07/05 06:22:55 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/07/05 06:22:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for checks to finish 2025/07/05 06:22:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Waiting for transfers to finish 2025/07/05 06:23:01 INFO : sub dir/hello world: Copied (server-side copy) 2025/07/05 06:23:06 INFO : sub dir/hello world: Deleted 2025/07/05 06:23:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-faxasub9zize': Purge remote 2025/07/05 06:23:06 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:06 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (71.55s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:23:12 DEBUG : sub dir/hello world: md5 = 624bd301b7aec4ab16f6c15429853395 OK 2025/07/05 06:23:12 ERROR : error listing: directory not found 2025/07/05 06:23:17 INFO : Local file system at /tmp/rclone540121788: Making directory 2025/07/05 06:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (21.33s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:23:33 DEBUG : sub dir/hello world: md5 = d9b5432f101dbaf077d0c7221970e4e1 OK 2025/07/05 06:23:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:23:38 DEBUG : Local file system at /tmp/rclone540121788: Waiting for checks to finish 2025/07/05 06:23:38 DEBUG : Local file system at /tmp/rclone540121788: Waiting for transfers to finish 2025/07/05 06:23:43 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/07/05 06:23:43 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (21.06s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:23:49 DEBUG : check sum: Need to transfer - File not found at Destination 2025/07/05 06:23:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:49 DEBUG : check sum: md5 = 35c76e8c9165d0c72393a13f17f4c3ba OK 2025/07/05 06:23:49 INFO : check sum: Copied (new) 2025/07/05 06:23:49 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:50 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/07/05 06:23:50 DEBUG : check sum: Size of src and dst objects identical 2025/07/05 06:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:50 DEBUG : check sum: Unchanged skipping 2025/07/05 06:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:50 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:50 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.49s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:23:50 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/07/05 06:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:51 DEBUG : sizeonly: md5 = 5a270fbf7f9f39816eae6a223a1ae64d OK 2025/07/05 06:23:51 INFO : sizeonly: Copied (new) 2025/07/05 06:23:51 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:51 DEBUG : sizeonly: Sizes identical 2025/07/05 06:23:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:51 DEBUG : sizeonly: Unchanged skipping 2025/07/05 06:23:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:51 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:51 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.43s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:23:52 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/07/05 06:23:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:57 DEBUG : ignore-size: md5 = c555e22466107fc05b64745e92dfc54c OK 2025/07/05 06:23:57 INFO : ignore-size: Copied (new) 2025/07/05 06:23:57 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:23:58 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:23:58 DEBUG : ignore-size: Unchanged skipping 2025/07/05 06:23:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:23:58 DEBUG : Waiting for deletions to finish 2025/07/05 06:23:58 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (16.29s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:24:14 DEBUG : existing: md5 = 8322056c30b2b3be2be7f5cd4403d8d3 OK 2025/07/05 06:24:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:24:19 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:24:19 DEBUG : existing: Unchanged skipping 2025/07/05 06:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:24:19 DEBUG : Waiting for deletions to finish 2025/07/05 06:24:19 INFO : There was nothing to transfer 2025/07/05 06:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:24:19 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/07/05 06:24:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:24:25 DEBUG : existing: md5 = 21aeccb7c9cf05059a6a068d5c11d65b OK 2025/07/05 06:24:25 INFO : existing: Copied (replaced existing) 2025/07/05 06:24:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (27.00s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:24:35 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/05 06:24:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:24:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:24:40 DEBUG : existing: md5 = e1a238f6a8cc5391ac2d7fcbbdb6ccd2 OK 2025/07/05 06:24:40 INFO : existing: Copied (new) 2025/07/05 06:24:40 DEBUG : Waiting for deletions to finish 2025/07/05 06:24:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:24:46 DEBUG : existing: Destination exists, skipping 2025/07/05 06:24:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:24:46 DEBUG : Waiting for deletions to finish 2025/07/05 06:24:46 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (16.08s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:24:56 DEBUG : b/potato: md5 = 19c8367275fa22c9562dcf85e91ada5b OK 2025/07/05 06:25:02 DEBUG : c/non empty space: md5 = 6bf7a90904c305b784c7fce535ba9cf6 OK 2025/07/05 06:25:02 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/07/05 06:25:07 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/05 06:25:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:25:07 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:25:07 DEBUG : c/non empty space: Unchanged skipping 2025/07/05 06:25:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:25:12 DEBUG : a/potato2: md5 = 5fc08223e5bff80a09730baf1d85ccc7 OK 2025/07/05 06:25:12 INFO : a/potato2: Copied (new) 2025/07/05 06:25:12 DEBUG : Waiting for deletions to finish 2025/07/05 06:25:18 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (37.52s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:25:34 DEBUG : empty space: md5 = ad059ac0a796ca86e0d6c096501a3a8c OK 2025/07/05 06:25:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:25:35 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/07/05 06:25:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:25:35 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/07/05 06:25:35 DEBUG : Waiting for deletions to finish 2025/07/05 06:25:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:25:40 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/07/05 06:25:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:25:45 DEBUG : empty space: md5 = cb036c00e07c0cb63f43eacbfaf0093f OK 2025/07/05 06:25:45 INFO : empty space: Copied (replaced existing) 2025/07/05 06:25:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (22.46s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:1046: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.32s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:25:52 DEBUG : foo: md5 = e58dbf04d9b5a1285d03de1a0dc5edaa OK 2025/07/05 06:25:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:25:53 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/07/05 06:25:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:25:53 DEBUG : foo: md5 = 1b310fc904d554ee6974e96b8a0e327e OK 2025/07/05 06:25:53 INFO : foo: Copied (replaced existing) 2025/07/05 06:25:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.19s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:25:59 DEBUG : empty space: md5 = 2e1e89e6525c64f294f8d736f9f62088 OK 2025/07/05 06:26:00 DEBUG : potato: Need to transfer - File not found at Destination 2025/07/05 06:26:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:26:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:26:00 DEBUG : empty space: Unchanged skipping 2025/07/05 06:26:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:26:05 DEBUG : potato: md5 = 2e5a8272530a9e8f3b31b8ac56517e7f OK 2025/07/05 06:26:05 INFO : potato: Copied (new) 2025/07/05 06:26:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (27.04s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:26:26 DEBUG : potato: md5 = fe780d666edb717cdcfb91a1ccc9cea3 OK 2025/07/05 06:26:26 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/07/05 06:26:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:26:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:26:36 DEBUG : potato: md5 = bb5774dd8f975eb02bade73330ed88a5 OK 2025/07/05 06:26:36 INFO : potato: Copied (replaced existing) 2025/07/05 06:26:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (21.01s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:26:47 DEBUG : potato: md5 = d8b55229e8a21497b6a1b5be0436b4bb OK 2025/07/05 06:26:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:26:52 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/07/05 06:26:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:26:57 DEBUG : potato: md5 = f5b79cd88d21bb7042f329ceb23977e5 OK 2025/07/05 06:26:57 INFO : potato: Copied (replaced existing) 2025/07/05 06:26:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (21.37s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:27:08 DEBUG : potato: md5 = 0e5a7181a1d7964dce02d2ea7f6a7519 OK 2025/07/05 06:27:13 DEBUG : empty space: md5 = 58366358683765c1c598d3d7aa5c18e8 OK 2025/07/05 06:27:13 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:27:13 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/07/05 06:27:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:27:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:27:14 DEBUG : empty space: Unchanged skipping 2025/07/05 06:27:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:27:14 DEBUG : Waiting for deletions to finish 2025/07/05 06:27:14 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (31.21s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:27:39 DEBUG : potato: md5 = 28afa4807e5062f01185adc5ffebbcd0 OK 2025/07/05 06:27:45 DEBUG : empty space: md5 = 4c69c6ebba7a395ada8858e79a74ee13 OK 2025/07/05 06:27:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:27:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:27:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:27:45 DEBUG : empty space: Unchanged skipping 2025/07/05 06:27:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:27:50 DEBUG : potato2: md5 = 4016498c284b57a39bdfa80098f6b146 OK 2025/07/05 06:27:50 INFO : potato2: Copied (new) 2025/07/05 06:27:50 DEBUG : Waiting for deletions to finish 2025/07/05 06:27:55 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (36.76s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:28:16 DEBUG : b/potato: md5 = 661beb3ba12b46bb9df2009889cb8732 OK 2025/07/05 06:28:21 DEBUG : c/non empty space: md5 = 57107288fc4d92ea77811752888475c9 OK 2025/07/05 06:28:21 INFO : d: Making directory 2025/07/05 06:28:21 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/07/05 06:28:22 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/05 06:28:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:28:22 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:28:22 DEBUG : c/non empty space: Unchanged skipping 2025/07/05 06:28:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:28:27 DEBUG : a/potato2: md5 = 54dfe56fca6f7bbe8076f912c05614f8 OK 2025/07/05 06:28:27 INFO : a/potato2: Copied (new) 2025/07/05 06:28:27 DEBUG : Waiting for deletions to finish 2025/07/05 06:28:32 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (37.29s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:28:53 DEBUG : b/potato: md5 = ea0023093f67d5f87bbedbf928c0972d OK 2025/07/05 06:28:59 DEBUG : c/non empty space: md5 = 478962e202c9e48fd1d383266cbd5014 OK 2025/07/05 06:28:59 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/07/05 06:28:59 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/07/05 06:28:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:29:04 DEBUG : a/potato2: md5 = 08bca9d09cdf9c5953b9c348947242e8 OK 2025/07/05 06:29:04 INFO : a/potato2: Copied (new) 2025/07/05 06:29:04 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:29:04 DEBUG : c/non empty space: Unchanged skipping 2025/07/05 06:29:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:29:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': not deleting files as there were IO errors 2025/07/05 06:29:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (36.75s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:29:30 DEBUG : potato: md5 = dcc4f50f9b813bd278154a18c14316fd OK 2025/07/05 06:29:36 DEBUG : empty space: md5 = 7970f07ffce1b3e88c8e3938236211e5 OK 2025/07/05 06:29:36 DEBUG : Waiting for deletions to finish 2025/07/05 06:29:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:29:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:29:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:29:36 DEBUG : empty space: Unchanged skipping 2025/07/05 06:29:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:29:41 DEBUG : potato2: md5 = 350222feb6a11d6eebaa038df0da6713 OK 2025/07/05 06:29:41 INFO : potato2: Copied (new) 2025/07/05 06:29:41 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (26.86s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:29:57 DEBUG : potato: md5 = 628011ebc70d5a2aadb3cd35d02fd9af OK 2025/07/05 06:30:02 DEBUG : empty space: md5 = 4ff6812ee46f3f9f4853def7c99770ee OK 2025/07/05 06:30:03 DEBUG : Waiting for deletions to finish 2025/07/05 06:30:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:30:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:30:08 INFO : potato: Deleted 2025/07/05 06:30:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:30:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:30:08 DEBUG : empty space: Unchanged skipping 2025/07/05 06:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:30:13 DEBUG : potato2: md5 = 7a08f4774a64caae946c1f4424e0c7b8 OK 2025/07/05 06:30:13 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (41.93s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:30:39 DEBUG : potato: md5 = df4275a01886eee36381b8df1cd9d20b OK 2025/07/05 06:30:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:30:49 DEBUG : potato2: md5 = ec51b5a86eb9973860637762dfedaa5a OK 2025/07/05 06:30:49 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (31.29s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:31:10 DEBUG : potato2: md5 = 53c914f9917b9424acf11e93a8599f90 OK 2025/07/05 06:31:16 DEBUG : empty space: md5 = c07ffa153de035e27f358e92f05e0ad1 OK 2025/07/05 06:31:26 DEBUG : enormous: Excluded (Size Filter) 2025/07/05 06:31:26 DEBUG : enormous: Excluded 2025/07/05 06:31:26 DEBUG : potato2: Excluded (Size Filter) 2025/07/05 06:31:26 DEBUG : potato2: Excluded 2025/07/05 06:31:26 DEBUG : potato2: Excluded (Size Filter) 2025/07/05 06:31:26 DEBUG : potato2: Excluded 2025/07/05 06:31:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:31:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:31:31 DEBUG : empty space: Unchanged skipping 2025/07/05 06:31:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:31:31 DEBUG : Waiting for deletions to finish 2025/07/05 06:31:31 INFO : There was nothing to transfer 2025/07/05 06:31:31 DEBUG : enormous: Excluded (Size Filter) 2025/07/05 06:31:31 DEBUG : enormous: Excluded 2025/07/05 06:31:31 DEBUG : potato2: Excluded (Size Filter) 2025/07/05 06:31:31 DEBUG : potato2: Excluded 2025/07/05 06:31:31 DEBUG : potato2: Excluded (Size Filter) 2025/07/05 06:31:31 DEBUG : potato2: Excluded 2025/07/05 06:31:31 DEBUG : Local file system at /tmp/rclone540121788: Waiting for checks to finish 2025/07/05 06:31:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:31:31 DEBUG : empty space: Unchanged skipping 2025/07/05 06:31:31 DEBUG : Local file system at /tmp/rclone540121788: Waiting for transfers to finish 2025/07/05 06:31:31 DEBUG : Waiting for deletions to finish 2025/07/05 06:31:31 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (36.29s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:31:47 DEBUG : potato2: md5 = dded1c1dd653aab68f883229e88d4d07 OK 2025/07/05 06:31:52 DEBUG : empty space: md5 = 9cb04f4c33e6a308bb08ce192ed4244e OK 2025/07/05 06:31:57 DEBUG : enormous: md5 = 7185219dbbdf7a767e80a7dddc2ee07e OK 2025/07/05 06:31:57 DEBUG : enormous: Excluded (Size Filter) 2025/07/05 06:31:57 DEBUG : enormous: Excluded 2025/07/05 06:31:57 DEBUG : potato2: Excluded (Size Filter) 2025/07/05 06:31:57 DEBUG : potato2: Excluded 2025/07/05 06:31:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:31:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:31:58 DEBUG : empty space: Unchanged skipping 2025/07/05 06:31:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:31:58 DEBUG : Waiting for deletions to finish 2025/07/05 06:32:03 INFO : potato2: Deleted 2025/07/05 06:32:08 INFO : enormous: Deleted 2025/07/05 06:32:08 INFO : There was nothing to transfer 2025/07/05 06:32:08 DEBUG : Local file system at /tmp/rclone540121788: Waiting for checks to finish 2025/07/05 06:32:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:32:13 DEBUG : empty space: Unchanged skipping 2025/07/05 06:32:13 DEBUG : Local file system at /tmp/rclone540121788: Waiting for transfers to finish 2025/07/05 06:32:13 DEBUG : Waiting for deletions to finish 2025/07/05 06:32:13 INFO : enormous: Deleted 2025/07/05 06:32:13 INFO : potato2: Deleted 2025/07/05 06:32:13 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (42.27s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:32:29 DEBUG : one: md5 = 311584773eaebcfabca813bba8b10f44 OK 2025/07/05 06:32:34 DEBUG : two: md5 = 093a98c16fb5aaa7b56fffd8e9b53a0a OK 2025/07/05 06:32:34 DEBUG : three: md5 = 628eab1ba90702ce0a1f2fe0786e8e6e OK 2025/07/05 06:32:35 DEBUG : four: md5 = 005e3d1e1313b313b9c8a3ca213057d2 OK 2025/07/05 06:32:40 DEBUG : five: Need to transfer - File not found at Destination 2025/07/05 06:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:32:41 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/07/05 06:32:41 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/07/05 06:32:41 DEBUG : one: Destination is newer than source, skipping 2025/07/05 06:32:41 DEBUG : three: Sizes identical 2025/07/05 06:32:41 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/07/05 06:32:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:32:41 DEBUG : four: md5 = 64e97e7e77ccb64e27288d8e17bb34be OK 2025/07/05 06:32:41 INFO : four: Copied (replaced existing) 2025/07/05 06:32:46 DEBUG : five: md5 = 2752bce4700c1818b155cad88a89022b OK 2025/07/05 06:32:46 INFO : five: Copied (new) 2025/07/05 06:32:46 DEBUG : two: md5 = ef17c87b5b039dddd2e13ba81a47de09 OK 2025/07/05 06:32:46 INFO : two: Copied (replaced existing) 2025/07/05 06:32:46 DEBUG : Waiting for deletions to finish sync_test.go:1479: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (53.58s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1496: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1496: 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-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:1568: Can track renames: false 2025/07/05 06:33:17 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Ignoring --track-renames as the source and destination do not have a common hash 2025/07/05 06:33:17 DEBUG : potato: Need to transfer - File not found at Destination 2025/07/05 06:33:17 DEBUG : yam: Need to transfer - File not found at Destination 2025/07/05 06:33:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:33:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:33:23 DEBUG : potato: md5 = 3e119376c8d0ae0548f85518e522af33 OK 2025/07/05 06:33:23 INFO : potato: Copied (new) 2025/07/05 06:33:23 DEBUG : yam: md5 = 3715446e40a13d7db3f6551012e55687 OK 2025/07/05 06:33:23 INFO : yam: Copied (new) 2025/07/05 06:33:23 DEBUG : Waiting for deletions to finish 2025/07/05 06:33:28 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Ignoring --track-renames as the source and destination do not have a common hash 2025/07/05 06:33:28 DEBUG : yaml: Need to transfer - File not found at Destination 2025/07/05 06:33:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:33:28 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:33:28 DEBUG : potato: Unchanged skipping 2025/07/05 06:33:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:33:28 DEBUG : yaml: md5 = 1eff60375dc6396d14c6f77105f452d2 OK 2025/07/05 06:33:28 INFO : yaml: Copied (new) 2025/07/05 06:33:28 DEBUG : Waiting for deletions to finish 2025/07/05 06:33:39 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (36.86s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:1640: Can track renames: true 2025/07/05 06:33:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Making map for --track-renames 2025/07/05 06:33:54 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Finished making map for --track-renames 2025/07/05 06:33:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:33:54 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/05 06:33:54 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/07/05 06:33:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for renames to finish 2025/07/05 06:33:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:33:59 DEBUG : potato: md5 = 99dcee3c23380f1c837e89a02e96b811 OK 2025/07/05 06:33:59 INFO : potato: Copied (new) 2025/07/05 06:34:00 DEBUG : yam: md5 = 67b0c1a7119110e704edb6ab6a0f5354 OK 2025/07/05 06:34:00 INFO : yam: Copied (new) 2025/07/05 06:34:00 DEBUG : Waiting for deletions to finish 2025/07/05 06:34:05 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Making map for --track-renames 2025/07/05 06:34:05 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Finished making map for --track-renames 2025/07/05 06:34:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:34:10 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:34:10 DEBUG : potato: Unchanged skipping 2025/07/05 06:34:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for renames to finish 2025/07/05 06:34:15 INFO : yam: Copied (server-side copy) to: yaml 2025/07/05 06:34:20 INFO : yam: Deleted 2025/07/05 06:34:20 INFO : yaml: Renamed from "yam" 2025/07/05 06:34:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:34:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (36.79s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:1679: Can track renames: true 2025/07/05 06:34:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Making map for --track-renames 2025/07/05 06:34:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Finished making map for --track-renames 2025/07/05 06:34:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:34:31 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/07/05 06:34:31 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/07/05 06:34:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for renames to finish 2025/07/05 06:34:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:34:36 DEBUG : sub/yam: md5 = 7c44f9431ae507c47a3fce4e2342926e OK 2025/07/05 06:34:36 INFO : sub/yam: Copied (new) 2025/07/05 06:34:36 DEBUG : potato: md5 = 4b3352ef9e2c9b89e30f9b78a09b7ce7 OK 2025/07/05 06:34:36 INFO : potato: Copied (new) 2025/07/05 06:34:36 DEBUG : Waiting for deletions to finish 2025/07/05 06:34:37 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:34:37 DEBUG : potato: Unchanged skipping 2025/07/05 06:34:37 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Making map for --track-renames 2025/07/05 06:34:37 INFO : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Finished making map for --track-renames 2025/07/05 06:34:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:34:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for renames to finish 2025/07/05 06:34:57 INFO : sub/yam: Copied (server-side copy) to: yam 2025/07/05 06:35:02 INFO : sub/yam: Deleted 2025/07/05 06:35:02 INFO : yam: Renamed from "sub/yam" 2025/07/05 06:35:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:35:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (51.76s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:35:23 DEBUG : Creating backend with remote "/tmp/rclone540121788/dir1" 2025/07/05 06:35:23 DEBUG : Config file has changed externally - reloading 2025/07/05 06:35:23 DEBUG : Creating backend with remote "/tmp/rclone540121788/dir2" 2025/07/05 06:35:23 DEBUG : Local file system at /tmp/rclone540121788/dir2: Using server-side directory move 2025/07/05 06:35:23 INFO : Local file system at /tmp/rclone540121788/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/07/05 06:35:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/07/05 06:35:23 DEBUG : Local file system at /tmp/rclone540121788/dir2: Waiting for checks to finish 2025/07/05 06:35:23 DEBUG : Local file system at /tmp/rclone540121788/dir2: Waiting for transfers to finish 2025/07/05 06:35:23 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.25s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:35:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:35:23 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/05 06:35:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:35:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:35:23 DEBUG : nested/sub dir/file: md5 = b5a6d6e12c671166d60c8f760925b331 OK 2025/07/05 06:35:23 INFO : nested/sub dir/file: Copied (new) 2025/07/05 06:35:23 INFO : nested/sub dir/file: Deleted 2025/07/05 06:35:28 DEBUG : sub dir/hello world: md5 = d854ff999cdc404d9b21f130a35ce99d OK 2025/07/05 06:35:28 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:35:28 INFO : sub dir/hello world: Deleted 2025/07/05 06:35:28 INFO : sub dir: Removing directory 2025/07/05 06:35:28 INFO : nested/sub dir: Removing directory 2025/07/05 06:35:28 INFO : nested: Removing directory 2025/07/05 06:35:28 DEBUG : Local file system at /tmp/rclone540121788: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (16.20s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:35:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/07/05 06:35:39 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/07/05 06:35:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:35:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:35:39 DEBUG : nested/sub dir/file: md5 = 1e46813b9dfbac94ed8694c4fd39e939 OK 2025/07/05 06:35:39 INFO : nested/sub dir/file: Copied (new) 2025/07/05 06:35:39 INFO : nested/sub dir/file: Deleted 2025/07/05 06:35:44 DEBUG : sub dir/hello world: md5 = 59a4ba4a73a643e3bd41eba8189399ca OK 2025/07/05 06:35:44 INFO : sub dir/hello world: Copied (new) 2025/07/05 06:35:44 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (21.10s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:36:00 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/07/05 06:36:00 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/05 06:36:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:36:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:36:06 DEBUG : existing: md5 = fb99c44292687de86b6e5f2e6ac2a0bb OK 2025/07/05 06:36:06 INFO : existing: Copied (new) 2025/07/05 06:36:06 INFO : existing: Deleted 2025/07/05 06:36:06 DEBUG : existing-b: md5 = 6c59402ff3e8ed5266fa2939e9f6acdc OK 2025/07/05 06:36:06 INFO : existing-b: Copied (new) 2025/07/05 06:36:06 INFO : existing-b: Deleted 2025/07/05 06:36:12 DEBUG : existing: Destination exists, skipping 2025/07/05 06:36:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:36:12 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/07/05 06:36:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:36:12 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (32.15s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:36:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ponaras1gusa" 2025/07/05 06:36:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ponaras1gusa.bin" 2025/07/05 06:36:33 DEBUG : Swift container crypt path rclone-test-ponaras1gusa.bin: Auto set use_segments_container to true 2025/07/05 06:36:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ponaras1gusa" 2025/07/05 06:36:39 DEBUG : Swift container crypt path rclone-test-ponaras1gusa: Auto set use_segments_container to true 2025/07/05 06:36:49 DEBUG : potato2: md5 = 6c8b22f2e1fe66ef20f1effe6ea34abb OK 2025/07/05 06:36:54 DEBUG : empty space: md5 = 682cafa150120c27860c020a17c129fa OK 2025/07/05 06:36:55 DEBUG : potato3: md5 = db76ecde7c032d97ed88a890119c895a OK sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-ponaras1gusa' 2025/07/05 06:37:00 DEBUG : empty space: md5 = 5ffe0eadaa826fa2044a10697b36a61c OK 2025/07/05 06:37:01 DEBUG : potato3: md5 = 9f711cbc8ff5179e62f85ed2900b7c58 OK 2025/07/05 06:37:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:37:01 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/05 06:37:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponaras1gusa': Waiting for checks to finish 2025/07/05 06:37:01 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:37:01 DEBUG : empty space: Unchanged skipping 2025/07/05 06:37:06 INFO : empty space: Deleted 2025/07/05 06:37:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponaras1gusa': Waiting for transfers to finish 2025/07/05 06:37:07 INFO : potato3: Copied (server-side copy) 2025/07/05 06:37:07 INFO : potato3: Deleted 2025/07/05 06:37:25 INFO : potato2: Copied (server-side copy) 2025/07/05 06:37:30 INFO : potato2: Deleted 2025/07/05 06:37:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jagaloh3qabu" 2025/07/05 06:37:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jagaloh3qabu.bin" 2025/07/05 06:37:31 DEBUG : Swift container crypt path rclone-test-jagaloh3qabu.bin: Auto set use_segments_container to true 2025/07/05 06:37:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jagaloh3qabu" 2025/07/05 06:37:36 DEBUG : Swift container crypt path rclone-test-jagaloh3qabu: Auto set use_segments_container to true 2025/07/05 06:37:42 DEBUG : empty space: Need to transfer - File not found at Destination 2025/07/05 06:37:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:37:42 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/05 06:37:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jagaloh3qabu': Waiting for checks to finish 2025/07/05 06:37:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jagaloh3qabu': Waiting for transfers to finish 2025/07/05 06:37:47 INFO : potato2: Copied (server-side copy) 2025/07/05 06:37:52 INFO : potato3: Copied (server-side copy) 2025/07/05 06:37:52 INFO : potato3: Deleted 2025/07/05 06:37:53 INFO : potato2: Deleted 2025/07/05 06:37:57 INFO : empty space: Copied (server-side copy) 2025/07/05 06:37:57 INFO : empty space: Deleted 2025/07/05 06:37:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jagaloh3qabu': Purge remote 2025/07/05 06:37:58 DEBUG : Waiting for deletions to finish 2025/07/05 06:37:58 INFO : potato2.bin: Deleted 2025/07/05 06:38:03 INFO : empty space.bin: Deleted 2025/07/05 06:38:03 INFO : potato3.bin: Deleted 2025/07/05 06:38:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponaras1gusa': Purge remote 2025/07/05 06:38:03 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (90.85s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:38:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-doyazok6rasa" 2025/07/05 06:38:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-doyazok6rasa.bin" 2025/07/05 06:38:04 DEBUG : Swift container crypt path rclone-test-doyazok6rasa.bin: Auto set use_segments_container to true 2025/07/05 06:38:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-doyazok6rasa" 2025/07/05 06:38:09 DEBUG : Swift container crypt path rclone-test-doyazok6rasa: Auto set use_segments_container to true 2025/07/05 06:38:20 DEBUG : potato2: md5 = 63575ae1106a448e7a65cc8dfe17dd2b OK 2025/07/05 06:38:25 DEBUG : empty space: md5 = e28f04a4878faba9a974af8f1499ceba OK 2025/07/05 06:38:25 DEBUG : potato3: md5 = ddf40e4b93baaa5b1189db4aa799188f OK sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-doyazok6rasa' 2025/07/05 06:38:36 DEBUG : empty space: md5 = 14ca5d10a7a3efa4981c51e4a4bc6f49 OK 2025/07/05 06:38:41 DEBUG : potato3: md5 = b0effa550cdca2b933c526991629faa3 OK 2025/07/05 06:38:41 DEBUG : empty space: Excluded (Size Filter) 2025/07/05 06:38:41 DEBUG : empty space: Excluded 2025/07/05 06:38:41 DEBUG : empty space: Excluded (Size Filter) 2025/07/05 06:38:41 DEBUG : empty space: Excluded 2025/07/05 06:38:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:38:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/05 06:38:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-doyazok6rasa': Waiting for checks to finish 2025/07/05 06:38:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-doyazok6rasa': Waiting for transfers to finish 2025/07/05 06:39:08 INFO : potato3: Copied (server-side copy) 2025/07/05 06:39:08 INFO : potato3: Deleted 2025/07/05 06:39:18 INFO : potato2: Copied (server-side copy) 2025/07/05 06:39:23 INFO : potato2: Deleted 2025/07/05 06:39:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vuvonaz0yuho" 2025/07/05 06:39:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vuvonaz0yuho.bin" 2025/07/05 06:39:29 DEBUG : Swift container crypt path rclone-test-vuvonaz0yuho.bin: Auto set use_segments_container to true 2025/07/05 06:39:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vuvonaz0yuho" 2025/07/05 06:39:34 DEBUG : Swift container crypt path rclone-test-vuvonaz0yuho: Auto set use_segments_container to true 2025/07/05 06:39:40 DEBUG : empty space: Excluded (Size Filter) 2025/07/05 06:39:40 DEBUG : empty space: Excluded 2025/07/05 06:39:40 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:39:40 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/05 06:39:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vuvonaz0yuho': Waiting for checks to finish 2025/07/05 06:39:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vuvonaz0yuho': Waiting for transfers to finish 2025/07/05 06:39:50 INFO : potato3: Copied (server-side copy) 2025/07/05 06:39:56 INFO : potato3: Deleted 2025/07/05 06:39:56 INFO : potato2: Copied (server-side copy) 2025/07/05 06:40:01 INFO : potato2: Deleted 2025/07/05 06:40:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vuvonaz0yuho': Purge remote 2025/07/05 06:40:07 DEBUG : Waiting for deletions to finish 2025/07/05 06:40:07 INFO : potato2.bin: Deleted 2025/07/05 06:40:12 INFO : potato3.bin: Deleted 2025/07/05 06:40:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-doyazok6rasa': Purge remote 2025/07/05 06:40:12 DEBUG : Waiting for deletions to finish 2025/07/05 06:40:17 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (139.11s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:40:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fasutum2niva" 2025/07/05 06:40:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fasutum2niva.bin" 2025/07/05 06:40:23 DEBUG : Swift container crypt path rclone-test-fasutum2niva.bin: Auto set use_segments_container to true 2025/07/05 06:40:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fasutum2niva" 2025/07/05 06:40:28 DEBUG : Swift container crypt path rclone-test-fasutum2niva: Auto set use_segments_container to true 2025/07/05 06:40:39 DEBUG : potato2: md5 = fd2f0363965b8f6910377fe656b4467a OK 2025/07/05 06:40:44 DEBUG : empty space: md5 = f04e9e1ca5ca2cf57081317835583329 OK 2025/07/05 06:40:44 DEBUG : potato3: md5 = 7cb293f7d4019b167bc67ebb5777769c OK 2025/07/05 06:40:44 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu' -> Encrypted drive 'TestCryptSwift:rclone-test-fasutum2niva' 2025/07/05 06:40:56 DEBUG : empty space: md5 = eba2eded7f31e7d138b7f0d549cdb0e8 OK 2025/07/05 06:40:56 DEBUG : potato3: md5 = a6e612a359e4dacd12efa7b5c032a144 OK 2025/07/05 06:40:57 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:40:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/07/05 06:40:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fasutum2niva': Waiting for checks to finish 2025/07/05 06:40:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:40:57 DEBUG : empty space: Unchanged skipping 2025/07/05 06:41:02 INFO : potato3: Copied (server-side copy) 2025/07/05 06:41:02 INFO : empty space: Deleted 2025/07/05 06:41:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fasutum2niva': Waiting for transfers to finish 2025/07/05 06:41:02 INFO : potato3: Deleted 2025/07/05 06:41:07 INFO : potato2: Copied (server-side copy) 2025/07/05 06:41:12 INFO : potato2: Deleted 2025/07/05 06:41:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vayoziq9meji" 2025/07/05 06:41:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vayoziq9meji.bin" 2025/07/05 06:41:18 DEBUG : Swift container crypt path rclone-test-vayoziq9meji.bin: Auto set use_segments_container to true 2025/07/05 06:41:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vayoziq9meji" 2025/07/05 06:41:23 DEBUG : Swift container crypt path rclone-test-vayoziq9meji: Auto set use_segments_container to true 2025/07/05 06:41:29 INFO : tomatoDir: Making directory 2025/07/05 06:41:29 DEBUG : empty space: Need to transfer - File not found at Destination 2025/07/05 06:41:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/07/05 06:41:29 DEBUG : potato3: Need to transfer - File not found at Destination 2025/07/05 06:41:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayoziq9meji': Waiting for checks to finish 2025/07/05 06:41:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayoziq9meji': Waiting for transfers to finish 2025/07/05 06:41:35 INFO : potato3: Copied (server-side copy) 2025/07/05 06:41:35 INFO : potato3: Deleted 2025/07/05 06:41:40 INFO : potato2: Copied (server-side copy) 2025/07/05 06:41:40 INFO : potato2: Deleted 2025/07/05 06:41:40 INFO : empty space: Copied (server-side copy) 2025/07/05 06:41:45 INFO : empty space: Deleted 2025/07/05 06:41:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vayoziq9meji': Purge remote 2025/07/05 06:41:45 DEBUG : Waiting for deletions to finish 2025/07/05 06:41:45 INFO : empty space.bin: Deleted 2025/07/05 06:41:45 INFO : potato3.bin: Deleted 2025/07/05 06:41:50 INFO : potato2.bin: Deleted 2025/07/05 06:41:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fasutum2niva': Purge remote 2025/07/05 06:41:50 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (88.79s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:41:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/rclone-move-test" 2025/07/05 06:41:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-move-test.bin" 2025/07/05 06:41:51 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-move-test.bin: Auto set use_segments_container to true 2025/07/05 06:41:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-move-test" 2025/07/05 06:41:57 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-move-test: Auto set use_segments_container to true 2025/07/05 06:42:08 DEBUG : potato2: md5 = b3dddf68af751e06170751fc7aa94d7d OK --- PASS: TestServerSideMoveOverlap (22.51s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:42:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test" 2025/07/05 06:42:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test.bin" 2025/07/05 06:42:14 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/07/05 06:42:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test" 2025/07/05 06:42:20 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.51s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:42:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test" 2025/07/05 06:42:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test.bin" 2025/07/05 06:42:26 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test.bin: Auto set use_segments_container to true 2025/07/05 06:42:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test" 2025/07/05 06:42:31 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test: Auto set use_segments_container to true 2025/07/05 06:42:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-include/layer2" 2025/07/05 06:42:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test-include/layer2.bin" 2025/07/05 06:42:37 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/07/05 06:42:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test-include/layer2" 2025/07/05 06:42:42 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/07/05 06:42:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-ignore-file" 2025/07/05 06:42:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test-ignore-file.bin" 2025/07/05 06:42:48 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/07/05 06:42:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/rclone-sync-test-ignore-file" 2025/07/05 06:42:54 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/07/05 06:43:00 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = d2c47a0482ec9e4bf2fe3d03c70aa3ac OK 2025/07/05 06:43:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/05 06:43:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test': Waiting for checks to finish 2025/07/05 06:43:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test': Waiting for transfers to finish 2025/07/05 06:43:05 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:05 INFO : There was nothing to transfer 2025/07/05 06:43:10 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/05 06:43:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:43:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:43:10 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:10 INFO : There was nothing to transfer 2025/07/05 06:43:16 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/05 06:43:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/07/05 06:43:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/07/05 06:43:16 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:16 INFO : There was nothing to transfer 2025/07/05 06:43:21 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/05 06:43:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:43:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:43:21 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:21 INFO : There was nothing to transfer 2025/07/05 06:43:21 DEBUG : Excluded 2025/07/05 06:43:21 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/07/05 06:43:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/07/05 06:43:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/07/05 06:43:21 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:21 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (56.19s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:43:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:43:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:43:22 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:43:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:43:28 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:43:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/CompareDest" 2025/07/05 06:43:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/CompareDest.bin" 2025/07/05 06:43:33 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/CompareDest.bin: Auto set use_segments_container to true 2025/07/05 06:43:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/CompareDest" 2025/07/05 06:43:39 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/CompareDest: Auto set use_segments_container to true 2025/07/05 06:43:50 DEBUG : one: Need to transfer - File not found at Destination 2025/07/05 06:43:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:43:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:43:50 DEBUG : one: md5 = c6101581cf4e4dc21c8d59c6c0e106dc OK 2025/07/05 06:43:50 INFO : one: Copied (new) 2025/07/05 06:43:50 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:43:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:43:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:43:56 DEBUG : one: md5 = 6187410f719a0baa4279b7a89f279f71 OK 2025/07/05 06:43:56 INFO : one: Copied (replaced existing) 2025/07/05 06:43:56 DEBUG : Waiting for deletions to finish 2025/07/05 06:43:57 DEBUG : dst/one: md5 = 78870d807bf9b0911cebd40f70ec87e5 OK 2025/07/05 06:44:02 DEBUG : CompareDest/one: md5 = b2fb5c86eb426dd75bc3eaef11112477 OK 2025/07/05 06:44:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:44:02 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:44:07 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:07 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/05 06:44:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:44:07 DEBUG : Waiting for deletions to finish 2025/07/05 06:44:07 INFO : There was nothing to transfer 2025/07/05 06:44:13 DEBUG : CompareDest/two: md5 = fe88e7c3e8ca9aee56ca744bcd107370 OK 2025/07/05 06:44:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:44:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/05 06:44:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:44:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:28 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/05 06:44:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:44:28 DEBUG : Waiting for deletions to finish 2025/07/05 06:44:28 INFO : There was nothing to transfer 2025/07/05 06:44:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:44:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:33 DEBUG : two: Destination found in --compare-dest, skipping 2025/07/05 06:44:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:44:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:33 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/05 06:44:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:44:33 DEBUG : Waiting for deletions to finish 2025/07/05 06:44:33 INFO : There was nothing to transfer sync_test.go:2205: No hash on uploaded file so skipping compare timestamp test 2025/07/05 06:44:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:44:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:44:39 DEBUG : one: Destination found in --compare-dest, skipping 2025/07/05 06:44:39 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/07/05 06:44:39 DEBUG : two: Need to transfer - File not found at Destination 2025/07/05 06:44:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:44:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:44:40 DEBUG : two: md5 = 3d2c34f3c28a50754f3d185b338d9be8 OK 2025/07/05 06:44:40 INFO : two: Copied (new) 2025/07/05 06:44:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (90.09s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:44:57 DEBUG : pre-dest1/1: md5 = 0c308c8ef886020dfbe41298139321ab OK 2025/07/05 06:44:58 DEBUG : pre-dest2/2: md5 = 9a62c171c97ba57c671da601406a3ac4 OK 2025/07/05 06:45:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dest" 2025/07/05 06:45:03 DEBUG : Config file has changed externally - reloading 2025/07/05 06:45:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dest.bin" 2025/07/05 06:45:03 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dest.bin: Auto set use_segments_container to true 2025/07/05 06:45:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dest" 2025/07/05 06:45:09 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dest: Auto set use_segments_container to true 2025/07/05 06:45:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/pre-dest1" 2025/07/05 06:45:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/pre-dest1.bin" 2025/07/05 06:45:15 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/pre-dest1.bin: Auto set use_segments_container to true 2025/07/05 06:45:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/pre-dest1" 2025/07/05 06:45:20 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/pre-dest1: Auto set use_segments_container to true 2025/07/05 06:45:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/pre-dest2" 2025/07/05 06:45:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/pre-dest2.bin" 2025/07/05 06:45:26 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/pre-dest2.bin: Auto set use_segments_container to true 2025/07/05 06:45:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/pre-dest2" 2025/07/05 06:45:32 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/pre-dest2: Auto set use_segments_container to true 2025/07/05 06:45:37 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:45:37 DEBUG : 1: Destination found in --compare-dest, skipping 2025/07/05 06:45:48 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:45:48 DEBUG : 2: Destination found in --compare-dest, skipping 2025/07/05 06:45:58 DEBUG : 3: Need to transfer - File not found at Destination 2025/07/05 06:45:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dest': Waiting for checks to finish 2025/07/05 06:45:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dest': Waiting for transfers to finish 2025/07/05 06:46:03 DEBUG : 3: md5 = 12e42892058986fe587fc83354a0c20b OK 2025/07/05 06:46:03 INFO : 3: Copied (new) 2025/07/05 06:46:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (87.90s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:46:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:46:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:46:20 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:46:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:46:25 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:46:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/CopyDest" 2025/07/05 06:46:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/CopyDest.bin" 2025/07/05 06:46:31 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/CopyDest.bin: Auto set use_segments_container to true 2025/07/05 06:46:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/CopyDest" 2025/07/05 06:46:37 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/CopyDest: Auto set use_segments_container to true 2025/07/05 06:46:47 DEBUG : one: Need to transfer - File not found at Destination 2025/07/05 06:46:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:46:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:46:47 DEBUG : one: md5 = 1c431f82f3f4ff765470d471302dd32d OK 2025/07/05 06:46:47 INFO : one: Copied (new) 2025/07/05 06:46:47 DEBUG : Waiting for deletions to finish 2025/07/05 06:46:48 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:46:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:46:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:46:53 DEBUG : one: md5 = f9e453a71f920b17c872eb4d757f6e7f OK 2025/07/05 06:46:53 INFO : one: Copied (replaced existing) 2025/07/05 06:46:53 DEBUG : Waiting for deletions to finish 2025/07/05 06:46:54 DEBUG : dst/one: md5 = 2787da697c8e185a4943c3145e7db821 OK 2025/07/05 06:46:59 DEBUG : CopyDest/one: md5 = d4ce298a70eae6a692aca1e5874ae144 OK 2025/07/05 06:46:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/BackupDir" 2025/07/05 06:46:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/BackupDir.bin" 2025/07/05 06:47:00 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/BackupDir.bin: Auto set use_segments_container to true 2025/07/05 06:47:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/BackupDir" 2025/07/05 06:47:05 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/BackupDir: Auto set use_segments_container to true 2025/07/05 06:47:11 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:47:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:47:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:11 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/07/05 06:47:27 INFO : one: Copied (server-side copy) 2025/07/05 06:47:27 INFO : one: Deleted 2025/07/05 06:47:32 INFO : one: Copied (server-side copy) 2025/07/05 06:47:32 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/07/05 06:47:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:47:32 DEBUG : Waiting for deletions to finish 2025/07/05 06:47:33 DEBUG : CopyDest/two: md5 = 8e22eb817c2ae72b2badf0d1a45c4703 OK 2025/07/05 06:47:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:38 DEBUG : one: Unchanged skipping 2025/07/05 06:47:44 INFO : two: Copied (server-side copy) 2025/07/05 06:47:44 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/07/05 06:47:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:47:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:47:44 DEBUG : Waiting for deletions to finish 2025/07/05 06:47:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:47:49 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:49 DEBUG : one: Unchanged skipping 2025/07/05 06:47:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:49 DEBUG : two: Unchanged skipping 2025/07/05 06:47:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:47:49 DEBUG : Waiting for deletions to finish 2025/07/05 06:47:49 INFO : There was nothing to transfer 2025/07/05 06:47:50 DEBUG : CopyDest/three: md5 = e1b773ff19e847dbd5f9572c91b2bd48 OK 2025/07/05 06:47:51 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/07/05 06:47:51 DEBUG : three: Destination not found in --copy-dest 2025/07/05 06:47:51 DEBUG : three: Need to transfer - File not found at Destination 2025/07/05 06:47:51 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:51 DEBUG : one: Unchanged skipping 2025/07/05 06:47:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:47:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:47:51 DEBUG : two: Unchanged skipping 2025/07/05 06:47:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:47:51 DEBUG : three: md5 = 5f2bebd21354723754850bc62dcf37ae OK 2025/07/05 06:47:51 INFO : three: Copied (new) 2025/07/05 06:47:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (108.80s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:48:08 DEBUG : dst/one: md5 = 6c0fa96c901b40e47d2a4e161aab30c7 OK 2025/07/05 06:48:08 DEBUG : dst/two: md5 = 9107fd0bde8f5eccf15c2bb7443c1cb6 OK 2025/07/05 06:48:14 DEBUG : dst/three.txt: md5 = f0b92cc84a886d2b69789e33e7e04d79 OK 2025/07/05 06:48:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:48:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:48:19 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:48:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:48:25 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:48:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/backup" 2025/07/05 06:48:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup.bin" 2025/07/05 06:48:30 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup.bin: Auto set use_segments_container to true 2025/07/05 06:48:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup" 2025/07/05 06:48:36 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup: Auto set use_segments_container to true 2025/07/05 06:48:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:48:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:48:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:48:42 DEBUG : two: Unchanged skipping 2025/07/05 06:48:58 INFO : one: Copied (server-side copy) 2025/07/05 06:48:58 INFO : one: Deleted 2025/07/05 06:48:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:48:58 DEBUG : one: md5 = 6c81e347acbcaa74736a35e09af181bb OK 2025/07/05 06:48:58 INFO : one: Copied (new) 2025/07/05 06:48:58 DEBUG : Waiting for deletions to finish 2025/07/05 06:49:14 INFO : three.txt: Copied (server-side copy) 2025/07/05 06:49:19 INFO : three.txt: Deleted 2025/07/05 06:49:19 INFO : three.txt: Moved into backup dir 2025/07/05 06:49:24 DEBUG : dst/three.txt: md5 = d69855db922de0946f0c09c37743c5cc OK 2025/07/05 06:49:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:49:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:49:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:49:25 DEBUG : two: Unchanged skipping 2025/07/05 06:49:35 INFO : one: Copied (server-side copy) 2025/07/05 06:49:35 INFO : one: Deleted 2025/07/05 06:49:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:49:36 DEBUG : one: md5 = f67652a420111760f95dd1b46555e1d3 OK 2025/07/05 06:49:36 INFO : one: Copied (new) 2025/07/05 06:49:36 DEBUG : Waiting for deletions to finish 2025/07/05 06:49:46 INFO : three.txt: Copied (server-side copy) 2025/07/05 06:49:51 INFO : three.txt: Deleted 2025/07/05 06:49:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (108.94s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:49:57 DEBUG : dst/one: md5 = 9d7fd5d0bcccf991c2fe3d112ce78d30 OK 2025/07/05 06:49:57 DEBUG : dst/two: md5 = ed594a6a6c1572c9be6fd177f96e8ecb OK 2025/07/05 06:50:03 DEBUG : dst/three.txt: md5 = 4a595c0a2bbe86e4d6240fc3c66a606e OK 2025/07/05 06:50:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:50:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:50:03 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:50:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:50:09 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:50:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/backup" 2025/07/05 06:50:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup.bin" 2025/07/05 06:50:14 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup.bin: Auto set use_segments_container to true 2025/07/05 06:50:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup" 2025/07/05 06:50:20 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup: Auto set use_segments_container to true 2025/07/05 06:50:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:50:25 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:50:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:50:25 DEBUG : two: Unchanged skipping 2025/07/05 06:50:36 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:50:36 INFO : one: Deleted 2025/07/05 06:50:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:50:36 DEBUG : one: md5 = 6995a51785c0fc85ffaa8ff5853927cf OK 2025/07/05 06:50:36 INFO : one: Copied (new) 2025/07/05 06:50:36 DEBUG : Waiting for deletions to finish 2025/07/05 06:50:57 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:51:02 INFO : three.txt: Deleted 2025/07/05 06:51:02 INFO : three.txt: Moved into backup dir 2025/07/05 06:51:07 DEBUG : dst/three.txt: md5 = 22a60b86a93f9443dc6d52679670505d OK 2025/07/05 06:51:13 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:51:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:51:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:51:13 DEBUG : two: Unchanged skipping 2025/07/05 06:51:18 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:51:18 INFO : one: Deleted 2025/07/05 06:51:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:51:18 DEBUG : one: md5 = ba7954d8ba0969beb178ab6045640814 OK 2025/07/05 06:51:18 INFO : one: Copied (new) 2025/07/05 06:51:18 DEBUG : Waiting for deletions to finish 2025/07/05 06:51:28 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:51:34 INFO : three.txt: Deleted 2025/07/05 06:51:34 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (102.84s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:51:40 DEBUG : dst/one: md5 = 43fa9ebae8672f5177130866291bf5d9 OK 2025/07/05 06:51:40 DEBUG : dst/two: md5 = 70661c1bd8b868d47f20451db5d66576 OK 2025/07/05 06:51:46 DEBUG : dst/three.txt: md5 = e5e3498bc2d55095f7219d1d01b9fc0a OK 2025/07/05 06:51:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:51:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:51:51 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:51:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:51:57 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:52:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/backup" 2025/07/05 06:52:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup.bin" 2025/07/05 06:52:02 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup.bin: Auto set use_segments_container to true 2025/07/05 06:52:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/backup" 2025/07/05 06:52:08 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/backup: Auto set use_segments_container to true 2025/07/05 06:52:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:52:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:52:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:52:13 DEBUG : two: Unchanged skipping 2025/07/05 06:52:25 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/05 06:52:25 INFO : one: Deleted 2025/07/05 06:52:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:52:25 DEBUG : one: md5 = 64178019c83e00dc9b3d777830daefb3 OK 2025/07/05 06:52:25 INFO : one: Copied (new) 2025/07/05 06:52:25 DEBUG : Waiting for deletions to finish 2025/07/05 06:52:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/05 06:52:46 INFO : three.txt: Deleted 2025/07/05 06:52:46 INFO : three.txt: Moved into backup dir 2025/07/05 06:52:52 DEBUG : dst/three.txt: md5 = bd062f01d83f9b2557c1a278921d1364 OK 2025/07/05 06:52:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:52:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:52:52 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:52:52 DEBUG : two: Unchanged skipping 2025/07/05 06:52:57 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/05 06:52:58 INFO : one: Deleted 2025/07/05 06:52:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:52:58 DEBUG : one: md5 = 8f39ebff267158bd7fe14c61b339bac9 OK 2025/07/05 06:52:58 INFO : one: Copied (new) 2025/07/05 06:52:58 DEBUG : Waiting for deletions to finish 2025/07/05 06:53:08 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/05 06:53:13 INFO : three.txt: Deleted 2025/07/05 06:53:13 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (104.95s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:53:25 DEBUG : dst/one: md5 = 60051a3758d8fb6f44caaeb869514936 OK 2025/07/05 06:53:25 DEBUG : dst/two: md5 = e9ca6ac39a3ac68c0723cac341d06d84 OK 2025/07/05 06:53:30 DEBUG : dst/three.txt: md5 = 14dd33a0de19903048bcef14e0820356 OK 2025/07/05 06:53:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:53:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:53:31 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:53:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:53:37 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:53:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:53:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:53:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:53:42 DEBUG : two: Unchanged skipping 2025/07/05 06:53:53 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:53:53 INFO : one: Deleted 2025/07/05 06:53:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:53:53 DEBUG : one: md5 = a0ac7ff611b17ba5f31979db76028ec4 OK 2025/07/05 06:53:53 INFO : one: Copied (new) 2025/07/05 06:53:53 DEBUG : Waiting for deletions to finish 2025/07/05 06:54:03 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:54:08 INFO : three.txt: Deleted 2025/07/05 06:54:08 INFO : three.txt: Moved into backup dir 2025/07/05 06:54:14 DEBUG : dst/three.txt: md5 = 93d2b3a874ac79a331eb764c4b8fe736 OK 2025/07/05 06:54:20 DEBUG : one.bak: Excluded (Path Filter) 2025/07/05 06:54:20 DEBUG : one.bak: Excluded 2025/07/05 06:54:20 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/07/05 06:54:20 DEBUG : three.txt.bak: Excluded 2025/07/05 06:54:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:54:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for checks to finish 2025/07/05 06:54:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:54:20 DEBUG : two: Unchanged skipping 2025/07/05 06:54:25 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:54:25 INFO : one: Deleted 2025/07/05 06:54:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu/dst': Waiting for transfers to finish 2025/07/05 06:54:25 DEBUG : one: md5 = 6bce8b1edfa7d201326f4526f4700879 OK 2025/07/05 06:54:25 INFO : one: Copied (new) 2025/07/05 06:54:25 DEBUG : Waiting for deletions to finish 2025/07/05 06:54:31 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:54:36 INFO : three.txt: Deleted 2025/07/05 06:54:36 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (72.23s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:54:37 DEBUG : dst/one: md5 = 4cfbb8dec7988449460a75eeb78b8875 OK 2025/07/05 06:54:37 DEBUG : dst/two: md5 = 13b54f318e9a6d2c90c21d361adc33d7 OK 2025/07/05 06:54:43 DEBUG : dst/three.txt: md5 = 1c93a353d5df14cc9afd313aebe03378 OK 2025/07/05 06:54:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:54:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:54:48 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:54:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:54:54 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:54:59 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:55:09 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:55:10 INFO : one: Deleted 2025/07/05 06:55:10 DEBUG : one: md5 = 4505d024f00e0a9bcdab7156ab144acb OK 2025/07/05 06:55:10 INFO : one: Copied (new) 2025/07/05 06:55:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:55:10 DEBUG : two: Unchanged skipping 2025/07/05 06:55:10 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/07/05 06:55:20 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:55:25 INFO : three.txt: Deleted 2025/07/05 06:55:30 DEBUG : three.txt: md5 = f81177adc5527274907896c952f460f1 OK 2025/07/05 06:55:30 INFO : three.txt: Copied (new) 2025/07/05 06:55:41 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:55:47 INFO : one: Copied (server-side copy) to: one.bak 2025/07/05 06:55:47 INFO : one: Deleted 2025/07/05 06:55:47 DEBUG : one: md5 = c5a8e5a203cea6751d34381db21858ba OK 2025/07/05 06:55:47 INFO : one: Copied (new) 2025/07/05 06:55:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:55:47 DEBUG : two: Unchanged skipping 2025/07/05 06:55:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/07/05 06:55:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/07/05 06:55:57 INFO : three.txt: Deleted 2025/07/05 06:56:02 DEBUG : three.txt: md5 = c554390745e8d9acb007cf610cd860e0 OK 2025/07/05 06:56:02 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (91.94s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:56:09 DEBUG : dst/one: md5 = 9d1e7171c04d71f0cf309cd6a6423084 OK 2025/07/05 06:56:09 DEBUG : dst/two: md5 = 9ab3791547c1cc8663b632920a93a951 OK 2025/07/05 06:56:15 DEBUG : dst/three.txt: md5 = 7367f5388fc83ebc5e9ff0727634e474 OK 2025/07/05 06:56:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-misiyos1gutu/dst" 2025/07/05 06:56:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst.bin" 2025/07/05 06:56:15 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst.bin: Auto set use_segments_container to true 2025/07/05 06:56:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-misiyos1gutu/dst" 2025/07/05 06:56:36 DEBUG : Swift container crypt path rclone-test-misiyos1gutu/dst: Auto set use_segments_container to true 2025/07/05 06:56:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/07/05 06:56:57 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/05 06:56:57 INFO : one: Deleted 2025/07/05 06:56:57 DEBUG : one: md5 = c093e3e2529777a18141aed349e66226 OK 2025/07/05 06:56:57 INFO : one: Copied (new) 2025/07/05 06:56:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:56:57 DEBUG : two: Unchanged skipping 2025/07/05 06:57:02 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/07/05 06:57:18 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/05 06:57:23 INFO : three.txt: Deleted 2025/07/05 06:57:29 DEBUG : three.txt: md5 = f3b1d494b7b3f9eaf2b9a2b77d044671 OK 2025/07/05 06:57:29 INFO : three.txt: Copied (new) 2025/07/05 06:57:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/07/05 06:57:50 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/07/05 06:57:50 INFO : one: Deleted 2025/07/05 06:57:51 DEBUG : one: md5 = ec0b8ed507a91925b0ffb9a3a87cd0f6 OK 2025/07/05 06:57:51 INFO : one: Copied (new) 2025/07/05 06:57:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:57:51 DEBUG : two: Unchanged skipping 2025/07/05 06:57:51 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/07/05 06:58:06 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/07/05 06:58:11 INFO : three.txt: Deleted 2025/07/05 06:58:16 DEBUG : three.txt: md5 = 656d6482b483a213610c9cc2948470fb OK 2025/07/05 06:58:16 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (148.99s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:58:38 DEBUG : Testêé: md5 = 600689bdb6e0baf1291c9954adc3a78c OK 2025/07/05 06:58:39 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/07/05 06:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:58:39 DEBUG : Testêé: md5 = ee53f8b16401c9970b1e55e344772b82 OK 2025/07/05 06:58:39 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/07/05 06:58:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.70s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:58:40 DEBUG : existing: Need to transfer - File not found at Destination 2025/07/05 06:58:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:58:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:58:45 DEBUG : existing: md5 = cb6adc8deb6820245e9584e7330fa95e OK 2025/07/05 06:58:45 INFO : existing: Copied (new) 2025/07/05 06:58:45 DEBUG : Waiting for deletions to finish 2025/07/05 06:58:45 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/07/05 06:58:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:58:45 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/07/05 06:58:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:58:45 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': not deleting files as there were IO errors 2025/07/05 06:58:45 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (16.36s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:59:02 DEBUG : EXISTING: md5 = be1535b7ba5282aa004766874d1c965c OK 2025/07/05 06:59:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 06:59:02 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 06:59:02 DEBUG : existing: Unchanged skipping 2025/07/05 06:59:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 06:59:02 DEBUG : Waiting for deletions to finish 2025/07/05 06:59:02 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (11.34s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:2665: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.22s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:2704: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:2704: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" sync_test.go:2704: This test only runs on local --- PASS: TestMaxTransfer (0.49s) --- SKIP: TestMaxTransfer/Hard (0.21s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.18s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 06:59:08 DEBUG : both0: md5 = e8f36013468b9d2bab028d744b61716c OK 2025/07/05 06:59:14 DEBUG : only0: md5 = f38a40fd32dc13ee59e92782f820825b OK 2025/07/05 06:59:19 DEBUG : both1: md5 = 7e4861a3d693ffecd22d720f8ebf3c5c OK 2025/07/05 06:59:19 DEBUG : only1: md5 = aff354ca700d7bf65e4637c206e2022d OK 2025/07/05 06:59:20 DEBUG : both2: md5 = 391ee2a38a75f8a78c8789fbf7404f0a OK 2025/07/05 06:59:20 DEBUG : only2: md5 = 0b385255386232fc09b72e54a801b70e OK 2025/07/05 06:59:27 DEBUG : both3: md5 = ef745d2eed81e2580c5b7a612519e5c4 OK 2025/07/05 06:59:27 DEBUG : only3: md5 = 1f2fa6b0f168a437ec405cc701a532f5 OK 2025/07/05 06:59:32 DEBUG : both4: md5 = 4c93eecb27f22a3023b5a59b806d56ff OK 2025/07/05 06:59:33 DEBUG : only4: md5 = 5d73c19c742ceeac93882c3a2c3e5aa3 OK 2025/07/05 06:59:38 DEBUG : both5: md5 = cd1cce2798bed86d3985c49b49f1262b OK 2025/07/05 06:59:39 DEBUG : only5: md5 = e58f9c8d084bee0f0d0656075c19a9e3 OK 2025/07/05 06:59:44 DEBUG : both6: md5 = 367df514eb1326eade209865c53cd8e2 OK 2025/07/05 06:59:44 DEBUG : only6: md5 = aa3329adddc4e6d17e2e8bcd40e9627a OK 2025/07/05 06:59:45 DEBUG : both7: md5 = ccac2f026e991eadde9f08877b727358 OK 2025/07/05 06:59:45 DEBUG : only7: md5 = a6b3e334a524a957a74e5fe1793d6994 OK 2025/07/05 06:59:51 DEBUG : both8: md5 = 231cce8a0c7dc70fa89f665dca924cd9 OK 2025/07/05 06:59:56 DEBUG : only8: md5 = 16f0d3d0a3f0fbfeea9a9e36877fec88 OK 2025/07/05 06:59:56 DEBUG : both9: md5 = 830154762c57b407e0d84164f07f988f OK 2025/07/05 07:00:01 DEBUG : only9: md5 = 6199fb9490dd085638e2238fe28c84a7 OK 2025/07/05 07:00:07 DEBUG : both10: md5 = 9fa84d344258220ecf14f85fc3e280b5 OK 2025/07/05 07:00:12 DEBUG : only10: md5 = bbf87817424c02f02375c809cab69949 OK 2025/07/05 07:00:12 DEBUG : both11: md5 = b485d550a8ed35f005344c1b6d8b23b4 OK 2025/07/05 07:00:17 DEBUG : only11: md5 = e9990ac0ba570056ec06ac0b5babeeb3 OK 2025/07/05 07:00:18 DEBUG : both12: md5 = c60d4ffcb793ab41cbd608d0ca3deace OK 2025/07/05 07:00:23 DEBUG : only12: md5 = 55666933b2e4c109cf0319d99a1c690f OK 2025/07/05 07:00:23 DEBUG : both13: md5 = 65a6c119f3f81e095a2fb75b22f29e74 OK 2025/07/05 07:00:29 DEBUG : only13: md5 = effe56c6d013e0d282ca26b7e62eb4f5 OK 2025/07/05 07:00:29 DEBUG : both14: md5 = d7689d5f7bf63d173ce5e259e6f01330 OK 2025/07/05 07:00:29 DEBUG : only14: md5 = edcfb4efbe5b5468aeb0a2d358ba1e2a OK 2025/07/05 07:00:35 DEBUG : both15: md5 = dc1c981d89cc8ff074423382f77e4378 OK 2025/07/05 07:00:35 DEBUG : only15: md5 = 94214ab3ce96dd041fcf22849960bdc0 OK 2025/07/05 07:00:40 DEBUG : both16: md5 = f2e7eb218cc0b45b75daa2426762045e OK 2025/07/05 07:00:40 DEBUG : only16: md5 = b05a3afd6286c7628399327c139f0c9f OK 2025/07/05 07:00:46 DEBUG : both17: md5 = 2245e644607e56f5108cc4690d09033e OK 2025/07/05 07:00:46 DEBUG : only17: md5 = ab6aa9b00ffba817ff48fed42df09ab3 OK 2025/07/05 07:00:51 DEBUG : both18: md5 = 95cd3ee44b0b2816ba03de5c38f3b50b OK 2025/07/05 07:00:57 DEBUG : only18: md5 = 6ed1e39dc270e19020e2c7bf18f9306b OK 2025/07/05 07:00:57 DEBUG : both19: md5 = 7f200eb6f6f217fa134d87736589d274 OK 2025/07/05 07:01:03 DEBUG : only19: md5 = 7b660c8a95162149cb7cae5042e6b125 OK 2025/07/05 07:01:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:01:35 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both10: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both0: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both11: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both12: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both13: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both14: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both15: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both17: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both18: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both19: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both2: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both1: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both3: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both5: Unchanged skipping 2025/07/05 07:01:35 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:35 DEBUG : both7: Unchanged skipping 2025/07/05 07:01:40 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:40 DEBUG : both16: Unchanged skipping 2025/07/05 07:01:40 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:40 DEBUG : both9: Unchanged skipping 2025/07/05 07:01:40 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:40 DEBUG : both4: Unchanged skipping 2025/07/05 07:01:40 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:40 DEBUG : both6: Unchanged skipping 2025/07/05 07:01:40 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:01:40 DEBUG : both8: Unchanged skipping 2025/07/05 07:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:01:40 DEBUG : Waiting for deletions to finish 2025/07/05 07:01:41 INFO : only1: Deleted 2025/07/05 07:01:41 INFO : only4: Deleted 2025/07/05 07:01:41 INFO : only5: Deleted 2025/07/05 07:01:41 INFO : only7: Deleted 2025/07/05 07:01:45 INFO : only8: Deleted 2025/07/05 07:01:46 INFO : only16: Deleted 2025/07/05 07:01:46 INFO : only6: Deleted 2025/07/05 07:01:46 INFO : only9: Deleted 2025/07/05 07:01:46 INFO : only14: Deleted 2025/07/05 07:01:46 INFO : only15: Deleted 2025/07/05 07:01:47 INFO : only17: Deleted 2025/07/05 07:01:50 INFO : only0: Deleted 2025/07/05 07:01:50 INFO : only11: Deleted 2025/07/05 07:01:51 INFO : only2: Deleted 2025/07/05 07:01:51 INFO : only3: Deleted 2025/07/05 07:01:51 INFO : only13: Deleted 2025/07/05 07:01:52 INFO : only19: Deleted 2025/07/05 07:01:56 INFO : only10: Deleted 2025/07/05 07:01:56 INFO : only12: Deleted 2025/07/05 07:02:01 INFO : only18: Deleted 2025/07/05 07:02:01 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (275.43s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:03:44 DEBUG : both0: md5 = d0dfdd0ab88d3b07f05a612a44505fdc OK 2025/07/05 07:03:49 DEBUG : only0: md5 = 778ac478abe257fa2de3a1340601d502 OK 2025/07/05 07:03:54 DEBUG : both1: md5 = d17cf521080ab881cf209477aa77da93 OK 2025/07/05 07:03:54 DEBUG : only1: md5 = a02eda43ecc631c5ca4d8f931ecb7b29 OK 2025/07/05 07:03:55 DEBUG : both2: md5 = a093dec0d4df44b6e51eecabdfb87797 OK 2025/07/05 07:03:55 DEBUG : only2: md5 = 6cc7c3d9f09af03b5173466c69779a70 OK 2025/07/05 07:04:00 DEBUG : both3: md5 = d8324275fa1c024fe45ed2bdff873134 OK 2025/07/05 07:04:00 DEBUG : only3: md5 = a74462db724543570095dc8267098174 OK 2025/07/05 07:04:05 DEBUG : both4: md5 = 52cd5ba2c3c2e7b71394ae530e2b0bf8 OK 2025/07/05 07:04:05 DEBUG : only4: md5 = b57c8e2c32aa461b79549640a4c9e542 OK 2025/07/05 07:04:10 DEBUG : both5: md5 = a2c67b5abdf32ff02a0243c041a63f43 OK 2025/07/05 07:04:11 DEBUG : only5: md5 = 0c9af77f5febe128a7f2d50450296060 OK 2025/07/05 07:04:16 DEBUG : both6: md5 = 7701191121bb33686159bd5934cff4b7 OK 2025/07/05 07:04:16 DEBUG : only6: md5 = c17fb29d9a86cff81e3c9c321cb0db76 OK 2025/07/05 07:04:16 DEBUG : both7: md5 = c864e43a9a9e6be1bbc7e23ddb585716 OK 2025/07/05 07:04:17 DEBUG : only7: md5 = b82eab8d93b77f349b97558f46d4bbcd OK 2025/07/05 07:04:22 DEBUG : both8: md5 = a4fcfee9ff9e88910b1c38e8c02cde25 OK 2025/07/05 07:04:27 DEBUG : only8: md5 = c4a56dc862825b0e20be96f343b35103 OK 2025/07/05 07:04:27 DEBUG : both9: md5 = fcaae8cf6aa716b23b3fbda710933a9c OK 2025/07/05 07:04:32 DEBUG : only9: md5 = d3f0bc2b387828874f87bb1e1ae40a12 OK 2025/07/05 07:04:37 DEBUG : both10: md5 = 8f96f1727a1f36dc3501337713350e57 OK 2025/07/05 07:04:42 DEBUG : only10: md5 = 170cb49658a68ed0dfe09258a1443471 OK 2025/07/05 07:04:43 DEBUG : both11: md5 = fb9681c63f5409b979bbcae94e802d11 OK 2025/07/05 07:04:48 DEBUG : only11: md5 = 8171e6feea4e6f94537c5368dc86ef42 OK 2025/07/05 07:04:48 DEBUG : both12: md5 = aa67431e7bfd885dedfc9b6de196da60 OK 2025/07/05 07:04:53 DEBUG : only12: md5 = 4c01d223a307399ef87ea2b5ab989e41 OK 2025/07/05 07:04:53 DEBUG : both13: md5 = aa5102666e747c555318a38354746b2c OK 2025/07/05 07:04:58 DEBUG : only13: md5 = 4cddba4386c575a37419698e521184fc OK 2025/07/05 07:04:59 DEBUG : both14: md5 = 003bacb43fabccad4e7beab46a321e56 OK 2025/07/05 07:04:59 DEBUG : only14: md5 = eb335d8fc06f0ee60aa156142958f85e OK 2025/07/05 07:05:04 DEBUG : both15: md5 = f3ced77e3c2bfe0ba4bdb70aab1a7d5f OK 2025/07/05 07:05:04 DEBUG : only15: md5 = 2e579d7c42c3f854586e623c49f546b6 OK 2025/07/05 07:05:09 DEBUG : both16: md5 = 7fdbb978eeb641def0dfaed79db0c9a8 OK 2025/07/05 07:05:09 DEBUG : only16: md5 = fab825c2e5ea6f968e7713ce8ae022f9 OK 2025/07/05 07:05:15 DEBUG : both17: md5 = 4ba8dcdc4be3ae1eaa3135c1b6dde7a9 OK 2025/07/05 07:05:15 DEBUG : only17: md5 = 646fd2c15b45da27e31247f5208e2bfe OK 2025/07/05 07:05:20 DEBUG : both18: md5 = 8ec5608a3f18c019ce0092bb54159bf3 OK 2025/07/05 07:05:25 DEBUG : only18: md5 = 48eb99fd8ae322a5f24226756cd893a5 OK 2025/07/05 07:05:25 DEBUG : both19: md5 = 8817b381ec18f59a4fc575c8dd552374 OK 2025/07/05 07:05:30 DEBUG : only19: md5 = b842b9b8975959c5039ebac676015398 OK 2025/07/05 07:06:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:06:05 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:05 DEBUG : both10: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both0: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both11: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both12: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both13: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both14: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both15: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both18: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both19: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both1: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both2: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both3: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both4: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both5: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both7: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both8: Unchanged skipping 2025/07/05 07:06:06 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:06 DEBUG : both9: Unchanged skipping 2025/07/05 07:06:06 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:06 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/07/05 07:06:11 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:11 DEBUG : both16: Unchanged skipping 2025/07/05 07:06:11 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:11 DEBUG : both17: Unchanged skipping 2025/07/05 07:06:11 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:06:11 DEBUG : both6: Unchanged skipping 2025/07/05 07:06:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:06:11 DEBUG : only10: md5 = cc58c91539b93c883181438b0a7cb22c OK 2025/07/05 07:06:11 INFO : only10: Copied (replaced existing) 2025/07/05 07:06:11 DEBUG : only11: md5 = c8ff16e94159ee02352702a3c743d8b2 OK 2025/07/05 07:06:11 INFO : only11: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only12: md5 = 36f7d1e7c51845da0641fbc798c63918 OK 2025/07/05 07:06:12 INFO : only12: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only0: md5 = 9abcd8d89c57b8fcfe6a3b49f364eafe OK 2025/07/05 07:06:12 INFO : only0: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only14: md5 = 9b14a911d7a26befe03e50deab3c04ff OK 2025/07/05 07:06:12 INFO : only14: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only15: md5 = 4462b985d1edde9928f539fa11f5dd63 OK 2025/07/05 07:06:12 INFO : only15: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only16: md5 = 50d572229793562abb08f0584890879c OK 2025/07/05 07:06:12 INFO : only16: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only17: md5 = ebe65fc269cd672ad147cf4c20eedffb OK 2025/07/05 07:06:12 INFO : only17: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only1: md5 = c29031507ff95998da4fdde3831cceeb OK 2025/07/05 07:06:12 INFO : only1: Copied (replaced existing) 2025/07/05 07:06:12 DEBUG : only2: md5 = 548495ba65ca1044baadced184589cd1 OK 2025/07/05 07:06:12 INFO : only2: Copied (replaced existing) 2025/07/05 07:06:13 DEBUG : only3: md5 = 6b334dfd6c2afe1486cb8d3567377fe7 OK 2025/07/05 07:06:13 INFO : only3: Copied (replaced existing) 2025/07/05 07:06:13 DEBUG : only4: md5 = 8e647a7e2e572415dfe32fc845565064 OK 2025/07/05 07:06:13 INFO : only4: Copied (replaced existing) 2025/07/05 07:06:13 DEBUG : only5: md5 = 422e8b8a53ef76aa8f997828396ac7e5 OK 2025/07/05 07:06:13 INFO : only5: Copied (replaced existing) 2025/07/05 07:06:13 DEBUG : only6: md5 = a0145ba0d37733547f78cdc0cfad0175 OK 2025/07/05 07:06:13 INFO : only6: Copied (replaced existing) 2025/07/05 07:06:13 DEBUG : only7: md5 = c14f79614fb2e2ad37c2804b4e91d315 OK 2025/07/05 07:06:13 INFO : only7: Copied (replaced existing) 2025/07/05 07:06:17 DEBUG : only13: md5 = 31435df3269a6cee8fc7154340d8d118 OK 2025/07/05 07:06:17 INFO : only13: Copied (replaced existing) 2025/07/05 07:06:17 DEBUG : only18: md5 = 873b08c461f85da22ff6581d7ad547e5 OK 2025/07/05 07:06:17 INFO : only18: Copied (replaced existing) 2025/07/05 07:06:17 DEBUG : only19: md5 = 3475f05295a892bf5c3d579c487420fd OK 2025/07/05 07:06:17 INFO : only19: Copied (replaced existing) 2025/07/05 07:06:19 DEBUG : only8: md5 = 0f9b1a8a0f00fd7a8ae995f6e156231c OK 2025/07/05 07:06:19 INFO : only8: Copied (replaced existing) 2025/07/05 07:06:22 DEBUG : only9: md5 = 9dab7c01576758bd82e65bc242abf1a5 OK 2025/07/05 07:06:22 INFO : only9: Copied (replaced existing) 2025/07/05 07:06:22 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (332.22s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:09:16 DEBUG : empty_dir: Making directory with metadata 2025/07/05 07:09:16 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 07:09:16 DEBUG : empty_on_remote: Making directory with metadata 2025/07/05 07:09:16 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 07:09:16 INFO : empty_on_remote: Making directory 2025/07/05 07:09:16 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/07/05 07:09:21 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:21 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:21 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:21 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (16.85s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:09:33 DEBUG : empty_dir: Making directory with metadata 2025/07/05 07:09:33 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 07:09:33 DEBUG : empty_on_remote: Making directory with metadata 2025/07/05 07:09:33 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/07/05 07:09:33 INFO : empty_on_remote: Making directory 2025/07/05 07:09:33 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/07/05 07:09:38 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:38 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:38 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:38 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:38 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (16.57s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:09:49 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/05 07:09:49 INFO : sub dir: Making directory 2025/07/05 07:09:49 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (26.72s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:10:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/07/05 07:10:16 INFO : sub dir: Making directory 2025/07/05 07:10:16 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithoutEmptyDirs (21.49s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:10:43 DEBUG : dir1/0000-abcdefg.txt: md5 = 4571ab0cc74915911b9e745f8f1bd010 OK 2025/07/05 07:10:43 DEBUG : dir1/0001-bcdefgh.txt: md5 = 8ccd7b11bde2111d610f87476e273b02 OK 2025/07/05 07:10:44 DEBUG : dir1/0002-cdefghi.txt: md5 = a62ef347e1bd747224b3b064d365bf06 OK 2025/07/05 07:10:49 DEBUG : dir1/0003-defghij.txt: md5 = 0b4a25c2a38080bf7b99521b8edcd303 OK 2025/07/05 07:10:55 DEBUG : dir1/0004-efghijk.txt: md5 = c4331c6acdae1a2120b82906c9c90be8 OK 2025/07/05 07:10:55 DEBUG : dir1/0005-fghijkl.txt: md5 = cd4811ad6766be88f8ac60b0e7d2c0fc OK 2025/07/05 07:11:00 DEBUG : dir1/0006-ghijklm.txt: md5 = e1bc00b2f3c0b5e3346ffcf66834583e OK 2025/07/05 07:11:01 DEBUG : dir1/0007-1234567.txt: md5 = 6620a4350a0c0f34f0b75597618db5f8 OK 2025/07/05 07:11:06 DEBUG : dir1/0008-2345678.txt: md5 = 66aae98b03bfa5c139cf2a26a71f7789 OK 2025/07/05 07:11:12 DEBUG : dir1/0009-3456789.txt: md5 = b8dd6e8cabd6c49b89922ecca4223bec OK 2025/07/05 07:11:17 DEBUG : dir1/0010-456789.txt: md5 = 180355cf8857eabeebc1b7ce3133ba56 OK 2025/07/05 07:11:17 DEBUG : dir1/0011-56789;.txt: md5 = 37d54f299d43693c55d8fa79d1203909 OK 2025/07/05 07:11:23 DEBUG : dir1/0012-6789;.txt: md5 = e7219db6f70500083a94af36189f3238 OK 2025/07/05 07:11:29 DEBUG : dir1/0013-789;=.txt: md5 = 33b8618133021ab7fa309a33befc55da OK 2025/07/05 07:11:34 DEBUG : dir1/0014-89;=.txt: md5 = 8575a7ef1249ea56c351f8ce2f2333be OK 2025/07/05 07:11:35 DEBUG : dir1/0015-9;=.txt: md5 = 717f4e8fdf023591e332cb840af220b4 OK 2025/07/05 07:11:40 DEBUG : apple: md5 = 57aaeabb7dc36202a9e247a650233003 OK 2025/07/05 07:11:40 DEBUG : banana: md5 = fc9be39b389bc5cb456d7ea727b12371 OK 2025/07/05 07:11:46 DEBUG : appleappleapplebanana: md5 = a8d63010f5c0d5808a047c8998ae8652 OK 2025/07/05 07:11:46 DEBUG : splitbananasplit: md5 = f500b25d68fc9d16bad0b13b6ffb0b9d OK 2025/07/05 07:11:46 DEBUG : Waiting for deletions to finish 2025/07/05 07:11:46 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:11:46 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/05 07:12:07 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:07 DEBUG : apple: Unchanged skipping 2025/07/05 07:12:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:12:08 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : banana: Unchanged skipping 2025/07/05 07:12:08 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : splitbananasplit: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/05 07:12:08 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:08 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:12:13 DEBUG : Waiting for deletions to finish 2025/07/05 07:12:13 INFO : There was nothing to transfer 2025/07/05 07:12:13 DEBUG : Waiting for deletions to finish 2025/07/05 07:12:13 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/05 07:12:13 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : splitbananasplit: Unchanged skipping 2025/07/05 07:12:13 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : banana: Unchanged skipping 2025/07/05 07:12:13 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/05 07:12:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:12:13 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/05 07:12:13 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:13 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/05 07:12:18 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:18 DEBUG : apple: Unchanged skipping 2025/07/05 07:12:18 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:18 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/05 07:12:18 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:18 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/05 07:12:18 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:18 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/05 07:12:18 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:12:18 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/05 07:12:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:12:18 DEBUG : Waiting for deletions to finish 2025/07/05 07:12:18 INFO : There was nothing to transfer 2025/07/05 07:12:18 DEBUG : Waiting for deletions to finish 2025/07/05 07:12:18 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:12:18 DEBUG : splitbananasplit: Excluded (Path Filter) === RUN TestTransform/NFD run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu'", Local "Local file system at /tmp/rclone540121788", Modify Window "1ns" 2025/07/05 07:13:53 DEBUG : dir1/0000-abcdefg.txt: md5 = 3b5282648c8efa010d0c77503d7b7fcd OK 2025/07/05 07:13:53 DEBUG : dir1/0001-bcdefgh.txt: md5 = 0921719047c7d05c6e5c33781c15a4a1 OK 2025/07/05 07:13:53 DEBUG : dir1/0002-cdefghi.txt: md5 = 43ad1c5d9c38a9f74ce7d47fc416c18a OK 2025/07/05 07:13:59 DEBUG : dir1/0003-defghij.txt: md5 = a64690e1b960c8f0dfde608c6c05fb30 OK 2025/07/05 07:14:04 DEBUG : dir1/0004-efghijk.txt: md5 = 7b39f4731fd1a0e637d1f0121d50ce04 OK 2025/07/05 07:14:04 DEBUG : dir1/0005-fghijkl.txt: md5 = 3099dc4b6a435a839ebf60c4402e7bec OK 2025/07/05 07:14:09 DEBUG : dir1/0006-ghijklm.txt: md5 = e874c699d7c6071006b30adb549dea38 OK 2025/07/05 07:14:09 DEBUG : dir1/0007-1234567.txt: md5 = fd6b8923c622a0181f112c9ce9a167f0 OK 2025/07/05 07:14:15 DEBUG : dir1/0008-2345678.txt: md5 = f92916ac4c40e9249474d1ed03ca128d OK 2025/07/05 07:14:20 DEBUG : dir1/0009-3456789.txt: md5 = 030624dacc9288ddf5c5b5078b4839eb OK 2025/07/05 07:14:25 DEBUG : dir1/0010-456789.txt: md5 = 51a23136c21f2d131a0bc26bb69aec4a OK 2025/07/05 07:14:25 DEBUG : dir1/0011-56789;.txt: md5 = 31d28cda2caf2e147160ef8a26474b47 OK 2025/07/05 07:14:30 DEBUG : dir1/0012-6789;.txt: md5 = 8d8065093950e10ca3711b77048c6da0 OK 2025/07/05 07:14:36 DEBUG : dir1/0013-789;=.txt: md5 = 3fc83eb395ed0437f4fd6fc062f90c4b OK 2025/07/05 07:14:41 DEBUG : dir1/0014-89;=.txt: md5 = 71e4dcad10643a5d13096a42a7a45410 OK 2025/07/05 07:14:41 DEBUG : dir1/0015-9;=.txt: md5 = 7abe0a8a9bc2120dd1c18cf15dcdbd40 OK 2025/07/05 07:14:46 DEBUG : apple: md5 = 2cf509f8f0b4bf017aef0966008febb4 OK 2025/07/05 07:14:46 DEBUG : banana: md5 = 442de442eccddf31d676a1ea69a80590 OK 2025/07/05 07:14:52 DEBUG : appleappleapplebanana: md5 = f53557e6b286928bcf40d71a8df07e5b OK 2025/07/05 07:14:52 DEBUG : splitbananasplit: md5 = b8e99dbde1c69342413d9b78a393caff OK 2025/07/05 07:14:52 DEBUG : Waiting for deletions to finish 2025/07/05 07:14:52 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:14:52 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/05 07:14:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:14:59 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : banana: Unchanged skipping 2025/07/05 07:14:59 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : splitbananasplit: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/05 07:14:59 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:14:59 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/05 07:15:03 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:03 DEBUG : apple: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:15:04 DEBUG : Waiting for deletions to finish 2025/07/05 07:15:04 INFO : There was nothing to transfer 2025/07/05 07:15:04 DEBUG : Waiting for deletions to finish 2025/07/05 07:15:04 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/07/05 07:15:04 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : banana: Unchanged skipping 2025/07/05 07:15:04 DEBUG : appleappleapplebanana: Unchanged skipping 2025/07/05 07:15:04 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : splitbananasplit: Unchanged skipping 2025/07/05 07:15:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for checks to finish 2025/07/05 07:15:04 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:04 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/07/05 07:15:04 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/07/05 07:15:09 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:09 DEBUG : apple: Unchanged skipping 2025/07/05 07:15:09 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:09 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/07/05 07:15:09 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:09 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/07/05 07:15:09 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/07/05 07:15:09 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/07/05 07:15:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-misiyos1gutu': Waiting for transfers to finish 2025/07/05 07:15:09 DEBUG : Waiting for deletions to finish 2025/07/05 07:15:09 INFO : There was nothing to transfer 2025/07/05 07:15:09 DEBUG : Waiting for deletions to finish 2025/07/05 07:15:09 DEBUG : apple: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : banana: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/07/05 07:15:09 DEBUG : splitbananasplit: Excluded (Path Filter) panic: test timed out after 1h0m0s running tests: TestTransform (4m54s) TestTransform/NFD (1m44s) goroutine 15463 [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, 5 minutes]: testing.(*T).Run(0xc001a13dc0, {0x2693393?, 0xc0000bfad8?}, 0x27bfa10) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc001a13dc0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc001a13dc0, 0xc0000bfc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc001a03110, {0x3e79440, 0x6c, 0x6c}, {0xedffaba04?, 0x3e8f400?, 0x3e8f400?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0006cdb80) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0006cdb80) /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:261 +0xa9 goroutine 4 [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 5 [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 15181 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b060, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001ef000?, 0xc000c91980?, 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(0xc0001ef000, {0xc000c91980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001ef000, {0xc000c91980?, 0x1973?, 0xc0019782c0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0003b03c8, {0xc000c91980?, 0xc000187dd0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004ce6a8, {0xc000c91980?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0003823f0, {0xc000c91980?, 0x1973?, 0x4bc957?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000187eb8, {0x2acd440, 0xc0003823f0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000187c08, {0x7e5ce87149f0, 0xc0004ce6a8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000187c08, 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(0xc000187c08, {0xc00052e000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e6fc0, {0xc00052e000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001fa23c0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001fa23c0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e6fc0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15148 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15168 [IO wait]: internal/poll.runtime_pollWait(0x7e5ce86d51a0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001eed80?, 0xc0001d4600?, 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(0xc0001eed80, {0xc0001d4600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001eed80, {0xc0001d4600?, 0x1973?, 0xc0000eb420?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0003b03b8, {0xc0001d4600?, 0xc0001f5a50?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004ce570, {0xc0001d4600?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382348, {0xc0001d4600?, 0x1973?, 0xc001a32230?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0001f5b38, {0x2acd440, 0xc000382348}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0001f5888, {0x7e5ce87149f0, 0xc0004ce570}, 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, {0xc000a80000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e6c60, {0xc000a80000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000392360) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000392360, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e6c60) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15089 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15233 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98a9d0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035aa00?, 0xc000716000?, 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(0xc00035aa00, {0xc000716000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035aa00, {0xc000716000?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0007e0020, {0xc000716000?, 0xc00046c240?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0022a4330, {0xc000716000?, 0x7e5ce86c2128?, 0x7e5d32e87a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0022a4e88, {0xc000716000?, 0xc00043e000?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000642d38, {0x2acd440, 0xc0022a4e88}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000642a88, {0x7e5ce87149f0, 0xc0022a4330}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000642a88, 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(0xc000642a88, {0xc00086e000, 0x1000, 0x7e5d32e87a78?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000aa1440, {0xc00086e000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a870e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a870e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000aa1440) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15196 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14425 [select]: net/http.(*persistConn).writeLoop(0xc000a82000) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14387 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14424 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b178, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc002192100?, 0xc000947980?, 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(0xc002192100, {0xc000947980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc002192100, {0xc000947980?, 0x1973?, 0xc0002fa5a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e398, {0xc000947980?, 0xc0006728d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f925b8, {0xc000947980?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382f78, {0xc000947980?, 0x1973?, 0xc0000bb9d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006729b8, {0x2acd440, 0xc000382f78}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000672708, {0x7e5ce87149f0, 0xc001f925b8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000672708, 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(0xc000672708, {0xc0005b0000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000a82000, {0xc0005b0000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001c60b40) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001c60b40, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000a82000) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14387 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15152 [IO wait]: internal/poll.runtime_pollWait(0x7e5ce86d52b8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035a800?, 0xc0001d2c80?, 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(0xc00035a800, {0xc0001d2c80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035a800, {0xc0001d2c80?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001a0e268, {0xc0001d2c80?, 0x5?, 0x3eaece8?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000383ea8, {0xc0001d2c80?, 0x7e5ce975ce08?, 0x7e5d32e87f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0000cb9e0, {0xc0001d2c80?, 0x3eb5260?, 0xc00079e9c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000673438, {0x2acd440, 0xc0000cb9e0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000673188, {0x7e5ce87149f0, 0xc000383ea8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000673188, 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(0xc000673188, {0xc0008e6000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000aa0d80, {0xc0008e6000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a86ba0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a86ba0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000aa0d80) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15116 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15251 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98af48, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001d42180?, 0xc000719300?, 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(0xc001d42180, {0xc000719300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001d42180, {0xc000719300?, 0x20c7800?, 0xc001b91b10?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e368, {0xc000719300?, 0x0?, 0x2?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000518360, {0xc000719300?, 0x7e5ce975ce08?, 0x7e5d32e87f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0000ca888, {0xc000719300?, 0x0?, 0x0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000370638, {0x2acd440, 0xc0000ca888}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000370388, {0x7e5ce87149f0, 0xc000518360}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000370388, 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(0xc000370388, {0xc000870000, 0x1000, 0x7e5d32e87f30?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000aa1200, {0xc000870000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a87260) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a87260, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000aa1200) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15200 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15173 [select]: net/http.(*persistConn).writeLoop(0xc0006e6d80) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15140 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15211 [IO wait]: internal/poll.runtime_pollWait(0x7e5ce86d53d0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035a880?, 0xc000c90000?, 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(0xc00035a880, {0xc000c90000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035a880, {0xc000c90000?, 0x1973?, 0xc001cf6f00?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001a0e2b0, {0xc000c90000?, 0xc0006736d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0022a4378, {0xc000c90000?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382ee8, {0xc000c90000?, 0x1973?, 0xc001a32d70?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006737b8, {0x2acd440, 0xc000382ee8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000673508, {0x7e5ce87149f0, 0xc0022a4378}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000673508, 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(0xc000673508, {0xc00045c000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e70e0, {0xc00045c000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0008462a0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0008462a0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e70e0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15151 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15172 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98ae30, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035a700?, 0xc000949300?, 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(0xc00035a700, {0xc000949300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035a700, {0xc000949300?, 0x1973?, 0xc0000eb8a0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e350, {0xc000949300?, 0xc0006421d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f922a0, {0xc000949300?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382f18, {0xc000949300?, 0x1973?, 0xc0007659c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006422b8, {0x2acd440, 0xc000382f18}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000642008, {0x7e5ce87149f0, 0xc001f922a0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000642008, 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(0xc000642008, {0xc000b5a000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e6d80, {0xc000b5a000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a876e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a876e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e6d80) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15140 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15253 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b3a8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00035ab80?, 0xc000808000?, 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(0xc00035ab80, {0xc000808000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc00035ab80, {0xc000808000?, 0x1973?, 0xc0000eaae0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e908, {0xc000808000?, 0xc0006d41d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000518930, {0xc000808000?, 0x7e5ce86c2128?, 0x7e5d32e87a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0022a5ad0, {0xc000808000?, 0x1973?, 0xc0008009d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006d42b8, {0x2acd440, 0xc0022a5ad0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006d4008, {0x7e5ce87149f0, 0xc000518930}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006d4008, 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(0xc0006d4008, {0xc000872000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000aa1680, {0xc000872000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a873e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a873e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000aa1680) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15206 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15462 [select]: net/http.(*persistConn).roundTrip(0xc000aa1680, 0xc000857220) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc00043e000, 0xc000293180) /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(0xc000488070, 0xc000293180) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000293180, {0x2acbec0, 0xc000488070}, {0xc000099d01?, 0x2aca340?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001a0b380, 0xc000293180, {0x41013e?, 0x482592?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001a0b380, 0xc000293180) /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 14721 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 15183 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98ac00, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001d42680?, 0xc000c93300?, 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(0xc001d42680, {0xc000c93300, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001d42680, {0xc000c93300?, 0xc0006e6fc0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc001a0e2c0, {0xc000c93300?, 0xc0006ea5a0?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0022a43c0, {0xc000c93300?, 0x7e5ce9800058?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000518348, {0xc000c93300?, 0xc00043e000?, 0xc00001a960?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000673b38, {0x2acd440, 0xc000518348}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000673888, {0x7e5ce87149f0, 0xc0022a43c0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000673888, 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(0xc000673888, {0xc000530000, 0x1000, 0x7e5d32e87108?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000521680, {0xc000530000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001fa2540) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001fa2540, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000521680) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15163 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15246 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98aae8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc002192280?, 0xc00094c600?, 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(0xc002192280, {0xc00094c600, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc002192280, {0xc00094c600?, 0x7e5d32e87a78?, 0x3900000018?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0007e0010, {0xc00094c600?, 0xc000388360?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0022a42b8, {0xc00094c600?, 0x7e5ce975ce08?, 0x7e5d32e87f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0000ca288, {0xc00094c600?, 0xc00043e000?, 0xa0715c?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006429b8, {0x2acd440, 0xc0000ca288}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000642708, {0x7e5ce87149f0, 0xc0022a42b8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000642708, 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(0xc000642708, {0xc0007fe000, 0x1000, 0x7e5d32e87f30?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc000aa10e0, {0xc0007fe000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a0cc60) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a0cc60, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc000aa10e0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15190 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15254 [select]: net/http.(*persistConn).writeLoop(0xc000aa1680) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15206 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 13870 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001a443c0, {0x2af2a68, 0xc001a32d20}) /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 13720 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 15252 [select]: net/http.(*persistConn).writeLoop(0xc000aa1200) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15200 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15212 [select]: net/http.(*persistConn).writeLoop(0xc0006e70e0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15151 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15182 [select]: net/http.(*persistConn).writeLoop(0xc0006e6fc0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15148 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 14015 [chan receive, 3 minutes]: testing.(*T).Run(0xc000906c40, {0x26779d5?, 0x41bb05?}, 0xc0008565a0) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/fs/sync.TestTransform(0xc000906c40) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:95 +0x62a testing.tRunner(0xc000906c40, 0x27bfa10) /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 15282 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b6f0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001ee100?, 0xc00071ac80?, 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(0xc0001ee100, {0xc00071ac80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001ee100, {0xc00071ac80?, 0x2000?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00014e380, {0xc00071ac80?, 0x5?, 0x3eaece8?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0005183f0, {0xc00071ac80?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382e70, {0xc00071ac80?, 0x43d367?, 0xc0008849c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000370d38, {0x2acd440, 0xc000382e70}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000370a88, {0x7e5ce87149f0, 0xc0005183f0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000370a88, 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(0xc000370a88, {0xc00088c000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e7560, {0xc00088c000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a0cf60) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a0cf60, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e7560) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15137 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15180 [select]: net/http.(*persistConn).writeLoop(0xc0006e6ea0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15145 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15250 [select]: net/http.(*persistConn).writeLoop(0xc000aa1440) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15196 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15153 [select]: net/http.(*persistConn).writeLoop(0xc000aa0d80) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15116 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15169 [select]: net/http.(*persistConn).writeLoop(0xc0006e6c60) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15089 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15249 [select]: net/http.(*persistConn).writeLoop(0xc0000d8000) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15220 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 2077 [chan receive, 53 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 2109 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 15170 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b4c0, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001d42300?, 0xc000946000?, 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(0xc001d42300, {0xc000946000, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001d42300, {0xc000946000?, 0x1973?, 0xc0000eb660?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0007e0070, {0xc000946000?, 0xc000472550?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0005184c8, {0xc000946000?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382f00, {0xc000946000?, 0x1973?, 0xc0008809c8?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000472638, {0x2acd440, 0xc000382f00}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000472388, {0x7e5ce87149f0, 0xc0005184c8}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000472388, 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(0xc000472388, {0xc000b58000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0000d8240, {0xc000b58000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a86f00) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a86f00, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0000d8240) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15124 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15248 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98b290, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001d42080?, 0xc000717980?, 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(0xc001d42080, {0xc000717980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001d42080, {0xc000717980?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0003b0358, {0xc000717980?, 0xc000636480?, 0x5?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001f93008, {0xc000717980?, 0x7e5ce975ce08?, 0x7e5d32e87f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0000ca750, {0xc000717980?, 0xc00043e000?, 0xc00001aaf0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000672638, {0x2acd440, 0xc0000ca750}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000672388, {0x7e5ce87149f0, 0xc001f93008}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000672388, 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(0xc000672388, {0xc000888000, 0x1000, 0x7e5d32e87f30?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0000d8000, {0xc000888000?, 0x412565?, 0x68?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001a0cde0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001a0cde0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0000d8000) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15220 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 15171 [select]: net/http.(*persistConn).writeLoop(0xc0000d8240) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15124 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15283 [select]: net/http.(*persistConn).writeLoop(0xc0006e7560) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15137 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15247 [select]: net/http.(*persistConn).writeLoop(0xc000aa10e0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15190 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15184 [select]: net/http.(*persistConn).writeLoop(0xc000521680) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 15163 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 15179 [IO wait]: internal/poll.runtime_pollWait(0x7e5cea98a8b8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0001eee80?, 0xc00094ac80?, 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(0xc0001eee80, {0xc00094ac80, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0001eee80, {0xc00094ac80?, 0x1973?, 0xc0000ebb60?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0007e0330, {0xc00094ac80?, 0xc0004728d0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000519818, {0xc00094ac80?, 0x7e5ce979c868?, 0x7e5d32e87108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000382ed0, {0xc00094ac80?, 0x1973?, 0x1?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004729b8, {0x2acd440, 0xc000382ed0}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000472708, {0x7e5ce87149f0, 0xc000519818}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000472708, 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(0xc000472708, {0xc00052c000, 0x1000, 0xc000591950?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0006e6ea0, {0xc00052c000?, 0x7f4fc5?, 0x2aca340?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001fa21e0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001fa21e0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006e6ea0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 15145 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14721 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc001a441e0, 0xc00049c700, 0xc000293180) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc001a441e0, {0x2af2988, 0x3eb29c0}, {0xc001a80050, 0x4f}, {{0xc0018587c0, 0x5}, {0xc0018587c6, 0x32}, {0x267f9b2, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc001a441e0, {0x2af2988, 0x3eb29c0}, {{0xc0018587c0, 0x5}, {0xc0018587c6, 0x32}, {0x267f9b2, 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, 0xc0005a6008?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001a0d920, 0xc000857180, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001a0d920, 0xc000857180) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/swift.(*Object).Remove(0xc001fa3740, {0x2af2988, 0x3eb29c0}) /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(0xc000907340, {0xc000a170b0, 0x25}, 0xc0000c1920) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc0005e2a00?, 0xc0005e2a00?, 0x1e018f4?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc0005e2a00, 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({0xc0005e2a00?, 0xc001855e60?, 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(0xc0000c1b88) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/swift.(*Fs).ListR(0xc001a13340, {0x2af2988, 0x3eb29c0}, {0x0?, 0x2414d20?}, 0xc001d0f2c0) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:881 +0x185 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc001b20d00, {0x2af2988, 0x3eb29c0}, {0x0, 0x0}, 0xc001a33630) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2af2988, 0x3eb29c0}, {0x2b07040, 0xc001b20d00}, {0x0, 0x0}, 0x1, 0x3, 0xc001d0f230, 0xc001a02d38, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2af2988, 0x3eb29c0}, {0x2b07040, 0xc001b20d00}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc001d0f230) /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(0xc00016ca80) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c github.com/rclone/rclone/fs/sync.TestTransform.func1(0xc000907340) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:124 +0x3a5 testing.tRunner(0xc000907340, 0xc0008565a0) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 14015 /usr/local/go/src/testing/testing.go:1851 +0x413 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.560955663s (try 3/5): exit status 2: Failed []