"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 2/5) 2025/08/07 01:38:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa" 2025/08/07 01:38:50 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/07 01:38:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa.bin" 2025/08/07 01:38:50 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa.bin: Auto set use_segments_container to true 2025/08/07 01:38:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa" 2025/08/07 01:38:56 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa: Auto set use_segments_container to true 2025/08/07 01:39:01 DEBUG : Creating backend with remote "/tmp/rclone2736969499" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:39:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:39:01 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/08/07 01:39:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:39:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.44s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:39:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/07 01:39:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:39:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:39:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:39:07 DEBUG : sub dir/hello world: md5 = c82e73e7694d459ddc23cc961df723c3 OK 2025/08/07 01:39:07 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:39:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (16.12s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:98: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:39:18 DEBUG : Creating backend with remote "/non-existing" 2025/08/07 01:39:18 DEBUG : Config file has changed externally - reloading 2025/08/07 01:39:18 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/08/07 01:39:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:39:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.29s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:39:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:39:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:39:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:39:34 DEBUG : sub dir/hello world: md5 = 1ab4f96c156af98023fab5058e928991 OK 2025/08/07 01:39:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (26.13s) === RUN TestCopyNoTraverseDeadlock run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:222: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (0.12s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:39:45 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Running all checks before starting transfers 2025/08/07 01:39:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:39:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:39:45 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Checks finished, now starting transfers 2025/08/07 01:39:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:39:50 DEBUG : sub dir/hello world: md5 = 8919d3d730ee219264f78b491f411b76 OK 2025/08/07 01:39:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (16.07s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:40:01 ERROR : Ignoring --no-traverse with sync 2025/08/07 01:40:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:40:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:40:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:40:06 DEBUG : sub dir/hello world: md5 = 6314df8b0623c572b643805fbc691896 OK 2025/08/07 01:40:06 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:40:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (16.14s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:40:17 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/08/07 01:40:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:40:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:40:23 DEBUG : hello world2: md5 = ac6d058b553e94c56a6c2f78f98a27e4 OK 2025/08/07 01:40:23 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (11.34s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:40:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:40:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:40:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:40:34 DEBUG : potato2: md5 = a919e0f3b591ffcdf55d66d2fff86e09 OK 2025/08/07 01:40:34 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (11.02s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:40:44 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:40:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:40:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:40:49 DEBUG : potato2: md5 = 2750781029d82c8fe038b462acafb29d OK 2025/08/07 01:40:49 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (15.76s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:40:55 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/08/07 01:40:55 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/07 01:40:55 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/08/07 01:40:55 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/07 01:40:55 INFO : sub dir2: Making directory 2025/08/07 01:40:55 INFO : sub dir: Making directory 2025/08/07 01:40:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:40:55 INFO : sub dir2/sub sub dir2: Making directory 2025/08/07 01:40:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:40:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:41:00 DEBUG : sub dir/hello world: md5 = 0b1372fc0efa1a08164c3a346e85b02d OK 2025/08/07 01:41:00 INFO : sub dir/hello world: Copied (new) fstest.go:247: Filtering empty directory "sub dir2" fstest.go:247: Filtering empty directory "sub dir2/sub sub dir2" 2025/08/07 01:41:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (16.01s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:41:11 INFO : sub dir2: Making directory 2025/08/07 01:41:11 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/08/07 01:41:11 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/07 01:41:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:41:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:41:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:41:16 DEBUG : sub dir/hello world: md5 = 6a06e81faa38f08113739a9ceb361bee OK 2025/08/07 01:41:16 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (16.22s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:41:27 DEBUG : sub dir2: Making directory with metadata 2025/08/07 01:41:27 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/07 01:41:27 INFO : sub dir2: Making directory 2025/08/07 01:41:27 INFO : sub dir: Making directory 2025/08/07 01:41:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:41:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:41:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:41:33 DEBUG : sub dir/hello world: md5 = 30cabd75e132091cfacf30b38a437145 OK 2025/08/07 01:41:33 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:41:33 INFO : sub dir/hello world: Deleted fstest.go:247: Filtering empty directory "sub dir2" 2025/08/07 01:41:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/08/07 01:41:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (15.95s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:438: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.10s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:41:43 INFO : sub dir2: Making directory 2025/08/07 01:41:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:41:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:41:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:41:49 DEBUG : sub dir/hello world: md5 = 1b8159f127a0649f866525136f8a5f17 OK 2025/08/07 01:41:49 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:41:49 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (11.13s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:41:54 DEBUG : sub dir2: Making directory with metadata 2025/08/07 01:41:54 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/07 01:41:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/07 01:41:54 INFO : sub dir2: Making directory 2025/08/07 01:41:54 INFO : sub dir: Making directory 2025/08/07 01:41:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:41:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:41:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:42:00 DEBUG : sub dir/hello world: md5 = d6ccca3798dfb16d21e659f190e84ff7 OK 2025/08/07 01:42:00 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:42:00 DEBUG : Waiting for deletions to finish fstest.go:247: Filtering empty directory "sub dir2" 2025/08/07 01:42:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (15.81s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:537: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.24s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:42:10 INFO : sub dir2: Making directory 2025/08/07 01:42:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:42:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:42:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:42:16 DEBUG : sub dir/hello world: md5 = c687f79b5f2c14bbf485ff9366b8ed4b OK 2025/08/07 01:42:16 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:42:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (11.07s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:42:27 DEBUG : sub dir/hello world: md5 = 376946a66cf452dc29316c33d05f555c OK 2025/08/07 01:42:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-taxuhob9vifi" 2025/08/07 01:42:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-taxuhob9vifi.bin" 2025/08/07 01:42:32 DEBUG : Swift container crypt path rclone-test-taxuhob9vifi.bin: Auto set use_segments_container to true 2025/08/07 01:42:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-taxuhob9vifi" 2025/08/07 01:42:38 DEBUG : Config file has changed externally - reloading 2025/08/07 01:42:38 DEBUG : Swift container crypt path rclone-test-taxuhob9vifi: Auto set use_segments_container to true sync_test.go:620: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-taxuhob9vifi' 2025/08/07 01:42:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-taxuhob9vifi': Waiting for checks to finish 2025/08/07 01:42:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-taxuhob9vifi': Waiting for transfers to finish 2025/08/07 01:42:49 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:42:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-taxuhob9vifi': Purge remote 2025/08/07 01:42:49 DEBUG : Waiting for deletions to finish 2025/08/07 01:42:50 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (33.33s) === RUN TestCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:43:00 DEBUG : sub dir/hello world: md5 = 18dec457adb21f8ec7d15011d82b1bb3 OK 2025/08/07 01:43:00 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/07 01:43:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:43:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:43:06 DEBUG : sub dir/hello world: md5 = c7d89cb796ffcfd1a4c9beaf149b52f5 OK 2025/08/07 01:43:06 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (16.53s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:43:17 DEBUG : sub dir/hello world: md5 = 781cd673c9c4f9570b4204fe364f2554 OK 2025/08/07 01:43:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-daracex3tafi" 2025/08/07 01:43:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-daracex3tafi.bin" 2025/08/07 01:43:17 DEBUG : Swift container crypt path rclone-test-daracex3tafi.bin: Auto set use_segments_container to true 2025/08/07 01:43:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-daracex3tafi" 2025/08/07 01:43:23 DEBUG : Swift container crypt path rclone-test-daracex3tafi: Auto set use_segments_container to true sync_test.go:656: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi' 2025/08/07 01:43:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:43:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi': Waiting for checks to finish 2025/08/07 01:43:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi': Waiting for transfers to finish 2025/08/07 01:43:39 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:43:44 DEBUG : sub dir/hello world: md5 = 29b454cdcee559fc9ae4c0d51b6864a8 OK 2025/08/07 01:43:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi': Waiting for checks to finish 2025/08/07 01:43:45 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/07 01:43:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi': Waiting for transfers to finish 2025/08/07 01:43:55 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:43:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-daracex3tafi': Purge remote 2025/08/07 01:43:55 DEBUG : Waiting for deletions to finish 2025/08/07 01:44:06 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopyOverSelf (64.73s) === RUN TestMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:44:21 DEBUG : sub dir/hello world: md5 = 7811dcf4da798de23b481dce881e18c5 OK 2025/08/07 01:44:22 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/07 01:44:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:44:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:44:27 DEBUG : sub dir/hello world: md5 = aa7aae1326d52589314e18f63c8e3695 OK 2025/08/07 01:44:27 INFO : sub dir/hello world: Copied (replaced existing) 2025/08/07 01:44:27 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (16.50s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:44:38 DEBUG : sub dir/hello world: md5 = 0e7a8c250bbb024c2f494c6e0ec7c2ac OK 2025/08/07 01:44:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kojekac8caqu" 2025/08/07 01:44:38 DEBUG : Config file has changed externally - reloading 2025/08/07 01:44:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kojekac8caqu.bin" 2025/08/07 01:44:38 DEBUG : Swift container crypt path rclone-test-kojekac8caqu.bin: Auto set use_segments_container to true 2025/08/07 01:44:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kojekac8caqu" 2025/08/07 01:44:44 DEBUG : Swift container crypt path rclone-test-kojekac8caqu: Auto set use_segments_container to true sync_test.go:701: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu' 2025/08/07 01:44:55 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:44:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for checks to finish 2025/08/07 01:44:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for transfers to finish 2025/08/07 01:45:00 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:45:06 DEBUG : sub dir/hello world: md5 = 48097cd632d92e1f7772630646fade6b OK 2025/08/07 01:45:11 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/08/07 01:45:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for checks to finish 2025/08/07 01:45:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for transfers to finish 2025/08/07 01:45:16 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:45:21 INFO : sub dir/hello world: Deleted 2025/08/07 01:45:27 DEBUG : sub dir/hello world: md5 = ba26253460d880a6e8838c933b943821 OK 2025/08/07 01:45:32 DEBUG : testing file moves 2025/08/07 01:45:32 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/08/07 01:45:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for checks to finish 2025/08/07 01:45:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Waiting for transfers to finish 2025/08/07 01:45:37 INFO : sub dir/hello world: Copied (server-side copy) 2025/08/07 01:45:43 INFO : sub dir/hello world: Deleted 2025/08/07 01:45:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kojekac8caqu': Purge remote 2025/08/07 01:45:43 DEBUG : Waiting for deletions to finish 2025/08/07 01:45:43 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideMoveOverSelf (70.48s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:45:48 DEBUG : sub dir/hello world: md5 = 12c21a345000f86dfa0b9563706ad5d0 OK 2025/08/07 01:45:48 ERROR : error listing: directory not found 2025/08/07 01:45:53 INFO : Local file system at /tmp/rclone2736969499: Making directory 2025/08/07 01:45:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:45:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (16.25s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:05 DEBUG : sub dir/hello world: md5 = 287b1d220fba3c1794d89507fd0483e8 OK 2025/08/07 01:46:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:46:05 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for checks to finish 2025/08/07 01:46:05 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for transfers to finish 2025/08/07 01:46:10 DEBUG : sub dir/hello world.adfb51a5.partial: renamed to: sub dir/hello world 2025/08/07 01:46:10 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (16.21s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:16 DEBUG : check sum: Need to transfer - File not found at Destination 2025/08/07 01:46:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:16 DEBUG : check sum: md5 = f3788d06b12c7b6dca6ea96936adb1ec OK 2025/08/07 01:46:16 INFO : check sum: Copied (new) 2025/08/07 01:46:16 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:16 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/08/07 01:46:16 DEBUG : check sum: Size of src and dst objects identical 2025/08/07 01:46:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:17 DEBUG : check sum: Unchanged skipping 2025/08/07 01:46:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:17 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:17 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.40s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:17 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/08/07 01:46:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:17 DEBUG : sizeonly: md5 = 021c97c0147e4ae2903f98c276684c84 OK 2025/08/07 01:46:17 INFO : sizeonly: Copied (new) 2025/08/07 01:46:17 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:18 DEBUG : sizeonly: Sizes identical 2025/08/07 01:46:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:18 DEBUG : sizeonly: Unchanged skipping 2025/08/07 01:46:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:18 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:18 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.23s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:18 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/08/07 01:46:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:24 DEBUG : ignore-size: md5 = aa90e468cdd7360e645f6b5cbe121d09 OK 2025/08/07 01:46:24 INFO : ignore-size: Copied (new) 2025/08/07 01:46:24 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:34 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:46:34 DEBUG : ignore-size: Unchanged skipping 2025/08/07 01:46:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:34 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:34 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (21.29s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:40 DEBUG : existing: md5 = 54037dd5dda3adbf1d43eca41d009fcf OK 2025/08/07 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:41 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:46:41 DEBUG : existing: Unchanged skipping 2025/08/07 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:41 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:41 INFO : There was nothing to transfer 2025/08/07 01:46:41 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/08/07 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:41 DEBUG : existing: md5 = a96f1900312e7d09e8036654eb6a384f OK 2025/08/07 01:46:41 INFO : existing: Copied (replaced existing) 2025/08/07 01:46:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (1.94s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:42 DEBUG : existing: Need to transfer - File not found at Destination 2025/08/07 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:42 DEBUG : existing: md5 = 38868ce5d3e3520f73734fa2236dac61 OK 2025/08/07 01:46:42 INFO : existing: Copied (new) 2025/08/07 01:46:42 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:42 DEBUG : existing: Destination exists, skipping 2025/08/07 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:42 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:42 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.10s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:46:43 DEBUG : b/potato: md5 = 5eb04ac2ec83e394d0a6af2eef8cdf93 OK 2025/08/07 01:46:48 DEBUG : c/non empty space: md5 = 7f491384d9531e7c142491bdaf35996d OK 2025/08/07 01:46:48 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/08/07 01:46:49 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/08/07 01:46:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:46:54 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:46:54 DEBUG : c/non empty space: Unchanged skipping 2025/08/07 01:46:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:46:54 DEBUG : a/potato2: md5 = 34112236d3966fdffde666f2737667f7 OK 2025/08/07 01:46:54 INFO : a/potato2: Copied (new) 2025/08/07 01:46:54 DEBUG : Waiting for deletions to finish 2025/08/07 01:46:54 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (27.33s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:47:15 DEBUG : empty space: md5 = 9342d6dacdd0e3da17d45c17a5d20ef6 OK 2025/08/07 01:47:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:47: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/08/07 01:47:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:47:16 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/08/07 01:47:16 DEBUG : Waiting for deletions to finish 2025/08/07 01:47:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:47: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/08/07 01:47:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:47:26 DEBUG : empty space: md5 = 59d255df509b7d332eef3f757280ad7c OK 2025/08/07 01:47:26 INFO : empty space: Copied (replaced existing) 2025/08/07 01:47:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (26.61s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:1046: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.19s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:47:37 DEBUG : foo: md5 = 5e740309b16464aafde26f94b5ae8a30 OK 2025/08/07 01:47:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:47:37 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/08/07 01:47:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:47:38 DEBUG : foo: md5 = 1a255fd366638c587581c6f79e539919 OK 2025/08/07 01:47:38 INFO : foo: Copied (replaced existing) 2025/08/07 01:47:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (1.37s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:47:43 DEBUG : empty space: md5 = 0a025dda19fc1e8dc0a68d39014b51ad OK 2025/08/07 01:47:44 DEBUG : potato: Need to transfer - File not found at Destination 2025/08/07 01:47:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:47:44 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:47:44 DEBUG : empty space: Unchanged skipping 2025/08/07 01:47:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:47:49 DEBUG : potato: md5 = 6e5b08fa59a41c5344b43a25fbf429e5 OK 2025/08/07 01:47:49 INFO : potato: Copied (new) 2025/08/07 01:47:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (21.55s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:48:05 DEBUG : potato: md5 = a11606eb86a55f26e8eda45dd0c11be3 OK 2025/08/07 01:48:05 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/08/07 01:48:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:48:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:48:10 DEBUG : potato: md5 = 2a92f0301c7adab3b007403ff3a7805e OK 2025/08/07 01:48:10 INFO : potato: Copied (replaced existing) 2025/08/07 01:48:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (21.13s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:48:26 DEBUG : potato: md5 = f44e2e346881398c253410f1fb3f3cc9 OK 2025/08/07 01:48:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:48:31 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/08/07 01:48:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:48:36 DEBUG : potato: md5 = 2933fb59d4051aad79219033bf436b8b OK 2025/08/07 01:48:36 INFO : potato: Copied (replaced existing) 2025/08/07 01:48:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (21.26s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:48:47 DEBUG : potato: md5 = 7ea34acf9d799fd2505b9cb42430c805 OK 2025/08/07 01:48:52 DEBUG : empty space: md5 = 60c3e1655d25ae879a325f5d53ed1637 OK 2025/08/07 01:48:53 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:48:53 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/08/07 01:48:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:48:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:48:53 DEBUG : empty space: Unchanged skipping 2025/08/07 01:48:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:48:53 DEBUG : Waiting for deletions to finish 2025/08/07 01:48:53 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (31.33s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:49:18 DEBUG : potato: md5 = 91432291482601761f1ca7839af08e18 OK 2025/08/07 01:49:24 DEBUG : empty space: md5 = 8d1c12d3dacd2b32cacca91a08ad08d1 OK 2025/08/07 01:49:34 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:49:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:49:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:49:39 DEBUG : empty space: Unchanged skipping 2025/08/07 01:49:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:49:39 DEBUG : potato2: md5 = 82efccc98cca244375f2a049548d7e06 OK 2025/08/07 01:49:39 INFO : potato2: Copied (new) 2025/08/07 01:49:39 DEBUG : Waiting for deletions to finish 2025/08/07 01:49:49 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (51.96s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:50:05 DEBUG : b/potato: md5 = 5b604815c260e9ed2b2f1e9072a58d71 OK 2025/08/07 01:50:11 DEBUG : c/non empty space: md5 = 26f595d54c4a11d0af322b64ebbcd5dd OK 2025/08/07 01:50:11 INFO : d: Making directory 2025/08/07 01:50:11 INFO : d/e: Making directory fstest.go:247: Filtering empty directory "d" fstest.go:247: Filtering empty directory "d/e" 2025/08/07 01:50:16 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/08/07 01:50:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:50:21 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:50:21 DEBUG : c/non empty space: Unchanged skipping 2025/08/07 01:50:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:50:21 DEBUG : a/potato2: md5 = 79ea340c4b8753b327f370fa764b968d OK 2025/08/07 01:50:21 INFO : a/potato2: Copied (new) 2025/08/07 01:50:21 DEBUG : Waiting for deletions to finish 2025/08/07 01:50:21 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (41.67s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:50:47 DEBUG : b/potato: md5 = 019a275da469dd667fb50f6cc3ab99a8 OK 2025/08/07 01:50:52 DEBUG : c/non empty space: md5 = c2e9cc41f0ed68f53203a1db6c66dee9 OK 2025/08/07 01:50:52 INFO : d: Making directory fstest.go:247: Filtering empty directory "d" 2025/08/07 01:50:53 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/08/07 01:50:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:50:53 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:50:53 DEBUG : c/non empty space: Unchanged skipping 2025/08/07 01:50:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:50:58 DEBUG : a/potato2: md5 = 07a38cd3baedecf4a508e9a7db1e8991 OK 2025/08/07 01:50:58 INFO : a/potato2: Copied (new) 2025/08/07 01:50:58 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': not deleting files as there were IO errors 2025/08/07 01:50:58 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': not deleting directories as there were IO errors fstest.go:247: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (27.01s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:51:19 DEBUG : potato: md5 = 233e0a1b5c68cff8922d48fc58e31b24 OK 2025/08/07 01:51:24 DEBUG : empty space: md5 = 676227614026287dbcc3f2ff603d2b7e OK 2025/08/07 01:51:35 DEBUG : Waiting for deletions to finish 2025/08/07 01:51:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:51:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:51:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:51:40 DEBUG : empty space: Unchanged skipping 2025/08/07 01:51:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:51:40 DEBUG : potato2: md5 = 8274e59369a3f83fa9308d48258b3f9f OK 2025/08/07 01:51:40 INFO : potato2: Copied (new) 2025/08/07 01:51:45 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (56.26s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:52:15 DEBUG : potato: md5 = aec845024b8e9a23c67ea80a4a883810 OK 2025/08/07 01:52:21 DEBUG : empty space: md5 = c94bc3861d3f8af838afd4fb15c0d357 OK 2025/08/07 01:52:26 DEBUG : Waiting for deletions to finish 2025/08/07 01:52:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:52:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:52:36 INFO : potato: Deleted 2025/08/07 01:52:36 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:52:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:52:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:52:36 DEBUG : empty space: Unchanged skipping 2025/08/07 01:52:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:52:41 DEBUG : potato2: md5 = 0cf0a113fe1530e53adbd92180745afe OK 2025/08/07 01:52:41 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (51.88s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:53:08 DEBUG : potato: md5 = 29a5b21c24e79d0c57a75a97da468b4e OK 2025/08/07 01:53:08 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:53:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:53:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:53:13 DEBUG : potato2: md5 = 7e00b78ebdffd5d96ef7216fe5dd8268 OK 2025/08/07 01:53:13 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (31.83s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:53:39 DEBUG : potato2: md5 = 138ed02d0afe4438020785877e975590 OK 2025/08/07 01:53:44 DEBUG : empty space: md5 = 25ae234f00ab13d48506e01b8d0cf255 OK 2025/08/07 01:53:50 DEBUG : enormous: Excluded (Size Filter) 2025/08/07 01:53:50 DEBUG : enormous: Excluded 2025/08/07 01:53:50 DEBUG : potato2: Excluded (Size Filter) 2025/08/07 01:53:50 DEBUG : potato2: Excluded 2025/08/07 01:53:50 DEBUG : potato2: Excluded (Size Filter) 2025/08/07 01:53:50 DEBUG : potato2: Excluded 2025/08/07 01:53:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:53:50 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:53:50 DEBUG : empty space: Unchanged skipping 2025/08/07 01:53:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:53:50 DEBUG : Waiting for deletions to finish 2025/08/07 01:53:50 INFO : There was nothing to transfer 2025/08/07 01:54:00 DEBUG : enormous: Excluded (Size Filter) 2025/08/07 01:54:00 DEBUG : enormous: Excluded 2025/08/07 01:54:00 DEBUG : potato2: Excluded (Size Filter) 2025/08/07 01:54:00 DEBUG : potato2: Excluded 2025/08/07 01:54:00 DEBUG : potato2: Excluded (Size Filter) 2025/08/07 01:54:00 DEBUG : potato2: Excluded 2025/08/07 01:54:00 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for checks to finish 2025/08/07 01:54:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:54:00 DEBUG : empty space: Unchanged skipping 2025/08/07 01:54:00 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for transfers to finish 2025/08/07 01:54:00 DEBUG : Waiting for deletions to finish 2025/08/07 01:54:00 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (46.42s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:54:25 DEBUG : potato2: md5 = 61a96427c42c423e22fa826d8994910e OK 2025/08/07 01:54:31 DEBUG : empty space: md5 = 8421e9e344cb0b4386eb74dbf1cf2a0a OK 2025/08/07 01:54:37 DEBUG : enormous: md5 = e8950e68749aa5fa7ff32e3868efbe37 OK 2025/08/07 01:54:37 DEBUG : enormous: Excluded (Size Filter) 2025/08/07 01:54:37 DEBUG : enormous: Excluded 2025/08/07 01:54:37 DEBUG : potato2: Excluded (Size Filter) 2025/08/07 01:54:37 DEBUG : potato2: Excluded 2025/08/07 01:54:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:54:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:54:37 DEBUG : empty space: Unchanged skipping 2025/08/07 01:54:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:54:37 DEBUG : Waiting for deletions to finish 2025/08/07 01:54:42 INFO : enormous: Deleted 2025/08/07 01:54:43 INFO : potato2: Deleted 2025/08/07 01:54:43 INFO : There was nothing to transfer 2025/08/07 01:54:43 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for checks to finish 2025/08/07 01:54:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:54:48 DEBUG : empty space: Unchanged skipping 2025/08/07 01:54:48 DEBUG : Local file system at /tmp/rclone2736969499: Waiting for transfers to finish 2025/08/07 01:54:48 DEBUG : Waiting for deletions to finish 2025/08/07 01:54:48 INFO : enormous: Deleted 2025/08/07 01:54:48 INFO : potato2: Deleted 2025/08/07 01:54:48 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (37.75s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:55:03 DEBUG : one: md5 = 06de5b0d2bd505f3c2b5d8cff818c2f0 OK 2025/08/07 01:55:04 DEBUG : two: md5 = 318844f8f8f49a7f8287788fa5f57a65 OK 2025/08/07 01:55:09 DEBUG : three: md5 = 04d213fa18216b8537f4e63eb53c952c OK 2025/08/07 01:55:09 DEBUG : four: md5 = 6ca913dbb759fd2f9c47072ac284e07a OK 2025/08/07 01:55:10 DEBUG : five: Need to transfer - File not found at Destination 2025/08/07 01:55:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:55:10 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/08/07 01:55:10 DEBUG : one: Destination is newer than source, skipping 2025/08/07 01:55:10 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/08/07 01:55:10 DEBUG : four: md5 = 33052bfb3dd930176b94079cda1d806a OK 2025/08/07 01:55:10 INFO : four: Copied (replaced existing) 2025/08/07 01:55:10 DEBUG : two: md5 = 319b0ee8bad0942020887137482f4bf0 OK 2025/08/07 01:55:10 INFO : two: Copied (replaced existing) 2025/08/07 01:55:10 DEBUG : five: md5 = 3e3e13252c8ed7478a1af22b0d30bd27 OK 2025/08/07 01:55:10 INFO : five: Copied (new) 2025/08/07 01:55:15 DEBUG : three: Sizes identical 2025/08/07 01:55:15 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/08/07 01:55:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:55:15 DEBUG : Waiting for deletions to finish sync_test.go:1479: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (38.12s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1496: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1496: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:1568: Can track renames: false 2025/08/07 01:55:36 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Ignoring --track-renames as the source and destination do not have a common hash 2025/08/07 01:55:36 DEBUG : potato: Need to transfer - File not found at Destination 2025/08/07 01:55:36 DEBUG : yam: Need to transfer - File not found at Destination 2025/08/07 01:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:55:42 DEBUG : potato: md5 = 56904a50362f97195bc646afae59afe0 OK 2025/08/07 01:55:42 INFO : potato: Copied (new) 2025/08/07 01:55:42 DEBUG : yam: md5 = c2764c1e284fede8df6ad7ca2552dc57 OK 2025/08/07 01:55:42 INFO : yam: Copied (new) 2025/08/07 01:55:42 DEBUG : Waiting for deletions to finish 2025/08/07 01:55:42 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Ignoring --track-renames as the source and destination do not have a common hash 2025/08/07 01:55:42 DEBUG : yaml: Need to transfer - File not found at Destination 2025/08/07 01:55:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:55:42 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:55:42 DEBUG : potato: Unchanged skipping 2025/08/07 01:55:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:55:47 DEBUG : yaml: md5 = a68f7d7367f90afc4b024d99d0255e11 OK 2025/08/07 01:55:47 INFO : yaml: Copied (new) 2025/08/07 01:55:47 DEBUG : Waiting for deletions to finish 2025/08/07 01:55:53 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (36.94s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:1640: Can track renames: true 2025/08/07 01:56:13 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Making map for --track-renames 2025/08/07 01:56:13 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Finished making map for --track-renames 2025/08/07 01:56:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:56:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/07 01:56:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/08/07 01:56:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for renames to finish 2025/08/07 01:56:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:56:18 DEBUG : potato: md5 = ef1c900a502d4369d7c507e0b6f98e45 OK 2025/08/07 01:56:18 INFO : potato: Copied (new) 2025/08/07 01:56:18 DEBUG : yam: md5 = 7f6f548ab8c458e0bea35dd358e7350d OK 2025/08/07 01:56:18 INFO : yam: Copied (new) 2025/08/07 01:56:18 DEBUG : Waiting for deletions to finish 2025/08/07 01:56:19 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Making map for --track-renames 2025/08/07 01:56:19 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Finished making map for --track-renames 2025/08/07 01:56:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:56:19 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:56:19 DEBUG : potato: Unchanged skipping 2025/08/07 01:56:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for renames to finish 2025/08/07 01:56:34 INFO : yam: Copied (server-side copy) to: yaml 2025/08/07 01:56:39 INFO : yam: Deleted 2025/08/07 01:56:39 INFO : yaml: Renamed from "yam" 2025/08/07 01:56:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:56:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (41.72s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:1679: Can track renames: true 2025/08/07 01:56:55 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Making map for --track-renames 2025/08/07 01:56:55 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Finished making map for --track-renames 2025/08/07 01:56:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:56:55 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/08/07 01:56:55 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/08/07 01:56:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for renames to finish 2025/08/07 01:56:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:56:55 DEBUG : sub/yam: md5 = 7da414b0340cb4d0a301f12f472e9886 OK 2025/08/07 01:56:55 INFO : sub/yam: Copied (new) 2025/08/07 01:57:00 DEBUG : potato: md5 = 3a5aa6e86b863559d330c24550cda0fa OK 2025/08/07 01:57:00 INFO : potato: Copied (new) 2025/08/07 01:57:00 DEBUG : Waiting for deletions to finish 2025/08/07 01:57:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Making map for --track-renames 2025/08/07 01:57:01 INFO : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Finished making map for --track-renames 2025/08/07 01:57:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:57:06 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:57:06 DEBUG : potato: Unchanged skipping 2025/08/07 01:57:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for renames to finish 2025/08/07 01:57:16 INFO : sub/yam: Copied (server-side copy) to: yam 2025/08/07 01:57:16 INFO : sub/yam: Deleted 2025/08/07 01:57:16 INFO : yam: Renamed from "sub/yam" 2025/08/07 01:57:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:57:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (31.84s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:57:27 DEBUG : Creating backend with remote "/tmp/rclone2736969499/dir1" 2025/08/07 01:57:27 DEBUG : Config file has changed externally - reloading 2025/08/07 01:57:27 DEBUG : Creating backend with remote "/tmp/rclone2736969499/dir2" 2025/08/07 01:57:27 DEBUG : Local file system at /tmp/rclone2736969499/dir2: Using server-side directory move 2025/08/07 01:57:27 INFO : Local file system at /tmp/rclone2736969499/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/08/07 01:57:27 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/08/07 01:57:27 INFO : file1.txt: Moved (server-side) 2025/08/07 01:57:27 DEBUG : Local file system at /tmp/rclone2736969499/dir2: Waiting for checks to finish 2025/08/07 01:57:27 DEBUG : Local file system at /tmp/rclone2736969499/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.22s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:57:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:57:27 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/08/07 01:57:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:57:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:57:32 DEBUG : sub dir/hello world: md5 = 3bf072eea8857af703683e6252061569 OK 2025/08/07 01:57:32 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:57:32 INFO : sub dir/hello world: Deleted 2025/08/07 01:57:32 DEBUG : nested/sub dir/file: md5 = 98bb400b0ed510a50aa4ec53a5d8b992 OK 2025/08/07 01:57:32 INFO : nested/sub dir/file: Copied (new) 2025/08/07 01:57:32 INFO : nested/sub dir/file: Deleted 2025/08/07 01:57:32 INFO : sub dir: Removing directory 2025/08/07 01:57:32 INFO : nested/sub dir: Removing directory 2025/08/07 01:57:32 INFO : nested: Removing directory 2025/08/07 01:57:32 DEBUG : Local file system at /tmp/rclone2736969499: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (31.12s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:57:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/08/07 01:57:58 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/08/07 01:57:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:57:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:58:03 DEBUG : nested/sub dir/file: md5 = d7180392c35757f853ded47adc70a14a OK 2025/08/07 01:58:03 INFO : nested/sub dir/file: Copied (new) 2025/08/07 01:58:03 INFO : nested/sub dir/file: Deleted 2025/08/07 01:58:03 DEBUG : sub dir/hello world: md5 = 9bc90c8af0280d51039d2d98e4fc988b OK 2025/08/07 01:58:03 INFO : sub dir/hello world: Copied (new) 2025/08/07 01:58:03 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (21.43s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:58:20 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/08/07 01:58:20 DEBUG : existing: Need to transfer - File not found at Destination 2025/08/07 01:58:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:58:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:58:20 DEBUG : existing: md5 = fe205e554a2ad0b7272ac8c5cca6f7ab OK 2025/08/07 01:58:20 INFO : existing: Copied (new) 2025/08/07 01:58:20 INFO : existing: Deleted 2025/08/07 01:58:25 DEBUG : existing-b: md5 = 3a408b13e5fc19f076cb80d52cb63197 OK 2025/08/07 01:58:25 INFO : existing-b: Copied (new) 2025/08/07 01:58:25 INFO : existing-b: Deleted 2025/08/07 01:58:30 DEBUG : existing: Destination exists, skipping 2025/08/07 01:58:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 01:58:30 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/08/07 01:58:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 01:58:30 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (16.86s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 01:58:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nacalaz0voru" 2025/08/07 01:58:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nacalaz0voru.bin" 2025/08/07 01:58:37 DEBUG : Swift container crypt path rclone-test-nacalaz0voru.bin: Auto set use_segments_container to true 2025/08/07 01:58:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nacalaz0voru" 2025/08/07 01:58:42 DEBUG : Swift container crypt path rclone-test-nacalaz0voru: Auto set use_segments_container to true 2025/08/07 01:58:53 DEBUG : potato2: md5 = ed4dfc4c8081be556c29e995a98528ee OK 2025/08/07 01:58:58 DEBUG : empty space: md5 = f29e800f0ca160facbaaea05c2d51ec2 OK 2025/08/07 01:59:04 DEBUG : potato3: md5 = 987408bdcfbb83506042858df61f1c40 OK sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-nacalaz0voru' 2025/08/07 01:59:10 DEBUG : empty space: md5 = dbcf366b012bc19c106897d28123ab49 OK 2025/08/07 01:59:15 DEBUG : potato3: md5 = ed9c7a7521d74284da26d2ced5c972ca OK 2025/08/07 01:59:16 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 01:59:16 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/07 01:59:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nacalaz0voru': Waiting for checks to finish 2025/08/07 01:59:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 01:59:16 DEBUG : empty space: Unchanged skipping 2025/08/07 01:59:21 INFO : empty space: Deleted 2025/08/07 01:59:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nacalaz0voru': Waiting for transfers to finish 2025/08/07 01:59:27 INFO : potato3: Copied (server-side copy) 2025/08/07 01:59:32 INFO : potato2: Copied (server-side copy) 2025/08/07 01:59:32 INFO : potato3: Deleted 2025/08/07 01:59:37 INFO : potato2: Deleted 2025/08/07 01:59:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zideqil3tifa" 2025/08/07 01:59:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zideqil3tifa.bin" 2025/08/07 01:59:58 DEBUG : Swift container crypt path rclone-test-zideqil3tifa.bin: Auto set use_segments_container to true 2025/08/07 02:00:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zideqil3tifa" 2025/08/07 02:00:18 DEBUG : Swift container crypt path rclone-test-zideqil3tifa: Auto set use_segments_container to true 2025/08/07 02:00:24 DEBUG : empty space: Need to transfer - File not found at Destination 2025/08/07 02:00:24 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 02:00:24 DEBUG : potato3: Need to transfer - File not found at Destination 2025/08/07 02:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zideqil3tifa': Waiting for checks to finish 2025/08/07 02:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zideqil3tifa': Waiting for transfers to finish 2025/08/07 02:00:34 INFO : empty space: Copied (server-side copy) 2025/08/07 02:00:35 INFO : empty space: Deleted 2025/08/07 02:00:35 INFO : potato3: Copied (server-side copy) 2025/08/07 02:00:35 INFO : potato2: Copied (server-side copy) 2025/08/07 02:00:40 INFO : potato3: Deleted 2025/08/07 02:00:40 INFO : potato2: Deleted 2025/08/07 02:00:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zideqil3tifa': Purge remote 2025/08/07 02:00:41 DEBUG : Waiting for deletions to finish 2025/08/07 02:00:46 INFO : potato3.bin: Deleted 2025/08/07 02:00:51 INFO : empty space.bin: Deleted 2025/08/07 02:00:51 INFO : potato2.bin: Deleted 2025/08/07 02:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nacalaz0voru': Purge remote 2025/08/07 02:00:51 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (135.17s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:00:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-todifiy7jiqe" 2025/08/07 02:00:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-todifiy7jiqe.bin" 2025/08/07 02:00:52 DEBUG : Swift container crypt path rclone-test-todifiy7jiqe.bin: Auto set use_segments_container to true 2025/08/07 02:00:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-todifiy7jiqe" 2025/08/07 02:00:58 DEBUG : Swift container crypt path rclone-test-todifiy7jiqe: Auto set use_segments_container to true 2025/08/07 02:01:08 DEBUG : potato2: md5 = 6cba92f4c4d15bcaa0c09f3c550a99b8 OK 2025/08/07 02:01:13 DEBUG : empty space: md5 = 483efae7dff0ec2c88c1d6f3a1e5d874 OK 2025/08/07 02:01:18 DEBUG : potato3: md5 = 804b7036cda2e64f8027decdf93cb97f OK sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-todifiy7jiqe' 2025/08/07 02:01:29 DEBUG : empty space: md5 = 7cea8cb7ceca9ba51b1b557eb2336e73 OK 2025/08/07 02:01:34 DEBUG : potato3: md5 = a7f5c134e141a5c05d41fc22588ac954 OK 2025/08/07 02:01:40 DEBUG : empty space: Excluded (Size Filter) 2025/08/07 02:01:40 DEBUG : empty space: Excluded 2025/08/07 02:01:40 DEBUG : empty space: Excluded (Size Filter) 2025/08/07 02:01:40 DEBUG : empty space: Excluded 2025/08/07 02:01:40 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 02:01:40 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/07 02:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-todifiy7jiqe': Waiting for checks to finish 2025/08/07 02:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-todifiy7jiqe': Waiting for transfers to finish 2025/08/07 02:01:55 INFO : potato3: Copied (server-side copy) 2025/08/07 02:02:00 INFO : potato2: Copied (server-side copy) 2025/08/07 02:02:00 INFO : potato3: Deleted 2025/08/07 02:02:05 INFO : potato2: Deleted 2025/08/07 02:02:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gicohiz6hefa" 2025/08/07 02:02:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gicohiz6hefa.bin" 2025/08/07 02:02:16 DEBUG : Swift container crypt path rclone-test-gicohiz6hefa.bin: Auto set use_segments_container to true 2025/08/07 02:02:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gicohiz6hefa" 2025/08/07 02:02:22 DEBUG : Swift container crypt path rclone-test-gicohiz6hefa: Auto set use_segments_container to true 2025/08/07 02:02:27 DEBUG : empty space: Excluded (Size Filter) 2025/08/07 02:02:27 DEBUG : empty space: Excluded 2025/08/07 02:02:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 02:02:27 DEBUG : potato3: Need to transfer - File not found at Destination 2025/08/07 02:02:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gicohiz6hefa': Waiting for checks to finish 2025/08/07 02:02:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gicohiz6hefa': Waiting for transfers to finish 2025/08/07 02:02:32 INFO : potato2: Copied (server-side copy) 2025/08/07 02:02:38 INFO : potato2: Deleted 2025/08/07 02:02:38 INFO : potato3: Copied (server-side copy) 2025/08/07 02:02:43 INFO : potato3: Deleted 2025/08/07 02:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gicohiz6hefa': Purge remote 2025/08/07 02:02:48 DEBUG : Waiting for deletions to finish 2025/08/07 02:02:48 INFO : potato2.bin: Deleted 2025/08/07 02:02:53 INFO : potato3.bin: Deleted 2025/08/07 02:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-todifiy7jiqe': Purge remote 2025/08/07 02:02:53 DEBUG : Waiting for deletions to finish 2025/08/07 02:02:59 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (132.85s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:03:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lateqel2higo" 2025/08/07 02:03:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lateqel2higo.bin" 2025/08/07 02:03:05 DEBUG : Swift container crypt path rclone-test-lateqel2higo.bin: Auto set use_segments_container to true 2025/08/07 02:03:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lateqel2higo" 2025/08/07 02:03:10 DEBUG : Swift container crypt path rclone-test-lateqel2higo: Auto set use_segments_container to true 2025/08/07 02:03:21 DEBUG : potato2: md5 = 5ba01dec0369c0a375cdaa98112bda34 OK 2025/08/07 02:03:26 DEBUG : empty space: md5 = 2022809223ee552f0eda58154e372d1a OK 2025/08/07 02:03:34 DEBUG : potato3: md5 = 55eeb757db49bbd91d7493de4dadea69 OK 2025/08/07 02:03:34 INFO : tomatoDir: Making directory sync_test.go:1735: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa' -> Encrypted drive 'TestCryptSwift:rclone-test-lateqel2higo' 2025/08/07 02:03:45 DEBUG : empty space: md5 = 7c61662fde3f0840010207e903f9fd69 OK 2025/08/07 02:03:46 DEBUG : potato3: md5 = 638f48694b95069cbe8454b1c9d9625c OK 2025/08/07 02:03:46 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 02:03:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/08/07 02:03:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lateqel2higo': Waiting for checks to finish 2025/08/07 02:03:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:03:47 DEBUG : empty space: Unchanged skipping 2025/08/07 02:03:52 INFO : empty space: Deleted 2025/08/07 02:03:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lateqel2higo': Waiting for transfers to finish 2025/08/07 02:03:57 INFO : potato3: Copied (server-side copy) 2025/08/07 02:04:02 INFO : potato3: Deleted 2025/08/07 02:04:02 INFO : potato2: Copied (server-side copy) 2025/08/07 02:04:07 INFO : potato2: Deleted 2025/08/07 02:04:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xejocum6jida" 2025/08/07 02:04:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xejocum6jida.bin" 2025/08/07 02:04:09 DEBUG : Swift container crypt path rclone-test-xejocum6jida.bin: Auto set use_segments_container to true 2025/08/07 02:04:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xejocum6jida" 2025/08/07 02:04:29 DEBUG : Swift container crypt path rclone-test-xejocum6jida: Auto set use_segments_container to true 2025/08/07 02:04:35 INFO : tomatoDir: Making directory 2025/08/07 02:04:35 DEBUG : empty space: Need to transfer - File not found at Destination 2025/08/07 02:04:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/07 02:04:35 DEBUG : potato3: Need to transfer - File not found at Destination 2025/08/07 02:04:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xejocum6jida': Waiting for checks to finish 2025/08/07 02:04:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xejocum6jida': Waiting for transfers to finish 2025/08/07 02:04:41 INFO : potato3: Copied (server-side copy) 2025/08/07 02:04:41 INFO : potato3: Deleted 2025/08/07 02:04:46 INFO : potato2: Copied (server-side copy) 2025/08/07 02:04:50 INFO : empty space: Copied (server-side copy) 2025/08/07 02:04:50 INFO : empty space: Deleted 2025/08/07 02:04:51 INFO : potato2: Deleted 2025/08/07 02:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xejocum6jida': Purge remote 2025/08/07 02:04:51 DEBUG : Waiting for deletions to finish 2025/08/07 02:04:51 INFO : potato3.bin: Deleted 2025/08/07 02:04:57 INFO : potato2.bin: Deleted 2025/08/07 02:04:58 INFO : empty space.bin: Deleted 2025/08/07 02:04:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lateqel2higo': Purge remote 2025/08/07 02:04:58 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (113.66s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:04:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/rclone-move-test" 2025/08/07 02:04:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-move-test.bin" 2025/08/07 02:04:58 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-move-test.bin: Auto set use_segments_container to true 2025/08/07 02:05:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-move-test" 2025/08/07 02:05:19 DEBUG : Config file has changed externally - reloading 2025/08/07 02:05:19 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-move-test: Auto set use_segments_container to true 2025/08/07 02:05:30 DEBUG : potato2: md5 = 68571931f7ebb956b12b37c14967117e OK --- PASS: TestServerSideMoveOverlap (37.37s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:05:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test" 2025/08/07 02:05:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test.bin" 2025/08/07 02:05:36 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test.bin: Auto set use_segments_container to true 2025/08/07 02:05:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test" 2025/08/07 02:05:41 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test: Auto set use_segments_container to true --- PASS: TestSyncOverlap (26.59s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:06:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test" 2025/08/07 02:06:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test.bin" 2025/08/07 02:06:02 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test.bin: Auto set use_segments_container to true 2025/08/07 02:06:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test" 2025/08/07 02:06:08 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test: Auto set use_segments_container to true 2025/08/07 02:06:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2" 2025/08/07 02:06:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2.bin" 2025/08/07 02:06:14 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2.bin: Auto set use_segments_container to true 2025/08/07 02:06:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2" 2025/08/07 02:06:19 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2: Auto set use_segments_container to true 2025/08/07 02:06:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file" 2025/08/07 02:06:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file.bin" 2025/08/07 02:06:30 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file.bin: Auto set use_segments_container to true 2025/08/07 02:06:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file" 2025/08/07 02:06:35 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file: Auto set use_segments_container to true 2025/08/07 02:06:50 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = a7a831adc287d4840f16b9444c1e793b OK 2025/08/07 02:07:00 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/07 02:07:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test': Waiting for checks to finish 2025/08/07 02:07:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test': Waiting for transfers to finish 2025/08/07 02:07:00 DEBUG : Waiting for deletions to finish 2025/08/07 02:07:00 INFO : There was nothing to transfer 2025/08/07 02:07:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/07 02:07:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:07:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:07:11 DEBUG : Waiting for deletions to finish 2025/08/07 02:07:11 INFO : There was nothing to transfer 2025/08/07 02:07:21 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/07 02:07:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/08/07 02:07:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/08/07 02:07:21 DEBUG : Waiting for deletions to finish 2025/08/07 02:07:21 INFO : There was nothing to transfer 2025/08/07 02:07:27 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/07 02:07:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:07:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:07:27 DEBUG : Waiting for deletions to finish 2025/08/07 02:07:27 INFO : There was nothing to transfer 2025/08/07 02:07:32 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/08/07 02:07:32 DEBUG : Excluded 2025/08/07 02:07:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/08/07 02:07:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/08/07 02:07:32 DEBUG : Waiting for deletions to finish 2025/08/07 02:07:32 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (95.03s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:07:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:07:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:07:37 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:07:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:07:43 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:07:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/CompareDest" 2025/08/07 02:07:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/CompareDest.bin" 2025/08/07 02:07:49 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/CompareDest.bin: Auto set use_segments_container to true 2025/08/07 02:07:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/CompareDest" 2025/08/07 02:07:54 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/CompareDest: Auto set use_segments_container to true 2025/08/07 02:08:10 DEBUG : one: Need to transfer - File not found at Destination 2025/08/07 02:08:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:08:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:08:15 DEBUG : one: md5 = 72b7bc99ff32769992110ff93ec2f7b6 OK 2025/08/07 02:08:15 INFO : one: Copied (new) 2025/08/07 02:08:15 DEBUG : Waiting for deletions to finish 2025/08/07 02:08:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:08:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:08:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:08:54 DEBUG : one: md5 = 680aaa534232949d90baa3b607ffbaa4 OK 2025/08/07 02:08:54 INFO : one: Copied (replaced existing) 2025/08/07 02:08:54 DEBUG : Waiting for deletions to finish 2025/08/07 02:08:59 DEBUG : dst/one: md5 = 8949a6bedc476957a7f8ebb1ffb000bc OK 2025/08/07 02:09:05 DEBUG : CompareDest/one: md5 = 44c335b00112b869551d84c950e6d84a OK 2025/08/07 02:09:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:09:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:09:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:10 DEBUG : one: Destination found in --compare-dest, skipping 2025/08/07 02:09:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:09:10 DEBUG : Waiting for deletions to finish 2025/08/07 02:09:10 INFO : There was nothing to transfer 2025/08/07 02:09:21 DEBUG : CompareDest/two: md5 = d4f9cecddaf4207e6742befeed35bb94 OK 2025/08/07 02:09:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:09:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:26 DEBUG : two: Destination found in --compare-dest, skipping 2025/08/07 02:09:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:09:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:26 DEBUG : one: Destination found in --compare-dest, skipping 2025/08/07 02:09:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:09:26 DEBUG : Waiting for deletions to finish 2025/08/07 02:09:26 INFO : There was nothing to transfer 2025/08/07 02:09:31 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:09:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:31 DEBUG : two: Destination found in --compare-dest, skipping 2025/08/07 02:09:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:09:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:36 DEBUG : one: Destination found in --compare-dest, skipping 2025/08/07 02:09:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:09:36 DEBUG : Waiting for deletions to finish 2025/08/07 02:09:36 INFO : There was nothing to transfer sync_test.go:2205: No hash on uploaded file so skipping compare timestamp test 2025/08/07 02:09:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:09:42 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/08/07 02:09:42 DEBUG : two: Need to transfer - File not found at Destination 2025/08/07 02:09:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:09:42 DEBUG : one: Destination found in --compare-dest, skipping 2025/08/07 02:09:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:09:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:09:47 DEBUG : two: md5 = 652f299bd7eef4d95c7dc6d2918392b6 OK 2025/08/07 02:09:47 INFO : two: Copied (new) 2025/08/07 02:09:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (171.16s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:10:33 DEBUG : pre-dest1/1: md5 = 16d5e799f6e7315b52d36cd1e80d31d6 OK 2025/08/07 02:10:34 DEBUG : pre-dest2/2: md5 = d349ba0cd3136a5637caa6d49451408c OK 2025/08/07 02:10:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dest" 2025/08/07 02:10:34 DEBUG : Config file has changed externally - reloading 2025/08/07 02:10:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dest.bin" 2025/08/07 02:10:34 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dest.bin: Auto set use_segments_container to true 2025/08/07 02:10:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dest" 2025/08/07 02:10:40 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dest: Auto set use_segments_container to true 2025/08/07 02:10:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/pre-dest1" 2025/08/07 02:10:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/pre-dest1.bin" 2025/08/07 02:10:46 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/pre-dest1.bin: Auto set use_segments_container to true 2025/08/07 02:10:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/pre-dest1" 2025/08/07 02:10:51 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/pre-dest1: Auto set use_segments_container to true 2025/08/07 02:10:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/pre-dest2" 2025/08/07 02:10:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/pre-dest2.bin" 2025/08/07 02:10:57 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/pre-dest2.bin: Auto set use_segments_container to true 2025/08/07 02:11:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/pre-dest2" 2025/08/07 02:11:03 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/pre-dest2: Auto set use_segments_container to true 2025/08/07 02:11:13 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:11:13 DEBUG : 1: Destination found in --compare-dest, skipping 2025/08/07 02:11:18 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:11:18 DEBUG : 2: Destination found in --compare-dest, skipping 2025/08/07 02:11:29 DEBUG : 3: Need to transfer - File not found at Destination 2025/08/07 02:11:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dest': Waiting for checks to finish 2025/08/07 02:11:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dest': Waiting for transfers to finish 2025/08/07 02:11:34 DEBUG : 3: md5 = 6a62e81f7714a5a56c1f4754be9304c5 OK 2025/08/07 02:11:34 INFO : 3: Copied (new) 2025/08/07 02:11:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (92.41s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:12:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:12:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:12:01 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:12:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:12:06 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:12:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/CopyDest" 2025/08/07 02:12:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/CopyDest.bin" 2025/08/07 02:12:12 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/CopyDest.bin: Auto set use_segments_container to true 2025/08/07 02:12:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/CopyDest" 2025/08/07 02:12:33 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/CopyDest: Auto set use_segments_container to true 2025/08/07 02:12:43 DEBUG : one: Need to transfer - File not found at Destination 2025/08/07 02:12:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:12:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:12:48 DEBUG : one: md5 = c5a0036ecb04a90bd819d7715eaf1bf9 OK 2025/08/07 02:12:48 INFO : one: Copied (new) 2025/08/07 02:12:48 DEBUG : Waiting for deletions to finish 2025/08/07 02:12:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:12:59 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:13:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:13:09 DEBUG : one: md5 = 8225aa51cd0cb6ca2a9e48f307025e61 OK 2025/08/07 02:13:09 INFO : one: Copied (replaced existing) 2025/08/07 02:13:09 DEBUG : Waiting for deletions to finish 2025/08/07 02:13:15 DEBUG : dst/one: md5 = b775084d7e7f0b17b2857e06718267d9 OK 2025/08/07 02:13:15 DEBUG : CopyDest/one: md5 = d2bfef94af5886831cde64b47be626a9 OK 2025/08/07 02:13:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/BackupDir" 2025/08/07 02:13:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/BackupDir.bin" 2025/08/07 02:13:16 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/BackupDir.bin: Auto set use_segments_container to true 2025/08/07 02:13:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/BackupDir" 2025/08/07 02:13:21 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/BackupDir: Auto set use_segments_container to true 2025/08/07 02:13:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:13:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:13:27 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:13:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/08/07 02:13:47 INFO : one: Copied (server-side copy) 2025/08/07 02:13:53 INFO : one: Deleted 2025/08/07 02:14:08 INFO : one: Copied (server-side copy) 2025/08/07 02:14:08 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/08/07 02:14:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:14:08 DEBUG : Waiting for deletions to finish 2025/08/07 02:14:23 DEBUG : CopyDest/two: md5 = d67ad004470e07439ba32c39e11efd27 OK 2025/08/07 02:14:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:14:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:14:44 DEBUG : one: Unchanged skipping 2025/08/07 02:14:54 INFO : two: Copied (server-side copy) 2025/08/07 02:14:54 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/08/07 02:14:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:14:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:14:54 DEBUG : Waiting for deletions to finish 2025/08/07 02:15:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:15:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:15:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:15:00 DEBUG : two: Unchanged skipping 2025/08/07 02:15:00 DEBUG : one: Unchanged skipping 2025/08/07 02:15:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:15:00 DEBUG : Waiting for deletions to finish 2025/08/07 02:15:00 INFO : There was nothing to transfer 2025/08/07 02:15:11 DEBUG : CopyDest/three: md5 = d09555918c954475226cd6d78cd0f79b OK 2025/08/07 02:15:22 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/08/07 02:15:22 DEBUG : three: Destination not found in --copy-dest 2025/08/07 02:15:22 DEBUG : three: Need to transfer - File not found at Destination 2025/08/07 02:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:15:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:15:22 DEBUG : one: Unchanged skipping 2025/08/07 02:15:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:15:22 DEBUG : two: Unchanged skipping 2025/08/07 02:15:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:15:22 DEBUG : three: md5 = f8dabae9a2bec74ef6e4070f34e7d14f OK 2025/08/07 02:15:22 INFO : three: Copied (new) 2025/08/07 02:15:22 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (247.85s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:16:14 DEBUG : dst/one: md5 = 998aa6f0c535271b64f27e1954ada398 OK 2025/08/07 02:16:19 DEBUG : dst/two: md5 = 559d477456fd08d37927a7d5c027515a OK 2025/08/07 02:16:24 DEBUG : dst/three.txt: md5 = e304d1fd9bbb9d03b80b0c3acf1a8a59 OK 2025/08/07 02:16:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:16:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:16:25 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:16:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:16:30 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:16:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/backup" 2025/08/07 02:16:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup.bin" 2025/08/07 02:16:36 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup.bin: Auto set use_segments_container to true 2025/08/07 02:16:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup" 2025/08/07 02:16:42 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup: Auto set use_segments_container to true 2025/08/07 02:16:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:16:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:16:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:16:55 DEBUG : two: Unchanged skipping 2025/08/07 02:17:19 INFO : one: Copied (server-side copy) 2025/08/07 02:17:24 INFO : one: Deleted 2025/08/07 02:17:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:17:30 DEBUG : one: md5 = 0df4684b8386f80d06de07c8e09b8220 OK 2025/08/07 02:17:30 INFO : one: Copied (new) 2025/08/07 02:17:30 DEBUG : Waiting for deletions to finish 2025/08/07 02:17:45 INFO : three.txt: Copied (server-side copy) 2025/08/07 02:17:50 INFO : three.txt: Deleted 2025/08/07 02:17:50 INFO : three.txt: Moved into backup dir 2025/08/07 02:18:01 DEBUG : dst/three.txt: md5 = 96da528b2d9a4e94f9ec4997ae26c645 OK 2025/08/07 02:18:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:18:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:18:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:18:17 DEBUG : two: Unchanged skipping 2025/08/07 02:18:37 INFO : one: Copied (server-side copy) 2025/08/07 02:18:42 INFO : one: Deleted 2025/08/07 02:18:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:18:47 DEBUG : one: md5 = 24b7a117c7bb8233fb2fd76675350502 OK 2025/08/07 02:18:47 INFO : one: Copied (new) 2025/08/07 02:18:47 DEBUG : Waiting for deletions to finish 2025/08/07 02:18:58 INFO : three.txt: Copied (server-side copy) 2025/08/07 02:19:03 INFO : three.txt: Deleted 2025/08/07 02:19:03 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (215.56s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:19:49 DEBUG : dst/one: md5 = 44d3d33226914b2814fda23b7b79ef54 OK 2025/08/07 02:19:54 DEBUG : dst/two: md5 = 1b24872da8b2e50c0087527c41969bbf OK 2025/08/07 02:19:59 DEBUG : dst/three.txt: md5 = 77a5bb7186d1b26757d3a4e27aca8b87 OK 2025/08/07 02:20:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:20:10 DEBUG : Config file has changed externally - reloading 2025/08/07 02:20:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:20:10 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:20:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:20:16 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:20:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/backup" 2025/08/07 02:20:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup.bin" 2025/08/07 02:20:22 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup.bin: Auto set use_segments_container to true 2025/08/07 02:20:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup" 2025/08/07 02:20:27 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup: Auto set use_segments_container to true 2025/08/07 02:20:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:20:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:20:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:20:32 DEBUG : two: Unchanged skipping 2025/08/07 02:20:48 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:20:53 INFO : one: Deleted 2025/08/07 02:20:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:20:58 DEBUG : one: md5 = 19d0bcc5df1a23f2b2fa0086c945620d OK 2025/08/07 02:20:58 INFO : one: Copied (new) 2025/08/07 02:20:58 DEBUG : Waiting for deletions to finish 2025/08/07 02:21:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:21:21 INFO : three.txt: Deleted 2025/08/07 02:21:21 INFO : three.txt: Moved into backup dir 2025/08/07 02:21:36 DEBUG : dst/three.txt: md5 = 5e4ec0052ce668d4a8e8eeb0cc8ed942 OK 2025/08/07 02:21:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:21:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:21:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:21:47 DEBUG : two: Unchanged skipping 2025/08/07 02:21:57 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:22:02 INFO : one: Deleted 2025/08/07 02:22:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:22:07 DEBUG : one: md5 = 018568464d0e514d8a6d93a05c248dcb OK 2025/08/07 02:22:07 INFO : one: Copied (new) 2025/08/07 02:22:07 DEBUG : Waiting for deletions to finish 2025/08/07 02:22:18 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:22:23 INFO : three.txt: Deleted 2025/08/07 02:22:23 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (179.99s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:22:49 DEBUG : dst/one: md5 = 67a67c0b8719fa7b27de82288e8836c9 OK 2025/08/07 02:22:54 DEBUG : dst/two: md5 = 6a2923577fac8fa58b2612bf5735108f OK 2025/08/07 02:22:59 DEBUG : dst/three.txt: md5 = 6bf1320764ead87a3bd46431f93de3c6 OK 2025/08/07 02:23:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:23:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:23:00 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:23:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:23:06 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:23:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/backup" 2025/08/07 02:23:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup.bin" 2025/08/07 02:23:11 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup.bin: Auto set use_segments_container to true 2025/08/07 02:23:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/backup" 2025/08/07 02:23:16 DEBUG : Config file has changed externally - reloading 2025/08/07 02:23:17 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/backup: Auto set use_segments_container to true 2025/08/07 02:23:22 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:23:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:23:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:23:22 DEBUG : two: Unchanged skipping 2025/08/07 02:23:38 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/08/07 02:23:43 INFO : one: Deleted 2025/08/07 02:23:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:23:48 DEBUG : one: md5 = fd9223fcaa71112b115f5a6f0184e83d OK 2025/08/07 02:23:48 INFO : one: Copied (new) 2025/08/07 02:23:48 DEBUG : Waiting for deletions to finish 2025/08/07 02:23:59 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/08/07 02:24:04 INFO : three.txt: Deleted 2025/08/07 02:24:04 INFO : three.txt: Moved into backup dir 2025/08/07 02:24:14 DEBUG : dst/three.txt: md5 = 5374eb2137547ceb97fa5883cad63ce8 OK 2025/08/07 02:24:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:24:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:24:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:24:20 DEBUG : two: Unchanged skipping 2025/08/07 02:24:35 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/08/07 02:24:40 INFO : one: Deleted 2025/08/07 02:24:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:24:46 DEBUG : one: md5 = 694f9e46555f0ef60dba8d39d73f60cf OK 2025/08/07 02:24:46 INFO : one: Copied (new) 2025/08/07 02:24:46 DEBUG : Waiting for deletions to finish 2025/08/07 02:24:56 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/08/07 02:25:01 INFO : three.txt: Deleted 2025/08/07 02:25:01 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (168.19s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:25:37 DEBUG : dst/one: md5 = a6bd0479fe0f447602188d70fcfc83bd OK 2025/08/07 02:25:42 DEBUG : dst/two: md5 = eb2af3cd051ff3e75fef88da6b94956a OK 2025/08/07 02:25:48 DEBUG : dst/three.txt: md5 = 22a176a0b1f9169222f4807f2fe95648 OK 2025/08/07 02:25:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:25:48 DEBUG : Config file has changed externally - reloading 2025/08/07 02:25:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:25:48 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:25:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:25:54 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:25:59 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:25:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:26:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:26:04 DEBUG : two: Unchanged skipping 2025/08/07 02:26:15 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:26:20 INFO : one: Deleted 2025/08/07 02:26:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:26:25 DEBUG : one: md5 = 0fa1bed0991b8098f5d73c853a63542d OK 2025/08/07 02:26:25 INFO : one: Copied (new) 2025/08/07 02:26:25 DEBUG : Waiting for deletions to finish 2025/08/07 02:26:56 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:27:01 INFO : three.txt: Deleted 2025/08/07 02:27:01 INFO : three.txt: Moved into backup dir 2025/08/07 02:27:06 DEBUG : dst/three.txt: md5 = 57f514f35909e5a267c5b252137f569b OK 2025/08/07 02:27:17 DEBUG : one.bak: Excluded (Path Filter) 2025/08/07 02:27:17 DEBUG : one.bak: Excluded 2025/08/07 02:27:17 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/08/07 02:27:17 DEBUG : three.txt.bak: Excluded 2025/08/07 02:27:17 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:27:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for checks to finish 2025/08/07 02:27:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:27:22 DEBUG : two: Unchanged skipping 2025/08/07 02:27:27 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:27:32 INFO : one: Deleted 2025/08/07 02:27:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa/dst': Waiting for transfers to finish 2025/08/07 02:27:38 DEBUG : one: md5 = 838f0e3589d04443ad552ae4ce2a5da0 OK 2025/08/07 02:27:38 INFO : one: Copied (new) 2025/08/07 02:27:38 DEBUG : Waiting for deletions to finish 2025/08/07 02:27:58 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:28:03 INFO : three.txt: Deleted 2025/08/07 02:28:03 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (181.98s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:28:39 DEBUG : dst/one: md5 = fa7d98ededcec709cbbfacbceb792feb OK 2025/08/07 02:28:44 DEBUG : dst/two: md5 = 2f8054484bbc422c5d1cace5bc2e174c OK 2025/08/07 02:28:50 DEBUG : dst/three.txt: md5 = 4aea144318d471885d579b5f0b47621e OK 2025/08/07 02:28:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:28:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:28:55 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:29:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:29:01 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:29:06 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:29:16 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:29:21 INFO : one: Deleted 2025/08/07 02:29:27 DEBUG : one: md5 = 63c7fe636888f72edd21d0d2e5dc9b71 OK 2025/08/07 02:29:27 INFO : one: Copied (new) 2025/08/07 02:29:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:29:32 DEBUG : two: Unchanged skipping 2025/08/07 02:29:32 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/08/07 02:29:52 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:29:57 INFO : three.txt: Deleted 2025/08/07 02:30:02 DEBUG : three.txt: md5 = 3000bfebe989e1488274db1d7900cf20 OK 2025/08/07 02:30:02 INFO : three.txt: Copied (new) 2025/08/07 02:30:18 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:30:23 INFO : one: Copied (server-side copy) to: one.bak 2025/08/07 02:30:28 INFO : one: Deleted 2025/08/07 02:30:34 DEBUG : one: md5 = 919cfc53bd197fe6b16cdc8fc13be461 OK 2025/08/07 02:30:34 INFO : one: Copied (new) 2025/08/07 02:30:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:30:34 DEBUG : two: Unchanged skipping 2025/08/07 02:30:34 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/08/07 02:30:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2025/08/07 02:30:54 INFO : three.txt: Deleted 2025/08/07 02:30:59 DEBUG : three.txt: md5 = 2a21530e706d08d6e796686b7a676ea7 OK 2025/08/07 02:30:59 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (176.22s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:31:35 DEBUG : dst/one: md5 = fe0139666639bfa45848a237f44cfb0d OK 2025/08/07 02:31:41 DEBUG : dst/two: md5 = 72e92a19b69e695afb76fcf34a5a60fb OK 2025/08/07 02:31:46 DEBUG : dst/three.txt: md5 = 92e92dfbbffc9c1a486a5872afc81bff OK 2025/08/07 02:32:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gobuyir3qasa/dst" 2025/08/07 02:32:01 DEBUG : Config file has changed externally - reloading 2025/08/07 02:32:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst.bin" 2025/08/07 02:32:01 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst.bin: Auto set use_segments_container to true 2025/08/07 02:32:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gobuyir3qasa/dst" 2025/08/07 02:32:07 DEBUG : Swift container crypt path rclone-test-gobuyir3qasa/dst: Auto set use_segments_container to true 2025/08/07 02:32:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/07 02:32:33 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/08/07 02:32:38 INFO : one: Deleted 2025/08/07 02:32:43 DEBUG : one: md5 = 73b7448800b1b7c0dc3ccaabc24b04a4 OK 2025/08/07 02:32:43 INFO : one: Copied (new) 2025/08/07 02:32:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:32:48 DEBUG : two: Unchanged skipping 2025/08/07 02:32:48 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/08/07 02:32:59 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/08/07 02:33:04 INFO : three.txt: Deleted 2025/08/07 02:33:09 DEBUG : three.txt: md5 = 4dd08b9f502912636d4ded1a4fe3ded3 OK 2025/08/07 02:33:09 INFO : three.txt: Copied (new) 2025/08/07 02:33:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/07 02:33:50 INFO : one: Copied (server-side copy) to: one-2019-01-01 2025/08/07 02:33:55 INFO : one: Deleted 2025/08/07 02:34:00 DEBUG : one: md5 = 54f0295161db7b0c8e920ddb99679d2f OK 2025/08/07 02:34:00 INFO : one: Copied (new) 2025/08/07 02:34:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:34:00 DEBUG : two: Unchanged skipping 2025/08/07 02:34:00 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/08/07 02:34:05 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2025/08/07 02:34:11 INFO : three.txt: Deleted 2025/08/07 02:34:16 DEBUG : three.txt: md5 = 28f713790f5eb780a2b8ade3094a9152 OK 2025/08/07 02:34:16 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (202.11s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:34:53 DEBUG : Testêé: md5 = 7bfb60160b5d7e2daaf24662fd7c613c OK 2025/08/07 02:34:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:34:53 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/08/07 02:34:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:34:54 DEBUG : Testêé: md5 = c8f365f05f654cddcf19227ce3a5d287 OK 2025/08/07 02:34:54 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/08/07 02:34:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.82s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:34:55 DEBUG : existing: Need to transfer - File not found at Destination 2025/08/07 02:34:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:34:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:34:55 DEBUG : existing: md5 = 98a2b749d58fba8a82b9b5c88eb244e1 OK 2025/08/07 02:34:55 INFO : existing: Copied (new) 2025/08/07 02:34:55 DEBUG : Waiting for deletions to finish 2025/08/07 02:34:56 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/08/07 02:34:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:34:56 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/08/07 02:34:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:34:56 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': not deleting files as there were IO errors 2025/08/07 02:34:56 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.74s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:34:56 DEBUG : EXISTING: md5 = 610440fdbb56b87ff4cd108400250e57 OK 2025/08/07 02:34:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:34:57 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:34:57 DEBUG : existing: Unchanged skipping 2025/08/07 02:34:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:34:57 DEBUG : Waiting for deletions to finish 2025/08/07 02:34:57 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.14s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:2665: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.31s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:2704: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:2704: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" sync_test.go:2704: This test only runs on local --- PASS: TestMaxTransfer (0.29s) --- SKIP: TestMaxTransfer/Hard (0.10s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa'", Local "Local file system at /tmp/rclone2736969499", Modify Window "1ns" 2025/08/07 02:34:58 DEBUG : both0: md5 = 6632b7c75244b8a5405dc026e30e6c36 OK 2025/08/07 02:34:58 DEBUG : only0: md5 = a0c8edf8502a580830899b0bd60e5c58 OK 2025/08/07 02:34:59 DEBUG : both1: md5 = 4d66246ef5e64463cd1be14b75dae744 OK 2025/08/07 02:34:59 DEBUG : only1: md5 = 29e0da41ec477d71cbc04727200b35f0 OK 2025/08/07 02:35:00 DEBUG : both2: md5 = 22d0ec4686398c61b92eb01ba8a28eeb OK 2025/08/07 02:35:00 DEBUG : only2: md5 = 1c9f808b4a00a91d1a37cc07cdae1cd0 OK 2025/08/07 02:35:06 DEBUG : both3: md5 = 0959017e529befc50fe292e8717eb6b0 OK 2025/08/07 02:35:06 DEBUG : only3: md5 = df1d2c83807614a8c68932bc1297fb4a OK 2025/08/07 02:35:11 DEBUG : both4: md5 = 8b4ae29df70ce1ef2a652fca2f3ff27d OK 2025/08/07 02:35:12 DEBUG : only4: md5 = ab3bdf476fa6e59584ab4327533be41d OK 2025/08/07 02:35:17 DEBUG : both5: md5 = 7022613ea8d0284230c1a2f7a414e3d7 OK 2025/08/07 02:35:23 DEBUG : only5: md5 = 9c6162c63fb33f510244bd7f46dd561a OK 2025/08/07 02:35:23 DEBUG : both6: md5 = dc614bf7dbf735fcb2b1ae066e270450 OK 2025/08/07 02:35:24 DEBUG : only6: md5 = dd980b175cee7383fa9c81036dc9dc52 OK 2025/08/07 02:35:24 DEBUG : both7: md5 = d53e877c059fd9c55a8384bd284d2f28 OK 2025/08/07 02:35:25 DEBUG : only7: md5 = cbd46eaf041f14152fa72e4e24f3a484 OK 2025/08/07 02:35:30 DEBUG : both8: md5 = f1099a19e70782d2f82b6492236cbcc4 OK 2025/08/07 02:35:35 DEBUG : only8: md5 = df90ce64130d597ffd7451d101b41f99 OK 2025/08/07 02:35:41 DEBUG : both9: md5 = 2c6fcb28e5335c5897e2861ab3aae8e6 OK 2025/08/07 02:35:41 DEBUG : only9: md5 = 556ba9ef5ac7513dc02ddf20328dc64c OK 2025/08/07 02:35:41 DEBUG : both10: md5 = 6e54f11e3d2706ee0dfd382f65e6210c OK 2025/08/07 02:35:42 DEBUG : only10: md5 = 866fe94d9481f27ab107fd73e7324cde OK 2025/08/07 02:35:42 DEBUG : both11: md5 = 2d46925b4096c1c3c0b142fbbec9dd0c OK 2025/08/07 02:35:43 DEBUG : only11: md5 = 08bee652e40e40add4053d5f9fb9e886 OK 2025/08/07 02:35:48 DEBUG : both12: md5 = fafdbe49904660ba3465ba814d91d9a8 OK 2025/08/07 02:35:49 DEBUG : only12: md5 = 9e77fc8e1edd273827e1208710d1bd37 OK 2025/08/07 02:35:49 DEBUG : both13: md5 = 457d1d327a05afcc330b28a5e4c6fcc9 OK 2025/08/07 02:35:55 DEBUG : only13: md5 = 06985ca98523d0ca0420e6031d0c476e OK 2025/08/07 02:36:00 DEBUG : both14: md5 = b1288321b2aabf17b7218e0c32a3b178 OK 2025/08/07 02:36:07 DEBUG : only14: md5 = d2519533d9080cf19e5f063a4e9f8450 OK 2025/08/07 02:36:07 DEBUG : both15: md5 = 8366edad695700f5809d34f5628484e0 OK 2025/08/07 02:36:07 DEBUG : only15: md5 = 5fd8fada1507181fccd2e8fe776350af OK 2025/08/07 02:36:13 DEBUG : both16: md5 = 18130c9df65c2ffab9f1d409054f0817 OK 2025/08/07 02:36:18 DEBUG : only16: md5 = ce830469f14a6c606bf949a7b7ea178a OK 2025/08/07 02:36:23 DEBUG : both17: md5 = 2d15dcb37c2c51500f72b944dc197aa1 OK 2025/08/07 02:36:24 DEBUG : only17: md5 = c6d18fccab71bea0cd2979ccd40c65f3 OK 2025/08/07 02:36:29 DEBUG : both18: md5 = 0c5777fc8febbc1c367e97fc8462ccec OK 2025/08/07 02:36:34 DEBUG : only18: md5 = d4d16801eaffa32e892210858f508cbf OK 2025/08/07 02:36:35 DEBUG : both19: md5 = e3eb8126e05a0f78805de10dff9a7d99 OK 2025/08/07 02:36:40 DEBUG : only19: md5 = 55efd94029524053e3aa251af8b3a3d4 OK 2025/08/07 02:37:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for checks to finish 2025/08/07 02:37:27 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:27 DEBUG : both0: Unchanged skipping 2025/08/07 02:37:27 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:27 DEBUG : both10: Unchanged skipping 2025/08/07 02:37:27 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:27 DEBUG : both11: Unchanged skipping 2025/08/07 02:37:27 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:27 DEBUG : both12: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both13: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both14: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both15: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both18: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both19: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both1: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both2: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both3: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both4: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both5: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both6: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both7: Unchanged skipping 2025/08/07 02:37:28 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:28 DEBUG : both9: Unchanged skipping 2025/08/07 02:37:33 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:33 DEBUG : both16: Unchanged skipping 2025/08/07 02:37:33 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:33 DEBUG : both17: Unchanged skipping 2025/08/07 02:37:33 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/08/07 02:37:33 DEBUG : both8: Unchanged skipping 2025/08/07 02:37:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gobuyir3qasa': Waiting for transfers to finish 2025/08/07 02:37:33 DEBUG : Waiting for deletions to finish 2025/08/07 02:37:33 INFO : only4: Deleted 2025/08/07 02:37:33 INFO : only9: Deleted 2025/08/07 02:37:33 INFO : only15: Deleted 2025/08/07 02:37:33 INFO : only0: Deleted 2025/08/07 02:37:34 INFO : only10: Deleted 2025/08/07 02:37:34 INFO : only11: Deleted 2025/08/07 02:37:34 INFO : only17: Deleted 2025/08/07 02:37:34 INFO : only7: Deleted 2025/08/07 02:37:38 INFO : only13: Deleted 2025/08/07 02:37:39 INFO : only14: Deleted 2025/08/07 02:37:43 INFO : only19: Deleted 2025/08/07 02:37:43 INFO : only16: Deleted 2025/08/07 02:37:43 INFO : only6: Deleted 2025/08/07 02:37:43 INFO : only2: Deleted 2025/08/07 02:37:44 INFO : only3: Deleted 2025/08/07 02:37:44 INFO : only12: Deleted 2025/08/07 02:37:44 INFO : only5: Deleted 2025/08/07 02:37:44 INFO : only1: Deleted 2025/08/07 02:37:44 INFO : only8: Deleted 2025/08/07 02:37:49 INFO : only18: Deleted 2025/08/07 02:37:49 INFO : There was nothing to transfer panic: test timed out after 1h0m0s running tests: TestSyncConcurrentDelete (4m4s) goroutine 12731 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 4 minutes]: testing.(*T).Run(0xc001b00380, {0x26c619f?, 0xc00006fad8?}, 0x27cfd08) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc001b00380) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc001b00380, 0xc00006fc18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc001b60900, {0x3e95540, 0x6c, 0x6c}, {0xee025fab5?, 0x3eab5a0?, 0x3eab5a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc00014c0a0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc00014c0a0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:45 main.main() _testmain.go:261 +0xa9 goroutine 16 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 18 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 12205 [select, 2 minutes]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc0002d6960, 0xc000414cb0, 0xc0008c92c0) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:425 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc0002d6960, {0x2b06088, 0x3eceb60}, {0xc00188d9a0, 0x4f}, {{0xc002041230, 0x5}, {0xc002041236, 0x22}, {0x268ef29, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:777 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc0002d6960, {0x2b06088, 0x3eceb60}, {{0xc002041230, 0x5}, {0xc002041236, 0x22}, {0x268ef29, 0x6}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:847 +0x151 github.com/ncw/swift/v2.(*Connection).ObjectDelete(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:1902 github.com/rclone/rclone/backend/swift.(*Object).Remove.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1620 +0x105 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0xc000100008?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc001af84e0, 0xc0008e89b0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc001af84e0, 0xc0008e89b0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/swift.(*Object).Remove(0xc0009d9620, {0x2b06088, 0x3eceb60}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1619 +0x29b github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x24 github.com/rclone/rclone/fstest.retry(0xc0009e4000, {0xc0001c5350, 0x15}, 0xc00006f8c8) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc000944800?, 0xc000944800?, 0x1e0ff74?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x29a github.com/rclone/rclone/fs/walk.listR.func1({0xc000944800, 0x14, 0x20}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:331 +0x1ca github.com/rclone/rclone/backend/crypt.(*Fs).ListR.func1({0xc000944800?, 0xc001abbca0?, 0x5?}) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:481 +0x5b github.com/rclone/rclone/fs/list.(*Helper).send(...) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:29 github.com/rclone/rclone/fs/list.(*Helper).Flush(0xc00006fb30) /home/rclone/go/src/github.com/rclone/rclone/fs/list/helpers.go:47 +0x35 github.com/rclone/rclone/backend/swift.(*Fs).ListR(0xc0019ab500, {0x2b06088, 0x3eceb60}, {0x0?, 0x2423d00?}, 0xc000444240) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:881 +0x185 github.com/rclone/rclone/backend/crypt.(*Fs).ListR(0xc000135110, {0x2b06088, 0x3eceb60}, {0x0, 0x0}, 0xc000705c70) /home/rclone/go/src/github.com/rclone/rclone/backend/crypt/crypt.go:476 +0x118 github.com/rclone/rclone/fs/walk.listR({0x2b06088, 0x3eceb60}, {0x2b1a860, 0xc000135110}, {0x0, 0x0}, 0x1, 0x3, 0xc000393d70, 0xc00067f188, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:299 +0x292 github.com/rclone/rclone/fs/walk.ListR({0x2b06088, 0x3eceb60}, {0x2b1a860, 0xc000135110}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000393d70) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:163 +0x1d5 github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:150 +0x107 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc000526b60) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc0009e4000, 0x6?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 testing.tRunner(0xc0009e4000, 0x27cfd08) /usr/local/go/src/testing/testing.go:1798 +0x11e created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 2141 [chan receive, 54 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 2170 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 11667 [select, 2 minutes]: net/http.(*persistConn).writeLoop(0xc0007937a0) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 11658 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 12001 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0006854a0, {0x2b06168, 0xc000880460}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 12065 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x117 goroutine 12742 [select, 2 minutes]: net/http.(*persistConn).roundTrip(0xc0007937a0, 0xc0008e8a50) /usr/local/go/src/net/http/transport.go:2826 +0x785 net/http.(*Transport).roundTrip(0xc00068a1a0, 0xc0008c92c0) /usr/local/go/src/net/http/transport.go:696 +0xb9a net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0004da070, 0xc0008c92c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:362 +0x345 net/http.send(0xc0008c92c0, {0x2adf5e0, 0xc0004da070}, {0x1?, 0xc0009435a0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc001aca990, 0xc0008c92c0, {0x0?, 0x0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc001aca990, 0xc0008c92c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:421 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 12205 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.4/swift.go:420 +0xb8 goroutine 11666 [IO wait, 2 minutes]: internal/poll.runtime_pollWait(0x7a457762c390, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001f7c700?, 0xc000a62000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001f7c700, {0xc000a62000, 0x3500, 0x3500}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001f7c700, {0xc000a62000?, 0x34f3?, 0xc001a64f80?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00067c328, {0xc000a62000?, 0xc0007d9350?, 0xd?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc00226a5d0, {0xc000a62000?, 0x7a45776361b8?, 0x7a45777b4108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc00067fc68, {0xc000a62000?, 0x43d367?, 0xc0004c19d0?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0007d9438, {0x2ae0b60, 0xc00067fc68}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0007d9188, {0x7a45286fd918, 0xc00226a5d0}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0007d9188, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0007d9188, {0xc00083d000, 0x1000, 0xc000666d20?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 net/http.(*persistConn).Read(0xc0007937a0, {0xc00083d000?, 0x80cba5?, 0x2adda60?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0020d8360) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0020d8360, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0007937a0) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 11658 /usr/local/go/src/net/http/transport.go:1944 +0x174f "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished ERROR in 1h0m11.528548175s (try 2/5): exit status 2: Failed []