[INFO] fetching crate yk-lua 0.1.0...
[INFO] testing yk-lua-0.1.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate yk-lua 0.1.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate yk-lua 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate yk-lua 0.1.0
[INFO] tweaked toml for crates.io crate yk-lua 0.1.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate yk-lua 0.1.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 yk-lua 0.1.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 yk-core v0.1.0
[INFO] [stderr]   Downloaded mlua-sys v0.12.0
[INFO] [stderr]   Downloaded which v8.0.6
[INFO] [stderr]   Downloaded mlua v0.12.1
[INFO] [stderr]   Downloaded luau0-src v0.21.0+luau736
[INFO] [stderr]   Downloaded lua-src v551.0.2
[INFO] [stderr]   Downloaded luajit-src v210.7.4+c6ffc14
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d
[INFO] running `Command { std: "docker" "start" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", 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" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d" "/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" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", 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" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling erased-serde v0.4.10
[INFO] [stderr]    Compiling which v8.0.6
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling cc v1.5.1
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.21
[INFO] [stderr]    Compiling bstr v1.13.1
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling either v1.18.0
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling luajit-src v210.7.4+c6ffc14
[INFO] [stderr]    Compiling lua-src v551.0.2
[INFO] [stderr]    Compiling luau0-src v0.21.0+luau736
[INFO] [stderr]    Compiling thiserror v2.0.21
[INFO] [stderr]    Compiling mlua-sys v0.12.0
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling yk-core v0.1.0
[INFO] [stderr]    Compiling mlua v0.12.1
[INFO] [stderr]    Compiling yk-lua v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 18s
[INFO] running `Command { std: "docker" "inspect" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", 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" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d" "/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 tempfile v3.27.0
[INFO] [stderr]    Compiling yk-lua v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 4.30s
[INFO] running `Command { std: "docker" "inspect" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", 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" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d" "/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.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/yk_lua-4056ea002ca2f99d)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test host::tests::the_user_directory_is_always_active ... ok
[INFO] [stdout] test runner::tests::truncate_cuts_on_a_utf8_boundary ... ok
[INFO] [stdout] test host::tests::a_denying_host_refuses_rather_than_pretending ... ok
[INFO] [stdout] test host::tests::a_denying_host_reports_no_environment_rather_than_an_empty_one ... ok
[INFO] [stdout] test host::tests::a_repository_is_not_loaded_without_trust ... ok
[INFO] [stdout] test host::tests::a_trusted_repository_still_reports_nothing_skipped ... ok
[INFO] [stdout] test tests::a_fresh_registry_has_no_tools ... ok
[INFO] [stdout] test host::tests::a_host_can_be_implemented_outside_this_crate ... ok
[INFO] [stdout] test host::tests::the_user_directory_loads_before_the_project_one ... ok
[INFO] [stdout] test tests::a_missing_directory_loads_nothing_rather_than_failing ... ok
[INFO] [stdout] test tests::a_syntax_error_names_the_script ... ok
[INFO] [stdout] test tests::a_denying_host_reaches_a_post_as_an_error ... ok
[INFO] [stdout] test tests::a_non_coercible_argument_to_spawn_tool_is_an_error ... ok
[INFO] [stdout] test host::tests::model_written_extensions_live_under_dot_yk_extensions ... ok
[INFO] [stdout] test tests::a_script_under_the_instruction_limit_runs_to_completion ... ok
[INFO] [stdout] test host::tests::a_trusted_repository_is_loaded ... ok
[INFO] [stdout] test tests::a_denying_host_reaches_the_script_as_an_error ... ok
[INFO] [stdout] test tests::an_unset_environment_variable_is_nil_rather_than_an_error ... ok
[INFO] [stdout] test runner::tests::a_signal_killed_child_reports_minus_one ... ok
[INFO] [stdout] test tests::bytecode_is_refused ... ok
[INFO] [stdout] test tests::debug_is_not_reachable_from_a_script ... ok
[INFO] [stdout] test tests::io_is_not_reachable_from_a_script ... ok
[INFO] [stdout] test tests::ffi_is_not_reachable_from_a_script ... ok
[INFO] [stdout] test runner::tests::spawn_tool_runs_a_real_process_and_returns_its_stdout ... ok
[INFO] [stdout] test runner::tests::a_non_zero_exit_is_a_value_carrying_the_code ... ok
[INFO] [stdout] test tool::tests::a_builtin_name_is_not_a_prefix_match ... ok
[INFO] [stdout] test tests::package_is_not_reachable_from_a_script ... ok
[INFO] [stdout] test tests::the_host_table_has_exactly_the_members_the_records_name ... ok
[INFO] [stdout] test tests::json_decodes_into_a_lua_table ... ok
[INFO] [stdout] test tests::the_runner_global_is_installed ... ok
[INFO] [stdout] test tests::rawset_cannot_reach_inside_a_sandboxed_library ... ok
[INFO] [stdout] test tests::json_round_trips_through_the_host ... ok
[INFO] [stdout] test tool::tests::a_hook_returning_a_non_string_is_refused_rather_than_permitted ... ok
[INFO] [stdout] test tool::tests::a_hook_returning_nil_lets_the_call_through ... ok
[INFO] [stdout] test tool::tests::a_hook_returning_a_reason_blocks_the_call ... ok
[INFO] [stdout] test tool::tests::a_hook_receives_the_name_and_the_arguments ... ok
[INFO] [stdout] test tool::tests::a_registered_tool_returns_its_string ... ok
[INFO] [stdout] test tool::tests::a_hook_that_raises_blocks_rather_than_failing_open ... ok
[INFO] [stdout] test tool::tests::a_syntax_error_keeps_its_message ... ok
[INFO] [stdout] test tests::a_script_spawns_a_process_through_the_runner ... ok
[INFO] [stdout] test tool::tests::the_guarded_tool_reports_the_same_definition_as_the_inner_one ... ok
[INFO] [stdout] test tests::os_is_not_reachable_from_a_script ... ok
[INFO] [stdout] test tool::tests::the_first_hook_with_an_opinion_wins ... ok
[INFO] [stdout] test tool::tests::a_hook_only_sees_the_tool_it_was_wired_to ... ok
[INFO] [stdout] test tool::tests::the_four_builtins_are_recognised ... ok
[INFO] [stdout] test tool::tests::a_runtime_error_keeps_its_message ... ok
[INFO] [stdout] test runner::tests::stderr_is_captured ... ok
[INFO] [stdout] test runner::tests::an_unknown_command_is_an_error ... ok
[INFO] [stdout] test tool::tests::a_tool_returning_nothing_is_empty_rather_than_an_error ... ok
[INFO] [stdout] test tool::tests::a_tool_that_raises_reaches_the_model_as_an_error ... ok
[INFO] [stdout] test runner::tests::output_over_the_cap_is_truncated_and_flagged ... ok
[INFO] [stdout] test tool::tests::a_runaway_loop_is_interrupted_rather_than_hanging_the_agent ... ok
[INFO] [stdout] test runner::tests::a_child_that_outruns_its_deadline_is_killed_and_reported ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extensions.rs (/opt/rustwide/target/release/deps/extensions-3fe468ac94dab752)
[INFO] [stdout] 
[INFO] [stdout] running 25 tests
[INFO] [stdout] test a_project_directory_is_skipped_unless_it_is_trusted ... ok
[INFO] [stdout] test a_missing_directory_loads_nothing_rather_than_failing ... ok
[INFO] [stdout] test a_trusted_project_directory_really_is_read ... ok
[INFO] [stdout] test a_configured_key_is_read_and_sent_as_a_bearer_token ... FAILED
[INFO] [stdout] test non_lua_files_are_ignored ... ok
[INFO] [stdout] test a_reply_that_is_not_the_expected_shape_fails_open ... FAILED
[INFO] [stdout] test one_broken_extension_does_not_cost_the_others ... ok
[INFO] [stdout] test a_dead_endpoint_fails_open_even_when_the_host_refuses ... FAILED
[INFO] [stdout] test a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session ... FAILED
[INFO] [stdout] test an_identical_call_is_asked_once ... FAILED
[INFO] [stdout] test a_tool_the_gate_does_not_watch_is_never_asked_about ... FAILED
[INFO] [stdout] test a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one ... FAILED
[INFO] [stdout] test an_extension_on_disk_registers_and_runs ... ok
[INFO] [stdout] test one_request_carries_every_question ... FAILED
[INFO] [stdout] test enforce_mode_lets_a_call_the_model_is_confident_about_through ... FAILED
[INFO] [stdout] test enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted ... FAILED
[INFO] [stdout] test shadow_mode_asks_and_then_lets_the_call_through ... FAILED
[INFO] [stdout] test paths_in_the_project_are_separate_from_the_user_directory ... ok
[INFO] [stdout] test a_trusted_script_cannot_reach_the_filesystem_directly ... ok
[INFO] [stdout] test the_builtin_names_stay_reserved ... ok
[INFO] [stdout] test several_extensions_all_load ... ok
[INFO] [stdout] test the_gate_watches_a_tool_whose_name_it_lists ... FAILED
[INFO] [stdout] test the_shipped_gate_loads_and_registers_only_a_hook ... FAILED
[INFO] [stdout] test the_filesystem_is_really_absent_rather_than_merely_hidden ... ok
[INFO] [stdout] test the_model_sees_a_lua_tool_as_an_ordinary_tool_def ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- a_configured_key_is_read_and_sent_as_a_bearer_token stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_configured_key_is_read_and_sent_as_a_bearer_token' (972) panicked at tests/extensions.rs:280:36:
[INFO] [stderr] error: test failed, to rerun pass `--test extensions`
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664506 - extensions[1ac732083769aea]::keyed_gate
[INFO] [stdout]   14:     0x5c19c2661337 - extensions[1ac732083769aea]::a_configured_key_is_read_and_sent_as_a_bearer_token::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c26677fe - <extensions[1ac732083769aea]::a_configured_key_is_read_and_sent_as_a_bearer_token::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- a_reply_that_is_not_the_expected_shape_fails_open stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_reply_that_is_not_the_expected_shape_fails_open' (978) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664716 - extensions[1ac732083769aea]::enforcing_gate
[INFO] [stdout]   14:     0x5c19c2660669 - extensions[1ac732083769aea]::a_reply_that_is_not_the_expected_shape_fails_open::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c266750e - <extensions[1ac732083769aea]::a_reply_that_is_not_the_expected_shape_fails_open::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- a_dead_endpoint_fails_open_even_when_the_host_refuses stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_dead_endpoint_fails_open_even_when_the_host_refuses' (973) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664716 - extensions[1ac732083769aea]::enforcing_gate
[INFO] [stdout]   14:     0x5c19c26621a4 - extensions[1ac732083769aea]::a_dead_endpoint_fails_open_even_when_the_host_refuses::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c2667efe - <extensions[1ac732083769aea]::a_dead_endpoint_fails_open_even_when_the_host_refuses::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session' (977) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664716 - extensions[1ac732083769aea]::enforcing_gate
[INFO] [stdout]   14:     0x5c19c266361f - extensions[1ac732083769aea]::a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c26690be - <extensions[1ac732083769aea]::a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- an_identical_call_is_asked_once stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'an_identical_call_is_asked_once' (983) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c265cdf1 - extensions[1ac732083769aea]::an_identical_call_is_asked_once::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c266667e - <extensions[1ac732083769aea]::an_identical_call_is_asked_once::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- a_tool_the_gate_does_not_watch_is_never_asked_about stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_tool_the_gate_does_not_watch_is_never_asked_about' (979) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2661b42 - extensions[1ac732083769aea]::a_tool_the_gate_does_not_watch_is_never_asked_about::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c2667d9e - <extensions[1ac732083769aea]::a_tool_the_gate_does_not_watch_is_never_asked_about::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one' (974) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664506 - extensions[1ac732083769aea]::keyed_gate
[INFO] [stdout]   14:     0x5c19c2663b03 - extensions[1ac732083769aea]::a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c266926e - <extensions[1ac732083769aea]::a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- one_request_carries_every_question stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'one_request_carries_every_question' (988) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c265d884 - extensions[1ac732083769aea]::one_request_carries_every_question::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c2666bce - <extensions[1ac732083769aea]::one_request_carries_every_question::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- enforce_mode_lets_a_call_the_model_is_confident_about_through stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'enforce_mode_lets_a_call_the_model_is_confident_about_through' (985) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664716 - extensions[1ac732083769aea]::enforcing_gate
[INFO] [stdout]   14:     0x5c19c26629b0 - extensions[1ac732083769aea]::enforce_mode_lets_a_call_the_model_is_confident_about_through::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c2668d5e - <extensions[1ac732083769aea]::enforce_mode_lets_a_call_the_model_is_confident_about_through::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted' (984) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2664716 - extensions[1ac732083769aea]::enforcing_gate
[INFO] [stdout]   14:     0x5c19c2662ed6 - extensions[1ac732083769aea]::enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted::{closure#0}
[INFO] [stdout]   15:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   16:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   17:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   18:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   19:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   20:     0x5c19c2668f0e - <extensions[1ac732083769aea]::enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   21:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   22:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   23:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   24:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   25:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   26:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   27:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   28:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- shadow_mode_asks_and_then_lets_the_call_through stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'shadow_mode_asks_and_then_lets_the_call_through' (991) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c265fbad - extensions[1ac732083769aea]::shadow_mode_asks_and_then_lets_the_call_through::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c266726e - <extensions[1ac732083769aea]::shadow_mode_asks_and_then_lets_the_call_through::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- the_gate_watches_a_tool_whose_name_it_lists stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'the_gate_watches_a_tool_whose_name_it_lists' (994) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c265f100 - extensions[1ac732083769aea]::the_gate_watches_a_tool_whose_name_it_lists::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c2666fce - <extensions[1ac732083769aea]::the_gate_watches_a_tool_whose_name_it_lists::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- the_shipped_gate_loads_and_registers_only_a_hook stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'the_shipped_gate_loads_and_registers_only_a_hook' (996) panicked at tests/extensions.rs:280:36:
[INFO] [stdout] the worked example must be in the repository: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c19c28ca061 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c19c28e037a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c19c28ce98c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c19c28a3606 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c19c28c13e9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c19c2681dc0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c19c28c1712 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c19c28a36b2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c19c289c0e9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c19c28a42ad - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c19c28e0b5c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c19c28e0932 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c19c26646c3 - extensions[1ac732083769aea]::shipped_gate
[INFO] [stdout]   13:     0x5c19c2660263 - extensions[1ac732083769aea]::the_shipped_gate_loads_and_registers_only_a_hook::{closure#0}
[INFO] [stdout]   14:     0x5c19c26693d3 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   15:     0x5c19c2670f19 - <std[70759c8f55707aa4]::thread::local::LocalKey<tokio[ee22f2aee25a847]::runtime::context::Context>>::with::<tokio[ee22f2aee25a847]::runtime::context::set_scheduler<(alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>), <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   16:     0x5c19c2669557 - <tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   17:     0x5c19c266d79b - tokio[ee22f2aee25a847]::runtime::context::runtime::enter_runtime::<<tokio[ee22f2aee25a847]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c19c266e7d5 - <tokio[ee22f2aee25a847]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   19:     0x5c19c26673ce - <extensions[1ac732083769aea]::the_shipped_gate_loads_and_registers_only_a_hook::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c19c26750ab - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c19c2682715 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c19c267bfd4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c19c2685872 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c19c28c90a9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x7a9094996dfa - <unknown>
[INFO] [stdout]   26:     0x7a9094a2a3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     a_configured_key_is_read_and_sent_as_a_bearer_token
[INFO] [stdout]     a_dead_endpoint_fails_open_even_when_the_host_refuses
[INFO] [stdout]     a_gate_whose_key_is_missing_refuses_to_load_rather_than_asking_without_one
[INFO] [stdout]     a_rate_limited_endpoint_fails_open_rather_than_blocking_the_session
[INFO] [stdout]     a_reply_that_is_not_the_expected_shape_fails_open
[INFO] [stdout]     a_tool_the_gate_does_not_watch_is_never_asked_about
[INFO] [stdout]     an_identical_call_is_asked_once
[INFO] [stdout]     enforce_mode_blocks_the_call_the_shadow_mode_would_have_only_noted
[INFO] [stdout]     enforce_mode_lets_a_call_the_model_is_confident_about_through
[INFO] [stdout]     one_request_carries_every_question
[INFO] [stdout]     shadow_mode_asks_and_then_lets_the_call_through
[INFO] [stdout]     the_gate_watches_a_tool_whose_name_it_lists
[INFO] [stdout]     the_shipped_gate_loads_and_registers_only_a_hook
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 12 passed; 13 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d", kill_on_drop: false }`
[INFO] [stdout] 49c2ea770b5c1a3a2b2486c582062c1002f99a9e4891954e3c2c4c033fe3bc9d
