[INFO] fetching crate iec61850-rs 0.3.0...
[INFO] testing iec61850-rs-0.3.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate iec61850-rs 0.3.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate iec61850-rs 0.3.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate iec61850-rs 0.3.0
[INFO] tweaked toml for crates.io crate iec61850-rs 0.3.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate iec61850-rs 0.3.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate iec61850-rs 0.3.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.100.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-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952
[INFO] running `Command { std: "docker" "start" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", 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" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", 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" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling roxmltree v0.21.1
[INFO] [stderr]    Compiling iec61850-rs v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 41.47s
[INFO] running `Command { std: "docker" "inspect" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", 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" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling iec61850-rs v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 41s
[INFO] running `Command { std: "docker" "inspect" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", 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" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/iec61850-rs/20347d4cee5090c6/out/iec61850_rs-20347d4cee5090c6)
[INFO] [stdout] 
[INFO] [stdout] running 259 tests
[INFO] [stdout] test ber::writer::tests::fixed_width_fields_round_trip_through_the_lenient_reader ... ok
[INFO] [stdout] test ber::reader::tests::integers_and_floats ... ok
[INFO] [stdout] test ber::reader::tests::high_tag_numbers ... ok
[INFO] [stdout] test client::control::tests::a_reference_becomes_the_control_object_under_co ... ok
[INFO] [stdout] test ber::reader::tests::a_tag_number_that_does_not_fit_is_refused_rather_than_wrapped ... ok
[INFO] [stdout] test ber::writer::tests::constructed_with_long_length ... ok
[INFO] [stdout] test ber::writer::tests::minimal_integers ... ok
[INFO] [stdout] test client::control::tests::a_cancel_is_the_request_without_its_check ... ok
[INFO] [stdout] test client::rcb::tests::a_reference_normalises_from_either_spelling ... ok
[INFO] [stdout] test client::rcb::tests::settings_are_written_by_name_and_only_when_set ... ok
[INFO] [stdout] test client::control::tests::the_host_clock_produces_a_plausible_timestamp ... ok
[INFO] [stdout] test client::log::tests::a_control_block_reference_must_name_the_log_constraint ... ok
[INFO] [stdout] test client::tests::a_closed_reason_becomes_an_error_a_human_can_read ... ok
[INFO] [stdout] test client::sg::tests::a_reference_normalises_to_the_control_block_under_sp ... ok
[INFO] [stdout] test client::tests::a_host_without_a_port_gets_102 ... ok
[INFO] [stdout] test ber::writer::tests::fixed_width_and_floats ... ok
[INFO] [stdout] test ber::reader::tests::reads_short_and_long_lengths ... ok
[INFO] [stdout] test client::tests::a_data_set_name_splits_into_the_domain_and_the_list ... ok
[INFO] [stdout] test ber::reader::tests::rejects_malformed ... ok
[INFO] [stdout] test client::log::tests::a_log_reference_splits_into_the_journal_a_server_knows ... ok
[INFO] [stdout] test client::rcb::tests::a_buffered_block_has_different_attributes_from_an_unbuffered_one ... ok
[INFO] [stdout] test common::quality::tests::round_trip_bits ... ok
[INFO] [stdout] test common::reference::tests::a_controllable_object_can_be_asked_for_its_configuration ... ok
[INFO] [stdout] test client::tests::a_host_without_a_port_gets_one_whatever_family_it_is ... ok
[INFO] [stdout] test common::machine::tests::instant_saturates ... ok
[INFO] [stdout] test common::machine::tests::queue_drops_oldest_and_counts ... ok
[INFO] [stdout] test common::service::tests::a_refused_service_keeps_its_meaning_across_the_two_tables ... ok
[INFO] [stdout] test common::reference::tests::dotted_and_mms_forms ... ok
[INFO] [stdout] test common::reference::tests::the_mms_mapping_puts_the_functional_constraint_after_the_logical_node ... ok
[INFO] [stdout] test common::time::tests::a_nanosecond_field_that_overflows_carries_into_the_second ... ok
[INFO] [stdout] test common::time::tests::epoch_display ... ok
[INFO] [stdout] test common::time::tests::entry_time_counts_from_1984_not_from_1970 ... ok
[INFO] [stdout] test common::time::tests::octets_round_trip ... ok
[INFO] [stdout] test proto::data::tests::encode_decode_round_trip ... ok
[INFO] [stdout] test proto::data::tests::dbpos_keeps_the_two_states_a_bool_would_lose ... ok
[INFO] [stdout] test common::service::tests::a_tracking_cdc_is_recognised_by_the_cdc_the_file_declares ... ok
[INFO] [stdout] test common::service::tests::the_lists_are_the_standards_lists ... ok
[INFO] [stdout] test common::time::tests::nanos_precision_is_within_one_lsb ... ok
[INFO] [stdout] test model::supervision_tests::a_supervision_node_matches_its_control_block_in_either_spelling ... ok
[INFO] [stdout] test proto::data::tests::limits_are_enforced ... ok
[INFO] [stdout] test proto::data::tests::typed_accessors_read_what_is_there_and_nothing_else ... ok
[INFO] [stdout] test proto::ethernet::tests::header_round_trip_with_vlan ... ok
[INFO] [stdout] test proto::ethernet::tests::mac_parse_display ... ok
[INFO] [stdout] test proto::goose::apdu::tests::a_constructed_security_extension_is_accepted ... ok
[INFO] [stdout] test proto::goose::apdu::tests::member_count_is_checked_against_the_declaration ... ok
[INFO] [stdout] test proto::goose::apdu::tests::truncation_is_an_error_not_a_panic ... ok
[INFO] [stdout] test proto::ethernet::tests::rejects_non_process_bus ... ok
[INFO] [stdout] test proto::goose::apdu::tests::optional_defaults_may_be_omitted_on_the_wire ... ok
[INFO] [stdout] test proto::goose::apdu::tests::round_trip ... ok
[INFO] [stdout] test proto::goose::publisher::tests::counters_and_scheduling ... ok
[INFO] [stdout] test proto::goose::publisher::tests::st_num_wraps_to_one_never_zero ... ok
[INFO] [stdout] test pcap::tests::rejects_what_it_cannot_read ... ok
[INFO] [stdout] test proto::goose::publisher::tests::simulation_sets_both_flags ... ok
[INFO] [stdout] test proto::goose::publisher::tests::publishing_unchanged_values_is_not_a_state_change ... ok
[INFO] [stdout] test proto::ethernet::tests::the_address_is_readable_even_when_the_frame_is_not ... ok
[INFO] [stdout] test proto::goose::publisher::tests::uncollected_frames_are_counted_not_queued ... ok
[INFO] [stdout] test pcap::tests::round_trip ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::every_rejection_moves_a_counter ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::expiry_fires_once ... ok
[INFO] [stdout] test proto::goose::publisher::tests::the_curve_matches_t1_t1_t2_t3_t0 ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::other_traffic_and_malformed_frames ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::a_restarted_publisher_is_accepted_once_the_state_expired ... ok
[INFO] [stdout] test proto::goose::publisher::tests::non_ascii_configuration_is_rejected_at_construction ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::a_malformed_frame_belonging_to_someone_else_is_other_traffic ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::a_restarted_publisher_is_not_locked_out_by_its_own_sqnum ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::counters_may_wrap ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::conf_rev_mismatch_is_edge_triggered ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::needs_commissioning_is_edge_triggered ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::simulation_preferred_takes_over_and_then_ignores_the_real_stream ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::the_event_queue_is_bounded ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::replays_are_rejected_while_the_state_is_live ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::the_ids_delta_features_come_out_of_the_verdict ... ok
[INFO] [stdout] test proto::mms::alternate::tests::the_octets_a_second_stack_writes_for_an_array_element ... ok
[INFO] [stdout] test proto::mms::association::tests::a_client_may_not_exceed_the_budget_the_server_agreed_to ... ok
[INFO] [stdout] test proto::mms::alternate::tests::a_selection_that_picks_two_things_at_once_is_refused ... ok
[INFO] [stdout] test proto::mms::alternate::tests::a_range_and_all_elements_round_trip_at_both_depths ... ok
[INFO] [stdout] test proto::mms::association::tests::a_reject_answers_the_request_it_names_and_releases_it ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::simulation_off_ignores_simulated_frames_and_reports_once ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::state_changes_that_never_arrived_are_counted ... ok
[INFO] [stdout] test proto::mms::association::tests::a_reject_without_an_invoke_identifier_is_still_reported ... ok
[INFO] [stdout] test proto::goose::subscriber::tests::states_and_retransmissions ... ok
[INFO] [stdout] test proto::mms::alternate::tests::a_chain_deeper_than_the_limit_is_refused ... ok
[INFO] [stdout] test proto::mms::association::tests::a_release_the_peer_never_confirms_is_still_a_release ... ok
[INFO] [stdout] test proto::mms::association::tests::a_disconnect_request_names_the_reference_the_peer_issued ... ok
[INFO] [stdout] test proto::mms::association::tests::a_handshake_that_never_finishes_expires ... ok
[INFO] [stdout] test proto::mms::association::tests::a_password_travels_in_the_aarq_where_the_server_looks_for_it ... ok
[INFO] [stdout] test proto::mms::association::tests::a_request_that_is_never_answered_times_out_and_frees_its_slot ... ok
[INFO] [stdout] test proto::mms::association::tests::a_stream_that_is_not_the_protocol_closes_the_association_rather_than_looping ... ok
[INFO] [stdout] test proto::mms::association::tests::services_supported_names_exactly_the_services_that_exist ... ok
[INFO] [stdout] test proto::mms::association::tests::the_outstanding_call_budget_is_negotiated_down_from_both_sides ... ok
[INFO] [stdout] test proto::mms::association::tests::invoke_identifiers_do_not_collide_after_a_wrap ... ok
[INFO] [stdout] test proto::mms::association::tests::the_clients_budget_is_the_calling_one_and_the_servers_is_the_called_one ... ok
[INFO] [stdout] test proto::mms::association::tests::an_answer_that_does_not_decode_fails_its_call_rather_than_hanging ... ok
[INFO] [stdout] test proto::mms::association::tests::the_outstanding_limit_is_enforced_here_rather_than_discovered_as_a_reject ... ok
[INFO] [stdout] test proto::mms::association::tests::the_two_ends_complete_the_six_layer_handshake ... ok
[INFO] [stdout] test proto::mms::control::tests::a_termination_is_tied_to_its_command_by_ctl_num ... ok
[INFO] [stdout] test proto::mms::control::tests::a_cancel_is_an_oper_without_the_check ... ok
[INFO] [stdout] test proto::mms::association::tests::a_report_arrives_unsolicited ... ok
[INFO] [stdout] test proto::mms::association::tests::a_tsdu_larger_than_a_tpdu_is_segmented_and_reassembled ... ok
[INFO] [stdout] test proto::mms::association::tests::a_request_reaches_the_server_and_its_answer_comes_back ... ok
[INFO] [stdout] test proto::mms::association::tests::an_orderly_release_ends_both_ends ... ok
[INFO] [stdout] test proto::mms::control::tests::an_oper_from_a_device_without_ctl_num_still_decodes ... ok
[INFO] [stdout] test proto::mms::control::tests::an_origin_identifier_may_be_octets_or_text ... ok
[INFO] [stdout] test proto::mms::control::tests::last_appl_error_names_the_reason_a_breaker_did_not_move ... ok
[INFO] [stdout] test proto::mms::control::tests::the_check_bits_are_synchro_then_interlock ... ok
[INFO] [stdout] test proto::mms::control::tests::an_oper_round_trips_with_and_without_oper_tm ... ok
[INFO] [stdout] test proto::mms::control::tests::the_control_model_says_which_services_are_legal ... ok
[INFO] [stdout] test proto::mms::file::tests::a_file_name_survives_a_decode_and_re_encode ... ok
[INFO] [stdout] test proto::mms::journal::tests::a_journal_entry_always_names_an_originating_application ... ok
[INFO] [stdout] test proto::mms::journal::tests::a_time_of_day_keeps_the_width_it_arrived_in ... ok
[INFO] [stdout] test proto::mms::reject::tests::a_truncated_reject_is_an_error_not_a_panic ... ok
[INFO] [stdout] test proto::mms::reject::tests::a_reason_must_be_a_primitive_tag_that_is_not_the_invoke_identifier ... ok
[INFO] [stdout] test proto::mms::reject::tests::a_code_is_read_against_its_own_table ... ok
[INFO] [stdout] test proto::mms::reject::tests::an_invoke_identifier_above_i32_max_stays_positive ... ok
[INFO] [stdout] test proto::mms::reject::tests::an_invoke_identifier_outside_unsigned32_is_refused_rather_than_coerced ... ok
[INFO] [stdout] test proto::mms::file::tests::a_path_is_one_component_and_reads_back_as_the_path ... ok
[INFO] [stdout] test proto::mms::reject::tests::every_reason_table_round_trips ... ok
[INFO] [stdout] test proto::mms::reject::tests::every_table_matches_the_asn1_module ... ok
[INFO] [stdout] test proto::mms::reject::tests::trailing_content_after_the_reason_is_refused ... ok
[INFO] [stdout] test proto::mms::report::tests::a_segmented_report_carries_its_segment_number ... ok
[INFO] [stdout] test proto::mms::report::tests::a_server_that_never_finishes_a_report_cannot_grow_a_clients_memory ... ok
[INFO] [stdout] test proto::mms::reject::tests::the_wire_shape_is_the_one_the_asn1_module_describes ... ok
[INFO] [stdout] test proto::mms::report::tests::an_ed1_server_that_stamps_a_utc_time_is_still_understood ... ok
[INFO] [stdout] test proto::mms::report::tests::an_unsegmented_report_passes_straight_through ... ok
[INFO] [stdout] test proto::mms::report::tests::the_option_flags_decide_which_fields_are_on_the_wire ... ok
[INFO] [stdout] test proto::mms::report::tests::a_report_that_does_not_match_its_own_header_is_an_error_not_a_guess ... ok
[INFO] [stdout] test proto::mms::report::tests::the_segments_of_one_report_are_joined_into_it ... ok
[INFO] [stdout] test proto::mms::tests::a_data_set_is_created_and_deleted_by_name ... ok
[INFO] [stdout] test proto::mms::tests::a_file_directory_response_wraps_its_entries_in_a_sequence ... ok
[INFO] [stdout] test proto::mms::report::tests::a_missing_segment_abandons_the_run_rather_than_inventing_the_hole ... ok
[INFO] [stdout] test proto::mms::report::tests::a_report_round_trips_through_the_order_table_40_defines ... ok
[INFO] [stdout] test proto::mms::report::tests::an_oversized_first_segment_is_not_stored ... ok
[INFO] [stdout] test proto::mms::report::tests::one_segment_run_cannot_grow_without_limit ... ok
[INFO] [stdout] test proto::mms::tests::a_log_is_read_by_time_and_resumed_after_an_entry ... ok
[INFO] [stdout] test proto::mms::report::tests::opt_flds_and_trg_ops_pack_the_way_the_tables_number_them ... ok
[INFO] [stdout] test proto::mms::report::tests::the_inclusion_bit_string_says_which_members_arrived ... ok
[INFO] [stdout] test proto::mms::tests::a_name_list_may_be_larger_than_a_data_set ... ok
[INFO] [stdout] test proto::mms::tests::an_information_report_carries_a_data_set_and_its_values ... ok
[INFO] [stdout] test proto::mms::tests::an_invoke_identifier_outside_unsigned32_is_not_a_pdu ... ok
[INFO] [stdout] test proto::mms::tests::get_name_list_round_trips_in_both_directions ... ok
[INFO] [stdout] test proto::mms::tests::a_read_response_and_a_write_round_trip ... ok
[INFO] [stdout] test proto::mms::tests::an_unmodelled_service_keeps_its_octets ... ok
[INFO] [stdout] test proto::mms::tests::a_type_is_asked_for_by_name_and_comes_back_as_a_shape ... ok
[INFO] [stdout] test proto::mms::tests::conclude_and_errors_round_trip ... ok
[INFO] [stdout] test proto::mms::tests::the_file_services_round_trip_through_the_high_tag_number_form ... ok
[INFO] [stdout] test proto::mms::tests::a_service_error_tagged_like_a_modifier_position_still_re_encodes ... ok
[INFO] [stdout] test proto::mms::tests::the_reference_identify_request_round_trips ... ok
[INFO] [stdout] test proto::mms::tests::the_reference_initiate_request_round_trips ... ok
[INFO] [stdout] test proto::mms::tests::truncation_is_an_error_not_a_panic ... ok
[INFO] [stdout] test proto::mms::typespec::tests::a_floating_point_type_is_the_octets_the_field_writes ... ok
[INFO] [stdout] test proto::mms::typespec::tests::the_older_context_tagged_widths_are_still_accepted ... ok
[INFO] [stdout] test proto::osi::acse::tests::an_associate_response_carries_the_result_and_the_external_may_omit_its_syntax ... ok
[INFO] [stdout] test proto::osi::acse::tests::release_and_abort_round_trip ... ok
[INFO] [stdout] test proto::mms::typespec::tests::the_shape_of_an_oper_is_what_a_client_needs_before_it_writes_one ... ok
[INFO] [stdout] test proto::mms::tests::the_reference_read_request_names_a_domain_variable ... ok
[INFO] [stdout] test proto::mms::typespec::tests::a_type_nested_deeper_than_the_limit_is_refused_rather_than_recursed ... ok
[INFO] [stdout] test proto::mms::typespec::tests::every_simple_type_round_trips ... ok
[INFO] [stdout] test proto::osi::acse::tests::an_aare_that_carries_a_result_carries_a_diagnostic_too ... ok
[INFO] [stdout] test proto::osi::acse::tests::the_password_fields_sit_at_different_tags_in_the_request_and_the_response ... ok
[INFO] [stdout] test proto::osi::acse::tests::the_reference_associate_request_round_trips_byte_for_byte ... ok
[INFO] [stdout] test proto::osi::cotp::tests::a_disconnect_request_round_trips ... ok
[INFO] [stdout] test proto::osi::cotp::tests::a_segmented_tsdu_is_reassembled_and_a_whole_one_is_not_copied ... ok
[INFO] [stdout] test proto::osi::acse::tests::truncation_is_an_error_not_a_panic ... ok
[INFO] [stdout] test proto::osi::cotp::tests::data_tpdus_carry_the_end_of_transmission_bit ... ok
[INFO] [stdout] test proto::osi::cotp::tests::the_reference_connection_confirm_round_trips ... ok
[INFO] [stdout] test proto::osi::cotp::tests::truncation_and_unknown_codes_are_errors_or_data_never_panics ... ok
[INFO] [stdout] test proto::osi::cotp::tests::a_tsdu_that_never_ends_is_refused_rather_than_buffered ... ok
[INFO] [stdout] test proto::osi::oid::tests::a_malformed_identifier_ends_rather_than_looping ... ok
[INFO] [stdout] test proto::osi::oid::tests::the_identifiers_this_profile_uses_decode_to_their_names ... ok
[INFO] [stdout] test proto::osi::oid::tests::arcs_encode_back_to_the_octets_they_came_from ... ok
[INFO] [stdout] test proto::osi::cotp::tests::the_reference_connection_request_round_trips ... ok
[INFO] [stdout] test proto::osi::presentation::tests::the_accept_pdu_carries_a_result_list ... ok
[INFO] [stdout] test proto::osi::presentation::tests::truncation_and_the_wrong_mode_are_errors ... ok
[INFO] [stdout] test proto::osi::presentation::tests::a_data_pdu_is_a_bare_user_data ... ok
[INFO] [stdout] test proto::osi::session::tests::a_connect_above_512_octets_uses_extended_user_data ... ok
[INFO] [stdout] test proto::osi::presentation::tests::a_pdv_may_name_its_transfer_syntax ... ok
[INFO] [stdout] test proto::osi::presentation::tests::a_refused_connection_is_decoded_rather_than_reported_as_a_decode_failure ... ok
[INFO] [stdout] test proto::osi::presentation::tests::the_connect_pdu_matches_the_reference_capture_field_for_field ... ok
[INFO] [stdout] test proto::osi::session::tests::abort_accept_and_unknown_spdus ... ok
[INFO] [stdout] test proto::osi::session::tests::an_accept_above_512_octets_is_refused_rather_than_extended ... ok
[INFO] [stdout] test proto::osi::session::tests::data_transfer_is_two_concatenated_spdus ... ok
[INFO] [stdout] test proto::osi::session::tests::the_reference_connect_round_trips ... ok
[INFO] [stdout] test proto::osi::tpkt::tests::a_header_split_across_segments_still_frames ... ok
[INFO] [stdout] test proto::osi::tpkt::tests::a_stream_that_is_not_tpkt_is_an_error_not_a_resynchronisation ... ok
[INFO] [stdout] test proto::osi::session::tests::the_long_length_indicator_is_read_and_written ... ok
[INFO] [stdout] test proto::osi::session::tests::truncation_is_an_error_not_a_panic ... ok
[INFO] [stdout] test proto::osi::session::tests::the_reference_accept_has_no_selectors ... ok
[INFO] [stdout] test proto::osi::session::tests::user_data_beyond_what_the_parameter_holds_is_refused_not_truncated ... ok
[INFO] [stdout] test proto::sv::layout::tests::a_value_that_does_not_fit_is_refused_rather_than_truncated ... ok
[INFO] [stdout] test proto::sv::apdu::tests::count_mismatch_and_limits ... ok
[INFO] [stdout] test proto::sv::apdu::tests::round_trip ... ok
[INFO] [stdout] test proto::sv::layout::tests::the_layout_decodes_what_the_9_2le_struct_decodes ... ok
[INFO] [stdout] test proto::sv::publisher::tests::a_high_rate_stream_numbers_its_samples_without_going_negative ... ok
[INFO] [stdout] test proto::sv::layout::tests::a_channel_width_outside_the_range_is_clamped_rather_than_shifting_off_the_end ... ok
[INFO] [stdout] test proto::sv::publisher::tests::a_sample_rate_beyond_the_counter_wraps_at_the_field_width ... ok
[INFO] [stdout] test proto::sv::publisher::tests::bad_input_is_rejected_not_panicked ... ok
[INFO] [stdout] test proto::sv::publisher::tests::multi_asdu_frames_number_their_samples_consecutively ... ok
[INFO] [stdout] test proto::sv::publisher::tests::patching_the_template_yields_a_decodable_frame ... ok
[INFO] [stdout] test proto::osi::tpkt::tests::headers_round_trip ... ok
[INFO] [stdout] test proto::sv::layout::tests::every_width_round_trips_through_write_and_value ... ok
[INFO] [stdout] test proto::sv::publisher::tests::a_local_clock_identity_above_127_stays_a_positive_integer ... ok
[INFO] [stdout] test proto::osi::tpkt::tests::several_packets_in_one_read_come_out_one_at_a_time ... ok
[INFO] [stdout] test proto::sv::layout::tests::a_block_shorter_than_the_layout_yields_what_it_holds ... ok
[INFO] [stdout] test proto::sv::publisher::tests::ordinary_streams_keep_the_widths_the_field_uses ... ok
[INFO] [stdout] test proto::sv::publisher::tests::refr_tm_does_not_drift_across_a_six_asdu_frame ... ok
[INFO] [stdout] test proto::sv::publisher::tests::profiles_put_2400_frames_per_second_on_the_wire ... ok
[INFO] [stdout] test proto::sv::publisher::tests::scheduling_and_dropped_frames ... ok
[INFO] [stdout] test proto::sv::publisher::tests::simulation_sets_the_header_bit ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::a_stream_with_a_layout_hands_over_channels_and_one_without_hands_over_octets ... ok
[INFO] [stdout] test proto::sv::publisher::tests::smp_cnt_wraps_at_the_sample_rate ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::a_multi_asdu_frame_counts_as_one_frame ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::conf_rev_mismatch_is_edge_triggered ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::continuity_across_the_wrap ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::a_gap_across_the_wrap_counts_the_short_way ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::other_traffic_and_malformed_frames ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::simulated_streams_follow_the_edition_2_rule ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::staleness_is_noticed_on_arrival_as_well_as_on_a_timer ... ok
[INFO] [stdout] test proto::sv::subscriber::tests::the_event_queue_is_bounded ... ok
[INFO] [stdout] test scl::tests::an_array_count_is_a_number_a_sibling_or_a_diagnostic ... ok
[INFO] [stdout] test proto::sv::publisher::tests::the_clock_fields_are_patched_in_place ... ok
[INFO] [stdout] test proto::sv::publisher::tests::the_template_is_encoded_once_and_never_grows ... ok
[INFO] [stdout] test scl::tests::a_member_naming_only_a_data_object_covers_its_attributes ... ok
[INFO] [stdout] test scl::tests::a_sampled_value_publisher_is_configured_from_the_control_block ... ok
[INFO] [stdout] test scl::tests::lenient_by_default_strict_on_request ... ok
[INFO] [stdout] test scl::tests::instance_values_override_the_type_template ... ok
[INFO] [stdout] test scl::tests::an_unbound_input_is_not_a_finding_but_a_dangling_one_is ... ok
[INFO] [stdout] test scl::tests::subscriptions_resolve_to_the_publishers_addresses ... ok
[INFO] [stdout] test scl::tests::the_access_point_carries_the_osi_addressing_an_association_needs ... ok
[INFO] [stdout] test scl::validate::tests::a_control_model_that_promises_a_service_the_type_lacks_is_a_finding ... ok
[INFO] [stdout] test scl::validate::tests::a_correctly_engineered_file_produces_nothing ... ok
[INFO] [stdout] test scl::validate::tests::a_dangling_subscription_is_an_error ... ok
[INFO] [stdout] test scl::validate::tests::an_object_reference_longer_than_the_edition_allows ... ok
[INFO] [stdout] test scl::validate::tests::an_unindexed_block_that_asks_for_several_instances_gets_one ... ok
[INFO] [stdout] test scl::validate::tests::one_appid_on_two_addresses_is_a_warning_not_an_error ... ok
[INFO] [stdout] test scl::validate::tests::the_schema_valid_and_wrong_file_is_caught ... ok
[INFO] [stdout] test server::control::tests::only_the_four_service_attributes_are_controls ... ok
[INFO] [stdout] test scl::tests::loads_model_and_addresses ... ok
[INFO] [stdout] test scl::tests::scl_durations_carry_their_multiplier ... ok
[INFO] [stdout] test server::files::tests::a_path_that_could_leave_the_sandbox_is_refused_rather_than_normalised ... ok
[INFO] [stdout] test scl::validate::tests::a_report_or_log_control_block_that_writes_nowhere_is_a_finding ... ok
[INFO] [stdout] test server::files::tests::a_range_read_tiles_the_file_and_stops_at_its_end ... ok
[INFO] [stdout] test server::rcb::tests::a_member_that_cannot_be_split_is_sent_anyway ... ok
[INFO] [stdout] test scl::validate::tests::a_type_that_declares_a_member_twice_is_a_file_no_schema_would_accept ... ok
[INFO] [stdout] test server::files::tests::a_directory_store_serves_only_what_is_under_its_root ... ok
[INFO] [stdout] test server::rcb::tests::a_report_is_split_only_as_far_as_the_budget_makes_necessary ... ok
[INFO] [stdout] test scl::tests::an_ext_ref_that_names_only_the_signal_still_resolves ... ok
[INFO] [stdout] test scl::tests::an_input_naming_only_a_data_object_finds_the_attribute_that_carries_it ... ok
[INFO] [stdout] test server::rcb::tests::an_empty_report_produces_nothing ... ok
[INFO] [stdout] test server::sg::tests::the_edit_copy_of_a_setting_is_the_same_name_under_a_different_constraint ... ok
[INFO] [stdout] test server::supervision::tests::the_timestamp_belongs_to_the_data_object_not_the_attribute ... ok
[INFO] [stdout] test server::tree::tests::a_name_resolves_to_the_node_it_denotes ... ok
[INFO] [stdout] test server::tree::tests::an_item_name_splits_into_node_constraint_and_path ... ok
[INFO] [stdout] test server::tree::tests::control_blocks_join_the_tree_under_their_functional_constraint ... ok
[INFO] [stdout] test server::tree::tests::the_namespace_is_flattened_and_sorted ... ok
[INFO] [stdout] test server::tree::tests::the_type_of_a_structure_is_its_components_in_order ... ok
[INFO] [stdout] test server::supervision::tests::the_writes_are_the_status_objects_under_st ... ok
[INFO] [stdout] test server::tracking::tests::the_class_follows_the_control_block_it_tracks ... ok
[INFO] [stdout] test server::tracking::tests::the_one_rule_that_maps_a_tracker_onto_a_control_block ... ok
[INFO] [stderr]      Running tests/allocation.rs (/opt/rustwide/target/release/build/iec61850-rs/a2780b89642a089f/out/allocation-a2780b89642a089f)
[INFO] [stdout] test proto::osi::session::tests::a_parameter_group_nested_into_itself_is_not_recursed_into ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 259 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test the_steady_state_allocates_nothing ... ok
[INFO] [stdout] test opening_a_large_file_does_not_allocate_it ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/concepts.rs (/opt/rustwide/target/release/build/iec61850-rs/93a69e06bc8b95f2/out/concepts-93a69e06bc8b95f2)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test number_words_are_spelt_the_way_the_notes_spell_them ... ok
[INFO] [stdout] test relative_links_resolve ... ok
[INFO] [stdout] test stated_counts_match ... ok
[INFO] [stdout] test identifiers_are_contiguous_and_cited_ones_exist ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/interop.rs (/opt/rustwide/target/release/build/iec61850-rs/e0f1e72adfba6fcd/out/interop-e0f1e72adfba6fcd)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test an_array_element_is_read_as_one_element_in_both_directions ... ok
[INFO] [stdout] test libiec61850_operates_all_four_control_models_on_this_server ... ok
[INFO] [stdout] test libiec61850_takes_reports_from_this_server ... ok
[INFO] [stderr]      Running tests/mms_client.rs (/opt/rustwide/target/release/build/iec61850-rs/a3cbcf34cc6a2e4a/out/mms_client-a3cbcf34cc6a2e4a)
[INFO] [stdout] test libiec61850_browses_and_reads_this_server ... ok
[INFO] [stdout] test common::a_version_is_read_out_of_every_banner_a_packager_writes ... ok
[INFO] [stdout] test this_client_reads_a_log_out_of_a_libiec61850_server ... ok
[INFO] [stdout] test this_client_drives_a_libiec61850_server ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test a_peer_that_hangs_up_mid_association_is_reported_rather_than_waited_on ... ok
[INFO] [stdout] test a_refused_control_names_its_add_cause_rather_than_reporting_success ... ok
[INFO] [stdout] test a_direct_control_is_one_write ... ok
[INFO] [stdout] test a_data_set_is_created_and_deleted_and_a_refusal_is_not_success ... ok
[INFO] [stdout] test a_server_that_is_not_there_is_an_error_and_not_a_hang ... ok
[INFO] [stdout] test a_log_is_read_by_time_and_resumed_after_the_last_entry_seen ... ok
[INFO] [stdout] test a_report_asking_for_references_gets_them ... ok
[INFO] [stdout] test a_status_only_object_is_refused_without_a_round_trip ... ok
[INFO] [stdout] test a_termination_for_another_command_is_not_mistaken_for_this_one ... ok
[INFO] [stdout] test common::a_version_is_read_out_of_every_banner_a_packager_writes ... ok
[INFO] [stdout] test a_report_and_a_termination_do_not_consume_each_other ... ok
[INFO] [stdout] test a_segmented_report_reaches_the_application_whole_or_not_at_all ... ok
[INFO] [stdout] test a_variables_type_is_read_from_the_server_rather_than_assumed ... ok
[INFO] [stdout] test the_engineering_file_can_be_the_client_configuration ... ok
[INFO] [stdout] test reports_that_arrive_during_a_request_are_kept_rather_than_dropped ... ok
[INFO] [stdout] test a_report_control_block_is_read_configured_enabled_and_decoded ... ok
[INFO] [stdout] test a_client_associates_browses_reads_and_writes ... ok
[INFO] [stdout] test select_before_operate_with_enhanced_security_waits_for_the_termination ... ok
[INFO] [stdout] test a_setting_group_is_selected_written_and_confirmed_in_that_order ... ok
[INFO] [stdout] test a_file_is_listed_read_and_deleted_and_its_handle_is_given_back ... ok
[INFO] [stdout] test enabling_a_control_block_twice_takes_it_over_rather_than_failing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.77s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mms_server.rs (/opt/rustwide/target/release/build/iec61850-rs/a3fc61ae8f96da54/out/mms_server-a3fc61ae8f96da54)
[INFO] [stdout] 
[INFO] [stdout] running 65 tests
[INFO] [stdout] test a_cancel_for_a_finished_request_is_refused_rather_than_swallowed ... ok
[INFO] [stdout] test a_control_model_nobody_stated_is_read_off_the_server ... ok
[INFO] [stdout] test a_client_browses_the_namespace_the_scl_file_describes ... ok
[INFO] [stdout] test a_data_set_is_read_created_and_deleted ... ok
[INFO] [stdout] test a_direct_control_operates_the_object_it_names ... ok
[INFO] [stdout] test a_control_block_belongs_to_one_client_at_a_time ... ok
[INFO] [stdout] test a_browse_that_does_not_fit_one_pdu_is_paged ... ok
[INFO] [stdout] test a_command_is_refused_when_the_logical_node_is_not_in_a_mode_that_takes_it ... ok
[INFO] [stdout] test a_file_is_listed_and_read_off_the_server_and_the_sandbox_holds ... ok
[INFO] [stdout] test a_control_block_counter_is_not_a_client_s_to_write ... ok
[INFO] [stdout] test a_goose_control_block_is_served_from_the_file_that_configures_it ... ok
[INFO] [stdout] test a_log_entry_carries_the_reason_it_was_made ... ok
[INFO] [stdout] test a_read_and_a_write_go_through_the_model ... ok
[INFO] [stdout] test a_log_is_served_out_of_whatever_store_the_application_supplies ... ok
[INFO] [stdout] test a_report_control_block_names_the_client_that_holds_it ... ok
[INFO] [stdout] test a_log_records_what_changed_and_is_read_back_by_time_and_after_an_entry ... ok
[INFO] [stdout] test a_running_block_refuses_to_be_reconfigured ... ok
[INFO] [stdout] test an_application_hook_refuses_with_the_cause_it_chooses ... ok
[INFO] [stdout] test a_selection_belongs_to_the_client_that_made_it ... ok
[INFO] [stdout] test an_edit_reservation_belongs_to_one_client ... ok
[INFO] [stdout] test an_operate_without_a_select_is_refused_with_a_reason ... ok
[INFO] [stdout] test arrays::a_data_set_member_may_be_one_element_of_an_array ... ok
[INFO] [stdout] test arrays::a_reference_with_an_index_reads_one_element_and_not_the_array ... ok
[INFO] [stdout] test arrays::a_write_with_an_index_changes_one_element ... ok
[INFO] [stdout] test arrays::an_array_is_one_name_and_a_type_that_says_how_many ... ok
[INFO] [stdout] test arrays::an_index_outside_the_array_is_refused ... ok
[INFO] [stdout] test controls::an_association_that_ends_disarms_what_it_armed ... ok
[INFO] [stdout] test controls::an_operate_stamps_the_status_with_the_wall_clock ... ok
[INFO] [stdout] test controls::an_operate_with_a_future_oper_tm_waits_for_it ... ok
[INFO] [stdout] test controls::an_operate_with_a_past_oper_tm_runs_immediately ... ok
[INFO] [stdout] test controls::cancel_withdraws_a_command_that_is_waiting_for_its_time ... ok
[INFO] [stdout] test controls::the_system_clock_reports_a_plausible_date ... ok
[INFO] [stdout] test editions::an_edition_1_buffered_block_has_no_resv_tms ... ok
[INFO] [stdout] test editions::an_edition_1_report_control_block_has_no_reservation_attributes ... ok
[INFO] [stdout] test editions::the_edition_comes_from_the_schema_version_the_file_declares ... ok
[INFO] [stdout] test functionally_constrained_data::a_data_object_member_is_one_member_everywhere ... ok
[INFO] [stdout] test functionally_constrained_data::a_report_too_large_for_the_negotiated_pdu_is_split_into_segments ... ok
[INFO] [stdout] test functionally_constrained_data::repointing_a_block_at_another_data_set_moves_conf_rev ... ok
[INFO] [stdout] test publisher_control_blocks::a_unicast_stream_is_a_usvcb_and_not_a_multicast_one ... ok
[INFO] [stdout] test rejects::an_unrecognised_service_is_rejected_rather_than_failed ... ok
[INFO] [stdout] test rejects::something_that_is_not_a_request_is_a_pdu_error ... ok
[INFO] [stdout] test report_engine::a_report_carries_the_wall_clock_and_not_the_monotonic_one ... ok
[INFO] [stdout] test report_engine::a_report_is_reported_under_the_name_the_mapping_gives_it ... ok
[INFO] [stdout] test report_engine::an_integrity_period_without_its_trigger_reports_nothing ... ok
[INFO] [stdout] test report_engine::publishing_a_report_does_not_swallow_a_write_that_has_not_been_committed ... ok
[INFO] [stdout] test select_before_operate_with_enhanced_security_runs_the_whole_sequence ... ok
[INFO] [stdout] test a_general_interrogation_reports_every_member_once ... ok
[INFO] [stdout] test service_tracking::a_control_block_write_lands_in_the_tracking_object_the_file_declares ... ok
[INFO] [stdout] test service_tracking::a_control_is_tracked_by_the_tracker_whose_type_matches_it ... ok
[INFO] [stdout] test service_tracking::a_control_is_tracked_with_what_the_client_actually_sent ... ok
[INFO] [stdout] test service_tracking::a_log_query_is_tracked_in_the_ots_object ... ok
[INFO] [stdout] test service_tracking::a_report_control_block_carries_the_tracking_object_to_a_client ... ok
[INFO] [stdout] test service_tracking::every_attribute_of_a_whole_buffered_tracker_mirrors_its_block ... ok
[INFO] [stdout] test service_tracking::the_standards_list_order_is_the_fallback_and_the_file_is_the_answer ... ok
[INFO] [stdout] test setting_groups_activate_edit_and_confirm ... ok
[INFO] [stdout] test the_server_answers_what_type_a_variable_is ... ok
[INFO] [stdout] test the_vmd_answers_for_itself ... ok
[INFO] [stdout] test service_tracking::a_block_released_with_its_association_is_an_internal_change ... ok
[INFO] [stdout] test two_clients_see_the_same_model ... ok
[INFO] [stdout] test a_buffered_block_keeps_what_happened_while_nobody_was_listening ... FAILED
[INFO] [stdout] test a_client_reconnects_and_restores_nothing_behind_your_back ... ok
[INFO] [stdout] test a_report_control_block_is_enabled_and_reports_what_changed ... ok
[INFO] [stdout] test a_gathering_window_puts_one_report_where_three_changes_happened ... ok
[INFO] [stdout] test a_goose_subscription_is_supervised_by_the_lgos_the_file_declares ... ok
[INFO] [stdout] test an_edit_reservation_lapses_when_its_reservation_time_runs_out ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- a_buffered_block_keeps_what_happened_while_nobody_was_listening stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'a_buffered_block_keeps_what_happened_while_nobody_was_listening' (1259) panicked at tests/mms_server.rs:406:5:
[INFO] [stdout] assertion `left == right` failed: only what happened while it was away: [
[INFO] [stdout]     Report {
[INFO] [stdout]         rpt_id: "IED1LD0/LLN0$BR$brcb",
[INFO] [stdout]         opt_flds: OptFlds(sequence_number | reason_for_inclusion | buffer_overflow | entry_id),
[INFO] [stdout]         seq_num: Some(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]         time_of_entry: None,
[INFO] [stdout]         data_set: None,
[INFO] [stdout]         buf_ovfl: Some(
[INFO] [stdout]             false,
[INFO] [stdout]         ),
[INFO] [stdout]         entry_id: Some(
[INFO] [stdout]             [
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 0,
[INFO] [stdout]                 5,
[INFO] [stdout]             ],
[INFO] [stdout]         ),
[INFO] [stdout]         conf_rev: None,
[INFO] [stdout]         sub_seq_num: None,
[INFO] [stdout]         more_segments_follow: false,
[INFO] [stdout]         inclusion: (
[INFO] [stdout]             7,
[INFO] [stdout]             [
[INFO] [stdout]                 128,
[INFO] [stdout]             ],
[INFO] [stdout]         ),
[INFO] [stdout]         entries: [
[INFO] [stdout]             ReportEntry {
[INFO] [stdout]                 index: 0,
[INFO] [stdout]                 reference: None,
[INFO] [stdout]                 value: Boolean(
[INFO] [stdout]                     true,
[INFO] [stdout]                 ),
[INFO] [stdout]                 reason: Some(
[INFO] [stdout]                     ReasonCode(data_change),
[INFO] [stdout]                 ),
[INFO] [stdout]             },
[INFO] [stdout]         ],
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout]   left: 1
[INFO] [stdout]  right: 2
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x555e63514911 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x555e6352c8aa - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x555e635191bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x555e634edbe6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x555e6350bcd9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x555e6339c7d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x555e6350be92 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x555e634edc92 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x555e634e6739 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x555e634eea8d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x555e6352cfdc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x555e6352cf53 - core[a373ad73a504971]::panicking::assert_failed_inner
[INFO] [stdout]   12:     0x555e63526e1d - core[a373ad73a504971]::panicking::assert_failed::<usize, usize>
[INFO] [stdout]   13:     0x555e6334c8fa - <mms_server[a394ae9a83140e01]::a_buffered_block_keeps_what_happened_while_nobody_was_listening::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x555e6338fc1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   15:     0x555e6339cdf5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x555e633966e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x555e6339fe82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x555e635139cf - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x747dc2ba4dfa - <unknown>
[INFO] [stdout]   20:     0x747dc2c383d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     a_buffered_block_keeps_what_happened_while_nobody_was_listening
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 64 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.35s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test mms_server`
[INFO] running `Command { std: "docker" "inspect" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952", kill_on_drop: false }`
[INFO] [stdout] e33439e1da996f4f4bcd9ff2ae5300de70062210335ef79ff0c2787bcc32c952
