[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.98.0-beta.1 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-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ogital-net/sstp-server on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "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.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b
[INFO] running `Command { std: "docker" "start" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", 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" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", 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" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "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 litemap v0.8.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling icu_normalizer_data v2.1.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling zerofrom v0.1.8
[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 icu_properties_data v2.1.2
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling log v0.4.30
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling clang-sys v1.8.1
[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 either v1.16.0
[INFO] [stderr]    Compiling bindgen v0.72.1
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling jobserver v0.1.34
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling cexpr v0.6.0
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling libz-sys v1.1.28
[INFO] [stderr]    Compiling libgit2-sys v0.18.4+1.9.3
[INFO] [stderr]    Compiling vergen-lib v9.1.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling time-core v0.1.7
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling cmake v0.1.58
[INFO] [stderr]    Compiling vergen v9.1.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling fs_extra v1.3.0
[INFO] [stderr]    Compiling dunce v1.0.5
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling vergen-git2 v9.1.0
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling radius-tokio-dict-codegen v0.1.0 (https://github.com/ogital-net/radius-tokio?branch=client#25a9b999)
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling time v0.3.45
[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 tokio-macros v2.7.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling endian-type v0.2.0
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling radix_trie v0.3.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling fast-md5 v1.0.0
[INFO] [stderr]    Compiling symlink v0.1.0
[INFO] [stderr]    Compiling home v0.5.12
[INFO] [stderr]    Compiling command-trie v1.1.0
[INFO] [stderr]    Compiling getopt-iter v1.0.2
[INFO] [stderr]    Compiling tracing-appender v0.2.5
[INFO] [stderr]    Compiling rustyline v18.0.0
[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 18s
[INFO] running `Command { std: "docker" "inspect" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", 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" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "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 39.88s
[INFO] running `Command { std: "docker" "inspect" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", 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" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.30s
[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::gigawords_emitted_when_high32_nonzero ... ok
[INFO] [stdout] test auth::accounting::tests::session_end_maps_to_terminate_cause ... ok
[INFO] [stdout] test auth::accounting::tests::stop_carries_terminate_cause_and_counters ... ok
[INFO] [stdout] test auth::accounting::tests::round_trip_against_one_shot_responder ... ok
[INFO] [stdout] test auth::bridge::tests::hlak_from_mppe_concatenates_recv_then_send ... ok
[INFO] [stdout] test auth::bridge::tests::hlak_from_mppe_returns_none_when_missing_or_wrong_size ... ok
[INFO] [stdout] test auth::coa::tests::coa_request_nak_with_error_cause ... ok
[INFO] [stdout] test auth::reply::tests::accept_round_trip ... ok
[INFO] [stdout] test auth::coa::tests::disconnect_request_ack_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::reply::tests::dns_and_nbns_servers_decoded ... ok
[INFO] [stdout] test auth::reply::tests::framed_mtu_and_netmask_decoded ... ok
[INFO] [stdout] test auth::reply::tests::framed_routes_empty_when_absent ... ok
[INFO] [stdout] test auth::reply::tests::framed_routes_collects_multiple_and_skips_garbage ... ok
[INFO] [stdout] test auth::reply::tests::malformed_mppe_key_yields_auth_error ... ok
[INFO] [stdout] test auth::reply::tests::mikrotik_rate_limit_garbage_yields_none ... 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::mschap_error_strips_leading_ident_byte ... 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::reply::tests::mikrotik_rate_limit_populates_shaping ... ok
[INFO] [stdout] test auth::request::tests::eap_fragments_at_253_bytes ... ok
[INFO] [stdout] test auth::request::tests::mschapv2_shape ... 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 cli::tests::bad_listen ... ok
[INFO] [stdout] test auth::route::tests::zero_gateway_means_via_user ... ok
[INFO] [stdout] test cli::tests::bad_log_format ... ok
[INFO] [stdout] test cli::tests::compute_threads_default_on_high_cpu_box ... ok
[INFO] [stdout] test cli::tests::compute_threads_default_on_small_box ... ok
[INFO] [stdout] test cli::tests::compute_threads_explicit_io_caps_auth ... ok
[INFO] [stdout] test cli::tests::compute_threads_explicit_auth_not_capped_by_default_io ... 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::help_exits ... ok
[INFO] [stdout] test cli::tests::long_log_format ... ok
[INFO] [stdout] test cli::tests::missing_cert ... ok
[INFO] [stdout] test cli::tests::minimum_required ... ok
[INFO] [stdout] test cli::tests::missing_local_ip ... ok
[INFO] [stdout] test cli::tests::missing_radius ... ok
[INFO] [stdout] test cli::tests::no_control_socket_short ... ok
[INFO] [stdout] test cli::tests::no_control_socket_long ... ok
[INFO] [stdout] test cli::tests::quiet_wins_over_verbose ... ok
[INFO] [stdout] test cli::tests::positional_rejected ... ok
[INFO] [stdout] test cli::tests::repeatable_radius ... ok
[INFO] [stdout] test cli::tests::short_auth_threads ... ok
[INFO] [stdout] test cli::tests::short_control_socket ... ok
[INFO] [stdout] test cli::tests::verbose_levels ... ok
[INFO] [stdout] test cli::tests::short_log_format ... 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_garbage_fourth_token_is_unknown ... ok
[INFO] [stdout] test control::tests::rekey_session_default_does_not_request_peer ... 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 auth::accounting::tests::start_packet_shape ... 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::shutdown_broadcasts ... ok
[INFO] [stdout] test control::tests::unknown_command_returns_error ... ok
[INFO] [stdout] test crypto::hash::tests::md5_abc ... ok
[INFO] [stdout] test crypto::hash::tests::sha256_abc ... 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_sha384_truncates ... ok
[INFO] [stdout] test crypto::ktls::tests::hkdf_expand_label_truncates ... ok
[INFO] [stdout] test crypto::rand::tests::empty_is_noop ... ok
[INFO] [stdout] test crypto::hash::tests::sha256_streaming_matches_oneshot ... 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::matching_ack_returns_to_idle ... ok
[INFO] [stdout] test crypto::rekey::tests::mismatched_ack_is_refused ... ok
[INFO] [stdout] test crypto::rekey::tests::new_session_ticket_is_ignored_and_resets_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 control::tests::unix_socket_round_trip ... ok
[INFO] [stdout] test crypto::tests::const_time_eq_basic ... ok
[INFO] [stdout] test auth::coa::tests::bad_secret_drops_silently ... ok
[INFO] [stdout] test crypto::rand::tests::produces_distinct_blocks ... ok
[INFO] [stdout] test auth::bridge::tests::pap_reject_carries_reply_message ... ok
[INFO] [stdout] test kppp::ioctl::tests::ioctl_numbers_match_kernel ... ok
[INFO] [stdout] test auth::client::tests::pap_reject_round_trip ... ok
[INFO] [stdout] test kppp::ioctl::tests::npioctl_size_is_two_ints ... ok
[INFO] [stdout] test auth::client::tests::pap_accept_round_trip ... ok
[INFO] [stdout] test auth::bridge::tests::pap_accept_projects_addrs ... 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_zero_prefix_default_gateway ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_add_route_with_gateway_uses_universe_scope ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_set_link_up_only_touches_iff_up ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_rejects_truncated_attribute ... 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_returns_none_for_empty ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_skips_unrelated_attrs_and_returns_payload ... ok
[INFO] [stdout] test kppp::netlink::tests::find_stats64_returns_none_when_absent ... ok
[INFO] [stdout] test kppp::netlink::tests::link_stats64_accepts_exactly_32_bytes ... 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::netlink::tests::link_stats64_parses_first_four_counters ... ok
[INFO] [stdout] test kppp::netlink::tests::link_stats64_rejects_short_payload ... 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 kppp::sstp_kmod::tests::recv_control_struct_is_16_bytes ... 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::unit::tests::create_and_drop_unit ... ignored, requires CAP_NET_ADMIN and the ppp_generic kernel module
[INFO] [stdout] test metrics::tests::render_stats_lists_every_metric ... ok
[INFO] [stdout] test kppp::sstp_kmod::tests::stats_struct_is_v03_size ... ok
[INFO] [stdout] test net::listener::tests::bind_two_listeners_same_port ... ok
[INFO] [stdout] test kppp::netlink::tests::encode_add_route_without_gateway_uses_link_scope ... ok
[INFO] [stdout] test net::listener::tests::dual_stack_v6_accepts_v4_client ... ok
[INFO] [stdout] test netlink::tests::drain_acks_consumes_seq_on_zero_err ... ok
[INFO] [stdout] test netlink::tests::drain_acks_surfaces_nonzero_err ... ok
[INFO] [stdout] test metrics::tests::counter_and_gauge_smoke ... 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 ppp::auth::tests::chap_round_trip_response ... ok
[INFO] [stdout] test netlink::tests::nlmsg_len_patched_on_finalize ... ok
[INFO] [stdout] test ppp::auth::tests::chap_decode_challenge ... ok
[INFO] [stdout] test ppp::auth::tests::chap_terminal_round_trip ... ok
[INFO] [stdout] test ppp::auth::tests::eap_decode_request_identity ... ok
[INFO] [stdout] test ppp::auth::tests::eap_round_trip_request ... 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_rejects_length_overflow ... ok
[INFO] [stdout] test ppp::auth::tests::pap_rejects_truncated_password ... ok
[INFO] [stdout] test ppp::auth::tests::pap_round_trip_ack ... ok
[INFO] [stdout] test ppp::auth::tests::pap_decode_authenticate_request ... ok
[INFO] [stdout] test ppp::driver::tests::ack_acceptable_lcp_cr ... ok
[INFO] [stdout] test ppp::driver::tests::auth_accept_acks_pap_and_starts_ipcp ... ok
[INFO] [stdout] test ppp::driver::tests::auth_reject_emits_nak_and_terminates ... ok
[INFO] [stdout] test ppp::driver::tests::ccp_configure_request_after_lcp_open_gets_protocol_reject ... ok
[INFO] [stdout] test ppp::driver::tests::ipcp_acks_matching_ip ... ok
[INFO] [stdout] test ppp::driver::tests::lcp_opened_transitions_to_auth_pending ... ok
[INFO] [stdout] test ppp::driver::tests::ipcp_naks_zero_ip_with_assigned ... 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::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 crypto::tls::tests::build_context_from_pem ... 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::driver::tests::rejects_unknown_lcp_option ... 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::fsm::tests::close_from_opened_sends_terminate_req ... ok
[INFO] [stdout] test ppp::frame::tests::rejects_bad_parity ... ok
[INFO] [stdout] test ppp::fsm::tests::close_from_reqsent_emits_terminate_req ... ok
[INFO] [stdout] test ppp::frame::tests::protocol_id_roundtrip ... ok
[INFO] [stdout] test ppp::fsm::tests::close_from_starting_finishes_immediately ... ok
[INFO] [stdout] test ppp::fsm::tests::closed_configure_request_emits_terminate_ack ... ok
[INFO] [stdout] test ppp::frame::tests::rejects_empty ... ok
[INFO] [stdout] test ppp::fsm::tests::closing_restart_timeout_retransmits_terminate_req ... ok
[INFO] [stdout] test ppp::fsm::tests::closed_rejects_unsolicited_acks_with_code_reject ... ok
[INFO] [stdout] test ppp::fsm::tests::closing_restart_timeout_to_minus_drops_to_closed ... 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::code_reject_catastrophic_in_reqsent_drops_to_stopped ... ok
[INFO] [stdout] test ppp::fsm::tests::echo_only_replies_when_opened ... ok
[INFO] [stdout] test ppp::fsm::tests::down_from_closed_returns_to_initial ... ok
[INFO] [stdout] test ppp::fsm::tests::happy_path_to_opened ... ok
[INFO] [stdout] test ppp::fsm::tests::code_reject_permitted_is_noop ... ok
[INFO] [stdout] test ppp::fsm::tests::identifier_bump_wraps ... 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::down_from_opened_returns_to_starting_with_notify ... ok
[INFO] [stdout] test ppp::fsm::tests::opened_terminate_request_goes_to_stopping ... ok
[INFO] [stdout] test ppp::fsm::tests::rcr_bad_emits_nak_or_rej ... ok
[INFO] [stdout] test ppp::fsm::tests::rcr_in_opened_renegotiates ... 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_ack_in_reqsent_advances_to_ackrcvd ... 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::restart_timeout_retransmits_until_exhausted ... ok
[INFO] [stdout] test ppp::fsm::tests::unknown_code_always_emits_code_reject ... ok
[INFO] [stdout] test ppp::ipcp::tests::code_round_trip ... ok
[INFO] [stdout] test ppp::ipcp::tests::decode_typical_windows_configure_request ... 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::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_configure_request_with_options ... ok
[INFO] [stdout] test ppp::lcp::tests::decode_zero_length_options ... ok
[INFO] [stdout] test ppp::lcp::tests::option_iter_surfaces_bad_length ... ok
[INFO] [stdout] test ppp::lcp::tests::rejects_length_below_header ... 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 privdrop::tests::lookup_self_user_succeeds ... ok
[INFO] [stdout] test session::tests::np_filter_drops_ip_pre_ipcp ... ok
[INFO] [stdout] test session::tests::broadcast_disconnect_delivers_to_all ... 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 session::tests::np_filter_forwards_ip_at_or_below_mtu ... 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::np_filter_drops_unknown_protocols_as_not_network_layer ... 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::decimal_with_suffix ... ok
[INFO] [stdout] test shape::mikrotik::tests::empty_value_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::case_insensitive_suffixes ... ok
[INFO] [stdout] test shape::mikrotik::tests::extra_fields_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::flat_symmetric_rate ... ok
[INFO] [stdout] test shape::mikrotik::tests::invalid_number_rejected ... ok
[INFO] [stdout] test shape::mikrotik::tests::full_six_field_form ... ok
[INFO] [stdout] test privdrop::tests::lookup_missing_user_reports_unknown ... ok
[INFO] [stdout] test shape::mikrotik::tests::priority_range_validated ... 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::mikrotik::tests::whitespace_normalised ... 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::mikrotik::tests::malformed_pair_rejected ... 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 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::iter_truncated_attribute ... ok
[INFO] [stdout] test sstp::attr::tests::write_attr_header_roundtrip ... 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_bad_mac ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_cert_hash_mismatch ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_nonce_mismatch ... ok
[INFO] [stdout] test sstp::binding::tests::rejects_unsupported_hash_protocol ... ok
[INFO] [stdout] test sstp::frame::tests::ignores_reserved_high_bits_of_length ... ok
[INFO] [stdout] test sstp::frame::tests::parse_control_packet_no_attrs ... 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_empty_messages ... 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_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::handshake_writes_200 ... 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::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_abort_in_abort_pending_skips_to_t2 ... ok
[INFO] [stdout] test sstp::state::tests::call_connected_with_bad_cert_aborts ... 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::messages_in_terminal_drain_are_ignored ... 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::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 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::handshake_read_write_export has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b", kill_on_drop: false }`
[INFO] [stdout] 6fe832ace5d18a701a416ef62805b044059a7fe2a91d8d729c0a061571cea29b
