"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2024/09/26 02:52:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu" 2024/09/26 02:52:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/26 02:52:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu.bin" 2024/09/26 02:52:05 DEBUG : Swift container crypt path rclone-test-niladoz6tesu.bin: Auto set use_segments_container to true 2024/09/26 02:52:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu" 2024/09/26 02:52:06 DEBUG : Swift container crypt path rclone-test-niladoz6tesu: Auto set use_segments_container to true 2024/09/26 02:52:06 DEBUG : Creating backend with remote "/tmp/rclone3339624811" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === 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-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:06 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/09/26 02:52:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.59s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:07 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/26 02:52:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:07 DEBUG : sub dir/hello world: md5 = 5b14ebbb5086743b8d4be35c63f09e2e OK 2024/09/26 02:52:07 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.49s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.09s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:09 DEBUG : Creating backend with remote "/non-existing" 2024/09/26 02:52:09 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/09/26 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.24s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:09 DEBUG : sub dir/hello world: md5 = 699e6bfb2b7c21f7a91ab00836754252 OK 2024/09/26 02:52:09 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyNoTraverse (0.95s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Running all checks before starting transfers 2024/09/26 02:52:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:10 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Checks finished, now starting transfers 2024/09/26 02:52:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:10 DEBUG : sub dir/hello world: md5 = 20abab187873a816c4c898c42d1dc350 OK 2024/09/26 02:52:10 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyCheckFirst (1.04s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:11 ERROR : Ignoring --no-traverse with sync 2024/09/26 02:52:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:11 DEBUG : sub dir/hello world: md5 = 0ce9425b747f67012b44881d08e49ff8 OK 2024/09/26 02:52:11 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:52:11 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncNoTraverse (1.15s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:12 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/09/26 02:52:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:12 DEBUG : hello world2: md5 = 4ed62d819e9216d8715c24c3689d1de8 OK 2024/09/26 02:52:12 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyWithDepth (1.42s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:14 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:52:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:14 DEBUG : potato2: md5 = c3a299d7d0839bc33b5401eb35140b65 OK 2024/09/26 02:52:14 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.37s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:15 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:52:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:15 DEBUG : potato2: md5 = 560541e04eefc7fc550e268a0d32638e OK 2024/09/26 02:52:15 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.02s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:16 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/26 02:52:16 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/26 02:52:16 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2024/09/26 02:52:16 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/26 02:52:16 DEBUG : sub dir: Making directory 2024/09/26 02:52:16 DEBUG : sub dir2: Making directory 2024/09/26 02:52:16 DEBUG : sub dir2/sub sub dir2: Making directory 2024/09/26 02:52:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:16 DEBUG : sub dir/hello world: md5 = 9907f71a76e0f53d204166b436781c5f OK 2024/09/26 02:52:16 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2024/09/26 02:52:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.05s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:17 DEBUG : sub dir2: Making directory 2024/09/26 02:52:17 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/26 02:52:17 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/26 02:52:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:17 DEBUG : sub dir/hello world: md5 = 88e810e4d104440f89705f3e3b3b2cb6 OK 2024/09/26 02:52:17 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (0.81s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:18 DEBUG : sub dir2: Making directory with metadata 2024/09/26 02:52:18 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/26 02:52:18 DEBUG : sub dir: Making directory 2024/09/26 02:52:18 DEBUG : sub dir2: Making directory 2024/09/26 02:52:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:18 DEBUG : sub dir/hello world: md5 = b6132803a516c5de159229079063866e OK 2024/09/26 02:52:18 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:52:18 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/26 02:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/09/26 02:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.31s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.10s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:19 DEBUG : sub dir2: Making directory 2024/09/26 02:52:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:19 DEBUG : sub dir/hello world: md5 = f6a062326981df6bc4c0f0c14bbae80d OK 2024/09/26 02:52:19 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:52:19 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (1.03s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:20 DEBUG : sub dir2: Making directory with metadata 2024/09/26 02:52:20 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/26 02:52:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/26 02:52:20 DEBUG : sub dir: Making directory 2024/09/26 02:52:20 DEBUG : sub dir2: Making directory 2024/09/26 02:52:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:20 DEBUG : sub dir/hello world: md5 = 3e4147f9049821b13620a2a25530e82f OK 2024/09/26 02:52:20 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:52:20 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/26 02:52:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.28s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.10s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:22 DEBUG : sub dir2: Making directory 2024/09/26 02:52:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:22 DEBUG : sub dir/hello world: md5 = 4748f52bda4e5a61983706a7bcfae4fc OK 2024/09/26 02:52:22 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:52:22 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.82s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:23 DEBUG : sub dir/hello world: md5 = e87be4b08173851f24dfa77eeb87c507 OK 2024/09/26 02:52:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wilovud4xike" 2024/09/26 02:52:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wilovud4xike.bin" 2024/09/26 02:52:23 DEBUG : Swift container crypt path rclone-test-wilovud4xike.bin: Auto set use_segments_container to true 2024/09/26 02:52:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wilovud4xike" 2024/09/26 02:52:24 DEBUG : Swift container crypt path rclone-test-wilovud4xike: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu' -> Encrypted drive 'TestCryptSwift:rclone-test-wilovud4xike' 2024/09/26 02:52:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wilovud4xike': Waiting for checks to finish 2024/09/26 02:52:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wilovud4xike': Waiting for transfers to finish 2024/09/26 02:52:25 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wilovud4xike': Purge remote 2024/09/26 02:52:25 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:25 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (3.34s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:26 DEBUG : sub dir/hello world: md5 = 2a446cb15f7c890c0ff7c875eaf9c948 OK 2024/09/26 02:52:26 ERROR : : error listing: directory not found 2024/09/26 02:52:26 DEBUG : Local file system at /tmp/rclone3339624811: Making directory 2024/09/26 02:52:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyAfterDelete (1.55s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:27 DEBUG : sub dir/hello world: md5 = a3a3ddf3d450c2fe1d7171aadd349ec4 OK 2024/09/26 02:52:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:52:28 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for checks to finish 2024/09/26 02:52:28 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for transfers to finish 2024/09/26 02:52:28 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2024/09/26 02:52:28 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyRedownload (1.15s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:28 DEBUG : check sum: Need to transfer - File not found at Destination 2024/09/26 02:52:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:29 DEBUG : check sum: md5 = 108c20a4f5e79ad3a91c323d4af0dd72 OK 2024/09/26 02:52:29 INFO : check sum: Copied (new) 2024/09/26 02:52:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:30 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/09/26 02:52:30 DEBUG : check sum: Size of src and dst objects identical 2024/09/26 02:52:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:30 DEBUG : check sum: Unchanged skipping 2024/09/26 02:52:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:30 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:30 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (2.07s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:31 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/09/26 02:52:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:31 DEBUG : sizeonly: md5 = dd898374a63bcd0a6fe79bf33993a61d OK 2024/09/26 02:52:31 INFO : sizeonly: Copied (new) 2024/09/26 02:52:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:32 DEBUG : sizeonly: Sizes identical 2024/09/26 02:52:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:32 DEBUG : sizeonly: Unchanged skipping 2024/09/26 02:52:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:32 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:32 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncSizeOnly (1.89s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:32 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/09/26 02:52:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:33 DEBUG : ignore-size: md5 = ee903e738d082deec94423464ed2b185 OK 2024/09/26 02:52:33 INFO : ignore-size: Copied (new) 2024/09/26 02:52:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:34 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:34 DEBUG : ignore-size: Unchanged skipping 2024/09/26 02:52:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:34 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:34 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (6.09s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:39 DEBUG : existing: md5 = dcea6959a46435009cd7146350d706ab OK 2024/09/26 02:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:39 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:39 DEBUG : existing: Unchanged skipping 2024/09/26 02:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:39 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:39 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:39 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/09/26 02:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:40 DEBUG : existing: md5 = ec1f27e7a737fc437bf3833a48f1d646 OK 2024/09/26 02:52:40 INFO : existing: Copied (replaced existing) 2024/09/26 02:52:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (2.08s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:41 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/26 02:52:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:42 DEBUG : existing: md5 = df4f84254748e7ca292b260d8ddbe0a0 OK 2024/09/26 02:52:42 INFO : existing: Copied (new) 2024/09/26 02:52:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:42 DEBUG : existing: Destination exists, skipping 2024/09/26 02:52:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:42 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:42 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (2.61s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:44 DEBUG : b/potato: md5 = 38d9719432445c96d470e9b5ec3bef4c OK 2024/09/26 02:52:44 DEBUG : c/non empty space: md5 = eee11536a0777afa0db25feb5d074049 OK 2024/09/26 02:52:44 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/26 02:52:44 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/26 02:52:45 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:45 DEBUG : c/non empty space: Unchanged skipping 2024/09/26 02:52:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:45 DEBUG : a/potato2: md5 = 5baa7589f1b00958fee921b0f085f143 OK 2024/09/26 02:52:45 INFO : a/potato2: Copied (new) 2024/09/26 02:52:45 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:45 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (2.88s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:46 DEBUG : empty space: md5 = a9871a9daf1af15998f6aef4549807e0 OK 2024/09/26 02:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:47 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2024/09/26 02:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:47 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/09/26 02:52:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:47 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2024/09/26 02:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:47 DEBUG : empty space: md5 = 15ee11b4b17ba81e9dd3f1da614056fd OK 2024/09/26 02:52:47 INFO : empty space: Copied (replaced existing) 2024/09/26 02:52:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.22s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:914: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.10s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:49 DEBUG : foo: md5 = 654d53ed598df6d02fec7ca893b70b40 OK 2024/09/26 02:52:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:49 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 2024/09/26 02:52:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:49 DEBUG : foo: md5 = 30b10fe89be3c935d41208ed502fa5d1 OK 2024/09/26 02:52:49 INFO : foo: Copied (replaced existing) 2024/09/26 02:52:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (1.95s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:50 DEBUG : empty space: md5 = 7a7e0ec1b3b8667fd081502c1f6093c9 OK 2024/09/26 02:52:51 DEBUG : potato: Need to transfer - File not found at Destination 2024/09/26 02:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:51 DEBUG : empty space: Unchanged skipping 2024/09/26 02:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:51 DEBUG : potato: md5 = aa4d1060e9c2d431d8aed9e64b9f0f74 OK 2024/09/26 02:52:51 INFO : potato: Copied (new) 2024/09/26 02:52:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (1.89s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:52 DEBUG : potato: md5 = a8bb6bb54d7a96fd0aef738ed2ac5773 OK 2024/09/26 02:52:53 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/09/26 02:52:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:53 DEBUG : potato: md5 = 3ad20db99b8723d21b8a873cd0c722f4 OK 2024/09/26 02:52:53 INFO : potato: Copied (replaced existing) 2024/09/26 02:52:53 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.81s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:54 DEBUG : potato: md5 = 3541a6477119826cbc031b36ad1b1d19 OK 2024/09/26 02:52:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:55 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 2024/09/26 02:52:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:55 DEBUG : potato: md5 = ba33ead786d3b96d512cee9df48f2436 OK 2024/09/26 02:52:55 INFO : potato: Copied (replaced existing) 2024/09/26 02:52:55 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.56s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:56 DEBUG : potato: md5 = e54563cb47c9f1f0af65ea3fe454c361 OK 2024/09/26 02:52:56 DEBUG : empty space: md5 = a44580780624e187be7fbc7977ef363f OK 2024/09/26 02:52:56 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:52:56 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/09/26 02:52:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:56 DEBUG : empty space: Unchanged skipping 2024/09/26 02:52:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:56 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:56 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.54s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:57 DEBUG : potato: md5 = 074ec6b0cb9098ed746e7d56c178c224 OK 2024/09/26 02:52:57 DEBUG : empty space: md5 = 6fbdf3d13b5a4905c63997959201ef42 OK 2024/09/26 02:52:58 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:52:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:52:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:52:58 DEBUG : empty space: Unchanged skipping 2024/09/26 02:52:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:52:58 DEBUG : potato2: md5 = 11dc1a55d9a3e882e085a58a524ba2ac OK 2024/09/26 02:52:58 INFO : potato2: Copied (new) 2024/09/26 02:52:58 DEBUG : Waiting for deletions to finish 2024/09/26 02:52:58 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.11s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:52:59 DEBUG : b/potato: md5 = 9d5691698e8f8d546d4bd6ff6b25e020 OK 2024/09/26 02:53:00 DEBUG : c/non empty space: md5 = 64a967bb65d144c9eb9d81e44f2aa1c3 OK 2024/09/26 02:53:00 DEBUG : d: Making directory 2024/09/26 02:53:00 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/09/26 02:53:00 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:00 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:00 DEBUG : c/non empty space: Unchanged skipping 2024/09/26 02:53:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:00 DEBUG : a/potato2: md5 = a040ea7c076b5c60c7813b3e6438f593 OK 2024/09/26 02:53:00 INFO : a/potato2: Copied (new) 2024/09/26 02:53:00 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:00 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.00s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:01 DEBUG : b/potato: md5 = 35f79f5f9687de63b5119d9baf721651 OK 2024/09/26 02:53:02 DEBUG : c/non empty space: md5 = 730377c3a10ffb79ce988c953acde323 OK 2024/09/26 02:53:02 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/26 02:53:02 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:02 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:02 DEBUG : c/non empty space: Unchanged skipping 2024/09/26 02:53:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:02 DEBUG : a/potato2: md5 = 2adb64999bae01277bcc698291d3aab6 OK 2024/09/26 02:53:02 INFO : a/potato2: Copied (new) 2024/09/26 02:53:02 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': not deleting files as there were IO errors 2024/09/26 02:53:02 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.44s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:04 DEBUG : potato: md5 = 55b76dd51926df1c206d323110d9f73b OK 2024/09/26 02:53:04 DEBUG : empty space: md5 = 2152ccbb7fb6b18e5129edcb5e47ea46 OK 2024/09/26 02:53:04 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:04 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:04 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:04 INFO : potato: Deleted 2024/09/26 02:53:05 DEBUG : potato2: md5 = 8de80c5548beda0ecd78976306fa3c4b OK 2024/09/26 02:53:05 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (2.16s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:06 DEBUG : potato: md5 = f8ca597781a736b3976697e3885e6a69 OK 2024/09/26 02:53:06 DEBUG : empty space: md5 = 8340fa1adf3180dcdf950ec0bc8b6613 OK 2024/09/26 02:53:06 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:07 INFO : potato: Deleted 2024/09/26 02:53:07 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:07 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:07 DEBUG : potato2: md5 = cffec80d7fddb95c1456589c251277dc OK 2024/09/26 02:53:07 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (2.17s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:08 DEBUG : potato: md5 = fe2f3982365770c7197b0346ba65d015 OK 2024/09/26 02:53:08 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:09 DEBUG : potato2: md5 = 3b21c1e1de718792919e4bf22fe36444 OK 2024/09/26 02:53:09 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.87s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:10 DEBUG : potato2: md5 = e9c4f2c3045066236badd59c39e9ad30 OK 2024/09/26 02:53:11 DEBUG : empty space: md5 = c6555916a2f9e1b4416d0ecf93d0c0a8 OK 2024/09/26 02:53:11 DEBUG : enormous: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : enormous: Excluded 2024/09/26 02:53:11 DEBUG : potato2: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : potato2: Excluded 2024/09/26 02:53:11 DEBUG : potato2: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : potato2: Excluded 2024/09/26 02:53:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:11 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:11 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:11 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:11 DEBUG : enormous: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : enormous: Excluded 2024/09/26 02:53:11 DEBUG : potato2: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : potato2: Excluded 2024/09/26 02:53:11 DEBUG : potato2: Excluded (Size Filter) 2024/09/26 02:53:11 DEBUG : potato2: Excluded 2024/09/26 02:53:11 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for checks to finish 2024/09/26 02:53:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:11 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:11 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for transfers to finish 2024/09/26 02:53:11 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:11 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncWithExclude (2.30s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:12 DEBUG : potato2: md5 = 7f4582f8040ba8902457bc82d75f04b2 OK 2024/09/26 02:53:13 DEBUG : empty space: md5 = ace1d6f01bc68430299307f4a72e1a98 OK 2024/09/26 02:53:13 DEBUG : enormous: md5 = fb0c96b7d64fb361fb2ab6c37aebb8c8 OK 2024/09/26 02:53:13 DEBUG : enormous: Excluded (Size Filter) 2024/09/26 02:53:13 DEBUG : enormous: Excluded 2024/09/26 02:53:13 DEBUG : potato2: Excluded (Size Filter) 2024/09/26 02:53:13 DEBUG : potato2: Excluded 2024/09/26 02:53:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:13 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:13 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:13 INFO : enormous: Deleted 2024/09/26 02:53:13 INFO : potato2: Deleted 2024/09/26 02:53:13 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:14 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for checks to finish 2024/09/26 02:53:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:14 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:14 DEBUG : Local file system at /tmp/rclone3339624811: Waiting for transfers to finish 2024/09/26 02:53:14 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:14 INFO : enormous: Deleted 2024/09/26 02:53:14 INFO : potato2: Deleted 2024/09/26 02:53:14 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.60s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:16 DEBUG : one: md5 = ad88072f7decb906a65627abadd5a659 OK 2024/09/26 02:53:16 DEBUG : two: md5 = 68a580839797906504316880d48b6176 OK 2024/09/26 02:53:16 DEBUG : three: md5 = f2380509960e99c77c6ca81185b72adc OK 2024/09/26 02:53:17 DEBUG : four: md5 = 51a64b6306a73e5b3640a04274750b37 OK 2024/09/26 02:53:17 DEBUG : five: Need to transfer - File not found at Destination 2024/09/26 02:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:17 DEBUG : one: Destination is newer than source, skipping 2024/09/26 02:53:17 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/09/26 02:53:17 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/09/26 02:53:17 DEBUG : three: Sizes identical 2024/09/26 02:53:17 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/09/26 02:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:17 DEBUG : two: md5 = 85d6f9729d726ac34d08d9c36407af5d OK 2024/09/26 02:53:17 INFO : two: Copied (replaced existing) 2024/09/26 02:53:17 DEBUG : four: md5 = 9893800428dcd4967fc197344f5f057e OK 2024/09/26 02:53:17 INFO : four: Copied (replaced existing) 2024/09/26 02:53:17 DEBUG : five: md5 = a40c87cb892cc5ee0879920eeb333b82 OK 2024/09/26 02:53:17 INFO : five: Copied (new) 2024/09/26 02:53:17 DEBUG : Waiting for deletions to finish sync_test.go:1347: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.01s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1364: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1364: 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-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:1436: Can track renames: false 2024/09/26 02:53:19 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Ignoring --track-renames as the source and destination do not have a common hash 2024/09/26 02:53:19 DEBUG : potato: Need to transfer - File not found at Destination 2024/09/26 02:53:19 DEBUG : yam: Need to transfer - File not found at Destination 2024/09/26 02:53:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:19 DEBUG : potato: md5 = 6db161f9b93de142fe36fec031f93fea OK 2024/09/26 02:53:19 INFO : potato: Copied (new) 2024/09/26 02:53:19 DEBUG : yam: md5 = 998ea74e5a906f740fcd71fe1695317e OK 2024/09/26 02:53:19 INFO : yam: Copied (new) 2024/09/26 02:53:19 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:20 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Ignoring --track-renames as the source and destination do not have a common hash 2024/09/26 02:53:20 DEBUG : yaml: Need to transfer - File not found at Destination 2024/09/26 02:53:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:20 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:20 DEBUG : potato: Unchanged skipping 2024/09/26 02:53:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:21 DEBUG : yaml: md5 = 30dc21b6f125be090e3f8b18c5d959ca OK 2024/09/26 02:53:21 INFO : yaml: Copied (new) 2024/09/26 02:53:21 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:21 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (3.43s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:1508: Can track renames: true 2024/09/26 02:53:22 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Making map for --track-renames 2024/09/26 02:53:22 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Finished making map for --track-renames 2024/09/26 02:53:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:22 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/26 02:53:22 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/09/26 02:53:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for renames to finish 2024/09/26 02:53:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:22 DEBUG : potato: md5 = d91c47b8e7895b74e5bb6b98e5f32d9e OK 2024/09/26 02:53:22 INFO : potato: Copied (new) 2024/09/26 02:53:22 DEBUG : yam: md5 = e4fe9c97dfa3bf581fd51444fc9ca07f OK 2024/09/26 02:53:22 INFO : yam: Copied (new) 2024/09/26 02:53:22 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:23 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Making map for --track-renames 2024/09/26 02:53:23 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Finished making map for --track-renames 2024/09/26 02:53:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:23 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:23 DEBUG : potato: Unchanged skipping 2024/09/26 02:53:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for renames to finish 2024/09/26 02:53:23 INFO : yam: Copied (server-side copy) to: yaml 2024/09/26 02:53:23 INFO : yam: Deleted 2024/09/26 02:53:23 INFO : yaml: Renamed from "yam" 2024/09/26 02:53:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.01s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:1547: Can track renames: true 2024/09/26 02:53:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Making map for --track-renames 2024/09/26 02:53:24 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Finished making map for --track-renames 2024/09/26 02:53:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:24 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/26 02:53:24 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/09/26 02:53:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for renames to finish 2024/09/26 02:53:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:24 DEBUG : potato: md5 = 240490d7ae1cfe9a31688590407e9f0c OK 2024/09/26 02:53:24 INFO : potato: Copied (new) 2024/09/26 02:53:25 DEBUG : sub/yam: md5 = 6d5c54debf5b193550c5c789838171fa OK 2024/09/26 02:53:25 INFO : sub/yam: Copied (new) 2024/09/26 02:53:25 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:25 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:25 DEBUG : potato: Unchanged skipping 2024/09/26 02:53:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Making map for --track-renames 2024/09/26 02:53:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Finished making map for --track-renames 2024/09/26 02:53:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for renames to finish 2024/09/26 02:53:26 INFO : sub/yam: Copied (server-side copy) to: yam 2024/09/26 02:53:26 INFO : sub/yam: Deleted 2024/09/26 02:53:26 INFO : yam: Renamed from "sub/yam" 2024/09/26 02:53:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.82s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:27 DEBUG : Creating backend with remote "/tmp/rclone3339624811/dir1" 2024/09/26 02:53:27 DEBUG : Creating backend with remote "/tmp/rclone3339624811/dir2" 2024/09/26 02:53:27 DEBUG : Local file system at /tmp/rclone3339624811/dir2: Using server-side directory move 2024/09/26 02:53:27 INFO : Local file system at /tmp/rclone3339624811/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/09/26 02:53:27 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/09/26 02:53:27 DEBUG : Local file system at /tmp/rclone3339624811/dir2: Waiting for checks to finish 2024/09/26 02:53:27 DEBUG : Local file system at /tmp/rclone3339624811/dir2: Waiting for transfers to finish 2024/09/26 02:53:27 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.10s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:53:27 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/26 02:53:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:28 DEBUG : sub dir/hello world: md5 = e1cf3d51f8dd90b37afca5225296b5c6 OK 2024/09/26 02:53:28 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:53:28 INFO : sub dir/hello world: Deleted 2024/09/26 02:53:28 DEBUG : nested/sub dir/file: md5 = e7c4a2858554642d214ca03d03192137 OK 2024/09/26 02:53:28 INFO : nested/sub dir/file: Copied (new) 2024/09/26 02:53:28 INFO : nested/sub dir/file: Deleted 2024/09/26 02:53:28 INFO : sub dir: Removing directory 2024/09/26 02:53:28 INFO : nested/sub dir: Removing directory 2024/09/26 02:53:28 INFO : nested: Removing directory 2024/09/26 02:53:28 DEBUG : Local file system at /tmp/rclone3339624811: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (2.03s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/26 02:53:29 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/26 02:53:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:29 DEBUG : nested/sub dir/file: md5 = d3792bbbb50f42b4d81bac6e8323c5f0 OK 2024/09/26 02:53:29 INFO : nested/sub dir/file: Copied (new) 2024/09/26 02:53:29 INFO : nested/sub dir/file: Deleted 2024/09/26 02:53:30 DEBUG : sub dir/hello world: md5 = c365a0083f7c32fec836bfb4223b5e12 OK 2024/09/26 02:53:30 INFO : sub dir/hello world: Copied (new) 2024/09/26 02:53:30 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.09s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:31 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/26 02:53:31 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/09/26 02:53:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:32 DEBUG : existing: md5 = 4be6d8492e05491044fff943e053d2b4 OK 2024/09/26 02:53:32 INFO : existing: Copied (new) 2024/09/26 02:53:32 INFO : existing: Deleted 2024/09/26 02:53:32 DEBUG : existing-b: md5 = ca028d107815e555f63f951a859da42a OK 2024/09/26 02:53:32 INFO : existing-b: Copied (new) 2024/09/26 02:53:32 INFO : existing-b: Deleted 2024/09/26 02:53:33 DEBUG : existing: Destination exists, skipping 2024/09/26 02:53:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:53:33 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/09/26 02:53:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:53:33 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (2.30s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vigujop8pewe" 2024/09/26 02:53:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vigujop8pewe.bin" 2024/09/26 02:53:34 DEBUG : Swift container crypt path rclone-test-vigujop8pewe.bin: Auto set use_segments_container to true 2024/09/26 02:53:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vigujop8pewe" 2024/09/26 02:53:35 DEBUG : Swift container crypt path rclone-test-vigujop8pewe: Auto set use_segments_container to true 2024/09/26 02:53:35 DEBUG : potato2: md5 = 73c8e83d3198e121f4f45215df686aa4 OK 2024/09/26 02:53:35 DEBUG : empty space: md5 = 33b20ccf0022675ca910ff0ebc698561 OK 2024/09/26 02:53:36 DEBUG : potato3: md5 = 21409a8db0b04b901281f2be8c52e9de OK sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu' -> Encrypted drive 'TestCryptSwift:rclone-test-vigujop8pewe' 2024/09/26 02:53:37 DEBUG : empty space: md5 = d2d2ef3d1db11e38efe20a92eb888bb6 OK 2024/09/26 02:53:37 DEBUG : potato3: md5 = ec6d01f14577a47bae8d411ad1080f7e OK 2024/09/26 02:53:38 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/26 02:53:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vigujop8pewe': Waiting for checks to finish 2024/09/26 02:53:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:38 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:38 INFO : empty space: Deleted 2024/09/26 02:53:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vigujop8pewe': Waiting for transfers to finish 2024/09/26 02:53:38 INFO : potato2: Copied (server-side copy) 2024/09/26 02:53:38 INFO : potato3: Copied (server-side copy) 2024/09/26 02:53:38 INFO : potato2: Deleted 2024/09/26 02:53:38 INFO : potato3: Deleted 2024/09/26 02:53:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lafadaz0faqa" 2024/09/26 02:53:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lafadaz0faqa.bin" 2024/09/26 02:53:39 DEBUG : Swift container crypt path rclone-test-lafadaz0faqa.bin: Auto set use_segments_container to true 2024/09/26 02:53:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lafadaz0faqa" 2024/09/26 02:53:40 DEBUG : Swift container crypt path rclone-test-lafadaz0faqa: Auto set use_segments_container to true 2024/09/26 02:53:40 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/26 02:53:40 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:40 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/26 02:53:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lafadaz0faqa': Waiting for checks to finish 2024/09/26 02:53:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lafadaz0faqa': Waiting for transfers to finish 2024/09/26 02:53:41 INFO : potato3: Copied (server-side copy) 2024/09/26 02:53:41 INFO : potato2: Copied (server-side copy) 2024/09/26 02:53:41 INFO : empty space: Copied (server-side copy) 2024/09/26 02:53:41 INFO : potato3: Deleted 2024/09/26 02:53:41 INFO : potato2: Deleted 2024/09/26 02:53:42 INFO : empty space: Deleted 2024/09/26 02:53:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lafadaz0faqa': Purge remote 2024/09/26 02:53:42 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:42 INFO : empty space.bin: Deleted 2024/09/26 02:53:42 INFO : potato2.bin: Deleted 2024/09/26 02:53:42 INFO : potato3.bin: Deleted 2024/09/26 02:53:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vigujop8pewe': Purge remote 2024/09/26 02:53:42 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (9.19s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dehuhor4riza" 2024/09/26 02:53:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dehuhor4riza.bin" 2024/09/26 02:53:43 DEBUG : Swift container crypt path rclone-test-dehuhor4riza.bin: Auto set use_segments_container to true 2024/09/26 02:53:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dehuhor4riza" 2024/09/26 02:53:44 DEBUG : Swift container crypt path rclone-test-dehuhor4riza: Auto set use_segments_container to true 2024/09/26 02:53:44 DEBUG : potato2: md5 = 83b44a96a1045872f683227e446a861f OK 2024/09/26 02:53:44 DEBUG : empty space: md5 = 1d110e1dc02a2e8445cdf0b854747eee OK 2024/09/26 02:53:44 DEBUG : potato3: md5 = 2a952062c28cbb0946d77f0085cbd90e OK sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu' -> Encrypted drive 'TestCryptSwift:rclone-test-dehuhor4riza' 2024/09/26 02:53:45 DEBUG : empty space: md5 = 279e7ebc615bc3b773061dcefb9079fa OK 2024/09/26 02:53:46 DEBUG : potato3: md5 = f113438484331a56c6dbc296542ab7c9 OK 2024/09/26 02:53:46 DEBUG : empty space: Excluded (Size Filter) 2024/09/26 02:53:46 DEBUG : empty space: Excluded 2024/09/26 02:53:46 DEBUG : empty space: Excluded (Size Filter) 2024/09/26 02:53:46 DEBUG : empty space: Excluded 2024/09/26 02:53:46 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/26 02:53:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dehuhor4riza': Waiting for checks to finish 2024/09/26 02:53:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dehuhor4riza': Waiting for transfers to finish 2024/09/26 02:53:46 INFO : potato3: Copied (server-side copy) 2024/09/26 02:53:47 INFO : potato3: Deleted 2024/09/26 02:53:47 INFO : potato2: Copied (server-side copy) 2024/09/26 02:53:47 INFO : potato2: Deleted 2024/09/26 02:53:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qoyeqol3hezo" 2024/09/26 02:53:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qoyeqol3hezo.bin" 2024/09/26 02:53:48 DEBUG : Swift container crypt path rclone-test-qoyeqol3hezo.bin: Auto set use_segments_container to true 2024/09/26 02:53:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qoyeqol3hezo" 2024/09/26 02:53:48 DEBUG : Swift container crypt path rclone-test-qoyeqol3hezo: Auto set use_segments_container to true 2024/09/26 02:53:49 DEBUG : empty space: Excluded (Size Filter) 2024/09/26 02:53:49 DEBUG : empty space: Excluded 2024/09/26 02:53:49 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:49 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/26 02:53:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoyeqol3hezo': Waiting for checks to finish 2024/09/26 02:53:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoyeqol3hezo': Waiting for transfers to finish 2024/09/26 02:53:49 INFO : potato3: Copied (server-side copy) 2024/09/26 02:53:49 INFO : potato2: Copied (server-side copy) 2024/09/26 02:53:49 INFO : potato3: Deleted 2024/09/26 02:53:50 INFO : potato2: Deleted 2024/09/26 02:53:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qoyeqol3hezo': Purge remote 2024/09/26 02:53:50 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:50 INFO : potato3.bin: Deleted 2024/09/26 02:53:51 INFO : potato2.bin: Deleted 2024/09/26 02:53:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dehuhor4riza': Purge remote 2024/09/26 02:53:51 DEBUG : Waiting for deletions to finish 2024/09/26 02:53:51 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (8.99s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:53:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-pacibeq2woxu" 2024/09/26 02:53:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pacibeq2woxu.bin" 2024/09/26 02:53:52 DEBUG : Swift container crypt path rclone-test-pacibeq2woxu.bin: Auto set use_segments_container to true 2024/09/26 02:53:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pacibeq2woxu" 2024/09/26 02:53:53 DEBUG : Swift container crypt path rclone-test-pacibeq2woxu: Auto set use_segments_container to true 2024/09/26 02:53:53 DEBUG : potato2: md5 = 5b0d2c0681a8d20aff2cc1ce823e77a0 OK 2024/09/26 02:53:53 DEBUG : empty space: md5 = 066673e5634bbff24723d42c144a22f9 OK 2024/09/26 02:53:54 DEBUG : potato3: md5 = 8b8b28720cb601ffede3633eb99de4f7 OK 2024/09/26 02:53:54 DEBUG : tomatoDir: Making directory sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu' -> Encrypted drive 'TestCryptSwift:rclone-test-pacibeq2woxu' 2024/09/26 02:53:56 DEBUG : empty space: md5 = 727c2c9438ac595eabc15943e2d2bd7b OK 2024/09/26 02:53:56 DEBUG : potato3: md5 = 8cb56000d705c73dc86675acac3930dd OK 2024/09/26 02:53:57 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:53:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/26 02:53:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pacibeq2woxu': Waiting for checks to finish 2024/09/26 02:53:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:53:57 DEBUG : empty space: Unchanged skipping 2024/09/26 02:53:57 INFO : empty space: Deleted 2024/09/26 02:53:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pacibeq2woxu': Waiting for transfers to finish 2024/09/26 02:53:57 INFO : potato3: Copied (server-side copy) 2024/09/26 02:53:57 INFO : potato2: Copied (server-side copy) 2024/09/26 02:53:58 INFO : potato2: Deleted 2024/09/26 02:53:58 INFO : potato3: Deleted 2024/09/26 02:53:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lilitiz6qigo" 2024/09/26 02:53:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lilitiz6qigo.bin" 2024/09/26 02:53:58 DEBUG : Swift container crypt path rclone-test-lilitiz6qigo.bin: Auto set use_segments_container to true 2024/09/26 02:53:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lilitiz6qigo" 2024/09/26 02:53:59 DEBUG : Swift container crypt path rclone-test-lilitiz6qigo: Auto set use_segments_container to true 2024/09/26 02:53:59 DEBUG : tomatoDir: Making directory 2024/09/26 02:54:00 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/26 02:54:00 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/26 02:54:00 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/26 02:54:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lilitiz6qigo': Waiting for checks to finish 2024/09/26 02:54:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lilitiz6qigo': Waiting for transfers to finish 2024/09/26 02:54:00 INFO : potato2: Copied (server-side copy) 2024/09/26 02:54:00 INFO : potato2: Deleted 2024/09/26 02:54:00 INFO : potato3: Copied (server-side copy) 2024/09/26 02:54:00 INFO : empty space: Copied (server-side copy) 2024/09/26 02:54:01 INFO : potato3: Deleted 2024/09/26 02:54:01 INFO : empty space: Deleted 2024/09/26 02:54:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lilitiz6qigo': Purge remote 2024/09/26 02:54:02 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:02 INFO : empty space.bin: Deleted 2024/09/26 02:54:02 INFO : potato2.bin: Deleted 2024/09/26 02:54:02 INFO : potato3.bin: Deleted 2024/09/26 02:54:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pacibeq2woxu': Purge remote 2024/09/26 02:54:02 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (11.00s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/rclone-move-test" 2024/09/26 02:54:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-move-test.bin" 2024/09/26 02:54:03 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-move-test.bin: Auto set use_segments_container to true 2024/09/26 02:54:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-move-test" 2024/09/26 02:54:04 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-move-test: Auto set use_segments_container to true 2024/09/26 02:54:04 DEBUG : potato2: md5 = 38a564f866b40dadc7a92a4a5c6dc874 OK --- PASS: TestServerSideMoveOverlap (2.29s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test" 2024/09/26 02:54:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test.bin" 2024/09/26 02:54:05 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test.bin: Auto set use_segments_container to true 2024/09/26 02:54:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test" 2024/09/26 02:54:06 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncOverlap (2.56s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test" 2024/09/26 02:54:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test.bin" 2024/09/26 02:54:08 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test.bin: Auto set use_segments_container to true 2024/09/26 02:54:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test" 2024/09/26 02:54:08 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test: Auto set use_segments_container to true 2024/09/26 02:54:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-include/layer2" 2024/09/26 02:54:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test-include/layer2.bin" 2024/09/26 02:54:09 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2024/09/26 02:54:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test-include/layer2" 2024/09/26 02:54:10 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2024/09/26 02:54:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-ignore-file" 2024/09/26 02:54:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test-ignore-file.bin" 2024/09/26 02:54:10 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2024/09/26 02:54:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/rclone-sync-test-ignore-file" 2024/09/26 02:54:11 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2024/09/26 02:54:12 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = ab4682d28d2602db9778eb8143b09675 OK 2024/09/26 02:54:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/26 02:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test': Waiting for checks to finish 2024/09/26 02:54:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test': Waiting for transfers to finish 2024/09/26 02:54:12 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:12 INFO : There was nothing to transfer 2024/09/26 02:54:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:54:13 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:13 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/09/26 02:54:13 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:13 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:54:13 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:13 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:13 DEBUG : : Excluded 2024/09/26 02:54:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/09/26 02:54:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/09/26 02:54:13 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:13 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (8.75s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:54:17 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:54:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:17 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:54:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/CompareDest" 2024/09/26 02:54:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/CompareDest.bin" 2024/09/26 02:54:18 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/CompareDest.bin: Auto set use_segments_container to true 2024/09/26 02:54:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/CompareDest" 2024/09/26 02:54:19 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/CompareDest: Auto set use_segments_container to true 2024/09/26 02:54:19 DEBUG : one: Need to transfer - File not found at Destination 2024/09/26 02:54:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:20 DEBUG : one: md5 = bae6d2b9240fa966102d44136595986a OK 2024/09/26 02:54:20 INFO : one: Copied (new) 2024/09/26 02:54:20 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:21 DEBUG : one: md5 = c590cfab137178b7634c109277d3a26d OK 2024/09/26 02:54:21 INFO : one: Copied (replaced existing) 2024/09/26 02:54:21 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:21 DEBUG : dst/one: md5 = e8c63f2f180824c4adff3943b329ec34 OK 2024/09/26 02:54:22 DEBUG : CompareDest/one: md5 = d2a1304ff7816ce65dba68a7d9abbb61 OK 2024/09/26 02:54:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:22 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/26 02:54:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:22 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:22 INFO : There was nothing to transfer 2024/09/26 02:54:23 DEBUG : CompareDest/two: md5 = 2d81217a778796768cb4ca66f53ef72d OK 2024/09/26 02:54:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:24 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/26 02:54:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:24 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/26 02:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:24 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:24 INFO : There was nothing to transfer 2024/09/26 02:54:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:24 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/26 02:54:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:24 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/26 02:54:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:24 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:24 INFO : There was nothing to transfer sync_test.go:2073: No hash on uploaded file so skipping compare timestamp test 2024/09/26 02:54:25 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:25 DEBUG : two: Need to transfer - File not found at Destination 2024/09/26 02:54:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:25 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:25 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/26 02:54:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:25 DEBUG : two: md5 = f621286bb5b26fb90eb6ed2580f33bdf OK 2024/09/26 02:54:25 INFO : two: Copied (new) 2024/09/26 02:54:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (10.20s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:27 DEBUG : pre-dest1/1: md5 = 83f53b176cd03666b068febb3c94b6ac OK 2024/09/26 02:54:28 DEBUG : pre-dest2/2: md5 = 8655c90bf179219361b3b292db9e0ddd OK 2024/09/26 02:54:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dest" 2024/09/26 02:54:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dest.bin" 2024/09/26 02:54:29 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dest.bin: Auto set use_segments_container to true 2024/09/26 02:54:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dest" 2024/09/26 02:54:29 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dest: Auto set use_segments_container to true 2024/09/26 02:54:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/pre-dest1" 2024/09/26 02:54:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/pre-dest1.bin" 2024/09/26 02:54:30 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/pre-dest1.bin: Auto set use_segments_container to true 2024/09/26 02:54:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/pre-dest1" 2024/09/26 02:54:31 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/pre-dest1: Auto set use_segments_container to true 2024/09/26 02:54:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/pre-dest2" 2024/09/26 02:54:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/pre-dest2.bin" 2024/09/26 02:54:31 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/pre-dest2.bin: Auto set use_segments_container to true 2024/09/26 02:54:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/pre-dest2" 2024/09/26 02:54:32 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/pre-dest2: Auto set use_segments_container to true 2024/09/26 02:54:33 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:33 DEBUG : 1: Destination found in --compare-dest, skipping 2024/09/26 02:54:33 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:33 DEBUG : 2: Destination found in --compare-dest, skipping 2024/09/26 02:54:33 DEBUG : 3: Need to transfer - File not found at Destination 2024/09/26 02:54:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dest': Waiting for checks to finish 2024/09/26 02:54:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dest': Waiting for transfers to finish 2024/09/26 02:54:34 DEBUG : 3: md5 = aa5be6e1b13b7c3865d29a2e2bd4a1b5 OK 2024/09/26 02:54:34 INFO : 3: Copied (new) 2024/09/26 02:54:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (9.59s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:54:37 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:54:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:37 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:54:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/CopyDest" 2024/09/26 02:54:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/CopyDest.bin" 2024/09/26 02:54:38 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/CopyDest.bin: Auto set use_segments_container to true 2024/09/26 02:54:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/CopyDest" 2024/09/26 02:54:38 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/CopyDest: Auto set use_segments_container to true 2024/09/26 02:54:39 DEBUG : one: Need to transfer - File not found at Destination 2024/09/26 02:54:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:39 DEBUG : one: md5 = 4992121ddb3f03b41a64f0e584c9025b OK 2024/09/26 02:54:39 INFO : one: Copied (new) 2024/09/26 02:54:39 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:40 DEBUG : one: md5 = 59e76c22603c98e09a2aa2064e379feb OK 2024/09/26 02:54:40 INFO : one: Copied (replaced existing) 2024/09/26 02:54:40 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:40 DEBUG : dst/one: md5 = 1096e2472521b55eacc0252bdbd9fde7 OK 2024/09/26 02:54:41 DEBUG : CopyDest/one: md5 = b0d64d7dff8f519fcfc10325ff764739 OK 2024/09/26 02:54:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/BackupDir" 2024/09/26 02:54:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/BackupDir.bin" 2024/09/26 02:54:41 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/BackupDir.bin: Auto set use_segments_container to true 2024/09/26 02:54:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/BackupDir" 2024/09/26 02:54:42 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/BackupDir: Auto set use_segments_container to true 2024/09/26 02:54:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:43 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:43 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/26 02:54:43 INFO : one: Copied (server-side copy) 2024/09/26 02:54:43 INFO : one: Deleted 2024/09/26 02:54:44 INFO : one: Copied (server-side copy) 2024/09/26 02:54:44 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/09/26 02:54:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:44 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:45 DEBUG : CopyDest/two: md5 = 884bc9b84fb894fe5455aa03341a524b OK 2024/09/26 02:54:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:45 INFO : two: Copied (server-side copy) 2024/09/26 02:54:45 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/09/26 02:54:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:45 DEBUG : one: Unchanged skipping 2024/09/26 02:54:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:45 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:46 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:46 DEBUG : one: Unchanged skipping 2024/09/26 02:54:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:46 DEBUG : two: Unchanged skipping 2024/09/26 02:54:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:46 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:46 INFO : There was nothing to transfer 2024/09/26 02:54:47 DEBUG : CopyDest/three: md5 = a46a414f25b5a5a07a12f227c2aa8169 OK 2024/09/26 02:54:47 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/09/26 02:54:47 DEBUG : three: Destination not found in --copy-dest 2024/09/26 02:54:47 DEBUG : three: Need to transfer - File not found at Destination 2024/09/26 02:54:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:47 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:47 DEBUG : one: Unchanged skipping 2024/09/26 02:54:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:47 DEBUG : two: Unchanged skipping 2024/09/26 02:54:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:48 DEBUG : three: md5 = 574ea801b31bb5992e9c600929218bb1 OK 2024/09/26 02:54:48 INFO : three: Copied (new) 2024/09/26 02:54:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (13.79s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:54:50 DEBUG : dst/one: md5 = dc852a60bbc869477469b7929aba2ed0 OK 2024/09/26 02:54:50 DEBUG : dst/two: md5 = e748016fa89a72373d10a490df7ddce0 OK 2024/09/26 02:54:51 DEBUG : dst/three.txt: md5 = 12fc0cfcc20ada139f302707d7684adb OK 2024/09/26 02:54:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:54:52 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:54:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:54:52 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:54:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/backup" 2024/09/26 02:54:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup.bin" 2024/09/26 02:54:53 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup.bin: Auto set use_segments_container to true 2024/09/26 02:54:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup" 2024/09/26 02:54:53 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup: Auto set use_segments_container to true 2024/09/26 02:54:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:54:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:54 DEBUG : two: Unchanged skipping 2024/09/26 02:54:55 INFO : one: Copied (server-side copy) 2024/09/26 02:54:55 INFO : one: Deleted 2024/09/26 02:54:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:55 DEBUG : one: md5 = d16dac474bdc8ed9d6203aecad468ac3 OK 2024/09/26 02:54:55 INFO : one: Copied (new) 2024/09/26 02:54:55 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:56 INFO : three.txt: Copied (server-side copy) 2024/09/26 02:54:56 INFO : three.txt: Deleted 2024/09/26 02:54:56 INFO : three.txt: Moved into backup dir 2024/09/26 02:54:57 DEBUG : dst/three.txt: md5 = 50b90cdc1565fbc47bf9d188fd8ae4e1 OK 2024/09/26 02:54:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:54:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:54:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:54:58 DEBUG : two: Unchanged skipping 2024/09/26 02:54:58 INFO : one: Copied (server-side copy) 2024/09/26 02:54:58 INFO : one: Deleted 2024/09/26 02:54:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:54:58 DEBUG : one: md5 = 2423be2a3471c737455cba5f969b5de7 OK 2024/09/26 02:54:58 INFO : one: Copied (new) 2024/09/26 02:54:58 DEBUG : Waiting for deletions to finish 2024/09/26 02:54:59 INFO : three.txt: Copied (server-side copy) 2024/09/26 02:54:59 INFO : three.txt: Deleted 2024/09/26 02:54:59 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (10.72s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:01 DEBUG : dst/one: md5 = 972c9056df735099060f33a877b400cd OK 2024/09/26 02:55:01 DEBUG : dst/two: md5 = f989d1f8f5cb14bc32cd28bff3af7f0f OK 2024/09/26 02:55:01 DEBUG : dst/three.txt: md5 = a983266dfe17986deb5c27c83a55e7b4 OK 2024/09/26 02:55:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:55:02 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:55:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:02 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:55:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/backup" 2024/09/26 02:55:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup.bin" 2024/09/26 02:55:03 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup.bin: Auto set use_segments_container to true 2024/09/26 02:55:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup" 2024/09/26 02:55:04 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup: Auto set use_segments_container to true 2024/09/26 02:55:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:55:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:04 DEBUG : two: Unchanged skipping 2024/09/26 02:55:05 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:05 INFO : one: Deleted 2024/09/26 02:55:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:05 DEBUG : one: md5 = dace7e909b45be2b35039f7fa0011769 OK 2024/09/26 02:55:05 INFO : one: Copied (new) 2024/09/26 02:55:05 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:06 INFO : three.txt: Deleted 2024/09/26 02:55:06 INFO : three.txt: Moved into backup dir 2024/09/26 02:55:07 DEBUG : dst/three.txt: md5 = 8442fba090566c8b97ebb3aeb8b6af72 OK 2024/09/26 02:55:07 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:55:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:08 DEBUG : two: Unchanged skipping 2024/09/26 02:55:08 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:08 INFO : one: Deleted 2024/09/26 02:55:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:08 DEBUG : one: md5 = 2ec8dc24de5746f611e52cea41281d72 OK 2024/09/26 02:55:08 INFO : one: Copied (new) 2024/09/26 02:55:08 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:08 INFO : three.txt: Deleted 2024/09/26 02:55:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (10.09s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:11 DEBUG : dst/one: md5 = 2ae22c980478600d80ccfd36bfee1214 OK 2024/09/26 02:55:11 DEBUG : dst/two: md5 = 682fa36459451775c413a2de83b8094d OK 2024/09/26 02:55:11 DEBUG : dst/three.txt: md5 = 98f4ba37b00bca0b100d5621ad01c9ef OK 2024/09/26 02:55:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:55:12 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:55:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:13 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:55:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/backup" 2024/09/26 02:55:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup.bin" 2024/09/26 02:55:13 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup.bin: Auto set use_segments_container to true 2024/09/26 02:55:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/backup" 2024/09/26 02:55:14 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/backup: Auto set use_segments_container to true 2024/09/26 02:55:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:55:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:14 DEBUG : two: Unchanged skipping 2024/09/26 02:55:15 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/26 02:55:15 INFO : one: Deleted 2024/09/26 02:55:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:15 DEBUG : one: md5 = 0b020c8364335255ed0f4b3783bbbf14 OK 2024/09/26 02:55:15 INFO : one: Copied (new) 2024/09/26 02:55:15 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:16 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/26 02:55:16 INFO : three.txt: Deleted 2024/09/26 02:55:16 INFO : three.txt: Moved into backup dir 2024/09/26 02:55:17 DEBUG : dst/three.txt: md5 = c5df820aa9bfc0e66481d7b0ea76d44c OK 2024/09/26 02:55:17 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:55:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:17 DEBUG : two: Unchanged skipping 2024/09/26 02:55:18 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/26 02:55:18 INFO : one: Deleted 2024/09/26 02:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:19 DEBUG : one: md5 = f71daf3bca0baff23b2ced368ad2c990 OK 2024/09/26 02:55:19 INFO : one: Copied (new) 2024/09/26 02:55:19 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:19 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/26 02:55:19 INFO : three.txt: Deleted 2024/09/26 02:55:19 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (10.30s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:21 DEBUG : dst/one: md5 = f41daf9e0989060c899d3835498e078c OK 2024/09/26 02:55:21 DEBUG : dst/two: md5 = 8ecd5a8ea513bd4bd692ab3af77438e1 OK 2024/09/26 02:55:22 DEBUG : dst/three.txt: md5 = 5f5aaf741dd6037b072cc8d1a4f4b4f9 OK 2024/09/26 02:55:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:55:22 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:55:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:23 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:55:23 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:55:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:23 DEBUG : two: Unchanged skipping 2024/09/26 02:55:24 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:24 INFO : one: Deleted 2024/09/26 02:55:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:24 DEBUG : one: md5 = 4798bf5fb3cd736bd553887942380e08 OK 2024/09/26 02:55:24 INFO : one: Copied (new) 2024/09/26 02:55:24 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:25 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:25 INFO : three.txt: Deleted 2024/09/26 02:55:25 INFO : three.txt: Moved into backup dir 2024/09/26 02:55:26 DEBUG : dst/three.txt: md5 = ed338ceebdc3507feea93fad82508134 OK 2024/09/26 02:55:26 DEBUG : one.bak: Excluded (Path Filter) 2024/09/26 02:55:26 DEBUG : one.bak: Excluded 2024/09/26 02:55:26 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/09/26 02:55:26 DEBUG : three.txt.bak: Excluded 2024/09/26 02:55:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:55:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for checks to finish 2024/09/26 02:55:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:26 DEBUG : two: Unchanged skipping 2024/09/26 02:55:27 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:27 INFO : one: Deleted 2024/09/26 02:55:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu/dst': Waiting for transfers to finish 2024/09/26 02:55:27 DEBUG : one: md5 = 74ddb75e2d9821a3c0a454333df307fb OK 2024/09/26 02:55:27 INFO : one: Copied (new) 2024/09/26 02:55:27 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:27 INFO : three.txt: Deleted 2024/09/26 02:55:27 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (8.14s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:29 DEBUG : dst/one: md5 = 6859b75896924d4f49d0b2cc9b3b6e23 OK 2024/09/26 02:55:30 DEBUG : dst/two: md5 = 9bb102ae7b215baad5002e1c9916cc9a OK 2024/09/26 02:55:30 DEBUG : dst/three.txt: md5 = 2c4b69bfd41a3752881cfee81f85646f OK 2024/09/26 02:55:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:55:30 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:55:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:31 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:55:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:55:32 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:32 INFO : one: Deleted 2024/09/26 02:55:32 DEBUG : one: md5 = b5d1e35eab6ecfa3ad6bacb15ed24ffb OK 2024/09/26 02:55:32 INFO : one: Copied (new) 2024/09/26 02:55:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:32 DEBUG : two: Unchanged skipping 2024/09/26 02:55:32 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/26 02:55:33 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:33 INFO : three.txt: Deleted 2024/09/26 02:55:33 DEBUG : three.txt: md5 = a5b67a69966f6cd0d6909f4971288ef9 OK 2024/09/26 02:55:33 INFO : three.txt: Copied (new) 2024/09/26 02:55:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:55:34 INFO : one: Copied (server-side copy) to: one.bak 2024/09/26 02:55:34 INFO : one: Deleted 2024/09/26 02:55:34 DEBUG : one: md5 = ce917f71d7655876d2757c732bb53748 OK 2024/09/26 02:55:34 INFO : one: Copied (new) 2024/09/26 02:55:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:34 DEBUG : two: Unchanged skipping 2024/09/26 02:55:35 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/26 02:55:35 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/26 02:55:35 INFO : three.txt: Deleted 2024/09/26 02:55:35 DEBUG : three.txt: md5 = 2d19ec0527dbf50cb09c7092d79f4ebd OK 2024/09/26 02:55:35 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (7.94s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:37 DEBUG : dst/one: md5 = bf6d5fd1acd99235031bb8f7d41e33c6 OK 2024/09/26 02:55:37 DEBUG : dst/two: md5 = c7d784e61b3e80dab3d4053d2536308c OK 2024/09/26 02:55:38 DEBUG : dst/three.txt: md5 = 9064d1148531c943604a983d7c3c3ef7 OK 2024/09/26 02:55:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst.bin" 2024/09/26 02:55:38 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst.bin: Auto set use_segments_container to true 2024/09/26 02:55:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-niladoz6tesu/dst" 2024/09/26 02:55:39 DEBUG : Swift container crypt path rclone-test-niladoz6tesu/dst: Auto set use_segments_container to true 2024/09/26 02:55:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/26 02:55:41 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/26 02:55:41 INFO : one: Deleted 2024/09/26 02:55:41 DEBUG : one: md5 = 6c4dddbb5b929682996a154d0d77b9a2 OK 2024/09/26 02:55:41 INFO : one: Copied (new) 2024/09/26 02:55:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:41 DEBUG : two: Unchanged skipping 2024/09/26 02:55:41 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/26 02:55:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/26 02:55:42 INFO : three.txt: Deleted 2024/09/26 02:55:42 DEBUG : three.txt: md5 = 8455c3b8523c1579b9f825f0a0f782a3 OK 2024/09/26 02:55:42 INFO : three.txt: Copied (new) 2024/09/26 02:55:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/26 02:55:43 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/26 02:55:43 INFO : one: Deleted 2024/09/26 02:55:43 DEBUG : one: md5 = 87e7bbe52b1fbff30b3d935d40d7092d OK 2024/09/26 02:55:43 INFO : one: Copied (new) 2024/09/26 02:55:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:43 DEBUG : two: Unchanged skipping 2024/09/26 02:55:43 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/26 02:55:43 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/26 02:55:43 INFO : three.txt: Deleted 2024/09/26 02:55:43 DEBUG : three.txt: md5 = 326c08972dd3727b1ea6d701d976969d OK 2024/09/26 02:55:43 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (7.96s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:45 DEBUG : Testêé: md5 = b1b4be682d19d49fef2accecfe8bb76b OK 2024/09/26 02:55:46 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/09/26 02:55:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:55:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:55:46 DEBUG : Testêé: md5 = e11346671f49f2a7cf4dd0cbcbcc7a7e OK 2024/09/26 02:55:46 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/09/26 02:55:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.43s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:47 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/26 02:55:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:55:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:55:47 DEBUG : existing: md5 = a4081b3e4511d6f8f28ef721e288fd96 OK 2024/09/26 02:55:47 INFO : existing: Copied (new) 2024/09/26 02:55:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:48 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/09/26 02:55:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:55:48 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/09/26 02:55:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:55:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': not deleting files as there were IO errors 2024/09/26 02:55:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncImmutable (2.25s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:50 DEBUG : EXISTING: md5 = 9409ada8952c7782ccf21ceb2ec1d03f OK 2024/09/26 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:55:50 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:55:50 DEBUG : existing: Unchanged skipping 2024/09/26 02:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:55:50 DEBUG : Waiting for deletions to finish 2024/09/26 02:55:50 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.61s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:2529: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.21s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:2568: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:2568: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" sync_test.go:2568: This test only runs on local --- PASS: TestMaxTransfer (0.39s) --- SKIP: TestMaxTransfer/Hard (0.20s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:55:51 DEBUG : both0: md5 = 267a37a430198d662ce5515b6b72c792 OK 2024/09/26 02:55:52 DEBUG : only0: md5 = 1a314db220f3dfe1cb094f93f1d86c74 OK 2024/09/26 02:55:53 DEBUG : both1: md5 = 2053fcd32455b03f9734cc954d32f38e OK 2024/09/26 02:55:53 DEBUG : only1: md5 = 3fb21d8e909114b53f2cd036e8c6eaf7 OK 2024/09/26 02:55:53 DEBUG : both2: md5 = 30bfa6bfae25cf9c717ffe74dcdfc040 OK 2024/09/26 02:55:54 DEBUG : only2: md5 = bb361779b0aaee2051b0ed85ddd1d978 OK 2024/09/26 02:55:54 DEBUG : both3: md5 = a3e875be43b163bd5a6c0b0b3d7c0faa OK 2024/09/26 02:55:55 DEBUG : only3: md5 = 28a4a7041ebfc59e033ee5cb452d50b9 OK 2024/09/26 02:55:55 DEBUG : both4: md5 = 722cfcb9c52acae7cfb061986e66d1e9 OK 2024/09/26 02:55:56 DEBUG : only4: md5 = 7c97da69e3d357034e560b76a2169d4a OK 2024/09/26 02:55:56 DEBUG : both5: md5 = e6e7a2c703618e7465bf396b27d06e74 OK 2024/09/26 02:55:57 DEBUG : only5: md5 = 6f7c630daa0f979a50ddc9b4f58d6fe8 OK 2024/09/26 02:55:57 DEBUG : both6: md5 = 3a3d845dc4d6b7d73b2ddfbcbd98396e OK 2024/09/26 02:55:57 DEBUG : only6: md5 = 07b808655dda266b0ebd5889c4235b8d OK 2024/09/26 02:55:58 DEBUG : both7: md5 = 77044011569cd22c9c9f686ece447d17 OK 2024/09/26 02:55:58 DEBUG : only7: md5 = 5cb79952fef68c2ba66267de3d2156e5 OK 2024/09/26 02:55:59 DEBUG : both8: md5 = 1b944e119036c22d7cc98fa0e3fd9c50 OK 2024/09/26 02:55:59 DEBUG : only8: md5 = 4d31295aeffc1c5a86c849fc96badcf6 OK 2024/09/26 02:56:01 DEBUG : both9: md5 = 5f286ced37aabfe21be4643900b02064 OK 2024/09/26 02:56:01 DEBUG : only9: md5 = a24b7c24a24bfc2bb23bdec0dcc2d7ea OK 2024/09/26 02:56:01 DEBUG : both10: md5 = a537ce65acb2fb739d60393823ec19b8 OK 2024/09/26 02:56:02 DEBUG : only10: md5 = 3aa73f5837909581b27101a30624ecc4 OK 2024/09/26 02:56:02 DEBUG : both11: md5 = 1bf8795d3543bf2c09ea29082e0af9b9 OK 2024/09/26 02:56:03 DEBUG : only11: md5 = e44ac2e003cc098576fcc7001dbf5543 OK 2024/09/26 02:56:03 DEBUG : both12: md5 = faa5a5cb69f4d2e6a0cd032a9deb4a10 OK 2024/09/26 02:56:03 DEBUG : only12: md5 = 6ac44b40568fe1c44099c8e0838c49c4 OK 2024/09/26 02:56:03 DEBUG : both13: md5 = 0f7c2ace0abb4a247242007bf380b4d4 OK 2024/09/26 02:56:04 DEBUG : only13: md5 = 1979b402495fa7010567cd024b848d7c OK 2024/09/26 02:56:06 DEBUG : both14: md5 = 469da8d75f3bc629fbce3bfda4c7498b OK 2024/09/26 02:56:06 DEBUG : only14: md5 = 9edb7d31bd64c15d0663c473c6769aae OK 2024/09/26 02:56:06 DEBUG : both15: md5 = c1e9cb2f60e8f31ac60fba32551b9d7a OK 2024/09/26 02:56:07 DEBUG : only15: md5 = 47d41d86d67925b8dee5dfa1ee7ef690 OK 2024/09/26 02:56:07 DEBUG : both16: md5 = e7491abeeb216d88cad2a448bd4a1888 OK 2024/09/26 02:56:08 DEBUG : only16: md5 = fde6715e4845c7a97b86b9815ddcacbe OK 2024/09/26 02:56:08 DEBUG : both17: md5 = 604d8a9df8c207ef109d65d1f2de9e1f OK 2024/09/26 02:56:08 DEBUG : only17: md5 = ebd69bd18494cd94134273e32a18194b OK 2024/09/26 02:56:11 DEBUG : both18: md5 = 6ed8f6a1bfa842e7cc7e1279f6405153 OK 2024/09/26 02:56:12 DEBUG : only18: md5 = 27f8c931714034f2245036875cbbb34d OK 2024/09/26 02:56:12 DEBUG : both19: md5 = 50940ac3b8c99ea44ff15799756ca52e OK 2024/09/26 02:56:13 DEBUG : only19: md5 = f2bcd87993a70d0212c26f840f5bd90d OK 2024/09/26 02:56:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:56:15 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both0: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both1: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both10: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both13: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both14: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both11: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both12: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both15: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both16: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both17: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both18: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both19: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both3: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both4: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both2: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both6: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both5: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both8: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both7: Unchanged skipping 2024/09/26 02:56:15 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:15 DEBUG : both9: Unchanged skipping 2024/09/26 02:56:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:56:15 DEBUG : Waiting for deletions to finish 2024/09/26 02:56:15 INFO : only0: Deleted 2024/09/26 02:56:15 INFO : only3: Deleted 2024/09/26 02:56:15 INFO : only14: Deleted 2024/09/26 02:56:15 INFO : only9: Deleted 2024/09/26 02:56:16 INFO : only1: Deleted 2024/09/26 02:56:16 INFO : only17: Deleted 2024/09/26 02:56:16 INFO : only5: Deleted 2024/09/26 02:56:16 INFO : only11: Deleted 2024/09/26 02:56:16 INFO : only12: Deleted 2024/09/26 02:56:16 INFO : only15: Deleted 2024/09/26 02:56:16 INFO : only13: Deleted 2024/09/26 02:56:16 INFO : only16: Deleted 2024/09/26 02:56:16 INFO : only19: Deleted 2024/09/26 02:56:17 INFO : only2: Deleted 2024/09/26 02:56:17 INFO : only10: Deleted 2024/09/26 02:56:17 INFO : only8: Deleted 2024/09/26 02:56:17 INFO : only18: Deleted 2024/09/26 02:56:17 INFO : only6: Deleted 2024/09/26 02:56:17 INFO : only4: Deleted 2024/09/26 02:56:17 INFO : only7: Deleted 2024/09/26 02:56:17 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (31.65s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:56:23 DEBUG : both0: md5 = a3c60a502fdaecfc5149472997d4c716 OK 2024/09/26 02:56:23 DEBUG : only0: md5 = 70ae3ee583738aa3066913629e981ac5 OK 2024/09/26 02:56:23 DEBUG : both1: md5 = 0f6ebd340f1f4fed2333263565ed31d7 OK 2024/09/26 02:56:24 DEBUG : only1: md5 = 2ce782169245ccd4a5afe769648dd3aa OK 2024/09/26 02:56:24 DEBUG : both2: md5 = eafda11eedf9894f124485aa6e520798 OK 2024/09/26 02:56:24 DEBUG : only2: md5 = 80b333b6ce7fc173aed495d1f3cbf00d OK 2024/09/26 02:56:24 DEBUG : both3: md5 = 679eefe83bc1b2bf16d7d86bc9ccfd23 OK 2024/09/26 02:56:24 DEBUG : only3: md5 = cd1b414ef6de8375ac2b356aa7e347a9 OK 2024/09/26 02:56:25 DEBUG : both4: md5 = 547f1a21a54f30c3523576f33a3712de OK 2024/09/26 02:56:25 DEBUG : only4: md5 = a5053d9d8c8e4458dde3239ed88caa4a OK 2024/09/26 02:56:25 DEBUG : both5: md5 = 648e439226ced4ee8fe63cb504dfe781 OK 2024/09/26 02:56:26 DEBUG : only5: md5 = 791642f820fe807ea91d0ad9e8f3d76b OK 2024/09/26 02:56:26 DEBUG : both6: md5 = 01fb0f3fcaf40dd65f3af80a32aacb08 OK 2024/09/26 02:56:26 DEBUG : only6: md5 = 4474736a6e441973c335be94626fc469 OK 2024/09/26 02:56:27 DEBUG : both7: md5 = 52a197318bf0234bedb1f91c5d55a74c OK 2024/09/26 02:56:27 DEBUG : only7: md5 = 73c09667d4f1d68bdfb458b60a4099fd OK 2024/09/26 02:56:27 DEBUG : both8: md5 = 5ed224cf78e5aec9e3de50ed927dcbc0 OK 2024/09/26 02:56:27 DEBUG : only8: md5 = f387c499a501f95cd8a41de675eb2270 OK 2024/09/26 02:56:28 DEBUG : both9: md5 = 3201476bee501c17c85aa20b1dda20e8 OK 2024/09/26 02:56:28 DEBUG : only9: md5 = 5fed2c7b0005f829c72f3b80e606fdd5 OK 2024/09/26 02:56:28 DEBUG : both10: md5 = 542d4ab91f816cb7e2e9e63169ec0e27 OK 2024/09/26 02:56:28 DEBUG : only10: md5 = 9f434c3f477d178f5d30e5de2948687f OK 2024/09/26 02:56:28 DEBUG : both11: md5 = 0417d20c51c8c24f162b3d46ff352d64 OK 2024/09/26 02:56:29 DEBUG : only11: md5 = 4715352615e46961f68e20e74870585c OK 2024/09/26 02:56:29 DEBUG : both12: md5 = a8460a3cb74c261049b2be407bdc14fa OK 2024/09/26 02:56:29 DEBUG : only12: md5 = addba730f9085e734747ac6ae06e638c OK 2024/09/26 02:56:29 DEBUG : both13: md5 = 0ddcf3c6a0e16d75bf1eee592ad9d159 OK 2024/09/26 02:56:29 DEBUG : only13: md5 = 5f8df09bb664be198aba7a0941005ad3 OK 2024/09/26 02:56:29 DEBUG : both14: md5 = dccdd5b9b3bca378ec6c55278cc2c17a OK 2024/09/26 02:56:30 DEBUG : only14: md5 = be8fea73d0b11135af5e0793f0330816 OK 2024/09/26 02:56:30 DEBUG : both15: md5 = 4516d39db62cbe37f721ec73cc5cbb20 OK 2024/09/26 02:56:30 DEBUG : only15: md5 = 04d30a1819bc37b43dab456aa1721797 OK 2024/09/26 02:56:30 DEBUG : both16: md5 = 6eeae612bdb79a219c90d3183e51c62b OK 2024/09/26 02:56:30 DEBUG : only16: md5 = cf950ef2674954bee08a590795b60ae8 OK 2024/09/26 02:56:31 DEBUG : both17: md5 = 64ee02e8f96552a919f5297f97701eab OK 2024/09/26 02:56:31 DEBUG : only17: md5 = 6889ecd4e5813a0de6123deab2b65d25 OK 2024/09/26 02:56:31 DEBUG : both18: md5 = 46f17fff67e9864b2d958b585b5d1e22 OK 2024/09/26 02:56:32 DEBUG : only18: md5 = cd1a581ca80b87096d58042356cc792a OK 2024/09/26 02:56:32 DEBUG : both19: md5 = 94892c28e997a39df64bbc15cb7b7cb3 OK 2024/09/26 02:56:32 DEBUG : only19: md5 = 97115f617f9fcd6709fa79e92549583f OK 2024/09/26 02:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for checks to finish 2024/09/26 02:56:34 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both10: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both1: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both11: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both0: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both15: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both14: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both13: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both12: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both16: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both17: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both18: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both19: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both2: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both3: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both4: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both5: Unchanged skipping 2024/09/26 02:56:34 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : both7: Unchanged skipping 2024/09/26 02:56:34 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : both8: Unchanged skipping 2024/09/26 02:56:34 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : both6: Unchanged skipping 2024/09/26 02:56:34 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/26 02:56:34 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : both9: Unchanged skipping 2024/09/26 02:56:34 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/09/26 02:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Waiting for transfers to finish 2024/09/26 02:56:35 DEBUG : only0: md5 = 6faf25b72892b314b8bb7095dc265bfc OK 2024/09/26 02:56:35 INFO : only0: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only13: md5 = ce55ce5b6a032385cc5450adcc2c68e6 OK 2024/09/26 02:56:35 INFO : only13: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only11: md5 = f647dc942dc32709415bbfeace568e6f OK 2024/09/26 02:56:35 INFO : only11: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only14: md5 = f17da78c7ff654954107f662ed8fd7f2 OK 2024/09/26 02:56:35 INFO : only14: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only1: md5 = c249f680263cb5014f429d70807efefe OK 2024/09/26 02:56:35 INFO : only1: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only16: md5 = 5e80d338212f5560b9267313dc1efb61 OK 2024/09/26 02:56:35 INFO : only16: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only15: md5 = 9d5fd7b7b2d399377939f2de1514353c OK 2024/09/26 02:56:35 INFO : only15: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only10: md5 = ff94f4986230e24ea9562d6eac9d05b9 OK 2024/09/26 02:56:35 INFO : only10: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only12: md5 = 1954bb09c346c2dc7c96cd775fe37dc4 OK 2024/09/26 02:56:35 INFO : only12: Copied (replaced existing) 2024/09/26 02:56:35 DEBUG : only2: md5 = da8d696d59b0d148d48c48904303256b OK 2024/09/26 02:56:35 INFO : only2: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only3: md5 = 228d723f21ea723ed99b4bf77ed542bb OK 2024/09/26 02:56:36 INFO : only3: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only17: md5 = ae8c56c46ca9be05780653c232156f29 OK 2024/09/26 02:56:36 INFO : only17: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only4: md5 = 3d092721ae82f292a63288ebaa9b6c02 OK 2024/09/26 02:56:36 INFO : only4: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only6: md5 = eab78eeb73fede4017ac4e18c9171e55 OK 2024/09/26 02:56:36 INFO : only6: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only18: md5 = 5d11f84300fba617140e63c1a6926cb2 OK 2024/09/26 02:56:36 INFO : only18: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only19: md5 = d5def34e5eb0b4a6b0be07950dadee6e OK 2024/09/26 02:56:36 INFO : only19: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only5: md5 = 383eaf5bbd8d7dd5ea3a75c20578124b OK 2024/09/26 02:56:36 INFO : only5: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only7: md5 = 957f72290f615612138def22add5291e OK 2024/09/26 02:56:36 INFO : only7: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only8: md5 = 9defd8e0864571e71a4a42918ba385fa OK 2024/09/26 02:56:36 INFO : only8: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : only9: md5 = bf37a53fbea06b0ff25124d4946f73fb OK 2024/09/26 02:56:36 INFO : only9: Copied (replaced existing) 2024/09/26 02:56:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (25.79s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:56:48 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/26 02:56:48 DEBUG : sub dir: Making directory 2024/09/26 02:56:48 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestNothingToTransferWithEmptyDirs (2.54s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" 2024/09/26 02:56:51 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/26 02:56:51 DEBUG : sub dir: Making directory 2024/09/26 02:56:51 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu'", Local "Local file system at /tmp/rclone3339624811", Modify Window "1ns" --- PASS: TestNothingToTransferWithoutEmptyDirs (2.18s) PASS 2024/09/26 02:56:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-niladoz6tesu': Purge remote 2024/09/26 02:56:53 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m48.205129889s (try 1/5)