Open
Description
I'm following the instructions for running the pieces of a simulated deployment by hand but sim-sled-agent shut down as soon as it started:
$ cargo run --bin=sled-agent-sim -- d0900913-88c6-4702-8eaf-d2e88e714657 [::1]:12345 [::1]:12221 --rss-nexus-external-addr 127.0.0.1:12220 --rss-external-dns-internal-addr [::1]:5353 --rss-internal-dns-dns-addr [::1]:3535 --rss-tls-cert demo-cert.pem --rss-tls-key demo-key.pem
Blocking waiting for file lock on build directory
Compiling omicron-sled-agent v0.1.0 (/home/dap/omicron-fix/sled-agent)
Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 09s
Running `target/debug/sled-agent-sim d0900913-88c6-4702-8eaf-d2e88e714657 '[::1]:12345' '[::1]:12221' --rss-nexus-external-addr '127.0.0.1:12220' --rss-external-dns-internal-addr '[::1]:5353' --rss-internal-dns-dns-addr '[::1]:3535' --rss-tls-cert demo-cert.pem --rss-tls-key demo-key.pem`
Jun 16 20:51:14.528 INFO setting up sled agent server, file: sled-agent/src/sim/server.rs:94
Jun 16 20:51:14.587 INFO created simulated sled agent, sim_mode: Auto, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: sled-agent/src/sim/sled_agent.rs:124
Jun 16 20:51:14.587 INFO registered sled d0900913-88c6-4702-8eaf-d2e88e714657 storage, file: sled-agent/src/sim/upstairs.rs:48
Jun 16 20:51:14.588 INFO No ledger in /dangerzone/omicron_tmp/.tmpW5NjUD/artifact-config.json, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: common/src/ledger.rs:186
Jun 16 20:51:14.588 INFO No ledger in /dangerzone/omicron_tmp/.tmp75XkQg/artifact-config.json, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: common/src/ledger.rs:186
Jun 16 20:51:14.626 INFO listening, local_addr: [::1]:12345, component: dropshot (Repo Depot), server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: /home/dap/.cargo/registry/src/index.crates.io-1949cf8c6b5b557f/dropshot-0.16.2/src/server.rs:328
Jun 16 20:51:14.631 INFO beginning graceful shutdown, local_addr: [::1]:12345, component: dropshot (Repo Depot), server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: /home/dap/.cargo/registry/src/index.crates.io-1949cf8c6b5b557f/dropshot-0.16.2/src/server.rs:317
Jun 16 20:51:14.632 WARN All ledger manager request senders dropped, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: sled-agent/src/artifact_store.rs:502
Jun 16 20:51:14.632 WARN Delete reconciler sender dropped, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657sled-agent-sim: initializing server: failed to bind to [::1]:12345
, component: SledAgent, file: sled-agent/src/artifact_store.rs:590
The actual error message got mixed with the log messages:
sled-agent-sim: initializing server: failed to bind to [::1]:12345
I only noticed because when I tried again I got a slightly different log:
$ cargo run --bin=sled-agent-sim -- d0900913-88c6-4702-8eaf-d2e88e714657 [::1]:12345 [::1]:12221 --rss-nexus-external-addr 127.0.0.1:12220 --rss-external-dns-internal-addr [::1]:5353 --rss-internal-dns-dns-addr [::1]:3535 --rss-tls-cert demo-cert.pem --rss-tls-key demo-key.pem
Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.11s
Running `target/debug/sled-agent-sim d0900913-88c6-4702-8eaf-d2e88e714657 '[::1]:12345' '[::1]:12221' --rss-nexus-external-addr '127.0.0.1:12220' --rss-external-dns-internal-addr '[::1]:5353' --rss-internal-dns-dns-addr '[::1]:3535' --rss-tls-cert demo-cert.pem --rss-tls-key demo-key.pem`
Jun 16 20:52:05.107 INFO setting up sled agent server, file: sled-agent/src/sim/server.rs:94
Jun 16 20:52:05.160 INFO created simulated sled agent, sim_mode: Auto, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: sled-agent/src/sim/sled_agent.rs:124
Jun 16 20:52:05.160 INFO registered sled d0900913-88c6-4702-8eaf-d2e88e714657 storage, file: sled-agent/src/sim/upstairs.rs:48
Jun 16 20:52:05.160 INFO No ledger in /dangerzone/omicron_tmp/.tmpooU6Or/artifact-config.json, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: common/src/ledger.rs:186
Jun 16 20:52:05.161 INFO No ledger in /dangerzone/omicron_tmp/.tmpkA8mcD/artifact-config.json, component: ArtifactStore, server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: common/src/ledger.rs:186
Jun 16 20:52:05.197 INFO listening, local_addr: [::1]:12345, component: dropshot (Repo Depot), server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: /home/dap/.cargo/registry/src/index.crates.io-1949cf8c6b5b557f/dropshot-0.16.2/src/server.rs:328
Jun 16 20:52:05.201 INFO beginning graceful shutdown, local_addr: [::1]:12345, component: dropshot (Repo Depot), server: d0900913-88c6-4702-8eaf-d2e88e714657, component: SledAgent, file: /home/dap/.cargo/registry/src/index.crates.io-1949cf8c6b5b557f/dropshot-0.16.2/src/server.rs:317
sled-agent-sim: initializing server: failed to bind to [::1]:12345
When this program is not running, nothing else is bound to port 12345. That suggests that it's trying multiple times to bind to the same port but I'm not sure yet.