[INFO] cloning repository https://github.com/roscore/razer-chroma-mcp
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/roscore/razer-chroma-mcp" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Froscore%2Frazer-chroma-mcp", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Froscore%2Frazer-chroma-mcp'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 4ee456f548ad48d418d0e13c69a89e8f585df31f
[INFO] testing roscore/razer-chroma-mcp against 1.98.0-beta.1 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Froscore%2Frazer-chroma-mcp" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/roscore/razer-chroma-mcp
[INFO] finished tweaking git repo https://github.com/roscore/razer-chroma-mcp
[INFO] tweaked toml for git repo https://github.com/roscore/razer-chroma-mcp written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/roscore/razer-chroma-mcp on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/roscore/razer-chroma-mcp 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.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774
[INFO] running `Command { std: "docker" "start" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rustls v0.23.38
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling ipnet v2.12.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling webpki-roots v1.0.7
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling rmcp v1.5.0
[INFO] [stderr]    Compiling iri-string v0.7.12
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling pastey v0.2.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling displaydoc v0.2.5
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling rustls-webpki v0.103.12
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling schemars_derive v1.2.1
[INFO] [stderr]    Compiling rmcp-macros v1.5.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling schemars v1.2.1
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling tracing-appender v0.2.4
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling razer-chroma-mcp v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 12s
[INFO] running `Command { std: "docker" "inspect" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling hashbrown v0.17.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling num_cpus v1.17.0
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling deadpool-runtime v0.1.4
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling filetime v0.2.27
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling insta v1.47.2
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling deadpool v0.12.3
[INFO] [stderr]    Compiling tracing-appender v0.2.4
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling rmcp v1.5.0
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling wiremock v0.6.5
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling razer-chroma-mcp v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 40s
[INFO] running `Command { std: "docker" "inspect" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.37s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/razer_chroma_mcp-c2081804d2b177ee)
[INFO] [stdout] 
[INFO] [stdout] running 102 tests
[INFO] [stdout] test chroma::color::tests::bgr_u32_black_is_zero ... ok
[INFO] [stdout] test chroma::color::tests::bgr_u32_round_trip ... ok
[INFO] [stdout] test chroma::color::tests::parses_case_insensitive ... ok
[INFO] [stdout] test chroma::color::tests::parses_long_hex ... ok
[INFO] [stdout] test chroma::color::tests::parses_short_hex ... ok
[INFO] [stdout] test chroma::color::tests::rejects_missing_hash ... ok
[INFO] [stdout] test chroma::color::tests::rejects_bad_length ... ok
[INFO] [stdout] test chroma::color::tests::rejects_non_hex_chars ... ok
[INFO] [stdout] test chroma::devices::tests::keyboard_grid_is_6x22 ... ok
[INFO] [stdout] test chroma::devices::tests::parse_is_case_insensitive ... ok
[INFO] [stdout] test chroma::color::tests::bgr_u32_packs_low_to_high ... ok
[INFO] [stdout] test chroma::devices::tests::parse_rejects_unknown ... ok
[INFO] [stdout] test chroma::devices::tests::path_segment_stable ... ok
[INFO] [stdout] test chroma::effects::tests::keyboard_custom_serializes_as_6x22 ... ok
[INFO] [stdout] test chroma::client::tests::connection_refused_maps_to_synapse_not_running ... ok
[INFO] [stdout] test chroma::effects::tests::validate_grid_rejects_wrong_size ... ok
[INFO] [stdout] test chroma::effects::tests::validate_grid_accepts_correct_size ... ok
[INFO] [stdout] test cli::pattern::tests::unknown_pattern_returns_error ... ok
[INFO] [stdout] test cli::uninstall::tests::missing_file_is_noop ... ok
[INFO] [stdout] test cli::uninstall::tests::removes_our_key_and_preserves_others ... ok
[INFO] [stdout] test config::merge::tests::bare_object_adds_mcp_servers ... ok
[INFO] [stdout] test config::merge::tests::empty_file_treated_as_empty_object ... ok
[INFO] [stdout] test config::merge::tests::malformed_json_quarantines_and_errors ... ok
[INFO] [stdout] test config::merge::tests::missing_file_creates_it ... ok
[INFO] [stdout] test config::merge::tests::non_object_mcp_servers_errors ... ok
[INFO] [stdout] test config::merge::tests::non_object_root_errors ... ok
[INFO] [stdout] test config::merge::tests::preserves_existing_other_servers ... ok
[INFO] [stdout] test chroma::client::tests::chroma_module_disabled_is_distinct_from_synapse_not_running ... ok
[INFO] [stdout] test config::paths::tests::has_entry_detects_mcp_servers_key ... ok
[INFO] [stdout] test config::merge::tests::replaces_existing_entry_with_different_values ... ok
[INFO] [stdout] test config::paths::tests::has_entry_returns_false_when_key_absent ... ok
[INFO] [stdout] test mcp::tools::tests::color_input_parses_hex ... ok
[INFO] [stdout] test config::paths::tests::has_entry_returns_none_on_missing_file ... ok
[INFO] [stdout] test mcp::tools::tests::empty_args_deserializes_from_empty_object ... ok
[INFO] [stdout] test cli::uninstall::tests::no_entry_no_write ... ok
[INFO] [stdout] test mcp::tools::tests::color_input_parses_rgb_object ... ok
[INFO] [stdout] test mcp::tools::tests::signal_error_args_message_optional ... ok
[INFO] [stdout] test signals::controller::tests::cp_complete ... ok
[INFO] [stdout] test signals::controller::tests::cp_error ... ok
[INFO] [stdout] test signals::controller::tests::cp_idle ... ok
[INFO] [stdout] test chroma::effects::tests::breathing_two_colors_snapshot ... ok
[INFO] [stdout] test chroma::effects::tests::static_effect_snapshot ... ok
[INFO] [stdout] test chroma::effects::tests::none_effect_snapshot ... ok
[INFO] [stdout] test signals::controller::tests::cp_thinking ... ok
[INFO] [stdout] test signals::controller::tests::err_complete ... ok
[INFO] [stdout] test signals::controller::tests::err_error ... ok
[INFO] [stdout] test signals::controller::tests::err_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::cp_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::idle_complete ... ok
[INFO] [stdout] test signals::controller::tests::cp_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::err_thinking ... ok
[INFO] [stdout] test signals::controller::tests::idle_error ... ok
[INFO] [stdout] test signals::controller::tests::idle_idle ... ok
[INFO] [stdout] test signals::controller::tests::idle_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::idle_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::idle_thinking ... ok
[INFO] [stdout] test signals::controller::tests::ni_idle ... ok
[INFO] [stdout] test signals::controller::tests::ni_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::ni_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::off_complete ... ok
[INFO] [stdout] test signals::controller::tests::off_error ... ok
[INFO] [stdout] test signals::controller::tests::off_idle ... ok
[INFO] [stdout] test signals::controller::tests::off_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::off_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::off_thinking ... ok
[INFO] [stdout] test signals::controller::tests::th_complete ... ok
[INFO] [stdout] test signals::controller::tests::th_error ... ok
[INFO] [stdout] test signals::controller::tests::th_idle ... ok
[INFO] [stdout] test signals::controller::tests::th_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::th_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::th_thinking ... ok
[INFO] [stdout] test signals::controller::tests::err_idle ... ok
[INFO] [stdout] test signals::controller::tests::err_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::ni_complete ... ok
[INFO] [stdout] test signals::controller::tests::ni_error ... ok
[INFO] [stdout] test signals::controller::tests::uc_complete ... ok
[INFO] [stdout] test signals::controller::tests::uc_idle ... ok
[INFO] [stdout] test signals::controller::tests::ni_thinking ... ok
[INFO] [stdout] test signals::controller::tests::uc_error ... ok
[INFO] [stdout] test signals::controller::tests::uc_lowlevel ... ok
[INFO] [stdout] test signals::controller::tests::uc_needs_input ... ok
[INFO] [stdout] test signals::controller::tests::uc_thinking ... ok
[INFO] [stdout] test signals::palette::tests::lerp_endpoints_return_endpoints ... ok
[INFO] [stdout] test signals::patterns::complete::tests::asterisk_has_lit_center_ray ... ok
[INFO] [stdout] test signals::patterns::complete::tests::asterisk_mask_is_6x22 ... ok
[INFO] [stdout] test signals::palette::tests::scale_to_half_halves_channels ... ok
[INFO] [stdout] test signals::inactivity::tests::bump_resets_elapsed ... ok
[INFO] [stdout] test signals::inactivity::tests::eligibility_excludes_thinking_and_needs_input ... ok
[INFO] [stdout] test signals::patterns::complete::tests::last_tick_returns_dark_frame ... ok
[INFO] [stdout] test signals::palette::tests::scale_is_clamped ... ok
[INFO] [stdout] test signals::patterns::needs_input::tests::stage1_produces_three_flashes_at_90_percent_within_900ms ... ok
[INFO] [stdout] test signals::patterns::complete::tests::total_runs_approximately_1_8_seconds ... ok
[INFO] [stdout] test signals::patterns::thinking::tests::frame_is_6_by_22 ... ok
[INFO] [stdout] test signals::tests::as_persistent_returns_none_for_complete ... ok
[INFO] [stdout] test signals::patterns::thinking::tests::is_animated_across_cycle ... ok
[INFO] [stdout] test signals::tests::claudestate_is_copy_and_small ... ok
[INFO] [stdout] test signals::tests::persistent_round_trip ... ok
[INFO] [stdout] test config::merge::tests::idempotent_rerun_does_not_write ... ok
[INFO] [stdout] test signals::patterns::needs_input::tests::stage2_breathing_amplitude_at_least_40_percent ... ok
[INFO] [stdout] test signals::patterns::needs_input::tests::stage3_accents_differ_from_base_on_counter_rhythm ... ok
[INFO] [stdout] test signals::patterns::thinking::tests::brightness_stays_in_35_to_65_percent_band ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/razer_chroma_mcp-7a107167e4cb5688)
[INFO] [stdout] test config::merge::tests::backup_rotation_keeps_three ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 102 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test tests::prune_is_silent_on_missing_dir ... ok
[INFO] [stdout] test tests::prune_removes_files_older_than_retention_window ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chroma_client.rs (/opt/rustwide/target/debug/deps/chroma_client-b1b15bfc984d88ab)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test init_session_returns_session_id_and_uri ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chroma_session.rs (/opt/rustwide/target/debug/deps/chroma_session-33b004fd469f1120)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test heartbeat_loop_retries_and_reconnects_after_three_failures ... ok
[INFO] [stderr]      Running tests/mcp_subprocess.rs (/opt/rustwide/target/debug/deps/mcp_subprocess-41fa4669d5afd2a8)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.32s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] 
[INFO] [stderr] thread 'main' (2640) panicked at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-appender-0.2.4/src/rolling.rs:154:14:
[INFO] [stderr] initializing rolling file appender failed: InitError { context: "failed to create log directory", source: Os { code: 30, kind: ReadOnlyFilesystem, message: "Read-only file system" } }
[INFO] [stderr] stack backtrace:
[INFO] [stderr]    0:     0x5ca43b0f6901 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stderr]    1:     0x5ca43b0f6901 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stderr]    2:     0x5ca43b0f6901 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stderr]    3:     0x5ca43b0f6901 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stderr]    4:     0x5ca43b10fa4a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stderr]    5:     0x5ca43b10fa4a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stderr]    6:     0x5ca43b0fd4cc - std[73adb7dc35730857]::io::default_write_fmt::<std[73adb7dc35730857]::sys::stdio::unix::Stderr>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stderr]    7:     0x5ca43b0fd4cc - <std[73adb7dc35730857]::sys::stdio::unix::Stderr as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stderr]    8:     0x5ca43b0d84d6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stderr]    9:     0x5ca43b0d84d6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stderr]   10:     0x5ca43b0ed1a1 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:319:9
[INFO] [stderr]   11:     0x5ca43b0ed4cb - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:815:13
[INFO] [stderr]   12:     0x5ca43b0d8582 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stderr]   13:     0x5ca43b0cf629 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stderr]   14:     0x5ca43b0d95dd - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stderr]   15:     0x5ca43b1102fc - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stderr]   16:     0x5ca43b110042 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stderr]   17:     0x5ca43a40584c - <core[6883ba1bc0fe4ed1]::result::Result<tracing_appender[5e91117d45064f13]::rolling::RollingFileAppender, tracing_appender[5e91117d45064f13]::rolling::builder::InitError>>::expect
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stderr]   18:     0x5ca43a418eb5 - <tracing_appender[5e91117d45064f13]::rolling::RollingFileAppender>::new::<&std[73adb7dc35730857]::path::PathBuf, &str>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-appender-0.2.4/src/rolling.rs:154:14
[INFO] [stderr]   19:     0x5ca43a41b1fe - tracing_appender[5e91117d45064f13]::rolling::daily::<&std[73adb7dc35730857]::path::PathBuf, &str>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-appender-0.2.4/src/rolling.rs:370:5
[INFO] [stderr]   20:     0x5ca43a40f048 - razer_chroma_mcp[ccca019fec3b35c7]::init_tracing
[INFO] [stderr]                                at /opt/rustwide/workdir/src/main.rs:69:25
[INFO] [stderr]   21:     0x5ca43a40e435 - razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/src/main.rs:38:5
[INFO] [stderr]   22:     0x5ca43a410c9d - <core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>> as core[6883ba1bc0fe4ed1]::future::future::Future>::poll
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/future/future.rs:133:9
[INFO] [stderr]   23:     0x5ca43a413b0d - <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/park.rs:284:71
[INFO] [stderr]   24:     0x5ca43a411a7f - tokio[7a05b73ab93eb0dd]::task::coop::with_budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>, <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:167:5
[INFO] [stderr]   25:     0x5ca43a411a7f - tokio[7a05b73ab93eb0dd]::task::coop::budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>, <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:133:5
[INFO] [stderr]   26:     0x5ca43a411a7f - <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/park.rs:284:31
[INFO] [stderr]   27:     0x5ca43a40ad10 - <tokio[7a05b73ab93eb0dd]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/blocking.rs:66:14
[INFO] [stderr]   28:     0x5ca43a40de1e - <tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/multi_thread/mod.rs:92:22
[INFO] [stderr]   29:     0x5ca43a40444e - tokio[7a05b73ab93eb0dd]::runtime::context::runtime::enter_runtime::<<tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>::{closure#0}, core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/runtime.rs:65:16
[INFO] [stderr]   30:     0x5ca43a40af71 - <tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/multi_thread/mod.rs:91:9
[INFO] [stderr]   31:     0x5ca43a4024a0 - <tokio[7a05b73ab93eb0dd]::runtime::runtime::Runtime>::block_on_inner::<core[6883ba1bc0fe4ed1]::pin::Pin<alloc[55a36b64bcbf2c0d]::boxed::Box<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:373:50
[INFO] [stderr]   32:     0x5ca43a4029c2 - <tokio[7a05b73ab93eb0dd]::runtime::runtime::Runtime>::block_on::<razer_chroma_mcp[ccca019fec3b35c7]::main::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:343:18
[INFO] [stderr]   33:     0x5ca43a40f8b9 - razer_chroma_mcp[ccca019fec3b35c7]::main
[INFO] [stderr]                                at /opt/rustwide/workdir/src/main.rs:41:49
[INFO] [stderr]   34:     0x5ca43a410e1b - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stderr]   35:     0x5ca43a40d5de - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>, core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stderr]   36:     0x5ca43a4049d1 - std[73adb7dc35730857]::rt::lang_start::<core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>::{closure#0}
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/rt.rs:206:18
[INFO] [stderr]   37:     0x5ca43b0ec647 - <&dyn core[6883ba1bc0fe4ed1]::ops::function::Fn<(), Output = i32> + core[6883ba1bc0fe4ed1]::panic::unwind_safe::RefUnwindSafe + core[6883ba1bc0fe4ed1]::marker::Sync as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:287:21
[INFO] [stderr]   38:     0x5ca43b0ec647 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<&dyn core[6883ba1bc0fe4ed1]::ops::function::Fn<(), Output = i32> + core[6883ba1bc0fe4ed1]::panic::unwind_safe::RefUnwindSafe + core[6883ba1bc0fe4ed1]::marker::Sync, i32>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stderr]   39:     0x5ca43b0ec647 - std[73adb7dc35730857]::panicking::catch_unwind::<i32, &dyn core[6883ba1bc0fe4ed1]::ops::function::Fn<(), Output = i32> + core[6883ba1bc0fe4ed1]::panic::unwind_safe::RefUnwindSafe + core[6883ba1bc0fe4ed1]::marker::Sync>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stderr]   40:     0x5ca43b0ec647 - std[73adb7dc35730857]::panic::catch_unwind::<&dyn core[6883ba1bc0fe4ed1]::ops::function::Fn<(), Output = i32> + core[6883ba1bc0fe4ed1]::panic::unwind_safe::RefUnwindSafe + core[6883ba1bc0fe4ed1]::marker::Sync, i32>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stderr]   41:     0x5ca43b0ec647 - std[73adb7dc35730857]::rt::lang_start_internal::{closure#0}
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/rt.rs:175:24
[INFO] [stderr]   42:     0x5ca43b0ec647 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<std[73adb7dc35730857]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stderr]   43:     0x5ca43b0ec647 - std[73adb7dc35730857]::panicking::catch_unwind::<isize, std[73adb7dc35730857]::rt::lang_start_internal::{closure#0}>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stderr]   44:     0x5ca43b0ec647 - std[73adb7dc35730857]::panic::catch_unwind::<std[73adb7dc35730857]::rt::lang_start_internal::{closure#0}, isize>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stderr]   45:     0x5ca43b0ec647 - std[73adb7dc35730857]::rt::lang_start_internal
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/rt.rs:171:5
[INFO] [stderr]   46:     0x5ca43a404207 - std[73adb7dc35730857]::rt::lang_start::<core[6883ba1bc0fe4ed1]::result::Result<(), anyhow[3b9a07a6948cd063]::Error>>
[INFO] [stderr]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/rt.rs:205:5
[INFO] [stderr]   47:     0x5ca43a4113ae - main
[INFO] [stderr]   48:     0x7e3607b121ca - <unknown>
[INFO] [stderr]   49:     0x7e3607b1228b - __libc_start_main
[INFO] [stderr]   50:     0x5ca43a3f7e25 - _start
[INFO] [stderr]   51:                0x0 - <unknown>
[INFO] [stdout] test get_session_status_round_trip ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- get_session_status_round_trip stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_session_status_round_trip' (2630) panicked at tests/mcp_subprocess.rs:27:5:
[INFO] [stdout] server closed stdout before sending a frame
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x576f90a724e1 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x576f90a724e1 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x576f90a724e1 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x576f90a724e1 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x576f90a8853a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x576f90a8853a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x576f90a771bc - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x576f90a771bc - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x576f90a4eef6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x576f90a4eef6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x576f90a69d89 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x576f907b6f90 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x576f907b6f90 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x576f90a6a0b2 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x576f90a6a0b2 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x576f90a4efd4 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:681:13
[INFO] [stdout]   16:     0x576f90a46829 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x576f90a4fd8d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x576f90a88d6c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x576f906fd96e - mcp_subprocess[ac0267cd7178ee6e]::recv::<tokio[7a05b73ab93eb0dd]::process::ChildStdout>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/mcp_subprocess.rs:27:5
[INFO] [stdout]   20:     0x576f9079e4d7 - <tokio[7a05b73ab93eb0dd]::time::timeout::Timeout<mcp_subprocess[ac0267cd7178ee6e]::recv<tokio[7a05b73ab93eb0dd]::process::ChildStdout>::{closure#0}> as core[6883ba1bc0fe4ed1]::future::future::Future>::poll
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/time/timeout.rs:202:42
[INFO] [stdout]   21:     0x576f907001d9 - mcp_subprocess[ac0267cd7178ee6e]::get_session_status_round_trip::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/mcp_subprocess.rs:75:10
[INFO] [stdout]   22:     0x576f9072c3c2 - <core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>> as core[6883ba1bc0fe4ed1]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x576f9078f7ed - <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/park.rs:284:71
[INFO] [stdout]   24:     0x576f9078ab86 - tokio[7a05b73ab93eb0dd]::task::coop::with_budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<()>, <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   25:     0x576f9078ab86 - tokio[7a05b73ab93eb0dd]::task::coop::budget::<core[6883ba1bc0fe4ed1]::task::poll::Poll<()>, <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   26:     0x576f9078ab86 - <tokio[7a05b73ab93eb0dd]::runtime::park::CachedParkThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/park.rs:284:31
[INFO] [stdout]   27:     0x576f90706684 - <tokio[7a05b73ab93eb0dd]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   28:     0x576f907962b8 - <tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/multi_thread/mod.rs:92:22
[INFO] [stdout]   29:     0x576f90727dcb - tokio[7a05b73ab93eb0dd]::runtime::context::runtime::enter_runtime::<<tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   30:     0x576f90793d34 - <tokio[7a05b73ab93eb0dd]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/scheduler/multi_thread/mod.rs:91:9
[INFO] [stdout]   31:     0x576f906eb644 - <tokio[7a05b73ab93eb0dd]::runtime::runtime::Runtime>::block_on_inner::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:373:50
[INFO] [stdout]   32:     0x576f906eb9ef - <tokio[7a05b73ab93eb0dd]::runtime::runtime::Runtime>::block_on::<core[6883ba1bc0fe4ed1]::pin::Pin<&mut dyn core[6883ba1bc0fe4ed1]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.1/src/runtime/runtime.rs:345:18
[INFO] [stdout]   33:     0x576f90702ae5 - mcp_subprocess[ac0267cd7178ee6e]::get_session_status_round_trip
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/mcp_subprocess.rs:121:31
[INFO] [stdout]   34:     0x576f906fe3b7 - mcp_subprocess[ac0267cd7178ee6e]::get_session_status_round_trip::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/mcp_subprocess.rs:35:41
[INFO] [stdout]   35:     0x576f907786a6 - <mcp_subprocess[ac0267cd7178ee6e]::get_session_status_round_trip::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x576f907aa2cb - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x576f907aa2cb - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   38:     0x576f907b7915 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   39:     0x576f907b7915 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x576f907b7915 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   41:     0x576f907b7915 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x576f907b7915 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x576f907b7915 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   44:     0x576f907b7915 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   45:     0x576f907b23c4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   46:     0x576f907b23c4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x576f907baa62 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   48:     0x576f907baa62 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x576f907baa62 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   50:     0x576f907baa62 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x576f907baa62 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x576f907baa62 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   53:     0x576f907baa62 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x576f90a7176f - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   55:     0x576f90a7176f - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   56:     0x7596a44d1aa4 - <unknown>
[INFO] [stdout]   57:     0x7596a455ea64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     get_session_status_round_trip
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test mcp_subprocess`
[INFO] running `Command { std: "docker" "inspect" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774", kill_on_drop: false }`
[INFO] [stdout] e0d2dce787f6163a3531b10938b35fd23341b15d95b9dea19885ef330d978774
