"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 2/5) 2025/04/26 06:41:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi" 2025/04/26 06:41:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/26 06:41:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi.bin" 2025/04/26 06:41:10 DEBUG : Swift container crypt path rclone-test-gitexer1lasi.bin: Auto set use_segments_container to true 2025/04/26 06:41:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi" 2025/04/26 06:41:21 DEBUG : Swift container crypt path rclone-test-gitexer1lasi: Auto set use_segments_container to true 2025/04/26 06:41:26 DEBUG : Creating backend with remote "/tmp/rclone348659573" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:41:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:41:26 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/04/26 06:41:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:41:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.60s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:41:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/26 06:41:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:41:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:41:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:41:32 DEBUG : sub dir/hello world: md5 = 98ec4a4c1bf24071c4d5b40e34c5b57d OK 2025/04/26 06:41:32 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:41:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (15.96s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.20s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.30s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:41:43 DEBUG : Creating backend with remote "/non-existing" 2025/04/26 06:41:43 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/04/26 06:41:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:41:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.40s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:41:49 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:41:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:41:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:41:54 DEBUG : sub dir/hello world: md5 = 897e79734ba93a67e50d6867fa26b26e OK 2025/04/26 06:41:54 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyNoTraverse (21.07s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:05 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Running all checks before starting transfers 2025/04/26 06:42:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:42:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:05 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Checks finished, now starting transfers 2025/04/26 06:42:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:10 DEBUG : sub dir/hello world: md5 = 6512ece675b78e3d2e5024d14568d4a0 OK 2025/04/26 06:42:10 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyCheckFirst (16.13s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:21 ERROR : Ignoring --no-traverse with sync 2025/04/26 06:42:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:42:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:26 DEBUG : sub dir/hello world: md5 = e830c703f6258657f59662272c3030d1 OK 2025/04/26 06:42:26 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:42:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncNoTraverse (11.48s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:32 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/04/26 06:42:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:38 DEBUG : hello world2: md5 = a98b27edda1f50acc90606473c36fa0f OK 2025/04/26 06:42:38 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyWithDepth (10.89s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:43 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:44 DEBUG : potato2: md5 = d056ce084776f47d39796cc0f3a4fb92 OK 2025/04/26 06:42:44 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.62s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:42:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:50 DEBUG : potato2: md5 = 00cf6211a6401a3a862418d8863cff55 OK 2025/04/26 06:42:50 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (6.32s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:42:51 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/26 06:42:51 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 06:42:51 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/04/26 06:42:51 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/26 06:42:51 INFO : sub dir: Making directory 2025/04/26 06:42:51 INFO : sub dir2: Making directory 2025/04/26 06:42:51 INFO : sub dir2/sub sub dir2: Making directory 2025/04/26 06:42:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:42:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:42:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:42:56 DEBUG : sub dir/hello world: md5 = 9afe1d689941315a12fb00e466fee292 OK 2025/04/26 06:42:56 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" fstest.go:244: Filtering empty directory "sub dir2/sub sub dir2" 2025/04/26 06:43:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (17.49s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:43:09 INFO : sub dir2: Making directory 2025/04/26 06:43:09 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/26 06:43:09 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 06:43:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:43:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:43:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:43:14 DEBUG : sub dir/hello world: md5 = bcdd9525fc522a8f08d65f9a37d53892 OK 2025/04/26 06:43:14 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (20.60s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:43:29 DEBUG : sub dir2: Making directory with metadata 2025/04/26 06:43:29 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 06:43:30 INFO : sub dir: Making directory 2025/04/26 06:43:30 INFO : sub dir2: Making directory 2025/04/26 06:43:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:43:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:43:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:43:35 DEBUG : sub dir/hello world: md5 = 04096f740708f6f051b0379df673bdcf OK 2025/04/26 06:43:35 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:43:35 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/04/26 06:43:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/04/26 06:43:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (11.84s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", 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-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:43:41 INFO : sub dir2: Making directory 2025/04/26 06:43:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:43:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:43:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:43:46 DEBUG : sub dir/hello world: md5 = dfc022f58a38a3b516d61d3b2c38b979 OK 2025/04/26 06:43:46 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:43:46 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (20.96s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:44:02 DEBUG : sub dir2: Making directory with metadata 2025/04/26 06:44:02 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 06:44:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/26 06:44:02 INFO : sub dir: Making directory 2025/04/26 06:44:02 INFO : sub dir2: Making directory 2025/04/26 06:44:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:44:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:44:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:44:07 DEBUG : sub dir/hello world: md5 = 5e33eb5e9c93dc0947118552ef01c3e0 OK 2025/04/26 06:44:07 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:44:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2025/04/26 06:44:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (11.33s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.20s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:44:14 INFO : sub dir2: Making directory 2025/04/26 06:44:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:44:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:44:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:44:19 DEBUG : sub dir/hello world: md5 = d764a5a7422530e07f66abda52133dc5 OK 2025/04/26 06:44:19 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:44:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (10.74s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:44:30 DEBUG : sub dir/hello world: md5 = b04bca1234a368ec30e794fde257e4d2 OK 2025/04/26 06:44:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wuyaraz8vici" 2025/04/26 06:44:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wuyaraz8vici.bin" 2025/04/26 06:44:30 DEBUG : Swift container crypt path rclone-test-wuyaraz8vici.bin: Auto set use_segments_container to true 2025/04/26 06:44:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wuyaraz8vici" 2025/04/26 06:44:41 DEBUG : Swift container crypt path rclone-test-wuyaraz8vici: Auto set use_segments_container to true sync_test.go:590: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-wuyaraz8vici' 2025/04/26 06:44:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:44:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuyaraz8vici': Waiting for checks to finish 2025/04/26 06:44:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuyaraz8vici': Waiting for transfers to finish 2025/04/26 06:44:57 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:44:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wuyaraz8vici': Purge remote 2025/04/26 06:44:58 DEBUG : Waiting for deletions to finish 2025/04/26 06:45:08 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (49.65s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:45:19 DEBUG : sub dir/hello world: md5 = 18a815ebefc20bb4ae4f1b7cf7ceb5bf OK 2025/04/26 06:45:20 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/26 06:45:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:45:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:45:25 DEBUG : sub dir/hello world: md5 = 8ed7220b42c5a66bdcff20ffdde14d23 OK 2025/04/26 06:45:25 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyOverSelf (16.90s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:45:36 DEBUG : sub dir/hello world: md5 = e8a87ef445d6249f76b7d15efcbb1865 OK 2025/04/26 06:45:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rovanob4nevi" 2025/04/26 06:45:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rovanob4nevi.bin" 2025/04/26 06:45:37 DEBUG : Swift container crypt path rclone-test-rovanob4nevi.bin: Auto set use_segments_container to true 2025/04/26 06:45:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rovanob4nevi" 2025/04/26 06:45:43 DEBUG : Swift container crypt path rclone-test-rovanob4nevi: Auto set use_segments_container to true sync_test.go:626: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi' 2025/04/26 06:45:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:45:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi': Waiting for checks to finish 2025/04/26 06:45:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi': Waiting for transfers to finish 2025/04/26 06:46:09 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:46:19 DEBUG : sub dir/hello world: md5 = 681e0e9aa6248f57b93a754ad45ef641 OK 2025/04/26 06:46:20 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/26 06:46:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi': Waiting for checks to finish 2025/04/26 06:46:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi': Waiting for transfers to finish 2025/04/26 06:46:30 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:46:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rovanob4nevi': Purge remote 2025/04/26 06:46:30 DEBUG : Waiting for deletions to finish 2025/04/26 06:46:41 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (75.85s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:46:52 DEBUG : sub dir/hello world: md5 = 99f2f5a97585b380d8c6681c535aa57c OK 2025/04/26 06:46:57 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/26 06:46:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:46:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:47:03 DEBUG : sub dir/hello world: md5 = 9495f3cf64e369343a523c202c6846b4 OK 2025/04/26 06:47:03 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/26 06:47:03 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestMoveOverSelf (21.64s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:47:14 DEBUG : sub dir/hello world: md5 = a52902278e2670a3bd3a7c4af0da1cff OK 2025/04/26 06:47:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-noxariy9fecu" 2025/04/26 06:47:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noxariy9fecu.bin" 2025/04/26 06:47:15 DEBUG : Swift container crypt path rclone-test-noxariy9fecu.bin: Auto set use_segments_container to true 2025/04/26 06:47:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-noxariy9fecu" 2025/04/26 06:47:20 DEBUG : Swift container crypt path rclone-test-noxariy9fecu: Auto set use_segments_container to true sync_test.go:671: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu' 2025/04/26 06:47:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:47:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for checks to finish 2025/04/26 06:47:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for transfers to finish 2025/04/26 06:47:36 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:47:47 DEBUG : sub dir/hello world: md5 = e00131d0f13e2747ab8c02491b305c1f OK 2025/04/26 06:47:52 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/26 06:47:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for checks to finish 2025/04/26 06:47:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for transfers to finish 2025/04/26 06:48:07 INFO : sub dir/hello world: Copied (server-side copy) 2025/04/26 06:48:12 INFO : sub dir/hello world: Deleted 2025/04/26 06:48:18 DEBUG : sub dir/hello world: md5 = 4018fbe1551ed99c2bb4c2ec8a5c9afd OK 2025/04/26 06:48:18 DEBUG : testing file moves 2025/04/26 06:48:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for checks to finish 2025/04/26 06:48:18 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/04/26 06:48:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Waiting for transfers to finish 2025/04/26 06:48:29 INFO : sub dir/hello world: Copied (server-side copy) 2025/04/26 06:48:34 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:48:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-noxariy9fecu': Purge remote 2025/04/26 06:48:34 DEBUG : Waiting for deletions to finish 2025/04/26 06:48:39 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (91.37s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:48:45 DEBUG : sub dir/hello world: md5 = 3390421a89f4aadf8ca0cb68039b3fb4 OK 2025/04/26 06:48:45 ERROR : : error listing: directory not found 2025/04/26 06:48:45 INFO : Local file system at /tmp/rclone348659573: Making directory 2025/04/26 06:48:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:48:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyAfterDelete (26.19s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:11 DEBUG : sub dir/hello world: md5 = cfa2f6d2cf0799ad8668c31f8549d615 OK 2025/04/26 06:49:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:49:12 DEBUG : Local file system at /tmp/rclone348659573: Waiting for checks to finish 2025/04/26 06:49:12 DEBUG : Local file system at /tmp/rclone348659573: Waiting for transfers to finish 2025/04/26 06:49:17 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/04/26 06:49:17 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyRedownload (16.07s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:22 DEBUG : check sum: Need to transfer - File not found at Destination 2025/04/26 06:49:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:23 DEBUG : check sum: md5 = 54e7c4557fa19c948860248edd6e6372 OK 2025/04/26 06:49:23 INFO : check sum: Copied (new) 2025/04/26 06:49:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:23 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/04/26 06:49:23 DEBUG : check sum: Size of src and dst objects identical 2025/04/26 06:49:23 DEBUG : check sum: Unchanged skipping 2025/04/26 06:49:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:23 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:23 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (2.13s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:24 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/04/26 06:49:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:30 DEBUG : sizeonly: md5 = 698164449e2b33e80b669f65317101ed OK 2025/04/26 06:49:30 INFO : sizeonly: Copied (new) 2025/04/26 06:49:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:30 DEBUG : sizeonly: Sizes identical 2025/04/26 06:49:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:35 DEBUG : sizeonly: Unchanged skipping 2025/04/26 06:49:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:35 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:35 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncSizeOnly (21.66s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:46 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/04/26 06:49:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:46 DEBUG : ignore-size: md5 = be6101d4d0778a67fabb181c29dd38a1 OK 2025/04/26 06:49:46 INFO : ignore-size: Copied (new) 2025/04/26 06:49:46 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:47 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:49:47 DEBUG : ignore-size: Unchanged skipping 2025/04/26 06:49:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:47 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:47 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.80s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:48 DEBUG : existing: md5 = a961ba142dd494583c893a1dd3c4f825 OK 2025/04/26 06:49:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:49 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:49:49 DEBUG : existing: Unchanged skipping 2025/04/26 06:49:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:49 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:49 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:49 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/04/26 06:49:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:49 DEBUG : existing: md5 = d4ef455ac2c58c124a3fe772a6ecbfcc OK 2025/04/26 06:49:49 INFO : existing: Copied (replaced existing) 2025/04/26 06:49:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (2.10s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:50 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/26 06:49:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:50 DEBUG : existing: md5 = 19a63354b39ed0e2cd736b6b8882623d OK 2025/04/26 06:49:50 INFO : existing: Copied (new) 2025/04/26 06:49:50 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:51 DEBUG : existing: Destination exists, skipping 2025/04/26 06:49:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:51 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:51 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.91s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:49:52 DEBUG : b/potato: md5 = 0457a99dbe89ddec6ec7de01e3d2bccd OK 2025/04/26 06:49:58 DEBUG : c/non empty space: md5 = 2c82ba3f8a9880c73968731ac84b2da2 OK 2025/04/26 06:49:58 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/04/26 06:49:58 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/26 06:49:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:49:58 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:49:58 DEBUG : c/non empty space: Unchanged skipping 2025/04/26 06:49:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:49:59 DEBUG : a/potato2: md5 = 96febd306d327e1a6af608ae8ec241da OK 2025/04/26 06:49:59 INFO : a/potato2: Copied (new) 2025/04/26 06:49:59 DEBUG : Waiting for deletions to finish 2025/04/26 06:49:59 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (13.27s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:50:11 DEBUG : empty space: md5 = 5847165ea6f4a08e790d353fe05b69a5 OK 2025/04/26 06:50:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:50:16 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/04/26 06:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:50:16 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/04/26 06:50:16 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:50:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:50:21 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/04/26 06:50:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:50:26 DEBUG : empty space: md5 = b2024eec01bd4bdc1b2e2bf3e91f91b7 OK 2025/04/26 06:50:26 INFO : empty space: Copied (replaced existing) 2025/04/26 06:50:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (27.50s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.13s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:50:38 DEBUG : foo: md5 = fd8f9a567c8fc2a35bc6f2955e199c31 OK 2025/04/26 06:50:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:50:38 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2025/04/26 06:50:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:50:43 DEBUG : foo: md5 = 72821d7a6e4a10a82629d5a3c1dfd4d2 OK 2025/04/26 06:50:43 INFO : foo: Copied (replaced existing) 2025/04/26 06:50:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (16.47s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:50:54 DEBUG : empty space: md5 = 3db1cec4ce242011b9ca9c012c0572d1 OK 2025/04/26 06:51:00 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/26 06:51:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:51:00 DEBUG : potato: md5 = 749dbdcf05f695d250e65d650acf3142 OK 2025/04/26 06:51:00 INFO : potato: Copied (new) 2025/04/26 06:51:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:51:05 DEBUG : empty space: Unchanged skipping 2025/04/26 06:51:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:51:05 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (21.69s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:51:11 DEBUG : potato: md5 = 060e857811bf24416ed2b52197437ef3 OK 2025/04/26 06:51:11 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/04/26 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:51:12 DEBUG : potato: md5 = bf2f12ca56d93c02354dddacb2442447 OK 2025/04/26 06:51:12 INFO : potato: Copied (replaced existing) 2025/04/26 06:51:12 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.72s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:51:13 DEBUG : potato: md5 = 289ebf1ddefdc49686d6d71dd8fa012f OK 2025/04/26 06:51:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:51:13 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2025/04/26 06:51:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:51:13 DEBUG : potato: md5 = 3dfde8101c1024aff3fa70623eed4bd4 OK 2025/04/26 06:51:13 INFO : potato: Copied (replaced existing) 2025/04/26 06:51:13 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.45s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:51:14 DEBUG : potato: md5 = 8ca309100e01483fd81c0ffd5ea933b7 OK 2025/04/26 06:51:19 DEBUG : empty space: md5 = 765da88be480203208c83a1b4fd4035f OK 2025/04/26 06:51:19 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:51:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:51:19 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/04/26 06:51:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:51:19 DEBUG : empty space: Unchanged skipping 2025/04/26 06:51:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:51:19 DEBUG : Waiting for deletions to finish 2025/04/26 06:51:19 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (21.76s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:51:36 DEBUG : potato: md5 = e0a47cb937b4f780b945644145a18899 OK 2025/04/26 06:51:41 DEBUG : empty space: md5 = 14840da529af7463d795fe3c1c71cf21 OK 2025/04/26 06:51:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:51:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:51:47 DEBUG : potato2: md5 = af4b24a64476f92abc10b4f8b23bb523 OK 2025/04/26 06:51:47 INFO : potato2: Copied (new) 2025/04/26 06:51:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:51:51 DEBUG : empty space: Unchanged skipping 2025/04/26 06:51:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:51:51 DEBUG : Waiting for deletions to finish 2025/04/26 06:51:51 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (27.43s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:52:05 DEBUG : b/potato: md5 = a0e0efd116f95967008fe6672b9da884 OK 2025/04/26 06:52:10 DEBUG : c/non empty space: md5 = 18500e5798e63e50d94b01f06a74c574 OK 2025/04/26 06:52:10 INFO : d: Making directory 2025/04/26 06:52:10 INFO : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2025/04/26 06:52:10 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/26 06:52:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:52:10 DEBUG : a/potato2: md5 = d6ab8594d15fdb2b2d538cf872428faa OK 2025/04/26 06:52:10 INFO : a/potato2: Copied (new) 2025/04/26 06:52:15 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:52:15 DEBUG : c/non empty space: Unchanged skipping 2025/04/26 06:52:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:52:15 DEBUG : Waiting for deletions to finish 2025/04/26 06:52:16 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (28.37s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:52:32 DEBUG : b/potato: md5 = 86a52c6b97200a04ed4bc8ec33396d43 OK 2025/04/26 06:52:37 DEBUG : c/non empty space: md5 = 1cea6cafb4aefb6adbf638c9f9e52511 OK 2025/04/26 06:52:37 INFO : d: Making directory fstest.go:244: Filtering empty directory "d" 2025/04/26 06:52:37 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/26 06:52:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:52:38 DEBUG : a/potato2: md5 = 5535ff966ee59ab31a08e4c32ff590af OK 2025/04/26 06:52:38 INFO : a/potato2: Copied (new) 2025/04/26 06:52:42 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:52:42 DEBUG : c/non empty space: Unchanged skipping 2025/04/26 06:52:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:52:42 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': not deleting files as there were IO errors 2025/04/26 06:52:42 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (27.07s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:52:59 DEBUG : potato: md5 = f435b88d25dec3bd76f87ac408753fa7 OK 2025/04/26 06:53:04 DEBUG : empty space: md5 = 9a9e5e1c1ccb1a09cb407fae2d38d988 OK 2025/04/26 06:53:04 DEBUG : Waiting for deletions to finish 2025/04/26 06:53:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:53:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:53:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:53:04 DEBUG : empty space: Unchanged skipping 2025/04/26 06:53:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:53:05 INFO : potato: Deleted 2025/04/26 06:53:05 DEBUG : potato2: md5 = 80bc49b5822105fda6da2b8ce6d4259b OK 2025/04/26 06:53:05 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (12.02s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:53:11 DEBUG : potato: md5 = a4f9650a50d1f2f84a6caffc95974bcc OK 2025/04/26 06:53:16 DEBUG : empty space: md5 = cbd6671676fd79f10f490e404d80678a OK 2025/04/26 06:53:16 DEBUG : Waiting for deletions to finish 2025/04/26 06:53:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:53:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:53:16 INFO : potato: Deleted 2025/04/26 06:53:17 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:53:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:53:17 DEBUG : empty space: Unchanged skipping 2025/04/26 06:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:53:17 DEBUG : potato2: md5 = 6e6ff15507d6d6783aed98ef46182e9e OK 2025/04/26 06:53:17 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (17.28s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:53:28 DEBUG : potato: md5 = 4fbf429875baa0b43ec99ab41e56acd8 OK 2025/04/26 06:53:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:53:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:53:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:53:29 DEBUG : potato2: md5 = c90fade70e225f384c88aa00dfeacd14 OK 2025/04/26 06:53:29 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.91s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:53:30 DEBUG : potato2: md5 = 3ab5d59c9fede0aa9965e3dfc9d8e9a7 OK 2025/04/26 06:53:35 DEBUG : empty space: md5 = d3db4d9d39b57e02905c7e9b44d40d5c OK 2025/04/26 06:53:35 DEBUG : enormous: Excluded (Size Filter) 2025/04/26 06:53:35 DEBUG : enormous: Excluded 2025/04/26 06:53:35 DEBUG : potato2: Excluded (Size Filter) 2025/04/26 06:53:35 DEBUG : potato2: Excluded 2025/04/26 06:53:36 DEBUG : potato2: Excluded (Size Filter) 2025/04/26 06:53:36 DEBUG : potato2: Excluded 2025/04/26 06:53:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:53:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:53:36 DEBUG : empty space: Unchanged skipping 2025/04/26 06:53:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:53:36 DEBUG : Waiting for deletions to finish 2025/04/26 06:53:36 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:53:41 DEBUG : enormous: Excluded (Size Filter) 2025/04/26 06:53:41 DEBUG : enormous: Excluded 2025/04/26 06:53:41 DEBUG : potato2: Excluded (Size Filter) 2025/04/26 06:53:41 DEBUG : potato2: Excluded 2025/04/26 06:53:41 DEBUG : potato2: Excluded (Size Filter) 2025/04/26 06:53:41 DEBUG : potato2: Excluded 2025/04/26 06:53:41 DEBUG : Local file system at /tmp/rclone348659573: Waiting for checks to finish 2025/04/26 06:53:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:53:46 DEBUG : empty space: Unchanged skipping 2025/04/26 06:53:46 DEBUG : Local file system at /tmp/rclone348659573: Waiting for transfers to finish 2025/04/26 06:53:46 DEBUG : Waiting for deletions to finish 2025/04/26 06:53:46 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncWithExclude (22.43s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:53:52 DEBUG : potato2: md5 = ecf892ec32cc9c38c9352394c98f91cd OK 2025/04/26 06:53:58 DEBUG : empty space: md5 = 3a5b126e18100623b5fe8b205c8201d6 OK 2025/04/26 06:54:03 DEBUG : enormous: md5 = aa065ee4c7a0f70b3096d32a8ee860ac OK 2025/04/26 06:54:08 DEBUG : enormous: Excluded (Size Filter) 2025/04/26 06:54:08 DEBUG : enormous: Excluded 2025/04/26 06:54:08 DEBUG : potato2: Excluded (Size Filter) 2025/04/26 06:54:08 DEBUG : potato2: Excluded 2025/04/26 06:54:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:54:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:54:08 DEBUG : empty space: Unchanged skipping 2025/04/26 06:54:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:54:08 DEBUG : Waiting for deletions to finish 2025/04/26 06:54:09 INFO : potato2: Deleted 2025/04/26 06:54:19 INFO : enormous: Deleted 2025/04/26 06:54:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:54:19 DEBUG : Local file system at /tmp/rclone348659573: Waiting for checks to finish 2025/04/26 06:54:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:54:19 DEBUG : empty space: Unchanged skipping 2025/04/26 06:54:19 DEBUG : Local file system at /tmp/rclone348659573: Waiting for transfers to finish 2025/04/26 06:54:19 DEBUG : Waiting for deletions to finish 2025/04/26 06:54:19 INFO : enormous: Deleted 2025/04/26 06:54:19 INFO : potato2: Deleted 2025/04/26 06:54:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (32.53s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:54:30 DEBUG : one: md5 = 1177ccaf6ac7866ecdcd8f42a727d16f OK 2025/04/26 06:54:36 DEBUG : two: md5 = f2bbaf487b215cad26d0deca62fe0c75 OK 2025/04/26 06:54:36 DEBUG : three: md5 = 7b661f7f8714418ecc6675821b2039b2 OK 2025/04/26 06:54:41 DEBUG : four: md5 = f94efdccb609459ba74a60cc1062be5d OK 2025/04/26 06:54:42 DEBUG : five: Need to transfer - File not found at Destination 2025/04/26 06:54:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:54:42 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/04/26 06:54:42 DEBUG : one: Destination is newer than source, skipping 2025/04/26 06:54:42 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/04/26 06:54:42 DEBUG : three: Sizes identical 2025/04/26 06:54:42 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/04/26 06:54:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:54:47 DEBUG : four: md5 = c602cd012394c216feafee76e3e83465 OK 2025/04/26 06:54:47 INFO : four: Copied (replaced existing) 2025/04/26 06:54:47 DEBUG : five: md5 = 82a5a9697da4f74512877c580fcfb8e0 OK 2025/04/26 06:54:47 INFO : five: Copied (new) 2025/04/26 06:54:52 DEBUG : two: md5 = 7e883079a9c2d88fd3f5d371f97d6bef OK 2025/04/26 06:54:52 INFO : two: Copied (replaced existing) 2025/04/26 06:54:52 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (68.91s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: 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-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:1538: Can track renames: false 2025/04/26 06:55:34 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Ignoring --track-renames as the source and destination do not have a common hash 2025/04/26 06:55:34 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/26 06:55:34 DEBUG : yam: Need to transfer - File not found at Destination 2025/04/26 06:55:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:55:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:55:34 DEBUG : potato: md5 = 34707599755c4781a1be105722caf333 OK 2025/04/26 06:55:34 INFO : potato: Copied (new) 2025/04/26 06:55:35 DEBUG : yam: md5 = 014b5b705100433f2caa89f064365fb8 OK 2025/04/26 06:55:35 INFO : yam: Copied (new) 2025/04/26 06:55:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:55:35 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Ignoring --track-renames as the source and destination do not have a common hash 2025/04/26 06:55:35 DEBUG : yaml: Need to transfer - File not found at Destination 2025/04/26 06:55:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:55:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:55:35 DEBUG : potato: Unchanged skipping 2025/04/26 06:55:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:55:40 DEBUG : yaml: md5 = 859cb422c1b564bdf35f15c62d52e994 OK 2025/04/26 06:55:40 INFO : yaml: Copied (new) 2025/04/26 06:55:40 DEBUG : Waiting for deletions to finish 2025/04/26 06:55:40 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (22.90s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/04/26 06:55:57 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Making map for --track-renames 2025/04/26 06:55:57 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Finished making map for --track-renames 2025/04/26 06:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:55:57 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/26 06:55:57 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/26 06:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for renames to finish 2025/04/26 06:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:55:57 DEBUG : potato: md5 = 1e8761cde03ade747f14134f433750b1 OK 2025/04/26 06:55:57 INFO : potato: Copied (new) 2025/04/26 06:55:57 DEBUG : yam: md5 = db09e2973ddcd0cd170a098d57eca353 OK 2025/04/26 06:55:57 INFO : yam: Copied (new) 2025/04/26 06:55:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:55:57 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Making map for --track-renames 2025/04/26 06:55:57 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Finished making map for --track-renames 2025/04/26 06:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:55:57 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:55:57 DEBUG : potato: Unchanged skipping 2025/04/26 06:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for renames to finish 2025/04/26 06:56:13 INFO : yam: Copied (server-side copy) to: yaml 2025/04/26 06:56:13 INFO : yam: Deleted 2025/04/26 06:56:13 INFO : yaml: Renamed from "yam" 2025/04/26 06:56:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:56:13 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (32.72s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:1649: Can track renames: true 2025/04/26 06:56:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Making map for --track-renames 2025/04/26 06:56:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Finished making map for --track-renames 2025/04/26 06:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:56:29 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/26 06:56:29 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/04/26 06:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for renames to finish 2025/04/26 06:56:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:56:30 DEBUG : potato: md5 = 1173275e5c1fdc8b84ad1452f21cfee5 OK 2025/04/26 06:56:30 INFO : potato: Copied (new) 2025/04/26 06:56:30 DEBUG : sub/yam: md5 = cda6b074d42c5ab7e4656602e5748a1c OK 2025/04/26 06:56:30 INFO : sub/yam: Copied (new) 2025/04/26 06:56:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:56:30 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:56:30 DEBUG : potato: Unchanged skipping 2025/04/26 06:56:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Making map for --track-renames 2025/04/26 06:56:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Finished making map for --track-renames 2025/04/26 06:56:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:56:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for renames to finish 2025/04/26 06:56:41 INFO : sub/yam: Copied (server-side copy) to: yam 2025/04/26 06:56:41 INFO : sub/yam: Deleted 2025/04/26 06:56:41 INFO : yam: Renamed from "sub/yam" 2025/04/26 06:56:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:56:41 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (13.01s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:56:42 DEBUG : Creating backend with remote "/tmp/rclone348659573/dir1" 2025/04/26 06:56:42 DEBUG : Config file has changed externally - reloading 2025/04/26 06:56:42 DEBUG : Creating backend with remote "/tmp/rclone348659573/dir2" 2025/04/26 06:56:42 DEBUG : Local file system at /tmp/rclone348659573/dir2: Using server-side directory move 2025/04/26 06:56:42 INFO : Local file system at /tmp/rclone348659573/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/26 06:56:42 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/04/26 06:56:42 DEBUG : Local file system at /tmp/rclone348659573/dir2: Waiting for checks to finish 2025/04/26 06:56:42 DEBUG : Local file system at /tmp/rclone348659573/dir2: Waiting for transfers to finish 2025/04/26 06:56:42 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.11s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:56:42 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:56:42 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/26 06:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:56:48 DEBUG : nested/sub dir/file: md5 = 0090dbbc30cd6d082e819a9250869a9b OK 2025/04/26 06:56:48 INFO : nested/sub dir/file: Copied (new) 2025/04/26 06:56:48 INFO : nested/sub dir/file: Deleted 2025/04/26 06:56:48 DEBUG : sub dir/hello world: md5 = 5ad48056b09ab96fb2023d5e61e04c0a OK 2025/04/26 06:56:48 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:56:48 INFO : sub dir/hello world: Deleted 2025/04/26 06:56:48 INFO : sub dir: Removing directory 2025/04/26 06:56:48 INFO : nested/sub dir: Removing directory 2025/04/26 06:56:48 INFO : nested: Removing directory 2025/04/26 06:56:48 DEBUG : Local file system at /tmp/rclone348659573: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (21.44s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:57:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/26 06:57:04 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/26 06:57:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:57:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:57:09 DEBUG : sub dir/hello world: md5 = b06b1fcafc82a66f41d78bce1af46cd0 OK 2025/04/26 06:57:09 INFO : sub dir/hello world: Copied (new) 2025/04/26 06:57:09 INFO : sub dir/hello world: Deleted 2025/04/26 06:57:09 DEBUG : nested/sub dir/file: md5 = e9cb58abe7f5bc8bb1dbb4935503ba95 OK 2025/04/26 06:57:09 INFO : nested/sub dir/file: Copied (new) 2025/04/26 06:57:09 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (21.56s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:57:25 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/26 06:57:25 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/04/26 06:57:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:57:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:57:26 DEBUG : existing: md5 = fd14b265127067d36987ab41139adbde OK 2025/04/26 06:57:26 INFO : existing: Copied (new) 2025/04/26 06:57:26 INFO : existing: Deleted 2025/04/26 06:57:31 DEBUG : existing-b: md5 = c9f7663401f752b68c18fd13eabea808 OK 2025/04/26 06:57:31 INFO : existing-b: Copied (new) 2025/04/26 06:57:31 INFO : existing-b: Deleted 2025/04/26 06:57:31 DEBUG : existing: Destination exists, skipping 2025/04/26 06:57:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 06:57:36 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/04/26 06:57:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 06:57:36 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (16.84s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:57:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-mowuhog7xisa" 2025/04/26 06:57:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mowuhog7xisa.bin" 2025/04/26 06:57:43 DEBUG : Swift container crypt path rclone-test-mowuhog7xisa.bin: Auto set use_segments_container to true 2025/04/26 06:57:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-mowuhog7xisa" 2025/04/26 06:57:49 DEBUG : Swift container crypt path rclone-test-mowuhog7xisa: Auto set use_segments_container to true 2025/04/26 06:57:55 DEBUG : potato2: md5 = e93960168e461db9e4da61fc8621a5de OK 2025/04/26 06:58:00 DEBUG : empty space: md5 = b3cf34cb0b5c99eee2a26ee0d23399a1 OK 2025/04/26 06:58:00 DEBUG : potato3: md5 = 24e2cfa08b0ebe85b2f77a4f332bab92 OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-mowuhog7xisa' 2025/04/26 06:58:06 DEBUG : empty space: md5 = 7650e5945930fcb3c122052cc04ae90a OK 2025/04/26 06:58:11 DEBUG : potato3: md5 = a9631c897adf1d1c370e9f74e4b664a3 OK 2025/04/26 06:58:21 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:58:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mowuhog7xisa': Waiting for checks to finish 2025/04/26 06:58:21 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/26 06:58:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 06:58:22 DEBUG : empty space: Unchanged skipping 2025/04/26 06:58:27 INFO : empty space: Deleted 2025/04/26 06:58:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mowuhog7xisa': Waiting for transfers to finish 2025/04/26 06:58:37 INFO : potato3: Copied (server-side copy) 2025/04/26 06:58:37 INFO : potato2: Copied (server-side copy) 2025/04/26 06:58:37 INFO : potato3: Deleted 2025/04/26 06:58:37 INFO : potato2: Deleted 2025/04/26 06:58:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dobemeq0qacu" 2025/04/26 06:58:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dobemeq0qacu.bin" 2025/04/26 06:58:53 DEBUG : Swift container crypt path rclone-test-dobemeq0qacu.bin: Auto set use_segments_container to true 2025/04/26 06:58:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dobemeq0qacu" 2025/04/26 06:58:59 DEBUG : Swift container crypt path rclone-test-dobemeq0qacu: Auto set use_segments_container to true 2025/04/26 06:59:05 DEBUG : empty space: Need to transfer - File not found at Destination 2025/04/26 06:59:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 06:59:05 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/26 06:59:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dobemeq0qacu': Waiting for checks to finish 2025/04/26 06:59:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dobemeq0qacu': Waiting for transfers to finish 2025/04/26 06:59:21 INFO : potato2: Copied (server-side copy) 2025/04/26 06:59:21 INFO : empty space: Copied (server-side copy) 2025/04/26 06:59:21 INFO : potato3: Copied (server-side copy) 2025/04/26 06:59:26 INFO : potato2: Deleted 2025/04/26 06:59:26 INFO : empty space: Deleted 2025/04/26 06:59:26 INFO : potato3: Deleted 2025/04/26 06:59:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dobemeq0qacu': Purge remote 2025/04/26 06:59:27 DEBUG : Waiting for deletions to finish 2025/04/26 06:59:27 INFO : potato2.bin: Deleted 2025/04/26 06:59:27 INFO : potato3.bin: Deleted 2025/04/26 06:59:32 INFO : empty space.bin: Deleted 2025/04/26 06:59:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-mowuhog7xisa': Purge remote 2025/04/26 06:59:32 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (110.15s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 06:59:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ceqibej4rera" 2025/04/26 06:59:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ceqibej4rera.bin" 2025/04/26 06:59:33 DEBUG : Swift container crypt path rclone-test-ceqibej4rera.bin: Auto set use_segments_container to true 2025/04/26 06:59:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ceqibej4rera" 2025/04/26 06:59:39 DEBUG : Swift container crypt path rclone-test-ceqibej4rera: Auto set use_segments_container to true 2025/04/26 06:59:44 DEBUG : potato2: md5 = dc18bdd5f7f1b7f03e094a99cbeffdb1 OK 2025/04/26 06:59:50 DEBUG : empty space: md5 = 7f346dade661f2fa82a5142601172fcb OK 2025/04/26 06:59:50 DEBUG : potato3: md5 = d0006fc4977721d23db67be35be42fd1 OK sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-ceqibej4rera' 2025/04/26 06:59:55 DEBUG : empty space: md5 = 000c0ac85475d4a64ab48f54384bf82c OK 2025/04/26 07:00:01 DEBUG : potato3: md5 = 248decdf43e85d641b0b6bb96ad34c97 OK 2025/04/26 07:00:01 DEBUG : empty space: Excluded (Size Filter) 2025/04/26 07:00:01 DEBUG : empty space: Excluded 2025/04/26 07:00:01 DEBUG : empty space: Excluded (Size Filter) 2025/04/26 07:00:01 DEBUG : empty space: Excluded 2025/04/26 07:00:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 07:00:01 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/26 07:00:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ceqibej4rera': Waiting for checks to finish 2025/04/26 07:00:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ceqibej4rera': Waiting for transfers to finish 2025/04/26 07:00:12 INFO : potato2: Copied (server-side copy) 2025/04/26 07:00:12 INFO : potato2: Deleted 2025/04/26 07:00:17 INFO : potato3: Copied (server-side copy) 2025/04/26 07:00:17 INFO : potato3: Deleted 2025/04/26 07:00:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-juhahov1wifa" 2025/04/26 07:00:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-juhahov1wifa.bin" 2025/04/26 07:00:23 DEBUG : Swift container crypt path rclone-test-juhahov1wifa.bin: Auto set use_segments_container to true 2025/04/26 07:00:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-juhahov1wifa" 2025/04/26 07:00:29 DEBUG : Swift container crypt path rclone-test-juhahov1wifa: Auto set use_segments_container to true 2025/04/26 07:00:34 DEBUG : empty space: Excluded (Size Filter) 2025/04/26 07:00:34 DEBUG : empty space: Excluded 2025/04/26 07:00:34 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 07:00:34 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/26 07:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juhahov1wifa': Waiting for checks to finish 2025/04/26 07:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juhahov1wifa': Waiting for transfers to finish 2025/04/26 07:00:40 INFO : potato3: Copied (server-side copy) 2025/04/26 07:00:45 INFO : potato2: Copied (server-side copy) 2025/04/26 07:00:45 INFO : potato3: Deleted 2025/04/26 07:00:50 INFO : potato2: Deleted 2025/04/26 07:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-juhahov1wifa': Purge remote 2025/04/26 07:00:55 DEBUG : Waiting for deletions to finish 2025/04/26 07:00:55 INFO : potato3.bin: Deleted 2025/04/26 07:01:00 INFO : potato2.bin: Deleted 2025/04/26 07:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ceqibej4rera': Purge remote 2025/04/26 07:01:00 DEBUG : Waiting for deletions to finish 2025/04/26 07:01:06 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (98.69s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:01:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jafagib9cece" 2025/04/26 07:01:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jafagib9cece.bin" 2025/04/26 07:01:12 DEBUG : Swift container crypt path rclone-test-jafagib9cece.bin: Auto set use_segments_container to true 2025/04/26 07:01:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jafagib9cece" 2025/04/26 07:01:18 DEBUG : Swift container crypt path rclone-test-jafagib9cece: Auto set use_segments_container to true 2025/04/26 07:01:28 DEBUG : potato2: md5 = 83a32a4602cc818a0715b0d5b54e3427 OK 2025/04/26 07:01:33 DEBUG : empty space: md5 = 0c11e9fae95f4805bf3d96fc3be6e4f2 OK 2025/04/26 07:01:33 DEBUG : potato3: md5 = 84296a790b87cc68fd969bddfe41737e OK 2025/04/26 07:01:33 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi' -> Encrypted drive 'TestCryptSwift:rclone-test-jafagib9cece' 2025/04/26 07:01:44 DEBUG : empty space: md5 = aa6bb9871dbae183f0df4ea107f1ba50 OK 2025/04/26 07:01:45 DEBUG : potato3: md5 = d74415e7c5ad447f490e0f1d979b12c3 OK 2025/04/26 07:01:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 07:01:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/26 07:01:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jafagib9cece': Waiting for checks to finish 2025/04/26 07:01:45 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:01:45 DEBUG : empty space: Unchanged skipping 2025/04/26 07:01:50 INFO : empty space: Deleted 2025/04/26 07:01:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jafagib9cece': Waiting for transfers to finish 2025/04/26 07:01:51 INFO : potato3: Copied (server-side copy) 2025/04/26 07:01:51 INFO : potato3: Deleted 2025/04/26 07:02:01 INFO : potato2: Copied (server-side copy) 2025/04/26 07:02:01 INFO : potato2: Deleted 2025/04/26 07:02:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-haligid8mesu" 2025/04/26 07:02:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-haligid8mesu.bin" 2025/04/26 07:02:02 DEBUG : Swift container crypt path rclone-test-haligid8mesu.bin: Auto set use_segments_container to true 2025/04/26 07:02:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-haligid8mesu" 2025/04/26 07:02:08 DEBUG : Swift container crypt path rclone-test-haligid8mesu: Auto set use_segments_container to true 2025/04/26 07:02:14 INFO : tomatoDir: Making directory 2025/04/26 07:02:14 DEBUG : empty space: Need to transfer - File not found at Destination 2025/04/26 07:02:14 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/26 07:02:14 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/26 07:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-haligid8mesu': Waiting for checks to finish 2025/04/26 07:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-haligid8mesu': Waiting for transfers to finish 2025/04/26 07:02:24 INFO : empty space: Copied (server-side copy) 2025/04/26 07:02:24 INFO : potato3: Copied (server-side copy) 2025/04/26 07:02:24 INFO : potato3: Deleted 2025/04/26 07:02:25 INFO : potato2: Copied (server-side copy) 2025/04/26 07:02:29 INFO : empty space: Deleted 2025/04/26 07:02:30 INFO : potato2: Deleted 2025/04/26 07:02:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-haligid8mesu': Purge remote 2025/04/26 07:02:30 DEBUG : Waiting for deletions to finish 2025/04/26 07:02:30 INFO : potato2.bin: Deleted 2025/04/26 07:02:35 INFO : potato3.bin: Deleted 2025/04/26 07:02:35 INFO : empty space.bin: Deleted 2025/04/26 07:02:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jafagib9cece': Purge remote 2025/04/26 07:02:35 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (84.90s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:02:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/rclone-move-test" 2025/04/26 07:02:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-move-test.bin" 2025/04/26 07:02:37 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-move-test.bin: Auto set use_segments_container to true 2025/04/26 07:02:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-move-test" 2025/04/26 07:02:43 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-move-test: Auto set use_segments_container to true 2025/04/26 07:02:48 DEBUG : potato2: md5 = 6b6219e6460805ab0acfd983f99088aa OK --- PASS: TestServerSideMoveOverlap (13.04s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:02:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test" 2025/04/26 07:02:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test.bin" 2025/04/26 07:02:50 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/04/26 07:02:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test" 2025/04/26 07:02:56 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test: Auto set use_segments_container to true run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncOverlap (13.02s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:03:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test" 2025/04/26 07:03:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test.bin" 2025/04/26 07:03:03 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test.bin: Auto set use_segments_container to true 2025/04/26 07:03:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test" 2025/04/26 07:03:08 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test: Auto set use_segments_container to true 2025/04/26 07:03:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-include/layer2" 2025/04/26 07:03:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test-include/layer2.bin" 2025/04/26 07:03:14 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/04/26 07:03:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test-include/layer2" 2025/04/26 07:03:20 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/04/26 07:03:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-ignore-file" 2025/04/26 07:03:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test-ignore-file.bin" 2025/04/26 07:03:26 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/04/26 07:03:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/rclone-sync-test-ignore-file" 2025/04/26 07:03:32 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/04/26 07:03:38 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 24fd794ecabcc99be9a3e77902f5bae6 OK 2025/04/26 07:03:44 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/26 07:03:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test': Waiting for checks to finish 2025/04/26 07:03:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test': Waiting for transfers to finish 2025/04/26 07:03:44 DEBUG : Waiting for deletions to finish 2025/04/26 07:03:44 INFO : There was nothing to transfer 2025/04/26 07:03:49 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/26 07:03:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:03:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:03:49 DEBUG : Waiting for deletions to finish 2025/04/26 07:03:49 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:03:54 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/26 07:03:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/04/26 07:03:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/04/26 07:03:54 DEBUG : Waiting for deletions to finish 2025/04/26 07:03:54 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:03:59 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/26 07:03:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:03:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:03:59 DEBUG : Waiting for deletions to finish 2025/04/26 07:03:59 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:03:59 DEBUG : : Excluded 2025/04/26 07:03:59 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/26 07:03:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/04/26 07:03:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/04/26 07:03:59 DEBUG : Waiting for deletions to finish 2025/04/26 07:03:59 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (59.69s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:04:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:04:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:04:02 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:04:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:04:08 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:04:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/CompareDest" 2025/04/26 07:04:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/CompareDest.bin" 2025/04/26 07:04:14 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/CompareDest.bin: Auto set use_segments_container to true 2025/04/26 07:04:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/CompareDest" 2025/04/26 07:04:20 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/CompareDest: Auto set use_segments_container to true 2025/04/26 07:04:31 DEBUG : one: Need to transfer - File not found at Destination 2025/04/26 07:04:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:04:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:04:36 DEBUG : one: md5 = 5354cfd8cd71913be6b0a74fb3746179 OK 2025/04/26 07:04:36 INFO : one: Copied (new) 2025/04/26 07:04:36 DEBUG : Waiting for deletions to finish 2025/04/26 07:04:41 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:04:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:04:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:04:52 DEBUG : one: md5 = eaadeda4fe5c70c9ed97355a7e7b1a2e OK 2025/04/26 07:04:52 INFO : one: Copied (replaced existing) 2025/04/26 07:04:52 DEBUG : Waiting for deletions to finish 2025/04/26 07:04:57 DEBUG : dst/one: md5 = cedf32309a1ea710b5eea6799ab40499 OK 2025/04/26 07:05:03 DEBUG : CompareDest/one: md5 = 13528a5eb703e438b0c91b6260681074 OK 2025/04/26 07:05:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:05:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:05:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:08 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/26 07:05:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:05:08 DEBUG : Waiting for deletions to finish 2025/04/26 07:05:08 INFO : There was nothing to transfer 2025/04/26 07:05:09 DEBUG : CompareDest/two: md5 = cfb2de34b799f303974f938c6e5bf28a OK 2025/04/26 07:05:09 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:05:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:09 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/26 07:05:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:05:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:09 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/26 07:05:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:05:09 DEBUG : Waiting for deletions to finish 2025/04/26 07:05:09 INFO : There was nothing to transfer 2025/04/26 07:05:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:05:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:15 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/26 07:05:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:05:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:20 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/26 07:05:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:05:20 DEBUG : Waiting for deletions to finish 2025/04/26 07:05:20 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/04/26 07:05:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:05:25 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/04/26 07:05:25 DEBUG : two: Need to transfer - File not found at Destination 2025/04/26 07:05:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:05:25 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:05:25 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/26 07:05:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:05:25 DEBUG : two: md5 = fd14c3d4e3a62795029740985fa288a5 OK 2025/04/26 07:05:25 INFO : two: Copied (new) 2025/04/26 07:05:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (94.93s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:05:37 DEBUG : pre-dest1/1: md5 = 134e496cfdec4b94522dd4f8a663b9e0 OK 2025/04/26 07:05:42 DEBUG : pre-dest2/2: md5 = ff892bbeb5f177cf13f3cf2aed62d673 OK 2025/04/26 07:05:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dest" 2025/04/26 07:05:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dest.bin" 2025/04/26 07:05:43 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dest.bin: Auto set use_segments_container to true 2025/04/26 07:05:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dest" 2025/04/26 07:05:49 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dest: Auto set use_segments_container to true 2025/04/26 07:05:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/pre-dest1" 2025/04/26 07:05:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/pre-dest1.bin" 2025/04/26 07:05:55 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/pre-dest1.bin: Auto set use_segments_container to true 2025/04/26 07:06:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/pre-dest1" 2025/04/26 07:06:01 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/pre-dest1: Auto set use_segments_container to true 2025/04/26 07:06:06 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/pre-dest2" 2025/04/26 07:06:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/pre-dest2.bin" 2025/04/26 07:06:07 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/pre-dest2.bin: Auto set use_segments_container to true 2025/04/26 07:06:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/pre-dest2" 2025/04/26 07:06:13 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/pre-dest2: Auto set use_segments_container to true 2025/04/26 07:06:19 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:06:19 DEBUG : 1: Destination found in --compare-dest, skipping 2025/04/26 07:06:24 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:06:24 DEBUG : 2: Destination found in --compare-dest, skipping 2025/04/26 07:06:34 DEBUG : 3: Need to transfer - File not found at Destination 2025/04/26 07:06:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dest': Waiting for checks to finish 2025/04/26 07:06:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dest': Waiting for transfers to finish 2025/04/26 07:06:39 DEBUG : 3: md5 = a3a12d0bd06651adac4ebd7d9ed2303e OK 2025/04/26 07:06:39 INFO : 3: Copied (new) 2025/04/26 07:06:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (83.75s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:07:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:07:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:07:01 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:07:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:07:07 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:07:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/CopyDest" 2025/04/26 07:07:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/CopyDest.bin" 2025/04/26 07:07:13 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/CopyDest.bin: Auto set use_segments_container to true 2025/04/26 07:07:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/CopyDest" 2025/04/26 07:07:18 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/CopyDest: Auto set use_segments_container to true 2025/04/26 07:07:29 DEBUG : one: Need to transfer - File not found at Destination 2025/04/26 07:07:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:07:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:07:34 DEBUG : one: md5 = 8259ca848402af7ca5c9a90ad54b4a0a OK 2025/04/26 07:07:34 INFO : one: Copied (new) 2025/04/26 07:07:34 DEBUG : Waiting for deletions to finish 2025/04/26 07:07:40 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:07:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:07:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:07:50 DEBUG : one: md5 = f67668a9aa6821121de0b54bc42b9a51 OK 2025/04/26 07:07:50 INFO : one: Copied (replaced existing) 2025/04/26 07:07:50 DEBUG : Waiting for deletions to finish 2025/04/26 07:08:00 DEBUG : dst/one: md5 = 570f33c0e163467d438e8952afe17039 OK 2025/04/26 07:08:06 DEBUG : CopyDest/one: md5 = e45840fac490fcafb0fd60ecba242e98 OK 2025/04/26 07:08:06 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/BackupDir" 2025/04/26 07:08:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/BackupDir.bin" 2025/04/26 07:08:06 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/BackupDir.bin: Auto set use_segments_container to true 2025/04/26 07:08:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/BackupDir" 2025/04/26 07:08:12 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/BackupDir: Auto set use_segments_container to true 2025/04/26 07:08:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:08:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:08:18 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:08:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/26 07:08:39 INFO : one: Copied (server-side copy) 2025/04/26 07:08:44 INFO : one: Deleted 2025/04/26 07:09:00 INFO : one: Copied (server-side copy) 2025/04/26 07:09:00 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/26 07:09:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:09:00 DEBUG : Waiting for deletions to finish 2025/04/26 07:09:05 DEBUG : CopyDest/two: md5 = 3a423b1a95b82d602e01829656499611 OK 2025/04/26 07:09:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:16 DEBUG : one: Unchanged skipping 2025/04/26 07:09:21 INFO : two: Copied (server-side copy) 2025/04/26 07:09:21 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/04/26 07:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:09:21 DEBUG : Waiting for deletions to finish 2025/04/26 07:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:09:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:21 DEBUG : one: Unchanged skipping 2025/04/26 07:09:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:21 DEBUG : two: Unchanged skipping 2025/04/26 07:09:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:09:21 DEBUG : Waiting for deletions to finish 2025/04/26 07:09:21 INFO : There was nothing to transfer 2025/04/26 07:09:27 DEBUG : CopyDest/three: md5 = 141a78dfedcc6c5048cdaaa02d6c27f9 OK 2025/04/26 07:09:32 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/26 07:09:32 DEBUG : three: Destination not found in --copy-dest 2025/04/26 07:09:32 DEBUG : three: Need to transfer - File not found at Destination 2025/04/26 07:09:32 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:32 DEBUG : one: Unchanged skipping 2025/04/26 07:09:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:09:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:09:32 DEBUG : two: Unchanged skipping 2025/04/26 07:09:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:09:38 DEBUG : three: md5 = 549699bda575f6992fb010783162310e OK 2025/04/26 07:09:38 INFO : three: Copied (new) 2025/04/26 07:09:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (199.11s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:10:25 DEBUG : dst/one: md5 = 97f34ed4e0cc9f281c0d3f800ca3f5d0 OK 2025/04/26 07:10:25 DEBUG : dst/two: md5 = f0495f2f44c48854b16aea3e882d2d2c OK 2025/04/26 07:10:25 DEBUG : dst/three.txt: md5 = 771d447bf934a3500a62271362b5a37a OK 2025/04/26 07:10:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:10:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:10:26 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:10:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:10:32 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:10:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/backup" 2025/04/26 07:10:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup.bin" 2025/04/26 07:10:38 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup.bin: Auto set use_segments_container to true 2025/04/26 07:10:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup" 2025/04/26 07:10:43 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup: Auto set use_segments_container to true 2025/04/26 07:10:49 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:10:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:10:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:10:49 DEBUG : two: Unchanged skipping 2025/04/26 07:11:00 INFO : one: Copied (server-side copy) 2025/04/26 07:11:05 INFO : one: Deleted 2025/04/26 07:11:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:11:10 DEBUG : one: md5 = 5a6d8997ac850ac83eaf20163922fc48 OK 2025/04/26 07:11:10 INFO : one: Copied (new) 2025/04/26 07:11:10 DEBUG : Waiting for deletions to finish 2025/04/26 07:11:20 INFO : three.txt: Copied (server-side copy) 2025/04/26 07:11:20 INFO : three.txt: Deleted 2025/04/26 07:11:20 INFO : three.txt: Moved into backup dir 2025/04/26 07:11:26 DEBUG : dst/three.txt: md5 = a0eb5a12ec92d5f132f56ab5a098d903 OK 2025/04/26 07:11:32 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:11:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:11:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:11:32 DEBUG : two: Unchanged skipping 2025/04/26 07:11:37 INFO : one: Copied (server-side copy) 2025/04/26 07:11:42 INFO : one: Deleted 2025/04/26 07:11:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:11:47 DEBUG : one: md5 = 7b9ebb48d86d79b1f94d72e39c4d16cc OK 2025/04/26 07:11:47 INFO : one: Copied (new) 2025/04/26 07:11:47 DEBUG : Waiting for deletions to finish 2025/04/26 07:11:53 INFO : three.txt: Copied (server-side copy) 2025/04/26 07:11:53 INFO : three.txt: Deleted 2025/04/26 07:11:53 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (99.66s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:12:04 DEBUG : dst/one: md5 = 58e07b33e29fbabde4041a40eaa7d93b OK 2025/04/26 07:12:05 DEBUG : dst/two: md5 = 917ac5a7938be4dfaa8f947bdce3ce45 OK 2025/04/26 07:12:05 DEBUG : dst/three.txt: md5 = 152b0b691b689afd7bd7f02e68522190 OK 2025/04/26 07:12:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:12:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:12:06 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:12:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:12:11 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:12:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/backup" 2025/04/26 07:12:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup.bin" 2025/04/26 07:12:17 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup.bin: Auto set use_segments_container to true 2025/04/26 07:12:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup" 2025/04/26 07:12:23 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup: Auto set use_segments_container to true 2025/04/26 07:12:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:12:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:12:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:12:28 DEBUG : two: Unchanged skipping 2025/04/26 07:12:44 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:12:49 INFO : one: Deleted 2025/04/26 07:12:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:12:54 DEBUG : one: md5 = 0812457a9ee8cb7a99f738a6df7dcf63 OK 2025/04/26 07:12:54 INFO : one: Copied (new) 2025/04/26 07:12:54 DEBUG : Waiting for deletions to finish 2025/04/26 07:13:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:13:05 INFO : three.txt: Deleted 2025/04/26 07:13:05 INFO : three.txt: Moved into backup dir 2025/04/26 07:13:05 DEBUG : dst/three.txt: md5 = 739d8fda25e2e229ddd10e85968b52b9 OK 2025/04/26 07:13:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:13:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:13:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:13:06 DEBUG : two: Unchanged skipping 2025/04/26 07:13:16 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:13:21 INFO : one: Deleted 2025/04/26 07:13:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:13:26 DEBUG : one: md5 = 9d3a57ae868f7fcc28971314e8fd4449 OK 2025/04/26 07:13:26 INFO : one: Copied (new) 2025/04/26 07:13:26 DEBUG : Waiting for deletions to finish 2025/04/26 07:13:32 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:13:32 INFO : three.txt: Deleted 2025/04/26 07:13:32 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (103.73s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:13:48 DEBUG : dst/one: md5 = fb0688d18f9ba62a330a51380bed62a6 OK 2025/04/26 07:13:48 DEBUG : dst/two: md5 = 4af159e17d8eaa737e55f2bbfec508be OK 2025/04/26 07:13:48 DEBUG : dst/three.txt: md5 = 69d578c2e0d5ea1ad58a97ce6285fc91 OK 2025/04/26 07:13:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:13:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:13:54 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:14:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:14:00 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:14:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/backup" 2025/04/26 07:14:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup.bin" 2025/04/26 07:14:06 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup.bin: Auto set use_segments_container to true 2025/04/26 07:14:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/backup" 2025/04/26 07:14:12 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/backup: Auto set use_segments_container to true 2025/04/26 07:14:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:14:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:14:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:14:17 DEBUG : two: Unchanged skipping 2025/04/26 07:14:28 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/04/26 07:14:33 INFO : one: Deleted 2025/04/26 07:14:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:14:38 DEBUG : one: md5 = aac5d82c963def8301a0d64da397d6cd OK 2025/04/26 07:14:38 INFO : one: Copied (new) 2025/04/26 07:14:38 DEBUG : Waiting for deletions to finish 2025/04/26 07:14:54 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/04/26 07:14:54 INFO : three.txt: Deleted 2025/04/26 07:14:54 INFO : three.txt: Moved into backup dir 2025/04/26 07:14:54 DEBUG : dst/three.txt: md5 = 07fc79501078e90de693134dccd67f4a OK 2025/04/26 07:14:55 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:14:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:14:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:14:55 DEBUG : two: Unchanged skipping 2025/04/26 07:15:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/04/26 07:15:11 INFO : one: Deleted 2025/04/26 07:15:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:15:16 DEBUG : one: md5 = 80e1655d39ba9ca97d6049142e6a04d2 OK 2025/04/26 07:15:16 INFO : one: Copied (new) 2025/04/26 07:15:16 DEBUG : Waiting for deletions to finish 2025/04/26 07:15:26 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/04/26 07:15:26 INFO : three.txt: Deleted 2025/04/26 07:15:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (119.54s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:15:48 DEBUG : dst/one: md5 = b526aa25a72e41e6fe3bcd8cb97e4fd4 OK 2025/04/26 07:15:48 DEBUG : dst/two: md5 = 7bc3688cb07548e051a1f2c927b0ee6f OK 2025/04/26 07:15:48 DEBUG : dst/three.txt: md5 = da80318a16c3728c686558988bb1d3ac OK 2025/04/26 07:15:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:15:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:15:49 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:15:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:15:55 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:16:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:16:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:16:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:16:00 DEBUG : two: Unchanged skipping 2025/04/26 07:16:11 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:16:16 INFO : one: Deleted 2025/04/26 07:16:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:16:21 DEBUG : one: md5 = 28bfca2f1ad340d5d42a1e784b10e83d OK 2025/04/26 07:16:21 INFO : one: Copied (new) 2025/04/26 07:16:21 DEBUG : Waiting for deletions to finish 2025/04/26 07:16:32 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:16:33 INFO : three.txt: Deleted 2025/04/26 07:16:33 INFO : three.txt: Moved into backup dir 2025/04/26 07:16:33 DEBUG : dst/three.txt: md5 = e84a67419daa7d09f037635d717483ac OK 2025/04/26 07:16:39 DEBUG : one.bak: Excluded (Path Filter) 2025/04/26 07:16:39 DEBUG : one.bak: Excluded 2025/04/26 07:16:39 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/04/26 07:16:39 DEBUG : three.txt.bak: Excluded 2025/04/26 07:16:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:16:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for checks to finish 2025/04/26 07:16:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:16:39 DEBUG : two: Unchanged skipping 2025/04/26 07:16:44 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:16:49 INFO : one: Deleted 2025/04/26 07:16:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi/dst': Waiting for transfers to finish 2025/04/26 07:16:54 DEBUG : one: md5 = fbeaeec04ebcefa21460e1c5b335f818 OK 2025/04/26 07:16:54 INFO : one: Copied (new) 2025/04/26 07:16:54 DEBUG : Waiting for deletions to finish 2025/04/26 07:17:00 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:17:00 INFO : three.txt: Deleted 2025/04/26 07:17:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (88.90s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:17:16 DEBUG : dst/one: md5 = 1ac1b18efe15e16dd541f6e5357ee971 OK 2025/04/26 07:17:17 DEBUG : dst/two: md5 = 0534291b8b06cbc899640ff3a07372cd OK 2025/04/26 07:17:17 DEBUG : dst/three.txt: md5 = c8144c7fd987ab5d6ccc8ecd4464e743 OK 2025/04/26 07:17:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:17:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:17:18 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:17:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:17:23 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:17:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:17:44 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:17:49 INFO : one: Deleted 2025/04/26 07:17:54 DEBUG : one: md5 = e295d0625d001492add2931a163a6c9b OK 2025/04/26 07:17:54 INFO : one: Copied (new) 2025/04/26 07:17:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:17:54 DEBUG : two: Unchanged skipping 2025/04/26 07:17:54 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/26 07:18:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:18:05 INFO : three.txt: Deleted 2025/04/26 07:18:05 DEBUG : three.txt: md5 = a3707703d920ca47bb14f78d63aa842c OK 2025/04/26 07:18:05 INFO : three.txt: Copied (new) 2025/04/26 07:18:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:18:11 INFO : one: Copied (server-side copy) to: one.bak 2025/04/26 07:18:17 INFO : one: Deleted 2025/04/26 07:18:22 DEBUG : one: md5 = d900343eb56611bc9108235ea6077b51 OK 2025/04/26 07:18:22 INFO : one: Copied (new) 2025/04/26 07:18:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:18:22 DEBUG : two: Unchanged skipping 2025/04/26 07:18:22 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/26 07:18:27 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/04/26 07:18:27 INFO : three.txt: Deleted 2025/04/26 07:18:27 DEBUG : three.txt: md5 = 8877151183c64df0833c07d5e7b71acb OK 2025/04/26 07:18:27 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (87.57s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:18:44 DEBUG : dst/one: md5 = dc7474905a613f44ef0a1f25c746198b OK 2025/04/26 07:18:44 DEBUG : dst/two: md5 = fe288cd1b74d78a5bbdc197a6d61de51 OK 2025/04/26 07:18:44 DEBUG : dst/three.txt: md5 = 9c31e9bad3219ec679e6b95cd75bde14 OK 2025/04/26 07:18:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gitexer1lasi/dst" 2025/04/26 07:18:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst.bin" 2025/04/26 07:18:50 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst.bin: Auto set use_segments_container to true 2025/04/26 07:18:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gitexer1lasi/dst" 2025/04/26 07:18:56 DEBUG : Swift container crypt path rclone-test-gitexer1lasi/dst: Auto set use_segments_container to true 2025/04/26 07:19:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/26 07:19:22 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/04/26 07:19:27 INFO : one: Deleted 2025/04/26 07:19:32 DEBUG : one: md5 = 0f6e5c110c63098e6353b0f16fa1ff93 OK 2025/04/26 07:19:32 INFO : one: Copied (new) 2025/04/26 07:19:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:19:32 DEBUG : two: Unchanged skipping 2025/04/26 07:19:32 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/26 07:19:43 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/04/26 07:19:43 INFO : three.txt: Deleted 2025/04/26 07:19:43 DEBUG : three.txt: md5 = 9495a34e7fe53648486e755f6913bd90 OK 2025/04/26 07:19:43 INFO : three.txt: Copied (new) 2025/04/26 07:19:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/26 07:20:09 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/04/26 07:20:14 INFO : one: Deleted 2025/04/26 07:20:19 DEBUG : one: md5 = b758ca935c3e351094554b444520ab22 OK 2025/04/26 07:20:19 INFO : one: Copied (new) 2025/04/26 07:20:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:20:20 DEBUG : two: Unchanged skipping 2025/04/26 07:20:20 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/26 07:20:25 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/04/26 07:20:25 INFO : three.txt: Deleted 2025/04/26 07:20:25 DEBUG : three.txt: md5 = 7052aab5ba4b40210975e9c3fd7dd857 OK 2025/04/26 07:20:25 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (127.54s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:20:52 DEBUG : Testêé: md5 = f7141503ba5a29c0bd92d6b5d9921617 OK 2025/04/26 07:20:52 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/04/26 07:20:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:20:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:20:57 DEBUG : Testêé: md5 = aeba5849321c6d606ace4fd37a471ab8 OK 2025/04/26 07:20:57 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/04/26 07:20:57 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (16.49s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:21:03 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/26 07:21:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:21:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:21:03 DEBUG : existing: md5 = 5e0900471abaf29bb98fd0012dc97261 OK 2025/04/26 07:21:03 INFO : existing: Copied (new) 2025/04/26 07:21:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:21:04 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/04/26 07:21:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:21:04 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/04/26 07:21:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:21:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': not deleting files as there were IO errors 2025/04/26 07:21:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncImmutable (1.90s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:21:10 DEBUG : EXISTING: md5 = 32238b546cdda54e0c30453f627e6053 OK 2025/04/26 07:21:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:21:15 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:21:15 DEBUG : existing: Unchanged skipping 2025/04/26 07:21:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:21:15 DEBUG : Waiting for deletions to finish 2025/04/26 07:21:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (21.18s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.32s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (0.41s) --- SKIP: TestMaxTransfer/Hard (0.22s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:21:33 DEBUG : both0: md5 = 96a0c0d5b4117f5e400fed77c3a706f5 OK 2025/04/26 07:21:33 DEBUG : only0: md5 = ace6a31e3b7c878684d2459bf2e2ec31 OK 2025/04/26 07:21:39 DEBUG : both1: md5 = e373f4a932adb9e995516aa94ebade9c OK 2025/04/26 07:21:39 DEBUG : only1: md5 = fb8848224809951557f6490f8e2da211 OK 2025/04/26 07:21:45 DEBUG : both2: md5 = 22e533644d220ea469dcb8dbcde81787 OK 2025/04/26 07:21:50 DEBUG : only2: md5 = 81d3af2f2a8febb7cdccc6e92fad7ee0 OK 2025/04/26 07:21:50 DEBUG : both3: md5 = 449f968fee660fade2dcf15543ca1eb4 OK 2025/04/26 07:21:56 DEBUG : only3: md5 = e32d94da512030b3a793a72942979610 OK 2025/04/26 07:21:56 DEBUG : both4: md5 = 07e93162e71f3c23d595d070abdecc95 OK 2025/04/26 07:21:56 DEBUG : only4: md5 = 7a452ff44d75e0ed3970626f45a41e8d OK 2025/04/26 07:21:57 DEBUG : both5: md5 = 6988900a84f9379f74dcea27b49e2048 OK 2025/04/26 07:22:02 DEBUG : only5: md5 = c7f57eb89e37c48369e3952ef223ce91 OK 2025/04/26 07:22:07 DEBUG : both6: md5 = 61999cc55e3635f551d830eef6b7a1fd OK 2025/04/26 07:22:08 DEBUG : only6: md5 = d1090c2f590d3bc4786b7292b8fa97e0 OK 2025/04/26 07:22:14 DEBUG : both7: md5 = fc17945549fb1ab141f6be5e3a7c6273 OK 2025/04/26 07:22:19 DEBUG : only7: md5 = 3f0a4731714ef82e671675a1e85eb40d OK 2025/04/26 07:22:19 DEBUG : both8: md5 = 606362ba987760fe28a80b6565ad48be OK 2025/04/26 07:22:25 DEBUG : only8: md5 = e6e03211c1a55a9fccf2bb7dd14f3334 OK 2025/04/26 07:22:30 DEBUG : both9: md5 = 81476ac75a9c1805cf2fce19daaa05d3 OK 2025/04/26 07:22:31 DEBUG : only9: md5 = e25f1ef0d29097fb61af473c31d3d892 OK 2025/04/26 07:22:36 DEBUG : both10: md5 = dee166e19825b5cf40b2f522d3fd8784 OK 2025/04/26 07:22:41 DEBUG : only10: md5 = e3561e32279f84fd67a7ad8eb0c85ba4 OK 2025/04/26 07:22:42 DEBUG : both11: md5 = df2007b9e7639cb04dbddc0a699530bd OK 2025/04/26 07:22:47 DEBUG : only11: md5 = 0f33fbc2f0fdb2fb8aa7dbc6587d852b OK 2025/04/26 07:22:52 DEBUG : both12: md5 = 85a8e1ec5aba693ea37b5995b1c192da OK 2025/04/26 07:22:58 DEBUG : only12: md5 = bac349fe0e7d80840a2e46c64b74f672 OK 2025/04/26 07:22:58 DEBUG : both13: md5 = 314587d6eb6521076274251e11e87bee OK 2025/04/26 07:22:59 DEBUG : only13: md5 = 12dd5e4d25fb4a356256dd1beca67eed OK 2025/04/26 07:22:59 DEBUG : both14: md5 = 3b86884877e47f0e6207b6fae1b60615 OK 2025/04/26 07:23:04 DEBUG : only14: md5 = cfb13b9bbe5717c3e34b2f04567aa0c9 OK 2025/04/26 07:23:09 DEBUG : both15: md5 = 3374663a8722ef4a9320ef05cdb5241e OK 2025/04/26 07:23:10 DEBUG : only15: md5 = 0f41ff1bd0a3366e8c92724bc97738b8 OK 2025/04/26 07:23:15 DEBUG : both16: md5 = c05c1d9435dcc957b5f7bdaf9655896e OK 2025/04/26 07:23:15 DEBUG : only16: md5 = 4e2f93d0e3567692461875955d781047 OK 2025/04/26 07:23:21 DEBUG : both17: md5 = a029002217ab2c93b5199382baf02d4e OK 2025/04/26 07:23:21 DEBUG : only17: md5 = 4acd79648a5f576145177bc3573af34e OK 2025/04/26 07:23:22 DEBUG : both18: md5 = c2f60d2b7bd4abde04cc0404a3b538e7 OK 2025/04/26 07:23:27 DEBUG : only18: md5 = 88899beaeab01f12798a69c3ba8137e4 OK 2025/04/26 07:23:32 DEBUG : both19: md5 = 4666f9c3180e80af4a5e35606fe4ee67 OK 2025/04/26 07:23:38 DEBUG : only19: md5 = 214dbc0fc508459064bb6ae16a79de4c OK 2025/04/26 07:24:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:24:20 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both1: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both0: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both12: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both11: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both13: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both14: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both15: Unchanged skipping 2025/04/26 07:24:20 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:20 DEBUG : both18: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both10: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both2: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both16: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both3: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both17: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both4: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both5: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both19: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both7: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both8: Unchanged skipping 2025/04/26 07:24:25 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:25 DEBUG : both9: Unchanged skipping 2025/04/26 07:24:30 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:24:30 DEBUG : both6: Unchanged skipping 2025/04/26 07:24:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:24:30 DEBUG : Waiting for deletions to finish 2025/04/26 07:24:30 INFO : only1: Deleted 2025/04/26 07:24:30 INFO : only16: Deleted 2025/04/26 07:24:31 INFO : only4: Deleted 2025/04/26 07:24:35 INFO : only12: Deleted 2025/04/26 07:24:35 INFO : only19: Deleted 2025/04/26 07:24:36 INFO : only15: Deleted 2025/04/26 07:24:36 INFO : only6: Deleted 2025/04/26 07:24:36 INFO : only9: Deleted 2025/04/26 07:24:36 INFO : only0: Deleted 2025/04/26 07:24:40 INFO : only10: Deleted 2025/04/26 07:24:40 INFO : only13: Deleted 2025/04/26 07:24:41 INFO : only3: Deleted 2025/04/26 07:24:41 INFO : only5: Deleted 2025/04/26 07:24:41 INFO : only17: Deleted 2025/04/26 07:24:45 INFO : only14: Deleted 2025/04/26 07:24:46 INFO : only18: Deleted 2025/04/26 07:24:46 INFO : only11: Deleted 2025/04/26 07:24:51 INFO : only7: Deleted 2025/04/26 07:24:51 INFO : only2: Deleted 2025/04/26 07:24:56 INFO : only8: Deleted 2025/04/26 07:24:56 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (318.46s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:26:50 DEBUG : both0: md5 = abcf8a51d95834921f12f57ad8808cd9 OK 2025/04/26 07:26:51 DEBUG : only0: md5 = 2337532c7aff505940fc89644149f4de OK 2025/04/26 07:26:56 DEBUG : both1: md5 = ab1fcb5119b52c36471331fb10241b34 OK 2025/04/26 07:26:56 DEBUG : only1: md5 = 1111cbcf7d8beeefe94bb034d6b74dfe OK 2025/04/26 07:27:01 DEBUG : both2: md5 = 621f680e63791b158f118b8874c2a43c OK 2025/04/26 07:27:06 DEBUG : only2: md5 = 8480197193c18622bfdb78d32f7ce8e9 OK 2025/04/26 07:27:06 DEBUG : both3: md5 = 99277d7329eaaa6e172260c9de299277 OK 2025/04/26 07:27:12 DEBUG : only3: md5 = bb612fe77c5e1f32fd5336402d4e8ade OK 2025/04/26 07:27:12 DEBUG : both4: md5 = b180b5ac345e1ad2330ef48513f1aa40 OK 2025/04/26 07:27:13 DEBUG : only4: md5 = 9c27135bb079fa38fd487c202890d620 OK 2025/04/26 07:27:13 DEBUG : both5: md5 = 798635b01e388cabf073b783ba40edc4 OK 2025/04/26 07:27:18 DEBUG : only5: md5 = dc9aedab3f4751fea698915a056eee1d OK 2025/04/26 07:27:23 DEBUG : both6: md5 = b437da32bc09ce4003d593b649bbb14e OK 2025/04/26 07:27:23 DEBUG : only6: md5 = 4ac4c12a1dc7671102815048debeb47d OK 2025/04/26 07:27:28 DEBUG : both7: md5 = fde9cce83360f0007a64188ad4ad0277 OK 2025/04/26 07:27:34 DEBUG : only7: md5 = 2f901fd732884fc4a953f80a5a329fdb OK 2025/04/26 07:27:34 DEBUG : both8: md5 = 5fe3b60ae6ce2152f010976aa4b3eba8 OK 2025/04/26 07:27:39 DEBUG : only8: md5 = f91f52aaf49edcc40dfe378493940259 OK 2025/04/26 07:27:44 DEBUG : both9: md5 = 7b3b9481c1138f40d2e579dc97b37d55 OK 2025/04/26 07:27:44 DEBUG : only9: md5 = da86f54bfc9a9bef9ed7b763b3ef99af OK 2025/04/26 07:27:50 DEBUG : both10: md5 = fc3ad179d0509141bcf7ef35d4490af6 OK 2025/04/26 07:27:55 DEBUG : only10: md5 = b06213eff7a6452b365ba98b73b4f62a OK 2025/04/26 07:27:55 DEBUG : both11: md5 = 48d30f9078c1e6ce303bb20167fe4801 OK 2025/04/26 07:28:00 DEBUG : only11: md5 = c8f5844d2faa316d8b4b1f6a75359f84 OK 2025/04/26 07:28:05 DEBUG : both12: md5 = bf82962d58699f1974561753a22a4e24 OK 2025/04/26 07:28:10 DEBUG : only12: md5 = 5ec8ccbd06998b484797ee01d8dac968 OK 2025/04/26 07:28:11 DEBUG : both13: md5 = 686c3b133670bd54684ca95f1af0c135 OK 2025/04/26 07:28:11 DEBUG : only13: md5 = 58aef937cd0cb27551c569e605080193 OK 2025/04/26 07:28:11 DEBUG : both14: md5 = 48c6ac27e7f1d1199631c1f2cdf75e0d OK 2025/04/26 07:28:16 DEBUG : only14: md5 = 4a1f489fb0dee7a3128dc310996dafdf OK 2025/04/26 07:28:22 DEBUG : both15: md5 = 9fbfd9d3508dbc57e18709771299e68f OK 2025/04/26 07:28:22 DEBUG : only15: md5 = 312a10c62d4e263a764303ff9ff139e1 OK 2025/04/26 07:28:27 DEBUG : both16: md5 = 561c7941c945fb9413eda99d316d0d04 OK 2025/04/26 07:28:27 DEBUG : only16: md5 = 9c43a542e78586c0d7b820bee21f982d OK 2025/04/26 07:28:32 DEBUG : both17: md5 = 3da19287d083ee377532368410767dc5 OK 2025/04/26 07:28:32 DEBUG : only17: md5 = f369b3f1969a1d867a2014c8d81312fe OK 2025/04/26 07:28:33 DEBUG : both18: md5 = 591e8feb3c669d03622ed8be3c40ff9b OK 2025/04/26 07:28:38 DEBUG : only18: md5 = 0fa0fee53a741b0f34c23e63ac27fefd OK 2025/04/26 07:28:43 DEBUG : both19: md5 = 9c96a7acfc582e01880822a30907d936 OK 2025/04/26 07:28:48 DEBUG : only19: md5 = a9700fda7c4ba51b10ff87a194d153fd OK 2025/04/26 07:29:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for checks to finish 2025/04/26 07:29:35 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both1: Unchanged skipping 2025/04/26 07:29:35 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both0: Unchanged skipping 2025/04/26 07:29:35 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both11: Unchanged skipping 2025/04/26 07:29:35 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both13: Unchanged skipping 2025/04/26 07:29:35 DEBUG : both12: Unchanged skipping 2025/04/26 07:29:35 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:35 DEBUG : both14: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both17: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both18: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both19: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both2: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both3: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both4: Unchanged skipping 2025/04/26 07:29:36 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:36 DEBUG : both5: Unchanged skipping 2025/04/26 07:29:40 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:40 DEBUG : both10: Unchanged skipping 2025/04/26 07:29:40 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:40 DEBUG : both16: Unchanged skipping 2025/04/26 07:29:40 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:40 DEBUG : both15: Unchanged skipping 2025/04/26 07:29:41 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:41 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:41 DEBUG : both9: Unchanged skipping 2025/04/26 07:29:41 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : both8: Unchanged skipping 2025/04/26 07:29:41 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/04/26 07:29:41 DEBUG : only0: md5 = 103b13ccf90610a3e263b261f338ddc2 OK 2025/04/26 07:29:41 INFO : only0: Copied (replaced existing) 2025/04/26 07:29:41 DEBUG : only1: md5 = 54c1f2a5819a6a109c22fdad331b935e OK 2025/04/26 07:29:41 INFO : only1: Copied (replaced existing) 2025/04/26 07:29:41 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:41 DEBUG : both6: Unchanged skipping 2025/04/26 07:29:41 DEBUG : only13: md5 = 7d893612c16709fb5e1f5820acbd8b70 OK 2025/04/26 07:29:41 INFO : only13: Copied (replaced existing) 2025/04/26 07:29:41 DEBUG : only15: md5 = da1eca99ba3c9084db378f4e4087c5aa OK 2025/04/26 07:29:41 INFO : only15: Copied (replaced existing) 2025/04/26 07:29:42 DEBUG : only16: md5 = 5c702d664c36802b3ee68633912aaa0d OK 2025/04/26 07:29:42 INFO : only16: Copied (replaced existing) 2025/04/26 07:29:42 DEBUG : only17: md5 = 4e79158fde6cf94eab103438111c3551 OK 2025/04/26 07:29:42 INFO : only17: Copied (replaced existing) 2025/04/26 07:29:45 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/26 07:29:45 DEBUG : both7: Unchanged skipping 2025/04/26 07:29:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Waiting for transfers to finish 2025/04/26 07:29:46 DEBUG : only11: md5 = 39a2a4852a5627f8e321b1bf94a911c5 OK 2025/04/26 07:29:46 INFO : only11: Copied (replaced existing) 2025/04/26 07:29:47 DEBUG : only18: md5 = fd38e08f5bd200b23aae75b8d0b2539b OK 2025/04/26 07:29:47 INFO : only18: Copied (replaced existing) 2025/04/26 07:29:51 DEBUG : only10: md5 = 81162219b8fded3a76d375a98ac814eb OK 2025/04/26 07:29:51 INFO : only10: Copied (replaced existing) 2025/04/26 07:29:51 DEBUG : only12: md5 = afd8bf9279651c1082e805da6cdb7fef OK 2025/04/26 07:29:51 INFO : only12: Copied (replaced existing) 2025/04/26 07:29:51 DEBUG : only4: md5 = 3540178ac4399d25c361454c27071723 OK 2025/04/26 07:29:51 INFO : only4: Copied (replaced existing) 2025/04/26 07:29:52 DEBUG : only2: md5 = a5781851b57f88ebabae6f30ecb466f1 OK 2025/04/26 07:29:52 INFO : only2: Copied (replaced existing) 2025/04/26 07:29:52 DEBUG : only6: md5 = 9812900fe506ffb2166377ec06c8c8d2 OK 2025/04/26 07:29:52 INFO : only6: Copied (replaced existing) 2025/04/26 07:29:56 DEBUG : only3: md5 = 352a68bf159b4896c426dbfe9d3105d4 OK 2025/04/26 07:29:56 INFO : only3: Copied (replaced existing) 2025/04/26 07:29:56 DEBUG : only19: md5 = 32b2fefce162b6115a34113b573665e9 OK 2025/04/26 07:29:56 INFO : only19: Copied (replaced existing) 2025/04/26 07:29:56 DEBUG : only5: md5 = 15a816a7b09c1570bf6e6faecbfbef8b OK 2025/04/26 07:29:56 INFO : only5: Copied (replaced existing) 2025/04/26 07:29:57 DEBUG : only9: md5 = 3775916d977a0a13ea3ab2db8fcba245 OK 2025/04/26 07:29:57 INFO : only9: Copied (replaced existing) 2025/04/26 07:29:58 DEBUG : only14: md5 = ccfa0231475b5fb6a4150d131497b80c OK 2025/04/26 07:29:58 INFO : only14: Copied (replaced existing) 2025/04/26 07:30:01 DEBUG : only8: md5 = 68993e4570540b091349c4b055c9d62a OK 2025/04/26 07:30:01 INFO : only8: Copied (replaced existing) 2025/04/26 07:30:06 DEBUG : only7: md5 = 934d2e43c662d0a4406fdf629a81266b OK 2025/04/26 07:30:06 INFO : only7: Copied (replaced existing) 2025/04/26 07:30:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (404.90s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:33:30 DEBUG : empty_dir: Making directory with metadata 2025/04/26 07:33:30 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 07:33:30 DEBUG : empty_on_remote: Making directory with metadata 2025/04/26 07:33:30 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 07:33:30 INFO : empty_on_remote: Making directory 2025/04/26 07:33:30 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:33:36 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:36 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:36 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:36 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTime (17.28s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:33:47 DEBUG : empty_dir: Making directory with metadata 2025/04/26 07:33:47 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 07:33:47 DEBUG : empty_on_remote: Making directory with metadata 2025/04/26 07:33:47 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/26 07:33:47 INFO : empty_on_remote: Making directory 2025/04/26 07:33:47 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:33:53 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:53 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:53 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:53 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/04/26 07:33:53 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (17.09s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:34:05 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/26 07:34:05 INFO : sub dir: Making directory 2025/04/26 07:34:05 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-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestNothingToTransferWithEmptyDirs (33.18s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" 2025/04/26 07:34:38 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/26 07:34:38 INFO : sub dir: Making directory 2025/04/26 07:34:38 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-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi'", Local "Local file system at /tmp/rclone348659573", Modify Window "1ns" --- PASS: TestNothingToTransferWithoutEmptyDirs (22.57s) PASS 2025/04/26 07:35:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gitexer1lasi': Purge remote 2025/04/26 07:35:00 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 53m51.108805811s (try 2/5)