[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] 605e684527700973adef9a756d32c2363c9d7da7
[INFO] testing schneems/bullet_stream against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fschneems%2Fbullet_stream" "/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/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-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/schneems/bullet_stream on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded ascii_table v4.0.5
[INFO] [stderr]   Downloaded libcnb-package v0.23.0
[INFO] [stderr]   Downloaded fun_run v0.6.0
[INFO] [stderr]   Downloaded libcnb-common v0.23.0
[INFO] [stderr]   Downloaded libcnb-data v0.23.0
[INFO] [stderr]   Downloaded libcnb-proc-macros v0.23.0
[INFO] [stderr]   Downloaded libcnb-test v0.23.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 56300408719a5097f87cbd9fe58c23117f65d1b3db6fced3fb95fd71705be6c9
[INFO] running `Command { std: "docker" "start" "-a" "56300408719a5097f87cbd9fe58c23117f65d1b3db6fced3fb95fd71705be6c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "56300408719a5097f87cbd9fe58c23117f65d1b3db6fced3fb95fd71705be6c9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "56300408719a5097f87cbd9fe58c23117f65d1b3db6fced3fb95fd71705be6c9", kill_on_drop: false }`
[INFO] [stdout] 56300408719a5097f87cbd9fe58c23117f65d1b3db6fced3fb95fd71705be6c9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0a1e46b90280cd68cd71441e834dc9b54eab7cc5bfaa6e33be98cc5c8b13023a
[INFO] running `Command { std: "docker" "start" "-a" "0a1e46b90280cd68cd71441e834dc9b54eab7cc5bfaa6e33be98cc5c8b13023a", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling regex v1.11.1
[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 6.85s
[INFO] running `Command { std: "docker" "inspect" "0a1e46b90280cd68cd71441e834dc9b54eab7cc5bfaa6e33be98cc5c8b13023a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0a1e46b90280cd68cd71441e834dc9b54eab7cc5bfaa6e33be98cc5c8b13023a", kill_on_drop: false }`
[INFO] [stdout] 0a1e46b90280cd68cd71441e834dc9b54eab7cc5bfaa6e33be98cc5c8b13023a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 05c738d4c5095415c87213b988cc91b0dfdbaaedcececa2f107d0c745fe6ea9c
[INFO] running `Command { std: "docker" "start" "-a" "05c738d4c5095415c87213b988cc91b0dfdbaaedcececa2f107d0c745fe6ea9c", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.138
[INFO] [stderr]    Compiling semver v1.0.25
[INFO] [stderr]    Compiling camino v1.1.9
[INFO] [stderr]    Compiling bit-vec v0.6.3
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling serde v1.0.217
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling indexmap v2.7.1
[INFO] [stderr]    Compiling winnow v0.7.2
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling libc v0.2.169
[INFO] [stderr]    Compiling syn v2.0.98
[INFO] [stderr]    Compiling bstr v1.11.3
[INFO] [stderr]    Compiling log v0.4.25
[INFO] [stderr]    Compiling uriparse v0.6.4
[INFO] [stderr]    Compiling either v1.13.0
[INFO] [stderr]    Compiling bit-set v0.5.3
[INFO] [stderr]    Compiling fixedbitset v0.4.2
[INFO] [stderr]    Compiling home v0.5.11
[INFO] [stderr]    Compiling indoc v2.0.5
[INFO] [stderr]    Compiling once_cell v1.20.3
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling which v6.0.3
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling tempfile v3.16.0
[INFO] [stderr]    Compiling petgraph v0.6.5
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling serde_derive v1.0.217
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling fancy-regex v0.13.0
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling globset v0.4.15
[INFO] [stderr]    Compiling fun_run v0.6.0
[INFO] [stderr]    Compiling ascii_table v4.0.5
[INFO] [stderr]    Compiling ignore v0.4.23
[INFO] [stderr]    Compiling bullet_stream v0.11.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling toml_datetime v0.6.8
[INFO] [stderr]    Compiling serde_spanned v0.6.8
[INFO] [stderr]    Compiling cargo-platform v0.1.9
[INFO] [stderr]    Compiling toml_edit v0.22.24
[INFO] [stderr]    Compiling cargo_metadata v0.18.1
[INFO] [stderr]    Compiling toml v0.8.20
[INFO] [stderr]    Compiling libcnb-common v0.23.0
[INFO] [stderr]    Compiling libcnb-proc-macros v0.23.0
[INFO] [stderr]    Compiling libcnb-data v0.23.0
[INFO] [stderr]    Compiling libcnb-package v0.23.0
[INFO] [stderr]    Compiling libcnb-test v0.23.0
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 29.42s
[INFO] running `Command { std: "docker" "inspect" "05c738d4c5095415c87213b988cc91b0dfdbaaedcececa2f107d0c745fe6ea9c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "05c738d4c5095415c87213b988cc91b0dfdbaaedcececa2f107d0c745fe6ea9c", kill_on_drop: false }`
[INFO] [stdout] 05c738d4c5095415c87213b988cc91b0dfdbaaedcececa2f107d0c745fe6ea9c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ac59459f1b157f40be111fc9b31ae82a784a5413c0b9c1aa39fc1cadd761be38
[INFO] running `Command { std: "docker" "start" "-a" "ac59459f1b157f40be111fc9b31ae82a784a5413c0b9c1aa39fc1cadd761be38", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.19s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/bullet_stream-297d9d2110b779e8)
[INFO] [stdout] 
[INFO] [stdout] running 38 tests
[INFO] [stdout] test ansi_escape::test::empty_line ... ok
[INFO] [stdout] test ansi_escape::test::handles_double_nested_color ... ok
[INFO] [stdout] test ansi_escape::test::handles_nested_color_at_start ... ok
[INFO] [stdout] test ansi_escape::test::handles_nested_color_at_end ... 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::test_strip_ansi ... ok
[INFO] [stdout] test ansi_escape::test::splits_newlines ... ok
[INFO] [stdout] test duration_format::test::test_display_duration ... ok
[INFO] [stdout] test background_printer::test::does_stop_does_not_panic ... ok
[INFO] [stdout] test test::double_h1_h2_newlines ... ok
[INFO] [stdout] test test::paragraph_color_codes ... ok
[INFO] [stdout] test test::double_h2_h2_newlines ... ok
[INFO] [stdout] test test::background_timer ... ok
[INFO] [stdout] test test::double_warning_step_padding ... ok
[INFO] [stdout] test test::warning_after_buildpack ... ok
[INFO] [stdout] test test::test_captures ... ok
[INFO] [stdout] test test::test_error ... ok
[INFO] [stdout] test test::h3_first ... ok
[INFO] [stdout] test test::test_important ... ok
[INFO] [stdout] test background_printer::test::test_drop_stops_timer ... ok
[INFO] [stdout] test test::warning_step_padding ... ok
[INFO] [stdout] test test::write_paragraph_empty_lines ... ok
[INFO] [stdout] test util::test::test_paragraph_inspect_write ... ok
[INFO] [stdout] test util::test::test_prefix_first_rest_lines ... ok
[INFO] [stdout] test write::test::plain_ending_newline ... ok
[INFO] [stdout] test write::test::test_mapped_write ... ok
[INFO] [stdout] test util::test::test_prefix_lines ... ok
[INFO] [stdout] test util::test::test_mpsc_streaming_helper ... ok
[INFO] [stdout] test test::background_timer_dropped ... ok
[INFO] [stdout] test test::stream_with ... ok
[INFO] [stdout] test write::test::test_time_cmd ... ok
[INFO] [stdout] test test::test_streaming_a_command ... ok
[INFO] [stdout] test write::test::test_stream_cmd ... ok
[INFO] [stdout] test test::test_cmd ... ok
[INFO] [stdout] test test::global_preserves_newline ... 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] 
[INFO] [stdout] test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests bullet_stream
[INFO] [stdout] 
[INFO] [stdout] running 41 tests
[INFO] [stdout] test src/global.rs - global::print::sub_time_cmd (line 558) - 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_stream_with (line 486) - compile ... ok
[INFO] [stdout] test src/global.rs - global::print::plain (line 338) ... ok
[INFO] [stdout] test src/global.rs - global::set_writer (line 61) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 123) ... 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::h3 (line 303) ... ok
[INFO] [stdout] test src/global.rs - global::print::header (line 390) ... ok
[INFO] [stdout] test src/global.rs - global::print::buildpack (line 363) ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_bullet (line 464) ... ok
[INFO] [stdout] test src/global.rs - global::print::all_done (line 577) ... ok
[INFO] [stdout] test src/global.rs - global::print::sub_start_timer (line 527) ... ok
[INFO] [stdout] test src/global.rs - global::print::h1 (line 248) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 155) ... ok
[INFO] [stdout] test src/lib.rs - (line 117) ... ok
[INFO] [stdout] test src/lib.rs - (line 136) ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 145) ... 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::error (line 627) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::SubBullet<W>>::stream_with (line 671) - compile ... ok
[INFO] [stdout] test src/global.rs - global::with_locked_writer (line 167) ... ok
[INFO] [stdout] test src/lib.rs - (line 39) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::SubBullet<W>>::time_cmd (line 618) - compile ... ok
[INFO] [stdout] test src/global.rs - global::print::warning (line 603) ... 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 - GlobalTimer::cancel (line 48) ... ok
[INFO] [stdout] test src/lib.rs - state::Stream (line 241) ... ok
[INFO] [stdout] test src/lib.rs - Print<state::Background<W>>::cancel (line 462) ... ok
[INFO] [stdout] test src/lib.rs - state::Background (line 272) ... ok
[INFO] [stdout] test src/lib.rs - (line 23) ... ok
[INFO] [stdout] test src/lib.rs - (line 202) ... ok
[INFO] [stdout] test src/lib.rs - state::Bullet (line 173) ... ok
[INFO] [stdout] test src/lib.rs - (line 87) ... ok
[INFO] [stdout] test src/lib.rs - GlobalTimer::done (line 74) ... ok
[INFO] [stdout] test src/lib.rs - GlobalTimer (line 30) ... ok
[INFO] [stdout] test src/lib.rs - state::Header (line 148) ... ok
[INFO] [stdout] test src/style.rs - style::important (line 39) ... ok
[INFO] [stdout] test src/lib.rs - state::SubBullet (line 212) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.75s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ac59459f1b157f40be111fc9b31ae82a784a5413c0b9c1aa39fc1cadd761be38", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac59459f1b157f40be111fc9b31ae82a784a5413c0b9c1aa39fc1cadd761be38", kill_on_drop: false }`
[INFO] [stdout] ac59459f1b157f40be111fc9b31ae82a784a5413c0b9c1aa39fc1cadd761be38