[INFO] fetching crate zebra-test 4.0.0...
[INFO] testing zebra-test-4.0.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate zebra-test 4.0.0 into /workspace/builds/worker-2-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate zebra-test 4.0.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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] d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb
[INFO] running `Command { std: "docker" "start" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", 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" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", 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" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling syn v1.0.109
[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 pin-project-internal v1.1.11
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling indenter v0.3.4
[INFO] [stderr]    Compiling owo-colors v4.3.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand_xorshift v0.4.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 hashbrown v0.17.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling pin-project v1.1.11
[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 spandoc-attribute v0.1.1
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling backtrace v0.3.76
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling color-eyre v0.6.5
[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 59.11s
[INFO] running `Command { std: "docker" "inspect" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", 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" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "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.42s
[INFO] running `Command { std: "docker" "inspect" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", 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" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/zebra-test/bd9f81aedbd8a72e/out/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_overridden ... ok
[INFO] [stderr]      Running tests/command.rs (/opt/rustwide/target/release/build/zebra-test/f071ed128e3591d7/out/command-f071ed128e3591d7)
[INFO] [stdout] test command::arguments::tests::arguments_can_be_merged ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test ignore_regex_ignores_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_iter_matches_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message ... ok
[INFO] [stdout] test failure_regex_reads_multi_line_output_on_expect_line ... FAILED
[INFO] [stdout] test failure_regex_matches_stderr_failure_message ... ok
[INFO] [stdout] test kill_on_timeout_output_continuous_lines ... ok
[INFO] [stdout] test finish_before_timeout_short_output_no_newlines ... ok
[INFO] [stdout] test failure_regex_timeout_continuous_output ... ok
[INFO] [stdout] test ignore_regex_iter_ignores_stdout_failure_message ... ok
[INFO] [stdout] test finish_before_timeout_output_single_line ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_drop - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_kill_on_error - should panic ... ok
[INFO] [stdout] test failure_regex_reads_multi_line_output_on_drop - should panic ... ok
[INFO] [stdout] test failure_regex_matches_stdout_failure_message_no_kill_on_error - 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 - 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/.tmp88jvm7" && "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/.tmp88jvm7" && "echo" "failure_message\nmulti-line failure message"
[INFO] [stdout] 
[INFO] [stdout]   ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
[INFO] [stdout]    1: [91m<color_eyre[9c2e0d758687cd91]::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[9c2e0d758687cd91]::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.09s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test command`
[INFO] running `Command { std: "docker" "inspect" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb", kill_on_drop: false }`
[INFO] [stdout] d6c260412b0e783ef60c61bc83bedde28de872ab5bb2326823d09c5472bb52cb
