[INFO] fetching crate telephone 0.2.0...
[INFO] testing telephone-0.2.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate telephone 0.2.0 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate telephone 0.2.0
[INFO] finished tweaking crates.io crate telephone 0.2.0
[INFO] tweaked toml for crates.io crate telephone 0.2.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate telephone 0.2.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate telephone 0.2.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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded libredox v0.1.24
[INFO] [stderr]   Downloaded cc v1.4.6
[INFO] [stderr]   Downloaded hashlink v0.12.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/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] 7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb
[INFO] running `Command { std: "docker" "start" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", 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" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", 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" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling find-msvc-tools v0.1.12
[INFO] [stderr]    Compiling shlex v2.0.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling vcpkg v0.2.15
[INFO] [stderr]    Compiling pkg-config v0.3.34
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling anyhow v1.0.104
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling cc v1.4.6
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling smallvec v1.16.1
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling bitflags v2.13.2
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling clap_builder v4.6.6
[INFO] [stderr]    Compiling hashlink v0.12.2
[INFO] [stderr]    Compiling libsqlite3-sys v0.38.2
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling uuid v1.26.1
[INFO] [stderr]    Compiling clap_derive v4.6.4
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling clap v4.6.6
[INFO] [stderr]    Compiling rusqlite v0.40.2
[INFO] [stderr]    Compiling telephone v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 25.69s
[INFO] running `Command { std: "docker" "inspect" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", 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" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling bitflags v2.13.2
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling rusqlite v0.40.2
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling telephone v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.09s
[INFO] running `Command { std: "docker" "inspect" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", 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" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/telephone-5a06a39cc5480094)
[INFO] [stdout] 
[INFO] [stdout] running 69 tests
[INFO] [stdout] test adapters::claude_code::tests::key_selection_is_bound_to_socket_hash_and_private_permissions ... ok
[INFO] [stdout] test adapters::claude_code::tests::session_records_accept_present_or_absent_proc_start ... ok
[INFO] [stdout] test adapters::tests::peer_text_cannot_escape_its_json_line_or_inject_terminal_controls ... ok
[INFO] [stdout] test address::tests::quoted_arguments_round_trip_through_a_real_shell ... ok
[INFO] [stdout] test address::tests::rejects_shell_and_path_syntax_including_deserialization ... ok
[INFO] [stdout] test address::tests::typed_runtimes_preserve_address_wire_format_and_unknown_envelope_data ... ok
[INFO] [stdout] test context::tests::compaction_and_invalid_usage_cannot_reuse_old_high_counts ... ok
[INFO] [stdout] test context::tests::counts_cached_context_once_and_uses_latest_request_only ... ok
[INFO] [stdout] test adapters::codex::tests::inaccessible_rollout_directory_does_not_discard_readable_siblings ... ok
[INFO] [stdout] test adapters::codex::tests::real_rollout_files_keep_thread_ids_distinct_from_session_roots ... ok
[INFO] [stdout] test context::tests::iteration_usage_survives_zero_aggregates_and_never_sums_requests ... ok
[INFO] [stdout] test adapters::claude_code::tests::real_socket_delivery_reports_unconfirmed_and_frames_are_valid_json ... ok
[INFO] [stdout] test adapters::codex::tests::special_files_cannot_block_discovery ... ok
[INFO] [stdout] test envelope::tests::validates_limits_and_requires_reply_ancestry ... ok
[INFO] [stdout] test adapters::claude_code::tests::a_live_pid_without_start_evidence_is_only_inferred ... ok
[INFO] [stdout] test adapters::claude_code::tests::malformed_records_have_bounded_diagnostics_without_losing_valid_sessions ... ok
[INFO] [stdout] test adapters::codex::tests::capped_database_listing_does_not_hide_exact_addresses ... ok
[INFO] [stdout] test context::tests::reads_bounded_tail_and_ignores_partial_writes_and_special_files ... ok
[INFO] [stdout] test adapters::codex::tests::exact_resolution_can_find_quiet_threads_in_a_real_database ... ok
[INFO] [stdout] test mcp::integration::server_child ... ignored, subprocess helper; exercised by MCP socket tests
[INFO] [stdout] test adapters::codex::tests::malformed_database_rows_keep_valid_rows_and_reach_mcp_as_structured_warnings ... ok
[INFO] [stdout] test mcp::io::tests::restores_original_flags_even_when_opening_output_fails ... ok
[INFO] [stdout] test mcp::tests::invalid_delivery_is_rejected_before_journaling_or_sending ... ok
[INFO] [stdout] test mcp::tests::invalid_send_kind_is_rejected_before_any_adapter_or_journal_work ... ok
[INFO] [stdout] test adapters::claude_code::tests::stalled_socket_writes_obey_deadline_and_closed_peers_fail ... ok
[INFO] [stdout] test mcp::tests::real_protocol_transcript_handles_ping_errors_and_recovers_after_bad_json ... ok
[INFO] [stdout] test private_fs::tests::owner_only_and_no_symlinks_or_hardlinks ... ok
[INFO] [stdout] test proc::tests::a_recycled_pid_is_rejected_but_a_missing_record_is_not ... ok
[INFO] [stdout] test proc::tests::etime_covers_every_shape_ps_emits ... ok
[INFO] [stdout] test proc::tests::live_processes_are_discoverable ... ok
[INFO] [stdout] test proc::tests::our_own_start_time_is_recent_and_plausible ... ok
[INFO] [stdout] test proc::tests::process_group_ids_are_not_session_pids ... ok
[INFO] [stdout] test mcp::integration::buffered_socket_replies_preserve_the_following_frame ... ok
[INFO] [stdout] test process::tests::captures_real_process_output_and_exit_status ... ok
[INFO] [stdout] test mcp::integration::malformed_and_unknown_cancellations_do_not_cancel_another_request ... ok
[INFO] [stdout] test registry::tests::agent_runtime_comes_from_its_address_and_unknown_runtimes_cannot_route ... ok
[INFO] [stdout] test registry::tests::ambiguous_names_are_an_error_rather_than_a_guess ... ok
[INFO] [stdout] test registry::tests::an_agent_answers_to_its_address_name_and_bare_id ... ok
[INFO] [stdout] test registry::tests::resolving_an_unknown_name_points_at_list ... ok
[INFO] [stdout] test adapters::codex::tests::capped_rollout_listing_has_a_separate_bounded_exact_lookup ... ok
[INFO] [stdout] test process::tests::bounds_hung_and_noisy_processes ... ok
[INFO] [stdout] test mcp::integration::a_drained_response_commits_and_a_control_flood_has_a_bounded_queue ... ok
[INFO] [stdout] test store::diagnostics::tests::damaged_history_does_not_break_an_empty_inbox ... ok
[INFO] [stdout] test mcp::integration::ping_and_cancellation_work_while_a_real_sqlite_writer_blocks_the_tool ... ok
[INFO] [stdout] test process::tests::deadline_reaps_child_and_stops_its_process_group ... ok
[INFO] [stdout] test mcp::integration::process_death_during_output_keeps_inbox_messages_pending ... ok
[INFO] [stdout] test store::tests::consumer_child ... ignored, subprocess helper; run by separate_processes_consume_once
[INFO] [stdout] test store::receiving::tests::poll_evidence_expires_and_is_bound_to_the_session_not_just_pid ... ok
[INFO] [stdout] test store::tests::a_real_broken_output_socket_leaves_messages_unread ... ok
[INFO] [stdout] test store::diagnostics::tests::existing_journals_gain_history_without_replaying_native_messages ... ok
[INFO] [stdout] test store::tests::addresses_are_not_lossily_encoded ... ok
[INFO] [stdout] test store::diagnostics::tests::notices_survive_cancelled_output_and_peeks_without_repeating_on_polls ... ok
[INFO] [stdout] test store::tests::database_and_sidecars_are_private_and_symlinks_are_rejected ... ok
[INFO] [stdout] test store::tests::failed_output_rolls_back_and_duplicate_deposit_does_not_requeue ... ok
[INFO] [stdout] test store::tests::process_death_before_commit_preserves_pending_messages ... ok
[INFO] [stdout] test store::tests::real_journal_bounds_a_reply_exchange_and_checks_participants ... ok
[INFO] [stdout] test mcp::tests::oversized_request_is_rejected_before_parsing ... ok
[INFO] [stdout] test adapters::codex::tests::concurrent_rollout_writes_and_disappearances_preserve_stable_records ... ok
[INFO] [stdout] test mcp::integration::cancellation_during_partial_output_preserves_framing_but_not_the_receipt ... ok
[INFO] [stdout] test mcp::integration::broken_output_and_truncated_input_roll_back_pending_receipts ... ok
[INFO] [stdout] test adapters::codex::tests::total_rollout_metadata_bytes_are_bounded ... ok
[INFO] [stdout] test adapters::codex::tests::expensive_database_work_is_interrupted_instead_of_hanging_discovery ... ok
[INFO] [stdout] test store::diagnostics::tests::history_is_bounded_by_recipient_and_does_not_claim_uncertain_sends_were_accepted ... ok
[INFO] [stdout] test adapters::claude_code::tests::exact_pid_lookup_bypasses_directory_scan_limit ... ok
[INFO] [stdout] test store::tests::separate_processes_consume_once ... ok
[INFO] [stdout] test store::tests::independent_connections_never_deliver_the_same_message_twice ... ok
[INFO] [stdout] test store::tests::inbox_limits_and_id_collisions_are_transactional ... ok
[INFO] [stdout] test mcp::integration::partial_input_times_out_but_an_idle_session_does_not ... ok
[INFO] [stdout] test mcp::integration::stalled_output_rolls_back_inbox_and_releases_the_writer_lock ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 67 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 5.94s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb", kill_on_drop: false }`
[INFO] [stdout] 7d94c6fc206b7aec0611c9bbe1b000ef0d220394dfcc9edab4676a41d4e1aecb
