"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2024/09/25 04:16:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi" 2024/09/25 04:16:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/25 04:16:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi.bin" 2024/09/25 04:16:24 DEBUG : Swift container crypt path rclone-test-horucec7dixi.bin: Auto set use_segments_container to true 2024/09/25 04:16:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi" 2024/09/25 04:16:25 DEBUG : Swift container crypt path rclone-test-horucec7dixi: Auto set use_segments_container to true 2024/09/25 04:16:25 DEBUG : Creating backend with remote "/tmp/rclone322179843" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:25 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/09/25 04:16:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:26 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/25 04:16:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:27 DEBUG : sub dir/hello world: md5 = a5a18c4b4d08b64f17b7cf128e61d67c OK 2024/09/25 04:16:27 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.87s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.18s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:28 DEBUG : Creating backend with remote "/non-existing" 2024/09/25 04:16:28 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/09/25 04:16:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.24s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:28 DEBUG : sub dir/hello world: md5 = 5f076ad2ef053a3c4eefee50ca879807 OK 2024/09/25 04:16:28 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyNoTraverse (0.93s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Running all checks before starting transfers 2024/09/25 04:16:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Checks finished, now starting transfers 2024/09/25 04:16:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:29 DEBUG : sub dir/hello world: md5 = 7c580b7378ad73e8a4ba371684251293 OK 2024/09/25 04:16:29 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyCheckFirst (1.09s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:30 ERROR : Ignoring --no-traverse with sync 2024/09/25 04:16:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:31 DEBUG : sub dir/hello world: md5 = 106d4a2948e8348ae37c46ac5290936f OK 2024/09/25 04:16:31 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:16:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncNoTraverse (1.06s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:31 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/09/25 04:16:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:32 DEBUG : hello world2: md5 = 0a47e7053b3018397ffa35e1360a7c9c OK 2024/09/25 04:16:32 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyWithDepth (1.50s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:33 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:16:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:34 DEBUG : potato2: md5 = fc4e67005ee6dfcbd8a4506179685db4 OK 2024/09/25 04:16:34 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.69s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:35 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:16:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:35 DEBUG : potato2: md5 = e8817e17a7e86525d2df521aea0f3a0f OK 2024/09/25 04:16:35 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.43s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:36 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/25 04:16:36 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/25 04:16:36 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2024/09/25 04:16:36 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/25 04:16:36 DEBUG : sub dir: Making directory 2024/09/25 04:16:36 DEBUG : sub dir2: Making directory 2024/09/25 04:16:36 DEBUG : sub dir2/sub sub dir2: Making directory 2024/09/25 04:16:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:36 DEBUG : sub dir/hello world: md5 = c11f2aaa9c5c27f6061b2823bb9f2b8c OK 2024/09/25 04:16:36 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2024/09/25 04:16:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.37s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:37 DEBUG : sub dir2: Making directory 2024/09/25 04:16:37 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2024/09/25 04:16:37 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/25 04:16:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:38 DEBUG : sub dir/hello world: md5 = 27df7929e3fb60225f688e1ad4345ad7 OK 2024/09/25 04:16:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (0.82s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:38 DEBUG : sub dir2: Making directory with metadata 2024/09/25 04:16:38 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/25 04:16:38 DEBUG : sub dir: Making directory 2024/09/25 04:16:38 DEBUG : sub dir2: Making directory 2024/09/25 04:16:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:38 DEBUG : sub dir/hello world: md5 = 9b5bad3d4f3a15186bd1e73bfdc2ff66 OK 2024/09/25 04:16:38 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:16:38 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/25 04:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/09/25 04:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.02s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:39 DEBUG : sub dir2: Making directory 2024/09/25 04:16:39 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:40 DEBUG : sub dir/hello world: md5 = 2af8f5f9603bed56c84c2d6bab3ae0cf OK 2024/09/25 04:16:40 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:16:40 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (0.87s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:40 DEBUG : sub dir2: Making directory with metadata 2024/09/25 04:16:40 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/09/25 04:16:40 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/25 04:16:40 DEBUG : sub dir: Making directory 2024/09/25 04:16:40 DEBUG : sub dir2: Making directory 2024/09/25 04:16:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:41 DEBUG : sub dir/hello world: md5 = cdd65ee28bec586d4d3bd745944f8261 OK 2024/09/25 04:16:41 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:16:41 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/09/25 04:16:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.10s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:41 DEBUG : sub dir2: Making directory 2024/09/25 04:16:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:42 DEBUG : sub dir/hello world: md5 = cfb992d3d3cafd2abd5c21de34e602ce OK 2024/09/25 04:16:42 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:16:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (0.80s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:42 DEBUG : sub dir/hello world: md5 = 000e982820f05a69537f200cff5c4de4 OK 2024/09/25 04:16:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-samocix6jiva" 2024/09/25 04:16:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-samocix6jiva.bin" 2024/09/25 04:16:43 DEBUG : Swift container crypt path rclone-test-samocix6jiva.bin: Auto set use_segments_container to true 2024/09/25 04:16:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-samocix6jiva" 2024/09/25 04:16:44 DEBUG : Swift container crypt path rclone-test-samocix6jiva: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi' -> Encrypted drive 'TestCryptSwift:rclone-test-samocix6jiva' 2024/09/25 04:16:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-samocix6jiva': Waiting for checks to finish 2024/09/25 04:16:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-samocix6jiva': Waiting for transfers to finish 2024/09/25 04:16:45 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-samocix6jiva': Purge remote 2024/09/25 04:16:45 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:45 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (3.80s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:46 DEBUG : sub dir/hello world: md5 = 15d300cced9c7dd8c59aea5cae6cab56 OK 2024/09/25 04:16:46 ERROR : : error listing: directory not found 2024/09/25 04:16:46 DEBUG : Local file system at /tmp/rclone322179843: Making directory 2024/09/25 04:16:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyAfterDelete (1.44s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:48 DEBUG : sub dir/hello world: md5 = c57fa2f0412463830c75033df472c93c OK 2024/09/25 04:16:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:16:48 DEBUG : Local file system at /tmp/rclone322179843: Waiting for checks to finish 2024/09/25 04:16:48 DEBUG : Local file system at /tmp/rclone322179843: Waiting for transfers to finish 2024/09/25 04:16:48 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2024/09/25 04:16:48 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyRedownload (1.26s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:49 DEBUG : check sum: Need to transfer - File not found at Destination 2024/09/25 04:16:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:49 DEBUG : check sum: md5 = 5d81b68ee7b285d8166f176739d8206f OK 2024/09/25 04:16:49 INFO : check sum: Copied (new) 2024/09/25 04:16:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:49 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/09/25 04:16:49 DEBUG : check sum: Size of src and dst objects identical 2024/09/25 04:16:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:50 DEBUG : check sum: Unchanged skipping 2024/09/25 04:16:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:50 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (1.78s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:51 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/09/25 04:16:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:51 DEBUG : sizeonly: md5 = 58b88b5a55fabc01f251bc8ad03f40d9 OK 2024/09/25 04:16:51 INFO : sizeonly: Copied (new) 2024/09/25 04:16:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:51 DEBUG : sizeonly: Sizes identical 2024/09/25 04:16:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:51 DEBUG : sizeonly: Unchanged skipping 2024/09/25 04:16:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:51 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:51 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncSizeOnly (1.66s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:52 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/09/25 04:16:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:52 DEBUG : ignore-size: md5 = 126540bd3e9165f0cf0603258f111cdb OK 2024/09/25 04:16:52 INFO : ignore-size: Copied (new) 2024/09/25 04:16:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:53 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:16:53 DEBUG : ignore-size: Unchanged skipping 2024/09/25 04:16:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:53 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:53 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.74s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:54 DEBUG : existing: md5 = ff4e9046d722362a9969a5f5d5d29b1c OK 2024/09/25 04:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:55 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:16:55 DEBUG : existing: Unchanged skipping 2024/09/25 04:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:55 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:55 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:55 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/09/25 04:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:55 DEBUG : existing: md5 = 1d239c008b0d69bbf31c57d5631931ce OK 2024/09/25 04:16:55 INFO : existing: Copied (replaced existing) 2024/09/25 04:16:55 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (2.32s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:56 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/25 04:16:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:57 DEBUG : existing: md5 = 5cbcc021f618905870a77af08dc1ae34 OK 2024/09/25 04:16:57 INFO : existing: Copied (new) 2024/09/25 04:16:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:57 DEBUG : existing: Destination exists, skipping 2024/09/25 04:16:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:57 DEBUG : Waiting for deletions to finish 2024/09/25 04:16:57 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.85s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:16:58 DEBUG : b/potato: md5 = a8a6052719efc799371e6d0a3c0ffe30 OK 2024/09/25 04:16:59 DEBUG : c/non empty space: md5 = 8de33d4f2bc01859ef4e974a3357a92c OK 2024/09/25 04:16:59 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/25 04:16:59 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/25 04:16:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:16:59 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:16:59 DEBUG : c/non empty space: Unchanged skipping 2024/09/25 04:16:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:16:59 DEBUG : a/potato2: md5 = 18734df163650adf2a42e38312c820f8 OK 2024/09/25 04:16:59 INFO : a/potato2: Copied (new) 2024/09/25 04:16:59 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:00 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (2.63s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:01 DEBUG : empty space: md5 = 3865b7bf38bfe87898f293dd323b7367 OK 2024/09/25 04:17:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:01 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/25 04:17:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:01 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/09/25 04:17:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:02 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/25 04:17:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:02 DEBUG : empty space: md5 = 4379ce55b0a70d1e30daa1d2acfe638d OK 2024/09/25 04:17:02 INFO : empty space: Copied (replaced existing) 2024/09/25 04:17:02 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.16s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:03 DEBUG : foo: md5 = ef42fafef8669ff0609e93ba153ba620 OK 2024/09/25 04:17:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:04 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/25 04:17:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:04 DEBUG : foo: md5 = 27e62b5b0e7b295b822d24fe4e0a6c08 OK 2024/09/25 04:17:04 INFO : foo: Copied (replaced existing) 2024/09/25 04:17:04 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (1.66s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:05 DEBUG : empty space: md5 = ffc7021ed3613b8a9caa29a2604274fd OK 2024/09/25 04:17:05 DEBUG : potato: Need to transfer - File not found at Destination 2024/09/25 04:17:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:05 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:06 DEBUG : potato: md5 = 614e8a76c2a227d83e4732dc201c8302 OK 2024/09/25 04:17:06 INFO : potato: Copied (new) 2024/09/25 04:17:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (2.27s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:07 DEBUG : potato: md5 = 3e796bc066cf3a1cafb74816f6a582d3 OK 2024/09/25 04:17:07 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/09/25 04:17:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:07 DEBUG : potato: md5 = 4a79c8de63ac427c459f0ec3b194c403 OK 2024/09/25 04:17:07 INFO : potato: Copied (replaced existing) 2024/09/25 04:17:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.41s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:08 DEBUG : potato: md5 = 889243161ba889552a6e9bfe0ae74aac OK 2024/09/25 04:17:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:09 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2024/09/25 04:17:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:09 DEBUG : potato: md5 = c90d90e913f25903f2ff2b19ed96de38 OK 2024/09/25 04:17:09 INFO : potato: Copied (replaced existing) 2024/09/25 04:17:09 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.64s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:10 DEBUG : potato: md5 = 3ab395561ce588a6c0b26c96d5ab3d36 OK 2024/09/25 04:17:10 DEBUG : empty space: md5 = 4666d45b5bf7d1faa8e6db63368b190d OK 2024/09/25 04:17:11 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:11 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/09/25 04:17:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:11 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:11 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:11 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.61s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:12 DEBUG : potato: md5 = a8adad2c2360f5651c4b6fd56eb8da00 OK 2024/09/25 04:17:12 DEBUG : empty space: md5 = ece41a5c67ec6c39937181514bfe19be OK 2024/09/25 04:17:12 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:12 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:13 DEBUG : potato2: md5 = b921f982817ad8b87730a561a8024c98 OK 2024/09/25 04:17:13 INFO : potato2: Copied (new) 2024/09/25 04:17:13 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:13 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.25s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:14 DEBUG : b/potato: md5 = 70a28e7934adedb7409794f81d40c750 OK 2024/09/25 04:17:14 DEBUG : c/non empty space: md5 = 216a9253a6f1068709739fc2a7c68fae OK 2024/09/25 04:17:14 DEBUG : d: Making directory 2024/09/25 04:17:14 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/09/25 04:17:15 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:15 DEBUG : a/potato2: md5 = a3e5ed6d6665503d89b7a963c3a9263d OK 2024/09/25 04:17:15 INFO : a/potato2: Copied (new) 2024/09/25 04:17:15 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:15 DEBUG : c/non empty space: Unchanged skipping 2024/09/25 04:17:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:15 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:15 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.36s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:16 DEBUG : b/potato: md5 = 10dffe4ebd4d33341cf5345e23b9a669 OK 2024/09/25 04:17:17 DEBUG : c/non empty space: md5 = fcb000fd5d0d44bfd427d351cdfa7c36 OK 2024/09/25 04:17:17 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/09/25 04:17:17 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:17 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:17 DEBUG : c/non empty space: Unchanged skipping 2024/09/25 04:17:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:17 DEBUG : a/potato2: md5 = 0986b1708bb17c2847e29a1153b4b4a0 OK 2024/09/25 04:17:17 INFO : a/potato2: Copied (new) 2024/09/25 04:17:17 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': not deleting files as there were IO errors 2024/09/25 04:17:17 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.72s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:19 DEBUG : potato: md5 = d253820800b6d4cfb1a79224445ddf2f OK 2024/09/25 04:17:19 DEBUG : empty space: md5 = 36e47472c6615fe6b668a7b5a0343477 OK 2024/09/25 04:17:19 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:20 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:20 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:20 DEBUG : potato2: md5 = 9dbaee84705d99a61e85c8e1ef9710ad OK 2024/09/25 04:17:20 INFO : potato2: Copied (new) 2024/09/25 04:17:20 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (2.33s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:21 DEBUG : potato: md5 = 93cb8d8bc06a61574ead006f54894cd6 OK 2024/09/25 04:17:21 DEBUG : empty space: md5 = 7592c596de5fb8e259bc9dd310c7a4a4 OK 2024/09/25 04:17:22 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:22 INFO : potato: Deleted 2024/09/25 04:17:22 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:22 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:22 DEBUG : potato2: md5 = c0f716a8665824cafa17b91110bcc640 OK 2024/09/25 04:17:22 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (1.96s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:23 DEBUG : potato: md5 = ab7044acf0106219a330aca11218536d OK 2024/09/25 04:17:24 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:24 DEBUG : potato2: md5 = d86cbef7f4c25600a9dfcb2838d844de OK 2024/09/25 04:17:24 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.85s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:25 DEBUG : potato2: md5 = caa213fa0e02d718960fe52f4bd23003 OK 2024/09/25 04:17:25 DEBUG : empty space: md5 = 151f9a31cb45bf58ffb005eadd78615c OK 2024/09/25 04:17:25 DEBUG : enormous: Excluded (Size Filter) 2024/09/25 04:17:25 DEBUG : enormous: Excluded 2024/09/25 04:17:25 DEBUG : potato2: Excluded (Size Filter) 2024/09/25 04:17:25 DEBUG : potato2: Excluded 2024/09/25 04:17:26 DEBUG : potato2: Excluded (Size Filter) 2024/09/25 04:17:26 DEBUG : potato2: Excluded 2024/09/25 04:17:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:26 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:26 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:26 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:26 DEBUG : enormous: Excluded (Size Filter) 2024/09/25 04:17:26 DEBUG : enormous: Excluded 2024/09/25 04:17:26 DEBUG : potato2: Excluded (Size Filter) 2024/09/25 04:17:26 DEBUG : potato2: Excluded 2024/09/25 04:17:26 DEBUG : potato2: Excluded (Size Filter) 2024/09/25 04:17:26 DEBUG : potato2: Excluded 2024/09/25 04:17:26 DEBUG : Local file system at /tmp/rclone322179843: Waiting for checks to finish 2024/09/25 04:17:26 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:26 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:26 DEBUG : Local file system at /tmp/rclone322179843: Waiting for transfers to finish 2024/09/25 04:17:26 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:26 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncWithExclude (1.77s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:27 DEBUG : potato2: md5 = 51ee115bcfe4ac4c97feca82ad04e40d OK 2024/09/25 04:17:27 DEBUG : empty space: md5 = f97452d9c7378b6c4e658a29405fdfa5 OK 2024/09/25 04:17:28 DEBUG : enormous: md5 = 6c2e21d4609ce93f5f4b3948c77423a0 OK 2024/09/25 04:17:28 DEBUG : enormous: Excluded (Size Filter) 2024/09/25 04:17:28 DEBUG : enormous: Excluded 2024/09/25 04:17:28 DEBUG : potato2: Excluded (Size Filter) 2024/09/25 04:17:28 DEBUG : potato2: Excluded 2024/09/25 04:17:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:28 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:28 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:28 INFO : potato2: Deleted 2024/09/25 04:17:28 INFO : enormous: Deleted 2024/09/25 04:17:28 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:29 DEBUG : Local file system at /tmp/rclone322179843: Waiting for checks to finish 2024/09/25 04:17:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:29 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:29 DEBUG : Local file system at /tmp/rclone322179843: Waiting for transfers to finish 2024/09/25 04:17:29 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:29 INFO : enormous: Deleted 2024/09/25 04:17:29 INFO : potato2: Deleted 2024/09/25 04:17:29 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.63s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:30 DEBUG : one: md5 = c926e843e2452bd9c29e702f06ba1504 OK 2024/09/25 04:17:30 DEBUG : two: md5 = aa941b74db2e089d5edde1da4a86c1ad OK 2024/09/25 04:17:31 DEBUG : three: md5 = 48423489c0ab240a7b52d4d822ef93ce OK 2024/09/25 04:17:31 DEBUG : four: md5 = e0b10dd172c5a0375c335e4bd54371e3 OK 2024/09/25 04:17:31 DEBUG : five: Need to transfer - File not found at Destination 2024/09/25 04:17:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:31 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/09/25 04:17:31 DEBUG : one: Destination is newer than source, skipping 2024/09/25 04:17:31 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/09/25 04:17:31 DEBUG : three: Sizes identical 2024/09/25 04:17:31 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/09/25 04:17:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:32 DEBUG : two: md5 = c89ce63b79020c86ee87ac45e62195bf OK 2024/09/25 04:17:32 INFO : two: Copied (replaced existing) 2024/09/25 04:17:32 DEBUG : four: md5 = 66350cae3db2d0020b28d356757dc105 OK 2024/09/25 04:17:32 INFO : four: Copied (replaced existing) 2024/09/25 04:17:32 DEBUG : five: md5 = d33ca780f65aa0cc90b9d501b403e9d0 OK 2024/09/25 04:17:32 INFO : five: Copied (new) 2024/09/25 04:17:32 DEBUG : Waiting for deletions to finish sync_test.go:1347: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.44s) === 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:1436: Can track renames: false 2024/09/25 04:17:34 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Ignoring --track-renames as the source and destination do not have a common hash 2024/09/25 04:17:34 DEBUG : potato: Need to transfer - File not found at Destination 2024/09/25 04:17:34 DEBUG : yam: Need to transfer - File not found at Destination 2024/09/25 04:17:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:34 DEBUG : potato: md5 = d2dde284f80df30a3af1b022427f09c6 OK 2024/09/25 04:17:34 INFO : potato: Copied (new) 2024/09/25 04:17:34 DEBUG : yam: md5 = 849388f1d6ecfe049239b011a22ae624 OK 2024/09/25 04:17:34 INFO : yam: Copied (new) 2024/09/25 04:17:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:34 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Ignoring --track-renames as the source and destination do not have a common hash 2024/09/25 04:17:34 DEBUG : yaml: Need to transfer - File not found at Destination 2024/09/25 04:17:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:35 DEBUG : potato: Unchanged skipping 2024/09/25 04:17:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:35 DEBUG : yaml: md5 = afa542f8e67daff8bf18a94124cb532d OK 2024/09/25 04:17:35 INFO : yaml: Copied (new) 2024/09/25 04:17:35 DEBUG : Waiting for deletions to finish 2024/09/25 04:17:35 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (3.91s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:1508: Can track renames: true 2024/09/25 04:17:38 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Making map for --track-renames 2024/09/25 04:17:38 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Finished making map for --track-renames 2024/09/25 04:17:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:38 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/25 04:17:38 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/09/25 04:17:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for renames to finish 2024/09/25 04:17:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:38 DEBUG : yam: md5 = 93ea518c49b7a7d73e7f9b486b129ee2 OK 2024/09/25 04:17:38 INFO : yam: Copied (new) 2024/09/25 04:17:38 DEBUG : potato: md5 = 544d4abfb255e290f85e20d7241214c5 OK 2024/09/25 04:17:38 INFO : potato: Copied (new) 2024/09/25 04:17:38 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Making map for --track-renames 2024/09/25 04:17:39 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Finished making map for --track-renames 2024/09/25 04:17:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:39 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:39 DEBUG : potato: Unchanged skipping 2024/09/25 04:17:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for renames to finish 2024/09/25 04:17:40 INFO : yam: Copied (server-side copy) to: yaml 2024/09/25 04:17:40 INFO : yam: Deleted 2024/09/25 04:17:40 INFO : yaml: Renamed from "yam" 2024/09/25 04:17:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (4.02s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:1547: Can track renames: true 2024/09/25 04:17:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Making map for --track-renames 2024/09/25 04:17:42 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Finished making map for --track-renames 2024/09/25 04:17:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:42 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/25 04:17:42 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/09/25 04:17:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for renames to finish 2024/09/25 04:17:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:42 DEBUG : potato: md5 = 5a3a591eba2d3109d0194871647db706 OK 2024/09/25 04:17:42 INFO : potato: Copied (new) 2024/09/25 04:17:42 DEBUG : sub/yam: md5 = 7ecc3b424ff1fe1c5d4a7f107bd0a9dc OK 2024/09/25 04:17:42 INFO : sub/yam: Copied (new) 2024/09/25 04:17:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:43 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:43 DEBUG : potato: Unchanged skipping 2024/09/25 04:17:43 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Making map for --track-renames 2024/09/25 04:17:43 INFO : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Finished making map for --track-renames 2024/09/25 04:17:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for renames to finish 2024/09/25 04:17:43 INFO : sub/yam: Copied (server-side copy) to: yam 2024/09/25 04:17:43 INFO : sub/yam: Deleted 2024/09/25 04:17:43 INFO : yam: Renamed from "sub/yam" 2024/09/25 04:17:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.16s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:45 DEBUG : Creating backend with remote "/tmp/rclone322179843/dir1" 2024/09/25 04:17:45 DEBUG : Creating backend with remote "/tmp/rclone322179843/dir2" 2024/09/25 04:17:45 DEBUG : Local file system at /tmp/rclone322179843/dir2: Using server-side directory move 2024/09/25 04:17:45 INFO : Local file system at /tmp/rclone322179843/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/09/25 04:17:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/09/25 04:17:45 DEBUG : Local file system at /tmp/rclone322179843/dir2: Waiting for checks to finish 2024/09/25 04:17:45 DEBUG : Local file system at /tmp/rclone322179843/dir2: Waiting for transfers to finish 2024/09/25 04:17:45 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.20s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:17:45 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/25 04:17:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:45 DEBUG : nested/sub dir/file: md5 = e1fc5b4905f84a80372aa8db5575ad22 OK 2024/09/25 04:17:45 INFO : nested/sub dir/file: Copied (new) 2024/09/25 04:17:45 INFO : nested/sub dir/file: Deleted 2024/09/25 04:17:45 DEBUG : sub dir/hello world: md5 = ae15165ce7d7dcfe954c64fe9628e7e2 OK 2024/09/25 04:17:45 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:17:45 INFO : sub dir/hello world: Deleted 2024/09/25 04:17:45 INFO : sub dir: Removing directory 2024/09/25 04:17:45 INFO : nested/sub dir: Removing directory 2024/09/25 04:17:45 INFO : nested: Removing directory 2024/09/25 04:17:45 DEBUG : Local file system at /tmp/rclone322179843: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (1.64s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/25 04:17:47 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/09/25 04:17:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:47 DEBUG : nested/sub dir/file: md5 = 7ac8f6e2dc766d29da8ae20017ad1635 OK 2024/09/25 04:17:47 INFO : nested/sub dir/file: Copied (new) 2024/09/25 04:17:47 INFO : nested/sub dir/file: Deleted 2024/09/25 04:17:47 DEBUG : sub dir/hello world: md5 = 235eab1f0d3a7145a495d50d18384f3e OK 2024/09/25 04:17:47 INFO : sub dir/hello world: Copied (new) 2024/09/25 04:17:47 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.35s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:48 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/25 04:17:48 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/09/25 04:17:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:48 DEBUG : existing: md5 = 9abd6bc6c2bdc85e27015ab5e10bf4c3 OK 2024/09/25 04:17:48 INFO : existing: Copied (new) 2024/09/25 04:17:48 INFO : existing: Deleted 2024/09/25 04:17:49 DEBUG : existing-b: md5 = 5ce46a7f86ff715219eae71fa2b1f161 OK 2024/09/25 04:17:49 INFO : existing-b: Copied (new) 2024/09/25 04:17:49 INFO : existing-b: Deleted 2024/09/25 04:17:49 DEBUG : existing: Destination exists, skipping 2024/09/25 04:17:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:17:49 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/09/25 04:17:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:17:49 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (2.05s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:17:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zovevol6yago" 2024/09/25 04:17:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zovevol6yago.bin" 2024/09/25 04:17:51 DEBUG : Swift container crypt path rclone-test-zovevol6yago.bin: Auto set use_segments_container to true 2024/09/25 04:17:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zovevol6yago" 2024/09/25 04:17:51 DEBUG : Swift container crypt path rclone-test-zovevol6yago: Auto set use_segments_container to true 2024/09/25 04:17:52 DEBUG : potato2: md5 = 3b84952095a0a1a1c6caf5d095c25a85 OK 2024/09/25 04:17:52 DEBUG : empty space: md5 = 78a0f5c933f649a05e03e64f1a7b06fd OK 2024/09/25 04:17:53 DEBUG : potato3: md5 = c3b9e8701358240dc227a6de58948e38 OK sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi' -> Encrypted drive 'TestCryptSwift:rclone-test-zovevol6yago' 2024/09/25 04:17:54 DEBUG : empty space: md5 = 3df27a081a90e1bbc29420c2dcf285c7 OK 2024/09/25 04:17:54 DEBUG : potato3: md5 = 030a5b955bd8751f4535ddad4f7da19f OK 2024/09/25 04:17:55 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:55 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/25 04:17:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovevol6yago': Waiting for checks to finish 2024/09/25 04:17:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:17:55 DEBUG : empty space: Unchanged skipping 2024/09/25 04:17:55 INFO : empty space: Deleted 2024/09/25 04:17:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovevol6yago': Waiting for transfers to finish 2024/09/25 04:17:55 INFO : potato3: Copied (server-side copy) 2024/09/25 04:17:55 INFO : potato2: Copied (server-side copy) 2024/09/25 04:17:55 INFO : potato2: Deleted 2024/09/25 04:17:55 INFO : potato3: Deleted 2024/09/25 04:17:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fubinew6paqe" 2024/09/25 04:17:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fubinew6paqe.bin" 2024/09/25 04:17:56 DEBUG : Swift container crypt path rclone-test-fubinew6paqe.bin: Auto set use_segments_container to true 2024/09/25 04:17:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fubinew6paqe" 2024/09/25 04:17:57 DEBUG : Swift container crypt path rclone-test-fubinew6paqe: Auto set use_segments_container to true 2024/09/25 04:17:58 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/25 04:17:58 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:17:58 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/25 04:17:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fubinew6paqe': Waiting for checks to finish 2024/09/25 04:17:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fubinew6paqe': Waiting for transfers to finish 2024/09/25 04:17:58 INFO : potato2: Copied (server-side copy) 2024/09/25 04:17:58 INFO : potato2: Deleted 2024/09/25 04:17:58 INFO : potato3: Copied (server-side copy) 2024/09/25 04:17:59 INFO : potato3: Deleted 2024/09/25 04:17:59 INFO : empty space: Copied (server-side copy) 2024/09/25 04:17:59 INFO : empty space: Deleted 2024/09/25 04:17:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fubinew6paqe': Purge remote 2024/09/25 04:17:59 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:00 INFO : potato2.bin: Deleted 2024/09/25 04:18:00 INFO : potato3.bin: Deleted 2024/09/25 04:18:00 INFO : empty space.bin: Deleted 2024/09/25 04:18:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zovevol6yago': Purge remote 2024/09/25 04:18:00 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (10.29s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wiqubum8yoku" 2024/09/25 04:18:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wiqubum8yoku.bin" 2024/09/25 04:18:01 DEBUG : Swift container crypt path rclone-test-wiqubum8yoku.bin: Auto set use_segments_container to true 2024/09/25 04:18:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wiqubum8yoku" 2024/09/25 04:18:02 DEBUG : Swift container crypt path rclone-test-wiqubum8yoku: Auto set use_segments_container to true 2024/09/25 04:18:02 DEBUG : potato2: md5 = 52447ff6eadea01834db0fe452bd15b1 OK 2024/09/25 04:18:02 DEBUG : empty space: md5 = 7d42110289effe19ab0aed544716113e OK 2024/09/25 04:18:03 DEBUG : potato3: md5 = 5b359049cb7030d85e8ad382212d6520 OK sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi' -> Encrypted drive 'TestCryptSwift:rclone-test-wiqubum8yoku' 2024/09/25 04:18:05 DEBUG : empty space: md5 = ede9557e38fb8236340b5326f0f4fc57 OK 2024/09/25 04:18:05 DEBUG : potato3: md5 = ec1e5449305914809301549792789418 OK 2024/09/25 04:18:06 DEBUG : empty space: Excluded (Size Filter) 2024/09/25 04:18:06 DEBUG : empty space: Excluded 2024/09/25 04:18:06 DEBUG : empty space: Excluded (Size Filter) 2024/09/25 04:18:06 DEBUG : empty space: Excluded 2024/09/25 04:18:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:18:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wiqubum8yoku': Waiting for checks to finish 2024/09/25 04:18:06 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/25 04:18:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wiqubum8yoku': Waiting for transfers to finish 2024/09/25 04:18:06 INFO : potato3: Copied (server-side copy) 2024/09/25 04:18:06 INFO : potato2: Copied (server-side copy) 2024/09/25 04:18:06 INFO : potato2: Deleted 2024/09/25 04:18:06 INFO : potato3: Deleted 2024/09/25 04:18:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-noxuval5pogi" 2024/09/25 04:18:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noxuval5pogi.bin" 2024/09/25 04:18:07 DEBUG : Swift container crypt path rclone-test-noxuval5pogi.bin: Auto set use_segments_container to true 2024/09/25 04:18:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noxuval5pogi" 2024/09/25 04:18:08 DEBUG : Swift container crypt path rclone-test-noxuval5pogi: Auto set use_segments_container to true 2024/09/25 04:18:08 DEBUG : empty space: Excluded (Size Filter) 2024/09/25 04:18:08 DEBUG : empty space: Excluded 2024/09/25 04:18:08 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:18:08 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/25 04:18:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxuval5pogi': Waiting for checks to finish 2024/09/25 04:18:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxuval5pogi': Waiting for transfers to finish 2024/09/25 04:18:09 INFO : potato2: Copied (server-side copy) 2024/09/25 04:18:09 INFO : potato3: Copied (server-side copy) 2024/09/25 04:18:09 INFO : potato2: Deleted 2024/09/25 04:18:09 INFO : potato3: Deleted 2024/09/25 04:18:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxuval5pogi': Purge remote 2024/09/25 04:18:10 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:10 INFO : potato2.bin: Deleted 2024/09/25 04:18:10 INFO : potato3.bin: Deleted 2024/09/25 04:18:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wiqubum8yoku': Purge remote 2024/09/25 04:18:10 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:10 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (10.61s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fekobir5xowi" 2024/09/25 04:18:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fekobir5xowi.bin" 2024/09/25 04:18:11 DEBUG : Swift container crypt path rclone-test-fekobir5xowi.bin: Auto set use_segments_container to true 2024/09/25 04:18:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fekobir5xowi" 2024/09/25 04:18:12 DEBUG : Swift container crypt path rclone-test-fekobir5xowi: Auto set use_segments_container to true 2024/09/25 04:18:13 DEBUG : potato2: md5 = bdfc4b6874b33919c00ecf96083b50d3 OK 2024/09/25 04:18:13 DEBUG : empty space: md5 = 0f5bcfa09e5d2d0d68fcf3766a3092a7 OK 2024/09/25 04:18:13 DEBUG : potato3: md5 = c8c9c47a89007817cf41e52ae8e7a5e1 OK 2024/09/25 04:18:13 DEBUG : tomatoDir: Making directory sync_test.go:1603: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi' -> Encrypted drive 'TestCryptSwift:rclone-test-fekobir5xowi' 2024/09/25 04:18:14 DEBUG : empty space: md5 = 43a309987e90a81d77f7750f201de014 OK 2024/09/25 04:18:15 DEBUG : potato3: md5 = 64d00d13215dd292837d8e0e2a4d5c68 OK 2024/09/25 04:18:15 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:18:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fekobir5xowi': Waiting for checks to finish 2024/09/25 04:18:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/09/25 04:18:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:15 DEBUG : empty space: Unchanged skipping 2024/09/25 04:18:15 INFO : empty space: Deleted 2024/09/25 04:18:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fekobir5xowi': Waiting for transfers to finish 2024/09/25 04:18:15 INFO : potato3: Copied (server-side copy) 2024/09/25 04:18:15 INFO : potato3: Deleted 2024/09/25 04:18:16 INFO : potato2: Copied (server-side copy) 2024/09/25 04:18:16 INFO : potato2: Deleted 2024/09/25 04:18:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-homubir7dujo" 2024/09/25 04:18:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-homubir7dujo.bin" 2024/09/25 04:18:16 DEBUG : Swift container crypt path rclone-test-homubir7dujo.bin: Auto set use_segments_container to true 2024/09/25 04:18:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-homubir7dujo" 2024/09/25 04:18:17 DEBUG : Swift container crypt path rclone-test-homubir7dujo: Auto set use_segments_container to true 2024/09/25 04:18:17 DEBUG : tomatoDir: Making directory 2024/09/25 04:18:18 DEBUG : empty space: Need to transfer - File not found at Destination 2024/09/25 04:18:18 DEBUG : potato2: Need to transfer - File not found at Destination 2024/09/25 04:18:18 DEBUG : potato3: Need to transfer - File not found at Destination 2024/09/25 04:18:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homubir7dujo': Waiting for checks to finish 2024/09/25 04:18:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homubir7dujo': Waiting for transfers to finish 2024/09/25 04:18:18 INFO : potato2: Copied (server-side copy) 2024/09/25 04:18:18 INFO : potato2: Deleted 2024/09/25 04:18:18 INFO : empty space: Copied (server-side copy) 2024/09/25 04:18:19 INFO : empty space: Deleted 2024/09/25 04:18:19 INFO : potato3: Copied (server-side copy) 2024/09/25 04:18:19 INFO : potato3: Deleted 2024/09/25 04:18:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homubir7dujo': Purge remote 2024/09/25 04:18:19 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:20 INFO : potato2.bin: Deleted 2024/09/25 04:18:20 INFO : potato3.bin: Deleted 2024/09/25 04:18:20 INFO : empty space.bin: Deleted 2024/09/25 04:18:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fekobir5xowi': Purge remote 2024/09/25 04:18:20 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.57s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/rclone-move-test" 2024/09/25 04:18:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-move-test.bin" 2024/09/25 04:18:21 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-move-test.bin: Auto set use_segments_container to true 2024/09/25 04:18:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-move-test" 2024/09/25 04:18:22 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-move-test: Auto set use_segments_container to true 2024/09/25 04:18:22 DEBUG : potato2: md5 = c39be48ada6c30e51abe73cfca9872a1 OK --- PASS: TestServerSideMoveOverlap (2.24s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test" 2024/09/25 04:18:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test.bin" 2024/09/25 04:18:23 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test.bin: Auto set use_segments_container to true 2024/09/25 04:18:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test" 2024/09/25 04:18:24 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncOverlap (2.17s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test" 2024/09/25 04:18:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test.bin" 2024/09/25 04:18:25 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test.bin: Auto set use_segments_container to true 2024/09/25 04:18:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test" 2024/09/25 04:18:26 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test: Auto set use_segments_container to true 2024/09/25 04:18:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-include/layer2" 2024/09/25 04:18:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test-include/layer2.bin" 2024/09/25 04:18:27 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2024/09/25 04:18:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test-include/layer2" 2024/09/25 04:18:27 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2024/09/25 04:18:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-ignore-file" 2024/09/25 04:18:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test-ignore-file.bin" 2024/09/25 04:18:28 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2024/09/25 04:18:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/rclone-sync-test-ignore-file" 2024/09/25 04:18:28 DEBUG : Swift container crypt path rclone-test-horucec7dixi/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2024/09/25 04:18:30 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 601608d5ee8c83c7f10e0ae88e8845d2 OK 2024/09/25 04:18:30 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/25 04:18:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test': Waiting for checks to finish 2024/09/25 04:18:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test': Waiting for transfers to finish 2024/09/25 04:18:30 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:30 INFO : There was nothing to transfer 2024/09/25 04:18:30 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/25 04:18:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:18:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:18:30 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:30 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:30 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/09/25 04:18:31 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:31 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:31 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:18:31 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:31 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:31 DEBUG : : Excluded 2024/09/25 04:18:31 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/09/25 04:18:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/09/25 04:18:31 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:31 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (9.37s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:18:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:18:35 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:18:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:18:36 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:18:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/CompareDest" 2024/09/25 04:18:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/CompareDest.bin" 2024/09/25 04:18:36 DEBUG : Swift container crypt path rclone-test-horucec7dixi/CompareDest.bin: Auto set use_segments_container to true 2024/09/25 04:18:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/CompareDest" 2024/09/25 04:18:37 DEBUG : Swift container crypt path rclone-test-horucec7dixi/CompareDest: Auto set use_segments_container to true 2024/09/25 04:18:37 DEBUG : one: Need to transfer - File not found at Destination 2024/09/25 04:18:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:38 DEBUG : one: md5 = 98e9bddea3adb6b5d705486d74076953 OK 2024/09/25 04:18:38 INFO : one: Copied (new) 2024/09/25 04:18:38 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:39 DEBUG : one: md5 = d18cdce9bd066af591515b2772b56e60 OK 2024/09/25 04:18:39 INFO : one: Copied (replaced existing) 2024/09/25 04:18:39 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:39 DEBUG : dst/one: md5 = 7f0746f9b826dad85996968937c953d1 OK 2024/09/25 04:18:40 DEBUG : CompareDest/one: md5 = f2424a5ac57ca79b97922f9e7b2e5167 OK 2024/09/25 04:18:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:40 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/25 04:18:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:40 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:40 INFO : There was nothing to transfer 2024/09/25 04:18:41 DEBUG : CompareDest/two: md5 = dbda83032599939450c843642abcc7e5 OK 2024/09/25 04:18:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:42 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/25 04:18:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:42 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/25 04:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:42 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:42 INFO : There was nothing to transfer 2024/09/25 04:18:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:42 DEBUG : two: Destination found in --compare-dest, skipping 2024/09/25 04:18:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:42 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/25 04:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:42 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:42 INFO : There was nothing to transfer sync_test.go:2073: No hash on uploaded file so skipping compare timestamp test 2024/09/25 04:18:42 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:42 DEBUG : two: Need to transfer - File not found at Destination 2024/09/25 04:18:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:43 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:43 DEBUG : one: Destination found in --compare-dest, skipping 2024/09/25 04:18:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:43 DEBUG : two: md5 = e5d6ab2528d86a94ed5d9635f6bfa6c5 OK 2024/09/25 04:18:43 INFO : two: Copied (new) 2024/09/25 04:18:43 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (10.21s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:45 DEBUG : pre-dest1/1: md5 = c21d3fc97b5587bc5379c3570a225805 OK 2024/09/25 04:18:45 DEBUG : pre-dest2/2: md5 = 65dbf26bc1825c435c302eb7d60b1632 OK 2024/09/25 04:18:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dest" 2024/09/25 04:18:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dest.bin" 2024/09/25 04:18:46 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dest.bin: Auto set use_segments_container to true 2024/09/25 04:18:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dest" 2024/09/25 04:18:47 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dest: Auto set use_segments_container to true 2024/09/25 04:18:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/pre-dest1" 2024/09/25 04:18:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/pre-dest1.bin" 2024/09/25 04:18:47 DEBUG : Swift container crypt path rclone-test-horucec7dixi/pre-dest1.bin: Auto set use_segments_container to true 2024/09/25 04:18:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/pre-dest1" 2024/09/25 04:18:48 DEBUG : Swift container crypt path rclone-test-horucec7dixi/pre-dest1: Auto set use_segments_container to true 2024/09/25 04:18:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/pre-dest2" 2024/09/25 04:18:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/pre-dest2.bin" 2024/09/25 04:18:49 DEBUG : Swift container crypt path rclone-test-horucec7dixi/pre-dest2.bin: Auto set use_segments_container to true 2024/09/25 04:18:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/pre-dest2" 2024/09/25 04:18:49 DEBUG : Swift container crypt path rclone-test-horucec7dixi/pre-dest2: Auto set use_segments_container to true 2024/09/25 04:18:50 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:50 DEBUG : 1: Destination found in --compare-dest, skipping 2024/09/25 04:18:50 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:18:50 DEBUG : 2: Destination found in --compare-dest, skipping 2024/09/25 04:18:51 DEBUG : 3: Need to transfer - File not found at Destination 2024/09/25 04:18:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dest': Waiting for checks to finish 2024/09/25 04:18:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dest': Waiting for transfers to finish 2024/09/25 04:18:52 DEBUG : 3: md5 = 413e139c98453176ca31d2fa078b7374 OK 2024/09/25 04:18:52 INFO : 3: Copied (new) 2024/09/25 04:18:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (8.36s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:18:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:18:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:18:53 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:18:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:18:54 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:18:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/CopyDest" 2024/09/25 04:18:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/CopyDest.bin" 2024/09/25 04:18:54 DEBUG : Swift container crypt path rclone-test-horucec7dixi/CopyDest.bin: Auto set use_segments_container to true 2024/09/25 04:18:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/CopyDest" 2024/09/25 04:18:55 DEBUG : Swift container crypt path rclone-test-horucec7dixi/CopyDest: Auto set use_segments_container to true 2024/09/25 04:18:56 DEBUG : one: Need to transfer - File not found at Destination 2024/09/25 04:18:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:56 DEBUG : one: md5 = 5d3bc78c73a77139021739f40c477d6b OK 2024/09/25 04:18:56 INFO : one: Copied (new) 2024/09/25 04:18:56 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:57 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:18:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:18:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:18:57 DEBUG : one: md5 = c6b44e36b68fd2ccd91e23d15bb0034c OK 2024/09/25 04:18:57 INFO : one: Copied (replaced existing) 2024/09/25 04:18:57 DEBUG : Waiting for deletions to finish 2024/09/25 04:18:57 DEBUG : dst/one: md5 = a7d900afb19c1259bf731175cdf4f242 OK 2024/09/25 04:18:58 DEBUG : CopyDest/one: md5 = ca9fafb64756fd713e7e43aa6e4d1d1e OK 2024/09/25 04:18:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/BackupDir" 2024/09/25 04:18:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/BackupDir.bin" 2024/09/25 04:18:59 DEBUG : Swift container crypt path rclone-test-horucec7dixi/BackupDir.bin: Auto set use_segments_container to true 2024/09/25 04:18:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/BackupDir" 2024/09/25 04:19:00 DEBUG : Swift container crypt path rclone-test-horucec7dixi/BackupDir: Auto set use_segments_container to true 2024/09/25 04:19:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:19:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/09/25 04:19:01 INFO : one: Copied (server-side copy) 2024/09/25 04:19:01 INFO : one: Deleted 2024/09/25 04:19:02 INFO : one: Copied (server-side copy) 2024/09/25 04:19:02 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/09/25 04:19:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:02 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:02 DEBUG : CopyDest/two: md5 = c67bb8613aaaaa6a6042641475395f02 OK 2024/09/25 04:19:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:03 INFO : two: Copied (server-side copy) 2024/09/25 04:19:03 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/09/25 04:19:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:03 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:03 DEBUG : one: Unchanged skipping 2024/09/25 04:19:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:03 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:04 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:04 DEBUG : one: Unchanged skipping 2024/09/25 04:19:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:04 DEBUG : two: Unchanged skipping 2024/09/25 04:19:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:04 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:04 INFO : There was nothing to transfer 2024/09/25 04:19:04 DEBUG : CopyDest/three: md5 = 2946b9075137d3c35157f9fe26c9f12f OK 2024/09/25 04:19:05 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/09/25 04:19:05 DEBUG : three: Destination not found in --copy-dest 2024/09/25 04:19:05 DEBUG : three: Need to transfer - File not found at Destination 2024/09/25 04:19:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:05 DEBUG : one: Unchanged skipping 2024/09/25 04:19:05 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:05 DEBUG : two: Unchanged skipping 2024/09/25 04:19:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:06 DEBUG : three: md5 = 0b64cb3507af16972033a1e5b95e1de6 OK 2024/09/25 04:19:06 INFO : three: Copied (new) 2024/09/25 04:19:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (19.28s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:12 DEBUG : dst/one: md5 = 87f3959e680d173e231d76f7adec759a OK 2024/09/25 04:19:13 DEBUG : dst/two: md5 = 546d4cc9c392c7ffb4b45ef5d79b4716 OK 2024/09/25 04:19:13 DEBUG : dst/three.txt: md5 = d9b8b06ce404f30643c84a8e173875de OK 2024/09/25 04:19:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:14 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:14 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/backup" 2024/09/25 04:19:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup.bin" 2024/09/25 04:19:15 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup.bin: Auto set use_segments_container to true 2024/09/25 04:19:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup" 2024/09/25 04:19:15 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup: Auto set use_segments_container to true 2024/09/25 04:19:16 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:16 DEBUG : two: Unchanged skipping 2024/09/25 04:19:16 INFO : one: Copied (server-side copy) 2024/09/25 04:19:16 INFO : one: Deleted 2024/09/25 04:19:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:17 DEBUG : one: md5 = e8e99f33f0f1baa559dae9019c54991a OK 2024/09/25 04:19:17 INFO : one: Copied (new) 2024/09/25 04:19:17 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:17 INFO : three.txt: Copied (server-side copy) 2024/09/25 04:19:18 INFO : three.txt: Deleted 2024/09/25 04:19:18 INFO : three.txt: Moved into backup dir 2024/09/25 04:19:18 DEBUG : dst/three.txt: md5 = c785fdb30996818ff7cec1f5fa1bf0ee OK 2024/09/25 04:19:19 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:19 DEBUG : two: Unchanged skipping 2024/09/25 04:19:19 INFO : one: Copied (server-side copy) 2024/09/25 04:19:19 INFO : one: Deleted 2024/09/25 04:19:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:19 DEBUG : one: md5 = 0d0ae1b19dffe13583ecd9526524bc3a OK 2024/09/25 04:19:19 INFO : one: Copied (new) 2024/09/25 04:19:19 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:20 INFO : three.txt: Copied (server-side copy) 2024/09/25 04:19:20 INFO : three.txt: Deleted 2024/09/25 04:19:20 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (9.15s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:22 DEBUG : dst/one: md5 = 12a4cf486cd86f54235d48bfa6b12d6a OK 2024/09/25 04:19:22 DEBUG : dst/two: md5 = 868e24f119e0f15054ae76f696e88f61 OK 2024/09/25 04:19:22 DEBUG : dst/three.txt: md5 = c85c6286652e8cadd29764f1bfb0b68e OK 2024/09/25 04:19:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:23 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:23 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/backup" 2024/09/25 04:19:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup.bin" 2024/09/25 04:19:24 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup.bin: Auto set use_segments_container to true 2024/09/25 04:19:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup" 2024/09/25 04:19:24 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup: Auto set use_segments_container to true 2024/09/25 04:19:25 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:25 DEBUG : two: Unchanged skipping 2024/09/25 04:19:25 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:25 INFO : one: Deleted 2024/09/25 04:19:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:26 DEBUG : one: md5 = 3b330b8b58b1e488f3e8e980c3ef69cb OK 2024/09/25 04:19:26 INFO : one: Copied (new) 2024/09/25 04:19:26 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:27 INFO : three.txt: Deleted 2024/09/25 04:19:27 INFO : three.txt: Moved into backup dir 2024/09/25 04:19:27 DEBUG : dst/three.txt: md5 = be64c8847b47b4dcbb4e7c2bc6400d7d OK 2024/09/25 04:19:28 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:28 DEBUG : two: Unchanged skipping 2024/09/25 04:19:28 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:28 INFO : one: Deleted 2024/09/25 04:19:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:28 DEBUG : one: md5 = c6e3841412060cdf0bfe8b423cb30a52 OK 2024/09/25 04:19:28 INFO : one: Copied (new) 2024/09/25 04:19:28 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:29 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:29 INFO : three.txt: Deleted 2024/09/25 04:19:29 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (8.68s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:30 DEBUG : dst/one: md5 = 41979977d12949e6a594b718ef06a84f OK 2024/09/25 04:19:30 DEBUG : dst/two: md5 = 1d4db4da3a9506a9fc36154e6128dc31 OK 2024/09/25 04:19:31 DEBUG : dst/three.txt: md5 = f8458982c51ca829a6b1c89e08799a5a OK 2024/09/25 04:19:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:31 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:32 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/backup" 2024/09/25 04:19:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup.bin" 2024/09/25 04:19:32 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup.bin: Auto set use_segments_container to true 2024/09/25 04:19:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/backup" 2024/09/25 04:19:33 DEBUG : Swift container crypt path rclone-test-horucec7dixi/backup: Auto set use_segments_container to true 2024/09/25 04:19:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:33 DEBUG : two: Unchanged skipping 2024/09/25 04:19:35 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/25 04:19:35 INFO : one: Deleted 2024/09/25 04:19:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:35 DEBUG : one: md5 = 8fd3e517ff698a6d42c5eea17f8706a3 OK 2024/09/25 04:19:35 INFO : one: Copied (new) 2024/09/25 04:19:35 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:35 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/25 04:19:35 INFO : three.txt: Deleted 2024/09/25 04:19:35 INFO : three.txt: Moved into backup dir 2024/09/25 04:19:36 DEBUG : dst/three.txt: md5 = 7a9aef8c785824e631b4fdfe1cdd33a9 OK 2024/09/25 04:19:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:37 DEBUG : two: Unchanged skipping 2024/09/25 04:19:37 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/25 04:19:37 INFO : one: Deleted 2024/09/25 04:19:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:37 DEBUG : one: md5 = c55ab08a370622c19bd2387051602322 OK 2024/09/25 04:19:37 INFO : one: Copied (new) 2024/09/25 04:19:37 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:38 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/25 04:19:38 INFO : three.txt: Deleted 2024/09/25 04:19:38 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (9.13s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:39 DEBUG : dst/one: md5 = ced37e98b1dac7b69de59861a2aaf05a OK 2024/09/25 04:19:40 DEBUG : dst/two: md5 = 4f4dcd7b58bb9b40fa265775a97eea64 OK 2024/09/25 04:19:40 DEBUG : dst/three.txt: md5 = 37fc483cb18fd6d4d5f574c048d0a20e OK 2024/09/25 04:19:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:40 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:41 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:41 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:42 DEBUG : two: Unchanged skipping 2024/09/25 04:19:42 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:42 INFO : one: Deleted 2024/09/25 04:19:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:42 DEBUG : one: md5 = 012e23e353c493c5854358db97866723 OK 2024/09/25 04:19:42 INFO : one: Copied (new) 2024/09/25 04:19:42 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:43 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:43 INFO : three.txt: Deleted 2024/09/25 04:19:43 INFO : three.txt: Moved into backup dir 2024/09/25 04:19:44 DEBUG : dst/three.txt: md5 = 288015aa485f4a7d37c7c0d3b5b255a8 OK 2024/09/25 04:19:44 DEBUG : one.bak: Excluded (Path Filter) 2024/09/25 04:19:44 DEBUG : one.bak: Excluded 2024/09/25 04:19:44 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/09/25 04:19:44 DEBUG : three.txt.bak: Excluded 2024/09/25 04:19:44 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for checks to finish 2024/09/25 04:19:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:44 DEBUG : two: Unchanged skipping 2024/09/25 04:19:45 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:45 INFO : one: Deleted 2024/09/25 04:19:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi/dst': Waiting for transfers to finish 2024/09/25 04:19:45 DEBUG : one: md5 = f0e3c42dfd087cf1e39aec04a46b0f7b OK 2024/09/25 04:19:45 INFO : one: Copied (new) 2024/09/25 04:19:45 DEBUG : Waiting for deletions to finish 2024/09/25 04:19:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:45 INFO : three.txt: Deleted 2024/09/25 04:19:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (7.46s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:47 DEBUG : dst/one: md5 = ff4c3912ec0c9c8c2a391728652c01d5 OK 2024/09/25 04:19:47 DEBUG : dst/two: md5 = b89f643e68bd402405e0ad2535c6d4ce OK 2024/09/25 04:19:47 DEBUG : dst/three.txt: md5 = f092e190228704ec0dca0d1505c06bb5 OK 2024/09/25 04:19:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:48 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:48 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:49 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:49 INFO : one: Deleted 2024/09/25 04:19:49 DEBUG : one: md5 = 60026374b6ae3cab4cdf01ed07489e85 OK 2024/09/25 04:19:49 INFO : one: Copied (new) 2024/09/25 04:19:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:49 DEBUG : two: Unchanged skipping 2024/09/25 04:19:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/25 04:19:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:50 INFO : three.txt: Deleted 2024/09/25 04:19:50 DEBUG : three.txt: md5 = 954132bec717454bb9c770c6a3051b72 OK 2024/09/25 04:19:50 INFO : three.txt: Copied (new) 2024/09/25 04:19:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:51 INFO : one: Copied (server-side copy) to: one.bak 2024/09/25 04:19:51 INFO : one: Deleted 2024/09/25 04:19:51 DEBUG : one: md5 = 469dfda539da6c91150acfbca637d592 OK 2024/09/25 04:19:51 INFO : one: Copied (new) 2024/09/25 04:19:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:51 DEBUG : two: Unchanged skipping 2024/09/25 04:19:51 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/25 04:19:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/09/25 04:19:52 INFO : three.txt: Deleted 2024/09/25 04:19:52 DEBUG : three.txt: md5 = ab0b679cf0b66003587cd3822e3a040d OK 2024/09/25 04:19:52 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (6.50s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:19:53 DEBUG : dst/one: md5 = 5276bfc8d992e4740f10a35ada6f2656 OK 2024/09/25 04:19:54 DEBUG : dst/two: md5 = 48cd72a9f1c2a574c7a6042e257912da OK 2024/09/25 04:19:54 DEBUG : dst/three.txt: md5 = c607ddace24995791b3937618c34a136 OK 2024/09/25 04:19:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-horucec7dixi/dst" 2024/09/25 04:19:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst.bin" 2024/09/25 04:19:54 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst.bin: Auto set use_segments_container to true 2024/09/25 04:19:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-horucec7dixi/dst" 2024/09/25 04:19:55 DEBUG : Swift container crypt path rclone-test-horucec7dixi/dst: Auto set use_segments_container to true 2024/09/25 04:19:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/09/25 04:19:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/25 04:19:56 INFO : one: Deleted 2024/09/25 04:19:56 DEBUG : one: md5 = de5bdcc036a92092f093adf7bb54cf91 OK 2024/09/25 04:19:56 INFO : one: Copied (new) 2024/09/25 04:19:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:56 DEBUG : two: Unchanged skipping 2024/09/25 04:19:56 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/09/25 04:19:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/25 04:19:57 INFO : three.txt: Deleted 2024/09/25 04:19:57 DEBUG : three.txt: md5 = b8c1989205e94e3f8c8122f6d31d2a80 OK 2024/09/25 04:19:57 INFO : three.txt: Copied (new) 2024/09/25 04:19:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/09/25 04:19:58 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/09/25 04:19:58 INFO : one: Deleted 2024/09/25 04:19:59 DEBUG : one: md5 = a8af584cffc87126c34a9baf3a5bb5e5 OK 2024/09/25 04:19:59 INFO : one: Copied (new) 2024/09/25 04:19:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:19:59 DEBUG : two: Unchanged skipping 2024/09/25 04:19:59 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/09/25 04:19:59 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/09/25 04:19:59 INFO : three.txt: Deleted 2024/09/25 04:19:59 DEBUG : three.txt: md5 = a417ffc23057a4c609d5767553312b72 OK 2024/09/25 04:19:59 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (8.00s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:02 DEBUG : Testêé: md5 = 0465b86dc50ed406b64ed80913640eca OK 2024/09/25 04:20:02 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/09/25 04:20:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:02 DEBUG : Testêé: md5 = 3872b47708de40a3b940a3a075d590d1 OK 2024/09/25 04:20:02 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/09/25 04:20:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.94s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:03 DEBUG : existing: Need to transfer - File not found at Destination 2024/09/25 04:20:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:04 DEBUG : existing: md5 = 877bdde3fb832d9500c5c7b3ce8c16e8 OK 2024/09/25 04:20:04 INFO : existing: Copied (new) 2024/09/25 04:20:04 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:04 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/09/25 04:20:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:04 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/09/25 04:20:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': not deleting files as there were IO errors 2024/09/25 04:20:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncImmutable (1.96s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:05 DEBUG : EXISTING: md5 = 17172fac49c8f5e488fd99ae56507d78 OK 2024/09/25 04:20:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:06 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:06 DEBUG : existing: Unchanged skipping 2024/09/25 04:20:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:06 DEBUG : Waiting for deletions to finish 2024/09/25 04:20:06 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.31s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:2529: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.22s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" sync_test.go:2568: This test only runs on local --- PASS: TestMaxTransfer (0.66s) --- SKIP: TestMaxTransfer/Hard (0.09s) --- SKIP: TestMaxTransfer/Soft (0.11s) --- SKIP: TestMaxTransfer/Cautious (0.45s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:08 DEBUG : both0: md5 = 236f9df6e4f730a7b77736c3e411310f OK 2024/09/25 04:20:08 DEBUG : only0: md5 = 54fb175ec18bd595b777fab0011f106a OK 2024/09/25 04:20:09 DEBUG : both1: md5 = 5d10208f1a4344143c92dfc16ee2de80 OK 2024/09/25 04:20:09 DEBUG : only1: md5 = 3036189cd55e82c4dbe7b0870222c7fd OK 2024/09/25 04:20:10 DEBUG : both2: md5 = 9fbe7caed6ebe3d23014ff6131d01d86 OK 2024/09/25 04:20:10 DEBUG : only2: md5 = ce146f4259de59064158367d64460b94 OK 2024/09/25 04:20:11 DEBUG : both3: md5 = f99d2c822ae0a43903d50c853820c5cb OK 2024/09/25 04:20:11 DEBUG : only3: md5 = d7fe96b5af5d00e641f8dc511d03550d OK 2024/09/25 04:20:11 DEBUG : both4: md5 = abfd1aa50707cf7351caeaca55901c17 OK 2024/09/25 04:20:12 DEBUG : only4: md5 = 9e695774c7c6b67fc73af75eba11efb4 OK 2024/09/25 04:20:13 DEBUG : both5: md5 = a15c6d3a2e824cd7dc91acc44b1e0dd3 OK 2024/09/25 04:20:13 DEBUG : only5: md5 = f0c171e18fcb2dc929514cff5de52837 OK 2024/09/25 04:20:14 DEBUG : both6: md5 = d0e425b9801bf0cbda2fa5c726f5ad4e OK 2024/09/25 04:20:14 DEBUG : only6: md5 = 6951a3e40a35fe6ce538d5b111ff2279 OK 2024/09/25 04:20:15 DEBUG : both7: md5 = fe83c252928918612867f1210eceac30 OK 2024/09/25 04:20:15 DEBUG : only7: md5 = cf89bbeb1df20a4796217d9a6307e9f8 OK 2024/09/25 04:20:15 DEBUG : both8: md5 = e2a9eaf036b04de6fdd0c5e1e3b8c4cd OK 2024/09/25 04:20:16 DEBUG : only8: md5 = 6e27348cc67b6cba5fff2e9f8be1b98e OK 2024/09/25 04:20:16 DEBUG : both9: md5 = c44919567f982ad7a98e87c920f6780e OK 2024/09/25 04:20:17 DEBUG : only9: md5 = b4c2782ff1833ccd9be609b01de1bf9e OK 2024/09/25 04:20:18 DEBUG : both10: md5 = 95048972a6afeeb431a75b8129b90a6b OK 2024/09/25 04:20:18 DEBUG : only10: md5 = a92626c91c5dbafe08cb4045aeb9a7d9 OK 2024/09/25 04:20:19 DEBUG : both11: md5 = 52f61a1d0a326546b9404374cd290426 OK 2024/09/25 04:20:19 DEBUG : only11: md5 = e5ef2142f166b0fe9e88957ccd7f1ed5 OK 2024/09/25 04:20:19 DEBUG : both12: md5 = 50dc8848dd347bb60e6d8ec8db00305c OK 2024/09/25 04:20:20 DEBUG : only12: md5 = ce1fbced643d48bd8c53cd9a54dc0b8b OK 2024/09/25 04:20:21 DEBUG : both13: md5 = 9c03661fe1237e89515f0434b228a34b OK 2024/09/25 04:20:22 DEBUG : only13: md5 = c7a87bbd9f99b464a6adef32f5684a32 OK 2024/09/25 04:20:22 DEBUG : both14: md5 = 4456b24789e5e8527d5d2ed9c3752667 OK 2024/09/25 04:20:23 DEBUG : only14: md5 = 8019c951ced965a25b64699b8d32cb85 OK 2024/09/25 04:20:23 DEBUG : both15: md5 = bedc37c503afa466999f2720a219f6a3 OK 2024/09/25 04:20:24 DEBUG : only15: md5 = dec472dafce46a414ca117681a4c9ef3 OK 2024/09/25 04:20:24 DEBUG : both16: md5 = 9da70f70e4cf16d576b90ff6fb035e88 OK 2024/09/25 04:20:24 DEBUG : only16: md5 = 3d0bef83cd9e4f0ed78bf91da0b68361 OK 2024/09/25 04:20:25 DEBUG : both17: md5 = b4334821f6feb601483e9c8bf4870cad OK 2024/09/25 04:20:25 DEBUG : only17: md5 = 8d41b212da17a8117efed4a6d1fa75b8 OK 2024/09/25 04:20:26 DEBUG : both18: md5 = a4ac59c5dcc08baad2a8c3b0823d9066 OK 2024/09/25 04:20:26 DEBUG : only18: md5 = 9d01c65acf4e03efc20f177420735b94 OK 2024/09/25 04:20:27 DEBUG : both19: md5 = 3742eee4fa3cda03e397860f78425514 OK 2024/09/25 04:20:27 DEBUG : only19: md5 = eeae5f0e4929247e5b50087e4101eb69 OK 2024/09/25 04:20:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:29 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both0: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both1: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both10: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both11: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both13: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both12: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both14: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both15: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both16: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both17: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both18: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both19: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both2: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both3: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both4: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both5: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both6: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both7: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both8: Unchanged skipping 2024/09/25 04:20:29 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:29 DEBUG : both9: Unchanged skipping 2024/09/25 04:20:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:29 DEBUG : Waiting for deletions to finish 2024/09/25 04:20:29 INFO : only3: Deleted 2024/09/25 04:20:30 INFO : only0: Deleted 2024/09/25 04:20:30 INFO : only9: Deleted 2024/09/25 04:20:30 INFO : only10: Deleted 2024/09/25 04:20:30 INFO : only6: Deleted 2024/09/25 04:20:30 INFO : only17: Deleted 2024/09/25 04:20:30 INFO : only4: Deleted 2024/09/25 04:20:30 INFO : only1: Deleted 2024/09/25 04:20:30 INFO : only13: Deleted 2024/09/25 04:20:30 INFO : only14: Deleted 2024/09/25 04:20:30 INFO : only15: Deleted 2024/09/25 04:20:31 INFO : only16: Deleted 2024/09/25 04:20:31 INFO : only18: Deleted 2024/09/25 04:20:31 INFO : only2: Deleted 2024/09/25 04:20:31 INFO : only5: Deleted 2024/09/25 04:20:31 INFO : only11: Deleted 2024/09/25 04:20:31 INFO : only19: Deleted 2024/09/25 04:20:31 INFO : only8: Deleted 2024/09/25 04:20:31 INFO : only7: Deleted 2024/09/25 04:20:32 INFO : only12: Deleted 2024/09/25 04:20:32 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (31.41s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:20:39 DEBUG : both0: md5 = d00dc0f47341b1103d3fa46908052841 OK 2024/09/25 04:20:39 DEBUG : only0: md5 = afd269e474cd0e6533c53f90050ec166 OK 2024/09/25 04:20:39 DEBUG : both1: md5 = be1341f0bebf6844b588d97a9bc1d478 OK 2024/09/25 04:20:40 DEBUG : only1: md5 = 33155234e68e8c2ac3bb88f1967b405f OK 2024/09/25 04:20:40 DEBUG : both2: md5 = e52a2da05e0a69f4bc880cfd011d912c OK 2024/09/25 04:20:41 DEBUG : only2: md5 = e96a0b7d0102a227eb0064f980e863a9 OK 2024/09/25 04:20:41 DEBUG : both3: md5 = 72102023dfec428fe378841fda518787 OK 2024/09/25 04:20:41 DEBUG : only3: md5 = 2351117f9935f34de5764cf0b987d801 OK 2024/09/25 04:20:41 DEBUG : both4: md5 = 9ce265c08be1be39f5be169892150701 OK 2024/09/25 04:20:41 DEBUG : only4: md5 = be35db4f2e57b9a2bf98258ad4cf2353 OK 2024/09/25 04:20:42 DEBUG : both5: md5 = bb643a47787afeafd0332250cb2a5c6e OK 2024/09/25 04:20:42 DEBUG : only5: md5 = e62a546e42655425403120c479d78da6 OK 2024/09/25 04:20:42 DEBUG : both6: md5 = 489b57de50e84aef9736223b5402daf6 OK 2024/09/25 04:20:42 DEBUG : only6: md5 = 531b77080626f417c0d16ed6f91effed OK 2024/09/25 04:20:43 DEBUG : both7: md5 = a0dff5269fae5386108570e3cd0aaae0 OK 2024/09/25 04:20:43 DEBUG : only7: md5 = 3afe240af87bc964f448fb32119508c7 OK 2024/09/25 04:20:43 DEBUG : both8: md5 = ed781cf082cb209054fafaa85e357395 OK 2024/09/25 04:20:44 DEBUG : only8: md5 = edb98490b0509fd377a9b559a006c194 OK 2024/09/25 04:20:44 DEBUG : both9: md5 = eda323092be6301152625df9ddd18c4f OK 2024/09/25 04:20:44 DEBUG : only9: md5 = fefd25a00c737cf3d1a82bed62368f64 OK 2024/09/25 04:20:44 DEBUG : both10: md5 = 224e5fa5d8c8d3ed768c1790798097e6 OK 2024/09/25 04:20:44 DEBUG : only10: md5 = a9d9becbfb5271030b47e07b759c0c09 OK 2024/09/25 04:20:44 DEBUG : both11: md5 = 0e116b1ac39604eb41aacd6fd57120f6 OK 2024/09/25 04:20:45 DEBUG : only11: md5 = 50dce0c11bef32c5e6f1c97081c34121 OK 2024/09/25 04:20:45 DEBUG : both12: md5 = 94992e35983e47c5eadc71d60b8200b1 OK 2024/09/25 04:20:45 DEBUG : only12: md5 = 145bcd9972275608a4e3f2b41c80c122 OK 2024/09/25 04:20:45 DEBUG : both13: md5 = 499353c3559532da49ff1a97c2c62486 OK 2024/09/25 04:20:45 DEBUG : only13: md5 = 5e0173bdfcde8bce11bb10c24a5ba356 OK 2024/09/25 04:20:46 DEBUG : both14: md5 = 2990732b7fd300629a63935bae69cc66 OK 2024/09/25 04:20:46 DEBUG : only14: md5 = e155afb7f37ce52be094de3499081c0b OK 2024/09/25 04:20:46 DEBUG : both15: md5 = 36228a6228fee3e9e5f84f762d999996 OK 2024/09/25 04:20:46 DEBUG : only15: md5 = 24d3474ae611851c8c8aceed8806b402 OK 2024/09/25 04:20:46 DEBUG : both16: md5 = 5eae709fefd088c01ab030ef2809b0ff OK 2024/09/25 04:20:47 DEBUG : only16: md5 = 35b2949f011c10849fa2f9ba9ad40df2 OK 2024/09/25 04:20:47 DEBUG : both17: md5 = ce5f9ae32dba2c2f1bbf65f5bba785bf OK 2024/09/25 04:20:47 DEBUG : only17: md5 = af78b00185eb0f529133397b65cf7416 OK 2024/09/25 04:20:47 DEBUG : both18: md5 = e47ef1662169a94e83a31fb20ce9c615 OK 2024/09/25 04:20:47 DEBUG : only18: md5 = e6194addc8317fc6621417f76c0896a9 OK 2024/09/25 04:20:48 DEBUG : both19: md5 = 12684a3a4aebf605f3a0e676b45781e4 OK 2024/09/25 04:20:48 DEBUG : only19: md5 = 006f27e936477aa6df5090a3ce62bf38 OK 2024/09/25 04:20:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for checks to finish 2024/09/25 04:20:50 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both0: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both10: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both11: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both1: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both12: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both13: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both15: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both14: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both16: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both17: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both19: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both18: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both2: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both3: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both5: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both4: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both6: Unchanged skipping 2024/09/25 04:20:50 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/09/25 04:20:50 DEBUG : both7: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both8: Unchanged skipping 2024/09/25 04:20:50 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/09/25 04:20:50 DEBUG : both9: Unchanged skipping 2024/09/25 04:20:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Waiting for transfers to finish 2024/09/25 04:20:50 DEBUG : only0: md5 = 9fd63685a86dfc5dd1a5a059c72833d1 OK 2024/09/25 04:20:50 INFO : only0: Copied (replaced existing) 2024/09/25 04:20:50 DEBUG : only10: md5 = d86e954d35ae953d597791a5340ba55e OK 2024/09/25 04:20:50 INFO : only10: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only1: md5 = 281d877991d617880c04c33b67160265 OK 2024/09/25 04:20:51 INFO : only1: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only11: md5 = fc96e7acde5f8307a697334aad1ea5b3 OK 2024/09/25 04:20:51 INFO : only11: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only14: md5 = e7e90dc58232e2dc25b0e2aa7b9f6277 OK 2024/09/25 04:20:51 INFO : only14: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only13: md5 = 8140d3cf86daf1e845e0ef8ab5c1d8cb OK 2024/09/25 04:20:51 INFO : only13: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only15: md5 = 56b812e898284d974c78ddcdcf03a6a6 OK 2024/09/25 04:20:51 INFO : only15: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only16: md5 = 8cf7c0957d3a0f808cfecbcaec6c8fe6 OK 2024/09/25 04:20:51 INFO : only16: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only12: md5 = 1f8abf8ae401798139b3732ea980c826 OK 2024/09/25 04:20:51 INFO : only12: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only18: md5 = de2bf2c1b3e5b1a82bea6c93907cd2c7 OK 2024/09/25 04:20:51 INFO : only18: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only19: md5 = 241dee7a440f9108f59ebc2a719c55b2 OK 2024/09/25 04:20:51 INFO : only19: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only17: md5 = 63710f854b148ee50620f0d60958745b OK 2024/09/25 04:20:51 INFO : only17: Copied (replaced existing) 2024/09/25 04:20:51 DEBUG : only2: md5 = 80434bf18ca6de56aa2b4f3deceea864 OK 2024/09/25 04:20:51 INFO : only2: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only3: md5 = 037a4988cab1e4ab4b43daba5fb8338b OK 2024/09/25 04:20:52 INFO : only3: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only4: md5 = 6bd172bb1e39f400dde3b3b27ec807d2 OK 2024/09/25 04:20:52 INFO : only4: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only5: md5 = c990964bcd2f4488304f3656038b0aa3 OK 2024/09/25 04:20:52 INFO : only5: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only6: md5 = 3aee43b0e1799a09bc40a738aff4c380 OK 2024/09/25 04:20:52 INFO : only6: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only7: md5 = 9dead7c784140a7fdbf54fc9281c82ee OK 2024/09/25 04:20:52 INFO : only7: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only8: md5 = e3af478096825c6541db4a40bf134b82 OK 2024/09/25 04:20:52 INFO : only8: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : only9: md5 = 18d0033d8a99b7c263d4e274be923bac OK 2024/09/25 04:20:52 INFO : only9: Copied (replaced existing) 2024/09/25 04:20:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (25.57s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:21:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/25 04:21:04 DEBUG : sub dir: Making directory 2024/09/25 04:21:04 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestNothingToTransferWithEmptyDirs (2.49s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" 2024/09/25 04:21:07 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/09/25 04:21:07 DEBUG : sub dir: Making directory 2024/09/25 04:21:07 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-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi'", Local "Local file system at /tmp/rclone322179843", Modify Window "1ns" --- PASS: TestNothingToTransferWithoutEmptyDirs (2.57s) PASS 2024/09/25 04:21:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-horucec7dixi': Purge remote 2024/09/25 04:21:09 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m46.13292685s (try 1/5)