[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 beta-2026-03-05 for beta-1.95-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilo-groves%2Ffiddlesticks" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-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-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/philo-groves/fiddlesticks on toolchain beta-2026-03-05
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-03-05" "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" "+beta-2026-03-05" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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" "+beta-2026-03-05" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2b7a695f4f3d49c3d9e6fc0a14b3b53ee4057a871be18d5921397980f74aabf0
[INFO] running `Command { std: "docker" "start" "-a" "2b7a695f4f3d49c3d9e6fc0a14b3b53ee4057a871be18d5921397980f74aabf0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2b7a695f4f3d49c3d9e6fc0a14b3b53ee4057a871be18d5921397980f74aabf0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2b7a695f4f3d49c3d9e6fc0a14b3b53ee4057a871be18d5921397980f74aabf0", kill_on_drop: false }`
[INFO] [stdout] 2b7a695f4f3d49c3d9e6fc0a14b3b53ee4057a871be18d5921397980f74aabf0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 06059b7eedd99ada07c18d79b746a5cea5654440d6a686741b4cbab5ed379d5e
[INFO] running `Command { std: "docker" "start" "-a" "06059b7eedd99ada07c18d79b746a5cea5654440d6a686741b4cbab5ed379d5e", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling typenum v1.19.0
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling vcpkg v0.2.15
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling writeable v0.6.2
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling litemap v0.8.1
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling rustls-pki-types v1.14.0
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling icu_normalizer_data v2.1.1
[INFO] [stderr]    Compiling icu_properties_data v2.1.2
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling openssl v0.10.75
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling webpki-roots v1.0.6
[INFO] [stderr]    Compiling openssl-probe v0.2.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling ipnet v2.12.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling openssl-sys v0.9.111
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling tinyvec_macros v0.1.1
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling tinyvec v1.10.0
[INFO] [stderr]    Compiling iri-string v0.7.10
[INFO] [stderr]    Compiling utf-8 v0.7.6
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling data-encoding v2.10.0
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling unicode-normalization v0.1.25
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling unicode-properties v0.1.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling unicode-bidi v0.3.18
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling libsqlite3-sys v0.31.0
[INFO] [stderr]    Compiling fcommon v3.0.0 (/opt/rustwide/workdir/fcommon)
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling fallible-iterator v0.2.0
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling stringprep v0.1.5
[INFO] [stderr]    Compiling siphasher v1.0.2
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling phf_shared v0.13.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling phf v0.13.1
[INFO] [stderr]    Compiling whoami v2.1.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[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 rustls-webpki v0.103.9
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling postgres-protocol v0.6.10
[INFO] [stderr]    Compiling zerofrom-derive v0.1.6
[INFO] [stderr]    Compiling yoke-derive v0.8.1
[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 async-stream v0.3.6
[INFO] [stderr]    Compiling postgres-types v0.2.12
[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 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 icu_provider v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tungstenite v0.28.0
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-postgres v0.7.16
[INFO] [stderr]    Compiling tokio-tungstenite v0.28.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling rusqlite v0.33.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[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 fobserve v3.0.0 (/opt/rustwide/workdir/fobserve)
[INFO] [stderr]    Compiling fiddlesticks v3.0.0 (/opt/rustwide/workdir/fiddlesticks)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 15s
[INFO] running `Command { std: "docker" "inspect" "06059b7eedd99ada07c18d79b746a5cea5654440d6a686741b4cbab5ed379d5e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "06059b7eedd99ada07c18d79b746a5cea5654440d6a686741b4cbab5ed379d5e", kill_on_drop: false }`
[INFO] [stdout] 06059b7eedd99ada07c18d79b746a5cea5654440d6a686741b4cbab5ed379d5e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d3205e19ea4cf8fa8744722f9c3b6844d026137ad108f6858491d49fe1093577
[INFO] running `Command { std: "docker" "start" "-a" "d3205e19ea4cf8fa8744722f9c3b6844d026137ad108f6858491d49fe1093577", 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 tower v0.5.3
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[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-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 fobserve v3.0.0 (/opt/rustwide/workdir/fobserve)
[INFO] [stderr]    Compiling fiddlesticks v3.0.0 (/opt/rustwide/workdir/fiddlesticks)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 42.17s
[INFO] running `Command { std: "docker" "inspect" "d3205e19ea4cf8fa8744722f9c3b6844d026137ad108f6858491d49fe1093577", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d3205e19ea4cf8fa8744722f9c3b6844d026137ad108f6858491d49fe1093577", kill_on_drop: false }`
[INFO] [stdout] d3205e19ea4cf8fa8744722f9c3b6844d026137ad108f6858491d49fe1093577
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7a91ce76acfcfa800ee7731c1a26a23a703d29d7c5793f3b4aff89195a646a76
[INFO] running `Command { std: "docker" "start" "-a" "7a91ce76acfcfa800ee7731c1a26a23a703d29d7c5793f3b4aff89195a646a76", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.38s
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test error::tests::tooling_invalid_arguments_is_user_error ... ok
[INFO] [stdout] test error::tests::provider_error_conversion_preserves_retryability_and_source ... 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::stream_turn_reports_streaming_phase_errors ... ok
[INFO] [stdout] test service::tests::stream_turn_maps_tool_runtime_failures_to_tooling_errors ... ok
[INFO] [stdout] test service::tests::run_turn_retries_provider_completion_using_policy ... ok
[INFO] [stdout] test service::tests::builder_applies_default_turn_options_to_requests ... ok
[INFO] [stdout] test service::tests::builder_configures_tool_runtime_and_round_trip_policy ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fchat-d069499e97969b41)
[INFO] [stdout] test service::tests::run_turn_executes_tools_when_runtime_configured ... ok
[INFO] [stdout] test service::tests::stream_turn_maps_provider_events_and_persists_transcript ... ok
[INFO] [stdout] test service::tests::run_turn_includes_history_and_system_prompt_in_provider_request ... ok
[INFO] [stdout] test service::tests::run_turn_marks_limit_reached_when_tool_cap_prevents_execution ... ok
[INFO] [stdout] test service::tests::stream_turn_retries_stream_acquisition_using_policy ... ok
[INFO] [stdout] test service::tests::run_turn_returns_assistant_message_and_persists_transcript ... ok
[INFO] [stdout] test service::tests::provider_hooks_are_called_for_complete_and_stream_operations ... ok
[INFO] [stderr]      Running tests/tool_loop_integration.rs (/opt/rustwide/target/debug/deps/tool_loop_integration-dd14e538fa474bc7)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fcommon-8ea86743e008cb9a)
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test chat_tool_loop_executes_registered_tool_and_completes_turn ... ok
[INFO] [stdout] test chat_stream_tool_loop_surfaces_tooling_error_context ... 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] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::generic_registry_basic_lifecycle ... ok
[INFO] [stdout] test tests::id_newtypes_round_trip_strings ... ok
[INFO] [stdout] test tests::generation_options_builder_helpers_set_values ... 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/fharness-3f0a5ffad13e1895)
[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::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_does_not_mark_feature_when_not_validated ... ok
[INFO] [stdout] test tests::coding_iteration_stops_when_turn_budget_is_exhausted ... ok
[INFO] [stdout] test tests::coding_iteration_uses_feature_selection_strategy ... ok
[INFO] [stdout] test tests::harness_does_not_declare_done_when_selector_returns_none_early ... ok
[INFO] [stdout] test tests::harness_hooks_report_phase_failure ... ok
[INFO] [stdout] test tests::initializer_creates_bootstrap_state_on_first_run ... ok
[INFO] [stdout] test tests::coding_iteration_stream_path_works_and_records_handoff ... ok
[INFO] [stdout] test tests::completion_gate_requires_all_features_to_pass_true ... ok
[INFO] [stdout] test tests::coding_iteration_retries_validation_when_policy_allows ... ok
[INFO] [stdout] test tests::harness_hooks_report_phase_start_and_success ... ok
[INFO] [stdout] test tests::initializer_rejects_empty_objective ... ok
[INFO] [stdout] test tests::run_policy_modes_enforce_feature_limits ... ok
[INFO] [stdout] test tests::runtime_run_forwards_prompt_override_and_streaming ... ok
[INFO] [stdout] test tests::runtime_run_initializer_applies_initializer_fields ... ok
[INFO] [stdout] test tests::runtime_run_selects_initializer_then_task_iteration_phase ... ok
[INFO] [stdout] test tests::initializer_generates_starter_feature_list_when_missing ... ok
[INFO] [stdout] test tests::initializer_is_idempotent_when_session_already_initialized ... ok
[INFO] [stdout] test tests::initializer_rejects_duplicate_or_passing_features ... ok
[INFO] [stdout] test tests::select_phase_tracks_session_initialization_state ... 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.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/harness_integration.rs (/opt/rustwide/target/debug/deps/harness_integration-b6eaf8fbabcd589f)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fiddlesticks-ea54a3360bec93f5)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[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] test task_iteration_picks_one_failing_feature_and_updates_progress ... ok
[INFO] [stdout] test initializer_creates_required_artifacts ... ok
[INFO] [stdout] test bounded_batch_mode_processes_up_to_feature_limit ... ok
[INFO] [stdout] test unlimited_batch_mode_processes_all_pending_features ... ok
[INFO] [stdout] test multi_run_completion_requires_all_features_passed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test tests::fs_messages_macro_builds_message_vector ... 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 tests::fs_msg_macro_creates_expected_message ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fmemory-31c7474b9d15bcc1)
[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.03s
[INFO] [stdout] 
[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::is_initialized_tracks_manifest_presence ... ok
[INFO] [stdout] test tests::update_feature_pass_fails_for_unknown_feature ... ok
[INFO] [stdout] test tests::postgres_backend_stores_bootstrap_state_and_transcript_when_configured ... 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] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fobserve-27d9975dd824911d)
[INFO] [stdout] test tests::initialize_session_if_missing_is_idempotent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[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_tool_hooks_delegate_when_inner_succeeds ... ok
[INFO] [stdout] test tests::safe_provider_hooks_delegate_when_inner_succeeds ... ok
[INFO] [stdout] test tests::tracing_hooks_smoke_test_all_callbacks ... ok
[INFO] [stdout] test tests::safe_harness_hooks_swallow_panics ... ok
[INFO] [stdout] test tests::safe_tool_hooks_swallow_panics ... ok
[INFO] [stdout] test tests::safe_provider_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.16s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fprovider-abfada15ed822dca)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test adapters::openai::tests::parse_finish_reason_maps_expected_values ... ok
[INFO] [stdout] test adapters::openai::tests::build_openai_request_appends_tool_results_as_tool_messages ... ok
[INFO] [stdout] test adapters::openai::tests::build_api_request_switches_token_parameter_name ... ok
[INFO] [stdout] test resilience::tests::retry_policy_backoff_grows_and_caps ... 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_request_builder_validates_before_building ... ok
[INFO] [stdout] test tests::openai_helpers_validate_and_store_api_key ... 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 tests::provider_error_helper_builders_assign_expected_retryability ... ok
[INFO] [stdout] test tests::model_provider_stream_returns_expected_events ... ok
[INFO] [stdout] test tests::provider_registry_registers_and_returns_providers ... ok
[INFO] [stdout] test tests::provider_id_display_is_stable ... 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::model_request_validate_enforces_contract ... 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.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/anthropic_provider_behavior.rs (/opt/rustwide/target/debug/deps/anthropic_provider_behavior-f16da29d40064820)
[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-2cc04fcc239680b2)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test complete_maps_to_ollama_provider_id_and_uses_placeholder_auth ... ok
[INFO] [stdout] test stream_maps_response_complete_to_ollama_provider_id ... 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-02280deb149538ce)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test missing_openai_credentials_returns_auth_error ... ok
[INFO] [stdout] test stream_rejects_browser_session_without_api_key ... 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.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/openai_provider_integration.rs (/opt/rustwide/target/debug/deps/openai_provider_integration-85d7a1d59cc2362b)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/opencode_zen_provider_behavior.rs (/opt/rustwide/target/debug/deps/opencode_zen_provider_behavior-97d9d78b5ed303bd)
[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.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test stream_maps_response_complete_to_zen_provider_id ... ok
[INFO] [stdout] test missing_zen_credentials_returns_authentication_error ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ftooling-06aaa651c57be093)
[INFO] [stdout] test complete_uses_zen_credentials_and_maps_provider_id ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test args::tests::parse_object_and_extract_required_string ... ok
[INFO] [stdout] test error::tests::context_fields_are_included_in_display ... ok
[INFO] [stdout] test args::tests::parse_invalid_json_returns_invalid_arguments ... ok
[INFO] [stdout] test runtime::tests::registry_register_fn_and_register_sync_fn_are_easy_to_use ... 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::runtime_propagates_tool_execution_error ... ok
[INFO] [stdout] test error::tests::helper_methods_report_retryable_and_user_error ... ok
[INFO] [stdout] test runtime::tests::runtime_returns_not_found_for_unknown_tool ... ok
[INFO] [stdout] test runtime::tests::registry_tracks_registered_tools ... ok
[INFO] [stdout] test runtime::tests::runtime_timeout_returns_timeout_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests fchat
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test fchat/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test fchat/src/types.rs - types (line 3) ... ok
[INFO] [stdout] test fchat/src/error.rs - error (line 3) ... ok
[INFO] [stdout] test fchat/src/store.rs - store (line 3) ... ok
[INFO] [stdout] test fchat/src/service.rs - service (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.39s; merged doctests compilation took 1.33s
[INFO] [stderr]    Doc-tests fcommon
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test fcommon/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test fcommon/src/lib.rs - model (line 122) ... ok
[INFO] [stdout] test fcommon/src/lib.rs - registry (line 167) ... ok
[INFO] [stdout] test fcommon/src/lib.rs - future (line 20) ... ok
[INFO] [stdout] test fcommon/src/lib.rs - context (line 39) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.66s; merged doctests compilation took 0.65s
[INFO] [stderr]    Doc-tests fharness
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test fharness/src/harness.rs - harness::HarnessBuilder (line 25) ... ok
[INFO] [stdout] test fharness/src/types.rs - types (line 3) ... ok
[INFO] [stdout] test fharness/src/hooks.rs - hooks (line 3) ... ok
[INFO] [stdout] test fharness/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test fharness/src/error.rs - error (line 3) ... ok
[INFO] [stdout] test fharness/src/traits.rs - traits (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.61s; merged doctests compilation took 1.43s
[INFO] [stderr]    Doc-tests fiddlesticks
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test fiddlesticks/src/runtime.rs - runtime (line 3) ... ok
[INFO] [stdout] test fiddlesticks/src/providers.rs - providers (line 3) ... ok
[INFO] [stdout] test fiddlesticks/src/macros.rs - macros::fs_session (line 55) ... ok
[INFO] [stdout] test fiddlesticks/src/lib.rs - (line 7) ... ok
[INFO] [stdout] test fiddlesticks/src/macros.rs - macros::fs_messages (line 31) ... ok
[INFO] [stdout] test fiddlesticks/src/providers.rs - providers::build_provider_with_config (line 56) ... ok
[INFO] [stdout] test fiddlesticks/src/macros.rs - macros::fs_msg (line 3) ... ok
[INFO] [stdout] test fiddlesticks/src/util.rs - util (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 2.47s; merged doctests compilation took 2.03s
[INFO] [stderr]    Doc-tests fmemory
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test fmemory/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test fmemory/src/error.rs - error (line 3) ... ok
[INFO] [stdout] test fmemory/src/adapter.rs - adapter (line 3) ... ok
[INFO] [stdout] test fmemory/src/types.rs - types (line 3) ... ok
[INFO] [stdout] test fmemory/src/backend.rs - backend (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.67s; merged doctests compilation took 1.62s
[INFO] [stderr]    Doc-tests fobserve
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test fobserve/src/safe_hooks.rs - safe_hooks (line 3) ... ok
[INFO] [stdout] test fobserve/src/metrics_hooks.rs - metrics_hooks (line 3) ... ok
[INFO] [stdout] test fobserve/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test fobserve/src/tracing_hooks.rs - tracing_hooks (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.65s; merged doctests compilation took 1.56s
[INFO] [stderr]    Doc-tests fprovider
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test fprovider/src/error.rs - error (line 3) ... ok
[INFO] [stdout] test fprovider/src/credentials.rs - credentials (line 3) ... ok
[INFO] [stdout] test fprovider/src/registry.rs - registry (line 3) ... ok
[INFO] [stdout] test fprovider/src/model.rs - model (line 3) ... ok
[INFO] [stdout] test fprovider/src/stream.rs - stream (line 3) ... ok
[INFO] [stdout] test fprovider/src/resilience.rs - resilience (line 3) ... ok
[INFO] [stdout] test fprovider/src/lib.rs - (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ftooling
[INFO] [stdout] all doctests ran in 1.87s; merged doctests compilation took 1.81s
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test ftooling/src/types.rs - types (line 3) ... ok
[INFO] [stdout] test ftooling/src/error.rs - error (line 3) ... ok
[INFO] [stdout] test ftooling/src/registry.rs - registry (line 3) ... ok
[INFO] [stdout] test ftooling/src/runtime.rs - runtime (line 3) ... ok
[INFO] [stdout] test ftooling/src/tool.rs - tool (line 3) ... ok
[INFO] [stdout] test ftooling/src/hooks.rs - hooks (line 3) ... ok
[INFO] [stdout] test ftooling/src/lib.rs - (line 3) ... ok
[INFO] [stdout] test ftooling/src/args.rs - args (line 3) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.67s; merged doctests compilation took 1.51s
[INFO] running `Command { std: "docker" "inspect" "7a91ce76acfcfa800ee7731c1a26a23a703d29d7c5793f3b4aff89195a646a76", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7a91ce76acfcfa800ee7731c1a26a23a703d29d7c5793f3b4aff89195a646a76", kill_on_drop: false }`
[INFO] [stdout] 7a91ce76acfcfa800ee7731c1a26a23a703d29d7c5793f3b4aff89195a646a76
