[INFO] fetching crate smysl-provider 1.8.0...
[INFO] testing smysl-provider-1.8.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate smysl-provider 1.8.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate smysl-provider 1.8.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate smysl-provider 1.8.0
[INFO] tweaked toml for crates.io crate smysl-provider 1.8.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate smysl-provider 1.8.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate smysl-provider 1.8.0 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.100.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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b
[INFO] running `Command { std: "docker" "start" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", 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" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", 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" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling tinyvec_macros v0.1.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling cc v1.4.0
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling tinyvec v1.12.0
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling arrayvec v0.7.8
[INFO] [stderr]    Compiling arrayref v0.3.9
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling unicode-normalization v0.1.25
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling blake3 v1.8.5
[INFO] [stderr]    Compiling smysl-core v1.8.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling smysl-provider v1.8.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `serde`
[INFO] [stderr]  --> Cargo.toml:1:15
[INFO] [stderr]   |
[INFO] [stderr] 1 | [dependencies.serde]
[INFO] [stderr]   |               ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `serde`
[INFO] [stderr] warning: unused dependency `serde_json`
[INFO] [stderr]  --> Cargo.toml:5:15
[INFO] [stderr]   |
[INFO] [stderr] 5 | [dependencies.serde_json]
[INFO] [stderr]   |               ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `serde_json`
[INFO] [stderr] warning: `smysl-provider` (manifest) generated 2 warnings
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 32.46s
[INFO] running `Command { std: "docker" "inspect" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", 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" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling smysl-provider v1.8.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `serde`
[INFO] [stderr]  --> Cargo.toml:1:15
[INFO] [stderr]   |
[INFO] [stderr] 1 | [dependencies.serde]
[INFO] [stderr]   |               ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `serde`
[INFO] [stderr] warning: unused dependency `serde_json`
[INFO] [stderr]  --> Cargo.toml:5:15
[INFO] [stderr]   |
[INFO] [stderr] 5 | [dependencies.serde_json]
[INFO] [stderr]   |               ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `serde_json`
[INFO] [stderr] warning: `smysl-provider` (manifest) generated 2 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.89s
[INFO] running `Command { std: "docker" "inspect" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", 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" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused dependency `serde`
[INFO] [stderr]  --> Cargo.toml:1:15
[INFO] [stderr]   |
[INFO] [stderr] 1 | [dependencies.serde]
[INFO] [stderr]   |               ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `serde`
[INFO] [stderr] warning: unused dependency `serde_json`
[INFO] [stderr]  --> Cargo.toml:5:15
[INFO] [stderr]   |
[INFO] [stderr] 5 | [dependencies.serde_json]
[INFO] [stderr]   |               ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the dependency on `serde_json`
[INFO] [stderr] warning: `smysl-provider` (manifest) generated 2 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/smysl-provider/f8659ff16998a234/out/smysl_provider-f8659ff16998a234)
[INFO] [stdout] 
[INFO] [stdout] running 80 tests
[INFO] [stdout] test config::tests::a_configuration_round_trips_its_fields ... ok
[INFO] [stdout] test config::tests::an_empty_configuration_is_valid_and_routes_nothing ... ok
[INFO] [stdout] test config::tests::an_ingest_prompt_is_read_as_a_path_and_nothing_else ... ok
[INFO] [stdout] test config::tests::a_config_mistake_is_reported_as_configuration ... ok
[INFO] [stdout] test config::tests::an_unknown_task_name_is_refused_rather_than_ignored ... ok
[INFO] [stdout] test config::tests::malformed_source_is_an_error_not_a_panic ... ok
[INFO] [stdout] test config::tests::locality_is_read_off_the_endpoint ... ok
[INFO] [stdout] test config::tests::a_fallback_to_an_unconfigured_provider_is_refused_at_load ... ok
[INFO] [stdout] test config::tests::there_is_no_field_a_key_could_be_written_into ... ok
[INFO] [stdout] test config::tests::a_non_string_ingest_path_is_named_by_its_type ... ok
[INFO] [stdout] test config::tests::routing_to_an_unconfigured_provider_is_refused_at_load ... ok
[INFO] [stdout] test config::tests::a_key_in_the_config_is_refused ... ok
[INFO] [stdout] test http::tests::only_rate_limiting_is_retryable ... ok
[INFO] [stdout] test map::tests::an_uncompiled_kind_says_what_is_available ... ok
[INFO] [stdout] test registry::tests::a_task_reaches_the_provider_it_is_routed_to ... ok
[INFO] [stdout] test registry::tests::a_working_provider_is_used_without_touching_the_fallback ... ok
[INFO] [stdout] test registry::tests::fallback_fires_on_nothing_else ... ok
[INFO] [stdout] test registry::tests::an_unrouted_task_is_an_error_rather_than_a_guess ... ok
[INFO] [stdout] test registry::tests::offline_permits_a_local_provider ... ok
[INFO] [stdout] test registry::tests::fallback_fires_on_unreachable ... ok
[INFO] [stdout] test registry::tests::an_exhausted_chain_reports_unreachable ... ok
[INFO] [stdout] test config::tests::an_ingest_path_is_one_of_three_and_nothing_else ... ok
[INFO] [stdout] test registry::tests::an_offline_violation_carries_exit_code_seven ... ok
[INFO] [stdout] test registry::tests::offline_refuses_to_probe_a_hosted_provider ... ok
[INFO] [stdout] test registry::tests::offline_skips_a_hosted_fallback_and_keeps_a_local_one ... ok
[INFO] [stdout] test registry::tests::routing_to_a_missing_provider_is_an_error ... ok
[INFO] [stdout] test registry::tests::the_chain_drops_providers_that_are_not_configured ... ok
[INFO] [stdout] test registry::tests::the_egress_report_names_what_leaves_the_machine ... ok
[INFO] [stdout] test registry::tests::offline_with_a_hosted_provider_fails_without_a_call ... ok
[INFO] [stdout] test registry::tests::retry_advice_is_reachable_from_the_registry ... ok
[INFO] [stdout] test runtime::tests::a_dropped_receiver_does_not_poison_the_thread ... ok
[INFO] [stdout] test runtime::tests::a_job_runs_and_returns_its_value ... ok
[INFO] [stdout] test runtime::tests::jobs_run_in_submission_order ... ok
[INFO] [stdout] test runtime::tests::jobs_run_on_the_provider_thread_not_the_caller ... ok
[INFO] [stdout] test runtime::tests::the_runtime_is_started_lazily_and_stays_started ... ok
[INFO] [stdout] test registry::tests::the_routed_provider_is_not_tried_twice ... ok
[INFO] [stdout] test config::tests::the_config_path_is_the_documented_one ... ok
[INFO] [stdout] test stream::tests::a_closed_receiver_tells_the_emitter_to_stop ... ok
[INFO] [stdout] test config::tests::the_default_configuration_is_entirely_local ... ok
[INFO] [stdout] test runtime::tests::a_spawned_job_runs_without_the_caller_waiting ... ok
[INFO] [stdout] test config::tests::an_unknown_structured_mode_is_refused ... ok
[INFO] [stdout] test stream::tests::a_dropped_sender_without_done_is_unreachable_not_complete ... ok
[INFO] [stdout] test stream::tests::a_finished_stream_reports_its_usage ... ok
[INFO] [stdout] test stream::tests::collect_blocks_until_the_stream_ends ... ok
[INFO] [stdout] test stream::tests::an_error_after_tokens_still_reaches_a_draining_caller ... ok
[INFO] [stdout] test stream::tests::collect_without_a_done_message_is_an_error ... ok
[INFO] [stdout] test runtime::tests::every_job_runs_on_the_same_thread ... ok
[INFO] [stdout] test stream::tests::draining_returns_only_what_is_new ... ok
[INFO] [stdout] test stream::tests::the_emitter_counts_what_it_sent ... ok
[INFO] [stdout] test tests::every_task_names_its_command_and_admits_it_egresses ... ok
[INFO] [stdout] test tests::provider_ids_stay_boring ... ok
[INFO] [stdout] test tests::structured_mode_names_round_trip_through_display ... ok
[INFO] [stdout] test tests::json_mode_is_not_an_enforced_structure ... ok
[INFO] [stdout] test tests::mapper_list_is_sorted_and_deduplicated ... ok
[INFO] [stdout] test tests::task_names_round_trip ... ok
[INFO] [stdout] test tests::temperature_defaults_to_zero ... ok
[INFO] [stdout] test stream::tests::collect_surfaces_an_error_rather_than_the_partial_text ... ok
[INFO] [stdout] test stream::tests::draining_an_idle_stream_returns_nothing_and_does_not_block ... ok
[INFO] [stdout] test tests::usage_totals_both_directions ... ok
[INFO] [stdout] test usage::tests::a_corrupt_line_is_skipped_rather_than_fatal ... ok
[INFO] [stdout] test usage::tests::a_malformed_line_is_none_rather_than_a_panic ... ok
[INFO] [stdout] test usage::tests::a_ledger_persists_and_reopens ... ok
[INFO] [stdout] test usage::tests::a_line_is_one_line ... ok
[INFO] [stdout] test usage::tests::an_in_memory_ledger_needs_no_path ... ok
[INFO] [stdout] test usage::tests::an_unknown_key_is_ignored_rather_than_fatal ... ok
[INFO] [stdout] test tests::a_request_can_measure_itself_before_it_is_sent ... ok
[INFO] [stdout] test tests::a_token_count_says_whether_it_can_be_trusted ... ok
[INFO] [stdout] test usage::tests::a_line_carries_no_content ... ok
[INFO] [stdout] test usage::tests::retries_are_counted_in_the_ledger ... ok
[INFO] [stdout] test usage::tests::since_filters_by_timestamp ... ok
[INFO] [stdout] test usage::tests::rows_come_back_in_key_order ... ok
[INFO] [stdout] test usage::tests::the_ledger_path_is_the_documented_one ... ok
[INFO] [stdout] test usage::tests::a_missing_ledger_is_an_empty_one_rather_than_an_error ... ok
[INFO] [stdout] test usage::tests::a_model_name_with_quotes_survives_the_round_trip ... ok
[INFO] [stdout] test usage::tests::an_entry_round_trips_through_its_line ... ok
[INFO] [stdout] test usage::tests::an_estimated_call_marks_its_whole_group ... ok
[INFO] [stdout] test usage::tests::group_names_parse ... ok
[INFO] [stdout] test usage::tests::totals_group_by_provider ... ok
[INFO] [stdout] test usage::tests::reset_empties_the_ledger_and_removes_the_file ... ok
[INFO] [stdout] test usage::tests::totals_group_by_task_run_and_model ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 80 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/a2_lazy_runtime.rs (/opt/rustwide/target/debug/build/smysl-provider/e6c87ee03884c6f5/out/a2_lazy_runtime-e6c87ee03884c6f5)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test a_purely_local_workload_starts_no_runtime_thread ... 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/capabilities_are_honest.rs (/opt/rustwide/target/debug/build/smysl-provider/1f63d2a1b8bd290d/out/capabilities_are_honest-1f63d2a1b8bd290d)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/deepseek_live.rs (/opt/rustwide/target/debug/build/smysl-provider/e7cf9bdd59b7cf92/out/deepseek_live-e7cf9bdd59b7cf92)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ollama_live.rs (/opt/rustwide/target/debug/build/smysl-provider/9be780f4a3437e21/out/ollama_live-9be780f4a3437e21)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/status_taxonomy.rs (/opt/rustwide/target/debug/build/smysl-provider/4a579261dcd78e90/out/status_taxonomy-4a579261dcd78e90)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests smysl_provider
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b", kill_on_drop: false }`
[INFO] [stdout] 9e97f79d7a87d517f35a79fc40029a2fbeec14903645307b16285d66514bd27b
