[INFO] fetching crate mockito 0.30.0... [INFO] testing mockito-0.30.0 against 1.55.0 for beta-1.56-1 [INFO] extracting crate mockito 0.30.0 into /workspace/builds/worker-20/source [INFO] validating manifest of crates.io crate mockito 0.30.0 on toolchain 1.55.0 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate mockito 0.30.0 [INFO] finished tweaking crates.io crate mockito 0.30.0 [INFO] tweaked toml for crates.io crate mockito 0.30.0 written to /workspace/builds/worker-20/source/Cargo.toml [INFO] crate crates.io crate mockito 0.30.0 already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded testing_logger v0.1.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] aa7739bf9da12031cb081fee33a2c98c0d10f7cfcb76b4985160aa967a6b3566 [INFO] running `Command { std: "docker" "start" "-a" "aa7739bf9da12031cb081fee33a2c98c0d10f7cfcb76b4985160aa967a6b3566", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "aa7739bf9da12031cb081fee33a2c98c0d10f7cfcb76b4985160aa967a6b3566", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "aa7739bf9da12031cb081fee33a2c98c0d10f7cfcb76b4985160aa967a6b3566", kill_on_drop: false }` [INFO] [stdout] aa7739bf9da12031cb081fee33a2c98c0d10f7cfcb76b4985160aa967a6b3566 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/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" "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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 39dd5478ef25e6e1d75a5afcb16d6cb35016f81bc2be56270ca06b458408d18d [INFO] running `Command { std: "docker" "start" "-a" "39dd5478ef25e6e1d75a5afcb16d6cb35016f81bc2be56270ca06b458408d18d", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling libc v0.2.66 [INFO] [stderr] Compiling getrandom v0.2.2 [INFO] [stderr] Compiling ryu v1.0.2 [INFO] [stderr] Compiling serde v1.0.104 [INFO] [stderr] Compiling memchr v2.3.0 [INFO] [stderr] Compiling itoa v0.4.4 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling httparse v1.3.4 [INFO] [stderr] Compiling log v0.4.8 [INFO] [stderr] Compiling matches v0.1.8 [INFO] [stderr] Compiling regex-syntax v0.6.13 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling form_urlencoded v1.0.0 [INFO] [stderr] Compiling aho-corasick v0.7.6 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling colored v2.0.0 [INFO] [stderr] Compiling rand_core v0.6.1 [INFO] [stderr] Compiling rand_chacha v0.3.0 [INFO] [stderr] Compiling rand v0.8.2 [INFO] [stderr] Compiling regex v1.3.3 [INFO] [stderr] Compiling serde_json v1.0.44 [INFO] [stderr] Compiling serde_urlencoded v0.7.0 [INFO] [stderr] Compiling assert-json-diff v2.0.1 [INFO] [stderr] Compiling mockito v0.30.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 12.48s [INFO] running `Command { std: "docker" "inspect" "39dd5478ef25e6e1d75a5afcb16d6cb35016f81bc2be56270ca06b458408d18d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "39dd5478ef25e6e1d75a5afcb16d6cb35016f81bc2be56270ca06b458408d18d", kill_on_drop: false }` [INFO] [stdout] 39dd5478ef25e6e1d75a5afcb16d6cb35016f81bc2be56270ca06b458408d18d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/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" "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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ef753301ef7d42153bc5f2a0b1b2a3ff1edcac6dfd32e26d415928a3380dd9e1 [INFO] running `Command { std: "docker" "start" "-a" "ef753301ef7d42153bc5f2a0b1b2a3ff1edcac6dfd32e26d415928a3380dd9e1", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling humantime v2.1.0 [INFO] [stderr] Compiling termcolor v1.1.0 [INFO] [stderr] Compiling testing_logger v0.1.1 [INFO] [stderr] Compiling env_logger v0.8.2 [INFO] [stderr] Compiling mockito v0.30.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 10.67s [INFO] running `Command { std: "docker" "inspect" "ef753301ef7d42153bc5f2a0b1b2a3ff1edcac6dfd32e26d415928a3380dd9e1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ef753301ef7d42153bc5f2a0b1b2a3ff1edcac6dfd32e26d415928a3380dd9e1", kill_on_drop: false }` [INFO] [stdout] ef753301ef7d42153bc5f2a0b1b2a3ff1edcac6dfd32e26d415928a3380dd9e1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-20/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" "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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 843dd30ddc99f718b0266c41f4d010c687ed7f64e7d34d606407038e7b3ebedd [INFO] running `Command { std: "docker" "start" "-a" "843dd30ddc99f718b0266c41f4d010c687ed7f64e7d34d606407038e7b3ebedd", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.05s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/mockito-165982daed4533d4) [INFO] [stdout] [INFO] [stderr] Running tests/lib.rs (/opt/rustwide/target/debug/deps/lib-2aeb404b32d3560d) [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 102 tests [INFO] [stdout] test test_all_of_match_body ... ok [INFO] [stdout] test test_all_of_match_header ... ok [INFO] [stdout] test test_display_mock_matching_any_path ... ok [INFO] [stdout] test test_display_mock_matching_exact_query ... ok [INFO] [stdout] test test_all_of_missing_match_header ... ok [INFO] [stdout] test test_display_mock_matching_exact_path ... ok [INFO] [stdout] test test_display_mock_matching_any_query ... ok [INFO] [stdout] test test_display_mock_matching_regex_path ... ok [INFO] [stdout] test test_display_mock_matching_regex_query ... ok [INFO] [stdout] test test_any_of_match_body ... ok [INFO] [stdout] test test_any_of_match_header ... ok [INFO] [stdout] test test_going_out_of_context_removes_mock ... ok [INFO] [stdout] test test_assert_defaults_to_one_hit ... ok [INFO] [stdout] test test_anyof_exact_path_and_query_matcher ... ok [INFO] [stdout] test test_mock_from_inside_thread_does_not_lock_forever ... ignored [INFO] [stdout] test test_missing_create_bad ... ok [INFO] [stdout] test test_assert_panics_expect_at_least_and_at_most_with_too_few_requests - should panic ... ok [INFO] [stdout] test test_assert_panics_expect_at_most_with_too_many_requests - should panic ... ok [INFO] [stdout] test test_assert_with_last_unmatched_request - should panic ... FAILED [INFO] [stdout] test test_assert_panics_expect_at_least_and_at_most_with_too_many_requests - should panic ... ok [INFO] [stdout] test test_assert_panics_expect_at_least_with_too_few_requests - should panic ... ok [INFO] [stdout] test test_assert_panics_if_no_request_was_performed - should panic ... ok [INFO] [stdout] test test_assert_panics_with_too_few_requests - should panic ... ok [INFO] [stdout] test test_assert_panics_with_too_many_requests - should panic ... ok [INFO] [stdout] test test_assert_with_last_unmatched_request_and_body - should panic ... ok [INFO] [stdout] test test_body_from_file ... ok [INFO] [stdout] test test_default_headers ... ok [INFO] [stdout] test test_display_mock_matching_exact_header ... ok [INFO] [stdout] test test_assert_with_last_unmatched_request_and_headers - should panic ... FAILED [INFO] [stdout] test test_display_mock_matching_any_body ... ok [INFO] [stdout] test test_display_mock_matching_any_of_headers ... ok [INFO] [stdout] test test_display_mock_matching_multiple_headers ... ok [INFO] [stdout] test test_display_mock_matching_all_of_queries ... ok [INFO] [stdout] test test_display_mock_matching_headers_and_body ... ok [INFO] [stdout] test test_display_mock_matching_exact_body ... ok [INFO] [stdout] test test_create_starts_the_server ... ok [INFO] [stdout] test test_display_mock_matching_regex_body ... ok [INFO] [stdout] test test_does_not_match_binary_body ... ok [INFO] [stdout] test test_expect ... ok [INFO] [stdout] test test_expect_at_most_with_few_requests ... ok [INFO] [stdout] test test_expect_at_least ... ok [INFO] [stdout] test test_explicitly_calling_drop_removes_the_mock ... ok [INFO] [stdout] test test_expect_at_least_and_at_most ... ok [INFO] [stdout] test test_expect_at_most_with_needed_requests ... ok [INFO] [stdout] test test_going_out_of_context_doesnt_remove_other_mocks ... ok [INFO] [stdout] test test_match_any_body_by_default ... ok [INFO] [stdout] test test_any_of_missing_match_header ... ok [INFO] [stdout] test test_expect_at_least_more ... ok [INFO] [stdout] test test_large_utf8_body ... ok [INFO] [stdout] test test_head_request_with_overridden_content_length ... ok [INFO] [stdout] test test_match_body_not_matching ... ok [INFO] [stdout] test test_large_body_without_content_length ... ok [INFO] [stdout] test test_match_body_with_json ... ok [INFO] [stdout] test test_match_body_with_json_string ... ok [INFO] [stdout] test test_match_body_with_json_order ... ok [INFO] [stdout] test test_match_binary_body ... ok [INFO] [stdout] test test_match_body_with_partial_json ... ok [INFO] [stdout] test test_match_body_with_json_string_order ... ok [INFO] [stdout] test test_match_body_with_more_headers_with_json ... ok [INFO] [stdout] test test_match_body ... ok [INFO] [stdout] test test_match_body_with_partial_json_string ... ok [INFO] [stdout] test test_match_body_with_partial_json_and_extra_fields ... ok [INFO] [stdout] test test_match_body_with_partial_json_string_and_extra_fields ... ok [INFO] [stdout] test test_match_body_with_regex ... ok [INFO] [stdout] test test_match_exact_query ... ok [INFO] [stdout] test test_match_exact_query_via_path ... ok [INFO] [stdout] test test_match_body_with_regex_not_matching ... ok [INFO] [stdout] test test_match_header_any_not_matching ... ok [INFO] [stdout] test test_match_header ... ok [INFO] [stdout] test test_match_header_missing_not_matching_even_when_empty ... ok [INFO] [stdout] test test_match_header_missing_matching ... ok [INFO] [stdout] test test_match_header_any_matching ... ok [INFO] [stdout] test test_match_header_is_case_insensitive_on_the_field_name ... ok [INFO] [stdout] test test_match_header_missing_not_matching ... ok [INFO] [stdout] test test_match_missing_query ... ok [INFO] [stdout] test test_match_multiple_header_conditions_not_matching ... ok [INFO] [stdout] test test_match_multiple_header_conditions_matching ... ok [INFO] [stdout] test test_match_multiple_headers ... ok [INFO] [stdout] test test_match_partial_query_by_regex ... ok [INFO] [stdout] test test_match_query_with_non_percent_url_escaping ... ok [INFO] [stdout] test test_match_partial_query_by_regex_all_of ... ok [INFO] [stdout] test test_match_partial_query_by_urlencoded_all_of ... ok [INFO] [stdout] test test_match_partial_query_by_urlencoded ... ok [INFO] [stdout] test test_missing_create_good ... ok [INFO] [stdout] test test_mock_preserves_header_order ... ok [INFO] [stdout] test test_matched_bool ... ok [INFO] [stdout] test test_mock_with_body ... ok [INFO] [stdout] test test_no_match_returns_501 ... ok [INFO] [stdout] test test_propagate_protocol_to_response ... ok [INFO] [stdout] test test_mock_with_multiple_headers ... ok [INFO] [stdout] test test_mock_with_custom_status ... ok [INFO] [stdout] test test_mock_with_header ... ok [INFO] [stdout] test test_mock_with_fn_body ... ok [INFO] [stdout] test test_regex_match_path ... ok [INFO] [stdout] test test_mock_with_status ... ok [INFO] [stdout] test test_regex_match_header ... ok [INFO] [stdout] test test_two_route_mocks ... ok [INFO] [stdout] test test_transfer_encoding_chunked ... ok [INFO] [stdout] test test_request_from_thread ... ok [INFO] [stdout] test test_simple_route_mock ... ok [INFO] [stdout] test test_same_endpoint_different_responses_last_one_forever ... ok [INFO] [stdout] test test_same_endpoint_different_responses ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_assert_with_last_unmatched_request stdout ---- [INFO] [stdout] thread 'test_assert_with_last_unmatched_request' panicked at ' [INFO] [stdout] > Expected 1 request(s) to: [INFO] [stdout] [INFO] [stdout] GET /hello [INFO] [stdout] [INFO] [stdout] ...but received 0 [INFO] [stdout] [INFO] [stdout] > The last unmatched request was: [INFO] [stdout] [INFO] [stdout] GET /bye [INFO] [stdout] [INFO] [stdout] > Difference: [INFO] [stdout] [INFO] [stdout] GET /hello [INFO] [stdout] GET /bye [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] ', src/lib.rs:1276:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5576b76e4c40 - std::backtrace_rs::backtrace::libunwind::trace::ha0ad43e8a952bfe7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x5576b76e4c40 - std::backtrace_rs::backtrace::trace_unsynchronized::h6830419c0c4130dc [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5576b76e4c40 - std::sys_common::backtrace::_print_fmt::h8f3516631ffa1ef5 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x5576b76e4c40 - ::fmt::he1640d5f0d93f618 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x5576b770879c - core::fmt::write::h88012e1f01caeebf [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/fmt/mod.rs:1115:17 [INFO] [stdout] 5: 0x5576b76de7c5 - std::io::Write::write_fmt::h7728c39ea5632753 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/io/mod.rs:1665:15 [INFO] [stdout] 6: 0x5576b76e6dab - std::sys_common::backtrace::_print::ha1f00492f406a015 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x5576b76e6dab - std::sys_common::backtrace::print::hd54561b13feb6af3 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x5576b76e6dab - std::panicking::default_hook::{{closure}}::h84fe124cd0864662 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x5576b76e68bc - std::panicking::default_hook::h5a8e74a76ce290a7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x5576b76e7474 - std::panicking::rust_panic_with_hook::h67c812a4fe9d4c91 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:622:17 [INFO] [stdout] 11: 0x5576b76e6f57 - std::panicking::begin_panic_handler::{{closure}}::h33f9c1b96af300d7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:519:13 [INFO] [stdout] 12: 0x5576b76e513c - std::sys_common::backtrace::__rust_end_short_backtrace::h51bae64be5921f0e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x5576b76e6eb9 - rust_begin_unwind [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:515:5 [INFO] [stdout] 14: 0x5576b73b7d6b - std::panicking::begin_panic_fmt::h7ab3d84aa4ae32a2 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:457:5 [INFO] [stdout] 15: 0x5576b74731db - mockito::Mock::assert::h46a69f7aa2500801 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:1276:13 [INFO] [stdout] 16: 0x5576b73cc881 - lib::test_assert_with_last_unmatched_request::hbf984006a83a2c7c [INFO] [stdout] at /opt/rustwide/workdir/tests/lib.rs:1138:5 [INFO] [stdout] 17: 0x5576b73cc7ca - lib::test_assert_with_last_unmatched_request::{{closure}}::he5d263b45817497b [INFO] [stdout] at /opt/rustwide/workdir/tests/lib.rs:1133:1 [INFO] [stdout] 18: 0x5576b73e410e - core::ops::function::FnOnce::call_once::h295f0ece1c2478d9 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x5576b74225f3 - core::ops::function::FnOnce::call_once::h5aba9a5d361d6c79 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 20: 0x5576b74225f3 - test::__rust_begin_short_backtrace::h705bb53b2b1d45e6 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:578:5 [INFO] [stdout] 21: 0x5576b74210a8 - as core::ops::function::FnOnce>::call_once::ha20f614d7c0b2b6d [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 22: 0x5576b74210a8 - as core::ops::function::FnOnce<()>>::call_once::hf02f19eeb64ceabd [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:347:9 [INFO] [stdout] 23: 0x5576b74210a8 - std::panicking::try::do_call::h4bfab65eb3b4b036 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:401:40 [INFO] [stdout] 24: 0x5576b74210a8 - std::panicking::try::h6613a46a6d2b6f88 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:365:19 [INFO] [stdout] 25: 0x5576b74210a8 - std::panic::catch_unwind::h9f07b17b60991273 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:434:14 [INFO] [stdout] 26: 0x5576b74210a8 - test::run_test_in_process::hc38797eecf07854f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:601:18 [INFO] [stdout] 27: 0x5576b74210a8 - test::run_test::run_test_inner::{{closure}}::hac209db8bce0fd77 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:493:39 [INFO] [stdout] 28: 0x5576b73ef83d - test::run_test::run_test_inner::{{closure}}::h4aefdd4b4a423e20 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:520:37 [INFO] [stdout] 29: 0x5576b73ef83d - std::sys_common::backtrace::__rust_begin_short_backtrace::h652c9e2663704700 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 30: 0x5576b73f4018 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hd84fd5e0b5b1f45f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/thread/mod.rs:476:17 [INFO] [stdout] 31: 0x5576b73f4018 - as core::ops::function::FnOnce<()>>::call_once::he23556962a2ae14b [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:347:9 [INFO] [stdout] 32: 0x5576b73f4018 - std::panicking::try::do_call::h67d4f93e1fbaa5f9 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:401:40 [INFO] [stdout] 33: 0x5576b73f4018 - std::panicking::try::h396886e678db815e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:365:19 [INFO] [stdout] 34: 0x5576b73f4018 - std::panic::catch_unwind::hc1d64dcecf0a00e0 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:434:14 [INFO] [stdout] 35: 0x5576b73f4018 - std::thread::Builder::spawn_unchecked::{{closure}}::hc2644cf33cb61d8e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/thread/mod.rs:475:30 [INFO] [stdout] 36: 0x5576b73f4018 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h4473c2c77b66963f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 37: 0x5576b76ec817 - as core::ops::function::FnOnce>::call_once::h6bff7798948b1075 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 38: 0x5576b76ec817 - as core::ops::function::FnOnce>::call_once::hc2d25ac38f6b2342 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 39: 0x5576b76ec817 - std::sys::unix::thread::Thread::new::thread_start::hbba5bc368baac205 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys/unix/thread.rs:74:17 [INFO] [stdout] 40: 0x7f350e27f609 - start_thread [INFO] [stdout] 41: 0x7f350e50a293 - clone [INFO] [stdout] 42: 0x0 - [INFO] [stdout] note: panic did not contain expected string [INFO] [stdout] panic message: `"\n> Expected 1 request(s) to:\n\r\nGET /hello\r\n\n...but received 0\n\n> The last unmatched request was:\n\r\nGET /bye\r\n\n> Difference:\n\nGET /hello\nGET /bye\n\n\n"`, [INFO] [stdout] expected substring: `"\n> Expected 1 request(s) to:\n\r\nGET /hello\r\n\n...but received 0\n\n> The last unmatched request was:\n\r\nGET /bye\r\n\n> Difference:\n\n\u{1b}[31mGET /hello\u{1b}[0m\n\u{1b}[32mGET\u{1b}[0m \u{1b}[42;37m/bye\u{1b}[0m\n\n\n"` [INFO] [stdout] ---- test_assert_with_last_unmatched_request_and_headers stdout ---- [INFO] [stdout] thread 'test_assert_with_last_unmatched_request_and_headers' panicked at ' [INFO] [stdout] > Expected 1 request(s) to: [INFO] [stdout] [INFO] [stdout] GET /hello [INFO] [stdout] [INFO] [stdout] ...but received 0 [INFO] [stdout] [INFO] [stdout] > The last unmatched request was: [INFO] [stdout] [INFO] [stdout] GET /bye [INFO] [stdout] authorization: 1234 [INFO] [stdout] accept: text [INFO] [stdout] [INFO] [stdout] > Difference: [INFO] [stdout] [INFO] [stdout] GET /hello [INFO] [stdout] GET /bye [INFO] [stdout] authorization: 1234 [INFO] [stdout] accept: text [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] ', src/lib.rs:1276:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5576b76e4c40 - std::backtrace_rs::backtrace::libunwind::trace::ha0ad43e8a952bfe7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x5576b76e4c40 - std::backtrace_rs::backtrace::trace_unsynchronized::h6830419c0c4130dc [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5576b76e4c40 - std::sys_common::backtrace::_print_fmt::h8f3516631ffa1ef5 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x5576b76e4c40 - ::fmt::he1640d5f0d93f618 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x5576b770879c - core::fmt::write::h88012e1f01caeebf [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/fmt/mod.rs:1115:17 [INFO] [stdout] 5: 0x5576b76de7c5 - std::io::Write::write_fmt::h7728c39ea5632753 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/io/mod.rs:1665:15 [INFO] [stdout] 6: 0x5576b76e6dab - std::sys_common::backtrace::_print::ha1f00492f406a015 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x5576b76e6dab - std::sys_common::backtrace::print::hd54561b13feb6af3 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x5576b76e6dab - std::panicking::default_hook::{{closure}}::h84fe124cd0864662 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x5576b76e68bc - std::panicking::default_hook::h5a8e74a76ce290a7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x5576b76e7474 - std::panicking::rust_panic_with_hook::h67c812a4fe9d4c91 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:622:17 [INFO] [stdout] 11: 0x5576b76e6f57 - std::panicking::begin_panic_handler::{{closure}}::h33f9c1b96af300d7 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:519:13 [INFO] [stdout] 12: 0x5576b76e513c - std::sys_common::backtrace::__rust_end_short_backtrace::h51bae64be5921f0e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x5576b76e6eb9 - rust_begin_unwind [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:515:5 [INFO] [stdout] 14: 0x5576b73b7d6b - std::panicking::begin_panic_fmt::h7ab3d84aa4ae32a2 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:457:5 [INFO] [stdout] 15: 0x5576b74731db - mockito::Mock::assert::h46a69f7aa2500801 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:1276:13 [INFO] [stdout] 16: 0x5576b73cc972 - lib::test_assert_with_last_unmatched_request_and_headers::h9eb06e7885fee525 [INFO] [stdout] at /opt/rustwide/workdir/tests/lib.rs:1165:5 [INFO] [stdout] 17: 0x5576b73cc8ba - lib::test_assert_with_last_unmatched_request_and_headers::{{closure}}::h6b21ba211a94fa80 [INFO] [stdout] at /opt/rustwide/workdir/tests/lib.rs:1160:1 [INFO] [stdout] 18: 0x5576b73e482e - core::ops::function::FnOnce::call_once::h67755046d61af0ce [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x5576b74225f3 - core::ops::function::FnOnce::call_once::h5aba9a5d361d6c79 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 20: 0x5576b74225f3 - test::__rust_begin_short_backtrace::h705bb53b2b1d45e6 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:578:5 [INFO] [stdout] 21: 0x5576b74210a8 - as core::ops::function::FnOnce>::call_once::ha20f614d7c0b2b6d [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 22: 0x5576b74210a8 - as core::ops::function::FnOnce<()>>::call_once::hf02f19eeb64ceabd [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:347:9 [INFO] [stdout] 23: 0x5576b74210a8 - std::panicking::try::do_call::h4bfab65eb3b4b036 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:401:40 [INFO] [stdout] 24: 0x5576b74210a8 - std::panicking::try::h6613a46a6d2b6f88 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:365:19 [INFO] [stdout] 25: 0x5576b74210a8 - std::panic::catch_unwind::h9f07b17b60991273 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:434:14 [INFO] [stdout] 26: 0x5576b74210a8 - test::run_test_in_process::hc38797eecf07854f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:601:18 [INFO] [stdout] 27: 0x5576b74210a8 - test::run_test::run_test_inner::{{closure}}::hac209db8bce0fd77 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:493:39 [INFO] [stdout] 28: 0x5576b73ef83d - test::run_test::run_test_inner::{{closure}}::h4aefdd4b4a423e20 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/test/src/lib.rs:520:37 [INFO] [stdout] 29: 0x5576b73ef83d - std::sys_common::backtrace::__rust_begin_short_backtrace::h652c9e2663704700 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 30: 0x5576b73f4018 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hd84fd5e0b5b1f45f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/thread/mod.rs:476:17 [INFO] [stdout] 31: 0x5576b73f4018 - as core::ops::function::FnOnce<()>>::call_once::he23556962a2ae14b [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:347:9 [INFO] [stdout] 32: 0x5576b73f4018 - std::panicking::try::do_call::h67d4f93e1fbaa5f9 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:401:40 [INFO] [stdout] 33: 0x5576b73f4018 - std::panicking::try::h396886e678db815e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panicking.rs:365:19 [INFO] [stdout] 34: 0x5576b73f4018 - std::panic::catch_unwind::hc1d64dcecf0a00e0 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/panic.rs:434:14 [INFO] [stdout] 35: 0x5576b73f4018 - std::thread::Builder::spawn_unchecked::{{closure}}::hc2644cf33cb61d8e [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/thread/mod.rs:475:30 [INFO] [stdout] 36: 0x5576b73f4018 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h4473c2c77b66963f [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 37: 0x5576b76ec817 - as core::ops::function::FnOnce>::call_once::h6bff7798948b1075 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 38: 0x5576b76ec817 - as core::ops::function::FnOnce>::call_once::hc2d25ac38f6b2342 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/alloc/src/boxed.rs:1572:9 [INFO] [stdout] 39: 0x5576b76ec817 - std::sys::unix::thread::Thread::new::thread_start::hbba5bc368baac205 [INFO] [stdout] at /rustc/c8dfcfe046a7680554bf4eb612bad840e7631c4b/library/std/src/sys/unix/thread.rs:74:17 [INFO] [stdout] 40: 0x7f350e27f609 - start_thread [INFO] [stdout] 41: 0x7f350e50a293 - clone [INFO] [stdout] 42: 0x0 - [INFO] [stdout] note: panic did not contain expected string [INFO] [stdout] panic message: `"\n> Expected 1 request(s) to:\n\r\nGET /hello\r\n\n...but received 0\n\n> The last unmatched request was:\n\r\nGET /bye\r\nauthorization: 1234\r\naccept: text\r\n\n> Difference:\n\nGET /hello\nGET /bye\nauthorization: 1234\naccept: text\n\n\n"`, [INFO] [stdout] expected substring: `"\n> Expected 1 request(s) to:\n\r\nGET /hello\r\n\n...but received 0\n\n> The last unmatched request was:\n\r\nGET /bye\r\nauthorization: 1234\r\naccept: text\r\n\n> Difference:\n\n\u{1b}[31mGET /hello\u{1b}[0m\n\u{1b}[32mGET\u{1b}[0m \u{1b}[42;37m/bye\nauthorization: 1234\naccept: text\u{1b}[0m\n\n\n"` [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_assert_with_last_unmatched_request [INFO] [stdout] test_assert_with_last_unmatched_request_and_headers [INFO] [stdout] [INFO] [stdout] test result: FAILED. 99 passed; 2 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.29s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass '--test lib' [INFO] running `Command { std: "docker" "inspect" "843dd30ddc99f718b0266c41f4d010c687ed7f64e7d34d606407038e7b3ebedd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "843dd30ddc99f718b0266c41f4d010c687ed7f64e7d34d606407038e7b3ebedd", kill_on_drop: false }` [INFO] [stdout] 843dd30ddc99f718b0266c41f4d010c687ed7f64e7d34d606407038e7b3ebedd