[INFO] fetching crate rig-compose 0.5.0...
[INFO] testing rig-compose-0.5.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rig-compose 0.5.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate rig-compose 0.5.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rig-compose 0.5.0
[INFO] tweaked toml for crates.io crate rig-compose 0.5.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rig-compose 0.5.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 rig-compose 0.5.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-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] f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda
[INFO] running `Command { std: "docker" "start" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", 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" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda" "/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" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", 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" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda" "/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.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling memchr v2.8.1
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling dashmap v6.2.1
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rig-compose v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.62s
[INFO] running `Command { std: "docker" "inspect" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", 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" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda" "/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 tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling rig-compose v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.44s
[INFO] running `Command { std: "docker" "inspect" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", 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" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda" "/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.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rig-compose/5ec26b00bcb6dab0/out/rig_compose-5ec26b00bcb6dab0)
[INFO] [stdout] 
[INFO] [stdout] running 72 tests
[INFO] [stdout] test budget::tests::token_budget_debits_overage ... ok
[INFO] [stdout] test budget::tests::token_reservation_reports_estimate ... ok
[INFO] [stdout] test budget::tests::token_budget_reconciles_each_reservation_independently ... ok
[INFO] [stdout] test budget::tests::utilization_tracks_consumption ... ok
[INFO] [stdout] test budget::tests::token_budget_reserves_records_and_reports ... ok
[INFO] [stdout] test budget::tests::token_reservation_refunds_on_drop ... ok
[INFO] [stdout] test budget::tests::token_reservation_refund_is_capped_at_capacity ... ok
[INFO] [stdout] test budget::tests::reserve_until_empty ... ok
[INFO] [stdout] test instructions::tests::builder_chains ... ok
[INFO] [stdout] test normalizer::tests::applicable_when_start_marker_present ... ok
[INFO] [stdout] test normalizer::tests::boolean_args ... ok
[INFO] [stdout] test coordinator::tests::unmatched_with_no_fallback_is_noop ... ok
[INFO] [stdout] test normalizer::tests::double_quoted_string_arg ... ok
[INFO] [stdout] test budget::tests::refill_restores_capacity ... ok
[INFO] [stdout] test budget::tests::release_caps_at_capacity ... ok
[INFO] [stdout] test delegate::tests::in_process_delegate_drives_child_agent ... ok
[INFO] [stdout] test instructions::tests::round_trips_serde ... ok
[INFO] [stdout] test normalizer::tests::dispatch_invocations_runs_tools_in_order ... ok
[INFO] [stdout] test delegate::tests::delegate_registry_descriptors_are_sorted ... ok
[INFO] [stdout] test coordinator::tests::falls_back_when_no_rule_matches ... ok
[INFO] [stdout] test coordinator::tests::routes_to_first_matching_specialist ... ok
[INFO] [stdout] test coordinator::tests::same_skill_instance_works_for_two_agents ... ok
[INFO] [stdout] test normalizer::tests::invalid_tool_name_returns_error ... ok
[INFO] [stdout] test normalizer::tests::multiple_calls_in_one_block ... ok
[INFO] [stdout] test normalizer::tests::openai_chat_completions_tool_calls ... ok
[INFO] [stdout] test normalizer::tests::malformed_standard_arguments_return_error ... ok
[INFO] [stdout] test normalizer::tests::multiple_blocks_in_one_message ... ok
[INFO] [stdout] test normalizer::tests::openai_responses_function_call_item ... ok
[INFO] [stdout] test normalizer::tests::missing_paren_returns_error ... ok
[INFO] [stdout] test normalizer::tests::single_call_multiple_args ... ok
[INFO] [stdout] test normalizer::tests::openai_responses_full_response ... ok
[INFO] [stdout] test normalizer::tests::not_applicable_for_plain_text ... ok
[INFO] [stdout] test normalizer::tests::single_call_string_arg ... ok
[INFO] [stdout] test normalizer::tests::integer_arg ... ok
[INFO] [stdout] test normalizer::tests::kwarg_without_equals_returns_error ... ok
[INFO] [stdout] test normalizer::tests::plain_text_returns_empty ... ok
[INFO] [stdout] test normalizer::tests::duplicate_kwarg_returns_error ... ok
[INFO] [stdout] test normalizer::tests::block_without_brackets_is_parsed ... ok
[INFO] [stdout] test normalizer::tests::empty_kwarg_name_returns_error ... ok
[INFO] [stdout] test normalizer::tests::single_call_no_args ... ok
[INFO] [stdout] test normalizer::tests::null_args ... ok
[INFO] [stdout] test normalizer::tests::float_arg ... ok
[INFO] [stdout] test normalizer::tests::split_respects_parens ... ok
[INFO] [stdout] test normalizer::tests::nested_list_and_object_args ... ok
[INFO] [stdout] test normalizer::tests::trailing_call_content_returns_error ... ok
[INFO] [stdout] test registry::tests::skill_registry_descriptors_are_sorted ... ok
[INFO] [stdout] test normalizer::tests::split_respects_single_quotes ... ok
[INFO] [stdout] test reliability::tests::fingerprint_differs_when_args_differ ... ok
[INFO] [stdout] test reliability::tests::fingerprint_differs_when_tool_name_differs ... ok
[INFO] [stdout] test reliability::tests::repair_is_idempotent ... ok
[INFO] [stdout] test reliability::tests::repair_keeps_last_failure_when_no_completion ... ok
[INFO] [stdout] test reliability::tests::repair_on_empty_history_returns_empty ... ok
[INFO] [stdout] test reliability::tests::fingerprint_is_order_independent_for_object_args ... ok
[INFO] [stdout] test normalizer::tests::structured_normalizer_aggregates_supported_shapes ... ok
[INFO] [stdout] test registry::tests::tool_registry_descriptors_are_sorted_and_scoped ... ok
[INFO] [stdout] test reliability::tests::default_classifier_marks_tool_failed_transient ... ok
[INFO] [stdout] test reliability::tests::fingerprint_is_stable_for_same_invocation ... ok
[INFO] [stdout] test registry::tests::tool_registry_missing ... ok
[INFO] [stdout] test reliability::tests::default_classifier_marks_policy_errors_permanent ... ok
[INFO] [stdout] test normalizer::tests::unterminated_nested_literal_returns_error ... ok
[INFO] [stdout] test registry::tests::tool_registry_authorisation ... ok
[INFO] [stdout] test tool::tests::tool_result_envelope_leaves_small_payloads_unchanged ... ok
[INFO] [stdout] test reliability::tests::repair_preserves_first_occurrence_order_across_fingerprints ... ok
[INFO] [stdout] test tool::tests::local_tool_roundtrip ... ok
[INFO] [stdout] test normalizer::tests::split_respects_nested_arrays_and_objects ... ok
[INFO] [stdout] test tool::tests::tool_result_envelope_redacts_before_truncation ... ok
[INFO] [stdout] test normalizer::tests::unclosed_marker_returns_error ... ok
[INFO] [stdout] test tool::tests::tool_result_envelope_bounds_arrays ... ok
[INFO] [stdout] test reliability::tests::history_entry_failed_helper_records_classifier_verdict ... ok
[INFO] [stdout] test tool::tests::tool_result_envelope_total_budget_drops_fields_with_path_tokens ... ok
[INFO] [stderr]      Running tests/context_pack.rs (/opt/rustwide/target/debug/build/rig-compose/1bd067c059e759ba/out/context_pack-1bd067c059e759ba)
[INFO] [stdout] test reliability::tests::repair_keeps_first_completion_after_retries ... ok
[INFO] [stdout] test tool::tests::tool_result_envelope_bounds_large_strings ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 72 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test context_pack_sorts_by_rank_before_packing ... ok
[INFO] [stdout] test context_pack_records_tool_result_over_budget_omissions ... ok
[INFO] [stdout] test context_pack_accounts_for_separator_budget ... ok
[INFO] [stdout] test context_pack_max_items_applies_uniformly_across_sources ... ok
[INFO] [stdout] test context_pack_mixes_memory_tool_and_resource_sources ... ok
[INFO] [stdout] test context_pack_reserves_non_context_budget ... ok
[INFO] [stdout] test context_item_defaults_estimated_chars_and_preserves_metadata ... ok
[INFO] [stdout] test context_pack_saturates_when_reserve_exceeds_budget ... ok
[INFO] [stdout] test context_pack_records_max_item_omissions ... ok
[INFO] [stdout] test context_item_decodes_missing_provenance_as_empty ... ok
[INFO] [stdout] test context_item_round_trips_typed_provenance ... ok
[INFO] [stderr]      Running tests/dispatch_trace.rs (/opt/rustwide/target/debug/build/rig-compose/78e1c8bcbc1b3c62/out/dispatch_trace-78e1c8bcbc1b3c62)
[INFO] [stdout] test context_pack_packs_tool_invocation_results ... ok
[INFO] [stdout] test context_pack_records_over_budget_omissions ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test tests::trace_records_skip_with_reason ... ok
[INFO] [stdout] test tests::trace_records_terminate_emits_failure_and_no_completion ... ok
[INFO] [stdout] test tests::trace_records_hook_before_error_with_cleanup_subset ... ok
[INFO] [stdout] test tests::trace_records_continue_then_completed ... ok
[INFO] [stderr]      Running tests/normalizer.rs (/opt/rustwide/target/debug/build/rig-compose/36bb2fee8167a32b/out/normalizer-36bb2fee8167a32b)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 28 tests
[INFO] [stdout] test kwarg_without_equals_is_an_error ... ok
[INFO] [stdout] test malformed_identifiers_are_errors ... ok
[INFO] [stdout] test mixed_arg_types ... ok
[INFO] [stdout] test negative_integer_arg ... ok
[INFO] [stdout] test nested_list_and_object_args ... ok
[INFO] [stdout] test no_tool_call_in_response ... ok
[INFO] [stdout] test duplicate_kwargs_are_errors ... ok
[INFO] [stdout] test is_applicable_matches_exactly_when_marker_present ... ok
[INFO] [stdout] test full_llm_response_with_preamble_and_postamble ... ok
[INFO] [stdout] test dispatch_hooks_report_skip_outcome ... ok
[INFO] [stdout] test dispatch_hooks_can_terminate_before_tool_invocation ... ok
[INFO] [stdout] test dispatch_budget_hook_releases_when_later_hook_errors_in_before_invocation ... ok
[INFO] [stdout] test dispatch_hooks_record_after_invocation ... ok
[INFO] [stdout] test error_message_mentions_unclosed ... ok
[INFO] [stdout] test dispatch_hooks_can_skip_with_synthetic_output ... ok
[INFO] [stdout] test chained_tool_calls_two_separate_blocks ... ok
[INFO] [stdout] test unclosed_start_marker_is_an_error ... ok
[INFO] [stderr]      Running tests/result_envelope.rs (/opt/rustwide/target/debug/build/rig-compose/65d501ea9ad22203/out/result_envelope-65d501ea9ad22203)
[INFO] [stdout] test dispatch_budget_hook_releases_after_tool_error ... ok
[INFO] [stdout] test dispatch_budget_hook_terminates_when_budget_is_denied ... ok
[INFO] [stdout] test dispatch_budget_hook_releases_after_success ... ok
[INFO] [stdout] test parallel_tool_calls_in_one_block ... ok
[INFO] [stdout] test normalized_invocations_dispatch_through_tool_registry ... ok
[INFO] [stdout] test normalized_tool_results_can_drive_a_second_model_turn ... ok
[INFO] [stdout] test string_with_comma_in_value ... ok
[INFO] [stdout] test structured_standard_invocations_dispatch_through_tool_registry ... ok
[INFO] [stdout] test openai_chat_completions_tool_calls_normalize_to_invocation ... ok
[INFO] [stdout] test openai_responses_output_normalizes_to_invocation ... ok
[INFO] [stdout] test unsupported_structured_payload_returns_empty ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 28 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test total_payload_budget_omits_later_fields ... ok
[INFO] [stdout] test small_payloads_pass_through_envelope_unchanged ... ok
[INFO] [stdout] test dispatch_then_bound_tool_result_clamps_oversized_payload ... ok
[INFO] [stdout] test custom_envelope_config_round_trips_through_serde ... ok
[INFO] [stdout] test omitted_segments_disambiguate_two_oversized_fields ... ok
[INFO] [stdout] test bounded_dispatch_returns_envelopes_without_changing_raw_dispatch ... ok
[INFO] [stdout] test redaction_runs_before_size_bounding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rig_compose
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test src/lib.rs - normalizer (line 76) - compile ... ok
[INFO] [stdout] test src/normalizer.rs - normalizer::LfmNormalizer (line 539) - compile ... ok
[INFO] [stdout] test src/budget.rs - budget (line 32) - compile ... ok
[INFO] [stdout] test src/trace.rs - trace (line 12) - compile ... ok
[INFO] [stdout] test src/reliability.rs - reliability (line 27) - compile ... ok
[INFO] [stdout] test src/context.rs - context::ContextProvenance (line 79) ... ok
[INFO] [stdout] test src/context.rs - context::ContextPack (line 426) ... ok
[INFO] [stdout] test src/context.rs - context::ContextItem (line 237) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.73s; merged doctests compilation took 0.72s
[INFO] running `Command { std: "docker" "inspect" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda", kill_on_drop: false }`
[INFO] [stdout] f743542a29836634b615f959be6ec736694de152038f86d5500e3920c86cccda
