[INFO] cloning repository https://github.com/philo-groves/fiddlesticks [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/philo-groves/fiddlesticks" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilo-groves%2Ffiddlesticks", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilo-groves%2Ffiddlesticks'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 01ea5ae847d02c09fd52c2c2462d3f4e4616e436 [INFO] testing philo-groves/fiddlesticks against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilo-groves%2Ffiddlesticks" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/philo-groves/fiddlesticks [INFO] finished tweaking git repo https://github.com/philo-groves/fiddlesticks [INFO] tweaked toml for git repo https://github.com/philo-groves/fiddlesticks written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/philo-groves/fiddlesticks on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/philo-groves/fiddlesticks 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] b16b6ae96a81db4a0a400fe1dabe8fdbcdceb92c52efef8828f390502a6ee09f [INFO] running `Command { std: "docker" "start" "-a" "b16b6ae96a81db4a0a400fe1dabe8fdbcdceb92c52efef8828f390502a6ee09f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "b16b6ae96a81db4a0a400fe1dabe8fdbcdceb92c52efef8828f390502a6ee09f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b16b6ae96a81db4a0a400fe1dabe8fdbcdceb92c52efef8828f390502a6ee09f", kill_on_drop: false }` [INFO] [stdout] b16b6ae96a81db4a0a400fe1dabe8fdbcdceb92c52efef8828f390502a6ee09f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 561752fcb2d551f8c9f45a0c85a3baaeed3edf44d75846736cc826751cc06203 [INFO] running `Command { std: "docker" "start" "-a" "561752fcb2d551f8c9f45a0c85a3baaeed3edf44d75846736cc826751cc06203", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.106 [INFO] [stderr] Compiling unicode-ident v1.0.24 [INFO] [stderr] Compiling libc v0.2.182 [INFO] [stderr] Compiling pin-project-lite v0.2.17 [INFO] [stderr] Compiling itoa v1.0.17 [INFO] [stderr] Compiling quote v1.0.44 [INFO] [stderr] Compiling futures-sink v0.3.32 [INFO] [stderr] Compiling zerocopy v0.8.40 [INFO] [stderr] Compiling zeroize v1.8.2 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling openssl-sys v0.9.111 [INFO] [stderr] Compiling litemap v0.8.1 [INFO] [stderr] Compiling writeable v0.6.2 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling tracing-core v0.1.36 [INFO] [stderr] Compiling bitflags v2.11.0 [INFO] [stderr] Compiling rustls v0.23.37 [INFO] [stderr] Compiling rustls-pki-types v1.14.0 [INFO] [stderr] Compiling icu_properties_data v2.1.2 [INFO] [stderr] Compiling log v0.4.29 [INFO] [stderr] Compiling icu_normalizer_data v2.1.1 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling http v1.4.0 [INFO] [stderr] Compiling openssl v0.10.75 [INFO] [stderr] Compiling zmij v1.0.21 [INFO] [stderr] Compiling native-tls v0.2.18 [INFO] [stderr] Compiling serde_json v1.0.149 [INFO] [stderr] Compiling webpki-roots v1.0.6 [INFO] [stderr] Compiling ipnet v2.12.0 [INFO] [stderr] Compiling openssl-probe v0.2.1 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling ryu v1.0.23 [INFO] [stderr] Compiling iri-string v0.7.10 [INFO] [stderr] Compiling data-encoding v2.10.0 [INFO] [stderr] Compiling unicode-normalization v0.1.25 [INFO] [stderr] Compiling webpki-roots v0.26.11 [INFO] [stderr] Compiling unicode-properties v0.1.4 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling sha2 v0.10.9 [INFO] [stderr] Compiling md-5 v0.10.6 [INFO] [stderr] Compiling libsqlite3-sys v0.31.0 [INFO] [stderr] Compiling fcommon v3.0.0 (/opt/rustwide/workdir/fcommon) [INFO] [stderr] Compiling fallible-iterator v0.2.0 [INFO] [stderr] Compiling hashbrown v0.15.5 [INFO] [stderr] Compiling phf_shared v0.13.1 [INFO] [stderr] Compiling stringprep v0.1.5 [INFO] [stderr] Compiling syn v2.0.117 [INFO] [stderr] Compiling phf v0.13.1 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling socket2 v0.6.2 [INFO] [stderr] Compiling mio v1.1.1 [INFO] [stderr] Compiling getrandom v0.3.4 [INFO] [stderr] Compiling getrandom v0.2.17 [INFO] [stderr] Compiling parking_lot_core v0.9.12 [INFO] [stderr] Compiling whoami v2.1.1 [INFO] [stderr] Compiling parking_lot v0.12.5 [INFO] [stderr] Compiling rand_core v0.9.5 [INFO] [stderr] Compiling hashlink v0.10.0 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling rustls-webpki v0.103.9 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling ahash v0.8.12 [INFO] [stderr] Compiling metrics v0.24.3 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling postgres-protocol v0.6.10 [INFO] [stderr] Compiling synstructure v0.13.2 [INFO] [stderr] Compiling zerovec-derive v0.11.2 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling futures-macro v0.3.32 [INFO] [stderr] Compiling tracing-attributes v0.1.31 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling thiserror-impl v2.0.18 [INFO] [stderr] Compiling async-stream-impl v0.3.6 [INFO] [stderr] Compiling async-trait v0.1.89 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling yoke-derive v0.8.1 [INFO] [stderr] Compiling postgres-types v0.2.12 [INFO] [stderr] Compiling tokio-util v0.7.18 [INFO] [stderr] Compiling async-stream v0.3.6 [INFO] [stderr] Compiling futures-util v0.3.32 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling tracing v0.1.44 [INFO] [stderr] Compiling yoke v0.8.1 [INFO] [stderr] Compiling h2 v0.4.13 [INFO] [stderr] Compiling zerovec v0.11.5 [INFO] [stderr] Compiling zerotrie v0.2.3 [INFO] [stderr] Compiling thiserror v2.0.18 [INFO] [stderr] Compiling tinystr v0.8.2 [INFO] [stderr] Compiling potential_utf v0.1.4 [INFO] [stderr] Compiling icu_collections v2.1.1 [INFO] [stderr] Compiling icu_locale_core v2.1.1 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling tower v0.5.3 [INFO] [stderr] Compiling tokio-postgres v0.7.16 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling tungstenite v0.28.0 [INFO] [stderr] Compiling icu_provider v2.1.1 [INFO] [stderr] Compiling tower-http v0.6.8 [INFO] [stderr] Compiling icu_properties v2.1.2 [INFO] [stderr] Compiling icu_normalizer v2.1.1 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling tokio-tungstenite v0.28.0 [INFO] [stderr] Compiling hyper v1.8.1 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling idna v1.1.0 [INFO] [stderr] Compiling url v2.5.8 [INFO] [stderr] Compiling hyper-util v0.1.20 [INFO] [stderr] Compiling rusqlite v0.33.0 [INFO] [stderr] Compiling hyper-rustls v0.27.7 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling fprovider v3.0.0 (/opt/rustwide/workdir/fprovider) [INFO] [stderr] Compiling ftooling v3.0.0 (/opt/rustwide/workdir/ftooling) [INFO] [stderr] Compiling fchat v3.0.0 (/opt/rustwide/workdir/fchat) [INFO] [stderr] Compiling fmemory v3.0.0 (/opt/rustwide/workdir/fmemory) [INFO] [stderr] Compiling fharness v3.0.0 (/opt/rustwide/workdir/fharness) [INFO] [stderr] Compiling fiddlesticks v3.0.0 (/opt/rustwide/workdir/fiddlesticks) [INFO] [stderr] Compiling fobserve v3.0.0 (/opt/rustwide/workdir/fobserve) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 02s [INFO] running `Command { std: "docker" "inspect" "561752fcb2d551f8c9f45a0c85a3baaeed3edf44d75846736cc826751cc06203", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "561752fcb2d551f8c9f45a0c85a3baaeed3edf44d75846736cc826751cc06203", kill_on_drop: false }` [INFO] [stdout] 561752fcb2d551f8c9f45a0c85a3baaeed3edf44d75846736cc826751cc06203 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 44b50ef2ce8076ca059d34163e3a5282313754a41ee8df0a825e8202e4452344 [INFO] running `Command { std: "docker" "start" "-a" "44b50ef2ce8076ca059d34163e3a5282313754a41ee8df0a825e8202e4452344", kill_on_drop: false }` [INFO] [stderr] Compiling tokio-macros v2.6.1 [INFO] [stderr] Compiling fcommon v3.0.0 (/opt/rustwide/workdir/fcommon) [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling tokio-util v0.7.18 [INFO] [stderr] Compiling tokio-rustls v0.26.4 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling tower v0.5.3 [INFO] [stderr] Compiling tokio-tungstenite v0.28.0 [INFO] [stderr] Compiling tower-http v0.6.8 [INFO] [stderr] Compiling h2 v0.4.13 [INFO] [stderr] Compiling tokio-postgres v0.7.16 [INFO] [stderr] Compiling hyper v1.8.1 [INFO] [stderr] Compiling hyper-util v0.1.20 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling hyper-rustls v0.27.7 [INFO] [stderr] Compiling reqwest v0.12.28 [INFO] [stderr] Compiling fprovider v3.0.0 (/opt/rustwide/workdir/fprovider) [INFO] [stderr] Compiling ftooling v3.0.0 (/opt/rustwide/workdir/ftooling) [INFO] [stderr] Compiling fchat v3.0.0 (/opt/rustwide/workdir/fchat) [INFO] [stderr] Compiling fmemory v3.0.0 (/opt/rustwide/workdir/fmemory) [INFO] [stderr] Compiling fharness v3.0.0 (/opt/rustwide/workdir/fharness) [INFO] [stderr] Compiling fiddlesticks v3.0.0 (/opt/rustwide/workdir/fiddlesticks) [INFO] [stderr] Compiling fobserve v3.0.0 (/opt/rustwide/workdir/fobserve) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 45.26s [INFO] running `Command { std: "docker" "inspect" "44b50ef2ce8076ca059d34163e3a5282313754a41ee8df0a825e8202e4452344", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "44b50ef2ce8076ca059d34163e3a5282313754a41ee8df0a825e8202e4452344", kill_on_drop: false }` [INFO] [stdout] 44b50ef2ce8076ca059d34163e3a5282313754a41ee8df0a825e8202e4452344 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1695498f370405b04feb1c475e5d80f3278b73c199ccf4b1edc05f356cda1f77 [INFO] running `Command { std: "docker" "start" "-a" "1695498f370405b04feb1c475e5d80f3278b73c199ccf4b1edc05f356cda1f77", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.55s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fchat-537b8d60d7916213) [INFO] [stdout] [INFO] [stdout] running 16 tests [INFO] [stdout] test error::tests::provider_error_conversion_preserves_retryability_and_source ... ok [INFO] [stdout] test error::tests::tooling_invalid_arguments_is_user_error ... ok [INFO] [stdout] test service::tests::builder_configures_tool_runtime_and_round_trip_policy ... ok [INFO] [stdout] test service::tests::builder_applies_default_turn_options_to_requests ... ok [INFO] [stdout] test service::tests::run_turn_includes_history_and_system_prompt_in_provider_request ... ok [INFO] [stdout] test service::tests::run_turn_executes_tools_when_runtime_configured ... ok [INFO] [stdout] test service::tests::run_turn_marks_limit_reached_when_tool_cap_prevents_execution ... ok [INFO] [stdout] test service::tests::provider_hooks_are_called_for_complete_and_stream_operations ... ok [INFO] [stdout] test service::tests::run_turn_rejects_empty_user_input ... ok [INFO] [stdout] test service::tests::stream_turn_executes_tools_and_emits_tool_events ... ok [INFO] [stdout] test service::tests::run_turn_retries_provider_completion_using_policy ... ok [INFO] [stdout] test service::tests::run_turn_returns_assistant_message_and_persists_transcript ... ok [INFO] [stdout] test service::tests::stream_turn_maps_tool_runtime_failures_to_tooling_errors ... ok [INFO] [stdout] test service::tests::stream_turn_reports_streaming_phase_errors ... ok [INFO] [stdout] test service::tests::stream_turn_retries_stream_acquisition_using_policy ... ok [INFO] [stdout] test service::tests::stream_turn_maps_provider_events_and_persists_transcript ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/tool_loop_integration.rs (/opt/rustwide/target/debug/deps/tool_loop_integration-cfa8bfe2cf035bea) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test chat_stream_tool_loop_surfaces_tooling_error_context ... ok [INFO] [stdout] test chat_tool_loop_executes_registered_tool_and_completes_turn ... 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 unittests src/lib.rs (/opt/rustwide/target/debug/deps/fcommon-bb1d4a15d684e3ce) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test tests::generation_options_builder_helpers_set_values ... ok [INFO] [stdout] test tests::generic_registry_basic_lifecycle ... ok [INFO] [stdout] test tests::id_newtypes_round_trip_strings ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fharness-8c17aacf1ff93e95) [INFO] [stdout] [INFO] [stdout] running 25 tests [INFO] [stdout] test harness::tests::all_required_features_passed_requires_non_empty_and_all_true ... ok [INFO] [stdout] test harness::tests::validate_feature_list_rejects_empty_input ... ok [INFO] [stdout] test tests::builder_requires_provider_to_build_runtime ... ok [INFO] [stdout] test tests::coding_iteration_does_not_mark_feature_when_not_validated ... ok [INFO] [stdout] test tests::builder_wires_provider_tooling_memory_and_chat ... ok [INFO] [stdout] test tests::coding_iteration_gets_bearings_executes_and_marks_feature_passed ... ok [INFO] [stdout] test tests::coding_iteration_retries_validation_when_policy_allows ... ok [INFO] [stdout] test tests::coding_iteration_stops_when_turn_budget_is_exhausted ... ok [INFO] [stdout] test tests::coding_iteration_stream_path_works_and_records_handoff ... ok [INFO] [stdout] test tests::harness_does_not_declare_done_when_selector_returns_none_early ... ok [INFO] [stdout] test tests::completion_gate_requires_all_features_to_pass_true ... ok [INFO] [stdout] test tests::coding_iteration_uses_feature_selection_strategy ... ok [INFO] [stdout] test tests::harness_hooks_report_phase_failure ... ok [INFO] [stdout] test tests::harness_hooks_report_phase_start_and_success ... ok [INFO] [stdout] test tests::initializer_is_idempotent_when_session_already_initialized ... ok [INFO] [stdout] test tests::initializer_creates_bootstrap_state_on_first_run ... ok [INFO] [stdout] test tests::initializer_generates_starter_feature_list_when_missing ... ok [INFO] [stdout] test tests::initializer_rejects_empty_objective ... ok [INFO] [stdout] test tests::runtime_run_initializer_applies_initializer_fields ... ok [INFO] [stdout] test tests::runtime_run_forwards_prompt_override_and_streaming ... ok [INFO] [stdout] test tests::select_phase_tracks_session_initialization_state ... ok [INFO] [stdout] test tests::run_policy_modes_enforce_feature_limits ... ok [INFO] [stdout] test tests::initializer_rejects_duplicate_or_passing_features ... ok [INFO] [stdout] test tests::runtime_run_selects_initializer_then_task_iteration_phase ... ok [INFO] [stdout] test tests::coding_iteration_retries_chat_errors_within_retry_budget ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s [INFO] [stdout] [INFO] [stderr] Running tests/harness_integration.rs (/opt/rustwide/target/debug/deps/harness_integration-98e2c0fdc361739f) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test initializer_creates_required_artifacts ... ok [INFO] [stdout] test bounded_batch_mode_processes_up_to_feature_limit ... ok [INFO] [stdout] test multi_run_completion_requires_all_features_passed ... ok [INFO] [stdout] test task_iteration_picks_one_failing_feature_and_updates_progress ... ok [INFO] [stdout] test unlimited_batch_mode_processes_all_pending_features ... ok [INFO] [stdout] test task_iteration_does_not_mark_feature_pass_without_validation ... ok [INFO] [stdout] test fresh_context_window_recovers_state_from_fmemory_and_continues ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fiddlesticks-23f819fbce378529) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test tests::fs_messages_macro_builds_message_vector ... ok [INFO] [stdout] test tests::fs_msg_macro_creates_expected_message ... ok [INFO] [stdout] test tests::fs_session_macro_supports_provider_shorthand_and_prompt ... ok [INFO] [stdout] test util::tests::message_and_turn_helpers_apply_expected_defaults ... ok [INFO] [stdout] test util::tests::parse_provider_id_supports_aliases ... ok [INFO] [stdout] test runtime::tests::build_runtime_with_tooling_builds_successfully ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fmemory-a70c69d07ccb8291) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test tests::backend_stores_bootstrap_state_and_transcript ... ok [INFO] [stdout] test tests::conversation_store_adapter_reads_and_writes_transcript ... ok [INFO] [stdout] test tests::initialize_session_if_missing_is_idempotent ... ok [INFO] [stdout] test tests::postgres_backend_stores_bootstrap_state_and_transcript_when_configured ... ok [INFO] [stdout] test tests::update_feature_pass_fails_for_unknown_feature ... ok [INFO] [stdout] test tests::is_initialized_tracks_manifest_presence ... ok [INFO] [stdout] test tests::sqlite_backend_stores_bootstrap_state_and_transcript ... ok [INFO] [stdout] test tests::filesystem_backend_stores_bootstrap_state_and_transcript ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fobserve-81eea53eef25975d) [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test tests::safe_harness_hooks_delegate_when_inner_succeeds ... ok [INFO] [stdout] test tests::metrics_hooks_smoke_test_all_callbacks ... ok [INFO] [stdout] test tests::safe_provider_hooks_delegate_when_inner_succeeds ... ok [INFO] [stdout] test tests::safe_tool_hooks_delegate_when_inner_succeeds ... ok [INFO] [stdout] test tests::tracing_hooks_smoke_test_all_callbacks ... ok [INFO] [stdout] test tests::safe_provider_hooks_swallow_panics ... ok [INFO] [stdout] test tests::safe_tool_hooks_swallow_panics ... ok [INFO] [stdout] test tests::safe_harness_hooks_swallow_panics ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fprovider-d27a3eadf9715e04) [INFO] [stdout] [INFO] [stdout] running 20 tests [INFO] [stdout] test adapters::openai::tests::build_api_request_switches_token_parameter_name ... ok [INFO] [stdout] test adapters::openai::tests::build_openai_request_appends_tool_results_as_tool_messages ... ok [INFO] [stdout] test resilience::tests::retry_policy_backoff_grows_and_caps ... ok [INFO] [stdout] test resilience::tests::retry_policy_uses_retryable_flag_and_attempt_limit ... ok [INFO] [stdout] test tests::credential_manager_observer_receives_sanitized_events ... ok [INFO] [stdout] test adapters::openai::tests::parse_finish_reason_maps_expected_values ... ok [INFO] [stdout] test resilience::tests::execute_with_retry_retries_and_reports_hooks ... ok [INFO] [stdout] test resilience::tests::execute_with_retry_stops_on_non_retryable_error ... ok [INFO] [stdout] test tests::model_provider_stream_returns_expected_events ... ok [INFO] [stdout] test tests::model_request_builder_validates_before_building ... ok [INFO] [stdout] test tests::provider_registry_registers_and_returns_providers ... ok [INFO] [stdout] test tests::openai_helpers_validate_and_store_api_key ... ok [INFO] [stdout] test tests::model_request_validate_enforces_contract ... ok [INFO] [stdout] test tests::provider_id_display_is_stable ... ok [INFO] [stdout] test tests::provider_error_helper_builders_assign_expected_retryability ... ok [INFO] [stdout] test tests::secret_string_debug_is_redacted ... ok [INFO] [stdout] test tests::secure_credential_manager_handles_browser_sessions ... ok [INFO] [stdout] test tests::secure_credential_manager_handles_provider_agnostic_credentials ... ok [INFO] [stdout] test tests::vec_event_stream_yields_events_in_order ... ok [INFO] [stdout] test tests::credential_manager_tracks_metadata_and_ttl ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s [INFO] [stdout] [INFO] [stderr] Running tests/anthropic_provider_behavior.rs (/opt/rustwide/target/debug/deps/anthropic_provider_behavior-bae5fe179760a50a) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test missing_anthropic_credentials_returns_authentication_error ... ok [INFO] [stdout] test complete_uses_anthropic_credentials_and_maps_provider_id ... ok [INFO] [stdout] test stream_maps_response_complete_to_anthropic_provider_id ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/ollama_provider_behavior.rs (/opt/rustwide/target/debug/deps/ollama_provider_behavior-fd8cc25e3e4a5d1c) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test stream_maps_response_complete_to_ollama_provider_id ... ok [INFO] [stdout] test complete_maps_to_ollama_provider_id_and_uses_placeholder_auth ... 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/openai_provider_behavior.rs (/opt/rustwide/target/debug/deps/openai_provider_behavior-046f8985677dbb87) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test stream_rejects_browser_session_without_api_key ... ok [INFO] [stdout] test missing_openai_credentials_returns_auth_error ... ok [INFO] [stdout] test complete_maps_openai_response_to_provider_response ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/openai_provider_integration.rs (/opt/rustwide/target/debug/deps/openai_provider_integration-b2fe2f60330d97c1) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test openai_provider_uses_openai_credentials_and_maps_completion ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running tests/opencode_zen_provider_behavior.rs (/opt/rustwide/target/debug/deps/opencode_zen_provider_behavior-9e93e5e03b02ad47) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test missing_zen_credentials_returns_authentication_error ... ok [INFO] [stdout] test complete_uses_zen_credentials_and_maps_provider_id ... ok [INFO] [stdout] test stream_maps_response_complete_to_zen_provider_id ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ftooling-bdbab4716c9260d9) [INFO] [stdout] [INFO] [stdout] running 11 tests [INFO] [stdout] test args::tests::parse_object_and_extract_required_string ... ok [INFO] [stdout] test error::tests::helper_methods_report_retryable_and_user_error ... ok [INFO] [stdout] test args::tests::parse_invalid_json_returns_invalid_arguments ... ok [INFO] [stdout] test error::tests::context_fields_are_included_in_display ... ok [INFO] [stdout] test runtime::tests::registry_tracks_registered_tools ... ok [INFO] [stdout] test runtime::tests::runtime_executes_registered_tool ... ok [INFO] [stdout] test runtime::tests::runtime_hooks_receive_success_and_failure_events ... ok [INFO] [stdout] test runtime::tests::registry_register_fn_and_register_sync_fn_are_easy_to_use ... ok [INFO] [stdout] test runtime::tests::runtime_returns_not_found_for_unknown_tool ... ok [INFO] [stdout] test runtime::tests::runtime_propagates_tool_execution_error ... ok [INFO] [stdout] test runtime::tests::runtime_timeout_returns_timeout_error ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- runtime::tests::runtime_timeout_returns_timeout_error stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'runtime::tests::runtime_timeout_returns_timeout_error' (150) panicked at ftooling/src/runtime.rs:352:14: [INFO] [stdout] execution should time out: ToolExecutionResult { tool_call_id: "call_4", output: "done" } [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x567d8a62350a - std[e72de78501789eb0]::backtrace_rs::backtrace::libunwind::trace [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x567d8a62350a - std[e72de78501789eb0]::backtrace_rs::backtrace::trace_unsynchronized:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x567d8a62350a - std[e72de78501789eb0]::sys::backtrace::_print_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:74:9 [INFO] [stdout] 3: 0x567d8a62350a - <::print::DisplayBacktrace as core[6aaeda2c7ad07a48]::fmt::Display>::fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:44:26 [INFO] [stdout] 4: 0x567d8a63862a - ::fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/fmt/rt.rs:152:76 [INFO] [stdout] 5: 0x567d8a63862a - core[6aaeda2c7ad07a48]::fmt::write [INFO] [stdout] 6: 0x567d8a627e82 - std[e72de78501789eb0]::io::default_write_fmt::> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x567d8a627e82 - as std[e72de78501789eb0]::io::Write>::write_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:1994:13 [INFO] [stdout] 8: 0x567d8a6020ef - ::print [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:47:9 [INFO] [stdout] 9: 0x567d8a6020ef - std[e72de78501789eb0]::panicking::default_hook::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:292:27 [INFO] [stdout] 10: 0x567d8a61b7d9 - std[e72de78501789eb0]::panicking::default_hook [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:316:9 [INFO] [stdout] 11: 0x567d8a51678c - core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 12: 0x567d8a51678c - test[4f12b380f1fb68c5]::test_main_with_exit_callback::::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x567d8a61b992 - core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9 [INFO] [stdout] 14: 0x567d8a61b992 - std[e72de78501789eb0]::panicking::panic_with_hook [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:833:13 [INFO] [stdout] 15: 0x567d8a6021a8 - std[e72de78501789eb0]::panicking::panic_handler::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:698:13 [INFO] [stdout] 16: 0x567d8a5f9b19 - std[e72de78501789eb0]::sys::backtrace::__rust_end_short_backtrace:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:182:18 [INFO] [stdout] 17: 0x567d8a602ebd - __rustc[78a4cf188af856fc]::rust_begin_unwind [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:689:5 [INFO] [stdout] 18: 0x567d8a638dec - core[6aaeda2c7ad07a48]::panicking::panic_fmt [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panicking.rs:80:14 [INFO] [stdout] 19: 0x567d8a638b62 - core[6aaeda2c7ad07a48]::result::unwrap_failed [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1867:5 [INFO] [stdout] 20: 0x567d8a509a62 - >::expect_err [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1298:22 [INFO] [stdout] 21: 0x567d8a4edab4 - ftooling[7c182f430cdac8bb]::runtime::tests::runtime_timeout_returns_timeout_error::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/ftooling/src/runtime.rs:352:14 [INFO] [stdout] 22: 0x567d8a5037a2 - > as core[6aaeda2c7ad07a48]::future::future::Future>::poll [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/future/future.rs:133:9 [INFO] [stdout] 23: 0x567d8a50381d - >> as core[6aaeda2c7ad07a48]::future::future::Future>::poll [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/future/future.rs:133:9 [INFO] [stdout] 24: 0x567d8a50943d - ::block_on::>>>::{closure#0}::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:70 [INFO] [stdout] 25: 0x567d8a50933b - tokio[56a833bba2afe7d8]::task::coop::with_budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:167:5 [INFO] [stdout] 26: 0x567d8a50933b - tokio[56a833bba2afe7d8]::task::coop::budget::, ::block_on>>>::{closure#0}::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/task/coop/mod.rs:133:5 [INFO] [stdout] 27: 0x567d8a50933b - ::block_on::>>>::{closure#0}::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:742:25 [INFO] [stdout] 28: 0x567d8a5045f0 - ::enter::, ::block_on>>>::{closure#0}::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:432:19 [INFO] [stdout] 29: 0x567d8a50838d - ::block_on::>>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:741:44 [INFO] [stdout] 30: 0x567d8a507684 - ::enter::<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:68 [INFO] [stdout] 31: 0x567d8a4f95db - >::set::<::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/scoped.rs:40:9 [INFO] [stdout] 32: 0x567d8a4ff869 - tokio[56a833bba2afe7d8]::runtime::context::set_scheduler::<(alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:38 [INFO] [stdout] 33: 0x567d8a4eab72 - >::try_with::, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/local.rs:462:12 [INFO] [stdout] 34: 0x567d8a4ea6fe - >::with::, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>)> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/local.rs:426:20 [INFO] [stdout] 35: 0x567d8a4ff23d - tokio[56a833bba2afe7d8]::runtime::context::set_scheduler::<(alloc[28d2dab30c1dc666]::boxed::Box, core[6aaeda2c7ad07a48]::option::Option<()>), ::enter<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>>::{closure#0}> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context.rs:176:17 [INFO] [stdout] 36: 0x567d8a505690 - ::enter::<::block_on>>>::{closure#0}, core[6aaeda2c7ad07a48]::option::Option<()>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:829:27 [INFO] [stdout] 37: 0x567d8a505986 - ::block_on::>>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:729:24 [INFO] [stdout] 38: 0x567d8a507290 - ::block_on::>>::{closure#0} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:200:33 [INFO] [stdout] 39: 0x567d8a4fa203 - tokio[56a833bba2afe7d8]::runtime::context::runtime::enter_runtime::<::block_on>>::{closure#0}, ()> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 40: 0x567d8a504121 - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/scheduler/current_thread/mod.rs:188:9 [INFO] [stdout] 41: 0x567d8a4f9054 - ::block_on_inner::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:368:52 [INFO] [stdout] 42: 0x567d8a4f921f - ::block_on::>> [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.48.0/src/runtime/runtime.rs:342:18 [INFO] [stdout] 43: 0x567d8a4f1a00 - ftooling[7c182f430cdac8bb]::runtime::tests::runtime_timeout_returns_timeout_error [INFO] [stdout] at /opt/rustwide/workdir/ftooling/src/runtime.rs:354:55 [INFO] [stdout] 44: 0x567d8a4ecda7 - ftooling[7c182f430cdac8bb]::runtime::tests::runtime_timeout_returns_timeout_error::{closure#0} [INFO] [stdout] at /opt/rustwide/workdir/ftooling/src/runtime.rs:336:53 [INFO] [stdout] 45: 0x567d8a4f3db6 - >::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 46: 0x567d8a50a86b - core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 47: 0x567d8a50a86b - test[4f12b380f1fb68c5]::__rust_begin_short_backtrace::, fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18 [INFO] [stdout] 48: 0x567d8a51725b - test[4f12b380f1fb68c5]::run_test_in_process::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74 [INFO] [stdout] 49: 0x567d8a51725b - as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 50: 0x567d8a51725b - std[e72de78501789eb0]::panicking::catch_unwind::do_call::, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40 [INFO] [stdout] 51: 0x567d8a51725b - std[e72de78501789eb0]::panicking::catch_unwind::, core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19 [INFO] [stdout] 52: 0x567d8a51725b - std[e72de78501789eb0]::panic::catch_unwind::, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14 [INFO] [stdout] 53: 0x567d8a51725b - test[4f12b380f1fb68c5]::run_test_in_process [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27 [INFO] [stdout] 54: 0x567d8a51725b - test[4f12b380f1fb68c5]::run_test::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43 [INFO] [stdout] 55: 0x567d8a512974 - test[4f12b380f1fb68c5]::run_test::{closure#1} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41 [INFO] [stdout] 56: 0x567d8a512974 - std[e72de78501789eb0]::sys::backtrace::__rust_begin_short_backtrace:: [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 57: 0x567d8a519e62 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13 [INFO] [stdout] 58: 0x567d8a519e62 - ::{closure#1}::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9 [INFO] [stdout] 59: 0x567d8a519e62 - std[e72de78501789eb0]::panicking::catch_unwind::do_call::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40 [INFO] [stdout] 60: 0x567d8a519e62 - std[e72de78501789eb0]::panicking::catch_unwind::<(), core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe::{closure#1}::{closure#0}>> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19 [INFO] [stdout] 61: 0x567d8a519e62 - std[e72de78501789eb0]::panic::catch_unwind::::{closure#1}::{closure#0}>, ()> [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14 [INFO] [stdout] 62: 0x567d8a519e62 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::::{closure#1} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26 [INFO] [stdout] 63: 0x567d8a519e62 - ::{closure#1} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0} [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 64: 0x567d8a622def - + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9 [INFO] [stdout] 65: 0x567d8a622def - ::new::thread_start [INFO] [stdout] at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17 [INFO] [stdout] 66: 0x7c2148aa0aa4 - [INFO] [stdout] 67: 0x7c2148b2da64 - clone [INFO] [stdout] 68: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] runtime::tests::runtime_timeout_returns_timeout_error [INFO] [stdout] [INFO] [stdout] test result: FAILED. 10 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `-p ftooling --lib` [INFO] running `Command { std: "docker" "inspect" "1695498f370405b04feb1c475e5d80f3278b73c199ccf4b1edc05f356cda1f77", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1695498f370405b04feb1c475e5d80f3278b73c199ccf4b1edc05f356cda1f77", kill_on_drop: false }` [INFO] [stdout] 1695498f370405b04feb1c475e5d80f3278b73c199ccf4b1edc05f356cda1f77