[INFO] fetching crate spvirit-ioc 0.3.4...
[INFO] testing spvirit-ioc-0.3.4 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate spvirit-ioc 0.3.4 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate spvirit-ioc 0.3.4
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate spvirit-ioc 0.3.4
[INFO] tweaked toml for crates.io crate spvirit-ioc 0.3.4 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate spvirit-ioc 0.3.4 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 spvirit-ioc 0.3.4 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tokio-macros v2.6.1
[INFO] [stderr]   Downloaded spvirit-types v0.3.4
[INFO] [stderr]   Downloaded c_linked_list v1.1.1
[INFO] [stderr]   Downloaded dns-lookup v3.0.1
[INFO] [stderr]   Downloaded get_if_addrs v0.5.3
[INFO] [stderr]   Downloaded get_if_addrs-sys v0.1.1
[INFO] [stderr]   Downloaded spvirit-client v0.3.4
[INFO] [stderr]   Downloaded spvirit-server v0.3.4
[INFO] [stderr]   Downloaded spvirit-codec v0.3.4
[INFO] [stderr]   Downloaded cc v1.2.57
[INFO] [stderr]   Downloaded libc v0.2.183
[INFO] [stderr]   Downloaded tokio v1.50.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5
[INFO] running `Command { std: "docker" "start" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", 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" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5" "/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" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", 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" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling futures-io v0.3.32
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling spvirit-types v0.3.4
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling c_linked_list v1.1.1
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling get_if_addrs v0.5.3
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling spvirit-codec v0.3.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling spvirit-server v0.3.4
[INFO] [stderr]    Compiling spvirit-ioc v0.3.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 29.83s
[INFO] running `Command { std: "docker" "inspect" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", 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" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling dns-lookup v3.0.1
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling spvirit-server v0.3.4
[INFO] [stderr]    Compiling spvirit-client v0.3.4
[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 spvirit-ioc v0.3.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 27.51s
[INFO] running `Command { std: "docker" "inspect" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", 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" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/spvirit_ioc-ef83cc7b30c4b076)
[INFO] [stdout] 
[INFO] [stdout] running 137 tests
[INFO] [stdout] test alarm::tests::every_condition_has_a_distinct_epics_string ... ok
[INFO] [stdout] test alarm::tests::no_alarm_maps_to_none_with_empty_message ... ok
[INFO] [stdout] test alarm::tests::severity_renders_the_epics_menu_strings ... ok
[INFO] [stdout] test alarm::tests::undefined_records_map_to_the_undefined_status ... ok
[INFO] [stdout] test alarm::tests::severity_raises_but_never_lowers ... ok
[INFO] [stdout] test build::tests::a_db_link_inp_does_not_seed_val_at_load ... ok
[INFO] [stdout] test build::tests::an_absent_inp_does_not_seed_val_or_clear_udf ... ok
[INFO] [stdout] test build::tests::a_numeric_link_is_a_constant_of_the_records_value_type ... ok
[INFO] [stdout] test build::tests::a_bare_target_defaults_to_nppncams ... ok
[INFO] [stdout] test alarm::tests::limit_alarms_match_the_simple_store_convention ... ok
[INFO] [stdout] test build::tests::all_six_kinds_are_recognised ... ok
[INFO] [stdout] test build::tests::a_specified_numeric_dol_seeds_val_and_clears_udf_for_an_output_record ... ok
[INFO] [stdout] test build::tests::a_specified_constant_inp_overrides_an_explicit_val_field ... ok
[INFO] [stdout] test build::tests::a_specified_numeric_inp_seeds_val_and_clears_udf_at_load ... ok
[INFO] [stdout] test build::tests::an_absent_dol_does_not_seed_val_or_clear_udf_for_an_output_record ... ok
[INFO] [stdout] test build::tests::a_malformed_number_in_a_numeric_field_names_the_field ... ok
[INFO] [stdout] test build::tests::an_unsupported_kind_is_a_build_error_naming_the_type ... ok
[INFO] [stdout] test build::tests::a_db_link_parses_its_process_and_severity_flags ... ok
[INFO] [stdout] test ctx::tests::exceeding_the_depth_cap_names_the_record ... ok
[INFO] [stdout] test ctx::tests::depth_is_restored_by_pop ... ok
[INFO] [stdout] test fields::tests::the_kind_table_also_covers_the_dbcommon_fallback ... ok
[INFO] [stdout] test ctx::tests::posted_events_come_back_in_post_order ... ok
[INFO] [stdout] test build::tests::value_coercion_follows_the_records_kind ... ok
[INFO] [stdout] test ctx::tests::take_events_empties_the_buffer ... ok
[INFO] [stdout] test graph::tests::a_single_self_loop_is_a_cycle ... ok
[INFO] [stdout] test graph::tests::a_tail_into_a_cycle_is_not_part_of_it ... ok
[INFO] [stdout] test graph::tests::dag_with_no_back_edges_has_no_cycles ... ok
[INFO] [stdout] test graph::tests::being_a_pp_or_flnk_target_makes_a_record_reachable ... ok
[INFO] [stdout] test graph::tests::an_npp_target_alone_does_not_make_a_record_reachable ... ok
[INFO] [stdout] test fields::tests::explicit_fields_reflect_the_db_not_the_dbcommon_default ... ok
[INFO] [stdout] test fields::tests::serves_the_typed_model_as_fields ... ok
[INFO] [stdout] test build::tests::defaults_match_the_record_reference ... ok
[INFO] [stdout] test graph::tests::nested_cycles_are_reported_as_distinct_components ... ok
[INFO] [stdout] test graph::tests::two_disjoint_cycles_are_reported_separately ... ok
[INFO] [stdout] test graph::tests::pini_makes_a_record_reachable ... ok
[INFO] [stdout] test graph::tests::overlapping_paths_that_reconverge_form_one_scc ... ok
[INFO] [stdout] test graph::tests::a_clean_database_produces_an_empty_report ... ok
[INFO] [stdout] test graph::tests::a_non_passive_scan_makes_a_record_reachable ... ok
[INFO] [stdout] test graph::tests::a_passive_record_nothing_targets_is_unreachable ... ok
[INFO] [stdout] test graph::tests::lock_sets_are_reported_by_member_name ... ok
[INFO] [stdout] test graph::tests::fan_out_exactly_at_the_threshold_is_not_reported ... ok
[INFO] [stdout] test build::tests::egu_is_parsed_and_defaults_to_empty ... ok
[INFO] [stdout] test fields::tests::renders_links_the_way_base_would ... ok
[INFO] [stdout] test lockset::tests::linked_records_share_a_lock_set ... ok
[INFO] [stdout] test fields::tests::falls_back_to_dbcommon_for_fields_the_ioc_does_not_model ... ok
[INFO] [stdout] test graph::tests::out_dol_and_sdis_edges_also_carry_the_right_kind ... ok
[INFO] [stdout] test graph::tests::a_cycle_is_reported_but_is_not_an_error ... ok
[INFO] [stdout] test graph::tests::edges_record_which_field_created_them ... ok
[INFO] [stdout] test graph::tests::fan_out_above_the_threshold_is_reported ... ok
[INFO] [stdout] test lockset::tests::an_unresolvable_target_becomes_unresolved_not_a_panic ... ok
[INFO] [stdout] test lockset::tests::flnk_and_sdis_also_join_lock_sets ... ok
[INFO] [stdout] test lockset::tests::order_preserves_the_db_file_order ... ok
[INFO] [stdout] test lockset::tests::unlinked_records_get_separate_lock_sets ... ok
[INFO] [stdout] test process::tests::a_constant_link_is_a_no_op_during_processing ... ok
[INFO] [stdout] test process::tests::a_binary_record_stores_zero_or_one ... ok
[INFO] [stdout] test model::tests::to_payload_carries_egu_as_units ... ok
[INFO] [stdout] test process::tests::a_disabled_record_with_default_diss_does_not_oscillate_across_passes ... ok
[INFO] [stdout] test process::tests::a_disabled_record_raises_disable_at_diss_and_skips_the_body ... ok
[INFO] [stdout] test lockset::tests::links_resolve_to_ids_not_names ... ok
[INFO] [stdout] test model::tests::a_record_without_egu_serves_empty_units ... ok
[INFO] [stdout] test model::tests::an_empty_egu_serves_empty_units_too ... ok
[INFO] [stdout] test process::tests::a_disabled_record_still_posts_its_disabled_state ... ok
[INFO] [stdout] test process::tests::a_disabled_record_does_not_fire_its_forward_link ... ok
[INFO] [stdout] test process::tests::a_disabled_record_with_default_diss_is_not_in_alarm ... ok
[INFO] [stdout] test process::tests::a_link_to_a_dot_field_reads_that_field ... ok
[INFO] [stdout] test process::tests::a_long_record_rounds_a_double_input ... ok
[INFO] [stdout] test process::tests::a_longin_record_crossing_hihi_is_limit_checked ... ok
[INFO] [stdout] test process::tests::a_never_processed_binary_record_is_invalid_udf ... ok
[INFO] [stdout] test process::tests::a_binary_record_does_not_apply_numeric_limits ... ok
[INFO] [stdout] test process::tests::a_pp_cycle_terminates_via_pact ... ok
[INFO] [stdout] test process::tests::a_value_exactly_at_high_alarms_high ... ok
[INFO] [stdout] test process::tests::a_record_reports_invalid_udf_before_the_first_process_pass ... ok
[INFO] [stdout] test process::tests::a_value_below_lolo_is_major_lolo ... ok
[INFO] [stdout] test process::tests::a_pp_out_write_posts_exactly_once ... ok
[INFO] [stdout] test process::tests::a_record_reports_no_alarm_and_clear_udf_after_the_first_process_pass ... ok
[INFO] [stdout] test process::tests::a_record_already_active_returns_without_reprocessing ... ok
[INFO] [stdout] test process::tests::a_too_deep_error_does_not_strand_pact_for_later_passes ... ok
[INFO] [stdout] test process::tests::a_pp_self_link_terminates_via_pact_not_the_depth_cap ... ok
[INFO] [stdout] test process::tests::a_value_exactly_at_hihi_alarms_hihi ... ok
[INFO] [stdout] test process::tests::a_pp_input_processes_its_target_before_reading ... ok
[INFO] [stdout] test process::tests::a_value_above_hihi_is_major_hihi ... ok
[INFO] [stdout] test process::tests::a_value_exactly_at_lolo_alarms_lolo ... ok
[INFO] [stdout] test process::tests::a_disabled_record_with_diss_major_stays_stable_across_passes ... ok
[INFO] [stdout] test process::tests::a_value_above_high_is_minor_high ... ok
[INFO] [stdout] test fields::tests::the_kind_table_agrees_with_the_value_table_for_every_kind_and_field ... ok
[INFO] [stdout] test process::tests::an_undefined_record_is_invalid_udf ... ok
[INFO] [stdout] test process::tests::a_value_exactly_at_low_alarms_low ... ok
[INFO] [stdout] test process::tests::a_zero_severity_limit_does_not_alarm ... ok
[INFO] [stdout] test process::tests::an_output_record_writes_its_value_through_out ... ok
[INFO] [stdout] test process::tests::a_value_inside_the_limits_clears_the_alarm ... ok
[INFO] [stdout] test process::tests::an_npp_input_reads_without_processing_the_target ... ok
[INFO] [stdout] test process::tests::an_alarm_change_posts_regardless_of_mdel ... ok
[INFO] [stdout] test process::tests::an_alarm_only_post_does_not_advance_mlst ... ok
[INFO] [stdout] test process::tests::an_npp_out_write_posts_a_monitor_naming_the_target ... ok
[INFO] [stdout] test process::tests::an_ordinary_process_still_returns_early_while_pact_is_set ... ok
[INFO] [stdout] test process::tests::an_output_record_in_closed_loop_takes_its_value_from_dol ... ok
[INFO] [stdout] test process::tests::completing_a_record_that_is_not_active_is_a_no_op ... ok
[INFO] [stdout] test process::tests::an_async_completion_fires_the_forward_link ... ok
[INFO] [stdout] test process::tests::an_output_record_in_supervisory_mode_keeps_its_own_value ... ok
[INFO] [stdout] test process::tests::an_npp_out_write_advances_the_targets_prev_val_for_the_next_mdel_check ... ok
[INFO] [stdout] test process::tests::an_undefined_record_reports_udf_not_a_limit_alarm ... ok
[INFO] [stdout] test process::tests::completing_an_async_record_clears_pact_and_posts ... ok
[INFO] [stdout] test process::tests::crossing_both_hihi_and_high_yields_hihi_by_ladder_order_alone ... ok
[INFO] [stdout] test process::tests::crossing_both_lolo_and_low_yields_lolo_by_ladder_order_alone ... ok
[INFO] [stdout] test process::tests::reset_alarms_reports_false_when_recommitting_the_same_state ... ok
[INFO] [stdout] test process::tests::ms_propagates_the_targets_severity_to_the_reader ... ok
[INFO] [stdout] test process::tests::reset_alarms_reports_true_when_only_the_condition_differs ... ok
[INFO] [stdout] test process::tests::mdel_suppresses_a_change_smaller_than_the_deadband ... ok
[INFO] [stdout] test process::tests::processing_posts_a_monitor_for_the_record ... ok
[INFO] [stdout] test source::tests::debug_names_the_type ... ok
[INFO] [stdout] test source::tests::record_names_sorted_lists_every_real_name_in_order ... ok
[INFO] [stdout] test spec::tests::a_fully_modelled_record_reports_no_unmodelled_fields ... ok
[INFO] [stdout] test spec::tests::a_specs_name_is_the_one_it_was_constructed_with ... ok
[INFO] [stdout] test spec::tests::an_unmodelled_field_is_carried_not_rejected ... ok
[INFO] [stdout] test spec::tests::every_record_kind_has_a_constructor ... ok
[INFO] [stdout] test process::tests::reset_alarms_reports_true_when_the_pending_state_differs_from_committed ... ok
[INFO] [stdout] test spec::tests::f_does_not_apply_the_terse_form_far_above_the_threshold ... ok
[INFO] [stdout] test spec::tests::pini_and_tpro_render_as_the_epics_menu_strings ... ok
[INFO] [stdout] test spec::tests::setting_a_field_twice_keeps_the_last_value ... ok
[INFO] [stdout] test spec::tests::spec_error_display_messages_name_the_problem ... ok
[INFO] [stdout] test spec::tests::a_builder_call_after_bind_is_ignored ... ok
[INFO] [stdout] test spec::tests::whole_floats_render_without_a_decimal_point ... ok
[INFO] [stdout] test process::tests::sdis_supplies_disa_when_it_is_a_link ... ok
[INFO] [stdout] test process::tests::disa_not_equal_to_disv_leaves_the_record_enabled ... ok
[INFO] [stdout] test process::tests::pact_is_clear_again_after_a_synchronous_pass ... ok
[INFO] [stdout] test process::tests::writing_dot_proc_forces_a_process_pass ... ok
[INFO] [stdout] test process::tests::severity_only_ever_rises_within_one_pass ... ok
[INFO] [stdout] test spec::tests::a_spec_lowers_to_the_same_db_record_the_parser_would_produce ... ok
[INFO] [stdout] test spec::tests::is_bound_reflects_whether_bind_was_ever_called ... ok
[INFO] [stdout] test source::tests::an_unmodelled_field_is_warned_about_by_name_at_load ... ok
[INFO] [stdout] test process::tests::hyst_holds_an_alarm_until_the_value_clears_the_deadband ... ok
[INFO] [stdout] test process::tests::flnk_posts_after_the_records_own_monitor ... ok
[INFO] [stdout] test process::tests::processing_clears_udf_and_stamps_the_time ... ok
[INFO] [stdout] test process::tests::the_depth_cap_reports_the_record_rather_than_overflowing_the_stack ... ok
[INFO] [stdout] test process::tests::nms_does_not_propagate_severity ... ok
[INFO] [stdout] test spec::tests::the_escape_hatch_uppercases_the_field_name ... ok
[INFO] [stdout] test process::tests::the_posted_payload_carries_the_records_alarm_state ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 137 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.42s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conformance.rs (/opt/rustwide/target/debug/deps/conformance-373c2e11cca5f77b)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test rule_omsl_selects_the_output_source ... ok
[INFO] [stdout] test rule_flnk_fires_after_monitors ... ok
[INFO] [stdout] test rule_long_records_round_to_integers ... ok
[INFO] [stdout] test rule_pp_input_processes_before_reading ... ok
[INFO] [stdout] test rule_mdel_suppresses_small_changes ... ok
[INFO] [stdout] test rule_binary_records_normalise_to_zero_or_one ... ok
[INFO] [stdout] test rule_a_link_cycle_terminates ... ok
[INFO] [stdout] test rule_a_never_processed_record_is_udf ... ok
[INFO] [stdout] test rule_ms_propagates_the_worst_severity ... ok
[INFO] [stdout] test rule_disabled_records_report_disable_at_diss ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/debug/deps/determinism-b4e12085bf53068a)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test the_graph_report_text_is_identical_across_runs ... ok
[INFO] [stdout] test lock_set_partitioning_is_identical_across_runs ... ok
[INFO] [stdout] test the_event_stream_is_identical_across_runs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/field_access.rs (/opt/rustwide/target/debug/deps/field_access-8e925853bb9455dd)
[INFO] [stdout] running 15 tests
[INFO] [stdout] test a_host_side_read_agrees_with_the_source_read ... ok
[INFO] [stdout] test a_record_without_egu_serves_empty_units ... ok
[INFO] [stdout] test an_input_record_is_writable_on_the_ioc_engine ... ok
[INFO] [stdout] test the_ioc_matches_the_field_contract_the_other_tiers_serve ... ok
[INFO] [stdout] test building_a_server_hands_the_engine_its_monitor_registry ... ok
[INFO] [stdout] test unknown_records_and_fields_are_not_claimed ... ok
[INFO] [stdout] test egu_survives_from_db_text_to_the_served_payload ... ok
[INFO] [stdout] test the_builder_registers_an_ioc_as_a_store ... ok
[INFO] [stdout] test the_long_string_form_works_on_the_ioc_too ... ok
[INFO] [stdout] test field_pvs_stay_read_only_against_the_host_write_path ... ok
[INFO] [stdout] test the_ioc_serves_its_own_field_pvs ... ok
[INFO] [stdout] test field_pvs_are_read_only ... ok
[INFO] [stdout] test the_record_pv_is_unaffected_by_field_routing ... ok
[INFO] [stdout] test calling_ioc_twice_panics - should panic ... ok
[INFO] [stdout] test claiming_a_field_does_not_take_the_records_lock_set ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.25s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/host_writes.rs (/opt/rustwide/target/debug/deps/host_writes-8d4e6ecee09e2b9a)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test a_host_side_write_and_a_client_put_produce_the_same_events ... ok
[INFO] [stdout] test a_bound_spec_refuses_an_unsupported_scalar ... ok
[INFO] [stdout] test an_unknown_record_is_reported_as_missing_not_as_a_readonly_field ... ok
[INFO] [stdout] test a_bound_spec_reads_a_field_by_epics_name ... ok
[INFO] [stdout] test a_bound_spec_handle_gets_and_sets ... ok
[INFO] [stdout] test a_pending_spec_handle_is_unbound ... ok
[INFO] [stdout] test writing_an_unknown_record_is_an_error_not_a_silent_no_op ... ok
[INFO] [stdout] test a_host_side_write_propagates_down_the_chain ... ok
[INFO] [stdout] test writing_a_field_is_refused_with_a_reason ... ok
[INFO] [stdout] test a_host_side_write_reaches_a_monitor_client ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.75s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ioc_server.rs (/opt/rustwide/target/debug/deps/ioc_server-e708bcff9d8aa480)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test an_ioc_built_server_serves_records_and_fields_over_the_wire ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.32s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/link_parity.rs (/opt/rustwide/target/debug/deps/link_parity-717de749693bebb5)
[INFO] [stdout] running 2 tests
[INFO] [stdout] test the_two_stores_render_link_fields_identically ... ok
[INFO] [stdout] test a_constant_link_is_served_as_the_constant_on_both_tiers ... ok
[INFO] [stderr]      Running tests/lockset_property.rs (/opt/rustwide/target/debug/deps/lockset_property-974cd612dc8fa314)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/programmatic.rs (/opt/rustwide/target/debug/deps/programmatic-bdaeb2537c64ce0f)
[INFO] [stdout] test partitioning_agrees_with_union_find ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test the_immutability_reason_names_lock_sets_and_the_base_precedent ... ok
[INFO] [stdout] test a_record_type_the_engine_does_not_support_is_rejected ... ok
[INFO] [stdout] test an_unmodelled_field_is_ignored_the_same_way_on_both_paths ... ok
[INFO] [stdout] test a_db_file_and_the_equivalent_records_produce_the_same_event_stream ... ok
[INFO] [stdout] test both_paths_agree_on_the_served_field_values ... ok
[INFO] [stdout] test both_paths_agree_on_the_record_namespace ... ok
[INFO] [stderr]      Running tests/source_integration.rs (/opt/rustwide/target/debug/deps/source_integration-9d339e2cfd719b1e)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test an_unknown_pv_is_not_claimed ... ok
[INFO] [stderr]    Doc-tests spvirit_ioc
[INFO] [stdout] test every_record_is_claimable_and_gettable ... ok
[INFO] [stdout] test pini_records_process_at_startup_in_definition_order ... ok
[INFO] [stdout] test a_subscriber_receives_the_monitors_a_later_put_causes ... ok
[INFO] [stdout] test a_put_to_a_linked_input_posts_the_written_value_then_the_linked_one ... ok
[INFO] [stdout] test pini_definition_order_differs_from_lock_set_traversal_order ... ok
[INFO] [stdout] test a_put_to_an_unknown_pv_is_an_error_naming_it ... ok
[INFO] [stdout] test a_put_propagates_along_the_chain_and_returns_every_monitor ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5", kill_on_drop: false }`
[INFO] [stdout] ac9b26ff0caf0d86ae68dc3d6192086de0c493e8cd9d109f9524936d415f50b5
