[INFO] fetching crate zebra-test 4.0.0...
[INFO] testing zebra-test-4.0.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate zebra-test 4.0.0 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate zebra-test 4.0.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate zebra-test 4.0.0
[INFO] tweaked toml for crates.io crate zebra-test 4.0.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate zebra-test 4.0.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 zebra-test 4.0.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]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded spandoc v0.2.2
[INFO] [stderr]   Downloaded spandoc-attribute v0.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71
[INFO] running `Command { std: "docker" "start" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", 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" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71" "/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" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", 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" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling pin-project-internal v1.1.11
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling eyre v0.6.12
[INFO] [stderr]    Compiling object v0.37.3
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling indenter v0.3.4
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling owo-colors v4.3.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling hashbrown v0.17.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling pin-project v1.1.11
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling tracing-futures v0.2.5
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling insta v1.47.2
[INFO] [stderr]    Compiling tracing-error v0.2.1
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling backtrace v0.3.76
[INFO] [stderr]    Compiling color-eyre v0.6.5
[INFO] [stderr]    Compiling spandoc-attribute v0.1.1
[INFO] [stderr]    Compiling spandoc v0.2.2
[INFO] [stderr]    Compiling zebra-test v4.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 51.69s
[INFO] running `Command { std: "docker" "inspect" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", 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" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling zebra-test v4.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 8.59s
[INFO] running `Command { std: "docker" "inspect" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", 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" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/zebra_test-bd9f81aedbd8a72e)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test vectors::block::test::block_test_vectors_count ... ok
[INFO] [stdout] test vectors::block::test::block_test_vectors_unique ... ok
[INFO] [stdout] test command::arguments::tests::argument_order_is_preserved ... ok
[INFO] [stdout] test command::arguments::tests::arguments_can_be_merged ... ok
[INFO] [stdout] test command::arguments::tests::arguments_can_be_overridden ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/command.rs (/opt/rustwide/target/release/deps/command-f071ed128e3591d7)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test failure_regex_matches_stderr_failure_message ... ok
[INFO] [stdout] test ignore_regex_ignores_stdout_failure_message ... ok
[INFO] [stdout] test finish_before_timeout_output_single_line ... ok
[INFO] [stdout] test finish_before_timeout_short_output_no_newlines ... ok
[INFO] [stdout] test kill_on_timeout_output_continuous_lines ... ok
[INFO] [stdout] test failure_regex_timeout_continuous_output ... ok
[INFO] [stdout] test failure_regex_reads_multi_line_output_on_expect_line ... FAILED
[INFO] [stdout] test ignore_regex_iter_ignores_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_iter_matches_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_kill - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_wait_for_output - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_kill_on_error - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_drop - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_no_kill_on_error - should panic ... ok
[INFO] [stderr] error: test failed, to rerun pass `--test command`
[INFO] [stdout] test failure_regex_reads_multi_line_output_on_drop - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- failure_regex_reads_multi_line_output_on_expect_line stdout ----
[INFO] [stdout] [31m
[INFO] [stdout] The application panicked (crashed).[39m
[INFO] [stdout] Message:  [36merror did not contain expected failure message: 
[INFO] [stdout]    0: [91mtest command:
[INFO] [stdout]       cd "/tmp/.tmpgRTq6y" && "echo" "failure_message\nmulti-line failure message"
[INFO] [stdout] 
[INFO] [stdout]       Logged a failure message:
[INFO] [stdout]       failure_message
[INFO] [stdout] 
[INFO] [stdout]       Matching failure regex: [
[INFO] [stdout]           "failure_message",
[INFO] [stdout]       ]
[INFO] [stdout] 
[INFO] [stdout]       All Failure regexes: [
[INFO] [stdout]           "failure_message",
[INFO] [stdout]       ]
[INFO] [stdout]       [0m
[INFO] [stdout] 
[INFO] [stdout] Command:
[INFO] [stdout]    cd "/tmp/.tmpgRTq6y" && "echo" "failure_message\nmulti-line failure message"
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]    1: [91m<color_eyre[dbda21fabebeebc3]::config::EyreHook>::into_eyre_hook::{closure#0}[0m[90m<unknown>[0m
[INFO] [stdout]       at [35m<unknown source file>[0m:[35m<unknown line>[0m
[INFO] [stdout]   [96m                              ⋮ 11 frames hidden ⋮                              [0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.[39m
[INFO] [stdout] Location: [35mtests/command.rs[39m:[35m312[39m
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]    1: [91m<color_eyre[dbda21fabebeebc3]::config::PanicHook>::into_panic_hook::{closure#0}[0m[90m<unknown>[0m
[INFO] [stdout]       at [35m<unknown source file>[0m:[35m<unknown line>[0m
[INFO] [stdout]   [96m                              ⋮ 12 frames hidden ⋮                              [0m
[INFO] [stdout] 
[INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering.
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     failure_regex_reads_multi_line_output_on_expect_line
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 15 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.14s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71", kill_on_drop: false }`
[INFO] [stdout] 7f26b0bd3614452996013335b09a9c812885619728111d57cff266b5ef8caa71
