[INFO] fetching crate tenement 0.2.1...
[INFO] testing tenement-0.2.1 against 1.95.0 for beta-1.96-2
[INFO] extracting crate tenement 0.2.1 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate tenement 0.2.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate tenement 0.2.1
[INFO] tweaked toml for crates.io crate tenement 0.2.1 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate tenement 0.2.1 on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tenement 0.2.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded fastrand v2.4.0
[INFO] [stderr]   Downloaded tokio v1.51.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 79daf396709c83db34d69dc5da80166950f73f648843b9f6b08cf00fdf5252db
[INFO] running `Command { std: "docker" "start" "-a" "79daf396709c83db34d69dc5da80166950f73f648843b9f6b08cf00fdf5252db", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "79daf396709c83db34d69dc5da80166950f73f648843b9f6b08cf00fdf5252db", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "79daf396709c83db34d69dc5da80166950f73f648843b9f6b08cf00fdf5252db", kill_on_drop: false }`
[INFO] [stdout] 79daf396709c83db34d69dc5da80166950f73f648843b9f6b08cf00fdf5252db
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0b2e36374b60251510373802e7b1042aa8988614a8a8e457eaa833f4f04846dd
[INFO] running `Command { std: "docker" "start" "-a" "0b2e36374b60251510373802e7b1042aa8988614a8a8e457eaa833f4f04846dd", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling indexmap v2.13.1
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling cc v1.2.59
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling tokio v1.51.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling futures-intrusive v0.5.0
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling dotenvy v0.15.7
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling toml_write v0.1.2
[INFO] [stderr]    Compiling base64ct v1.8.3
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling password-hash v0.5.0
[INFO] [stderr]    Compiling blake2 v0.10.6
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling argon2 v0.5.3
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling shell-words v1.1.1
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling sqlx-core v0.8.6
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.6
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.6
[INFO] [stderr]    Compiling sqlx-macros v0.8.6
[INFO] [stderr]    Compiling sqlx v0.8.6
[INFO] [stderr]    Compiling tenement v0.2.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::os::unix::process::CommandExt`
[INFO] [stdout]   --> src/runtime/namespace.rs:42:9
[INFO] [stdout]    |
[INFO] [stdout] 42 |     use std::os::unix::process::CommandExt;
[INFO] [stdout]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `key_to_labels` is never used
[INFO] [stdout]    --> src/metrics.rs:276:4
[INFO] [stdout]     |
[INFO] [stdout] 276 | fn key_to_labels(key: &str) -> Labels {
[INFO] [stdout]     |    ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 28s
[INFO] running `Command { std: "docker" "inspect" "0b2e36374b60251510373802e7b1042aa8988614a8a8e457eaa833f4f04846dd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0b2e36374b60251510373802e7b1042aa8988614a8a8e457eaa833f4f04846dd", kill_on_drop: false }`
[INFO] [stdout] 0b2e36374b60251510373802e7b1042aa8988614a8a8e457eaa833f4f04846dd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4dc253138473b6883ea42af7aafc9c630bae8d0d996e24389edb74360155c980
[INFO] running `Command { std: "docker" "start" "-a" "4dc253138473b6883ea42af7aafc9c630bae8d0d996e24389edb74360155c980", kill_on_drop: false }`
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling tokio v1.51.0
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling atoi v2.0.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling nix v0.29.0
[INFO] [stderr]    Compiling sqlx-core v0.8.6
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling fastrand v2.4.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling sqlx-sqlite v0.8.6
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling tokio-test v0.4.5
[INFO] [stderr]    Compiling sqlx-macros-core v0.8.6
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling sqlx-macros v0.8.6
[INFO] [stderr]    Compiling sqlx v0.8.6
[INFO] [stderr]    Compiling tenement v0.2.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::os::unix::process::CommandExt`
[INFO] [stdout]   --> src/runtime/namespace.rs:42:9
[INFO] [stdout]    |
[INFO] [stdout] 42 |     use std::os::unix::process::CommandExt;
[INFO] [stdout]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `tempfile::TempDir`
[INFO] [stdout]    --> src/cgroup.rs:508:13
[INFO] [stdout]     |
[INFO] [stdout] 508 |         use tempfile::TempDir;
[INFO] [stdout]     |             ^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `key_to_labels` is never used
[INFO] [stdout]    --> src/metrics.rs:276:4
[INFO] [stdout]     |
[INFO] [stdout] 276 | fn key_to_labels(key: &str) -> Labels {
[INFO] [stdout]     |    ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `std::os::unix::process::CommandExt`
[INFO] [stdout]   --> src/runtime/namespace.rs:42:9
[INFO] [stdout]    |
[INFO] [stdout] 42 |     use std::os::unix::process::CommandExt;
[INFO] [stdout]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: variable `v2_count` is assigned to, but never used
[INFO] [stdout]     --> src/hypervisor.rs:2569:13
[INFO] [stdout]      |
[INFO] [stdout] 2569 |         let mut v2_count = 0;
[INFO] [stdout]      |             ^^^^^^^^^^^^
[INFO] [stdout]      |
[INFO] [stdout]      = note: consider using `_v2_count` instead
[INFO] [stdout]      = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `v2_count` is never read
[INFO] [stdout]     --> src/hypervisor.rs:2576:29
[INFO] [stdout]      |
[INFO] [stdout] 2576 |                     "v2" => v2_count += 1,
[INFO] [stdout]      |                             ^^^^^^^^^^^^^
[INFO] [stdout]      |
[INFO] [stdout]      = help: maybe it is overwritten before being read?
[INFO] [stdout]      = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_socket_server_script` is never used
[INFO] [stdout]     --> src/hypervisor.rs:1561:8
[INFO] [stdout]      |
[INFO] [stdout] 1561 |     fn create_socket_server_script(dir: &Path) -> PathBuf {
[INFO] [stdout]      |        ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]      |
[INFO] [stdout]      = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_touch_socket_script` is never used
[INFO] [stdout]    --> tests/common/mod.rs:143:8
[INFO] [stdout]     |
[INFO] [stdout] 143 | pub fn create_touch_socket_script(dir: &TempDir) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_config_with_idle_timeout` is never used
[INFO] [stdout]   --> tests/common/mod.rs:47:8
[INFO] [stdout]    |
[INFO] [stdout] 47 | pub fn test_config_with_idle_timeout(name: &str, command: &str, idle_secs: u64) -> Config {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_config_with_limits` is never used
[INFO] [stdout]   --> tests/common/mod.rs:56:8
[INFO] [stdout]    |
[INFO] [stdout] 56 | pub fn test_config_with_limits(
[INFO] [stdout]    |        ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `wait_for_socket_removed` is never used
[INFO] [stdout]   --> tests/common/mod.rs:83:14
[INFO] [stdout]    |
[INFO] [stdout] 83 | pub async fn wait_for_socket_removed(path: &Path, timeout_ms: u64) -> bool {
[INFO] [stdout]    |              ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_test_db` is never used
[INFO] [stdout]   --> tests/common/mod.rs:96:14
[INFO] [stdout]    |
[INFO] [stdout] 96 | pub async fn create_test_db() -> (DbPool, TempDir) {
[INFO] [stdout]    |              ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `fixture_path` is never used
[INFO] [stdout]    --> tests/common/mod.rs:104:8
[INFO] [stdout]     |
[INFO] [stdout] 104 | pub fn fixture_path(name: &str) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `copy_fixture_to_temp` is never used
[INFO] [stdout]    --> tests/common/mod.rs:113:8
[INFO] [stdout]     |
[INFO] [stdout] 113 | pub fn copy_fixture_to_temp(fixture_name: &str, temp_dir: &TempDir) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `socket_env` is never used
[INFO] [stdout]    --> tests/common/mod.rs:131:8
[INFO] [stdout]     |
[INFO] [stdout] 131 | pub fn socket_env(socket_path: &Path) -> HashMap<String, String> {
[INFO] [stdout]     |        ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_config_with_idle_timeout` is never used
[INFO] [stdout]   --> tests/common/mod.rs:47:8
[INFO] [stdout]    |
[INFO] [stdout] 47 | pub fn test_config_with_idle_timeout(name: &str, command: &str, idle_secs: u64) -> Config {
[INFO] [stdout]    |        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `wait_for_socket_removed` is never used
[INFO] [stdout]   --> tests/common/mod.rs:83:14
[INFO] [stdout]    |
[INFO] [stdout] 83 | pub async fn wait_for_socket_removed(path: &Path, timeout_ms: u64) -> bool {
[INFO] [stdout]    |              ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_test_db` is never used
[INFO] [stdout]   --> tests/common/mod.rs:96:14
[INFO] [stdout]    |
[INFO] [stdout] 96 | pub async fn create_test_db() -> (DbPool, TempDir) {
[INFO] [stdout]    |              ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `fixture_path` is never used
[INFO] [stdout]    --> tests/common/mod.rs:104:8
[INFO] [stdout]     |
[INFO] [stdout] 104 | pub fn fixture_path(name: &str) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `copy_fixture_to_temp` is never used
[INFO] [stdout]    --> tests/common/mod.rs:113:8
[INFO] [stdout]     |
[INFO] [stdout] 113 | pub fn copy_fixture_to_temp(fixture_name: &str, temp_dir: &TempDir) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `socket_env` is never used
[INFO] [stdout]    --> tests/common/mod.rs:131:8
[INFO] [stdout]     |
[INFO] [stdout] 131 | pub fn socket_env(socket_path: &Path) -> HashMap<String, String> {
[INFO] [stdout]     |        ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_touch_socket_script` is never used
[INFO] [stdout]    --> tests/common/mod.rs:143:8
[INFO] [stdout]     |
[INFO] [stdout] 143 | pub fn create_touch_socket_script(dir: &TempDir) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_config_with_limits` is never used
[INFO] [stdout]   --> tests/common/mod.rs:56:8
[INFO] [stdout]    |
[INFO] [stdout] 56 | pub fn test_config_with_limits(
[INFO] [stdout]    |        ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `create_test_db` is never used
[INFO] [stdout]   --> tests/common/mod.rs:96:14
[INFO] [stdout]    |
[INFO] [stdout] 96 | pub async fn create_test_db() -> (DbPool, TempDir) {
[INFO] [stdout]    |              ^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `fixture_path` is never used
[INFO] [stdout]    --> tests/common/mod.rs:104:8
[INFO] [stdout]     |
[INFO] [stdout] 104 | pub fn fixture_path(name: &str) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `copy_fixture_to_temp` is never used
[INFO] [stdout]    --> tests/common/mod.rs:113:8
[INFO] [stdout]     |
[INFO] [stdout] 113 | pub fn copy_fixture_to_temp(fixture_name: &str, temp_dir: &TempDir) -> PathBuf {
[INFO] [stdout]     |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `socket_env` is never used
[INFO] [stdout]    --> tests/common/mod.rs:131:8
[INFO] [stdout]     |
[INFO] [stdout] 131 | pub fn socket_env(socket_path: &Path) -> HashMap<String, String> {
[INFO] [stdout]     |        ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 23s
[INFO] running `Command { std: "docker" "inspect" "4dc253138473b6883ea42af7aafc9c630bae8d0d996e24389edb74360155c980", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4dc253138473b6883ea42af7aafc9c630bae8d0d996e24389edb74360155c980", kill_on_drop: false }`
[INFO] [stdout] 4dc253138473b6883ea42af7aafc9c630bae8d0d996e24389edb74360155c980
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6e8d79130fe7d5f4ea5aa08a3ee52b50408fa03ef2880204b76a803571a2be07
[INFO] running `Command { std: "docker" "start" "-a" "6e8d79130fe7d5f4ea5aa08a3ee52b50408fa03ef2880204b76a803571a2be07", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `std::os::unix::process::CommandExt`
[INFO] [stderr]   --> src/runtime/namespace.rs:42:9
[INFO] [stderr]    |
[INFO] [stderr] 42 |     use std::os::unix::process::CommandExt;
[INFO] [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `key_to_labels` is never used
[INFO] [stderr]    --> src/metrics.rs:276:4
[INFO] [stderr]     |
[INFO] [stderr] 276 | fn key_to_labels(key: &str) -> Labels {
[INFO] [stderr]     |    ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `tenement` (lib) generated 2 warnings
[INFO] [stderr] warning: unused import: `tempfile::TempDir`
[INFO] [stderr]    --> src/cgroup.rs:508:13
[INFO] [stderr]     |
[INFO] [stderr] 508 |         use tempfile::TempDir;
[INFO] [stderr]     |             ^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::os::unix::process::CommandExt`
[INFO] [stderr]   --> src/runtime/namespace.rs:42:9
[INFO] [stderr]    |
[INFO] [stderr] 42 |     use std::os::unix::process::CommandExt;
[INFO] [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: variable `v2_count` is assigned to, but never used
[INFO] [stderr]     --> src/hypervisor.rs:2569:13
[INFO] [stderr]      |
[INFO] [stderr] 2569 |         let mut v2_count = 0;
[INFO] [stderr]      |             ^^^^^^^^^^^^
[INFO] [stderr]      |
[INFO] [stderr]      = note: consider using `_v2_count` instead
[INFO] [stderr]      = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `v2_count` is never read
[INFO] [stderr]     --> src/hypervisor.rs:2576:29
[INFO] [stderr]      |
[INFO] [stderr] 2576 |                     "v2" => v2_count += 1,
[INFO] [stderr]      |                             ^^^^^^^^^^^^^
[INFO] [stderr]      |
[INFO] [stderr]      = help: maybe it is overwritten before being read?
[INFO] [stderr]      = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `create_socket_server_script` is never used
[INFO] [stderr]     --> src/hypervisor.rs:1561:8
[INFO] [stderr]      |
[INFO] [stderr] 1561 |     fn create_socket_server_script(dir: &Path) -> PathBuf {
[INFO] [stderr]      |        ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]      |
[INFO] [stderr]      = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `test_config_with_idle_timeout` is never used
[INFO] [stderr]   --> tests/common/mod.rs:47:8
[INFO] [stderr]    |
[INFO] [stderr] 47 | pub fn test_config_with_idle_timeout(name: &str, command: &str, idle_secs: u64) -> Config {
[INFO] [stderr]    |        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `wait_for_socket_removed` is never used
[INFO] [stderr]   --> tests/common/mod.rs:83:14
[INFO] [stderr]    |
[INFO] [stderr] 83 | pub async fn wait_for_socket_removed(path: &Path, timeout_ms: u64) -> bool {
[INFO] [stderr]    |              ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `create_test_db` is never used
[INFO] [stderr]   --> tests/common/mod.rs:96:14
[INFO] [stderr]    |
[INFO] [stderr] 96 | pub async fn create_test_db() -> (DbPool, TempDir) {
[INFO] [stderr]    |              ^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `fixture_path` is never used
[INFO] [stderr]    --> tests/common/mod.rs:104:8
[INFO] [stderr]     |
[INFO] [stderr] 104 | pub fn fixture_path(name: &str) -> PathBuf {
[INFO] [stderr]     |        ^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `copy_fixture_to_temp` is never used
[INFO] [stderr]    --> tests/common/mod.rs:113:8
[INFO] [stderr]     |
[INFO] [stderr] 113 | pub fn copy_fixture_to_temp(fixture_name: &str, temp_dir: &TempDir) -> PathBuf {
[INFO] [stderr]     |        ^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `socket_env` is never used
[INFO] [stderr]    --> tests/common/mod.rs:131:8
[INFO] [stderr]     |
[INFO] [stderr] 131 | pub fn socket_env(socket_path: &Path) -> HashMap<String, String> {
[INFO] [stderr]     |        ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `create_touch_socket_script` is never used
[INFO] [stderr]    --> tests/common/mod.rs:143:8
[INFO] [stderr]     |
[INFO] [stderr] 143 | pub fn create_touch_socket_script(dir: &TempDir) -> PathBuf {
[INFO] [stderr]     |        ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `test_config_with_limits` is never used
[INFO] [stderr]   --> tests/common/mod.rs:56:8
[INFO] [stderr]    |
[INFO] [stderr] 56 | pub fn test_config_with_limits(
[INFO] [stderr]    |        ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: function `test_config_with_limits` is never used
[INFO] [stderr]   --> tests/common/mod.rs:56:8
[INFO] [stderr]    |
[INFO] [stderr] 56 | pub fn test_config_with_limits(
[INFO] [stderr]    |        ^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `create_touch_socket_script` is never used
[INFO] [stderr]    --> tests/common/mod.rs:143:8
[INFO] [stderr]     |
[INFO] [stderr] 143 | pub fn create_touch_socket_script(dir: &TempDir) -> PathBuf {
[INFO] [stderr]     |        ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `tenement` (lib test) generated 5 warnings (run `cargo fix --lib -p tenement --tests` to apply 1 suggestion)
[INFO] [stderr] warning: `tenement` (test "cgroup_lifecycle") generated 7 warnings
[INFO] [stderr] warning: `tenement` (test "stress_concurrent") generated 7 warnings (6 duplicates)
[INFO] [stderr] warning: `tenement` (test "e2e_lifecycle") generated 5 warnings (4 duplicates)
[INFO] [stderr] warning: `tenement` (test "test_utils_verify") generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.52s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tenement-a05be56d2895850d)
[INFO] [stdout] 
[INFO] [stdout] running 345 tests
[INFO] [stdout] test auth::tests::test_generate_token_entropy ... ok
[INFO] [stdout] test auth::tests::test_generate_token_length ... ok
[INFO] [stdout] test auth::tests::test_generate_token_uniqueness ... ok
[INFO] [stdout] test auth::tests::test_generate_token ... ok
[INFO] [stdout] test auth::tests::test_generate_token_url_safe ... ok
[INFO] [stdout] test auth::tests::test_hash_format_argon2 ... ok
[INFO] [stdout] test auth::tests::test_token_store_clear_idempotent ... ok
[INFO] [stdout] test auth::tests::test_hash_long_token ... ok
[INFO] [stdout] test auth::tests::test_hash_unicode ... ok
[INFO] [stdout] test auth::tests::test_token_store_verify_no_token ... ok
[INFO] [stdout] test auth::tests::test_generate_and_store_returns_unique ... ok
[INFO] [stdout] test auth::tests::test_verify_invalid_hash ... ok
[INFO] [stdout] test auth::tests::test_verify_malformed_hashes ... ok
[INFO] [stdout] test cgroup::tests::linux_tests::test_add_process_no_limits_skips ... ok
[INFO] [stdout] test cgroup::tests::linux_tests::test_cgroup_is_available ... ok
[INFO] [stdout] test cgroup::tests::linux_tests::test_cpu_weight_clamped_maximum ... ignored, requires root/cgroup privileges
[INFO] [stdout] test cgroup::tests::linux_tests::test_cpu_weight_clamped_minimum ... ignored, requires root/cgroup privileges
[INFO] [stdout] test cgroup::tests::linux_tests::test_create_and_remove_cgroup ... ignored, requires root/cgroup privileges
[INFO] [stdout] test cgroup::tests::linux_tests::test_create_cgroup_no_limits_skips ... ok
[INFO] [stdout] test cgroup::tests::linux_tests::test_create_cgroup_sets_cpu_weight ... ignored, requires root/cgroup privileges
[INFO] [stdout] test cgroup::tests::linux_tests::test_create_cgroup_sets_memory_limit ... ignored, requires root/cgroup privileges
[INFO] [stdout] test cgroup::tests::linux_tests::test_remove_nonexistent_cgroup ... ok
[INFO] [stdout] test cgroup::tests::test_cgroup_manager_default ... ok
[INFO] [stdout] test cgroup::tests::test_cgroup_manager_new ... ok
[INFO] [stdout] test cgroup::tests::test_cgroup_path ... ok
[INFO] [stdout] test cgroup::tests::test_cgroup_path_simple_id ... ok
[INFO] [stdout] test cgroup::tests::test_cgroup_path_with_special_chars ... ok
[INFO] [stdout] test cgroup::tests::test_cpu_weight_clamp_logic ... ok
[INFO] [stdout] test cgroup::tests::test_memory_bytes_calculation ... ok
[INFO] [stdout] test cgroup::tests::test_memory_bytes_large_values ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_clone ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_cpu_only ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_debug ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_default ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_has_limits ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_large_values ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_memory_only ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_zero_cpu ... ok
[INFO] [stdout] test cgroup::tests::test_resource_limits_zero_memory ... ok
[INFO] [stdout] test config::tests::test_args_interpolated ... ok
[INFO] [stdout] test config::tests::test_backoff_settings ... ok
[INFO] [stdout] test config::tests::test_backoff_settings_default ... ok
[INFO] [stdout] test config::tests::test_command_interpolated ... ok
[INFO] [stdout] test config::tests::test_config_clone ... ok
[INFO] [stdout] test config::tests::test_config_with_other_options ... ok
[INFO] [stdout] test config::tests::test_default_settings ... ok
[INFO] [stdout] test config::tests::test_duplicate_service_fails ... ok
[INFO] [stdout] test config::tests::test_empty_routing ... ok
[INFO] [stdout] test config::tests::test_explicit_process_isolation ... ok
[INFO] [stdout] test config::tests::test_firecracker_config_with_isolation ... ok
[INFO] [stdout] test config::tests::test_firecracker_defaults ... ok
[INFO] [stdout] test config::tests::test_firecracker_validation_missing_kernel ... ok
[INFO] [stdout] test config::tests::test_firecracker_validation_missing_rootfs ... ok
[INFO] [stdout] test config::tests::test_get_instances_to_spawn ... ok
[INFO] [stdout] test config::tests::test_has_instances_to_spawn ... ok
[INFO] [stdout] test config::tests::test_idle_timeout_config ... ok
[INFO] [stdout] test config::tests::test_idle_timeout_default ... ok
[INFO] [stdout] test config::tests::test_idle_timeout_zero_means_never ... ok
[INFO] [stdout] test config::tests::test_instances_empty_list ... ok
[INFO] [stdout] test config::tests::test_instances_multiple_services_multiple_ids ... ok
[INFO] [stdout] test config::tests::test_instances_references_undefined_service_fails ... ok
[INFO] [stdout] test config::tests::test_instances_section_basic ... ok
[INFO] [stdout] test config::tests::test_instances_section_empty ... ok
[INFO] [stdout] test config::tests::test_instances_section_missing ... ok
[INFO] [stdout] test config::tests::test_instances_with_single_id ... ok
[INFO] [stdout] test config::tests::test_interpolate_with_port ... ok
[INFO] [stdout] test config::tests::test_interpolation ... ok
[INFO] [stdout] test config::tests::test_invalid_toml ... ok
[INFO] [stdout] test config::tests::test_listen_addr_socket ... ok
[INFO] [stdout] test config::tests::test_listen_addr_tcp ... ok
[INFO] [stdout] test config::tests::test_load_from_nonexistent_path ... ok
[INFO] [stdout] test config::tests::test_load_from_path ... ok
[INFO] [stdout] test config::tests::test_multiple_services ... ok
[INFO] [stdout] test config::tests::test_multiple_services_together ... ok
[INFO] [stdout] test config::tests::test_namespace_isolation_default ... ok
[INFO] [stdout] test config::tests::test_parse_full_config ... ok
[INFO] [stdout] test config::tests::test_parse_minimal_config ... ok
[INFO] [stdout] test config::tests::test_resource_limits_both ... ok
[INFO] [stdout] test config::tests::test_resource_limits_cpu ... ok
[INFO] [stdout] test config::tests::test_resource_limits_default_none ... ok
[INFO] [stdout] test config::tests::test_resource_limits_memory ... ok
[INFO] [stdout] test config::tests::test_routing_config ... ok
[INFO] [stdout] test config::tests::test_service_config_clone ... ok
[INFO] [stdout] test config::tests::test_service_restart_policies ... ok
[INFO] [stdout] test config::tests::test_service_with_workdir ... ok
[INFO] [stdout] test config::tests::test_settings_clone ... ok
[INFO] [stdout] test config::tests::test_socket_path ... ok
[INFO] [stdout] test config::tests::test_startup_timeout_config ... ok
[INFO] [stdout] test config::tests::test_startup_timeout_default ... ok
[INFO] [stdout] test config::tests::test_storage_defaults ... ok
[INFO] [stdout] test config::tests::test_storage_persist_config ... ok
[INFO] [stdout] test config::tests::test_storage_quota_and_persist ... ok
[INFO] [stdout] test config::tests::test_storage_quota_config ... ok
[INFO] [stdout] test config::tests::test_storage_quota_large_value ... ok
[INFO] [stdout] test config::tests::test_storage_quota_zero ... ok
[INFO] [stdout] test auth::tests::test_hash_empty_string ... ok
[INFO] [stdout] test hypervisor::tests::test_calculate_backoff ... ok
[INFO] [stdout] test hypervisor::tests::test_calculate_backoff_custom_settings ... ok
[INFO] [stdout] test hypervisor::tests::test_calculate_backoff_overflow_protection ... ok
[INFO] [stdout] test hypervisor::tests::test_calculate_backoff_zero_base ... ok
[INFO] [stdout] test hypervisor::tests::test_blue_green_workflow ... ok
[INFO] [stdout] test auth::tests::test_hash_and_verify ... ok
[INFO] [stdout] test hypervisor::tests::test_check_health_not_running_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_check_health_unknown_process ... ok
[INFO] [stdout] test hypervisor::tests::test_check_health_no_endpoint_socket_file ... ok
[INFO] [stdout] test hypervisor::tests::test_deploy_unknown_process ... ok
[INFO] [stdout] test hypervisor::tests::test_canary_workflow ... ok
[INFO] [stdout] test hypervisor::tests::test_deploy_and_wait_healthy_no_health_endpoint ... ok
[INFO] [stdout] test hypervisor::tests::test_get_and_touch_nonexistent_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_deploy_with_initial_weight ... ok
[INFO] [stdout] test hypervisor::tests::test_get_and_touch_is_atomic ... ok
[INFO] [stdout] test hypervisor::tests::test_has_process ... ok
[INFO] [stdout] test hypervisor::tests::test_get_and_touch_running_instance ... ok
[INFO] [stdout] test auth::tests::test_token_store ... ok
[INFO] [stdout] test hypervisor::tests::test_get_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_list_by_process ... ok
[INFO] [stdout] test hypervisor::tests::test_restart_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_restart_increments_counter ... ok
[INFO] [stdout] test hypervisor::tests::test_list_instances ... ok
[INFO] [stdout] test hypervisor::tests::test_route_swap_from_not_found ... ok
[INFO] [stdout] test hypervisor::tests::test_route_swap_to_not_found ... ok
[INFO] [stdout] test hypervisor::tests::test_route_swap ... ok
[INFO] [stdout] test auth::tests::test_hash_produces_different_hashes ... ok
[INFO] [stdout] test hypervisor::tests::test_select_weighted_no_instances ... ok
[INFO] [stdout] test hypervisor::tests::test_route_swap_weighted_routing ... ok
[INFO] [stdout] test hypervisor::tests::test_select_weighted_single_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_select_weighted_all_zero_weight ... ok
[INFO] [stdout] test hypervisor::tests::test_set_weight_nonexistent_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_select_weighted_distribution ... ok
[INFO] [stdout] test hypervisor::tests::test_set_weight ... ok
[INFO] [stdout] test hypervisor::tests::test_select_weighted_excludes_zero_weight ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_command_not_found ... ok
[INFO] [stdout] test hypervisor::tests::test_set_weight_caps_at_100 ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_configured_instances_empty ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_already_running_returns_socket ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_captures_stderr ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_captures_stdout ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_configured_instances_continues_on_failure ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_configured_instances_single ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_creates_data_directory ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_creates_instance_entry ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_if_not_running ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_configured_instances_multiple ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_unknown_process_returns_error ... ok
[INFO] [stdout] test auth::tests::test_token_store_set_token ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_increments_metrics ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_process_instance ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_returns_socket_path ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_sets_port_env ... ok
[INFO] [stdout] test hypervisor::tests::test_stop_nonexistent_instance_returns_error ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_with_command_string_shell_splits ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_with_explicit_args_no_split ... ok
[INFO] [stdout] test instance::tests::test_health_status_clone ... ok
[INFO] [stdout] test instance::tests::test_health_status_copy ... ok
[INFO] [stdout] test instance::tests::test_health_status_deserialize ... ok
[INFO] [stdout] test instance::tests::test_health_status_display ... ok
[INFO] [stdout] test instance::tests::test_health_status_equality ... ok
[INFO] [stdout] test instance::tests::test_health_status_serialize ... ok
[INFO] [stdout] test instance::tests::test_instance_id_deserialize ... ok
[INFO] [stdout] test instance::tests::test_instance_id_display_roundtrip ... ok
[INFO] [stdout] test instance::tests::test_instance_id_equality ... ok
[INFO] [stdout] test instance::tests::test_instance_id_hash ... ok
[INFO] [stdout] test instance::tests::test_instance_id_new ... ok
[INFO] [stdout] test instance::tests::test_instance_id_parse ... ok
[INFO] [stdout] test instance::tests::test_instance_id_parse_both_empty ... ok
[INFO] [stdout] test instance::tests::test_instance_id_parse_empty_id ... ok
[INFO] [stdout] test instance::tests::test_instance_id_parse_empty_process ... ok
[INFO] [stdout] test instance::tests::test_instance_id_parse_invalid ... ok
[INFO] [stdout] test instance::tests::test_instance_id_serde_roundtrip ... ok
[INFO] [stdout] test instance::tests::test_instance_id_serialize ... ok
[INFO] [stdout] test instance::tests::test_instance_id_with_colons ... ok
[INFO] [stdout] test instance::tests::test_instance_id_with_special_chars ... ok
[INFO] [stdout] test instance::tests::test_instance_info_clone ... ok
[INFO] [stdout] test instance::tests::test_instance_info_debug ... ok
[INFO] [stdout] test instance::tests::test_instance_info_idle_timeout_none ... ok
[INFO] [stdout] test instance::tests::test_instance_info_serialization ... ok
[INFO] [stdout] test instance::tests::test_instance_info_storage_no_quota ... ok
[INFO] [stdout] test instance::tests::test_instance_info_storage_with_quota ... ok
[INFO] [stdout] test instance::tests::test_instance_info_weight ... ok
[INFO] [stdout] test instance::tests::test_instance_info_weight_serialization ... ok
[INFO] [stdout] test instance::tests::test_instance_status_all_variants_serialize ... ok
[INFO] [stdout] test instance::tests::test_instance_status_clone ... ok
[INFO] [stdout] test instance::tests::test_instance_status_copy ... ok
[INFO] [stdout] test instance::tests::test_instance_status_deserialize ... ok
[INFO] [stdout] test instance::tests::test_instance_status_display ... ok
[INFO] [stdout] test instance::tests::test_instance_status_equality ... ok
[INFO] [stdout] test instance::tests::test_instance_status_serialize ... ok
[INFO] [stdout] test instance::tests::test_is_idle_logic_no_timeout ... ok
[INFO] [stdout] test instance::tests::test_is_idle_logic_positive_timeout ... ok
[INFO] [stdout] test instance::tests::test_is_idle_logic_zero_timeout ... ok
[INFO] [stdout] test instance::tests::test_sleeping_status_deserialize ... ok
[INFO] [stdout] test instance::tests::test_sleeping_status_display ... ok
[INFO] [stdout] test instance::tests::test_sleeping_status_serialize ... ok
[INFO] [stdout] test instance::tests::test_uptime_human_boundary_hours_to_days ... ok
[INFO] [stdout] test instance::tests::test_uptime_human_boundary_minutes_to_hours ... ok
[INFO] [stdout] test instance::tests::test_uptime_human_boundary_seconds_to_minutes ... ok
[INFO] [stdout] test instance::tests::test_uptime_human_format_logic ... ok
[INFO] [stdout] test instance::tests::test_uptime_human_large_values ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_is_empty ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_len ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_multiple_subscribers ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_push_and_query ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_push_stderr ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_push_stdout ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_subscribe ... ok
[INFO] [stdout] test logs::tests::test_log_buffer_with_capacity ... ok
[INFO] [stdout] test logs::tests::test_log_entry_clone ... ok
[INFO] [stdout] test logs::tests::test_log_entry_empty_message ... ok
[INFO] [stdout] test logs::tests::test_log_entry_long_message ... ok
[INFO] [stdout] test logs::tests::test_log_entry_new ... ok
[INFO] [stdout] test logs::tests::test_log_entry_serialize ... ok
[INFO] [stdout] test logs::tests::test_log_entry_special_chars ... ok
[INFO] [stdout] test logs::tests::test_log_entry_timestamp_increases ... ok
[INFO] [stdout] test logs::tests::test_log_level_clone ... ok
[INFO] [stdout] test logs::tests::test_log_level_copy ... ok
[INFO] [stdout] test logs::tests::test_log_level_display ... ok
[INFO] [stdout] test logs::tests::test_log_level_equality ... ok
[INFO] [stdout] test logs::tests::test_log_level_serialize ... ok
[INFO] [stdout] test logs::tests::test_log_query_clone ... ok
[INFO] [stdout] test logs::tests::test_log_query_debug ... ok
[INFO] [stdout] test logs::tests::test_log_query_default ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_at_exact_capacity ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_empty ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_eviction ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_push ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_combined_filters ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_filter_instance ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_filter_level ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_filter_process ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_limit ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_limit_larger_than_buffer ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_limit_zero ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_no_match ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_search ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_search_case_sensitive ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_search_empty_string ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_query_search_no_match ... ok
[INFO] [stdout] test instance::tests::test_health_status_all_variants_serialize ... ok
[INFO] [stdout] test logs::tests::test_ring_buffer_single_capacity ... ok
[INFO] [stdout] test metrics::tests::test_counter_inc_by ... ok
[INFO] [stdout] test metrics::tests::test_counter_inc ... ok
[INFO] [stdout] test metrics::tests::test_gauge_inc_dec ... ok
[INFO] [stdout] test metrics::tests::test_gauge_set ... ok
[INFO] [stdout] test metrics::tests::test_histogram_observe ... ok
[INFO] [stdout] test metrics::tests::test_key_to_labels ... ok
[INFO] [stdout] test metrics::tests::test_labeled_counter ... ok
[INFO] [stdout] test metrics::tests::test_labels_to_key ... ok
[INFO] [stdout] test metrics::tests::test_metrics_format_prometheus ... ok
[INFO] [stdout] test port_allocator::tests::test_allocate_multiple_ports ... ok
[INFO] [stdout] test port_allocator::tests::test_allocate_single_port ... ok
[INFO] [stdout] test port_allocator::tests::test_allocated_count ... ok
[INFO] [stdout] test port_allocator::tests::test_available_count ... ok
[INFO] [stdout] test port_allocator::tests::test_concurrent_allocate_and_release ... ok
[INFO] [stdout] test port_allocator::tests::test_concurrent_allocations ... ok
[INFO] [stdout] test port_allocator::tests::test_is_allocated ... ok
[INFO] [stdout] test port_allocator::tests::test_port_range_boundaries ... ok
[INFO] [stdout] test port_allocator::tests::test_allocate_all_ports ... ok
[INFO] [stdout] test port_allocator::tests::test_release_port ... ok
[INFO] [stdout] test port_allocator::tests::test_release_unallocated_port_is_safe ... ok
[INFO] [stdout] test port_allocator::tests::test_wrap_around ... ok
[INFO] [stdout] test runtime::namespace::tests::test_namespace_runtime_availability ... ok
[INFO] [stdout] test runtime::namespace::tests::test_namespace_runtime_name ... ok
[INFO] [stdout] test runtime::namespace::tests::test_namespace_runtime_spawn ... ignored
[INFO] [stdout] test runtime::namespace::tests::test_namespace_runtime_type ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_with_extra_env ... ok
[INFO] [stdout] test hypervisor::tests::test_spawn_with_interpolation_and_shell_split ... ok
[INFO] [stdout] test port_allocator::tests::test_release_and_reallocate ... ok
[INFO] [stdout] test hypervisor::tests::test_stop_instance ... ok
[INFO] [stdout] test runtime::process::tests::test_process_exits_naturally ... ok
[INFO] [stdout] test runtime::process::tests::test_process_exits_with_error ... ok
[INFO] [stdout] test runtime::process::tests::test_process_handle_is_running ... ok
[INFO] [stdout] test runtime::process::tests::test_process_handle_pid ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_default ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_is_available ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_name ... ok
[INFO] [stdout] test runtime::process::tests::test_process_handle_socket ... ok
[INFO] [stdout] test runtime::process::tests::test_process_handle_kill ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn_command_not_found ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn_removes_old_socket ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn_with_env ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn_with_args ... ok
[INFO] [stdout] test runtime::tests::test_runtime_type_default ... ok
[INFO] [stdout] test runtime::tests::test_runtime_type_display ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_type ... ok
[INFO] [stdout] test runtime::tests::test_runtime_type_serde ... ok
[INFO] [stdout] test runtime::tests::test_vm_config_default ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_async ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_spawn_with_workdir ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_empty ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_deeply_nested ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_multiple_files ... ok
[INFO] [stdout] test runtime::process::tests::test_process_runtime_rapid_spawn ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_single_file ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_single_file_path ... ok
[INFO] [stdout] test storage::tests::test_format_bytes ... ok
[INFO] [stdout] test storage::tests::test_format_usage ... ok
[INFO] [stdout] test storage::tests::test_is_over_quota ... ok
[INFO] [stdout] test storage::tests::test_storage_info_clone ... ok
[INFO] [stdout] test storage::tests::test_storage_info_debug ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_nested ... ok
[INFO] [stdout] test storage::tests::test_calculate_dir_size_nonexistent ... ok
[INFO] [stdout] test storage::tests::test_storage_info_deserialize ... ok
[INFO] [stdout] test storage::tests::test_storage_info_deserialize_null_quota ... ok
[INFO] [stdout] test storage::tests::test_storage_info_serialize ... ok
[INFO] [stdout] test storage::tests::test_storage_info_no_quota ... ok
[INFO] [stdout] test storage::tests::test_storage_info_new ... ok
[INFO] [stdout] test storage::tests::test_usage_percent ... ok
[INFO] [stdout] test storage::tests::test_usage_percent_zero_quota ... ok
[INFO] [stdout] test storage::tests::test_usage_percent_zero_used_zero_quota ... ok
[INFO] [stdout] test storage::tests::test_usage_ratio ... ok
[INFO] [stdout] test hypervisor::tests::test_touch_activity ... ok
[INFO] [stdout] test store::tests::test_config_store_get_set ... ok
[INFO] [stdout] test store::tests::test_config_store_delete_nonexistent ... ok
[INFO] [stdout] test store::tests::test_config_store_empty_value ... ok
[INFO] [stdout] test store::tests::test_config_store_long_value ... ok
[INFO] [stdout] test store::tests::test_config_store_special_chars ... ok
[INFO] [stdout] test store::tests::test_init_db ... ok
[INFO] [stdout] test runtime::tests::test_runtime_type_from_str ... ok
[INFO] [stdout] test runtime::process::tests::test_kill_also_kills_grandchildren ... FAILED
[INFO] [stdout] test store::tests::test_iso8601_to_millis_invalid ... ok
[INFO] [stdout] test store::tests::test_config_store_multiple_keys ... ok
[INFO] [stdout] test store::tests::test_init_db_creates_fts_table ... ok
[INFO] [stdout] test store::tests::test_init_db_creates_config_table ... ok
[INFO] [stdout] test store::tests::test_init_db_idempotent ... ok
[INFO] [stdout] test store::tests::test_init_db_creates_indexes ... ok
[INFO] [stdout] test store::tests::test_log_store_count ... ok
[INFO] [stdout] test store::tests::test_log_store_fts_no_match ... ok
[INFO] [stdout] test store::tests::test_log_store_fts_search ... ok
[INFO] [stdout] test store::tests::test_log_store_fts_special_chars ... ok
[INFO] [stdout] test store::tests::test_log_store_fts_with_filter ... ok
[INFO] [stdout] test store::tests::test_log_store_insert_and_query ... ok
[INFO] [stdout] test store::tests::test_log_store_query_empty ... ok
[INFO] [stdout] test store::tests::test_log_store_insert_multiple ... FAILED
[INFO] [stdout] test store::tests::test_log_store_query_combined_filters ... ok
[INFO] [stdout] test store::tests::test_log_store_preserves_timestamp ... ok
[INFO] [stdout] test store::tests::test_log_store_query_filter_level ... ok
[INFO] [stdout] test store::tests::test_millis_to_iso8601_roundtrip ... ok
[INFO] [stdout] test store::tests::test_log_store_query_filter_instance ... ok
[INFO] [stdout] test store::tests::test_log_store_query_filter_process ... ok
[INFO] [stdout] test store::tests::test_log_store_query_limit ... ok
[INFO] [stdout] test store::tests::test_log_store_rotate_keeps_recent ... ok
[INFO] [stdout] test store::tests::test_log_store_rotate ... ok
[INFO] [stdout] test auth::tests::test_token_store_replace_token ... ok
[INFO] [stdout] test auth::tests::test_verify_case_sensitive ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- runtime::process::tests::test_kill_also_kills_grandchildren stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runtime::process::tests::test_kill_also_kills_grandchildren' (491) panicked at src/runtime/process.rs:341:13:
[INFO] [stdout] assertion `left == right` failed: Process group should be dead after kill
[INFO] [stdout]   left: 0
[INFO] [stdout]  right: -1
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::trace_unsynchronized::<std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f2e3ad888ba - <<std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c1f1a4ba060b9bfa]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f2e3ada0f9a - <core[c1f1a4ba060b9bfa]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f2e3ada0f9a - core[c1f1a4ba060b9bfa]::fmt::write
[INFO] [stdout]    6:     0x5f2e3ad8df12 - std[e28293b1aa0f68bd]::io::default_write_fmt::<alloc[fdfd2bd8633a6659]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5f2e3ad8df12 - <alloc[fdfd2bd8633a6659]::vec::Vec<u8> as std[e28293b1aa0f68bd]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5f2e3ad642af - <std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f2e3ad642af - std[e28293b1aa0f68bd]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f2e3ad7f669 - std[e28293b1aa0f68bd]::panicking::default_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f2e3a8ea66c - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5f2e3a8ea66c - test[273d7611820c9051]::test_main_with_exit_callback::<test[273d7611820c9051]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5f2e3ad7f8e2 - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5f2e3ad7f8e2 - std[e28293b1aa0f68bd]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f2e3ad64368 - std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5f2e3ad5b409 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_end_short_backtrace::<std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f2e3ad653bd - __rustc[b7974e8690430dd9]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f2e3ada187c - core[c1f1a4ba060b9bfa]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f2e3ada1793 - core[c1f1a4ba060b9bfa]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:434:23
[INFO] [stdout]   20:     0x5f2e3ac97de9 - core::panicking::assert_failed::h81c5401c3aba620b
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5f2e3a7fe9ff - tenement::runtime::process::tests::test_kill_also_kills_grandchildren::{{closure}}::hd1e965b0dad71d54
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/process.rs:341:13
[INFO] [stdout]   22:     0x5f2e3a747f22 - <core::pin::Pin<P> as core::future::future::Future>::poll::h5dd667d712a1b5f4
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5f2e3a74801d - <core::pin::Pin<P> as core::future::future::Future>::poll::h960ba3745d85e864
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x5f2e3a6e329d - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::ha161aba0caba02e5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   25:     0x5f2e3a6e305b - tokio::task::coop::with_budget::h89b3b58f3a41a429
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x5f2e3a6e305b - tokio::task::coop::budget::h3c707780b007c5c6
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x5f2e3a6e305b - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::he65a857b3cffb5d7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   28:     0x5f2e3a6e02b0 - tokio::runtime::scheduler::current_thread::Context::enter::h70793c79183c9481
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   29:     0x5f2e3a6e1bdd - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h211c770f0ca740c8
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   30:     0x5f2e3a6e1864 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::hbd350a3ffb7c7a92
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   31:     0x5f2e3a7d4e7b - tokio::runtime::context::scoped::Scoped<T>::set::h3f76c54c31e79662
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x5f2e3a7d4bb9 - tokio::runtime::context::set_scheduler::{{closure}}::hf81aff019fde9625
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:38
[INFO] [stdout]   33:     0x5f2e3a744ea2 - std::thread::local::LocalKey<T>::try_with::h6cdf91632f6db75e
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/local.rs:513:12
[INFO] [stdout]   34:     0x5f2e3a74414e - std::thread::local::LocalKey<T>::with::h6f0f8b3cb6c267a5
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/local.rs:477:20
[INFO] [stdout]   35:     0x5f2e3a7d4aed - tokio::runtime::context::set_scheduler::h1659eb3484d66ce1
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:17
[INFO] [stdout]   36:     0x5f2e3a6e15f0 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hd1c132e766d65cfd
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   37:     0x5f2e3a6e1926 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hdd43301ff048e0dd
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   38:     0x5f2e3a6dcec0 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h3dc53efe898d5c8a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   39:     0x5f2e3a6d98f3 - tokio::runtime::context::runtime::enter_runtime::h6bbfe2e0df78de42
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x5f2e3a6dcbd1 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hab4742f8cda1ce05
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   41:     0x5f2e3a60c644 - tokio::runtime::runtime::Runtime::block_on_inner::h60b46f651421faa1
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   42:     0x5f2e3a60c80f - tokio::runtime::runtime::Runtime::block_on::ha96970784b022cc5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   43:     0x5f2e3a7fdc01 - tenement::runtime::process::tests::test_kill_also_kills_grandchildren::hf8ec0208ce246340
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/process.rs:339:9
[INFO] [stdout]   44:     0x5f2e3a7fea57 - tenement::runtime::process::tests::test_kill_also_kills_grandchildren::{{closure}}::hde02c0cf623052df
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/process.rs:316:50
[INFO] [stdout]   45:     0x5f2e3a5866e6 - core::ops::function::FnOnce::call_once::h56797b6a96ac6f92
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5f2e3a8de74b - <fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x5f2e3a8de74b - test[273d7611820c9051]::__rust_begin_short_backtrace::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:663:18
[INFO] [stdout]   48:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:74
[INFO] [stdout]   49:     0x5f2e3a8eb13b - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   50:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panicking::catch_unwind::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test_in_process
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:27
[INFO] [stdout]   54:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:607:43
[INFO] [stdout]   55:     0x5f2e3a8e6854 - test[273d7611820c9051]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:637:41
[INFO] [stdout]   56:     0x5f2e3a8e6854 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_begin_short_backtrace::<test[273d7611820c9051]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   58:     0x5f2e3a8edd42 - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   59:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panicking::catch_unwind::<(), core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   63:     0x5f2e3a8edd42 - <std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1} as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x5f2e3ad87c6f - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn core[c1f1a4ba060b9bfa]::ops::function::FnOnce<(), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   65:     0x5f2e3ad87c6f - <std[e28293b1aa0f68bd]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x7dc313704aa4 - <unknown>
[INFO] [stdout]   67:     0x7dc313791a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- store::tests::test_log_store_insert_multiple stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'store::tests::test_log_store_insert_multiple' (615) panicked at src/store.rs:885:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: 0
[INFO] [stdout]  right: 10
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::backtrace_rs::backtrace::trace_unsynchronized::<std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f2e3ad888ba - std[e28293b1aa0f68bd]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f2e3ad888ba - <<std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[c1f1a4ba060b9bfa]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f2e3ada0f9a - <core[c1f1a4ba060b9bfa]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f2e3ada0f9a - core[c1f1a4ba060b9bfa]::fmt::write
[INFO] [stdout]    6:     0x5f2e3ad8df12 - std[e28293b1aa0f68bd]::io::default_write_fmt::<alloc[fdfd2bd8633a6659]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5f2e3ad8df12 - <alloc[fdfd2bd8633a6659]::vec::Vec<u8> as std[e28293b1aa0f68bd]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5f2e3ad642af - <std[e28293b1aa0f68bd]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f2e3ad642af - std[e28293b1aa0f68bd]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f2e3ad7f669 - std[e28293b1aa0f68bd]::panicking::default_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f2e3a8ea66c - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5f2e3a8ea66c - test[273d7611820c9051]::test_main_with_exit_callback::<test[273d7611820c9051]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5f2e3ad7f8e2 - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn for<'a, 'b> core[c1f1a4ba060b9bfa]::ops::function::Fn<(&'a std[e28293b1aa0f68bd]::panic::PanicHookInfo<'b>,), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Sync + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::Fn<(&std[e28293b1aa0f68bd]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5f2e3ad7f8e2 - std[e28293b1aa0f68bd]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f2e3ad64368 - std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5f2e3ad5b409 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_end_short_backtrace::<std[e28293b1aa0f68bd]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f2e3ad653bd - __rustc[b7974e8690430dd9]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f2e3ada187c - core[c1f1a4ba060b9bfa]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f2e3ada1733 - core[c1f1a4ba060b9bfa]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:439:17
[INFO] [stdout]   20:     0x5f2e3a7d34b7 - core::panicking::assert_failed::h3611af5c94384730
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panicking.rs:394:5
[INFO] [stdout]   21:     0x5f2e3a7eaf6a - tenement::store::tests::test_log_store_insert_multiple::{{closure}}::hf9a09f59dfbd4418
[INFO] [stdout]                                at /opt/rustwide/workdir/src/store.rs:885:9
[INFO] [stdout]   22:     0x5f2e3a747f22 - <core::pin::Pin<P> as core::future::future::Future>::poll::h5dd667d712a1b5f4
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5f2e3a74801d - <core::pin::Pin<P> as core::future::future::Future>::poll::h960ba3745d85e864
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x5f2e3a6e329d - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::ha161aba0caba02e5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   25:     0x5f2e3a6e305b - tokio::task::coop::with_budget::h89b3b58f3a41a429
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x5f2e3a6e305b - tokio::task::coop::budget::h3c707780b007c5c6
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x5f2e3a6e305b - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::he65a857b3cffb5d7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   28:     0x5f2e3a6e02b0 - tokio::runtime::scheduler::current_thread::Context::enter::h70793c79183c9481
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   29:     0x5f2e3a6e1bdd - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h211c770f0ca740c8
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   30:     0x5f2e3a6e1864 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::hbd350a3ffb7c7a92
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   31:     0x5f2e3a7d4e7b - tokio::runtime::context::scoped::Scoped<T>::set::h3f76c54c31e79662
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x5f2e3a7d4bb9 - tokio::runtime::context::set_scheduler::{{closure}}::hf81aff019fde9625
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:38
[INFO] [stdout]   33:     0x5f2e3a744ea2 - std::thread::local::LocalKey<T>::try_with::h6cdf91632f6db75e
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/local.rs:513:12
[INFO] [stdout]   34:     0x5f2e3a74414e - std::thread::local::LocalKey<T>::with::h6f0f8b3cb6c267a5
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/local.rs:477:20
[INFO] [stdout]   35:     0x5f2e3a7d4aed - tokio::runtime::context::set_scheduler::h1659eb3484d66ce1
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:17
[INFO] [stdout]   36:     0x5f2e3a6e15f0 - tokio::runtime::scheduler::current_thread::CoreGuard::enter::hd1c132e766d65cfd
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   37:     0x5f2e3a6e1926 - tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hdd43301ff048e0dd
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   38:     0x5f2e3a6dcec0 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::{{closure}}::h3dc53efe898d5c8a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   39:     0x5f2e3a6d98f3 - tokio::runtime::context::runtime::enter_runtime::h6bbfe2e0df78de42
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x5f2e3a6dcbd1 - tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hab4742f8cda1ce05
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   41:     0x5f2e3a60c644 - tokio::runtime::runtime::Runtime::block_on_inner::h60b46f651421faa1
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   42:     0x5f2e3a60c80f - tokio::runtime::runtime::Runtime::block_on::ha96970784b022cc5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   43:     0x5f2e3a7ea51d - tenement::store::tests::test_log_store_insert_multiple::hb383def03842b279
[INFO] [stdout]                                at /opt/rustwide/workdir/src/store.rs:885:30
[INFO] [stdout]   44:     0x5f2e3a7ea5b7 - tenement::store::tests::test_log_store_insert_multiple::{{closure}}::h5b96f04836f8437a
[INFO] [stdout]                                at /opt/rustwide/workdir/src/store.rs:874:46
[INFO] [stdout]   45:     0x5f2e3a589ab6 - core::ops::function::FnOnce::call_once::hbd8966fed107c719
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5f2e3a8de74b - <fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x5f2e3a8de74b - test[273d7611820c9051]::__rust_begin_short_backtrace::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, fn() -> core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:663:18
[INFO] [stdout]   48:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:74
[INFO] [stdout]   49:     0x5f2e3a8eb13b - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   50:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panicking::catch_unwind::<core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>, core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x5f2e3a8eb13b - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<test[273d7611820c9051]::run_test_in_process::{closure#0}>, core[c1f1a4ba060b9bfa]::result::Result<(), alloc[fdfd2bd8633a6659]::string::String>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test_in_process
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:686:27
[INFO] [stdout]   54:     0x5f2e3a8eb13b - test[273d7611820c9051]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:607:43
[INFO] [stdout]   55:     0x5f2e3a8e6854 - test[273d7611820c9051]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/test/src/lib.rs:637:41
[INFO] [stdout]   56:     0x5f2e3a8e6854 - std[e28293b1aa0f68bd]::sys::backtrace::__rust_begin_short_backtrace::<test[273d7611820c9051]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   58:     0x5f2e3a8edd42 - <core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   59:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panicking::catch_unwind::do_call::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panicking::catch_unwind::<(), core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::panic::catch_unwind::<core[c1f1a4ba060b9bfa]::panic::unwind_safe::AssertUnwindSafe<std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5f2e3a8edd42 - std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked::<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   63:     0x5f2e3a8edd42 - <std[e28293b1aa0f68bd]::thread::lifecycle::spawn_unchecked<test[273d7611820c9051]::run_test::{closure#1}, ()>::{closure#1} as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x5f2e3ad87c6f - <alloc[fdfd2bd8633a6659]::boxed::Box<dyn core[c1f1a4ba060b9bfa]::ops::function::FnOnce<(), Output = ()> + core[c1f1a4ba060b9bfa]::marker::Send> as core[c1f1a4ba060b9bfa]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   65:     0x5f2e3ad87c6f - <std[e28293b1aa0f68bd]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/59807616e1fa2540724bfbac14d7976d7e4a3860/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x7dc313704aa4 - <unknown>
[INFO] [stdout]   67:     0x7dc313791a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     runtime::process::tests::test_kill_also_kills_grandchildren
[INFO] [stdout]     store::tests::test_log_store_insert_multiple
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 337 passed; 2 failed; 6 ignored; 0 measured; 0 filtered out; finished in 29.45s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "6e8d79130fe7d5f4ea5aa08a3ee52b50408fa03ef2880204b76a803571a2be07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6e8d79130fe7d5f4ea5aa08a3ee52b50408fa03ef2880204b76a803571a2be07", kill_on_drop: false }`
[INFO] [stdout] 6e8d79130fe7d5f4ea5aa08a3ee52b50408fa03ef2880204b76a803571a2be07
