[INFO] cloning repository https://github.com/ogital-net/sstp-server
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ogital-net/sstp-server" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fogital-net%2Fsstp-server", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fogital-net%2Fsstp-server'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] adc7754a683a580eb784e1dbb10a594191081a05
[INFO] testing ogital-net/sstp-server against 1.97.0-beta.6 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fogital-net%2Fsstp-server" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/ogital-net/sstp-server
[INFO] finished tweaking git repo https://github.com/ogital-net/sstp-server
[INFO] tweaked toml for git repo https://github.com/ogital-net/sstp-server written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ogital-net/sstp-server on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/ogital-net/sstp-server 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.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Updating git repository `https://github.com/ogital-net/radius-tokio`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded vergen-lib v9.1.0
[INFO] [stderr]   Downloaded vergen v9.1.0
[INFO] [stderr]   Downloaded tracing-appender v0.2.5
[INFO] [stderr]   Downloaded vergen-git2 v9.1.0
[INFO] [stderr]   Downloaded symlink v0.1.0
[INFO] [stderr]   Downloaded endian-type v0.2.0
[INFO] [stderr]   Downloaded fast-md5 v1.0.0
[INFO] [stderr]   Downloaded getopt-iter v1.0.2
[INFO] [stderr]   Downloaded command-trie v1.1.0
[INFO] [stderr]   Downloaded rustyline v18.0.0
[INFO] [stderr]   Downloaded radix_trie v0.3.0
[INFO] [stderr]   Downloaded libgit2-sys v0.18.4+1.9.3
[INFO] [stderr]   Downloaded aws-lc-sys v0.41.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b
[INFO] running `Command { std: "docker" "start" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", 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" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", 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" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling displaydoc v0.2.6
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling icu_properties_data v2.1.2
[INFO] [stderr]    Compiling icu_normalizer_data v2.1.1
[INFO] [stderr]    Compiling clang-sys v1.8.1
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling log v0.4.30
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling memchr v2.8.1
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling libloading v0.8.9
[INFO] [stderr]    Compiling bindgen v0.72.1
[INFO] [stderr]    Compiling either v1.16.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling vergen-lib v9.1.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling time-core v0.1.7
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling cexpr v0.6.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling libz-sys v1.1.28
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling libgit2-sys v0.18.4+1.9.3
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling vergen v9.1.0
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling vergen-git2 v9.1.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling radius-tokio-dict-codegen v0.1.0 (https://github.com/ogital-net/radius-tokio?branch=client#25a9b999)
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling time v0.3.45
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling radius-tokio-dict v0.1.0 (https://github.com/ogital-net/radius-tokio?branch=client#25a9b999)
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling nibble_vec v0.1.0
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling socket2 v0.6.4
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling endian-type v0.2.0
[INFO] [stderr]    Compiling radix_trie v0.3.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling home v0.5.12
[INFO] [stderr]    Compiling symlink v0.1.0
[INFO] [stderr]    Compiling fast-md5 v1.0.0
[INFO] [stderr]    Compiling command-trie v1.1.0
[INFO] [stderr]    Compiling getopt-iter v1.0.2
[INFO] [stderr]    Compiling rustyline v18.0.0
[INFO] [stderr]    Compiling tracing-appender v0.2.5
[INFO] [stderr]    Compiling aws-lc-sys v0.41.0
[INFO] [stderr]    Compiling git2 v0.20.4
[INFO] [stderr]    Compiling sstp-server v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling radius-tokio v0.1.0 (https://github.com/ogital-net/radius-tokio?branch=client#25a9b999)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4m 40s
[INFO] running `Command { std: "docker" "inspect" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", 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" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling radius-tokio v0.1.0 (https://github.com/ogital-net/radius-tokio?branch=client#25a9b999)
[INFO] [stderr]    Compiling sstp-server v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 42.40s
[INFO] running `Command { std: "docker" "inspect" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", 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" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.34s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/sstp_server-5fe5c6e15a30bc16)
[INFO] [stdout] 
[INFO] [stdout] running 347 tests
[INFO] [stdout] test auth::accounting::tests::delay_time_emitted_only_when_nonzero ... ok
[INFO] [stdout] test auth::accounting::tests::session_end_maps_to_terminate_cause ... ok
[INFO] [stdout] test auth::bridge::tests::hlak_from_mppe_concatenates_recv_then_send ... ok
[INFO] [stdout] test auth::accounting::tests::gigawords_emitted_when_high32_nonzero ... ok
[INFO] [stdout] test auth::bridge::tests::hlak_from_mppe_returns_none_when_missing_or_wrong_size ... ok
[INFO] [stdout] test auth::accounting::tests::round_trip_against_one_shot_responder ... ok
[INFO] [stdout] test auth::coa::tests::coa_request_nak_with_error_cause ... ok
[INFO] [stdout] test auth::coa::tests::disconnect_request_ack_round_trip ... ok
[INFO] [stdout] test auth::reply::tests::accept_round_trip ... ok
[INFO] [stdout] test auth::reply::tests::acct_interim_clamped_to_minimum ... ok
[INFO] [stdout] test auth::reply::tests::acct_interim_passthrough_when_above_minimum ... ok
[INFO] [stdout] test auth::reply::tests::class_attribute_round_tripped_verbatim ... ok
[INFO] [stdout] test auth::accounting::tests::start_packet_shape ... ok
[INFO] [stdout] test auth::accounting::tests::stop_carries_terminate_cause_and_counters ... ok
[INFO] [stdout] test auth::reply::tests::framed_mtu_and_netmask_decoded ... ok
[INFO] [stdout] test auth::reply::tests::framed_routes_collects_multiple_and_skips_garbage ... ok
[INFO] [stdout] test auth::reply::tests::framed_routes_empty_when_absent ... ok
[INFO] [stdout] test auth::reply::tests::mikrotik_rate_limit_garbage_yields_none ... ok
[INFO] [stdout] test auth::reply::tests::mikrotik_rate_limit_populates_shaping ... ok
[INFO] [stdout] test auth::reply::tests::missing_framed_ip_rejected ... ok
[INFO] [stdout] test auth::reply::tests::mschap2_success_empty_yields_empty_vec ... ok
[INFO] [stdout] test auth::reply::tests::mschap2_success_strips_leading_ident_byte ... ok
[INFO] [stdout] test auth::reply::tests::mschap_error_absent_returns_none ... ok
[INFO] [stdout] test auth::reply::tests::dns_and_nbns_servers_decoded ... ok
[INFO] [stdout] test auth::reply::tests::reject_carries_reply_message ... ok
[INFO] [stdout] test auth::reply::tests::reject_reason_absent_returns_none ... ok
[INFO] [stdout] test auth::reply::tests::session_and_idle_timeout_decoded ... ok
[INFO] [stdout] test auth::request::tests::chap_md5_emits_chap_password_and_challenge ... ok
[INFO] [stdout] test auth::request::tests::eap_fragments_at_253_bytes ... ok
[INFO] [stdout] test auth::reply::tests::malformed_mppe_key_yields_auth_error ... ok
[INFO] [stdout] test auth::reply::tests::mschap_error_strips_leading_ident_byte ... ok
[INFO] [stdout] test auth::request::tests::pap_round_trip ... ok
[INFO] [stdout] test auth::route::tests::classful_default_prefix ... ok
[INFO] [stdout] test auth::route::tests::explicit_prefix_and_gateway_with_metric ... ok
[INFO] [stdout] test auth::route::tests::extra_metrics_ignored ... ok
[INFO] [stdout] test auth::route::tests::invalid_prefix_errors ... ok
[INFO] [stdout] test auth::route::tests::missing_gateway_errors ... ok
[INFO] [stdout] test auth::route::tests::zero_gateway_means_via_user ... ok
[INFO] [stdout] test cli::tests::bad_listen ... ok
[INFO] [stdout] test cli::tests::bad_log_format ... ok
[INFO] [stdout] test cli::tests::compute_threads_default_on_small_box ... ok
[INFO] [stdout] test cli::tests::compute_threads_explicit_auth_not_capped_by_default_io ... ok
[INFO] [stdout] test cli::tests::compute_threads_default_on_high_cpu_box ... ok
[INFO] [stdout] test auth::request::tests::mschapv2_shape ... ok
[INFO] [stdout] test cli::tests::compute_threads_rejects_explicit_zero ... ok
[INFO] [stdout] test cli::tests::compute_threads_zero_ncpus_treated_as_one ... ok
[INFO] [stdout] test cli::tests::long_log_format ... ok
[INFO] [stdout] test cli::tests::minimum_required ... ok
[INFO] [stdout] test cli::tests::missing_cert ... ok
[INFO] [stdout] test cli::tests::compute_threads_explicit_io_caps_auth ... ok
[INFO] [stdout] test cli::tests::missing_local_ip ... ok
[INFO] [stdout] test cli::tests::help_exits ... ok
[INFO] [stdout] test cli::tests::missing_radius ... ok
[INFO] [stdout] test cli::tests::no_control_socket_long ... ok
[INFO] [stdout] test cli::tests::positional_rejected ... ok
[INFO] [stdout] test cli::tests::no_control_socket_short ... ok
[INFO] [stdout] test cli::tests::quiet_wins_over_verbose ... ok
[INFO] [stdout] test cli::tests::short_control_socket ... ok
[INFO] [stdout] test cli::tests::short_log_format ... ok
[INFO] [stdout] test cli::tests::verbose_levels ... ok
[INFO] [stdout] test cli::tests::version_exits ... ok
[INFO] [stdout] test control::tests::disable_session_known_id_queues_disconnect ... ok
[INFO] [stdout] test control::tests::disable_session_unknown_id_errors ... ok
[INFO] [stdout] test control::tests::empty_line_is_silent ... ok
[INFO] [stdout] test control::tests::rekey_session_default_does_not_request_peer ... ok
[INFO] [stdout] test control::tests::rekey_session_garbage_fourth_token_is_unknown ... ok
[INFO] [stdout] test control::tests::rekey_session_request_sets_peer_flag ... ok
[INFO] [stdout] test control::tests::rekey_session_unknown_id_errors ... ok
[INFO] [stdout] test control::tests::show_info_lists_uptime_and_threads ... ok
[INFO] [stdout] test cli::tests::repeatable_radius ... ok
[INFO] [stdout] test cli::tests::short_auth_threads ... ok
[INFO] [stdout] test control::tests::show_sess_empty_then_one ... ok
[INFO] [stdout] test control::tests::show_stat_includes_metric_names ... ok
[INFO] [stdout] test control::tests::unknown_command_returns_error ... ok
[INFO] [stdout] test crypto::hash::tests::md5_abc ... ok
[INFO] [stdout] test control::tests::shutdown_broadcasts ... ok
[INFO] [stdout] test crypto::hash::tests::sha256_abc ... ok
[INFO] [stdout] test crypto::hash::tests::sha256_streaming_matches_oneshot ... ok
[INFO] [stdout] test control::tests::unix_socket_round_trip ... ok
[INFO] [stdout] test crypto::hmac::tests::hmac_sha1_rfc2202_case1 ... ok
[INFO] [stdout] test crypto::hmac::tests::hmac_sha256_rfc4231_case1 ... ok
[INFO] [stdout] test crypto::ktls::tests::aes_gcm_128_crypto_info_is_40_bytes ... ok
[INFO] [stdout] test crypto::ktls::tests::aes_gcm_256_crypto_info_is_56_bytes ... ok
[INFO] [stdout] test crypto::ktls::tests::hkdf_expand_label_known_answer_key ... ok
[INFO] [stdout] test crypto::ktls::tests::hkdf_expand_label_truncates ... ok
[INFO] [stdout] test crypto::ktls::tests::hkdf_expand_label_sha384_truncates ... ok
[INFO] [stdout] test crypto::rand::tests::empty_is_noop ... ok
[INFO] [stdout] test crypto::rekey::tests::content_type_decode_covers_iana_values ... ok
[INFO] [stdout] test crypto::rekey::tests::double_signal_mid_rekey_is_refused ... ok
[INFO] [stdout] test crypto::rekey::tests::full_happy_path_round_trip ... ok
[INFO] [stdout] test crypto::rekey::tests::idle_alert_tears_down ... ok
[INFO] [stdout] test crypto::rekey::tests::idle_application_data_tears_down_unexpected ... ok
[INFO] [stdout] test crypto::rekey::tests::idle_handshake_pulls_next_record_and_advances ... ok
[INFO] [stdout] test crypto::rekey::tests::key_update_with_request_installs_both_directions ... ok
[INFO] [stdout] test crypto::rekey::tests::key_update_without_request_installs_rx_only ... ok
[INFO] [stdout] test crypto::rekey::tests::labels_are_metric_safe ... ok
[INFO] [stdout] test crypto::rekey::tests::mismatched_ack_is_refused ... ok
[INFO] [stdout] test crypto::rekey::tests::matching_ack_returns_to_idle ... ok
[INFO] [stdout] test crypto::rekey::tests::nst_followed_by_real_keyupdate_works ... ok
[INFO] [stdout] test crypto::rekey::tests::reinstall_failure_tears_down ... ok
[INFO] [stdout] test crypto::rekey::tests::v03_alert_tears_down_as_alert ... ok
[INFO] [stdout] test crypto::rekey::tests::v03_handshake_tears_down_with_v03_label ... ok
[INFO] [stdout] test crypto::rekey::tests::v03_unexpected_records_tear_down ... ok
[INFO] [stdout] test crypto::rekey::tests::new_session_ticket_is_ignored_and_resets_to_idle ... ok
[INFO] [stdout] test crypto::tests::const_time_eq_basic ... ok
[INFO] [stdout] test crypto::rand::tests::produces_distinct_blocks ... ok
[INFO] [stdout] test auth::client::tests::pap_accept_round_trip ... ok
[INFO] [stdout] test auth::client::tests::pap_reject_round_trip ... ok
[INFO] [stdout] test kppp::ioctl::tests::ioctl_numbers_match_kernel ... ok
[INFO] [stdout] test kppp::ioctl::tests::npioctl_size_is_two_ints ... ok
[INFO] [stdout] test kppp::netlink::tests::bring_up_pppn ... ignored, requires CAP_NET_ADMIN and the ppp_generic kernel module
[INFO] [stdout] test kppp::netlink::tests::encode_add_p2p_addr_layout ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_add_route_with_gateway_uses_universe_scope ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_add_route_without_gateway_uses_link_scope ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_add_route_zero_prefix_default_gateway ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_set_link_up_only_touches_iff_up ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_set_mtu_emits_ifla_mtu_attr ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_rejects_bogus_short_len ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_rejects_truncated_attribute ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_returns_none_for_empty ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_returns_none_when_absent ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_skips_unrelated_attrs_and_returns_payload ... ok
[INFO] [stdout] test kppp::netlink::tests::link_stats64_accepts_exactly_32_bytes ... ok
[INFO] [stdout] test auth::bridge::tests::pap_accept_projects_addrs ... ok
[INFO] [stdout] test kppp::netlink::tests::link_stats64_rejects_short_payload ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::attach_plain_tcp_returns_eopnotsupp ... ignored, requires CAP_NET_ADMIN and the sstp kernel module
[INFO] [stdout] test kppp::sstp_kmod::tests::control_packet_event_round_trips ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::probe_succeeds_when_loaded ... ignored, requires CAP_NET_ADMIN and the sstp kernel module
[INFO] [stdout] test auth::bridge::tests::pap_reject_carries_reply_message ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::rekey_struct_is_16_bytes ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::send_control_struct_is_16_bytes ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::sstp_ioctl_numbers_match_kernel ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::stats_struct_is_v03_size ... ok
[INFO] [stdout] test kppp::unit::tests::create_and_drop_unit ... ignored, requires CAP_NET_ADMIN and the ppp_generic kernel module
[INFO] [stdout] test metrics::tests::counter_and_gauge_smoke ... ok
[INFO] [stdout] test metrics::tests::render_stats_lists_every_metric ... ok
[INFO] [stdout] test kppp::netlink::tests::link_stats64_parses_first_four_counters ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::recv_control_struct_is_16_bytes ... ok
[INFO] [stdout] test net::listener::tests::bind_two_listeners_same_port ... ok
[INFO] [stdout] test netlink::tests::drain_acks_surfaces_nonzero_err ... ok
[INFO] [stdout] test netlink::tests::drain_acks_consumes_seq_on_zero_err ... ok
[INFO] [stdout] test netlink::tests::nest_begin_end_patches_length ... ok
[INFO] [stdout] test netlink::tests::nest_begin_sets_nla_f_nested_flag ... ok
[INFO] [stdout] test net::listener::tests::dual_stack_v6_accepts_v4_client ... ok
[INFO] [stdout] test ppp::auth::tests::chap_decode_challenge ... ok
[INFO] [stdout] test ppp::auth::tests::chap_round_trip_response ... ok
[INFO] [stdout] test ppp::auth::tests::chap_terminal_round_trip ... ok
[INFO] [stdout] test ppp::auth::tests::eap_decode_success_has_no_type ... ok
[INFO] [stdout] test ppp::auth::tests::eap_rejects_length_below_header ... ok
[INFO] [stdout] test ppp::auth::tests::eap_decode_request_identity ... ok
[INFO] [stdout] test ppp::auth::tests::eap_rejects_length_overflow ... ok
[INFO] [stdout] test ppp::auth::tests::eap_round_trip_request ... ok
[INFO] [stdout] test ppp::auth::tests::pap_decode_authenticate_request ... ok
[INFO] [stdout] test ppp::auth::tests::pap_rejects_truncated_password ... ok
[INFO] [stdout] test ppp::driver::tests::ack_acceptable_lcp_cr ... ok
[INFO] [stdout] test ppp::auth::tests::pap_round_trip_ack ... ok
[INFO] [stdout] test ppp::driver::tests::auth_accept_acks_pap_and_starts_ipcp ... ok
[INFO] [stdout] test ppp::driver::tests::ccp_configure_request_after_lcp_open_gets_protocol_reject ... ok
[INFO] [stdout] test netlink::tests::nlmsg_len_patched_on_finalize ... ok
[INFO] [stdout] test ppp::driver::tests::auth_reject_emits_nak_and_terminates ... ok
[INFO] [stdout] test ppp::driver::tests::ipcp_acks_matching_ip ... ok
[INFO] [stdout] test ppp::driver::tests::ipcp_naks_zero_ip_with_assigned ... ok
[INFO] [stdout] test ppp::driver::tests::lcp_opened_transitions_to_auth_pending ... ok
[INFO] [stdout] test ppp::driver::tests::open_emits_lcp_configure_request_with_pap ... ok
[INFO] [stdout] test ppp::driver::tests::pap_request_emits_need_auth_event ... ok
[INFO] [stdout] test ppp::driver::tests::protocol_reject_identifier_changes_per_packet ... ok
[INFO] [stdout] test ppp::driver::tests::rejects_unknown_lcp_option ... ok
[INFO] [stdout] test ppp::driver::tests::unknown_protocol_before_lcp_opens_is_silently_dropped ... ok
[INFO] [stdout] test ppp::frame::tests::decode_acfc_and_pfc ... ok
[INFO] [stdout] test ppp::frame::tests::decode_acfc_only ... ok
[INFO] [stdout] test ppp::frame::tests::decode_uncompressed_lcp ... ok
[INFO] [stdout] test ppp::frame::tests::decode_pfc_compressed ... ok
[INFO] [stdout] test ppp::frame::tests::encode_compressed_ip ... ok
[INFO] [stdout] test ppp::frame::tests::encode_compressed_lcp_stays_two_bytes ... ok
[INFO] [stdout] test ppp::frame::tests::encode_uncompressed_roundtrip ... ok
[INFO] [stdout] test ppp::frame::tests::protocol_id_roundtrip ... ok
[INFO] [stdout] test ppp::frame::tests::rejects_bad_parity ... ok
[INFO] [stdout] test ppp::frame::tests::rejects_empty ... ok
[INFO] [stdout] test ppp::fsm::tests::close_from_opened_sends_terminate_req ... ok
[INFO] [stdout] test ppp::fsm::tests::close_from_reqsent_emits_terminate_req ... ok
[INFO] [stdout] test ppp::fsm::tests::closed_configure_request_emits_terminate_ack ... ok
[INFO] [stdout] test ppp::fsm::tests::closed_rejects_unsolicited_acks_with_code_reject ... ok
[INFO] [stdout] test ppp::fsm::tests::close_from_starting_finishes_immediately ... ok
[INFO] [stdout] test ppp::fsm::tests::closing_restart_timeout_retransmits_terminate_req ... ok
[INFO] [stdout] test ppp::fsm::tests::closing_terminate_ack_finishes ... ok
[INFO] [stdout] test ppp::fsm::tests::code_reject_catastrophic_in_opened_starts_termination ... ok
[INFO] [stdout] test ppp::fsm::tests::closing_restart_timeout_to_minus_drops_to_closed ... ok
[INFO] [stdout] test ppp::fsm::tests::code_reject_catastrophic_in_reqsent_drops_to_stopped ... ok
[INFO] [stdout] test ppp::fsm::tests::code_reject_permitted_is_noop ... ok
[INFO] [stdout] test ppp::fsm::tests::down_from_closed_returns_to_initial ... ok
[INFO] [stdout] test ppp::fsm::tests::down_from_opened_returns_to_starting_with_notify ... ok
[INFO] [stdout] test ppp::fsm::tests::echo_only_replies_when_opened ... ok
[INFO] [stdout] test ppp::fsm::tests::identifier_bump_wraps ... ok
[INFO] [stdout] test ppp::fsm::tests::happy_path_to_opened ... ok
[INFO] [stdout] test ppp::fsm::tests::open_from_initial_starts_with_notify ... ok
[INFO] [stdout] test ppp::fsm::tests::open_in_starting_is_noop ... ok
[INFO] [stdout] test ppp::fsm::tests::opened_terminate_request_goes_to_stopping ... ok
[INFO] [stdout] test ppp::fsm::tests::rcr_in_opened_renegotiates ... ok
[INFO] [stdout] test ppp::fsm::tests::rcr_bad_emits_nak_or_rej ... ok
[INFO] [stdout] test ppp::fsm::tests::rcv_configure_ack_in_reqsent_advances_to_ackrcvd ... ok
[INFO] [stdout] test ppp::fsm::tests::rcv_configure_ack_in_opened_renegotiates_with_down_notify ... ok
[INFO] [stdout] test ppp::fsm::tests::rcv_configure_nak_in_opened_renegotiates ... ok
[INFO] [stdout] test ppp::fsm::tests::rcv_configure_nak_in_reqsent_re_emits_request ... ok
[INFO] [stdout] test ppp::fsm::tests::rcv_terminate_ack_in_stopping_finishes ... ok
[INFO] [stdout] test ppp::fsm::tests::restart_timeout_in_initial_is_noop ... ok
[INFO] [stdout] test ppp::fsm::tests::unknown_code_always_emits_code_reject ... ok
[INFO] [stdout] test ppp::fsm::tests::restart_timeout_retransmits_until_exhausted ... ok
[INFO] [stdout] test ppp::ipcp::tests::code_round_trip ... ok
[INFO] [stdout] test ppp::ipcp::tests::ipv4_option_round_trip ... ok
[INFO] [stdout] test ppp::ipcp::tests::option_id_round_trip ... ok
[INFO] [stdout] test ppp::ipcp::tests::decode_typical_windows_configure_request ... ok
[INFO] [stdout] test ppp::ipcp::tests::read_ipv4_rejects_wrong_length ... ok
[INFO] [stdout] test ppp::lcp::tests::auth_protocol_helpers ... ok
[INFO] [stdout] test ppp::lcp::tests::decode_auth_protocol_chap_mschapv2 ... ok
[INFO] [stdout] test ppp::lcp::tests::decode_zero_length_options ... ok
[INFO] [stdout] test ppp::lcp::tests::decode_configure_request_with_options ... ok
[INFO] [stdout] test ppp::lcp::tests::rejects_length_below_header ... ok
[INFO] [stdout] test ppp::lcp::tests::option_iter_surfaces_bad_length ... ok
[INFO] [stdout] test ppp::lcp::tests::rejects_length_overflowing_buffer ... ok
[INFO] [stdout] test ppp::lcp::tests::rejects_short_packet ... ok
[INFO] [stdout] test ppp::lcp::tests::write_header_and_option ... ok
[INFO] [stdout] test privdrop::tests::drop_to_rejects_non_root ... ok
[INFO] [stdout] test session::tests::broadcast_disconnect_delivers_to_all ... ok
[INFO] [stdout] test session::tests::np_filter_drops_ip_pre_ipcp ... ok
[INFO] [stdout] test session::tests::np_filter_drops_oversized_ip ... ok
[INFO] [stdout] test session::tests::np_filter_drops_pre_ipcp_takes_precedence_over_mru ... ok
[INFO] [stdout] test privdrop::tests::lookup_self_user_succeeds ... ok
[INFO] [stdout] test session::tests::np_filter_forwards_ip_at_or_below_mtu ... ok
[INFO] [stdout] test session::tests::np_filter_drops_unknown_protocols_as_not_network_layer ... ok
[INFO] [stdout] test session::tests::np_filter_passes_control_protocols_unchanged ... ok
[INFO] [stdout] test session::tests::registry_register_and_unregister ... ok
[INFO] [stdout] test session::tests::session_ids_are_monotonic ... ok
[INFO] [stdout] test shape::mikrotik::tests::asymmetric_rate ... ok
[INFO] [stdout] test session::tests::try_send_returns_false_when_session_gone ... ok
[INFO] [stdout] test shape::mikrotik::tests::bare_integer_is_bits_per_second ... ok
[INFO] [stdout] test shape::mikrotik::tests::case_insensitive_suffixes ... ok
[INFO] [stdout] test shape::mikrotik::tests::empty_value_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::extra_fields_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::decimal_with_suffix ... ok
[INFO] [stdout] test shape::mikrotik::tests::flat_symmetric_rate ... ok
[INFO] [stdout] test shape::mikrotik::tests::full_six_field_form ... ok
[INFO] [stdout] test shape::mikrotik::tests::invalid_number_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::malformed_pair_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::priority_range_validated ... ok
[INFO] [stdout] test shape::mikrotik::tests::whitespace_normalised ... ok
[INFO] [stdout] test shape::mss::tests::encap_total_is_self_consistent ... ok
[INFO] [stdout] test shape::mss::tests::encodes_ifname_match_as_ifnamsiz_payload ... ok
[INFO] [stdout] test shape::mss::tests::exthdr_expr_contains_tcpopt_write_fields ... ok
[INFO] [stdout] test shape::mss::tests::mss_value_is_big_endian_on_the_wire ... ok
[INFO] [stdout] test shape::mss::tests::mtu_above_1500_clamps_to_1500 ... ok
[INFO] [stdout] test shape::mss::tests::mtu_below_576_floors_at_536 ... ok
[INFO] [stdout] test shape::mss::tests::real_session_from_log_lands_at_1383 ... ok
[INFO] [stdout] test shape::mss::tests::small_mtu_picks_link_bound ... ok
[INFO] [stdout] test shape::mss::tests::tls12_aes_gcm_overhead_is_29 ... ok
[INFO] [stdout] test shape::mss::tests::tls12_cbc_sha_uses_56_byte_worst_case ... ok
[INFO] [stdout] test shape::mss::tests::tls12_chacha20_overhead_is_21 ... ok
[INFO] [stdout] test shape::mss::tests::tls13_overhead_is_22_for_every_aead ... ok
[INFO] [stdout] test shape::mss::tests::unknown_tls12_cipher_falls_back_to_worst_case ... ok
[INFO] [stdout] test shape::mss::tests::unknown_version_falls_back_to_worst_case ... ok
[INFO] [stdout] test shape::tc::tests::handle_packs_major_minor ... ok
[INFO] [stdout] test shape::tc::tests::handle_root_and_ingress_constants ... ok
[INFO] [stdout] test shape::tc::tests::struct_sizes_match_kernel_uapi ... ok
[INFO] [stdout] test shape::tests::burst_bytes_to_psched_ticks_handles_zero_rate ... ok
[INFO] [stdout] test shape::tests::compute_cell_log_picks_smallest_n_for_256_slots ... ok
[INFO] [stdout] test shape::tests::compute_rate_table_is_monotonic_and_matches_formula ... ok
[INFO] [stdout] test shape::tests::compute_rate_table_saturates_on_micro_rates ... ok
[INFO] [stdout] test shape::tests::encode_htb_leaf_carries_rate64_for_large_rates ... ok
[INFO] [stdout] test shape::tests::encode_htb_leaf_floors_zero_rate ... ok
[INFO] [stdout] test shape::tests::encode_htb_root_shape ... ok
[INFO] [stdout] test shape::tests::encode_ingress_police_carries_rate64_and_shot_action ... ok
[INFO] [stdout] test shape::tests::encode_ingress_police_emits_required_rate_table ... ok
[INFO] [stdout] test shape::tests::encode_ingress_police_ratespec_uses_unaware_linklayer ... ok
[INFO] [stdout] test shape::tests::encode_ingress_qdisc_shape ... ok
[INFO] [stdout] test shape::tests::rate_spec_flat_clears_optionals ... ok
[INFO] [stdout] test privdrop::tests::lookup_missing_user_reports_unknown ... ok
[INFO] [stdout] test shape::tests::rate_table_bytes_is_native_u32_layout ... ok
[INFO] [stdout] test shape::tests::saturating_ingress_burst_bytes_floors_to_ten_mtus ... ok
[INFO] [stdout] test shape::tests::shaper_apply_empty_policy_short_circuits ... ok
[INFO] [stdout] test shape::tests::shaping_policy_empty_when_default ... ok
[INFO] [stdout] test shape::tests::shaping_policy_non_empty_when_either_direction_set ... ok
[INFO] [stdout] test sstp::attr::tests::iter_crypto_binding ... ok
[INFO] [stdout] test sstp::attr::tests::iter_crypto_binding_req ... ok
[INFO] [stdout] test sstp::attr::tests::iter_encapsulated_protocol ... ok
[INFO] [stdout] test sstp::attr::tests::write_attr_header_roundtrip ... ok
[INFO] [stdout] test sstp::attr::tests::iter_truncated_attribute ... ok
[INFO] [stdout] test sstp::binding::tests::accepts_matching_sha1_with_correct_mac ... ok
[INFO] [stdout] test sstp::binding::tests::accepts_matching_sha256_with_correct_mac ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_cert_hash_mismatch ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_bad_mac ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_unsupported_hash_protocol ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_nonce_mismatch ... ok
[INFO] [stdout] test sstp::frame::tests::parse_control_packet_no_attrs ... ok
[INFO] [stdout] test sstp::frame::tests::ignores_reserved_high_bits_of_length ... ok
[INFO] [stdout] test sstp::frame::tests::parse_data_packet ... ok
[INFO] [stdout] test sstp::frame::tests::rejects_bad_version ... ok
[INFO] [stdout] test sstp::frame::tests::rejects_length_overflowing_buffer ... ok
[INFO] [stdout] test sstp::frame::tests::rejects_short_buffer ... ok
[INFO] [stdout] test sstp::frame::tests::write_header_roundtrip ... ok
[INFO] [stdout] test sstp::msg::tests::encode_call_connect_nak_roundtrip ... ok
[INFO] [stdout] test sstp::msg::tests::encode_call_connected_pre_mac_layout ... ok
[INFO] [stdout] test sstp::msg::tests::encode_disconnect_with_and_without_status ... ok
[INFO] [stdout] test sstp::msg::tests::encode_empty_messages ... ok
[INFO] [stdout] test sstp::msg::tests::encode_then_parse_call_connect_ack ... ok
[INFO] [stdout] test sstp::msg::tests::parse_call_connect_request ... ok
[INFO] [stdout] test sstp::msg::tests::rejects_call_connect_request_with_extra_attr ... ok
[INFO] [stdout] test sstp::preamble::tests::imf_fixdate_known_value ... ok
[INFO] [stdout] test sstp::preamble::tests::parses_canonical_request ... ok
[INFO] [stdout] test sstp::preamble::tests::handshake_writes_200 ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_missing_content_length ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_non_ascii ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_wrong_content_length ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_wrong_method ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_wrong_uri ... ok
[INFO] [stdout] test sstp::preamble::tests::rejects_wrong_version ... ok
[INFO] [stdout] test sstp::state::tests::abort_collision_responds_and_drains ... ok
[INFO] [stdout] test sstp::state::tests::abort_t1_expiry_drains_via_t2 ... ok
[INFO] [stdout] test sstp::state::tests::binding_stores_both_cert_hashes ... ok
[INFO] [stdout] test sstp::state::tests::call_connected_with_bad_cert_aborts ... ok
[INFO] [stdout] test sstp::state::tests::call_abort_in_abort_pending_skips_to_t2 ... ok
[INFO] [stdout] test sstp::state::tests::call_connected_with_valid_binding_advances_to_connected ... ok
[INFO] [stdout] test sstp::state::tests::data_packet_in_connected_rearms_hello ... ok
[INFO] [stdout] test sstp::state::tests::data_packet_pre_connected_is_silent ... ok
[INFO] [stdout] test sstp::state::tests::default_state_machine_starts_disconnected ... ok
[INFO] [stdout] test sstp::state::tests::disconnect_ack_in_drain_state_ignored ... ok
[INFO] [stdout] test sstp::state::tests::disconnect_t1_expiry_terminates_gracefully ... ok
[INFO] [stdout] test sstp::state::tests::echo_request_when_connected_responds ... ok
[INFO] [stdout] test sstp::state::tests::echo_response_in_connected_rearms_hello ... ok
[INFO] [stdout] test sstp::state::tests::echo_response_outside_connected_aborts ... ok
[INFO] [stdout] test sstp::state::tests::handshake_to_connect_pending ... ok
[INFO] [stdout] test sstp::state::tests::hello_timeout_no_response_aborts_abruptly ... ok
[INFO] [stdout] test sstp::state::tests::hello_timer_outside_connected_is_noop ... ok
[INFO] [stdout] test sstp::state::tests::hello_timer_when_connected_emits_echo_request ... ok
[INFO] [stdout] test sstp::state::tests::higher_layer_disconnect_then_ack_terminates ... ok
[INFO] [stdout] test sstp::state::tests::inner_auth_completed_records_hlak ... ok
[INFO] [stdout] test sstp::state::tests::messages_in_terminal_drain_are_ignored ... ok
[INFO] [stdout] test sstp::state::tests::negotiation_timeout_starts_abort ... ok
[INFO] [stdout] test sstp::state::tests::out_of_state_message_triggers_abort ... ok
[INFO] [stdout] test sstp::state::tests::peer_disconnect_acked_then_drain ... ok
[INFO] [stdout] test sstp::state::tests::rejects_non_ppp_protocol_with_nak ... ok
[INFO] [stdout] test sstp::state::tests::second_disconnect_in_disconnect_ack_pending_emits_ack ... ok
[INFO] [stdout] test sstp::state::tests::unsolicited_disconnect_ack_aborts ... ok
[INFO] [stdout] test shape::mss::root_tests::install_creates_table_and_drop_removes_it ... ok
[INFO] [stdout] test auth::coa::tests::bad_secret_drops_silently ... ok
[INFO] [stdout] test crypto::tls::tests::missing_cert_returns_error ... ok
[INFO] [stdout] test crypto::tls::tests::server_context_emits_no_session_tickets ... ok
[INFO] [stdout] test crypto::tls::tests::build_context_from_pem ... ok
[INFO] [stdout] test crypto::tls::tests::handshake_read_write_export ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 343 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.83s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sstp_server_cli.rs (/opt/rustwide/target/debug/deps/sstp_server_cli-91e2074a6918c353)
[INFO] [stderr]      Running tests/e2e.rs (/opt/rustwide/target/debug/deps/e2e-c5be1a6362e49945)
[INFO] [stdout] 
[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 4 tests
[INFO] [stdout] test sstpc_pap_login ... ok
[INFO] [stdout] test sstpc_mtu_netfilter_shaping ... ok
[INFO] [stdout] test tls_handshake_smoke ... ok
[INFO] [stdout] test sstp_https_preamble ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- sstp_https_preamble stdout ----
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.948947Z","level":"INFO","fields":{"message":"sstp-server starting","version":"0.1.0 (adc7754 2026-06-20)","listen":"127.0.0.1:44525","io_threads":6,"auth_threads":2},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.949676Z","level":"INFO","fields":{"message":"RADIUS NAS-Identifier resolved","nas_identifier":"e02e281acc0b"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.949864Z","level":"INFO","fields":{"message":"data-path: tun (sstp kmod not loaded; falling back to /dev/net/tun)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.950956Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":0,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951037Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":1,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951126Z","level":"INFO","fields":{"message":"listener ready","worker":0,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951180Z","level":"INFO","fields":{"message":"listener ready","worker":1,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951311Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":2,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951337Z","level":"INFO","fields":{"message":"session accepted","id":"1","peer":"127.0.0.1:59116"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951445Z","level":"INFO","fields":{"message":"listener ready","worker":2,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951672Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":3,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951852Z","level":"INFO","fields":{"message":"listener ready","worker":3,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.952017Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":4,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.952155Z","level":"INFO","fields":{"message":"listener ready","worker":4,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.951954Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":5,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:36.952451Z","level":"INFO","fields":{"message":"listener ready","worker":5,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:37.019324Z","level":"INFO","fields":{"message":"session accepted","id":"2","peer":"127.0.0.1:59128"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:37.131571Z","level":"WARN","fields":{"message":"TLS handshake failed","id":"1","error":"TLS handshake failed: SSL_do_handshake: code=5, (no error queued)"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:37.134621Z","level":"INFO","fields":{"message":"TLS handshake completed","id":"2"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:38.952072Z","level":"INFO","fields":{"message":"SSTP HTTPS preamble accepted","id":"2","correlation_id":"{DEADBEEF-1234-5678-9ABC-DEF012345678}"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:38.952291Z","level":"WARN","fields":{"message":"TLS read failed","id":"2","error":"SSL error code 5: (no error queued)"},"target":"sstp_server::session"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:38.952398Z","level":"INFO","fields":{"message":"session ended","id":"2"},"target":"sstp_server::session"}
[INFO] [stdout] 
[INFO] [stdout] thread 'sstp_https_preamble' (5815) panicked at tests/e2e.rs:755:5:
[INFO] [stdout] expected '200 OK' from server preamble; got:
[INFO] [stdout] 
[INFO] [stdout] server logs:
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.948947Z","level":"INFO","fields":{"message":"sstp-server starting","version":"0.1.0 (adc7754 2026-06-20)","listen":"127.0.0.1:44525","io_threads":6,"auth_threads":2},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.949676Z","level":"INFO","fields":{"message":"RADIUS NAS-Identifier resolved","nas_identifier":"e02e281acc0b"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.949864Z","level":"INFO","fields":{"message":"data-path: tun (sstp kmod not loaded; falling back to /dev/net/tun)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.950956Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":0,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951037Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":1,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951126Z","level":"INFO","fields":{"message":"listener ready","worker":0,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951180Z","level":"INFO","fields":{"message":"listener ready","worker":1,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951311Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":2,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951337Z","level":"INFO","fields":{"message":"session accepted","id":"1","peer":"127.0.0.1:59116"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951445Z","level":"INFO","fields":{"message":"listener ready","worker":2,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951672Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":3,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951852Z","level":"INFO","fields":{"message":"listener ready","worker":3,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.952017Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":4,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.952155Z","level":"INFO","fields":{"message":"listener ready","worker":4,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.951954Z","level":"WARN","fields":{"message":"could not set I/O worker to SCHED_FIFO; staying on default scheduler","worker":5,"error":"Operation not permitted (os error 1)"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:36.952451Z","level":"INFO","fields":{"message":"listener ready","worker":5,"listen":"127.0.0.1:44525"},"target":"sstp_server"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:37.019324Z","level":"INFO","fields":{"message":"session accepted","id":"2","peer":"127.0.0.1:59128"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:37.131571Z","level":"WARN","fields":{"message":"TLS handshake failed","id":"1","error":"TLS handshake failed: SSL_do_handshake: code=5, (no error queued)"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:37.134621Z","level":"INFO","fields":{"message":"TLS handshake completed","id":"2"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:38.952072Z","level":"INFO","fields":{"message":"SSTP HTTPS preamble accepted","id":"2","correlation_id":"{DEADBEEF-1234-5678-9ABC-DEF012345678}"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:38.952291Z","level":"WARN","fields":{"message":"TLS read failed","id":"2","error":"SSL error code 5: (no error queued)"},"target":"sstp_server::session"}
[INFO] [stdout] {"timestamp":"2026-07-10T13:16:38.952398Z","level":"INFO","fields":{"message":"session ended","id":"2"},"target":"sstp_server::session"}
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x641cdbc62638 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x641cdbc62638 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::trace_unsynchronized::<std[10d71ac2c42b111e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x641cdbc62638 - std[10d71ac2c42b111e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x641cdbc62638 - <<std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[8fb95b77d25ab6c8]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x641cdbc7800a - <core[8fb95b77d25ab6c8]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x641cdbc7800a - core[8fb95b77d25ab6c8]::fmt::write
[INFO] [stdout]    6:     0x641cdbc67642 - std[10d71ac2c42b111e]::io::default_write_fmt::<alloc[a7ee25936407c584]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x641cdbc67642 - <alloc[a7ee25936407c584]::vec::Vec<u8> as std[10d71ac2c42b111e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x641cdbc3e616 - <std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x641cdbc3e616 - std[10d71ac2c42b111e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x641cdbc59da9 - std[10d71ac2c42b111e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x641cdb8b8f7c - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   12:     0x641cdb8b8f7c - test[213991da798b1c03]::test_main_inner::<test[213991da798b1c03]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x641cdbc5a022 - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   14:     0x641cdbc5a022 - std[10d71ac2c42b111e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x641cdbc3e6d2 - std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x641cdbc33749 - std[10d71ac2c42b111e]::sys::backtrace::__rust_end_short_backtrace::<std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x641cdbc3f5ad - __rustc[3be9ba60078d4c86]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x641cdbc787cc - core[8fb95b77d25ab6c8]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x641cdb8a2730 - e2e[5441ee29aae4699c]::sstp_https_preamble::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/e2e.rs:755:5
[INFO] [stdout]   20:     0x641cdb86b172 - <core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>> as core[8fb95b77d25ab6c8]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/future/future.rs:133:9
[INFO] [stdout]   21:     0x641cdb87116d - <tokio[cc22a21315432f95]::runtime::park::CachedParkThread>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/park.rs:284:71
[INFO] [stdout]   22:     0x641cdb86d732 - tokio[cc22a21315432f95]::task::coop::with_budget::<core[8fb95b77d25ab6c8]::task::poll::Poll<()>, <tokio[cc22a21315432f95]::runtime::park::CachedParkThread>::block_on<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/task/coop/mod.rs:167:5
[INFO] [stdout]   23:     0x641cdb86d732 - tokio[cc22a21315432f95]::task::coop::budget::<core[8fb95b77d25ab6c8]::task::poll::Poll<()>, <tokio[cc22a21315432f95]::runtime::park::CachedParkThread>::block_on<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/task/coop/mod.rs:133:5
[INFO] [stdout]   24:     0x641cdb86d732 - <tokio[cc22a21315432f95]::runtime::park::CachedParkThread>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/park.rs:284:31
[INFO] [stdout]   25:     0x641cdb828954 - <tokio[cc22a21315432f95]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   26:     0x641cdb892508 - <tokio[cc22a21315432f95]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/multi_thread/mod.rs:92:22
[INFO] [stdout]   27:     0x641cdb87d6bb - tokio[cc22a21315432f95]::runtime::context::runtime::enter_runtime::<<tokio[cc22a21315432f95]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   28:     0x641cdb88c0f4 - <tokio[cc22a21315432f95]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/multi_thread/mod.rs:91:9
[INFO] [stdout]   29:     0x641cdb831f14 - <tokio[cc22a21315432f95]::runtime::runtime::Runtime>::block_on_inner::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/runtime.rs:373:50
[INFO] [stdout]   30:     0x641cdb83210f - <tokio[cc22a21315432f95]::runtime::runtime::Runtime>::block_on::<core[8fb95b77d25ab6c8]::pin::Pin<&mut dyn core[8fb95b77d25ab6c8]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/runtime.rs:345:18
[INFO] [stdout]   31:     0x641cdb8ab244 - e2e[5441ee29aae4699c]::sstp_https_preamble
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/e2e.rs:785:6
[INFO] [stdout]   32:     0x641cdb89ebe7 - e2e[5441ee29aae4699c]::sstp_https_preamble::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/e2e.rs:700:31
[INFO] [stdout]   33:     0x641cdb8273f6 - <e2e[5441ee29aae4699c]::sstp_https_preamble::{closure#0} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   34:     0x641cdb8ac05b - <fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x641cdb8ac05b - test[213991da798b1c03]::__rust_begin_short_backtrace::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:724:18
[INFO] [stdout]   36:     0x641cdb8b98d5 - test[213991da798b1c03]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:74
[INFO] [stdout]   37:     0x641cdb8b98d5 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x641cdb8b98d5 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x641cdb8b98d5 - std[10d71ac2c42b111e]::panicking::catch_unwind::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x641cdb8b98d5 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x641cdb8b98d5 - test[213991da798b1c03]::run_test_in_process
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:27
[INFO] [stdout]   42:     0x641cdb8b98d5 - test[213991da798b1c03]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:668:43
[INFO] [stdout]   43:     0x641cdb8b31e4 - test[213991da798b1c03]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:698:41
[INFO] [stdout]   44:     0x641cdb8b31e4 - std[10d71ac2c42b111e]::sys::backtrace::__rust_begin_short_backtrace::<test[213991da798b1c03]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   45:     0x641cdb8bc952 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   46:     0x641cdb8bc952 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   47:     0x641cdb8bc952 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   48:     0x641cdb8bc952 - std[10d71ac2c42b111e]::panicking::catch_unwind::<(), core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   49:     0x641cdb8bc952 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   50:     0x641cdb8bc952 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   51:     0x641cdb8bc952 - <std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   52:     0x641cdbc6196f - <alloc[a7ee25936407c584]::boxed::Box<dyn core[8fb95b77d25ab6c8]::ops::function::FnOnce<(), Output = ()> + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2275:9
[INFO] [stdout]   53:     0x641cdbc6196f - <std[10d71ac2c42b111e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   54:     0x7ad9c3171aa4 - <unknown>
[INFO] [stdout]   55:     0x7ad9c31fea64 - clone
[INFO] [stdout]   56:                0x0 - <unknown>
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.003348Z","level":"INFO","fields":{"message":"received SIGTERM, shutting down"},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.003479Z","level":"INFO","fields":{"message":"accept loop draining","worker":1,"in_flight":0},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.003519Z","level":"INFO","fields":{"message":"accept loop exited","worker":1},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.003951Z","level":"INFO","fields":{"message":"accept loop draining","worker":0,"in_flight":1},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004001Z","level":"INFO","fields":{"message":"accept loop exited","worker":0},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004276Z","level":"INFO","fields":{"message":"accept loop draining","worker":5,"in_flight":1},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004318Z","level":"INFO","fields":{"message":"accept loop exited","worker":5},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004559Z","level":"INFO","fields":{"message":"accept loop draining","worker":4,"in_flight":0},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004597Z","level":"INFO","fields":{"message":"accept loop exited","worker":4},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004859Z","level":"INFO","fields":{"message":"accept loop draining","worker":3,"in_flight":0},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.004921Z","level":"INFO","fields":{"message":"accept loop exited","worker":3},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.039924Z","level":"INFO","fields":{"message":"accept loop draining","worker":2,"in_flight":0},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.040025Z","level":"INFO","fields":{"message":"accept loop exited","worker":2},"target":"sstp_server"}
[INFO] [stdout] [sstp-server] {"timestamp":"2026-07-10T13:16:39.040357Z","level":"INFO","fields":{"message":"sstp-server stopped"},"target":"sstp_server"}
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     sstp_https_preamble
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.42s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test e2e`
[INFO] running `Command { std: "docker" "inspect" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b", kill_on_drop: false }`
[INFO] [stdout] e02e281acc0b5e1a580460ed8c2ef39d695d36fdec033a550b125987d3c1485b
