[INFO] cloning repository https://github.com/jfim/jfterm-muxer
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jfim/jfterm-muxer" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjfim%2Fjfterm-muxer", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjfim%2Fjfterm-muxer'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] e85f57917c0d7104b08acee9e5355ae24b7d2d2b
[INFO] testing jfim/jfterm-muxer against 1.98.0-beta.8+cargoflags=--release for beta-release-1.99-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjfim%2Fjfterm-muxer" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/jfim/jfterm-muxer
[INFO] finished tweaking git repo https://github.com/jfim/jfterm-muxer
[INFO] tweaked toml for git repo https://github.com/jfim/jfterm-muxer written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/jfim/jfterm-muxer on toolchain 1.98.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/jfim/jfterm-muxer 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.98.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded log v0.4.30
[INFO] [stderr]   Downloaded vte v0.15.0
[INFO] [stderr]   Downloaded vt100 v0.16.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:8683fc1fc2eb5c9ac98e0d076ab094b2ffac7f99da555d2b6a2e27f346de2ec7" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c
[INFO] running `Command { std: "docker" "start" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", 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" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", 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" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling memchr v2.8.1
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling log v0.4.30
[INFO] [stderr]    Compiling memoffset v0.9.1
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling socket2 v0.6.4
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling vte v0.15.0
[INFO] [stderr]    Compiling jftermd-core v0.1.0 (/opt/rustwide/workdir/core)
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling jftermd v0.1.0 (/opt/rustwide/workdir/jftermd)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 37.78s
[INFO] running `Command { std: "docker" "inspect" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", 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" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling vt100 v0.16.2
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling jftermd-core v0.1.0 (/opt/rustwide/workdir/core)
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling jftermd v0.1.0 (/opt/rustwide/workdir/jftermd)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 25.19s
[INFO] running `Command { std: "docker" "inspect" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", 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" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/jftermd-47a1474ea77b26df)
[INFO] [stdout] 
[INFO] [stdout] running 46 tests
[INFO] [stdout] test daemonize::tests::refuses_to_unlink_non_socket_at_socket_path ... ok
[INFO] [stdout] test protocol::tests::attach_or_open_round_trips_through_a_frame ... ok
[INFO] [stdout] test protocol::tests::close_msg_round_trips ... ok
[INFO] [stdout] test protocol::tests::decode_oversized_length_errors_without_alloc ... ok
[INFO] [stdout] test protocol::tests::decode_partial_then_complete ... ok
[INFO] [stdout] test protocol::tests::decode_round_trip ... ok
[INFO] [stdout] test protocol::tests::decode_two_frames_in_one_push ... ok
[INFO] [stdout] test protocol::tests::decode_unknown_type_errors ... ok
[INFO] [stdout] test daemonize::tests::lock_releases_on_drop_allowing_reacquire ... ok
[INFO] [stdout] test protocol::tests::encode_data_frame_layout ... ok
[INFO] [stdout] test daemonize::tests::stale_socket_file_is_unlinked_and_rebound ... ok
[INFO] [stdout] test protocol::tests::encode_empty_payload ... ok
[INFO] [stdout] test protocol::tests::hello_round_trips ... ok
[INFO] [stdout] test protocol::tests::status_round_trips_both_progress_states ... ok
[INFO] [stdout] test registry::tests::create_then_get_then_remove ... ok
[INFO] [stdout] test pty::tests::write_input_reaches_the_shell ... ok
[INFO] [stdout] test pty::tests::resize_does_not_error ... ok
[INFO] [stdout] test registry::tests::new_sessions_rejected_at_cap_but_reattach_still_works ... ok
[INFO] [stdout] test pty::tests::child_exit_is_reaped_with_status ... ok
[INFO] [stdout] test protocol::tests::frame_data_empty_input_yields_no_frames ... ok
[INFO] [stdout] test server::tests::merge_running_prefers_engine_when_marked_else_poll ... ok
[INFO] [stdout] test server::tests::actor_attaches_forwards_input_and_closes ... ok
[INFO] [stdout] test registry::tests::second_attach_or_create_returns_existing ... ok
[INFO] [stdout] test server::tests::peer_uid_check_accepts_same_uid_socket ... ok
[INFO] [stdout] test session::tests::draining_osc133_latches_has_prompt_marking ... ok
[INFO] [stdout] test pty::tests::echoes_colorterm_truecolor ... ok
[INFO] [stdout] test pty::tests::kill_terminates_and_reaps_as_sigkill ... ok
[INFO] [stdout] test session::tests::resize_and_info_reflect_state ... ok
[INFO] [stdout] test session::tests::input_round_trips ... ok
[INFO] [stdout] test socket::tests::socket_path_falls_back_to_tmp_with_uid_when_unset ... ok
[INFO] [stdout] test socket::tests::ensure_socket_dir_refuses_loose_mode ... ok
[INFO] [stdout] test socket::tests::ensure_socket_dir_creates_0700_directory ... ok
[INFO] [stdout] test socket::tests::ensure_socket_dir_refuses_symlink ... ok
[INFO] [stdout] test registry::tests::wait_for_change_wakes_on_remove ... ok
[INFO] [stdout] test socket::tests::socket_path_uses_xdg_runtime_dir_when_set ... ok
[INFO] [stdout] test pty::tests::echoes_term_to_xterm_256color ... ok
[INFO] [stdout] test session::tests::open_drains_into_replay_before_attach ... ok
[INFO] [stdout] test socket::tests::restrict_socket_perms_sets_0600 ... ok
[INFO] [stdout] test session::tests::shell_exit_marks_dead_and_keeps_replay ... ok
[INFO] [stdout] test protocol::tests::frame_data_splits_oversized_payload_into_max_sized_frames ... ok
[INFO] [stdout] test daemonize::tests::successor_binds_after_predecessor_releases_during_teardown ... ok
[INFO] [stdout] test server::tests::idle_watcher_waits_for_first_session_then_resolves_when_empty ... ok
[INFO] [stdout] test server::tests::dead_session_is_retained_then_reattach_replays_and_exits ... ok
[INFO] [stdout] test server::tests::idle_watcher_does_not_resolve_while_a_session_lives ... ok
[INFO] [stdout] test daemonize::tests::second_acquire_reports_already_running ... ok
[INFO] [stdout] test pty::tests::foreground_not_busy_at_prompt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 46 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.22s
[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] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test list_on_empty_daemon_returns_no_sessions ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/jftermd-1d45a7bbbbd218d4)
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/release/deps/integration-a18025061bbd2a4f)
[INFO] [stdout] test hello_handshake_returns_hello_ok ... ok
[INFO] [stdout] test proto_mismatch_is_rejected_by_closing ... ok
[INFO] [stdout] test second_attach_takes_over_and_kicks_first ... ok
[INFO] [stdout] test socket_drop_detaches_but_keeps_shell_running ... ok
[INFO] [stdout] test input_reaches_shell_and_output_returns ... ok
[INFO] [stdout] test close_grace_zero_reaps_without_escalation ... ok
[INFO] [stdout] test close_kills_and_drops_session ... ok
[INFO] [stdout] test open_drains_then_reattach_replays_scrollback ... ok
[INFO] [stdout] test close_escalates_to_sigkill_when_child_ignores_sighup ... ok
[INFO] [stdout] test shell_exit_while_detached_retains_dead_session_then_replays_exit ... ok
[INFO] [stdout] test running_falls_back_to_tcgetpgrp_without_osc133 ... ok
[INFO] [stdout] test backpressure_drops_stalled_client_without_disturbing_shell ... ok
[INFO] [stderr] 2026-09-03T10:07:30.410498Z  INFO jftermd: idle; shutting down
[INFO] [stdout] test binary_foreground_serves_a_session_then_self_exits_when_idle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.11s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/jftermd_core-13225045448f0520)
[INFO] [stdout] 
[INFO] [stdout] running 36 tests
[INFO] [stdout] test engine::tests::feed_replay_status_roundtrip ... ok
[INFO] [stdout] test engine::tests::has_prompt_marking_latches_on_first_osc133_any_subtype ... ok
[INFO] [stdout] test ring::tests::replay_more_chunks_than_exist_returns_all ... ok
[INFO] [stdout] test engine::tests::no_prompt_marking_without_osc133 ... ok
[INFO] [stdout] test ring::tests::should_cut_reports_when_open_chunk_passes_watermark ... ok
[INFO] [stdout] test ring::tests::replays_open_chunk_data_with_empty_prologue ... ok
[INFO] [stdout] test engine::tests::replay_default_reaches_all_available_scrollback ... ok
[INFO] [stdout] test ring::tests::want_chunks_caps_to_most_recent_and_uses_their_prologue ... ok
[INFO] [stdout] test scanner::tests::c1_st_terminated_dropped_osc_keeps_following_byte ... ok
[INFO] [stdout] test scanner::tests::bell_is_dropped ... ok
[INFO] [stdout] test scanner::tests::c1_st_terminated_title_keeps_following_byte ... ok
[INFO] [stdout] test scanner::tests::desktop_notification_osc9_is_dropped ... ok
[INFO] [stdout] test scanner::tests::dsr_and_da_queries_are_dropped ... ok
[INFO] [stdout] test scanner::tests::osc133_prompt_updates_running_and_is_dropped ... ok
[INFO] [stdout] test scanner::tests::long_single_sequence_is_not_split_across_the_watermark ... ok
[INFO] [stdout] test scanner::tests::osc9_4_progress_updates_status_and_is_dropped ... ok
[INFO] [stdout] test scanner::tests::plain_text_is_kept_verbatim ... ok
[INFO] [stdout] test scanner::tests::osc52_clipboard_is_dropped_from_the_ring ... ok
[INFO] [stdout] test scanner::tests::sgr_is_kept_verbatim_and_tracked ... ok
[INFO] [stdout] test scanner::tests::st_terminated_notification_is_fully_dropped ... ok
[INFO] [stdout] test scanner::tests::osc7_cwd_is_kept_verbatim_and_tracked_into_prologue ... ok
[INFO] [stdout] test scanner::tests::st_terminated_title_is_kept_intact ... ok
[INFO] [stdout] test status::tests::prompt_marker_latches ... ok
[INFO] [stdout] test status::tests::tracks_running_and_progress ... ok
[INFO] [stdout] test scanner::tests::st_terminated_clipboard_is_fully_dropped ... ok
[INFO] [stdout] test sticky::tests::dec_mode_reset_removes_it ... ok
[INFO] [stdout] test status::tests::defaults_are_idle ... ok
[INFO] [stdout] test sticky::tests::dec_private_modes_are_reasserted ... ok
[INFO] [stdout] test scanner::tests::utf8_chars_with_0x9c_continuation_byte_survive_replay ... ok
[INFO] [stdout] test sticky::tests::empty_state_serializes_to_a_hard_reset_only ... ok
[INFO] [stdout] test sticky::tests::scroll_region_and_cwd_and_title ... ok
[INFO] [stdout] test status::tests::progress_can_clear ... ok
[INFO] [stdout] test ring::tests::cut_seals_chunk_and_starts_new_with_prologue ... ok
[INFO] [stdout] test sticky::tests::sgr_is_reasserted ... ok
[INFO] [stdout] test scanner::tests::clear_screen_purges_history ... ok
[INFO] [stderr]      Running tests/oracle.rs (/opt/rustwide/target/release/deps/oracle-f1e53c523d7981d9)
[INFO] [stdout] test ring::tests::purge_drops_prior_chunks_and_resets_open_chunk ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test clear_then_redraw ... ok
[INFO] [stdout] test clipboard_write_does_not_affect_screen ... ok
[INFO] [stdout] test capped_replay_keeps_bottom_screen_faithful ... ok
[INFO] [stdout] test colored_and_styled_text ... ok
[INFO] [stdout] test plain_text ... ok
[INFO] [stdout] test cursor_movement_and_overwrite ... ok
[INFO] [stdout] test scroll_region_and_newlines ... ok
[INFO] [stdout] test title_and_cwd_then_text ... ok
[INFO] [stdout] test bell_and_queries_do_not_affect_screen ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests jftermd
[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]    Doc-tests jftermd_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c", kill_on_drop: false }`
[INFO] [stdout] b0ada56ce1c8c94d307c2342036fca227cfed579c704a0e72537909d7be48c5c