[INFO] fetching crate mat-native 1.38.0...
[INFO] testing mat-native-1.38.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate mat-native 1.38.0 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate mat-native 1.38.0
[INFO] finished tweaking crates.io crate mat-native 1.38.0
[INFO] tweaked toml for crates.io crate mat-native 1.38.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate mat-native 1.38.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mat-native 1.38.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded custom_debug v0.6.2
[INFO] [stderr]   Downloaded custom_debug_derive v0.6.2
[INFO] [stderr]   Downloaded toml v1.1.5+spec-1.1.0
[INFO] [stderr]   Downloaded dbus-crossroads v0.5.3
[INFO] [stderr]   Downloaded ccm v0.6.1
[INFO] [stderr]   Downloaded mat-core v1.38.0
[INFO] [stderr]   Downloaded bluer v0.17.4
[INFO] [stderr]   Downloaded dbus v0.9.12
[INFO] [stderr]   Downloaded mat-controller v1.38.0
[INFO] [stderr]   Downloaded libdbus-sys v0.2.7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57
[INFO] running `Command { std: "docker" "start" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", 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" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", 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" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling cmov v0.5.4
[INFO] [stderr]    Compiling cpubits v0.1.1
[INFO] [stderr]    Compiling pem-rfc7468 v1.0.0
[INFO] [stderr]    Compiling base16ct v1.0.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml_writer v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling hybrid-array v0.4.15
[INFO] [stderr]    Compiling ff v0.14.0
[INFO] [stderr]    Compiling der v0.8.2
[INFO] [stderr]    Compiling group v0.14.0
[INFO] [stderr]    Compiling ctutils v0.4.2
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling inout v0.2.2
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling crypto-bigint v0.7.5
[INFO] [stderr]    Compiling toml v1.1.5+spec-1.1.0
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling cipher v0.5.2
[INFO] [stderr]    Compiling aead v0.6.1
[INFO] [stderr]    Compiling hmac v0.13.0
[INFO] [stderr]    Compiling signature v3.0.0
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling sha1 v0.11.0
[INFO] [stderr]    Compiling hkdf v0.13.0
[INFO] [stderr]    Compiling ctr v0.10.1
[INFO] [stderr]    Compiling spki v0.8.0
[INFO] [stderr]    Compiling sec1 v0.8.1
[INFO] [stderr]    Compiling ccm v0.6.1
[INFO] [stderr]    Compiling pkcs8 v0.11.0
[INFO] [stderr]    Compiling aes v0.9.3
[INFO] [stderr]    Compiling pbkdf2 v0.13.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling async-trait v0.1.92
[INFO] [stderr]    Compiling primefield v0.14.0
[INFO] [stderr]    Compiling elliptic-curve v0.14.1
[INFO] [stderr]    Compiling rfc6979 v0.6.0
[INFO] [stderr]    Compiling wnaf v0.14.1
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling primeorder v0.14.0
[INFO] [stderr]    Compiling ecdsa v0.17.0
[INFO] [stderr]    Compiling p256 v0.14.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling mat-core v1.38.0
[INFO] [stderr]    Compiling mat-controller v1.38.0
[INFO] [stderr]    Compiling mat-native v1.38.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 54.15s
[INFO] running `Command { std: "docker" "inspect" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", 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" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling mat-controller v1.38.0
[INFO] [stderr]    Compiling mat-native v1.38.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<usize>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/test_support.rs:69:10
[INFO] [stdout]    |
[INFO] [stdout] 69 |         .fetch_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stdout]    |          ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 69 -         .fetch_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stdout] 69 +         .try_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 36.37s
[INFO] running `Command { std: "docker" "inspect" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", 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" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: use of deprecated method `std::sync::atomic::Atomic::<usize>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stderr]   --> src/test_support.rs:69:10
[INFO] [stderr]    |
[INFO] [stderr] 69 |         .fetch_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stderr]    |          ^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(deprecated)]` on by default
[INFO] [stderr] help: replace the use of the deprecated method
[INFO] [stderr]    |
[INFO] [stderr] 69 -         .fetch_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stderr] 69 +         .try_update(Ordering::SeqCst, Ordering::SeqCst, |n| n.checked_sub(1))
[INFO] [stderr]    |
[INFO] [stderr] 
[INFO] [stderr] warning: `mat-native` (lib test) generated 1 warning (run `cargo fix --lib -p mat-native --tests` to apply 1 suggestion)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/mat_native-aba8d871ebd1bf13)
[INFO] [stdout] 
[INFO] [stdout] running 171 tests
[INFO] [stdout] test commission::tests::error_kind_mapping_follows_spec ... ok
[INFO] [stdout] test commission::tests::case_timeout_is_not_a_dead_end ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_qr_miss_is_ble_only ... ok
[INFO] [stdout] test commission::tests::plan_routes_ble_manual_is_internal_error ... ok
[INFO] [stdout] test commission::tests::dead_end_is_limited_to_pase_and_predelivery ... ok
[INFO] [stdout] test commission::tests::device_rejection_is_not_a_dead_end ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_qr_hit_omits_ble_when_it_cannot_run ... ok
[INFO] [stdout] test commission::tests::kind_of_splits_timeout_and_rejection_out_of_commission_failed ... ok
[INFO] [stdout] test commission::tests::manual_code_short_filter_selects_unique_match ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_qr_hit_falls_back_to_ble ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_manual_hit_is_on_network_only ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_manual_miss_is_unreachable_with_qr_hint ... ok
[INFO] [stdout] test commission::tests::post_pase_timeouts_are_not_dead_ends ... ok
[INFO] [stdout] test commission::tests::manual_code_short_filter_rejects_ambiguous ... ok
[INFO] [stdout] test commission::tests::multi_route_failure_lists_every_route ... ok
[INFO] [stdout] test commission::tests::plan_routes_ble_qr_skips_mdns_entirely ... ok
[INFO] [stdout] test commission::tests::plan_routes_auto_qr_miss_still_plans_ble_when_it_cannot_run ... ok
[INFO] [stdout] test commission::tests::plan_routes_on_network_manual_miss_is_unreachable_with_qr_hint ... ok
[INFO] [stdout] test commission::tests::plan_routes_on_network_qr_hit_never_includes_ble ... ok
[INFO] [stdout] test commission::tests::plan_routes_on_network_qr_miss_is_unreachable ... ok
[INFO] [stdout] test commission::tests::run_plan_advances_to_the_next_route_on_a_dead_end ... ok
[INFO] [stdout] test commission::tests::run_plan_composes_every_failure_and_keeps_the_last_kind ... ok
[INFO] [stdout] test commission::tests::short_detail_strips_known_prefixes ... ok
[INFO] [stdout] test errmap::tests::invalid_argument_maps_to_parse_error ... ok
[INFO] [stdout] test errmap::tests::map_establish_err_wordings_per_role ... ok
[INFO] [stdout] test errmap::tests::map_session_err_splits_im_decode_failure_from_device_rejection ... ok
[INFO] [stdout] test commission::tests::run_plan_stops_at_the_first_route_when_it_is_not_a_dead_end ... ok
[INFO] [stdout] test errmap::tests::resolve_timeout_maps_to_timeout_kind ... ok
[INFO] [stdout] test commission::tests::single_route_failure_is_reported_verbatim ... ok
[INFO] [stdout] test commission::tests::resolve_ipk_epoch_rejects_operational_unrelated_to_default ... ok
[INFO] [stdout] test commission::tests::run_plan_stops_on_success_without_touching_later_routes ... ok
[INFO] [stdout] test commission::tests::resolve_ipk_epoch_reads_persisted_epoch_without_rewriting ... ok
[INFO] [stdout] test commission::tests::resolve_ipk_epoch_adopts_and_persists_default_when_key_absent ... ok
[INFO] [stdout] test group::tests::group_send_error_maps_io_to_unreachable_and_crypto_to_other ... ok
[INFO] [stdout] test errmap::tests::map_session_err_maps_malformed_message_to_parse_error ... ok
[INFO] [stdout] test iface_select::tests::excludes_down_carrier_and_missing_ll ... ok
[INFO] [stdout] test iface_select::tests::multiple_candidates_is_ambiguous_and_lists_names ... ok
[INFO] [stdout] test iface_select::tests::scan_lists_loopback_with_index ... ok
[INFO] [stdout] test iface_select::tests::selects_single_ethernet ... ok
[INFO] [stdout] test iface_select::tests::thread_iface_ambiguous_wpan_is_none ... ok
[INFO] [stdout] test group::tests::group_bump_without_gdc_is_unavailable ... ok
[INFO] [stdout] test iface_select::tests::thread_iface_detects_single_wpan ... ok
[INFO] [stdout] test iface_select::tests::excludes_pointopoint_tailscale ... ok
[INFO] [stdout] test iface_select::tests::thread_iface_down_wpan_is_none ... ok
[INFO] [stdout] test iface_select::tests::thread_iface_requires_wpan_prefix ... ok
[INFO] [stdout] test group_settings::tests::locked_kvs_is_hard_error_not_fallback_shaped ... ok
[INFO] [stdout] test group::tests::group_invoke_sends_multicast_and_reports_sent ... FAILED
[INFO] [stdout] test group::tests::late_thread_egress_acquired_and_skipped_correctly ... FAILED
[INFO] [stdout] test op::tests::arg_value_conversions ... ok
[INFO] [stdout] test iface_select::tests::zero_candidates_is_error ... ok
[INFO] [stdout] test op::tests::color_color_temp_level_send_expected_commands ... ok
[INFO] [stdout] test op::tests::budget_applies_only_to_single_node_hotpath_ops ... ok
[INFO] [stdout] test op::tests::color_temp_and_level_constructors_convert_units ... ok
[INFO] [stdout] test op::tests::conn_error_propagates_unchanged ... ok
[INFO] [stdout] test op::tests::describe_diag_thread_and_open_window_build_bodies ... ok
[INFO] [stdout] test op::tests::encode_command_fields_uses_positional_context_tags ... ok
[INFO] [stdout] test op::tests::generic_acl_encoding_matches_dedicated_encoder ... ok
[INFO] [stdout] test op::tests::generic_group_key_map_encoding_matches_dedicated_encoder ... ok
[INFO] [stdout] test op::tests::generic_key_set_write_encoding_matches_dedicated_encoder ... ok
[INFO] [stdout] test op::tests::group_op_hard_errors_when_engine_group_ctx_unconfigured ... ok
[INFO] [stdout] test op::tests::group_invoke_resolves_like_node_invoke ... ok
[INFO] [stdout] test group_settings::tests::write_group_provision_writes_kvs_and_maps_duplicate_to_other ... ok
[INFO] [stdout] test op::tests::invoke_generic_forwards_ids_and_builds_body ... ok
[INFO] [stdout] test op::tests::invoke_scalar_args_ok_struct_args_rejected ... ok
[INFO] [stdout] test op::tests::kelvin_2700_converts_to_370_mireds ... ok
[INFO] [stdout] test op::tests::group_wire_and_sent_body_for_shortcuts ... ok
[INFO] [stdout] test op::tests::names_are_snake_case_wire_tags ... ok
[INFO] [stdout] test group::tests::group_bump_initializes_lazily_and_jumps_forward ... ok
[INFO] [stdout] test group_settings::tests::remove_group_reports_keyset_removal_and_maps_unknown_to_other ... ok
[INFO] [stdout] test op::tests::on_off_invoke_onoff_and_build_invoke_body ... ok
[INFO] [stdout] test op::tests::put_value_encodes_list_of_struct_as_tlv_array_and_roundtrips_to_read_json ... ok
[INFO] [stdout] test op::tests::node_and_group_shortcuts_share_wire ... ok
[INFO] [stdout] test op::tests::read_onoff_and_generic_read_both_use_json ... ok
[INFO] [stdout] test op::tests::mireds_direct_computes_kelvin_echo ... ok
[INFO] [stdout] test op::tests::kelvin_6500_rounds_to_154_mireds ... ok
[INFO] [stdout] test op::tests::read_onoff_non_bool_is_parse_error ... ok
[INFO] [stdout] test op::tests::group_bump_advances_counter_via_engine ... ok
[INFO] [stdout] test op::tests::read_cluster_maps_rows_to_attributes_object ... ok
[INFO] [stdout] test op::tests::remove_fabric_name_and_budget ... ok
[INFO] [stdout] test op::tests::resolve_level_rounds_percent_to_254_scale ... ok
[INFO] [stdout] test op::tests::timed_override_forces_true_but_never_false ... ok
[INFO] [stdout] test op::tests::read_cluster_unknown_name_is_unresolved_op ... ok
[INFO] [stdout] test op::tests::remove_fabric_reads_current_index_then_invokes_and_reports_it ... ok
[INFO] [stdout] test ops::tests::describe_walks_parts_and_server_lists ... ok
[INFO] [stdout] test op::tests::remove_fabric_non_zero_status_is_device_rejected ... ok
[INFO] [stdout] test op::tests::read_resolves_names_and_numeric_ids ... ok
[INFO] [stdout] test ops::tests::ensure_group_acl_is_idempotent_when_entry_exists ... ok
[INFO] [stdout] test ops::tests::thread_identity_none_without_thread_interface ... ok
[INFO] [stdout] test op::tests::write_encodes_scalar_tlv_and_echoes_normalized_value ... ok
[INFO] [stdout] test ops::tests::thread_check_from_snapshot_errs_when_neighbor_table_missing ... ok
[INFO] [stdout] test ops::tests::remove_group_node_runs_four_steps_and_removes_unreferenced_keyset ... ok
[INFO] [stdout] test op::tests::write_scalar_ok_bad_json_shape_rejected_unknown_unresolved ... ok
[INFO] [stdout] test ops::tests::diag_thread_includes_leader_and_ml_prefix_scalars ... ok
[INFO] [stdout] test ops::tests::thread_identity_none_on_non_array ... ok
[INFO] [stdout] test ops::tests::thread_identity_picks_thread_interface ... ok
[INFO] [stdout] test ops::tests::decode_response_status_flat_and_nested ... ok
[INFO] [stdout] test ops::tests::thread_check_from_snapshot_computes_max_lqi_and_routing_role ... ok
[INFO] [stdout] test resolver::tests::cache_miss_timeout_is_pinned ... ok
[INFO] [stdout] test ops::tests::write_ipk_keyset_prefixes_error_with_step_name ... ok
[INFO] [stdout] test ops::tests::diag_thread_maps_names_and_partial_results ... ok
[INFO] [stdout] test ops::tests::remove_group_node_keeps_keyset_still_referenced_and_tolerates_not_found ... ok
[INFO] [stdout] test ops::tests::remove_group_node_never_writes_acl_when_read_fails ... ok
[INFO] [stdout] test ops::tests::diag_thread_propagates_err_when_read_cluster_fails ... ok
[INFO] [stdout] test ops::tests::write_ipk_keyset_invokes_key_set_write_on_ep0_with_keyset_0 ... ok
[INFO] [stdout] test ops::tests::provision_node_preserves_other_groups_mappings ... ok
[INFO] [stdout] test ops::tests::provision_node_replaces_existing_mapping_for_same_group ... ok
[INFO] [stdout] test ops::tests::provision_node_runs_steps_in_order ... ok
[INFO] [stdout] test rotate_ipk::tests::read_slot_permission_denied_is_other ... ok
[INFO] [stdout] test rotate_ipk::tests::read_slot_missing_ini_is_store_missing ... ok
[INFO] [stdout] test resolver::tests::caching_resolver_awaits_listener_fill_then_returns ... ok
[INFO] [stdout] test resolver::tests::caching_resolver_returns_cached_hit_immediately ... ok
[INFO] [stdout] test resolver::tests::caching_resolver_times_out_when_never_filled ... ok
[INFO] [stdout] test resolver::tests::oneshot_resolver_times_out_without_responder ... ok
[INFO] [stdout] test runner::tests::grant_reports_updated_nodes ... ok
[INFO] [stdout] test rotate_ipk::tests::read_slot_bad_bytes_is_store_parse_with_slot_name ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_verify_case_failure_is_failed_node_with_step_name ... ok
[INFO] [stdout] test rotate_ipk::tests::catch_up_uses_prev_to_write_and_cur_to_verify ... ok
[INFO] [stdout] test rotate_ipk::tests::catch_up_failure_is_incomplete_with_partial_error ... ok
[INFO] [stdout] test runner::tests::one_shot_closes_and_calls_after_close_on_success ... ok
[INFO] [stdout] test runner::tests::one_shot_closes_on_failure_and_does_not_retry ... ok
[INFO] [stdout] test runner::tests::provision_hard_errors_when_group_settings_ctx_missing ... ok
[INFO] [stdout] test rotate_ipk::tests::catch_up_key_set_write_carries_prev_at_1_and_current_at_2 ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_key_set_write_rejection_is_failed_node ... ok
[INFO] [stdout] test runner::tests::remove_group_hard_errors_when_group_settings_ctx_missing ... ok
[INFO] [stdout] test runner::tests::provision_rejects_keyset_zero_before_kvs_and_nodes ... ok
[INFO] [stdout] test test_support::close_tests::fake_sub_conn_records_close ... ok
[INFO] [stdout] test runner::tests::remove_group_reports_false_when_controller_kvs_lacks_the_group ... ok
[INFO] [stdout] test rotate_ipk::tests::catch_up_without_prev_or_while_pending_is_other ... ok
[INFO] [stdout] test test_support::close_tests::fake_conn_records_close ... ok
[INFO] [stdout] test test_support::tests::fake_conn_invoke_for_data_returns_registered_tlv_and_records_call ... ok
[INFO] [stdout] test tests::build_fails_cleanly_without_kvs ... ok
[INFO] [stdout] test test_support::gate_tests::fail_at_applies_to_invoke_for_data ... ok
[INFO] [stdout] test rotate_ipk::tests::abort_clears_pending_and_is_idle_otherwise ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_key_set_write_carries_current_at_1_and_next_at_2 ... ok
[INFO] [stdout] test tests::case_establisher_reload_maps_missing_store_to_store_missing ... ok
[INFO] [stdout] test tests::case_establisher_swap_reports_ipk_change ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_all_ok_commits_and_records_prev ... ok
[INFO] [stdout] test tests::check_identity_accepts_same_fabric_and_rejects_changes ... ok
[INFO] [stdout] test runner::tests::provision_prefixes_node_errors_with_node_id ... ok
[INFO] [stdout] test tests::default_establisher_rejects_subscription ... ok
[INFO] [stdout] test runner::tests::remove_group_stops_at_first_node_failure_and_leaves_controller_kvs ... ok
[INFO] [stdout] test tests::establisher_reload_is_unsupported_by_default ... ok
[INFO] [stdout] test tests::fake_establisher_fails_subscription_n_times_then_succeeds ... ok
[INFO] [stdout] test rotate_ipk::tests::run_abort_mode_does_not_require_credentials ... ok
[INFO] [stdout] test tests::dedicated_op_socket::concurrent_establishes_use_dedicated_sockets ... ok
[INFO] [stdout] test runner::tests::remove_group_reports_per_node_steps_and_drops_controller_state ... ok
[INFO] [stdout] test tests::fake_sub_conn_records_event_scope_and_serves_priming_events ... ok
[INFO] [stdout] test runner::tests::provision_writes_controller_state_and_builds_body_with_note ... ok
[INFO] [stdout] test tests::generic_read_write_via_fake ... ok
[INFO] [stdout] test tests::load_fabric_credentials_maps_missing_store_to_store_missing ... ok
[INFO] [stdout] test tests::load_self_issue_materials_hints_fabric_init_on_missing_store ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_with_one_failure_stays_pending_and_is_resumable ... ok
[INFO] [stdout] test tests::op_scope_id_maps_unknown_iface_to_other ... ok
[INFO] [stdout] test tests::thread_egress_none_is_lan_only ... ok
[INFO] [stdout] test tests::thread_egress_explicit_failure_is_hard_error ... ok
[INFO] [stdout] test tests::thread_egress_same_as_op_iface_is_skipped_without_resolving_auto ... ok
[INFO] [stdout] test tests::thread_egress_resolved_returns_scope ... ok
[INFO] [stdout] test tests::thread_egress_auto_failure_degrades_to_lan_only ... ok
[INFO] [stdout] test tests::thread_egress_same_as_op_iface_is_skipped_without_resolving_explicit ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_with_no_nodes_commits_immediately ... ok
[INFO] [stdout] test tests::case_establisher_reload_over_real_kvs_is_false_when_unchanged ... ok
[INFO] [stdout] test tests::case_establisher_reload_over_real_kvs_picks_up_a_rotated_ipk ... ok
[INFO] [stdout] test tests::case_establisher_reload_over_real_kvs_rejects_a_different_fabric ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_per_node_timeout_closes_established_conn ... ok
[INFO] [stdout] test test_support::delay_tests::conn_delay_delays_send_ops ... ok
[INFO] [stdout] test test_support::delay_tests::establish_delay_delays_establish ... ok
[INFO] [stdout] test tests::fake_sub_conn_next_report_fails_when_injected_after_establish ... ok
[INFO] [stdout] test tests::fake_establisher_serves_scripted_subscription ... ok
[INFO] [stdout] test tests::fake_sub_conn_next_report_full_serves_live_events ... ok
[INFO] [stdout] test rotate_ipk::tests::rotate_per_node_timeout_marks_node_timeout_and_continues ... ok
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] test tests::dedicated_op_socket::swapped_credentials_are_used_by_the_next_establish ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- group::tests::group_invoke_sends_multicast_and_reports_sent stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'group::tests::group_invoke_sends_multicast_and_reports_sent' (1285) panicked at src/group.rs:316:9:
[INFO] [stdout] no multicast-capable interface delivered a loopback groupcast datagram (lo excluded — it lacks IFF_MULTICAST on Linux); tried: []
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5609f8100c21 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5609f811942a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5609f810580c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5609f80d9976 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5609f80f7539 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5609f7fe70f0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5609f80f7862 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5609f80d9a22 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5609f80d2349 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5609f80da9bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5609f8119b8c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5609f7fb80ad - mat_native[dabeafae46f8f4ce]::group::tests::group_invoke_sends_multicast_and_reports_sent::{closure#0}
[INFO] [stdout]   12:     0x5609f7f481c3 - <tokio[fa756688d2426641]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   13:     0x5609f7f5da3c - <tokio[fa756688d2426641]::runtime::context::scoped::Scoped<tokio[fa756688d2426641]::runtime::scheduler::Context>>::set::<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[fa756688d2426641]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   14:     0x5609f7f484d5 - <tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   15:     0x5609f7fb2073 - tokio[fa756688d2426641]::runtime::context::runtime::enter_runtime::<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5609f7fa32db - <tokio[fa756688d2426641]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   17:     0x5609f7fb9c88 - mat_native[dabeafae46f8f4ce]::group::tests::group_invoke_sends_multicast_and_reports_sent
[INFO] [stdout]   18:     0x5609f7f1fca9 - <mat_native[dabeafae46f8f4ce]::group::tests::group_invoke_sends_multicast_and_reports_sent::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5609f7fda3db - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5609f7fe7a45 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5609f7fe1304 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5609f7feaba2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5609f80ff8c9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x757403523dfa - <unknown>
[INFO] [stdout]   25:     0x7574035b73d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- group::tests::late_thread_egress_acquired_and_skipped_correctly stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'group::tests::late_thread_egress_acquired_and_skipped_correctly' (1287) panicked at src/group.rs:375:13:
[INFO] [stdout] no multicast-capable interface
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5609f8100c21 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5609f811942a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5609f810580c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5609f80d9976 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5609f80f7539 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5609f7fe70f0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5609f80f7862 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5609f80d9a54 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5609f80d2349 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5609f80da9bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5609f8119b8c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5609f7fb985b - mat_native[dabeafae46f8f4ce]::group::tests::late_thread_egress_acquired_and_skipped_correctly::{closure#0}
[INFO] [stdout]   12:     0x5609f7f481c3 - <tokio[fa756688d2426641]::runtime::scheduler::current_thread::Context>::enter::<core[df12db4294e9bfd3]::task::poll::Poll<()>, <tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]   13:     0x5609f7f5da3c - <tokio[fa756688d2426641]::runtime::context::scoped::Scoped<tokio[fa756688d2426641]::runtime::scheduler::Context>>::set::<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>::{closure#0}, core[df12db4294e9bfd3]::option::Option<()>>::{closure#0}, (alloc[2182bb758b4b3781]::boxed::Box<tokio[fa756688d2426641]::runtime::scheduler::current_thread::Core>, core[df12db4294e9bfd3]::option::Option<()>)>
[INFO] [stdout]   14:     0x5609f7f484d5 - <tokio[fa756688d2426641]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>>
[INFO] [stdout]   15:     0x5609f7fb2073 - tokio[fa756688d2426641]::runtime::context::runtime::enter_runtime::<<tokio[fa756688d2426641]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5609f7fa32db - <tokio[fa756688d2426641]::runtime::runtime::Runtime>::block_on::<core[df12db4294e9bfd3]::pin::Pin<&mut dyn core[df12db4294e9bfd3]::future::future::Future<Output = ()>>>
[INFO] [stdout]   17:     0x5609f7fb9f08 - mat_native[dabeafae46f8f4ce]::group::tests::late_thread_egress_acquired_and_skipped_correctly
[INFO] [stdout]   18:     0x5609f7f1fce9 - <mat_native[dabeafae46f8f4ce]::group::tests::late_thread_egress_acquired_and_skipped_correctly::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5609f7fda3db - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5609f7fe7a45 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5609f7fe1304 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5609f7feaba2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5609f80ff8c9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x757403523dfa - <unknown>
[INFO] [stdout]   25:     0x7574035b73d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     group::tests::group_invoke_sends_multicast_and_reports_sent
[INFO] [stdout]     group::tests::late_thread_egress_acquired_and_skipped_correctly
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 169 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.95s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57", kill_on_drop: false }`
[INFO] [stdout] aa20d7a7da51c739546f10567d1421ef78a91382bec5027dc1767d933f400f57
