"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 5/5) 2025/06/26 05:14:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi" 2025/06/26 05:14:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/26 05:14:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi.bin" 2025/06/26 05:14:00 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi.bin: Auto set use_segments_container to true 2025/06/26 05:14:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi" 2025/06/26 05:14:06 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi: Auto set use_segments_container to true 2025/06/26 05:14:11 DEBUG : Creating backend with remote "/tmp/rclone2303589887" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === 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-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:14:11 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/06/26 05:14:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.39s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/26 05:14:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:14:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:12 DEBUG : sub dir/hello world: md5 = fac07ad21cb308f6dfc65334fb5eb162 OK 2025/06/26 05:14:12 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:14:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.46s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.32s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.35s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:14 DEBUG : Creating backend with remote "/non-existing" 2025/06/26 05:14:14 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/06/26 05:14:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.13s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:14:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:19 DEBUG : sub dir/hello world: md5 = ce31e58640a1cb7a1be47748c208db64 OK 2025/06/26 05:14:19 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (6.04s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:20 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Running all checks before starting transfers 2025/06/26 05:14:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:14:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:20 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Checks finished, now starting transfers 2025/06/26 05:14:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:20 DEBUG : sub dir/hello world: md5 = 35959833b1088382996cbbc5cf25063b OK 2025/06/26 05:14:20 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.02s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:21 ERROR : Ignoring --no-traverse with sync 2025/06/26 05:14:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:14:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:21 DEBUG : sub dir/hello world: md5 = 5b334ecae31d3333535834b73ebb7310 OK 2025/06/26 05:14:21 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:14:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.73s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:22 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/06/26 05:14:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:27 DEBUG : hello world2: md5 = ed46e4b4acd6f2835d429c4a869b8085 OK 2025/06/26 05:14:27 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (11.04s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:14:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:14:38 DEBUG : potato2: md5 = b620cc403b2189714cf3b656ff2e2932 OK 2025/06/26 05:14:38 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (20.97s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:14:59 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:14:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:14:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:04 DEBUG : potato2: md5 = c1af2612519c1a05ff979c806ccbe3e4 OK 2025/06/26 05:15:04 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (25.93s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:20 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/26 05:15:20 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 05:15:20 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/06/26 05:15:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/26 05:15:20 INFO : sub dir2: Making directory 2025/06/26 05:15:20 INFO : sub dir: Making directory 2025/06/26 05:15:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:20 INFO : sub dir2/sub sub dir2: Making directory 2025/06/26 05:15:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:20 DEBUG : sub dir/hello world: md5 = e4f52f0140772cf13522478ac0dd5ecf OK 2025/06/26 05:15:20 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/06/26 05:15:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (0.99s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:21 INFO : sub dir2: Making directory 2025/06/26 05:15:21 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/06/26 05:15:21 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 05:15:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:21 DEBUG : sub dir/hello world: md5 = 8afe3ac8ee2efc717289bdcb042ebbcc OK 2025/06/26 05:15:21 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (1.03s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:22 DEBUG : sub dir2: Making directory with metadata 2025/06/26 05:15:22 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 05:15:22 INFO : sub dir2: Making directory 2025/06/26 05:15:22 INFO : sub dir: Making directory 2025/06/26 05:15:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:22 DEBUG : sub dir/hello world: md5 = f1cdc78778ffca7a8e506d859caa3f02 OK 2025/06/26 05:15:22 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:15:22 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/06/26 05:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/06/26 05:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.11s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:409: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.09s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:23 INFO : sub dir2: Making directory 2025/06/26 05:15:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:23 DEBUG : sub dir/hello world: md5 = da9341ae3fe60b84180e78ddf56d73b8 OK 2025/06/26 05:15:23 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:15:23 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (0.83s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:24 DEBUG : sub dir2: Making directory with metadata 2025/06/26 05:15:24 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 05:15:24 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/26 05:15:24 INFO : sub dir2: Making directory 2025/06/26 05:15:24 INFO : sub dir: Making directory 2025/06/26 05:15:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:24 DEBUG : sub dir/hello world: md5 = c57c30a8c9be79f3045e33f7b7389042 OK 2025/06/26 05:15:24 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:15:24 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/06/26 05:15:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.01s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:508: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.25s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:25 INFO : sub dir2: Making directory 2025/06/26 05:15:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:15:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:15:25 DEBUG : sub dir/hello world: md5 = 5684b339823d0bc23c7e59b73cc3d0f7 OK 2025/06/26 05:15:25 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:15:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.96s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:26 DEBUG : sub dir/hello world: md5 = 4bb13959c1014ee868f2d8618b7ca547 OK 2025/06/26 05:15:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kepebut4mahu" 2025/06/26 05:15:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kepebut4mahu.bin" 2025/06/26 05:15:27 DEBUG : Swift container crypt path rclone-test-kepebut4mahu.bin: Auto set use_segments_container to true 2025/06/26 05:15:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kepebut4mahu" 2025/06/26 05:15:32 DEBUG : Swift container crypt path rclone-test-kepebut4mahu: Auto set use_segments_container to true sync_test.go:591: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-kepebut4mahu' 2025/06/26 05:15:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:15:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kepebut4mahu': Waiting for checks to finish 2025/06/26 05:15:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kepebut4mahu': Waiting for transfers to finish 2025/06/26 05:15:48 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:15:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kepebut4mahu': Purge remote 2025/06/26 05:15:48 DEBUG : Waiting for deletions to finish 2025/06/26 05:15:59 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (33.26s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:15:59 DEBUG : sub dir/hello world: md5 = 6391b2236335395662a2ff236e620f12 OK 2025/06/26 05:16:00 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/26 05:16:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:16:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:16:00 DEBUG : sub dir/hello world: md5 = dca27ca95aefc5e08bc8f83d156eb18b OK 2025/06/26 05:16:00 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.30s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:16:01 DEBUG : sub dir/hello world: md5 = 1e9958ff6241a196675ec07ffdfc053d OK 2025/06/26 05:16:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vapicit6logo" 2025/06/26 05:16:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vapicit6logo.bin" 2025/06/26 05:16:01 DEBUG : Swift container crypt path rclone-test-vapicit6logo.bin: Auto set use_segments_container to true 2025/06/26 05:16:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vapicit6logo" 2025/06/26 05:16:07 DEBUG : Swift container crypt path rclone-test-vapicit6logo: Auto set use_segments_container to true sync_test.go:627: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo' 2025/06/26 05:16:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:16:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo': Waiting for checks to finish 2025/06/26 05:16:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo': Waiting for transfers to finish 2025/06/26 05:16:28 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:16:28 DEBUG : sub dir/hello world: md5 = 6f88372c83da533c6a564f3f6d2a5c2c OK 2025/06/26 05:16:29 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/26 05:16:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo': Waiting for checks to finish 2025/06/26 05:16:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo': Waiting for transfers to finish 2025/06/26 05:16:44 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:16:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vapicit6logo': Purge remote 2025/06/26 05:16:49 DEBUG : Waiting for deletions to finish 2025/06/26 05:16:54 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (54.24s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:16:55 DEBUG : sub dir/hello world: md5 = 9596a5e758ee2374fd9da3ac75cbd2d2 OK 2025/06/26 05:16:55 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/26 05:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:16:56 DEBUG : sub dir/hello world: md5 = 60164cc5550b9c345b7e25648937aa2c OK 2025/06/26 05:16:56 INFO : sub dir/hello world: Copied (replaced existing) 2025/06/26 05:16:56 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.82s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:16:57 DEBUG : sub dir/hello world: md5 = 6da8dffe94123b27167e2a9ff6d55800 OK 2025/06/26 05:16:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zanubir7yaya" 2025/06/26 05:16:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zanubir7yaya.bin" 2025/06/26 05:16:57 DEBUG : Swift container crypt path rclone-test-zanubir7yaya.bin: Auto set use_segments_container to true 2025/06/26 05:17:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zanubir7yaya" 2025/06/26 05:17:03 DEBUG : Swift container crypt path rclone-test-zanubir7yaya: Auto set use_segments_container to true sync_test.go:672: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya' 2025/06/26 05:17:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:17:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for checks to finish 2025/06/26 05:17:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for transfers to finish 2025/06/26 05:17:14 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:17:14 DEBUG : sub dir/hello world: md5 = 1f9a242fa165cfca926e0b797fd66cf6 OK 2025/06/26 05:17:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for checks to finish 2025/06/26 05:17:15 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/26 05:17:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for transfers to finish 2025/06/26 05:17:20 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:17:20 INFO : sub dir/hello world: Deleted 2025/06/26 05:17:21 DEBUG : sub dir/hello world: md5 = 75c3a9605c52ec433fd228865282ee93 OK 2025/06/26 05:17:21 DEBUG : testing file moves 2025/06/26 05:17:21 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/06/26 05:17:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for checks to finish 2025/06/26 05:17:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Waiting for transfers to finish 2025/06/26 05:17:26 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/26 05:17:27 INFO : sub dir/hello world: Deleted 2025/06/26 05:17:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zanubir7yaya': Purge remote 2025/06/26 05:17:27 DEBUG : Waiting for deletions to finish 2025/06/26 05:17:27 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (31.35s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:17:28 DEBUG : sub dir/hello world: md5 = 3758b3b70bec234a169d3b80eefbd187 OK 2025/06/26 05:17:28 ERROR : error listing: directory not found 2025/06/26 05:17:28 INFO : Local file system at /tmp/rclone2303589887: Making directory 2025/06/26 05:17:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.07s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:17:29 DEBUG : sub dir/hello world: md5 = d6f4f535362dfd0372a389e44cafb62e OK 2025/06/26 05:17:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:17:30 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for checks to finish 2025/06/26 05:17:30 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for transfers to finish 2025/06/26 05:17:30 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/06/26 05:17:30 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.64s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:17:31 DEBUG : check sum: Need to transfer - File not found at Destination 2025/06/26 05:17:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:17:36 DEBUG : check sum: md5 = 3f830417fe3e1415ebeabe321c325f85 OK 2025/06/26 05:17:36 INFO : check sum: Copied (new) 2025/06/26 05:17:36 DEBUG : Waiting for deletions to finish 2025/06/26 05:17:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:37 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/06/26 05:17:37 DEBUG : check sum: Size of src and dst objects identical 2025/06/26 05:17:37 DEBUG : check sum: Unchanged skipping 2025/06/26 05:17:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:17:37 DEBUG : Waiting for deletions to finish 2025/06/26 05:17:37 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (21.54s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:17:52 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/06/26 05:17:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:17:53 DEBUG : sizeonly: md5 = d8d1d4484c6ed4eafdf31525141ee22b OK 2025/06/26 05:17:53 INFO : sizeonly: Copied (new) 2025/06/26 05:17:53 DEBUG : Waiting for deletions to finish 2025/06/26 05:17:53 DEBUG : sizeonly: Sizes identical 2025/06/26 05:17:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:53 DEBUG : sizeonly: Unchanged skipping 2025/06/26 05:17:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:17:53 DEBUG : Waiting for deletions to finish 2025/06/26 05:17:53 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.57s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:17:54 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/06/26 05:17:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:17:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:17:59 DEBUG : ignore-size: md5 = 9c371d205c5bd74b0daaa1b851af019a OK 2025/06/26 05:17:59 INFO : ignore-size: Copied (new) 2025/06/26 05:17:59 DEBUG : Waiting for deletions to finish 2025/06/26 05:18:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:18:00 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:18:00 DEBUG : ignore-size: Unchanged skipping 2025/06/26 05:18:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:18:00 DEBUG : Waiting for deletions to finish 2025/06/26 05:18:00 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (16.96s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:18:16 DEBUG : existing: md5 = fb4f3fdfed5719e6ad320595a228db05 OK 2025/06/26 05:18:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:18:17 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:18:17 DEBUG : existing: Unchanged skipping 2025/06/26 05:18:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:18:17 DEBUG : Waiting for deletions to finish 2025/06/26 05:18:17 INFO : There was nothing to transfer 2025/06/26 05:18:17 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/06/26 05:18:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:18:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:18:27 DEBUG : existing: md5 = 72d3b5317a25e3f339c712db6bb47038 OK 2025/06/26 05:18:27 INFO : existing: Copied (replaced existing) 2025/06/26 05:18:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (31.78s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:18:43 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/26 05:18:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:18:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:18:48 DEBUG : existing: md5 = df134770256672389be432c20970bbcd OK 2025/06/26 05:18:48 INFO : existing: Copied (new) 2025/06/26 05:18:48 DEBUG : Waiting for deletions to finish 2025/06/26 05:18:48 DEBUG : existing: Destination exists, skipping 2025/06/26 05:18:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:18:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:18:53 DEBUG : Waiting for deletions to finish 2025/06/26 05:18:53 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (21.20s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:19:04 DEBUG : b/potato: md5 = a12eb09f20e883f7d59a4dc01c2fef5c OK 2025/06/26 05:19:09 DEBUG : c/non empty space: md5 = 64ea514bb80bb108f68e826a382a60de OK 2025/06/26 05:19:09 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/26 05:19:10 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/26 05:19:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:19:15 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:19:15 DEBUG : c/non empty space: Unchanged skipping 2025/06/26 05:19:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:19:15 DEBUG : a/potato2: md5 = dcc1f9766000c08d2c3e99c5274431ee OK 2025/06/26 05:19:15 INFO : a/potato2: Copied (new) 2025/06/26 05:19:15 DEBUG : Waiting for deletions to finish 2025/06/26 05:19:15 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (27.25s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:19:38 DEBUG : empty space: md5 = f3bc53198c43affcf6a7b07a283a3bf4 OK 2025/06/26 05:19:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:19:43 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/26 05:19:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:19:43 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/06/26 05:19:43 DEBUG : Waiting for deletions to finish 2025/06/26 05:19:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:19:48 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/26 05:19:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:19:54 DEBUG : empty space: md5 = aae95fa66b578315d311091c23ad321f OK 2025/06/26 05:19:54 INFO : empty space: Copied (replaced existing) 2025/06/26 05:19:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (33.28s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:1017: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.25s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:20:10 DEBUG : foo: md5 = f5ec84b61112baaae1aeee73a23bb5c5 OK 2025/06/26 05:20:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:20:10 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/06/26 05:20:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:20:15 DEBUG : foo: md5 = 14d0986044e2051318fe01414d89031a OK 2025/06/26 05:20:15 INFO : foo: Copied (replaced existing) 2025/06/26 05:20:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (16.27s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:20:28 DEBUG : empty space: md5 = 2bcf3635b2c4adf7c7491b4153876620 OK 2025/06/26 05:20:33 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/26 05:20:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:20:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:20:38 DEBUG : empty space: Unchanged skipping 2025/06/26 05:20:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:20:38 DEBUG : potato: md5 = d74d03b7cd3a534539f09e162d7b5bca OK 2025/06/26 05:20:38 INFO : potato: Copied (new) 2025/06/26 05:20:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (39.37s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:21:05 DEBUG : potato: md5 = 38a646ea92c1f3ea5e66d1631b0cf4c6 OK 2025/06/26 05:21:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:21:11 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/06/26 05:21:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:21:16 DEBUG : potato: md5 = b2be48a4a5e9f53da3034e0c6478cabe OK 2025/06/26 05:21:16 INFO : potato: Copied (replaced existing) 2025/06/26 05:21:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (26.51s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:21:32 DEBUG : potato: md5 = bc8e0814c75fa0a9c470b2f221cec79e OK 2025/06/26 05:21:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:21:42 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/06/26 05:21:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:21:47 DEBUG : potato: md5 = ae9833a5658f533441e69bc5f65b22b1 OK 2025/06/26 05:21:47 INFO : potato: Copied (replaced existing) 2025/06/26 05:21:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (31.39s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:22:03 DEBUG : potato: md5 = f511ffdbaad66adbc1ecdca0447b0616 OK 2025/06/26 05:22:08 DEBUG : empty space: md5 = fd252f11c841430289e6dcab55384a6c OK 2025/06/26 05:22:09 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:22:09 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/06/26 05:22:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:22:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:22:14 DEBUG : empty space: Unchanged skipping 2025/06/26 05:22:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:22:14 DEBUG : Waiting for deletions to finish 2025/06/26 05:22:14 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (31.62s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:22:35 DEBUG : potato: md5 = f522ff504b028a0cafd5ca94f5e262a7 OK 2025/06/26 05:22:40 DEBUG : empty space: md5 = 151424d343fbc6a441edc552fd528255 OK 2025/06/26 05:22:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:22:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:22:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:22:41 DEBUG : empty space: Unchanged skipping 2025/06/26 05:22:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:22:46 DEBUG : potato2: md5 = f805d4e2940a348bfb4b0ccaabf9d5bd OK 2025/06/26 05:22:46 INFO : potato2: Copied (new) 2025/06/26 05:22:46 DEBUG : Waiting for deletions to finish 2025/06/26 05:22:56 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (48.47s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:23:18 DEBUG : b/potato: md5 = 335c1820bdd5681b8ba8bed273d2452a OK 2025/06/26 05:23:23 DEBUG : c/non empty space: md5 = fa3ba8678eb431453052b5012c9a41ca OK 2025/06/26 05:23:23 INFO : d: Making directory 2025/06/26 05:23:23 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/06/26 05:23:24 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/26 05:23:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:23:24 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:23:24 DEBUG : c/non empty space: Unchanged skipping 2025/06/26 05:23:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:23:29 DEBUG : a/potato2: md5 = 0946fbb736a00f7c800a06fc3075208b OK 2025/06/26 05:23:29 INFO : a/potato2: Copied (new) 2025/06/26 05:23:29 DEBUG : Waiting for deletions to finish 2025/06/26 05:23:29 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (26.80s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:23:45 DEBUG : b/potato: md5 = 6ca8c45a3bf2ee8bf25dd929cb2d816b OK 2025/06/26 05:23:50 DEBUG : c/non empty space: md5 = 453669b297912e6eb042723625ef682c OK 2025/06/26 05:23:50 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/06/26 05:23:56 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/06/26 05:23:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:24:01 DEBUG : a/potato2: md5 = 49ec2e396a98111a7aa183d0ac2f3e31 OK 2025/06/26 05:24:01 INFO : a/potato2: Copied (new) 2025/06/26 05:24:01 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:24:01 DEBUG : c/non empty space: Unchanged skipping 2025/06/26 05:24:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:24:01 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': not deleting files as there were IO errors 2025/06/26 05:24:01 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (37.16s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:24:27 DEBUG : potato: md5 = bd060bd1baf5d19e3cda1cb1c7e1205b OK 2025/06/26 05:24:33 DEBUG : empty space: md5 = f0d45c7de467eb09774b39631003a456 OK 2025/06/26 05:24:33 DEBUG : Waiting for deletions to finish 2025/06/26 05:24:33 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:24:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:24:33 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:24:33 DEBUG : empty space: Unchanged skipping 2025/06/26 05:24:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:24:38 DEBUG : potato2: md5 = 8320574baeea104891d89a791b6a8151 OK 2025/06/26 05:24:38 INFO : potato2: Copied (new) 2025/06/26 05:24:43 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (52.12s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:25:20 DEBUG : potato: md5 = b7baca16092fd3a19cd7b8f87d790670 OK 2025/06/26 05:25:25 DEBUG : empty space: md5 = a1a8b71249b561b0a7387ed286eff7e9 OK 2025/06/26 05:25:30 DEBUG : Waiting for deletions to finish 2025/06/26 05:25:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:25:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:25:40 INFO : potato: Deleted 2025/06/26 05:25:40 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:25:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:25:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:25:40 DEBUG : empty space: Unchanged skipping 2025/06/26 05:25:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:25:46 DEBUG : potato2: md5 = 4d48412d52b50dfe8398e26b1b72d9a5 OK 2025/06/26 05:25:46 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (48.76s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:26:08 DEBUG : potato: md5 = e25698cded32f20b9f21d44291d72d2d OK 2025/06/26 05:26:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:26:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:26:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:26:14 DEBUG : potato2: md5 = 264663e24091fc21b7a6d5fa2bae6640 OK 2025/06/26 05:26:14 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (31.55s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:26:40 DEBUG : potato2: md5 = 7feaebabf44d56758ec98b253b14f578 OK 2025/06/26 05:26:46 DEBUG : empty space: md5 = eac55a974257b1db36beadd4434310ff OK 2025/06/26 05:26:56 DEBUG : enormous: Excluded (Size Filter) 2025/06/26 05:26:56 DEBUG : enormous: Excluded 2025/06/26 05:26:56 DEBUG : potato2: Excluded (Size Filter) 2025/06/26 05:26:56 DEBUG : potato2: Excluded 2025/06/26 05:26:56 DEBUG : potato2: Excluded (Size Filter) 2025/06/26 05:26:56 DEBUG : potato2: Excluded 2025/06/26 05:26:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:27:01 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:27:01 DEBUG : empty space: Unchanged skipping 2025/06/26 05:27:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:27:01 DEBUG : Waiting for deletions to finish 2025/06/26 05:27:01 INFO : There was nothing to transfer 2025/06/26 05:27:06 DEBUG : enormous: Excluded (Size Filter) 2025/06/26 05:27:06 DEBUG : enormous: Excluded 2025/06/26 05:27:06 DEBUG : potato2: Excluded (Size Filter) 2025/06/26 05:27:06 DEBUG : potato2: Excluded 2025/06/26 05:27:06 DEBUG : potato2: Excluded (Size Filter) 2025/06/26 05:27:06 DEBUG : potato2: Excluded 2025/06/26 05:27:06 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for checks to finish 2025/06/26 05:27:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:27:11 DEBUG : empty space: Unchanged skipping 2025/06/26 05:27:11 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for transfers to finish 2025/06/26 05:27:11 DEBUG : Waiting for deletions to finish 2025/06/26 05:27:11 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (52.43s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:27:32 DEBUG : potato2: md5 = cbfbeb492ff702f4b591b79819839e35 OK 2025/06/26 05:27:38 DEBUG : empty space: md5 = 059967f73b5577d5e3ce1bf9803664f8 OK 2025/06/26 05:27:39 DEBUG : enormous: md5 = f9ba0391002d39773d986b8d7a2da821 OK 2025/06/26 05:27:40 DEBUG : enormous: Excluded (Size Filter) 2025/06/26 05:27:40 DEBUG : enormous: Excluded 2025/06/26 05:27:40 DEBUG : potato2: Excluded (Size Filter) 2025/06/26 05:27:40 DEBUG : potato2: Excluded 2025/06/26 05:27:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:27:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:27:40 DEBUG : empty space: Unchanged skipping 2025/06/26 05:27:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:27:40 DEBUG : Waiting for deletions to finish 2025/06/26 05:27:40 INFO : enormous: Deleted 2025/06/26 05:27:50 INFO : potato2: Deleted 2025/06/26 05:27:50 INFO : There was nothing to transfer 2025/06/26 05:27:55 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for checks to finish 2025/06/26 05:27:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:27:55 DEBUG : empty space: Unchanged skipping 2025/06/26 05:27:55 DEBUG : Local file system at /tmp/rclone2303589887: Waiting for transfers to finish 2025/06/26 05:27:55 DEBUG : Waiting for deletions to finish 2025/06/26 05:27:55 INFO : enormous: Deleted 2025/06/26 05:27:55 INFO : potato2: Deleted 2025/06/26 05:27:55 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (35.33s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:28:03 DEBUG : one: md5 = 7a101730c02fb82ab2986c0895ce9614 OK 2025/06/26 05:28:03 DEBUG : two: md5 = 76e7565d5cce9cb89c2025ab861606aa OK 2025/06/26 05:28:09 DEBUG : three: md5 = 6a48a023ab8b6b6fd3fe24c48fa00e9a OK 2025/06/26 05:28:14 DEBUG : four: md5 = 93c91a69f47699b056bc2656e14aace7 OK 2025/06/26 05:28:19 DEBUG : five: Need to transfer - File not found at Destination 2025/06/26 05:28:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:28:19 DEBUG : one: Destination is newer than source, skipping 2025/06/26 05:28:19 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/06/26 05:28:19 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/06/26 05:28:19 DEBUG : three: Sizes identical 2025/06/26 05:28:19 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/06/26 05:28:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:28:20 DEBUG : two: md5 = 4970302cfc3daa7273a7b0b4b7758890 OK 2025/06/26 05:28:20 INFO : two: Copied (replaced existing) 2025/06/26 05:28:20 DEBUG : five: md5 = 13dd5bc96241a8e11edabdee228d3be5 OK 2025/06/26 05:28:20 INFO : five: Copied (new) 2025/06/26 05:28:24 DEBUG : four: md5 = ede9679bbf41d67ba4885ec3e00b75a0 OK 2025/06/26 05:28:24 INFO : four: Copied (replaced existing) 2025/06/26 05:28:24 DEBUG : Waiting for deletions to finish sync_test.go:1450: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (43.62s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1467: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1467: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:1539: Can track renames: false 2025/06/26 05:28:46 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/26 05:28:46 DEBUG : potato: Need to transfer - File not found at Destination 2025/06/26 05:28:46 DEBUG : yam: Need to transfer - File not found at Destination 2025/06/26 05:28:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:28:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:28:51 DEBUG : potato: md5 = dca2e251851395c4e889f91623d8f684 OK 2025/06/26 05:28:51 INFO : potato: Copied (new) 2025/06/26 05:28:51 DEBUG : yam: md5 = 8b2e56d67560f297efc40a0826eaa4e5 OK 2025/06/26 05:28:51 INFO : yam: Copied (new) 2025/06/26 05:28:51 DEBUG : Waiting for deletions to finish 2025/06/26 05:28:52 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Ignoring --track-renames as the source and destination do not have a common hash 2025/06/26 05:28:52 DEBUG : yaml: Need to transfer - File not found at Destination 2025/06/26 05:28:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:28:52 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:28:52 DEBUG : potato: Unchanged skipping 2025/06/26 05:28:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:28:57 DEBUG : yaml: md5 = 12d5795495e1444e78140478bf0b8630 OK 2025/06/26 05:28:57 INFO : yaml: Copied (new) 2025/06/26 05:28:57 DEBUG : Waiting for deletions to finish 2025/06/26 05:29:07 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (47.51s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:1611: Can track renames: true 2025/06/26 05:29:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Making map for --track-renames 2025/06/26 05:29:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Finished making map for --track-renames 2025/06/26 05:29:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:29:33 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/26 05:29:33 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/06/26 05:29:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for renames to finish 2025/06/26 05:29:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:29:39 DEBUG : yam: md5 = f86c8f26f6cfb2bd71846967e90c57d4 OK 2025/06/26 05:29:39 INFO : yam: Copied (new) 2025/06/26 05:29:39 DEBUG : potato: md5 = 4f50bf52dbcc44a4b27b503ca73551b5 OK 2025/06/26 05:29:39 INFO : potato: Copied (new) 2025/06/26 05:29:39 DEBUG : Waiting for deletions to finish 2025/06/26 05:29:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Making map for --track-renames 2025/06/26 05:29:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Finished making map for --track-renames 2025/06/26 05:29:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:29:39 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:29:39 DEBUG : potato: Unchanged skipping 2025/06/26 05:29:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for renames to finish 2025/06/26 05:30:04 INFO : yam: Copied (server-side copy) to: yaml 2025/06/26 05:30:10 INFO : yam: Deleted 2025/06/26 05:30:10 INFO : yaml: Renamed from "yam" 2025/06/26 05:30:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:30:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (52.37s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:1650: Can track renames: true 2025/06/26 05:30:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Making map for --track-renames 2025/06/26 05:30:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Finished making map for --track-renames 2025/06/26 05:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:30:26 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/06/26 05:30:26 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/06/26 05:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for renames to finish 2025/06/26 05:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:30:26 DEBUG : sub/yam: md5 = 4e515d9f8ab6f7ac6887ec7d6e4429d6 OK 2025/06/26 05:30:26 INFO : sub/yam: Copied (new) 2025/06/26 05:30:31 DEBUG : potato: md5 = dc315d41a40eb2e4a2d669f49cf48b4f OK 2025/06/26 05:30:31 INFO : potato: Copied (new) 2025/06/26 05:30:31 DEBUG : Waiting for deletions to finish 2025/06/26 05:30:37 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:30:37 DEBUG : potato: Unchanged skipping 2025/06/26 05:30:37 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Making map for --track-renames 2025/06/26 05:30:37 INFO : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Finished making map for --track-renames 2025/06/26 05:30:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:30:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for renames to finish 2025/06/26 05:30:57 INFO : sub/yam: Copied (server-side copy) to: yam 2025/06/26 05:30:57 INFO : sub/yam: Deleted 2025/06/26 05:30:57 INFO : yam: Renamed from "sub/yam" 2025/06/26 05:30:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:30:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (47.04s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:31:13 DEBUG : Creating backend with remote "/tmp/rclone2303589887/dir1" 2025/06/26 05:31:13 DEBUG : Config file has changed externally - reloading 2025/06/26 05:31:13 DEBUG : Creating backend with remote "/tmp/rclone2303589887/dir2" 2025/06/26 05:31:13 DEBUG : Local file system at /tmp/rclone2303589887/dir2: Using server-side directory move 2025/06/26 05:31:13 INFO : Local file system at /tmp/rclone2303589887/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/06/26 05:31:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/06/26 05:31:13 DEBUG : Local file system at /tmp/rclone2303589887/dir2: Waiting for checks to finish 2025/06/26 05:31:13 DEBUG : Local file system at /tmp/rclone2303589887/dir2: Waiting for transfers to finish 2025/06/26 05:31:13 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.20s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:31:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:31:13 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/26 05:31:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:31:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:31:13 DEBUG : nested/sub dir/file: md5 = d0db1f3cd99c4daf55841465fcb2fa5f OK 2025/06/26 05:31:13 INFO : nested/sub dir/file: Copied (new) 2025/06/26 05:31:13 INFO : nested/sub dir/file: Deleted 2025/06/26 05:31:14 DEBUG : sub dir/hello world: md5 = c2bb044b364ad9a5a29fa6a4a4959c7d OK 2025/06/26 05:31:14 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:31:14 INFO : sub dir/hello world: Deleted 2025/06/26 05:31:14 INFO : sub dir: Removing directory 2025/06/26 05:31:14 INFO : nested/sub dir: Removing directory 2025/06/26 05:31:14 INFO : nested: Removing directory 2025/06/26 05:31:14 DEBUG : Local file system at /tmp/rclone2303589887: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (1.42s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:31:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/26 05:31:15 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/26 05:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:31:15 DEBUG : nested/sub dir/file: md5 = 02cddcd5a80e2f615b9ff39688abf678 OK 2025/06/26 05:31:15 INFO : nested/sub dir/file: Copied (new) 2025/06/26 05:31:15 INFO : nested/sub dir/file: Deleted 2025/06/26 05:31:15 DEBUG : sub dir/hello world: md5 = 714147c8518b1c7d29aabf3ea22bd3a7 OK 2025/06/26 05:31:15 INFO : sub dir/hello world: Copied (new) 2025/06/26 05:31:15 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.02s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:31:15 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/06/26 05:31:15 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/26 05:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:31:16 DEBUG : existing-b: md5 = 5020adf555a56ca96a50de1d58f817bc OK 2025/06/26 05:31:16 INFO : existing-b: Copied (new) 2025/06/26 05:31:16 INFO : existing-b: Deleted 2025/06/26 05:31:21 DEBUG : existing: md5 = 7456424cf963a02f6975eb859e4f7bbf OK 2025/06/26 05:31:21 INFO : existing: Copied (new) 2025/06/26 05:31:21 INFO : existing: Deleted 2025/06/26 05:31:26 DEBUG : existing: Destination exists, skipping 2025/06/26 05:31:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:31:26 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/06/26 05:31:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:31:26 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (16.60s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:31:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-difehuk5vaju" 2025/06/26 05:31:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-difehuk5vaju.bin" 2025/06/26 05:31:32 DEBUG : Swift container crypt path rclone-test-difehuk5vaju.bin: Auto set use_segments_container to true 2025/06/26 05:31:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-difehuk5vaju" 2025/06/26 05:31:38 DEBUG : Swift container crypt path rclone-test-difehuk5vaju: Auto set use_segments_container to true 2025/06/26 05:31:48 DEBUG : potato2: md5 = 028ca0b416c23c9a30c2d9532e5ef545 OK 2025/06/26 05:31:54 DEBUG : empty space: md5 = b10cc8335941f5550498b0ae619817a5 OK 2025/06/26 05:32:00 DEBUG : potato3: md5 = b4ec2e27b184484ffe35897bb22441a3 OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-difehuk5vaju' 2025/06/26 05:32:00 DEBUG : empty space: md5 = 0785be2284eb134464f09a2ac9b60104 OK 2025/06/26 05:32:06 DEBUG : potato3: md5 = 938d2e208ab6422c2a5b5654c1e75b64 OK 2025/06/26 05:32:06 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:32:06 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/26 05:32:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-difehuk5vaju': Waiting for checks to finish 2025/06/26 05:32:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:32:06 DEBUG : empty space: Unchanged skipping 2025/06/26 05:32:12 INFO : empty space: Deleted 2025/06/26 05:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-difehuk5vaju': Waiting for transfers to finish 2025/06/26 05:32:16 INFO : potato3: Copied (server-side copy) 2025/06/26 05:32:21 INFO : potato2: Copied (server-side copy) 2025/06/26 05:32:22 INFO : potato3: Deleted 2025/06/26 05:32:27 INFO : potato2: Deleted 2025/06/26 05:32:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zijapij0mora" 2025/06/26 05:32:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zijapij0mora.bin" 2025/06/26 05:32:27 DEBUG : Swift container crypt path rclone-test-zijapij0mora.bin: Auto set use_segments_container to true 2025/06/26 05:32:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zijapij0mora" 2025/06/26 05:32:33 DEBUG : Swift container crypt path rclone-test-zijapij0mora: Auto set use_segments_container to true 2025/06/26 05:32:38 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/26 05:32:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:32:38 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/26 05:32:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zijapij0mora': Waiting for checks to finish 2025/06/26 05:32:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zijapij0mora': Waiting for transfers to finish 2025/06/26 05:32:44 INFO : empty space: Copied (server-side copy) 2025/06/26 05:32:44 INFO : empty space: Deleted 2025/06/26 05:32:44 INFO : potato3: Copied (server-side copy) 2025/06/26 05:32:49 INFO : potato2: Copied (server-side copy) 2025/06/26 05:32:49 INFO : potato3: Deleted 2025/06/26 05:32:55 INFO : potato2: Deleted 2025/06/26 05:33:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zijapij0mora': Purge remote 2025/06/26 05:33:00 DEBUG : Waiting for deletions to finish 2025/06/26 05:33:01 INFO : empty space.bin: Deleted 2025/06/26 05:33:01 INFO : potato3.bin: Deleted 2025/06/26 05:33:10 INFO : potato2.bin: Deleted 2025/06/26 05:33:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-difehuk5vaju': Purge remote 2025/06/26 05:33:10 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (98.90s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:33:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-comifem7xola" 2025/06/26 05:33:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-comifem7xola.bin" 2025/06/26 05:33:11 DEBUG : Swift container crypt path rclone-test-comifem7xola.bin: Auto set use_segments_container to true 2025/06/26 05:33:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-comifem7xola" 2025/06/26 05:33:17 DEBUG : Swift container crypt path rclone-test-comifem7xola: Auto set use_segments_container to true 2025/06/26 05:33:27 DEBUG : potato2: md5 = 0ca5b2b988b38897013de19bbb58fb5a OK 2025/06/26 05:33:33 DEBUG : empty space: md5 = f9782c643f2385dd709aace325f55811 OK 2025/06/26 05:33:38 DEBUG : potato3: md5 = 00a1121e08da5c8b6406d8804918038e OK sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-comifem7xola' 2025/06/26 05:33:44 DEBUG : empty space: md5 = d333b45a06ccf0220f150eaddb874cb4 OK 2025/06/26 05:33:49 DEBUG : potato3: md5 = b6f7bc6aa942e685d7018fc2786ff448 OK 2025/06/26 05:33:50 DEBUG : empty space: Excluded (Size Filter) 2025/06/26 05:33:50 DEBUG : empty space: Excluded 2025/06/26 05:33:50 DEBUG : empty space: Excluded (Size Filter) 2025/06/26 05:33:50 DEBUG : empty space: Excluded 2025/06/26 05:33:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:33:50 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/26 05:33:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-comifem7xola': Waiting for checks to finish 2025/06/26 05:33:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-comifem7xola': Waiting for transfers to finish 2025/06/26 05:33:56 INFO : potato2: Copied (server-side copy) 2025/06/26 05:34:01 INFO : potato2: Deleted 2025/06/26 05:34:05 INFO : potato3: Copied (server-side copy) 2025/06/26 05:34:11 INFO : potato3: Deleted 2025/06/26 05:34:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cusiduq6cazo" 2025/06/26 05:34:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cusiduq6cazo.bin" 2025/06/26 05:34:21 DEBUG : Swift container crypt path rclone-test-cusiduq6cazo.bin: Auto set use_segments_container to true 2025/06/26 05:34:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cusiduq6cazo" 2025/06/26 05:34:27 DEBUG : Swift container crypt path rclone-test-cusiduq6cazo: Auto set use_segments_container to true 2025/06/26 05:34:32 DEBUG : empty space: Excluded (Size Filter) 2025/06/26 05:34:32 DEBUG : empty space: Excluded 2025/06/26 05:34:32 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:34:32 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/26 05:34:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cusiduq6cazo': Waiting for checks to finish 2025/06/26 05:34:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cusiduq6cazo': Waiting for transfers to finish 2025/06/26 05:34:43 INFO : potato2: Copied (server-side copy) 2025/06/26 05:34:43 INFO : potato3: Copied (server-side copy) 2025/06/26 05:34:43 INFO : potato2: Deleted 2025/06/26 05:34:48 INFO : potato3: Deleted 2025/06/26 05:34:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cusiduq6cazo': Purge remote 2025/06/26 05:34:48 DEBUG : Waiting for deletions to finish 2025/06/26 05:34:54 INFO : potato2.bin: Deleted 2025/06/26 05:34:54 INFO : potato3.bin: Deleted 2025/06/26 05:34:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-comifem7xola': Purge remote 2025/06/26 05:34:54 DEBUG : Waiting for deletions to finish 2025/06/26 05:34:54 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (109.20s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:35:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zecifaf7cuwa" 2025/06/26 05:35:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zecifaf7cuwa.bin" 2025/06/26 05:35:00 DEBUG : Swift container crypt path rclone-test-zecifaf7cuwa.bin: Auto set use_segments_container to true 2025/06/26 05:35:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zecifaf7cuwa" 2025/06/26 05:35:06 DEBUG : Swift container crypt path rclone-test-zecifaf7cuwa: Auto set use_segments_container to true 2025/06/26 05:35:17 DEBUG : potato2: md5 = 656a308da972192368c29da940174323 OK 2025/06/26 05:35:22 DEBUG : empty space: md5 = f7b8fed54b05fc2ce7998afb3cb0b197 OK 2025/06/26 05:35:27 DEBUG : potato3: md5 = f4924e6fdc8c5f99b04be94dfd0bb3fc OK 2025/06/26 05:35:27 INFO : tomatoDir: Making directory sync_test.go:1706: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi' -> Encrypted drive 'TestCryptSwift:rclone-test-zecifaf7cuwa' 2025/06/26 05:35:33 DEBUG : empty space: md5 = ede0d2b0f36cd2530ca52321396c7e33 OK 2025/06/26 05:35:40 DEBUG : potato3: md5 = 6612341f40e3ff7f12b05231466fce9d OK 2025/06/26 05:35:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:35:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/06/26 05:35:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zecifaf7cuwa': Waiting for checks to finish 2025/06/26 05:35:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:35:46 DEBUG : empty space: Unchanged skipping 2025/06/26 05:35:52 INFO : potato2: Copied (server-side copy) 2025/06/26 05:35:52 INFO : empty space: Deleted 2025/06/26 05:35:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zecifaf7cuwa': Waiting for transfers to finish 2025/06/26 05:35:57 INFO : potato2: Deleted 2025/06/26 05:35:57 INFO : potato3: Copied (server-side copy) 2025/06/26 05:36:02 INFO : potato3: Deleted 2025/06/26 05:36:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bidafog6gevi" 2025/06/26 05:36:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bidafog6gevi.bin" 2025/06/26 05:36:07 DEBUG : Swift container crypt path rclone-test-bidafog6gevi.bin: Auto set use_segments_container to true 2025/06/26 05:36:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bidafog6gevi" 2025/06/26 05:36:13 DEBUG : Swift container crypt path rclone-test-bidafog6gevi: Auto set use_segments_container to true 2025/06/26 05:36:18 INFO : tomatoDir: Making directory 2025/06/26 05:36:18 DEBUG : empty space: Need to transfer - File not found at Destination 2025/06/26 05:36:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/06/26 05:36:18 DEBUG : potato3: Need to transfer - File not found at Destination 2025/06/26 05:36:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bidafog6gevi': Waiting for checks to finish 2025/06/26 05:36:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bidafog6gevi': Waiting for transfers to finish 2025/06/26 05:36:24 INFO : potato3: Copied (server-side copy) 2025/06/26 05:36:24 INFO : empty space: Copied (server-side copy) 2025/06/26 05:36:24 INFO : empty space: Deleted 2025/06/26 05:36:29 INFO : potato2: Copied (server-side copy) 2025/06/26 05:36:29 INFO : potato2: Deleted 2025/06/26 05:36:29 INFO : potato3: Deleted 2025/06/26 05:36:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bidafog6gevi': Purge remote 2025/06/26 05:36:29 DEBUG : Waiting for deletions to finish 2025/06/26 05:36:30 INFO : potato3.bin: Deleted 2025/06/26 05:36:30 INFO : empty space.bin: Deleted 2025/06/26 05:36:40 INFO : potato2.bin: Deleted 2025/06/26 05:36:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zecifaf7cuwa': Purge remote 2025/06/26 05:36:40 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (99.95s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:36:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/rclone-move-test" 2025/06/26 05:36:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-move-test.bin" 2025/06/26 05:36:40 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-move-test.bin: Auto set use_segments_container to true 2025/06/26 05:36:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-move-test" 2025/06/26 05:36:46 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-move-test: Auto set use_segments_container to true 2025/06/26 05:36:56 DEBUG : potato2: md5 = 2c9f65efc4987d514db633cdcd02f497 OK --- PASS: TestServerSideMoveOverlap (27.04s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:37:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test" 2025/06/26 05:37:07 DEBUG : Config file has changed externally - reloading 2025/06/26 05:37:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test.bin" 2025/06/26 05:37:07 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/26 05:37:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test" 2025/06/26 05:37:13 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (11.56s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:37:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test" 2025/06/26 05:37:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test.bin" 2025/06/26 05:37:19 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/06/26 05:37:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test" 2025/06/26 05:37:25 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test: Auto set use_segments_container to true 2025/06/26 05:37:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2" 2025/06/26 05:37:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2.bin" 2025/06/26 05:37:30 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/06/26 05:37:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2" 2025/06/26 05:37:36 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/06/26 05:37:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file" 2025/06/26 05:37:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file.bin" 2025/06/26 05:37:42 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/06/26 05:37:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file" 2025/06/26 05:37:47 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/06/26 05:37:58 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 4ecdaaba4939d64d7996f1d5f9bf6134 OK 2025/06/26 05:38:03 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/26 05:38:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test': Waiting for checks to finish 2025/06/26 05:38:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test': Waiting for transfers to finish 2025/06/26 05:38:03 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:03 INFO : There was nothing to transfer 2025/06/26 05:38:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/26 05:38:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:38:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:38:13 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:13 INFO : There was nothing to transfer 2025/06/26 05:38:19 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/26 05:38:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/06/26 05:38:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/06/26 05:38:19 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:19 INFO : There was nothing to transfer 2025/06/26 05:38:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/26 05:38:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:38:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:38:24 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:24 INFO : There was nothing to transfer 2025/06/26 05:38:24 DEBUG : Excluded 2025/06/26 05:38:24 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/26 05:38:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/06/26 05:38:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/06/26 05:38:24 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:24 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (70.86s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:38:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:38:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:38:30 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:38:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:38:36 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:38:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/CompareDest" 2025/06/26 05:38:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/CompareDest.bin" 2025/06/26 05:38:41 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/CompareDest.bin: Auto set use_segments_container to true 2025/06/26 05:38:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/CompareDest" 2025/06/26 05:38:47 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/CompareDest: Auto set use_segments_container to true 2025/06/26 05:38:57 DEBUG : one: Need to transfer - File not found at Destination 2025/06/26 05:38:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:38:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:38:58 DEBUG : one: md5 = f667e59c10108f7134d019eedf749cbe OK 2025/06/26 05:38:58 INFO : one: Copied (new) 2025/06/26 05:38:58 DEBUG : Waiting for deletions to finish 2025/06/26 05:38:58 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:38:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:39:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:39:04 DEBUG : one: md5 = e72f75b171f8cd839ae3667a38dcc2fb OK 2025/06/26 05:39:04 INFO : one: Copied (replaced existing) 2025/06/26 05:39:04 DEBUG : Waiting for deletions to finish 2025/06/26 05:39:04 DEBUG : dst/one: md5 = f48283d5b274a117fef67852f49a5430 OK 2025/06/26 05:39:04 DEBUG : CompareDest/one: md5 = 6dfe9f9095df1eacc29dcd7ab5fd97b1 OK 2025/06/26 05:39:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:39:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:39:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:05 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/26 05:39:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:39:05 DEBUG : Waiting for deletions to finish 2025/06/26 05:39:05 INFO : There was nothing to transfer 2025/06/26 05:39:10 DEBUG : CompareDest/two: md5 = 4c29803aa93ee981416a421a2cd37a1e OK 2025/06/26 05:39:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:39:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:16 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/26 05:39:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:39:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:16 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/26 05:39:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:39:16 DEBUG : Waiting for deletions to finish 2025/06/26 05:39:16 INFO : There was nothing to transfer 2025/06/26 05:39:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:39:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:21 DEBUG : two: Destination found in --compare-dest, skipping 2025/06/26 05:39:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:39:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:21 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/26 05:39:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:39:21 DEBUG : Waiting for deletions to finish 2025/06/26 05:39:21 INFO : There was nothing to transfer sync_test.go:2176: No hash on uploaded file so skipping compare timestamp test 2025/06/26 05:39:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:39:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:39:21 DEBUG : one: Destination found in --compare-dest, skipping 2025/06/26 05:39:21 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/06/26 05:39:21 DEBUG : two: Need to transfer - File not found at Destination 2025/06/26 05:39:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:39:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:39:27 DEBUG : two: md5 = 2673abcdef93397478f4563ab2f34eec OK 2025/06/26 05:39:27 INFO : two: Copied (new) 2025/06/26 05:39:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (83.48s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:39:53 DEBUG : pre-dest1/1: md5 = 6da67ce70219263af5e7b5a716f3696f OK 2025/06/26 05:39:59 DEBUG : pre-dest2/2: md5 = edba256c1f713232481b61e632e72971 OK 2025/06/26 05:39:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dest" 2025/06/26 05:39:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dest.bin" 2025/06/26 05:40:00 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dest.bin: Auto set use_segments_container to true 2025/06/26 05:40:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dest" 2025/06/26 05:40:05 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dest: Auto set use_segments_container to true 2025/06/26 05:40:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/pre-dest1" 2025/06/26 05:40:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/pre-dest1.bin" 2025/06/26 05:40:11 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/pre-dest1.bin: Auto set use_segments_container to true 2025/06/26 05:40:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/pre-dest1" 2025/06/26 05:40:17 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/pre-dest1: Auto set use_segments_container to true 2025/06/26 05:40:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/pre-dest2" 2025/06/26 05:40:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/pre-dest2.bin" 2025/06/26 05:40:22 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/pre-dest2.bin: Auto set use_segments_container to true 2025/06/26 05:40:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/pre-dest2" 2025/06/26 05:40:28 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/pre-dest2: Auto set use_segments_container to true 2025/06/26 05:40:33 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:40:33 DEBUG : 1: Destination found in --compare-dest, skipping 2025/06/26 05:40:43 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:40:43 DEBUG : 2: Destination found in --compare-dest, skipping 2025/06/26 05:40:54 DEBUG : 3: Need to transfer - File not found at Destination 2025/06/26 05:40:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dest': Waiting for checks to finish 2025/06/26 05:40:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dest': Waiting for transfers to finish 2025/06/26 05:40:59 DEBUG : 3: md5 = f62607f28e4ee7210ef45ce39f2b4d64 OK 2025/06/26 05:40:59 INFO : 3: Copied (new) 2025/06/26 05:40:59 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (77.04s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:41:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:41:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:41:10 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:41:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:41:16 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:41:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/CopyDest" 2025/06/26 05:41:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/CopyDest.bin" 2025/06/26 05:41:21 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/CopyDest.bin: Auto set use_segments_container to true 2025/06/26 05:41:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/CopyDest" 2025/06/26 05:41:27 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/CopyDest: Auto set use_segments_container to true 2025/06/26 05:41:38 DEBUG : one: Need to transfer - File not found at Destination 2025/06/26 05:41:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:41:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:41:38 DEBUG : one: md5 = 03bd304db06034c7a87d930f2183193d OK 2025/06/26 05:41:38 INFO : one: Copied (new) 2025/06/26 05:41:38 DEBUG : Waiting for deletions to finish 2025/06/26 05:41:39 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:41:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:41:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:41:44 DEBUG : one: md5 = 6978c316f0de463f47a59233c35dc4e3 OK 2025/06/26 05:41:44 INFO : one: Copied (replaced existing) 2025/06/26 05:41:44 DEBUG : Waiting for deletions to finish 2025/06/26 05:41:44 DEBUG : dst/one: md5 = 6ee8e182befca02cd01551ae47fa7948 OK 2025/06/26 05:41:45 DEBUG : CopyDest/one: md5 = a36dab638830365fa9d07cc50b526b1a OK 2025/06/26 05:41:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/BackupDir" 2025/06/26 05:41:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/BackupDir.bin" 2025/06/26 05:41:45 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/BackupDir.bin: Auto set use_segments_container to true 2025/06/26 05:41:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/BackupDir" 2025/06/26 05:41:51 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/BackupDir: Auto set use_segments_container to true 2025/06/26 05:41:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:41:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:41:56 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:41:56 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/06/26 05:42:17 INFO : one: Copied (server-side copy) 2025/06/26 05:42:17 INFO : one: Deleted 2025/06/26 05:42:22 INFO : one: Copied (server-side copy) 2025/06/26 05:42:22 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/06/26 05:42:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:42:22 DEBUG : Waiting for deletions to finish 2025/06/26 05:42:33 DEBUG : CopyDest/two: md5 = 7b96a68aa0d4b4cc2a85d2acbf6cfb31 OK 2025/06/26 05:42:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:42:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:42:34 DEBUG : one: Unchanged skipping 2025/06/26 05:42:44 INFO : two: Copied (server-side copy) 2025/06/26 05:42:44 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/06/26 05:42:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:42:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:42:44 DEBUG : Waiting for deletions to finish 2025/06/26 05:42:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:42:50 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:42:50 DEBUG : one: Unchanged skipping 2025/06/26 05:42:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:42:50 DEBUG : two: Unchanged skipping 2025/06/26 05:42:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:42:50 DEBUG : Waiting for deletions to finish 2025/06/26 05:42:50 INFO : There was nothing to transfer 2025/06/26 05:42:56 DEBUG : CopyDest/three: md5 = d6e845bc037d12b736106ec196302ca8 OK 2025/06/26 05:43:06 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:43:06 DEBUG : one: Unchanged skipping 2025/06/26 05:43:06 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/06/26 05:43:06 DEBUG : three: Destination not found in --copy-dest 2025/06/26 05:43:06 DEBUG : three: Need to transfer - File not found at Destination 2025/06/26 05:43:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:43:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:43:06 DEBUG : two: Unchanged skipping 2025/06/26 05:43:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:43:07 DEBUG : three: md5 = 29ecf399da885d3641cce9f89fea829e OK 2025/06/26 05:43:07 INFO : three: Copied (new) 2025/06/26 05:43:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (144.00s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:43:34 DEBUG : dst/one: md5 = fe54bda1abe6313ce8ef8e7a6ac03098 OK 2025/06/26 05:43:39 DEBUG : dst/two: md5 = c34b4dd92d4a3ed703045d6631eb34ee OK 2025/06/26 05:43:45 DEBUG : dst/three.txt: md5 = 40995b7bd33805e1a4f60e1bf18927cc OK 2025/06/26 05:43:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:43:50 DEBUG : Config file has changed externally - reloading 2025/06/26 05:43:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:43:50 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:43:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:43:56 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:44:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/backup" 2025/06/26 05:44:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup.bin" 2025/06/26 05:44:01 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup.bin: Auto set use_segments_container to true 2025/06/26 05:44:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup" 2025/06/26 05:44:07 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup: Auto set use_segments_container to true 2025/06/26 05:44:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:44:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:44:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:44:13 DEBUG : two: Unchanged skipping 2025/06/26 05:44:29 INFO : one: Copied (server-side copy) 2025/06/26 05:44:29 INFO : one: Deleted 2025/06/26 05:44:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:44:29 DEBUG : one: md5 = 0368fdb3904667d39fa5a5fdd12e7e4e OK 2025/06/26 05:44:29 INFO : one: Copied (new) 2025/06/26 05:44:29 DEBUG : Waiting for deletions to finish 2025/06/26 05:44:45 INFO : three.txt: Copied (server-side copy) 2025/06/26 05:44:50 INFO : three.txt: Deleted 2025/06/26 05:44:50 INFO : three.txt: Moved into backup dir 2025/06/26 05:44:55 DEBUG : dst/three.txt: md5 = 5d91d3cfc7fea68c300bbcde552a6536 OK 2025/06/26 05:45:11 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:45:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:45:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:45:11 DEBUG : two: Unchanged skipping 2025/06/26 05:45:32 INFO : one: Copied (server-side copy) 2025/06/26 05:45:32 INFO : one: Deleted 2025/06/26 05:45:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:45:32 DEBUG : one: md5 = 833f2318ed66c716c4900af0c2d8f50b OK 2025/06/26 05:45:32 INFO : one: Copied (new) 2025/06/26 05:45:32 DEBUG : Waiting for deletions to finish 2025/06/26 05:45:52 INFO : three.txt: Copied (server-side copy) 2025/06/26 05:45:58 INFO : three.txt: Deleted 2025/06/26 05:45:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (160.92s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:46:15 DEBUG : dst/one: md5 = c9b1f94ea939708a8b99bf6994f2f44d OK 2025/06/26 05:46:20 DEBUG : dst/two: md5 = 99aa168a05e2b2aadd04c335c550cf54 OK 2025/06/26 05:46:25 DEBUG : dst/three.txt: md5 = 759f5f631fd970bc3b44a95637822b25 OK 2025/06/26 05:46:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:46:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:46:31 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:46:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:46:51 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:46:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/backup" 2025/06/26 05:46:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup.bin" 2025/06/26 05:46:57 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup.bin: Auto set use_segments_container to true 2025/06/26 05:47:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup" 2025/06/26 05:47:02 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup: Auto set use_segments_container to true 2025/06/26 05:47:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:47:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:47:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:47:13 DEBUG : two: Unchanged skipping 2025/06/26 05:47:24 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:47:24 INFO : one: Deleted 2025/06/26 05:47:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:47:24 DEBUG : one: md5 = 989cb3bc5852a9c2f16910580bc24b4b OK 2025/06/26 05:47:24 INFO : one: Copied (new) 2025/06/26 05:47:24 DEBUG : Waiting for deletions to finish 2025/06/26 05:47:40 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:47:45 INFO : three.txt: Deleted 2025/06/26 05:47:45 INFO : three.txt: Moved into backup dir 2025/06/26 05:47:51 DEBUG : dst/three.txt: md5 = ff6301c34e7241ee66034ad6cca5c2f3 OK 2025/06/26 05:48:01 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:48:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:48:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:48:01 DEBUG : two: Unchanged skipping 2025/06/26 05:48:11 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:48:11 INFO : one: Deleted 2025/06/26 05:48:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:48:12 DEBUG : one: md5 = d17859f78217aeeb5625fcebd8228f79 OK 2025/06/26 05:48:12 INFO : one: Copied (new) 2025/06/26 05:48:12 DEBUG : Waiting for deletions to finish 2025/06/26 05:48:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:48:27 INFO : three.txt: Deleted 2025/06/26 05:48:27 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (151.29s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:48:47 DEBUG : dst/one: md5 = 2bb18edfacacf787849074626795b34e OK 2025/06/26 05:48:52 DEBUG : dst/two: md5 = 74b2e382b46b7db0b49d8d801e3a9bd9 OK 2025/06/26 05:48:57 DEBUG : dst/three.txt: md5 = d1d02c4a4867b9642f42231db920e094 OK 2025/06/26 05:49:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:49:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:49:08 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:49:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:49:13 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:49:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/backup" 2025/06/26 05:49:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup.bin" 2025/06/26 05:49:19 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup.bin: Auto set use_segments_container to true 2025/06/26 05:49:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/backup" 2025/06/26 05:49:24 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/backup: Auto set use_segments_container to true 2025/06/26 05:49:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:49:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:49:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:49:30 DEBUG : two: Unchanged skipping 2025/06/26 05:49:40 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/26 05:49:40 INFO : one: Deleted 2025/06/26 05:49:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:49:41 DEBUG : one: md5 = 004cb2c299885c5574f95ebf218e4fb1 OK 2025/06/26 05:49:41 INFO : one: Copied (new) 2025/06/26 05:49:41 DEBUG : Waiting for deletions to finish 2025/06/26 05:50:08 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/26 05:50:13 INFO : three.txt: Deleted 2025/06/26 05:50:13 INFO : three.txt: Moved into backup dir 2025/06/26 05:50:27 DEBUG : dst/three.txt: md5 = 33af43223fe84bccfa3b36efd7d6d19b OK 2025/06/26 05:50:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:50:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:50:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:50:47 DEBUG : two: Unchanged skipping 2025/06/26 05:50:48 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/26 05:50:48 INFO : one: Deleted 2025/06/26 05:50:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:50:48 DEBUG : one: md5 = a3ef04f63507dfc1e1266fdcdcff5b7d OK 2025/06/26 05:50:48 INFO : one: Copied (new) 2025/06/26 05:50:48 DEBUG : Waiting for deletions to finish 2025/06/26 05:50:58 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/26 05:51:03 INFO : three.txt: Deleted 2025/06/26 05:51:03 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (148.29s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:51:15 DEBUG : dst/one: md5 = 66edc9e594cd030df537e60d6b600f0a OK 2025/06/26 05:51:20 DEBUG : dst/two: md5 = 0347bb98befbb0d4e4f159b1de1629f5 OK 2025/06/26 05:51:25 DEBUG : dst/three.txt: md5 = 5fcce65b8b123ecb37a9e2b02cfa2cd4 OK 2025/06/26 05:51:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:51:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:51:31 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:51:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:51:36 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:51:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:51:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:51:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:51:47 DEBUG : two: Unchanged skipping 2025/06/26 05:51:57 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:51:57 INFO : one: Deleted 2025/06/26 05:51:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:51:58 DEBUG : one: md5 = 7ea9d8f0e932077b05c578706d83595f OK 2025/06/26 05:51:58 INFO : one: Copied (new) 2025/06/26 05:51:58 DEBUG : Waiting for deletions to finish 2025/06/26 05:52:18 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:52:23 INFO : three.txt: Deleted 2025/06/26 05:52:23 INFO : three.txt: Moved into backup dir 2025/06/26 05:52:34 DEBUG : dst/three.txt: md5 = 94b5a7176b5c96d3f39a17bc57b1fc5d OK 2025/06/26 05:52:45 DEBUG : one.bak: Excluded (Path Filter) 2025/06/26 05:52:45 DEBUG : one.bak: Excluded 2025/06/26 05:52:45 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/26 05:52:45 DEBUG : three.txt.bak: Excluded 2025/06/26 05:52:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:52:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for checks to finish 2025/06/26 05:52:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:52:50 DEBUG : two: Unchanged skipping 2025/06/26 05:52:55 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:52:55 INFO : one: Deleted 2025/06/26 05:52:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi/dst': Waiting for transfers to finish 2025/06/26 05:52:55 DEBUG : one: md5 = 3a18ad22176174e0c6aa90a04a81cbe5 OK 2025/06/26 05:52:55 INFO : one: Copied (new) 2025/06/26 05:52:55 DEBUG : Waiting for deletions to finish 2025/06/26 05:53:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:53:11 INFO : three.txt: Deleted 2025/06/26 05:53:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (147.25s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:53:42 DEBUG : dst/one: md5 = a61b304d6caee2da3427b6e64bbb0ca1 OK 2025/06/26 05:53:47 DEBUG : dst/two: md5 = fed2afeb70df8d0922abbd4ac80a9823 OK 2025/06/26 05:53:52 DEBUG : dst/three.txt: md5 = a6af8cbf5644eec0353c291e76a916e9 OK 2025/06/26 05:53:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:53:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:53:53 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:53:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:53:58 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:54:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:54:19 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:54:20 INFO : one: Deleted 2025/06/26 05:54:20 DEBUG : one: md5 = 00b4e2286cbecf9922c0b2b4cc4cdb55 OK 2025/06/26 05:54:20 INFO : one: Copied (new) 2025/06/26 05:54:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:54:20 DEBUG : two: Unchanged skipping 2025/06/26 05:54:20 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/26 05:54:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:54:40 INFO : three.txt: Deleted 2025/06/26 05:54:45 DEBUG : three.txt: md5 = da3d578c796f2070e25a967328a22b4d OK 2025/06/26 05:54:45 INFO : three.txt: Copied (new) 2025/06/26 05:54:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:55:07 INFO : one: Copied (server-side copy) to: one.bak 2025/06/26 05:55:07 INFO : one: Deleted 2025/06/26 05:55:07 DEBUG : one: md5 = 9be34e574ab733ada833eb8377b2e78f OK 2025/06/26 05:55:07 INFO : one: Copied (new) 2025/06/26 05:55:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:55:07 DEBUG : two: Unchanged skipping 2025/06/26 05:55:12 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/26 05:55:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/06/26 05:55:28 INFO : three.txt: Deleted 2025/06/26 05:55:33 DEBUG : three.txt: md5 = 324a6f4eae7c6c1c19418a3c3726b090 OK 2025/06/26 05:55:33 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (142.17s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:56:04 DEBUG : dst/one: md5 = d65eb01d42800d7f185b4a0eb4c14ac2 OK 2025/06/26 05:56:09 DEBUG : dst/two: md5 = a8ccceb4abcbad435978062edb3774be OK 2025/06/26 05:56:14 DEBUG : dst/three.txt: md5 = 273fda55a3da83193bb1a6c6273af6d2 OK 2025/06/26 05:56:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hizusoz1deqi/dst" 2025/06/26 05:56:15 DEBUG : Config file has changed externally - reloading 2025/06/26 05:56:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst.bin" 2025/06/26 05:56:15 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst.bin: Auto set use_segments_container to true 2025/06/26 05:56:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hizusoz1deqi/dst" 2025/06/26 05:56:21 DEBUG : Swift container crypt path rclone-test-hizusoz1deqi/dst: Auto set use_segments_container to true 2025/06/26 05:56:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/26 05:56:41 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/26 05:56:42 INFO : one: Deleted 2025/06/26 05:56:42 DEBUG : one: md5 = 65ebdc2762d255645acbd686ada9d44f OK 2025/06/26 05:56:42 INFO : one: Copied (new) 2025/06/26 05:56:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:56:42 DEBUG : two: Unchanged skipping 2025/06/26 05:56:42 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/06/26 05:57:01 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/26 05:57:06 INFO : three.txt: Deleted 2025/06/26 05:57:11 DEBUG : three.txt: md5 = 49a8482751e7290b2552f0ee5a4bdb70 OK 2025/06/26 05:57:11 INFO : three.txt: Copied (new) 2025/06/26 05:57:32 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/26 05:57:47 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/06/26 05:57:47 INFO : one: Deleted 2025/06/26 05:57:47 DEBUG : one: md5 = 84334bf4fbd77590ae634864f68552e4 OK 2025/06/26 05:57:47 INFO : one: Copied (new) 2025/06/26 05:57:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:57:47 DEBUG : two: Unchanged skipping 2025/06/26 05:57:52 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/06/26 05:58:08 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/06/26 05:58:13 INFO : three.txt: Deleted 2025/06/26 05:58:18 DEBUG : three.txt: md5 = e749270bbb4f04d36384b9e7ea14532c OK 2025/06/26 05:58:18 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (175.91s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:59:06 DEBUG : Testêé: md5 = dfaaf3d5cb4797de90430f8eb75032d5 OK 2025/06/26 05:59:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:59:06 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/06/26 05:59:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:59:16 DEBUG : Testêé: md5 = 77f9ef48e4725481f6209ea355676120 OK 2025/06/26 05:59:16 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/06/26 05:59:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (31.98s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:59:32 DEBUG : existing: Need to transfer - File not found at Destination 2025/06/26 05:59:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:59:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:59:38 DEBUG : existing: md5 = 67f7af4b18c4d40928f92604226062e4 OK 2025/06/26 05:59:38 INFO : existing: Copied (new) 2025/06/26 05:59:38 DEBUG : Waiting for deletions to finish 2025/06/26 05:59:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:59:38 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/06/26 05:59:38 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/06/26 05:59:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:59:38 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': not deleting files as there were IO errors 2025/06/26 05:59:38 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (11.63s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 05:59:49 DEBUG : EXISTING: md5 = 53c2c3bdfaaa8ad8242755655c240f34 OK 2025/06/26 05:59:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 05:59:54 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 05:59:54 DEBUG : existing: Unchanged skipping 2025/06/26 05:59:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 05:59:54 DEBUG : Waiting for deletions to finish 2025/06/26 05:59:54 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (15.96s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:2636: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.36s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:2675: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" sync_test.go:2675: This test only runs on local --- PASS: TestMaxTransfer (0.54s) --- SKIP: TestMaxTransfer/Hard (0.19s) --- SKIP: TestMaxTransfer/Soft (0.15s) --- SKIP: TestMaxTransfer/Cautious (0.20s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:00:01 DEBUG : both0: md5 = 2054b42c55a7c168ddf8b4a16640c20a OK 2025/06/26 06:00:01 DEBUG : only0: md5 = 319d54cf17292c9b74a5f4900f5ae6f8 OK 2025/06/26 06:00:02 DEBUG : both1: md5 = 3d80e37dc82994e6b56bec271679572a OK 2025/06/26 06:00:07 DEBUG : only1: md5 = 9a404d53831b60ef51abfc03c92b991b OK 2025/06/26 06:00:07 DEBUG : both2: md5 = b1d180c1f1f7c5d9d261614c9842bf52 OK 2025/06/26 06:00:13 DEBUG : only2: md5 = b816ccc824eac7bc6a2101c7b325d96b OK 2025/06/26 06:00:13 DEBUG : both3: md5 = bf7c1d1cd9c41e20eb53e6a9d56bbe2e OK 2025/06/26 06:00:14 DEBUG : only3: md5 = d2fd37915d2e8e5d2477e6b04c382e53 OK 2025/06/26 06:00:14 DEBUG : both4: md5 = 5abe4254a4447f0fd2a8ab75ff0fc02c OK 2025/06/26 06:00:15 DEBUG : only4: md5 = df9602fff6851202b6406b548ea8c7d6 OK 2025/06/26 06:00:15 DEBUG : both5: md5 = 4753e8f471814dfe93a35354b94f5da7 OK 2025/06/26 06:00:15 DEBUG : only5: md5 = 17b6441e135e2b3f972352abfc2da3b9 OK 2025/06/26 06:00:16 DEBUG : both6: md5 = 4bbc7e2d700969c0b9530b6c8822035a OK 2025/06/26 06:00:17 DEBUG : only6: md5 = 5e11fd95377ff12b65b224ab311d7a34 OK 2025/06/26 06:00:22 DEBUG : both7: md5 = f782381fc2bf83b5700f905b1171a23d OK 2025/06/26 06:00:28 DEBUG : only7: md5 = f40fc1ee4d7150eb43394467efd25658 OK 2025/06/26 06:00:33 DEBUG : both8: md5 = e1e3ab88280fdfeca63af440bc6f1ee3 OK 2025/06/26 06:00:34 DEBUG : only8: md5 = 2ec3a05eea5287033c421525014d13ca OK 2025/06/26 06:00:34 DEBUG : both9: md5 = 280fcc0d3dd2d2b65abb3dd4b731e7f2 OK 2025/06/26 06:00:39 DEBUG : only9: md5 = 962f682635de4650bfe01e612e6f1013 OK 2025/06/26 06:00:40 DEBUG : both10: md5 = a36efcf8ffdc0d0a1da4844e4ac6ed2f OK 2025/06/26 06:00:40 DEBUG : only10: md5 = bf9d50b2de43be92e82d85c636ecd814 OK 2025/06/26 06:00:41 DEBUG : both11: md5 = 28306c0df9661638ce9d7f4c1ff4f4f0 OK 2025/06/26 06:00:47 DEBUG : only11: md5 = c4c6952320eaad413e56ce571d947575 OK 2025/06/26 06:00:52 DEBUG : both12: md5 = b91f51f56b637d3a3cf92351cecfbf52 OK 2025/06/26 06:00:53 DEBUG : only12: md5 = 021852a5934b9b34e1f34e505bdf0938 OK 2025/06/26 06:00:58 DEBUG : both13: md5 = d502456cc12485151eb0ca17524702ad OK 2025/06/26 06:01:04 DEBUG : only13: md5 = 37096a266f59da29398e589892c5d239 OK 2025/06/26 06:01:09 DEBUG : both14: md5 = 04a293de74a79d90f5557171629af65f OK 2025/06/26 06:01:11 DEBUG : only14: md5 = 814910bf1b32dcf531484c15f8d7b52c OK 2025/06/26 06:01:17 DEBUG : both15: md5 = b5cce2b7880a5cb212e58b9aa6329601 OK 2025/06/26 06:01:17 DEBUG : only15: md5 = 95fe55f4905ab03299d899a4f0cbf67f OK 2025/06/26 06:01:23 DEBUG : both16: md5 = 4a990fd0f97c5db79419ac93425861e9 OK 2025/06/26 06:01:28 DEBUG : only16: md5 = 2021433e60ad0ee19fa444915d53aa20 OK 2025/06/26 06:01:28 DEBUG : both17: md5 = 91693dc04635169d7aba702815d983ff OK 2025/06/26 06:01:34 DEBUG : only17: md5 = e0438bab1b03dc50728c2f91db4271df OK 2025/06/26 06:01:34 DEBUG : both18: md5 = 2629889f551210f2d26519083c72de2c OK 2025/06/26 06:01:39 DEBUG : only18: md5 = b8f82f27cb491a363bdca78a96cb4474 OK 2025/06/26 06:01:45 DEBUG : both19: md5 = 77c323b4a2001a5a2cf631041049323b OK 2025/06/26 06:01:45 DEBUG : only19: md5 = bfc4cdbc57484f75772e31e342cdf55c OK 2025/06/26 06:02:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 06:02:00 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both0: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both10: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both11: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both12: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both13: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both14: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both16: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both17: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both18: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both19: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both1: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both2: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both3: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both4: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both5: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both6: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both7: Unchanged skipping 2025/06/26 06:02:00 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:00 DEBUG : both9: Unchanged skipping 2025/06/26 06:02:05 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:05 DEBUG : both15: Unchanged skipping 2025/06/26 06:02:05 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:02:05 DEBUG : both8: Unchanged skipping 2025/06/26 06:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 06:02:05 DEBUG : Waiting for deletions to finish 2025/06/26 06:02:06 INFO : only12: Deleted 2025/06/26 06:02:06 INFO : only19: Deleted 2025/06/26 06:02:08 INFO : only14: Deleted 2025/06/26 06:02:08 INFO : only4: Deleted 2025/06/26 06:02:08 INFO : only5: Deleted 2025/06/26 06:02:08 INFO : only0: Deleted 2025/06/26 06:02:08 INFO : only10: Deleted 2025/06/26 06:02:08 INFO : only15: Deleted 2025/06/26 06:02:10 INFO : only2: Deleted 2025/06/26 06:02:11 INFO : only18: Deleted 2025/06/26 06:02:11 INFO : only6: Deleted 2025/06/26 06:02:16 INFO : only9: Deleted 2025/06/26 06:02:18 INFO : only7: Deleted 2025/06/26 06:02:18 INFO : only17: Deleted 2025/06/26 06:02:21 INFO : only11: Deleted 2025/06/26 06:02:21 INFO : only3: Deleted 2025/06/26 06:02:22 INFO : only8: Deleted 2025/06/26 06:02:23 INFO : only16: Deleted 2025/06/26 06:02:26 INFO : only13: Deleted 2025/06/26 06:02:29 INFO : only1: Deleted 2025/06/26 06:02:29 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (217.69s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:03:38 DEBUG : both0: md5 = fc3c96cf3c81e4e726a8ce2044062b82 OK 2025/06/26 06:03:38 DEBUG : only0: md5 = e921eb8770a0f046fcdd040583f43999 OK 2025/06/26 06:03:39 DEBUG : both1: md5 = 358a6defb06f5a7b034ea1d12224f6d3 OK 2025/06/26 06:03:44 DEBUG : only1: md5 = d49f260f80158ea8cc9f7d3331cd579e OK 2025/06/26 06:03:44 DEBUG : both2: md5 = a169aec8404273994c0fc2cb5bc07f02 OK 2025/06/26 06:03:49 DEBUG : only2: md5 = 1fdbca83476d71d2b7ef3e3a9cd9f6d9 OK 2025/06/26 06:03:49 DEBUG : both3: md5 = beeb4ff953eec2aedfa12e956dfcd15a OK 2025/06/26 06:03:50 DEBUG : only3: md5 = 1ffe24e1c130e8752adc51e809d0e8d0 OK 2025/06/26 06:03:50 DEBUG : both4: md5 = 3611f76a59b6965f55681003b187d1c9 OK 2025/06/26 06:03:50 DEBUG : only4: md5 = ffc448a35da60e77571f5ce1e1a43d1b OK 2025/06/26 06:03:50 DEBUG : both5: md5 = bf80690c5360ff12e2e29780ff583d18 OK 2025/06/26 06:03:50 DEBUG : only5: md5 = cf3be6634880584bee8f834a999aa99a OK 2025/06/26 06:03:50 DEBUG : both6: md5 = 7dfe823ae86529b457cd7725f1a76fc1 OK 2025/06/26 06:03:51 DEBUG : only6: md5 = af8881d7838d26aa2384a7be344fab73 OK 2025/06/26 06:03:56 DEBUG : both7: md5 = 543e1a9cc809240657589ad24afe816d OK 2025/06/26 06:04:01 DEBUG : only7: md5 = c86295e1a8aa2d8254d561bc8eb6b5dd OK 2025/06/26 06:04:06 DEBUG : both8: md5 = 32e97e79381880f42c0d46c3204a38bf OK 2025/06/26 06:04:06 DEBUG : only8: md5 = 114f666fdbe94058a29165c55bad1154 OK 2025/06/26 06:04:06 DEBUG : both9: md5 = 7f8f27ff7a87f23fe800f636a24435f6 OK 2025/06/26 06:04:11 DEBUG : only9: md5 = 1228ee8a1fca7da1da0c8f998da31607 OK 2025/06/26 06:04:12 DEBUG : both10: md5 = 8b4f27d1dad06274c46599d35694d675 OK 2025/06/26 06:04:12 DEBUG : only10: md5 = 792fac64b52e73e1cd49a2a9fb78cd3f OK 2025/06/26 06:04:12 DEBUG : both11: md5 = 1ad693909ff0ccc326c97a2d14133ad5 OK 2025/06/26 06:04:17 DEBUG : only11: md5 = e8f388e68c8159be21db138e83baba14 OK 2025/06/26 06:04:23 DEBUG : both12: md5 = e4a8053a867ffbe86073eae2a141e8fd OK 2025/06/26 06:04:23 DEBUG : only12: md5 = 0996281a70b040228a3f8123e53fcce9 OK 2025/06/26 06:04:28 DEBUG : both13: md5 = 58ab2a46e45208d82cb4e8cced0ea2d0 OK 2025/06/26 06:04:33 DEBUG : only13: md5 = 192fe0af8a1846c1622461d2c9451821 OK 2025/06/26 06:04:38 DEBUG : both14: md5 = b1f0d7da9d7c469f0202d447ff10a864 OK 2025/06/26 06:04:38 DEBUG : only14: md5 = ef63028c28a4a0aaa49a677e3df5d277 OK 2025/06/26 06:04:44 DEBUG : both15: md5 = 8dcdbe452e754808b0a89e2d21611367 OK 2025/06/26 06:04:44 DEBUG : only15: md5 = db0ec627a16d1bec325a45d49402daed OK 2025/06/26 06:04:49 DEBUG : both16: md5 = 966dfaea619a2dc3313787f93e4aba86 OK 2025/06/26 06:04:54 DEBUG : only16: md5 = 08f1bbaba85300cc68b27fee34dd845c OK 2025/06/26 06:04:54 DEBUG : both17: md5 = ca6752a425e08f7b6304ed7fae7d69e8 OK 2025/06/26 06:04:59 DEBUG : only17: md5 = 768a52c8e39c4953b4f4372f0ee094a9 OK 2025/06/26 06:04:59 DEBUG : both18: md5 = 9db4cddc4a79603e94304b6c31f917c7 OK 2025/06/26 06:05:05 DEBUG : only18: md5 = 4eee73043ef504e6a636083b36e6665f OK 2025/06/26 06:05:10 DEBUG : both19: md5 = 4ace67435667767fb16e402ddb843a1b OK 2025/06/26 06:05:10 DEBUG : only19: md5 = 976824d5212a9aa725056e22236770ef OK 2025/06/26 06:05:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 06:05:42 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both0: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both10: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both11: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both12: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both13: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both14: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both17: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both18: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both19: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both1: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both2: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both3: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both5: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both4: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both7: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both6: Unchanged skipping 2025/06/26 06:05:42 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:42 DEBUG : both9: Unchanged skipping 2025/06/26 06:05:42 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:42 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/06/26 06:05:43 DEBUG : only10: md5 = ebeca20c75d3084c7f0c8dd78f96c0ff OK 2025/06/26 06:05:43 INFO : only10: Copied (replaced existing) 2025/06/26 06:05:43 DEBUG : only12: md5 = 81b097f2bb7b8bc5ac7565d26c7603c6 OK 2025/06/26 06:05:43 INFO : only12: Copied (replaced existing) 2025/06/26 06:05:43 DEBUG : only0: md5 = e1906a7ee6c7477128bd7c4b33dff8b8 OK 2025/06/26 06:05:43 INFO : only0: Copied (replaced existing) 2025/06/26 06:05:43 DEBUG : only14: md5 = 5acf789e3932c5304baa9346351ca4d5 OK 2025/06/26 06:05:43 INFO : only14: Copied (replaced existing) 2025/06/26 06:05:43 DEBUG : only15: md5 = 6745bb4a37387169edc9491527ebdc0a OK 2025/06/26 06:05:43 INFO : only15: Copied (replaced existing) 2025/06/26 06:05:47 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:47 DEBUG : both15: Unchanged skipping 2025/06/26 06:05:47 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:47 DEBUG : both16: Unchanged skipping 2025/06/26 06:05:47 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:05:47 DEBUG : both8: Unchanged skipping 2025/06/26 06:05:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 06:05:48 DEBUG : only11: md5 = c7122f6bc041e1af50bf8ebbb5f53031 OK 2025/06/26 06:05:48 INFO : only11: Copied (replaced existing) 2025/06/26 06:05:48 DEBUG : only16: md5 = bc370c6c7ca50f402f4e1ba1e44d41fd OK 2025/06/26 06:05:48 INFO : only16: Copied (replaced existing) 2025/06/26 06:05:48 DEBUG : only17: md5 = 0c1f4421f722536c7a79b4e5c63295af OK 2025/06/26 06:05:48 INFO : only17: Copied (replaced existing) 2025/06/26 06:05:48 DEBUG : only19: md5 = 688cdab910ebe63b20424248f39f0eb5 OK 2025/06/26 06:05:48 INFO : only19: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only13: md5 = ad348f78102e4d828d7c110c5cad1e43 OK 2025/06/26 06:05:53 INFO : only13: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only18: md5 = 77f2e0faff050895a82d245f1206f861 OK 2025/06/26 06:05:53 INFO : only18: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only3: md5 = 5d3701d53c67554f27172342d15f51f8 OK 2025/06/26 06:05:53 INFO : only3: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only5: md5 = 8f07da72d8da6bc8f1abf89583c50f57 OK 2025/06/26 06:05:53 INFO : only5: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only1: md5 = 3855d25590dd5d1ff616825a4da0260f OK 2025/06/26 06:05:53 INFO : only1: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only6: md5 = 2db115ca27544551c27b90d8a180a918 OK 2025/06/26 06:05:53 INFO : only6: Copied (replaced existing) 2025/06/26 06:05:53 DEBUG : only4: md5 = 4c821a643617d2e6e3c7ffa3715d64fd OK 2025/06/26 06:05:53 INFO : only4: Copied (replaced existing) 2025/06/26 06:05:54 DEBUG : only2: md5 = 5642706c168b09d8ef7715cd05201467 OK 2025/06/26 06:05:54 INFO : only2: Copied (replaced existing) 2025/06/26 06:05:54 DEBUG : only8: md5 = b128df1e52b9240a322f7d15ff826bee OK 2025/06/26 06:05:54 INFO : only8: Copied (replaced existing) 2025/06/26 06:05:59 DEBUG : only9: md5 = 3d3d4bbb0e12dca73931426c3b3708f0 OK 2025/06/26 06:05:59 INFO : only9: Copied (replaced existing) 2025/06/26 06:06:03 DEBUG : only7: md5 = e1d3d3c9e32968bff7d94f2864a601a2 OK 2025/06/26 06:06:03 INFO : only7: Copied (replaced existing) 2025/06/26 06:06:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (289.81s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:08:28 DEBUG : empty_dir: Making directory with metadata 2025/06/26 06:08:28 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 06:08:28 DEBUG : empty_on_remote: Making directory with metadata 2025/06/26 06:08:28 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 06:08:28 INFO : empty_on_remote: Making directory 2025/06/26 06:08:28 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/26 06:08:30 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:30 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:30 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:30 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTime (4.16s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:08:32 DEBUG : empty_dir: Making directory with metadata 2025/06/26 06:08:32 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 06:08:32 DEBUG : empty_on_remote: Making directory with metadata 2025/06/26 06:08:32 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/06/26 06:08:32 INFO : empty_on_remote: Making directory 2025/06/26 06:08:32 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/06/26 06:08:33 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:33 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:33 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:33 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:33 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (3.79s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:08:36 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:36 INFO : sub dir: Making directory 2025/06/26 06:08:36 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (13.27s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:08:49 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/26 06:08:49 INFO : sub dir: Making directory 2025/06/26 06:08:49 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithoutEmptyDirs (13.98s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:09:04 DEBUG : dir1/0000-abcdefg.txt: md5 = 02adee8a241c73981f1e5d4a078b098e OK 2025/06/26 06:09:09 DEBUG : dir1/0001-bcdefgh.txt: md5 = 10365752017dce4c07cd4ebeaa22954a OK 2025/06/26 06:09:15 DEBUG : dir1/0002-cdefghi.txt: md5 = d97ec5d68c690e8fa44a3bcd137211b3 OK 2025/06/26 06:09:20 DEBUG : dir1/0003-defghij.txt: md5 = bfb6b392b03ca66fb9a7673f65e4f419 OK 2025/06/26 06:09:20 DEBUG : dir1/0004-efghijk.txt: md5 = ba735d4304ee7100b08e09528f683a95 OK 2025/06/26 06:09:26 DEBUG : dir1/0005-fghijkl.txt: md5 = 209dd83e2a6f2a204b893c4d04fe7215 OK 2025/06/26 06:09:31 DEBUG : dir1/0006-ghijklm.txt: md5 = 2d02b32a7594aab204b51216b4d834b1 OK 2025/06/26 06:09:36 DEBUG : dir1/0007-1234567.txt: md5 = b974be47130694d4c84f76523de733e3 OK 2025/06/26 06:09:42 DEBUG : dir1/0008-2345678.txt: md5 = 4a9fc7a7f136e20207a8e1944d3c8560 OK 2025/06/26 06:09:47 DEBUG : dir1/0009-3456789.txt: md5 = 3f3c71119c99059d788e82d02b26275c OK 2025/06/26 06:09:52 DEBUG : dir1/0010-456789.txt: md5 = de6839e42f9018b020d8f0ad7330bb8b OK 2025/06/26 06:09:58 DEBUG : dir1/0011-56789;.txt: md5 = d5eb552930718e8e6233a9bbed8db6aa OK 2025/06/26 06:10:03 DEBUG : dir1/0012-6789;.txt: md5 = 060dd6d844cdc5025831e3cbaa953c3c OK 2025/06/26 06:10:08 DEBUG : dir1/0013-789;=.txt: md5 = 380fca5b26176c66879586c957aa555e OK 2025/06/26 06:10:13 DEBUG : dir1/0014-89;=.txt: md5 = 0bfd1bc9d19da919bd17070edee324a0 OK 2025/06/26 06:10:19 DEBUG : dir1/0015-9;=.txt: md5 = aa7ec8d6dfebe83797c612604227e107 OK 2025/06/26 06:10:19 DEBUG : apple: md5 = 3d8bf030b589573511c3a2809708846e OK 2025/06/26 06:10:25 DEBUG : banana: md5 = cb259c3c404d3dc1c4a688eef34ad27c OK 2025/06/26 06:10:30 DEBUG : appleappleapplebanana: md5 = 732d0b322b7303252373eb756a6ff27f OK 2025/06/26 06:10:35 DEBUG : splitbananasplit: md5 = dc29cb27365f2dfbc4047705b65b5ed1 OK 2025/06/26 06:10:35 DEBUG : Waiting for deletions to finish 2025/06/26 06:10:35 DEBUG : apple: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : banana: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/26 06:10:35 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/26 06:10:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 06:10:56 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : apple: Unchanged skipping 2025/06/26 06:10:56 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : splitbananasplit: Unchanged skipping 2025/06/26 06:10:56 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/26 06:10:56 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : banana: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/26 06:10:56 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:10:56 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/26 06:11:01 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:01 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/26 06:11:01 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:01 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/26 06:11:01 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:01 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/26 06:11:01 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:01 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/26 06:11:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 06:11:01 DEBUG : Waiting for deletions to finish 2025/06/26 06:11:01 INFO : There was nothing to transfer 2025/06/26 06:11:01 DEBUG : Waiting for deletions to finish 2025/06/26 06:11:02 DEBUG : apple: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : banana: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/26 06:11:02 DEBUG : apple: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : apple: Unchanged skipping 2025/06/26 06:11:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for checks to finish 2025/06/26 06:11:02 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/26 06:11:02 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:02 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : appleappleapplebanana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/26 06:11:07 DEBUG : splitbananasplit: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : splitbananasplit: Unchanged skipping 2025/06/26 06:11:07 DEBUG : banana: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : banana: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/06/26 06:11:07 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/26 06:11:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi': Waiting for transfers to finish 2025/06/26 06:11:07 DEBUG : Waiting for deletions to finish 2025/06/26 06:11:07 INFO : There was nothing to transfer 2025/06/26 06:11:07 DEBUG : Waiting for deletions to finish 2025/06/26 06:11:07 DEBUG : apple: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : banana: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/26 06:11:07 DEBUG : splitbananasplit: Excluded (Path Filter) === RUN TestTransform/NFD run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-hizusoz1deqi'", Local "Local file system at /tmp/rclone2303589887", Modify Window "1ns" 2025/06/26 06:13:11 DEBUG : dir1/0000-abcdefg.txt: md5 = 19d45a4b3f3fc98aac9451e2ce924075 OK 2025/06/26 06:13:17 DEBUG : dir1/0001-bcdefgh.txt: md5 = f033669c09cb8499eb06596fec81a1d8 OK 2025/06/26 06:13:22 DEBUG : dir1/0002-cdefghi.txt: md5 = efffccb1ff92e9e924f41ca08715a4e4 OK 2025/06/26 06:13:27 DEBUG : dir1/0003-defghij.txt: md5 = b40606b0746df73a6521191300f95221 OK 2025/06/26 06:13:27 DEBUG : dir1/0004-efghijk.txt: md5 = 90f0ca912b72ea45c3c7748925956eac OK 2025/06/26 06:13:32 DEBUG : dir1/0005-fghijkl.txt: md5 = 7ed3aa571f06bad5dcfa5fe01fc2c0bf OK 2025/06/26 06:13:37 DEBUG : dir1/0006-ghijklm.txt: md5 = 2a82372aca3ec83ef72e1766aea0f1f4 OK 2025/06/26 06:13:43 DEBUG : dir1/0007-1234567.txt: md5 = 5887d46aeb7c94d8187606ebd06d98a3 OK 2025/06/26 06:13:48 DEBUG : dir1/0008-2345678.txt: md5 = f09ec103b830387023170020bb44bb6b OK 2025/06/26 06:13:53 DEBUG : dir1/0009-3456789.txt: md5 = b7867bd7a1ac51a1d7dbbaf264b54555 OK 2025/06/26 06:13:58 DEBUG : dir1/0010-456789.txt: md5 = 045ab1915a3bb0f452612339295fbc4c OK 2025/06/26 06:14:03 DEBUG : dir1/0011-56789;.txt: md5 = f275807fac4c05e0d44757160224889e OK 2025/06/26 06:14:09 DEBUG : dir1/0012-6789;.txt: md5 = 0f25334e3a304232f5172e0b1ab4a3a5 OK panic: test timed out after 1h0m0s running tests: TestTransform (5m8s) TestTransform/NFD (1m0s) goroutine 14873 [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(0xc0019c48c0, {0x26954f2?, 0xc0006b1ad8?}, 0x27c1ad8) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0019c48c0) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0019c48c0, 0xc0006b1c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc001a70420, {0x3e7e5e0, 0x6b, 0x6b}, {0xedfeece23?, 0x3e945a0?, 0x3e945a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc0004820a0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0004820a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:259 +0xa9 goroutine 35 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 36 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 14012 [chan receive]: testing.(*T).Run(0xc001a7bdc0, {0x2679b35?, 0x41bb05?}, 0xc000978000) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/fs/sync.TestTransform(0xc001a7bdc0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:95 +0x62a testing.tRunner(0xc001a7bdc0, 0x27c1ad8) /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 14814 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc0002eba40, 0xc0004891f0, 0xc000990b40) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc0002eba40, {0x2af4be8, 0x3eb7b60}, {0xc001b820f0, 0x4f}, {{0xc001a19040, 0x5}, {0xc001a19046, 0x30}, {0x2679d24, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc0002eba40, {0x2af4be8, 0x3eb7b60}, {{0xc001a19040, 0x5}, {0xc001a19046, 0x30}, {0x2679d24, 0x3}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).objectPut(0xc0002eba40, {0x2af4be8, 0x3eb7b60}, {0xc001a19040, 0x5}, {0xc001a19046, 0x30}, {0x2acc820, 0xc0002fb4e0}, 0x1, ...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1569 +0x1f5 github.com/ncw/swift/v2.(*Connection).ObjectPut(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1615 github.com/rclone/rclone/backend/swift.(*Object).Update.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1559 +0xb0 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001b2b6e0, 0xc0000ca400, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:225 github.com/rclone/rclone/backend/swift.(*Object).Update(0xc00038fc20, {0x2af4be8, 0x3eb7b60}, {0x2acc820, 0xc0002fb4e0}, {0x2affe60, 0xc00055f620}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1558 +0x7b8 github.com/rclone/rclone/backend/swift.(*Fs).Put(0xc001b8a000, {0x2af4be8, 0x3eb7b60}, {0x2acc820, 0xc0002fb4e0}, {0x2affe60, 0xc00055f620}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:937 +0x139 github.com/rclone/rclone/backend/crypt.(*Fs).put(0xc001a36ea0, {0x2af4be8, 0x3eb7b60}, {0x2acc620, 0xc00055f3b0}, {0x2affcd0, 0xc000488fc0}, {0x0, 0x0, 0x0}, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:536 +0x3c3 github.com/rclone/rclone/backend/crypt.(*Fs).Put(0x461f3c?, {0x2af4be8?, 0x3eb7b60?}, {0x2acc620?, 0xc00055f3b0?}, {0x2affcd0?, 0xc000488fc0?}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:571 +0x91 github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc00032d030, {0x2af4be8, 0x3eb7b60}, {0x2b09360, 0xc001a36ea0}, {0xc00093ec48, 0x13}, {0xc00093ec48, 0x13}, {0x1dcd64ff, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:275 +0x3f2 github.com/rclone/rclone/fstest.(*Run).WriteObject(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 github.com/rclone/rclone/fs/sync.makeTestFiles(0xc00032d030?, 0xc00032d030, {0x267aa9c, 0x4}) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:150 +0x425 github.com/rclone/rclone/fs/sync.TestTransform.func1(0xc001b8a540) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:102 +0xd3 testing.tRunner(0xc001b8a540, 0xc000978000) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 14012 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 14569 [select]: net/http.(*persistConn).writeLoop(0xc00051b8c0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 14437 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 2067 [chan receive, 57 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 2050 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 13804 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc001c58000, {0x2af4cc8, 0xc000712500}) /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 13769 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 14568 [IO wait]: internal/poll.runtime_pollWait(0x7e7a49e8bd18, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000440900?, 0xc000b09980?, 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(0xc000440900, {0xc000b09980, 0x1980, 0x1980}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000440900, {0xc000b09980?, 0x1973?, 0xc001b0ae40?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000148dd0, {0xc000b09980?, 0xc000187dd0?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc001b1d8d8, {0xc000b09980?, 0x7e7a91b6f170?, 0x7e7a91b66108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000011080, {0xc000b09980?, 0x1973?, 0xc0009af9d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000187eb8, {0x2acf600, 0xc000011080}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000187c08, {0x7e7a4831cf00, 0xc001b1d8d8}, 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, {0xc00093c000, 0x1000, 0xc000484e40?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc00051b8c0, {0xc00093c000?, 0x7f4fc5?, 0x2acc520?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc001f0f080) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc001f0f080, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc00051b8c0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 14437 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 14838 [select]: net/http.(*persistConn).roundTrip(0xc00051b8c0, 0xc00098cb40) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc0005de340, 0xc000990b40) /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(0xc00047a1c0, 0xc000990b40) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000990b40, {0x2ace0a0, 0xc00047a1c0}, {0x1?, 0xc000089d80?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001ab8ae0, 0xc000990b40, {0x8000000025ef300?, 0xc000469680?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001ab8ae0, 0xc000990b40) /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 14814 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.417961963s (try 5/5): exit status 2: Failed []