[INFO] fetching crate philo 0.10.1...
[INFO] testing philo-0.10.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate philo 0.10.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate philo 0.10.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate philo 0.10.1
[INFO] tweaked toml for crates.io crate philo 0.10.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate philo 0.10.1 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 philo 0.10.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "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-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74
[INFO] running `Command { std: "docker" "start" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", 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" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74" "/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" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", 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" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling futures-core v0.3.33
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling futures-sink v0.3.33
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling futures-task v0.3.33
[INFO] [stderr]    Compiling futures-io v0.3.33
[INFO] [stderr]    Compiling find-msvc-tools v0.1.10
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling displaydoc v0.2.7
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling futures-channel v0.3.33
[INFO] [stderr]    Compiling cc v1.4.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling rustls-pki-types v1.15.1
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling rustls v0.23.43
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling ipnet v2.12.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_writer v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling http v1.5.0
[INFO] [stderr]    Compiling webpki-roots v1.0.9
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling http-body v1.1.0
[INFO] [stderr]    Compiling http-body-util v0.1.4
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling futures-macro v0.3.33
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling futures-util v0.3.33
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling tokio-util v0.7.19
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling futures-executor v0.3.33
[INFO] [stderr]    Compiling futures v0.3.33
[INFO] [stderr]    Compiling h2 v0.4.15
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling tower-http v0.6.11
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling hyper v1.11.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling philo v0.10.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.71s
[INFO] running `Command { std: "docker" "inspect" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", 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" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74" "/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 philo v0.10.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 17.15s
[INFO] running `Command { std: "docker" "inspect" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", 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" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.32s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/philo/c0f54d0f8ad68c61/out/philo-c0f54d0f8ad68c61)
[INFO] [stdout] 
[INFO] [stdout] running 199 tests
[INFO] [stdout] test domain::aggregate::tests::required_replay_is_enforced_at_block_completion ... ok
[INFO] [stdout] test domain::aggregate::tests::block_identity_index_and_delta_type_are_enforced ... ok
[INFO] [stdout] test domain::aggregate::tests::structured_output_is_not_repaired ... ok
[INFO] [stdout] test domain::aggregate::tests::tool_call_ids_are_unique_and_final_arguments_are_objects ... ok
[INFO] [stdout] test catalog::tests::max_output_tokens_metadata_parses_and_validates ... ok
[INFO] [stdout] test domain::aggregate::tests::truncated_accumulator_never_returns_partial_success ... ok
[INFO] [stdout] test domain::aggregate::tests::empty_delta_and_delta_after_finish_are_rejected ... ok
[INFO] [stdout] test catalog::tests::format_spec_refs_materialize_frozen_instances ... ok
[INFO] [stdout] test domain::aggregate::tests::invalid_event_poisons_validator ... ok
[INFO] [stdout] test domain::aggregate::tests::opaque_reasoning_accepts_no_delta_with_token ... ok
[INFO] [stdout] test domain::aggregate::tests::response_termination_matches_open_blocks_and_tool_calls ... ok
[INFO] [stdout] test domain::aggregate::tests::interleaved_blocks_are_aggregated_by_index ... ok
[INFO] [stdout] test domain::image::tests::media_type_is_open_but_image_only ... ok
[INFO] [stdout] test domain::budget::tests::unknown_window_never_judges ... ok
[INFO] [stdout] test domain::image::tests::debug_redacts_inline_bytes_and_url_query ... ok
[INFO] [stdout] test domain::budget::tests::footprint_only_grows_and_exhaustion_reports_deficit ... ok
[INFO] [stdout] test domain::replay::tests::endpoint_path_and_query_are_target_bound ... ok
[INFO] [stdout] test domain::replay::tests::snapshot_round_trips_and_has_safe_debug ... ok
[INFO] [stdout] test domain::request::tests::debug_redacts_request_instructions_and_message_text ... ok
[INFO] [stdout] test domain::replay::tests::serde_rejects_unknown_fields_and_noncanonical_base64 ... ok
[INFO] [stdout] test domain::request::tests::error_tool_result_requires_text ... ok
[INFO] [stdout] test domain::request::tests::schema_and_object_require_object_roots ... ok
[INFO] [stdout] test domain::replay::tests::snapshot_v1_fingerprint_digest_and_serde_are_golden ... ok
[INFO] [stdout] test domain::budget::tests::cache_meters_count_toward_the_footprint ... ok
[INFO] [stdout] test domain::image::tests::url_must_be_absolute_http_and_inline_bytes_are_preserved ... ok
[INFO] [stdout] test domain::replay::tests::corruption_and_version_are_validated_after_deserialization ... ok
[INFO] [stdout] test domain::request::tests::tool_results_must_correlate_once_and_after_the_call ... ok
[INFO] [stdout] test domain::request::tests::validates_generation_without_rewriting ... ok
[INFO] [stdout] test domain::request::tests::validates_tool_choice_and_unique_names ... ok
[INFO] [stdout] test domain::response::tests::debug_redacts_model_event_delta ... ok
[INFO] [stdout] test domain::tools::tests::debug_redacts_schema_and_tool_arguments ... ok
[INFO] [stdout] test error::tests::content_type_failure_summary_names_the_observed_media_type ... ok
[INFO] [stdout] test domain::response::tests::usage_preserves_unknown_and_checked_total ... ok
[INFO] [stdout] test error::tests::provider_summary_and_body_debug_are_redacted ... ok
[INFO] [stdout] test error::tests::every_protocol_reason_has_summary_phrasing ... ok
[INFO] [stdout] test error::tests::classifies_only_the_stable_previous_response_failure_code ... ok
[INFO] [stdout] test error::tests::semantic_summary_and_display_carry_layered_semantics ... ok
[INFO] [stdout] test error::tests::debug_and_display_do_not_render_source ... ok
[INFO] [stdout] test error::tests::replay_failures_have_frozen_top_level_classification ... ok
[INFO] [stdout] test http::tests::managed_headers_are_detected_case_insensitively ... ok
[INFO] [stdout] test ids::tests::rejects_empty_and_control_characters ... ok
[INFO] [stdout] test error::tests::error_code_table_is_frozen ... ok
[INFO] [stdout] test http::tests::retry_after_supports_seconds_dates_and_past ... ok
[INFO] [stdout] test ids::tests::preserves_exact_value_without_inference ... ok
[INFO] [stdout] test http::tests::debug_redacts_request_headers_url_and_body ... ok
[INFO] [stdout] test ids::tests::unknown_names_and_exact_versions_are_not_family_keys ... ok
[INFO] [stdout] test http::tests::patch_order_set_append_remove_is_exact ... ok
[INFO] [stdout] test ids::tests::standard_activations_point_every_family_at_its_shipping_version ... ok
[INFO] [stdout] test ids::tests::pointer_flip_redirects_only_the_flipped_family ... ok
[INFO] [stdout] test model::composition::tests::baseline_matches_official_chat_envelope ... ok
[INFO] [stdout] test model::composition::tests::disabling_reasoning_control_collapses_control_domains ... ok
[INFO] [stdout] test model::composition::tests::removing_tool_choices_and_parallel_policies_shrinks_their_domains ... ok
[INFO] [stdout] test model::composition::tests::removing_efforts_shrinks_the_mode_set ... ok
[INFO] [stdout] test model::composition::tests::deserialization_rejects_unknown_catalog_versions ... ok
[INFO] [stdout] test model::composition::tests::removing_function_tools_drops_the_tool_partition ... ok
[INFO] [stdout] test domain::request::tests::cache_session_id_rejects_empty_and_clamps_prompt_cache_key ... ok
[INFO] [stdout] test model::composition::tests::partial_knowledge_attributes_model_side_failures_as_unknown ... ok
[INFO] [stdout] test model::composition::tests::empty_subtraction_is_accepted ... ok
[INFO] [stdout] test model::composition::tests::serialized_form_uses_partition_compatible_tokens ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::cache_hints_mark_system_tools_and_history_breakpoints ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::request_preserves_system_images_and_tool_error_status ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::requests_without_cache_hints_have_no_cache_control ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::empty_tool_use_arguments_are_a_valid_object ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::stream_maps_structured_text_to_structured_events ... ok
[INFO] [stdout] test domain::request::tests::required_replay_cannot_be_silently_dropped ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::blocked_prompt_finishes_as_refusal ... ok
[INFO] [stdout] test model::composition::tests::disabling_output_reasoning_preserves_the_control_face ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::history_breakpoint_skips_trailing_thinking_blocks ... ok
[INFO] [stdout] test model::composition::tests::linkage_cleared_efforts_reject_further_narrowing ... ok
[INFO] [stdout] test model::composition::tests::serialization_round_trip_preserves_composition_and_fingerprint ... ok
[INFO] [stdout] test protocol::anthropic_messages::tests::thinking_replay_block_carries_streamed_text_and_signature ... ok
[INFO] [stdout] test model::composition::tests::subtraction_rejects_members_outside_the_composition ... ok
[INFO] [stdout] test model::tests::declared_composition_round_trips_through_the_profile ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::content_after_finish_reason_is_a_state_error ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f2_expressivity_feature_set_equals_model_baseline ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::parallel_forbid_is_inexpressible_and_rejected ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::structured_expectation_maps_text_to_structured_output ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::safety_finish_maps_to_refusal_and_unknown_reasons_fail ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::thought_parts_stream_reasoning_and_replay_the_final_part ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::request_omits_model_and_keeps_exact_camel_case_shape ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::truncated_stream_without_finish_reason_fails_termination ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::whole_function_call_parts_become_tool_blocks_with_replay ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::streamed_text_with_trailing_usage_defers_termination_to_finish ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::thinking_config_maps_budget_adaptive_and_report ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::tool_definitions_use_json_schema_field_and_choice_maps_to_modes ... ok
[INFO] [stdout] test protocol::gemini_generate_content::tests::verified_efforts_map_to_thinking_level_and_unsupported_values_fail ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f1_expressivity_is_identical_across_instances ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f3_official_spelling_value_table ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::spelling_helpers_track_their_source_fields ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f3_openrouter_normalized_spelling_value_table ... ok
[INFO] [stdout] test protocol::openai_chat::tests::compatible_v3_advertises_both_reasoning_axes ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f2_expressivity_matches_official_protocol_envelope ... ok
[INFO] [stdout] test protocol::openai_chat::tests::cache_retention_none_omits_identity_even_with_a_session ... ok
[INFO] [stdout] test protocol::openai_chat::tests::cache_hints_are_dropped_by_dialects_without_native_breakpoints ... ok
[INFO] [stdout] test protocol::openai_chat::tests::affinity_headers_are_never_emitted ... ok
[INFO] [stdout] test protocol::openai_chat::tests::finished_tool_call_without_name_carries_structured_reason ... ok
[INFO] [stdout] test protocol::openai_chat::format_spec::tests::f3_fallback_spelling_value_table ... ok
[INFO] [stdout] test protocol::openai_chat::tests::existing_instances_keep_treating_the_normalized_reasoning_key_as_before ... ok
[INFO] [stdout] test protocol::openai_chat::tests::compatible_chat_omits_prompt_cache_fields_by_default ... ok
[INFO] [stdout] test protocol::openai_chat::tests::fallback_long_retention_omits_identity_and_retention ... ok
[INFO] [stdout] test protocol::openai_chat::tests::exact_reasoning_content_is_a_visible_reasoning_block ... ok
[INFO] [stdout] test protocol::openai_chat::tests::official_chat_sends_long_retention_and_clamps_key ... ok
[INFO] [stdout] test protocol::openai_chat::tests::finished_tool_call_with_non_json_arguments_is_malformed_arguments ... ok
[INFO] [stdout] test protocol::openai_chat::tests::compatible_reasoning_effort_shape_maps_every_supported_mode ... ok
[INFO] [stdout] test protocol::openai_chat::tests::history_reasoning_follows_the_instance_reasoning_delta_key ... ok
[INFO] [stdout] test protocol::openai_chat::tests::strict_gate_follows_effective_native_strict_fact ... ok
[INFO] [stdout] test protocol::openai_chat::tests::normalized_reasoning_key_streams_a_visible_reasoning_block ... ok
[INFO] [stdout] test protocol::openai_chat::tests::replay_operations_are_always_unsupported ... ok
[INFO] [stdout] test protocol::openai_chat::tests::compatible_shape_uses_only_common_fields ... ok
[INFO] [stdout] test protocol::openai_chat::tests::text_stream_requires_done_and_aggregates ... ok
[INFO] [stdout] test protocol::openai_chat::tests::official_chat_sends_prompt_cache_key_for_short_retention ... ok
[INFO] [stdout] test protocol::openai_chat::tests::request_uses_modern_chat_shape_without_text_response_format ... ok
[INFO] [stdout] test protocol::openai_chat::tests::reasoning_content_shape_omits_empty_reasoning_and_optional_defaults ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::detect_openai_url_returns_default ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::compatible_matches_compatible_v1 ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::detect_opencode_zen_returns_compatible ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::default_matches_official_openai ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::equiv_compatible_v1 ... ok
[INFO] [stdout] test protocol::openai_chat::tests::usage_reads_root_level_prompt_cache_hit_tokens ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::equiv_openai_v1 ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::history_replay_helper_matrix ... ok
[INFO] [stdout] test catalog::tests::builtin_entry_loads_through_the_user_path ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::reasoning_enabled_matrix ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_continuation_requires_an_explicit_profile_declaration ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_ignores_done_sentinel_without_terminating ... ok
[INFO] [stdout] test protocol::openai_chat::tests::tool_delta_without_prior_id_reports_missing_call_id_immediately ... ok
[INFO] [stdout] test protocol::openai_responses::tests::capabilities_match_legacy_dialects ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_ignores_trailing_done_sentinel ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::detect_openai_provider_returns_default ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::equiv_openai_v2 ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::rejects_unknown_events_matrix ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::setters_are_chainable ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::setters_return_modified_self ... ok
[INFO] [stdout] test protocol::openai_chat::tests::composition_subtraction_drops_control_but_not_the_ceiling ... ok
[INFO] [stdout] test protocol::openai_responses::tests::cache_retention_none_omits_identity_even_with_a_session ... ok
[INFO] [stdout] test protocol::openai_chat::tests::unknown_field_policy_is_a_typed_profile_axis ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_rejects_reasoning_items_as_dialect_mismatch ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_reconstructs_history_without_replay_tokens ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_still_rejects_events_after_completed ... ok
[INFO] [stdout] test protocol::openai_responses::tests::continuation_is_explicit_and_encodes_only_the_new_suffix ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_profile_tolerates_missing_sequence_and_unknown_events ... ok
[INFO] [stdout] test protocol::openai_responses::tests::detected_selects_official_or_compatible_compat ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_responses_can_opt_in_to_prompt_cache_key ... ok
[INFO] [stdout] test protocol::openai_responses::tests::official_default_reconstructs_optional_tool_calls_without_tokens ... ok
[INFO] [stdout] test protocol::openai_responses::tests::items_history_config_still_rejects_tokenless_tool_calls ... ok
[INFO] [stdout] test protocol::openai_responses::tests::custom_compat_can_enable_reasoning_without_item_replay ... ok
[INFO] [stdout] test protocol::openai_responses::tests::official_profile_rejects_trailing_done_sentinel ... ok
[INFO] [stdout] test protocol::openai_responses::tests::official_responses_sends_long_retention_and_clamps_key ... ok
[INFO] [stdout] test protocol::openai_responses::tests::request_has_stateless_responses_invariants ... ok
[INFO] [stdout] test protocol::openai_responses::tests::official_responses_sends_prompt_cache_key_for_short_retention ... ok
[INFO] [stdout] test protocol::openai_responses::tests::session_affinity_headers_follow_the_compat_dialect ... ok
[INFO] [stdout] test protocol::tests::content_type_failure_carries_the_observed_media_type ... ok
[INFO] [stdout] test protocol::openai_responses::tests::compatible_responses_omits_prompt_cache_fields_by_default ... ok
[INFO] [stdout] test protocol::openai_responses::tests::responses_replay_codec_rejects_unknown_versions ... ok
[INFO] [stdout] test protocol::openai_responses::tests::continuation_handle_is_target_bound ... ok
[INFO] [stdout] test protocol::tests::pointer_switch_takes_effect_for_registries_built_afterwards ... ok
[INFO] [stdout] test protocol::openai_responses::tests::typed_message_stream_closes_item_before_completion ... ok
[INFO] [stdout] test protocol::tests::build_resolves_family_activation_pointers_against_registered_adapters ... ok
[INFO] [stdout] test protocol::tests::canonical_protocol_id_normalizes_families_and_passes_through_unknowns ... ok
[INFO] [stdout] test protocol::openai_responses::compat::tests::detect_unknown_provider_returns_compatible ... ok
[INFO] [stdout] test protocol::tests::content_type_failure_without_a_header_omits_the_observed_value ... ok
[INFO] [stdout] test runtime::call::error::tests::retry_mode_applies_to_explicit_disposition ... ok
[INFO] [stdout] test protocol::tests::valid_sse_head_passes_and_overlong_values_are_bounded ... ok
[INFO] [stdout] test provider::credential::tests::dynamic_patch_accepts_one_auth_header_only ... ok
[INFO] [stdout] test runtime::call::error::tests::terminal_retry_report_uses_only_frozen_stop_reasons ... ok
[INFO] [stdout] test runtime::resolver::tests::binding_format_attachment_selects_the_declared_spelling ... ok
[INFO] [stdout] test runtime::resolver::tests::fails_each_exact_lookup_without_fallback ... ok
[INFO] [stdout] test provider::preset::tests::opencode_preset_has_exact_versioned_routes_without_models ... ok
[INFO] [stdout] test runtime::resolver::tests::family_addressing_resolves_through_activation_pointer ... ok
[INFO] [stdout] test runtime::resolver::tests::completions_miss_resolves_fallback_spelling_with_baseline ... ok
[INFO] [stdout] test runtime::resolver::tests::model_capabilities_above_protocol_are_clamped_with_a_diagnostic ... ok
[INFO] [stdout] test runtime::call::attempts::tests::earliest_deadline_preserves_the_winning_timeout_kind ... ok
[INFO] [stdout] test sse::framer::tests::bom_is_only_special_at_stream_start ... ok
[INFO] [stdout] test runtime::resolver::tests::model_placeholder_substitution_rejects_url_breaking_models ... ok
[INFO] [stdout] test protocol::tests::family_alias_serves_the_same_adapter_as_the_exact_version ... ok
[INFO] [stdout] test sse::framer::tests::eof_drops_incomplete_record_and_rejects_invalid_utf8 ... ok
[INFO] [stdout] test sse::framer::tests::record_limit_is_exact ... ok
[INFO] [stdout] test runtime::resolver::tests::resolves_exact_target_and_only_overrides_endpoint ... ok
[INFO] [stdout] test runtime::resolver::tests::unknown_family_names_keep_the_established_error_contract ... ok
[INFO] [stdout] test sse::framer::tests::id_is_carried_without_enabling_reconnection ... ok
[INFO] [stdout] test protocol::tests::token_requires_matching_target_and_type ... ok
[INFO] [stdout] test runtime::resolver::tests::model_placeholder_is_substituted_in_path_and_query ... ok
[INFO] [stdout] test sse::framer::tests::representative_record_survives_every_single_split ... ok
[INFO] [stdout] test provider::error_mapper::tests::default_mapper_extracts_common_openai_error_envelope ... ok
[INFO] [stdout] test runtime::call::error::tests::delivery_state_maps_to_conservative_transport_disposition ... ok
[INFO] [stdout] test runtime::resolver::tests::catalog_entry_supplies_spec_and_composition ... ok
[INFO] [stdout] test provider::error_mapper::tests::default_mapper_falls_back_to_bounded_plain_text ... ok
[INFO] [stdout] test provider::credential::tests::secret_debug_is_redacted ... ok
[INFO] [stdout] test target::tests::debug_redacts_endpoint_query_and_userinfo ... ok
[INFO] [stdout] test sse::framer::tests::representative_stream_survives_one_byte_chunks ... ok
[INFO] [stdout] test sse::framer::tests::short_record_survives_every_double_split ... ok
[INFO] [stdout] test sse::framer::tests::representative_stream_survives_deterministic_random_chunks ... ok
[INFO] [stdout] test transport::reqwest::tests::loopback_preserves_request_and_body_and_returns_identity_bytes ... ok
[INFO] [stdout] test transport::reqwest::tests::loopback_does_not_follow_redirects_or_hide_non_success_status ... ok
[INFO] [stdout] test runtime::call::tests::retry_uses_injected_jitter_and_server_hint_without_real_sleep ... ok
[INFO] [stdout] test transport::reqwest::tests::unsupported_content_encoding_is_a_response_body_error ... ok
[INFO] [stdout] test runtime::call::tests::http_error_has_summary_but_no_raw_body_by_default ... ok
[INFO] [stdout] test runtime::call::tests::bounded_raw_body_capture_is_explicit_and_limited ... ok
[INFO] [stdout] test domain::replay::tests::oversized_payload_is_rejected ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 199 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.87s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/capability_contract.rs (/opt/rustwide/target/debug/build/philo/50c5a559105754d2/out/capability_contract-50c5a559105754d2)
[INFO] [stdout] running 10 tests
[INFO] [stdout] test capability_fingerprint_has_a_stable_golden_encoding ... ok
[INFO] [stderr]      Running tests/catalog_contract.rs (/opt/rustwide/target/debug/build/philo/4889de343e3bd5d6/out/catalog_contract-4889de343e3bd5d6)
[INFO] [stdout] test envelope_builder_derives_the_legacy_query_view_from_structured_facts ... ok
[INFO] [stdout] test preset_materializes_an_owned_profile_with_stable_version ... ok
[INFO] [stdout] test same_model_name_in_different_protocols_is_distinct ... ok
[INFO] [stdout] test subset_compositions_produce_no_diagnostics ... ok
[INFO] [stdout] test catalog_rejects_duplicate_protocol_and_model_key ... ok
[INFO] [stdout] test capability_intersection_is_commutative_associative_and_idempotent ... ok
[INFO] [stdout] test blockers_attribute_to_the_missing_side_of_the_intersection ... ok
[INFO] [stdout] test model_claims_above_the_format_skeleton_are_diagnosed_but_stay_legal ... ok
[INFO] [stdout] test request_requirements_preserve_image_positions_tools_and_generation_values ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test files_without_credential_env_resolve_to_none ... ok
[INFO] [stdout] test second_bad_entry_rejects_the_whole_file ... ok
[INFO] [stdout] test endpoint_must_be_http_or_https ... ok
[INFO] [stdout] test inline_secrets_have_no_schema_field ... ok
[INFO] [stdout] test t9_disabled_invalid_member_name_fails ... ok
[INFO] [stdout] test t9_empty_subtraction_with_complete_knowledge_is_accepted ... ok
[INFO] [stdout] test model_names_are_opaque_quoted_strings ... ok
[INFO] [stdout] test t9_narrow_subsets_shrink_value_domains ... ok
[INFO] [stdout] test duplicate_provider_ids_reject_the_file ... ok
[INFO] [stdout] test single_table_form_is_equivalent_to_array_of_tables ... ok
[INFO] [stdout] test structure_errors_cover_container_shape ... ok
[INFO] [stdout] test unsupported_catalog_version_rejects_the_file ... ok
[INFO] [stdout] test t10_round_trip_through_serde_preserves_composition_and_fingerprint ... ok
[INFO] [stdout] test unknown_provider_and_model_fields_are_rejected ... ok
[INFO] [stdout] test t9_omitted_knowledge_loads_as_partial ... ok
[INFO] [stdout] test t9_narrow_superset_fails_naming_entry_and_field ... ok
[INFO] [stdout] test builtin_opencode_zen_entry_materializes_the_measured_binding ... ok
[INFO] [stdout] test unknown_format_reference_is_rejected ... ok
[INFO] [stdout] test t9_unknown_narrow_target_is_rejected ... ok
[INFO] [stdout] test builtin_openrouter_entry_materializes_the_official_catalog_binding ... ok
[INFO] [stdout] test builtin_credential_is_an_environment_reference_only ... ok
[INFO] [stdout] test builtin_entry_materializes_exactly_the_code_instances ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chat_fixture_contract.rs (/opt/rustwide/target/debug/build/philo/58de062fedba5625/out/chat_fixture_contract-58de062fedba5625)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test fallback_effort_only_cell_advertises_control_without_visible_reasoning ... ok
[INFO] [stdout] test cells_without_reasoning_request_controls_do_not_advertise_them ... ok
[INFO] [stdout] test with_spec_keeps_the_unified_protocol_identity_and_frozen_spelling ... ok
[INFO] [stdout] test deepseek_shaped_reasoning_stream_decodes_exactly ... ok
[INFO] [stdout] test tool_call_continuations_with_empty_id_aggregate ... ok
[INFO] [stdout] test gateway_trailer_chunks_after_finish_are_no_ops ... ok
[INFO] [stdout] test fallback_no_reasoning_cell_rejects_explicit_reasoning_effort_before_transport ... ok
[INFO] [stdout] test interleaved_reasoning_content_tools_and_usage_keep_frozen_order ... ok
[INFO] [stdout] test real_openai_wire_extras_decode_under_the_exact_profile ... ok
[INFO] [stdout] test tool_deltas_usage_and_tool_finish_are_aggregated ... ok
[INFO] [stdout] test post_done_noise_is_tolerated_and_delivers_the_completed_response ... ok
[INFO] [stdout] test composition_without_reasoning_control_absorbs_requests_end_to_end ... ok
[INFO] [stdout] test all_frozen_finish_reasons_are_mapped_exactly ... ok
[INFO] [stdout] test fallback_no_reasoning_cell_tolerates_gateway_and_provider_extras ... ok
[INFO] [stdout] test openrouter_full_cell_decodes_reasoning_deltas_and_replays_history ... ok
[INFO] [stdout] test all_chat_cells_match_their_request_and_response_goldens ... ok
[INFO] [stdout] test exact_reasoning_fields_and_terminal_rules_are_enforced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fixture_catalog_contract.rs (/opt/rustwide/target/debug/build/philo/dee5f8b0a57a37e7/out/fixture_catalog_contract-dee5f8b0a57a37e7)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test frozen_fixture_catalog_is_complete_and_unique ... ok
[INFO] [stderr]      Running tests/live_anthropic_messages.rs (/opt/rustwide/target/debug/build/philo/d0944bab0d509c4f/out/live_anthropic_messages-d0944bab0d509c4f)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[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] [stderr]      Running tests/live_gemini.rs (/opt/rustwide/target/debug/build/philo/6f70b6ec5604e941/out/live_gemini-6f70b6ec5604e941)
[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/live_newapi.rs (/opt/rustwide/target/debug/build/philo/4b408eea22960f06/out/live_newapi-4b408eea22960f06)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/live_openai_chat.rs (/opt/rustwide/target/debug/build/philo/eaca11786cdcacc6/out/live_openai_chat-eaca11786cdcacc6)
[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] [stderr]      Running tests/live_openai_responses.rs (/opt/rustwide/target/debug/build/philo/a33552461b4ca79c/out/live_openai_responses-a33552461b4ca79c)
[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/live_opencode.rs (/opt/rustwide/target/debug/build/philo/9e8081eb92f1b930/out/live_opencode-9e8081eb92f1b930)
[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] [stdout] 
[INFO] [stderr]      Running tests/live_openrouter.rs (/opt/rustwide/target/debug/build/philo/60e105e91711a9fc/out/live_openrouter-60e105e91711a9fc)
[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] [stderr]      Running tests/live_sub2api.rs (/opt/rustwide/target/debug/build/philo/3a9e8bc8c582c845/out/live_sub2api-3a9e8bc8c582c845)
[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/live_zai.rs (/opt/rustwide/target/debug/build/philo/d33115d94d26c491/out/live_zai-d33115d94d26c491)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/protocol_contract.rs (/opt/rustwide/target/debug/build/philo/a6903a584efc1f70/out/protocol_contract-a6903a584efc1f70)
[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 22 tests
[INFO] [stdout] test gemini_blocked_prompt_is_refusal ... ok
[INFO] [stdout] test anthropic_messages_wire_and_domain_contract ... ok
[INFO] [stdout] test openai_chat_fallback_effort_only_cell_sends_effort_on_the_common_shape ... ok
[INFO] [stdout] test openai_chat_fallback_no_reasoning_cell_is_registered_and_uses_common_shape ... ok
[INFO] [stdout] test gemini_gateway_null_finish_reason_is_tolerated ... ok
[INFO] [stdout] test openai_chat_fallback_full_cell_combines_effort_and_reasoning_content ... ok
[INFO] [stdout] test openai_responses_v2_rejects_malformed_real_tool_shape ... ok
[INFO] [stdout] test openai_responses_incomplete_content_filter_is_refusal ... ok
[INFO] [stdout] test openai_chat_fallback_content_only_cell_keeps_the_compact_shape ... ok
[INFO] [stdout] test openai_chat_wire_and_domain_contract ... ok
[INFO] [stdout] test gemini_real_wire_function_call_uses_provider_issued_id ... ok
[INFO] [stdout] test gemini_real_wire_include_thoughts_exposes_reasoning_summary ... ok
[INFO] [stdout] test gemini_real_wire_text_carries_trailing_signature_and_default_thinking_usage ... ok
[INFO] [stdout] test gemini_thought_and_tool_stream_decodes_with_replay ... ok
[INFO] [stdout] test openai_responses_v2_real_tool_wire_has_tiered_replay_requirements ... ok
[INFO] [stdout] test gemini_generate_content_wire_and_domain_contract ... ok
[INFO] [stdout] test openai_responses_reasoning_summary_lifecycle_decodes ... ok
[INFO] [stdout] test openai_responses_compat_is_registered_and_uses_common_shape ... ok
[INFO] [stdout] test openai_responses_wire_and_domain_contract ... ok
[INFO] [stdout] test openai_responses_v2_snapshots_restore_across_client_instances ... ok
[INFO] [stdout] test unknown_field_policy_follows_the_frozen_spelling ... ok
[INFO] [stdout] test spelling_matrix_produces_distinct_wire_shapes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/public_api_contract.rs (/opt/rustwide/target/debug/build/philo/c3c655918ac0e676/out/public_api_contract-c3c655918ac0e676)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test format_spec_is_publicly_exported_and_frozen ... ok
[INFO] [stdout] test provider_headers_reject_transport_managed_names ... ok
[INFO] [stdout] test complete_uses_the_same_stream_pipeline_as_public_stream_consumption ... ok
[INFO] [stdout] test readme_migration_snippet_shapes_compile ... ok
[INFO] [stdout] test provider_headers_and_credentials_have_distinct_ownership ... ok
[INFO] [stdout] test model_metadata_feeds_context_budget_arithmetic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/runtime_contract.rs (/opt/rustwide/target/debug/build/philo/a34a14f210374529/out/runtime_contract-a34a14f210374529)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test retry_mode_and_disposition_public_matrix_is_explicit ... ok
[INFO] [stdout] test observer_receives_ordered_runtime_facts_without_model_payloads ... ok
[INFO] [stdout] test accepted_response_streams_immediately_and_never_retries_body_failure ... ok
[INFO] [stdout] test wire_is_encoded_once_and_reused_across_attempts ... ok
[INFO] [stdout] test capability_preflight_preserves_blocker_and_performs_no_io ... ok
[INFO] [stdout] test pure_prepare_failure_performs_no_credential_or_transport_io ... ok
[INFO] [stdout] test response_head_timeout_has_attempt_context_and_report ... ok
[INFO] [stdout] test stream_idle_timeout_occurs_after_partial_output ... ok
[INFO] [stdout] test dropping_model_call_drops_the_lazy_response_body ... ok
[INFO] [stdout] test protocol_decode_codes_distinguish_invalid_json_and_shape ... ok
[INFO] [stdout] test total_timeout_covers_the_accepted_stream ... ok
[INFO] [stdout] test cancellation_interrupts_retry_backoff_without_another_attempt ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- cancellation_interrupts_retry_backoff_without_another_attempt stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'cancellation_interrupts_retry_backoff_without_another_attempt' (2312) panicked at tests/support/mod.rs:77:18:
[INFO] [stdout] scripted response
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d82a3eb8a81 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d82a3eb8a81 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d82a3eb8a81 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d82a3eb8a81 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d82a3ece95a - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d82a3ece95a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5d82a3ebcdac - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5d82a3ebcdac - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5d82a3e94846 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d82a3e94846 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5d82a3eb0e09 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5d82a3ad4ed0 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5d82a3ad4ed0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5d82a3eb1132 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5d82a3eb1132 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5d82a3e948f2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5d82a3e8d609 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d82a3e954fd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5d82a3ecf17c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d82a3ecee54 - core[a373ad73a504971]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5d82a3ecee54 - core[a373ad73a504971]::option::expect_failed
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:2262:5
[INFO] [stdout]   21:     0x5d82a3a8e1a6 - <core[a373ad73a504971]::option::Option<runtime_contract[9c57fe10cc7ecfa4]::support::ScriptedResponse>>::expect
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5d82a3a8d88d - <runtime_contract[9c57fe10cc7ecfa4]::support::ScriptedTransport as philo[4e147ab0722918e2]::transport::Transport>::send::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/support/mod.rs:77:18
[INFO] [stdout]   23:     0x5d82a3a8f93f - <core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::future::future::Future<Output = core[a373ad73a504971]::result::Result<philo[4e147ab0722918e2]::transport::HttpResponse, philo[4e147ab0722918e2]::transport::TransportError>> + core[a373ad73a504971]::marker::Send>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x5d82a3ab4e24 - philo[4e147ab0722918e2]::runtime::call::attempts::run_attempts::{closure#0}::{closure#17}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/macros/select.rs:710:49
[INFO] [stdout]   25:     0x5d82a3a96520 - <core[a373ad73a504971]::future::poll_fn::PollFn<philo[4e147ab0722918e2]::runtime::call::attempts::run_attempts::{closure#0}::{closure#17}> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/poll_fn.rs:151:9
[INFO] [stdout]   26:     0x5d82a3ab948e - philo[4e147ab0722918e2]::runtime::call::attempts::run_attempts::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/call/attempts.rs:160:13
[INFO] [stdout]   27:     0x5d82a3a9e193 - philo[4e147ab0722918e2]::runtime::call::run_stream::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/call/mod.rs:77:88
[INFO] [stdout]   28:     0x5d82a3a9ca58 - <philo[4e147ab0722918e2]::runtime::call::Call>::stream::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/call/mod.rs:47:26
[INFO] [stdout]   29:     0x5d82a3a8c67d - <philo[4e147ab0722918e2]::runtime::client::PhiloClient>::call::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runtime/client.rs:83:14
[INFO] [stdout]   30:     0x5d82a3aadc41 - runtime_contract[9c57fe10cc7ecfa4]::cancellation_interrupts_retry_backoff_without_another_attempt::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/runtime_contract.rs:580:10
[INFO] [stdout]   31:     0x5d82a3a8fa02 - <core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stdout]   32:     0x5d82a3a8fa7d - <core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stdout]   33:     0x5d82a3ac44bd - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:830:70
[INFO] [stdout]   34:     0x5d82a3ac43f5 - tokio[54b10bc985108e81]::task::coop::with_budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   35:     0x5d82a3ac43f5 - tokio[54b10bc985108e81]::task::coop::budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   36:     0x5d82a3ac43f5 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:830:25
[INFO] [stdout]   37:     0x5d82a3ac0bb2 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:488:19
[INFO] [stdout]   38:     0x5d82a3ac367d - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:829:44
[INFO] [stdout]   39:     0x5d82a3ac2914 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:906:68
[INFO] [stdout]   40:     0x5d82a3a9029b - <tokio[54b10bc985108e81]::runtime::context::scoped::Scoped<tokio[54b10bc985108e81]::runtime::scheduler::Context>>::set::<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   41:     0x5d82a3a8c1c9 - tokio[54b10bc985108e81]::runtime::context::set_scheduler::<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context.rs:187:38
[INFO] [stdout]   42:     0x5d82a3a9112f - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[54b10bc985108e81]::runtime::context::Context>>::try_with::<tokio[54b10bc985108e81]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/local.rs:463:12
[INFO] [stdout]   43:     0x5d82a3a90dde - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[54b10bc985108e81]::runtime::context::Context>>::with::<tokio[54b10bc985108e81]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/local.rs:427:20
[INFO] [stdout]   44:     0x5d82a3a8b55d - tokio[54b10bc985108e81]::runtime::context::set_scheduler::<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context.rs:187:17
[INFO] [stdout]   45:     0x5d82a3ac1860 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:906:27
[INFO] [stdout]   46:     0x5d82a3ac1b57 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:817:24
[INFO] [stdout]   47:     0x5d82a3ac27b6 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:218:33
[INFO] [stdout]   48:     0x5d82a3a9b42a - tokio[54b10bc985108e81]::runtime::context::runtime::enter_runtime::<<tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   49:     0x5d82a3ac06f4 - <tokio[54b10bc985108e81]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/current_thread/mod.rs:206:9
[INFO] [stdout]   50:     0x5d82a3a99d3b - <tokio[54b10bc985108e81]::runtime::runtime::Runtime>::block_on_inner::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/runtime.rs:374:52
[INFO] [stdout]   51:     0x5d82a3a99e7b - <tokio[54b10bc985108e81]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/runtime.rs:343:18
[INFO] [stdout]   52:     0x5d82a3ab2951 - runtime_contract[9c57fe10cc7ecfa4]::cancellation_interrupts_retry_backoff_without_another_attempt
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/runtime_contract.rs:592:7
[INFO] [stdout]   53:     0x5d82a3aa5547 - runtime_contract[9c57fe10cc7ecfa4]::cancellation_interrupts_retry_backoff_without_another_attempt::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/runtime_contract.rs:549:73
[INFO] [stdout]   54:     0x5d82a3a8fec6 - <runtime_contract[9c57fe10cc7ecfa4]::cancellation_interrupts_retry_backoff_without_another_attempt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   55:     0x5d82a3ac831b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   56:     0x5d82a3ac831b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   57:     0x5d82a3ad54f5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   58:     0x5d82a3ad54f5 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   59:     0x5d82a3ad54f5 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   60:     0x5d82a3ad54f5 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   61:     0x5d82a3ad54f5 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5d82a3ad54f5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   63:     0x5d82a3ad54f5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   64:     0x5d82a3acede4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   65:     0x5d82a3acede4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   66:     0x5d82a3ad8582 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   67:     0x5d82a3ad8582 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   68:     0x5d82a3ad8582 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   69:     0x5d82a3ad8582 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   70:     0x5d82a3ad8582 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   71:     0x5d82a3ad8582 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   72:     0x5d82a3ad8582 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   73:     0x5d82a3eb829f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   74:     0x5d82a3eb829f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   75:     0x7d105329bdfa - <unknown>
[INFO] [stdout]   76:     0x7d105332f3d4 - clone
[INFO] [stdout]   77:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     cancellation_interrupts_retry_backoff_without_another_attempt
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 11 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test runtime_contract`
[INFO] running `Command { std: "docker" "inspect" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74", kill_on_drop: false }`
[INFO] [stdout] c49657f5c0378b76c5ece601f26ee2b8cce90e0f65ee68ad5a48619ef27e3f74
