[INFO] cloning repository https://github.com/schneems/bullet_stream
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/schneems/bullet_stream" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fschneems%2Fbullet_stream", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fschneems%2Fbullet_stream'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] e8e727aaf2759d319a1ec057c6bd0edc3e237bfd
[INFO] testing schneems/bullet_stream against beta-2026-04-21 for beta-1.96-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fschneems%2Fbullet_stream" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/schneems/bullet_stream
[INFO] finished tweaking git repo https://github.com/schneems/bullet_stream
[INFO] tweaked toml for git repo https://github.com/schneems/bullet_stream written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/schneems/bullet_stream on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/schneems/bullet_stream 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" "+beta-2026-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0137ef7cfafe1c377e1cdefafe9b6bb5e69ad7550dbc8cf6eeffce6b5eea724c
[INFO] running `Command { std: "docker" "start" "-a" "0137ef7cfafe1c377e1cdefafe9b6bb5e69ad7550dbc8cf6eeffce6b5eea724c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0137ef7cfafe1c377e1cdefafe9b6bb5e69ad7550dbc8cf6eeffce6b5eea724c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0137ef7cfafe1c377e1cdefafe9b6bb5e69ad7550dbc8cf6eeffce6b5eea724c", kill_on_drop: false }`
[INFO] [stdout] 0137ef7cfafe1c377e1cdefafe9b6bb5e69ad7550dbc8cf6eeffce6b5eea724c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b4dc24892ba39e60b8188bc1d3fb7ed2cb2ff7e0e022cf6597d12e6699f496db
[INFO] running `Command { std: "docker" "start" "-a" "b4dc24892ba39e60b8188bc1d3fb7ed2cb2ff7e0e022cf6597d12e6699f496db", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling fun_run v0.6.0
[INFO] [stderr]    Compiling bullet_stream v0.11.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.55s
[INFO] running `Command { std: "docker" "inspect" "b4dc24892ba39e60b8188bc1d3fb7ed2cb2ff7e0e022cf6597d12e6699f496db", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b4dc24892ba39e60b8188bc1d3fb7ed2cb2ff7e0e022cf6597d12e6699f496db", kill_on_drop: false }`
[INFO] [stdout] b4dc24892ba39e60b8188bc1d3fb7ed2cb2ff7e0e022cf6597d12e6699f496db
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6598cbfbe64906f5c0d9ba2f8b28281ba815e64991980cb9fba24b819b3a8059
[INFO] running `Command { std: "docker" "start" "-a" "6598cbfbe64906f5c0d9ba2f8b28281ba815e64991980cb9fba24b819b3a8059", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.93
[INFO] [stderr]    Compiling unicode-ident v1.0.16
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling camino v1.2.2
[INFO] [stderr]    Compiling itoa v1.0.14
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling log v0.4.25
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling bstr v1.11.3
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling uriparse v0.6.4
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling cargo-platform v0.3.2
[INFO] [stderr]    Compiling semver v1.0.27
[INFO] [stderr]    Compiling bitflags v2.8.0
[INFO] [stderr]    Compiling fixedbitset v0.5.7
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling indoc v2.0.7
[INFO] [stderr]    Compiling once_cell v1.20.3
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling petgraph v0.8.3
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling which v8.0.2
[INFO] [stderr]    Compiling toml_parser v1.0.9+spec-1.1.0
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling fancy-regex v0.16.2
[INFO] [stderr]    Compiling fun_run v0.6.0
[INFO] [stderr]    Compiling ascii_table v5.0.0
[INFO] [stderr]    Compiling ignore v0.4.25
[INFO] [stderr]    Compiling bullet_stream v0.11.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling cargo_metadata v0.23.1
[INFO] [stderr]    Compiling libcnb-common v0.30.2
[INFO] [stderr]    Compiling libcnb-proc-macros v0.30.2
[INFO] [stderr]    Compiling libcnb-data v0.30.2
[INFO] [stderr]    Compiling libcnb-package v0.30.2
[INFO] [stderr]    Compiling libcnb-test v0.30.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 47.89s
[INFO] running `Command { std: "docker" "inspect" "6598cbfbe64906f5c0d9ba2f8b28281ba815e64991980cb9fba24b819b3a8059", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6598cbfbe64906f5c0d9ba2f8b28281ba815e64991980cb9fba24b819b3a8059", kill_on_drop: false }`
[INFO] [stdout] 6598cbfbe64906f5c0d9ba2f8b28281ba815e64991980cb9fba24b819b3a8059
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1c5e617b92d4293f2ca902b122cb8f7cd200c9ff1bb59fa4ea3cd9a0554ae9f8
[INFO] running `Command { std: "docker" "start" "-a" "1c5e617b92d4293f2ca902b122cb8f7cd200c9ff1bb59fa4ea3cd9a0554ae9f8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/bullet_stream-9097f9fdbc762a74)
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test ansi_escape::test::empty_line ... ok
[INFO] [stdout] test ansi_escape::test::handles_nested_color_at_start ... ok
[INFO] [stdout] test ansi_escape::test::handles_nested_color_in_middle ... ok
[INFO] [stdout] test ansi_escape::test::simple_case ... ok
[INFO] [stdout] test ansi_escape::test::splits_newlines ... ok
[INFO] [stdout] test ansi_escape::test::handles_double_nested_color ... ok
[INFO] [stdout] test ansi_escape::test::test_strip_ansi ... ok
[INFO] [stdout] test test::double_h1_h2_newlines ... ok
[INFO] [stdout] test duration_format::test::test_display_duration ... ok
[INFO] [stdout] test test::double_h2_h2_newlines ... ok
[INFO] [stdout] test ansi_escape::test::handles_nested_color_at_end ... ok
[INFO] [stdout] test test::test_error ... ok
[INFO] [stdout] test test::h3_first ... ok
[INFO] [stdout] test test::warning_step_padding ... ok
[INFO] [stdout] test test::test_captures ... ok
[INFO] [stdout] test test::write_paragraph_empty_lines ... ok
[INFO] [stdout] test test::warning_after_buildpack ... ok
[INFO] [stdout] test util::test::test_prefix_first_rest_lines ... ok
[INFO] [stdout] test test::background_timer_dropped ... ok
[INFO] [stdout] test test::background_timer ... ok
[INFO] [stdout] test write::test::test_mapped_write ... ok
[INFO] [stdout] test util::test::test_mpsc_streaming_helper ... ok
[INFO] [stdout] test util::test::test_paragraph_inspect_write ... ok
[INFO] [stdout] test util::test::test_prefix_lines ... ok
[INFO] [stdout] test write::test::plain_ending_newline ... ok
[INFO] [stdout] test background_printer::test::test_drop_stops_timer ... ok
[INFO] [stdout] test test::stream_with ... ok
[INFO] [stdout] test write::test::test_stream_cmd ... ok
[INFO] [stdout] test test::double_warning_step_padding ... ok
[INFO] [stdout] test test::paragraph_color_codes ... ok
[INFO] [stdout] test test::test_important ... ok
[INFO] [stdout] test write::test::test_time_cmd ... ok
[INFO] [stdout] test test::test_streaming_a_command ... ok
[INFO] [stdout] test test::test_cmd ... ok
[INFO] [stdout] test background_printer::test::does_stop_does_not_panic ... ok
[INFO] [stdout] test util::test::test_mpsc_streaming_cannot_deadlock ... ok
[INFO] [stdout] test global::test::with_locked_writer_handles_panics_across_threads ... ok
[INFO] [stdout] test test::global_preserves_newline ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests bullet_stream
[INFO] [stdout] 
[INFO] [stdout] running 41 tests
[INFO] [stdout] test src/global.rs - global::print::sub_stream_with (line 486) - compile ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_stream_cmd (line 509) - compile ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_time_cmd (line 558) - compile ... ok
[INFO] [stdout] test src/global.rs - global::set_writer (line 61) ... ok
[INFO] [stdout] test src/global.rs - global::print::h1 (line 248) ... ok
[INFO] [stdout] test src/global.rs - global::print::all_done (line 577) ... ok
[INFO] [stdout] test src/global.rs - global::print::h2 (line 274) ... ok
[INFO] [stdout] test src/global.rs - global::print::bullet (line 444) ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_bullet (line 464) ... ok
[INFO] [stdout] test src/global.rs - global::print::plain (line 338) ... ok
[INFO] [stdout] test src/global.rs - global::print::warning (line 603) ... ok
[INFO] [stdout] test src/global.rs - global::print::h3 (line 303) ... ok
[INFO] [stdout] test src/global.rs - global::print::buildpack (line 363) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 145) ... ok
[INFO] [stdout] test src/lib.rs - (line 117) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 123) ... ok
[INFO] [stdout] test src/global.rs - global::print::error (line 627) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 155) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 167) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::SubBullet<W>>::stream_cmd (line 706) - compile ... ok
[INFO] [stdout] test src/global.rs - global::print::header (line 390) ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_start_timer (line 527) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::SubBullet<W>>::stream_with (line 671) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 136) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::SubBullet<W>>::time_cmd (line 618) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 39) ... ok
[INFO] [stdout] test src/lib.rs - (line 23) ... ok
[INFO] [stdout] test src/lib.rs - (line 170) ... ok
[INFO] [stdout] test src/lib.rs - Print (line 100) ... ok
[INFO] [stdout] test src/lib.rs - state::SubBullet (line 212) ... ok
[INFO] [stdout] test src/lib.rs - (line 87) ... ok
[INFO] [stdout] test src/lib.rs - state::Header (line 148) ... ok
[INFO] [stdout] test src/lib.rs - state::Background (line 272) ... ok
[INFO] [stdout] test src/style.rs - style::important (line 39) ... ok
[INFO] [stdout] test src/lib.rs - (line 202) ... ok
[INFO] [stdout] test src/lib.rs - GlobalTimer (line 30) ... ok
[INFO] [stdout] test src/lib.rs - state::Bullet (line 173) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::Background<W>>::cancel (line 462) ... ok
[INFO] [stdout] test src/lib.rs - state::Stream (line 241) ... ok
[INFO] [stdout] test src/lib.rs - GlobalTimer::done (line 74) ... ok
[INFO] [stdout] test src/lib.rs - GlobalTimer::cancel (line 48) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.66s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1c5e617b92d4293f2ca902b122cb8f7cd200c9ff1bb59fa4ea3cd9a0554ae9f8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1c5e617b92d4293f2ca902b122cb8f7cd200c9ff1bb59fa4ea3cd9a0554ae9f8", kill_on_drop: false }`
[INFO] [stdout] 1c5e617b92d4293f2ca902b122cb8f7cd200c9ff1bb59fa4ea3cd9a0554ae9f8