[INFO] fetching crate spvirit-server 0.3.4...
[INFO] testing spvirit-server-0.3.4 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate spvirit-server 0.3.4 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate spvirit-server 0.3.4
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate spvirit-server 0.3.4
[INFO] tweaked toml for crates.io crate spvirit-server 0.3.4 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate spvirit-server 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-server 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 get_if_addrs v0.5.3
[INFO] [stderr]   Downloaded c_linked_list v1.1.1
[INFO] [stderr]   Downloaded get_if_addrs-sys v0.1.1
[INFO] [stderr]   Downloaded spvirit-codec v0.3.4
[INFO] [stderr]   Downloaded spvirit-types v0.3.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3
[INFO] running `Command { std: "docker" "start" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", 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" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3" "/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" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", 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" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling c_linked_list v1.1.1
[INFO] [stderr]    Compiling spvirit-types v0.3.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling spvirit-codec v0.3.4
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling get_if_addrs v0.5.3
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling spvirit-server v0.3.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 32.94s
[INFO] running `Command { std: "docker" "inspect" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", 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" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3" "/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 tracing v0.1.44
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling spvirit-codec v0.3.4
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling spvirit-server v0.3.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 01s
[INFO] running `Command { std: "docker" "inspect" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", 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" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3" "/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.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/spvirit_server-902e4d0db0cd7baa)
[INFO] [stdout] 
[INFO] [stdout] running 191 tests
[INFO] [stdout] test apply::tests::array_put_restamps ... ok
[INFO] [stdout] test apply::tests::ndarray_put_honours_client_supplied_data_time_stamp ... ok
[INFO] [stdout] test apply::tests::epoch_zero_client_timestamp_falls_back_to_server_time ... ok
[INFO] [stdout] test apply::tests::ndarray_put_restamps_both_time_stamp_and_data_time_stamp ... ok
[INFO] [stdout] test apply::tests::put_with_compute_alarms_false_does_not_update_alarm ... ok
[INFO] [stdout] test apply::tests::put_with_compute_alarms_true_clears_alarm_within_normal_range ... ok
[INFO] [stdout] test apply::tests::put_with_compute_alarms_true_sets_high_severity ... ok
[INFO] [stdout] test apply::tests::array_put_with_unchanged_value_still_restamps ... ok
[INFO] [stdout] test apply::tests::put_with_client_timestamp_keeps_it_verbatim ... ok
[INFO] [stdout] test apply::tests::unchanged_value_still_restamps ... ok
[INFO] [stdout] test apply::tests::put_with_compute_alarms_true_sets_hihi_severity ... ok
[INFO] [stdout] test apply::tests::unrecognised_fields_still_restamp ... ok
[INFO] [stdout] test apply::tests::bare_scalar_body_is_wrapped_and_stamped ... ok
[INFO] [stdout] test apply::tests::enum_put_restamps ... ok
[INFO] [stdout] test apply::tests::ndarray_put_with_only_timestamp_fields_does_not_report_value_changed ... ok
[INFO] [stdout] test apply::tests::table_put_with_only_timestamp_field_does_not_report_value_changed ... ok
[INFO] [stdout] test apply::tests::put_without_timestamp_stamps_server_time ... ok
[INFO] [stdout] test db::tests::hash_inside_a_quoted_value_is_not_treated_as_a_comment ... ok
[INFO] [stdout] test db::tests::empty_record_name_is_rejected ... ok
[INFO] [stdout] test db::tests::macro_substitution_expands_dollar_braces_and_parens ... ok
[INFO] [stdout] test db::tests::parse_scan_modes ... ok
[INFO] [stdout] test db::tests::parse_error_names_the_file_and_line ... ok
[INFO] [stdout] test db::tests::parse_supported_records ... ok
[INFO] [stdout] test db::tests::include_cycle_is_reported_rather_than_hanging ... ok
[INFO] [stdout] test db::tests::trailing_inline_comments_after_a_field_value_are_stripped ... ok
[INFO] [stdout] test events::tests::a_handler_may_post_another_event ... ok
[INFO] [stdout] test db::tests::quoted_field_values_may_continue_across_lines ... ok
[INFO] [stdout] test db::tests::unterminated_quote_reports_the_opening_line ... ok
[INFO] [stdout] test db::tests::include_pulls_in_a_sibling_file ... ok
[INFO] [stdout] test db::tests::archiver_demo_db_parses_and_yields_expected_record_count ... ok
[INFO] [stdout] test events::tests::a_sink_may_post_another_event_without_deadlocking ... ok
[INFO] [stdout] test events::tests::drain_with_nothing_queued_is_fine_without_a_dispatcher ... ok
[INFO] [stdout] test events::tests::handler_can_write_the_store ... ok
[INFO] [stdout] test events::tests::handler_receives_the_event_name ... ok
[INFO] [stdout] test events::tests::handlers_are_serialized_in_registration_order ... ok
[INFO] [stdout] test events::tests::handlers_run_on_the_dispatcher_not_inline ... ok
[INFO] [stdout] test db::tests::undefined_macro_is_an_error_naming_the_macro ... ok
[INFO] [stdout] test events::tests::only_handlers_for_the_posted_event_run ... ok
[INFO] [stdout] test events::tests::post_calls_sinks_in_registration_order ... ok
[INFO] [stdout] test events::tests::post_with_no_sinks_is_a_noop ... ok
[INFO] [stdout] test db::tests::shipped_example_db_parses_and_yields_expected_records ... ok
[INFO] [stdout] test field_provider::tests::does_not_resolve_unknown_bases_fields_or_bare_names ... ok
[INFO] [stdout] test events::tests::full_queue_drops_and_counts ... ok
[INFO] [stdout] test field_provider::tests::resolve_field_info_never_reads_the_value ... ok
[INFO] [stdout] test field_provider::tests::resolves_a_field_payload_with_the_records_description ... ok
[INFO] [stdout] test field_provider::tests::the_descriptor_matches_the_payload_the_value_would_produce ... ok
[INFO] [stdout] test group::tests::merge_groups ... ok
[INFO] [stdout] test group::tests::parse_basic_group ... ok
[INFO] [stdout] test group::tests::parse_info_group_absolute_channel ... ok
[INFO] [stdout] test field_provider::tests::field_kind_maps_scalar_variants ... ok
[INFO] [stdout] test field_provider::tests::long_string_claims_only_string_fields ... ok
[INFO] [stdout] test group::tests::parse_info_group_prefix ... ok
[INFO] [stdout] test group::tests::parse_member_id ... ok
[INFO] [stdout] test group::tests::parse_member_no_id ... ok
[INFO] [stdout] test group::tests::parse_error_missing_channel ... ok
[INFO] [stdout] test group::tests::parse_multiple_groups ... ok
[INFO] [stdout] test db::tests::unknown_record_type_is_no_longer_silently_dropped ... ok
[INFO] [stdout] test group::tests::parse_minimal_member ... ok
[INFO] [stdout] test group::tests::parse_proc_mapping ... ok
[INFO] [stdout] test group::tests::trigger_validation_self_reference ... ok
[INFO] [stdout] test group::tests::trigger_validation_star_passes ... ok
[INFO] [stdout] test group::tests::trigger_validation_unknown_field ... ok
[INFO] [stdout] test pv::tests::aai_read_only_aao_writable ... ok
[INFO] [stdout] test monitor::tests::filtered_delta_emitted_when_selected_field_changes ... ok
[INFO] [stdout] test pv::tests::ai_constructor_builds_record_template ... ok
[INFO] [stdout] test monitor::tests::filtered_first_frame_then_delta_none_when_selected_fields_unchanged ... ok
[INFO] [stdout] test monitor::tests::unfiltered_first_frame_full_then_suppress_duplicate_then_resend_on_change ... ok
[INFO] [stdout] test pv::tests::attach_mints_typed_handle_and_checks_type ... ok
[INFO] [stdout] test pv::tests::alarm_limits_set_value_alarm_block ... ok
[INFO] [stdout] test pv::tests::bool_and_string_constructors ... ok
[INFO] [stdout] test pv::tests::ao_is_writable_with_drive_limits ... ok
[INFO] [stdout] test pv::tests::longin_longout_constructors ... ok
[INFO] [stdout] test pv::tests::longout_set_get_roundtrip ... ok
[INFO] [stdout] test pv::tests::mbbi_mbbo_constructors ... ok
[INFO] [stdout] test pv::tests::mbbo_set_get_index_with_bounds ... ok
[INFO] [stdout] test pv::tests::on_put_wrapper_unwraps_structure_wrapped_scalar_put ... ok
[INFO] [stdout] test pv::tests::on_put_callback_travels_to_store ... ok
[INFO] [stdout] test pv::tests::pverror_display ... ok
[INFO] [stdout] test pv::tests::pvscalar_f64_accepts_f32_widening ... ok
[INFO] [stdout] test pv::tests::pvscalar_rejects_wrong_variant ... ok
[INFO] [stdout] test pv::tests::pvscalar_roundtrip_f64 ... ok
[INFO] [stdout] test pv::tests::scalar_attach_rejects_array_backed_record ... ok
[INFO] [stdout] test pv::tests::scalar_value_from_decoded_maps_one_to_one ... ok
[INFO] [stdout] test pv::tests::scalar_value_handle_constructors ... ok
[INFO] [stdout] test pv::tests::bind_then_set_get_roundtrip ... ok
[INFO] [stdout] test pv::tests::set_alarm_missing_record_is_not_found ... ok
[INFO] [stdout] test pv::tests::pv_array_roundtrip_and_serve ... ok
[INFO] [stdout] test pv::tests::scalar_value_handle_set_get_preserves_variant ... ok
[INFO] [stdout] test pv::tests::set_alarm_on_enum_and_array_records ... ok
[INFO] [stdout] test pv::tests::set_get_before_bind_errors ... ok
[INFO] [stdout] test pv::tests::set_alarm_posts_and_reads_back ... ok
[INFO] [stdout] test pv::tests::set_nt_payload_stamps_missing_timestamp_but_keeps_explicit_one ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_cross_variant_preserves_target_variant_from_i32 ... ok
[INFO] [stdout] test pv::tests::mdel_adel_go_to_raw_fields ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_on_raw_nt_enum_record ... ok
[INFO] [stdout] test pv::tests::set_same_value_is_ok_not_not_found ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_same_variant_f32_is_exact ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_cross_variant_preserves_target_variant_from_f64 ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_same_variant_i64_is_exact ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_stamps_timestamp ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_unchanged_u64_returns_false ... ok
[INFO] [stdout] test pva_server::tests::a_builder_registered_sink_receives_posted_events ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_same_variant_u64_is_exact ... ok
[INFO] [stdout] test pv::tests::set_scalar_value_stamps_enum_timestamp ... ok
[INFO] [stdout] test handler::tests::udp_search_scopes_the_peer_identity ... ok
[INFO] [stdout] test pva_server::tests::builder_creates_records ... ok
[INFO] [stdout] test pva_server::tests::builder_defaults ... ok
[INFO] [stdout] test pva_server::tests::builder_guid_overrides_the_minted_guid ... ok
[INFO] [stdout] test pva_server::tests::builder_on_put_callback ... ok
[INFO] [stdout] test pva_server::tests::builder_port_override ... ok
[INFO] [stdout] test pva_server::tests::builder_scan_callback ... ok
[INFO] [stdout] test pva_server::tests::builder_waveform ... ok
[INFO] [stdout] test pva_server::tests::homogeneous_iterator_feeds_serve_without_manual_erasure ... ok
[INFO] [stdout] test pva_server::tests::link_propagates_on_set_value ... ok
[INFO] [stdout] test pva_server::tests::builder_db_string ... ok
[INFO] [stdout] test pva_server::tests::on_start_hook_can_write_the_store ... ok
[INFO] [stdout] test pva_server::tests::on_start_hook_write_reaches_a_subscribed_monitor ... ok
[INFO] [stdout] test pva_server::tests::on_start_hooks_are_stored_and_runnable_in_order ... ok
[INFO] [stdout] test pva_server::tests::post_event_reaches_a_builder_registered_handler ... ok
[INFO] [stdout] test pva_server::tests::pva_server_mints_typed_handles_pre_run ... ok
[INFO] [stdout] test pva_server::tests::no_client_can_connect_before_start_hooks_finish ... ok
[INFO] [stdout] test pva_server::tests::running_server_add_enum_and_table ... ok
[INFO] [stdout] test pva_server::tests::running_server_add_scalar_and_array ... ok
[INFO] [stdout] test pva_server::tests::running_server_can_post_to_a_serve_builder_handler ... ok
[INFO] [stdout] test events::tests::a_panicking_sink_does_not_truncate_the_fan_out ... ok
[INFO] [stdout] test events::tests::drain_without_a_dispatcher_fails_immediately_and_says_why - should panic ... ok
[INFO] [stdout] test pva_server::tests::serve_builder_binds_handles_and_registers_everything ... ok
[INFO] [stdout] test pva_server::tests::running_server_mints_handles_to_db_records ... ok
[INFO] [stdout] test pva_server::tests::serve_builder_start_returns_only_after_hooks_have_run ... ok
[INFO] [stdout] test pva_server::tests::serve_builder_forwards_on_start_and_on_event ... ok
[INFO] [stdout] test pva_server::tests::store_runtime_get_set ... ok
[INFO] [stdout] test pvstore::tests::a_source_shadowing_a_store_still_wins_the_claim ... ok
[INFO] [stdout] test pvstore::tests::a_store_added_late_still_sorts_by_order ... ok
[INFO] [stdout] test pvstore::tests::a_store_winning_its_own_claim_consults_nothing_else ... ok
[INFO] [stdout] test events::tests::dispatcher_survives_a_panicking_handler ... ok
[INFO] [stdout] test pvstore::tests::an_unshadowed_source_claim_is_also_checked_only_once ... ok
[INFO] [stdout] test pva_server::tests::a_malformed_db_file_aborts_the_builder_rather_than_serving_nothing ... ok
[INFO] [stdout] test pva_server::tests::a_malformed_db_string_aborts_the_builder_rather_than_serving_nothing - should panic ... ok
[INFO] [stdout] test pva_server::tests::run_aborts_when_a_start_hook_panics ... ok
[INFO] [stdout] test pva_server::tests::a_panicking_on_start_hook_reports_its_cause ... ok
[INFO] [stdout] test pva_server::tests::serve_builder_start_surfaces_a_start_hook_abort ... ok
[INFO] [stdout] test record_fields::tests::a_forward_link_drops_its_field ... ok
[INFO] [stdout] test pvstore::tests::the_shadow_check_runs_once_per_pv ... ok
[INFO] [stdout] test pvstore::tests::stores_are_recorded_as_stores_and_sources_are_not ... ok
[INFO] [stdout] test record_fields::tests::a_terse_link_renders_with_both_modifiers_spelled_out ... ok
[INFO] [stdout] test record_fields::tests::constants_and_unmodelled_modifiers_do_not_canonicalise ... ok
[INFO] [stdout] test record_fields::tests::dbcommon_defaults_cover_key_fields ... ok
[INFO] [stdout] test pvstore::tests::the_shadow_warning_is_emitted_once_not_just_counted ... ok
[INFO] [stdout] test record_fields::tests::parses_long_string_suffix ... ok
[INFO] [stdout] test record_fields::tests::parses_simple_field_ref ... ok
[INFO] [stdout] test record_fields::tests::payload_for_value_long_string_needs_a_string ... ok
[INFO] [stdout] test record_fields::tests::payload_for_value_wraps_a_scalar_with_its_description ... ok
[INFO] [stdout] test record_fields::tests::claims_field_pvs_read_only ... ok
[INFO] [stdout] test record_fields::tests::computed_fields_resolve ... ok
[INFO] [stdout] test record_fields::tests::record_type_names_match_db_names ... ok
[INFO] [stdout] test record_fields::tests::put_is_rejected ... ok
[INFO] [stdout] test record_fields::tests::rejects_non_field_names ... ok
[INFO] [stdout] test record_fields::tests::raw_db_fields_take_precedence_over_defaults ... ok
[INFO] [stdout] test record_fields::tests::the_implied_val_field_is_never_printed ... ok
[INFO] [stdout] test record_fields::tests::does_not_claim_non_field_names ... ok
[INFO] [stdout] test request_ctx::tests::context_visible_within_scope ... ok
[INFO] [stdout] test simple_store::tests::client_supplied_timestamp_posts_even_when_value_is_unchanged ... ok
[INFO] [stdout] test request_ctx::tests::context_absent_outside_scope ... ok
[INFO] [stdout] test simple_store::tests::get_nt_returns_full_payload ... ok
[INFO] [stdout] test simple_store::tests::get_snapshot_returns_payload ... ok
[INFO] [stdout] test simple_store::tests::has_pv_returns_true_for_existing ... ok
[INFO] [stdout] test record_fields::tests::subscribe_delivers_initial_snapshot ... ok
[INFO] [stdout] test simple_store::tests::on_put_callback_is_invoked ... ok
[INFO] [stdout] test request_ctx::tests::set_credentials_visible_via_current_request ... ok
[INFO] [stdout] test simple_store::tests::put_advances_the_timestamp ... ok
[INFO] [stdout] test simple_store::tests::put_nt_updates_scalar_array_table_and_ndarray ... ok
[INFO] [stdout] test simple_store::tests::put_of_identical_value_restamps_without_posting ... ok
[INFO] [stdout] test simple_store::tests::mdel_deadband_suppresses_small_monitor_updates ... ok
[INFO] [stdout] test simple_store::tests::put_value_rejects_readonly ... ok
[INFO] [stdout] test simple_store::tests::put_value_updates_writable_record ... ok
[INFO] [stdout] test simple_store::tests::put_wire_rejects_out_of_range_enum_index ... ok
[INFO] [stdout] test simple_store::tests::remove_deletes_record_and_is_idempotent ... ok
[INFO] [stdout] test simple_store::tests::descriptor_matches_value_type ... ok
[INFO] [stdout] test simple_store::tests::set_array_value_updates_all_scalar_array_types ... ok
[INFO] [stdout] test simple_store::tests::set_value_bypasses_writable_check ... ok
[INFO] [stdout] test record_fields::tests::dbcommon_defaults_fill_absent_fields ... ok
[INFO] [stdout] test simple_store::tests::validator_rejects_put_before_apply ... ok
[INFO] [stdout] test simple_store::tests::validator_allows_structure_wrapped_put_through ... ok
[INFO] [stdout] test simple_store::tests::store_stamps_initial_timestamps_on_static_records ... ok
[INFO] [stdout] test simple_store::tests::subscribe_receives_updates ... ok
[INFO] [stdout] test types::tests::set_time_stamp_on_generic_record_is_a_no_op ... ok
[INFO] [stdout] test types::tests::set_time_stamp_overwrites_an_existing_stamp ... ok
[INFO] [stdout] test record_fields::tests::link_fields_render_canonically_out_of_the_raw_db ... ok
[INFO] [stdout] test record_fields::tests::long_string_serves_utf8_bytes ... ok
[INFO] [stdout] test pva_server::tests::scan_tasks_do_not_run_before_start_hooks_finish ... ok
[INFO] [stdout] test simple_store::tests::on_put_fires_for_a_value_unchanged_put ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 191 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/segmented_put.rs (/opt/rustwide/target/debug/deps/segmented_put-5385304329317459)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test segmented_put_matches_unsegmented_put ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/store_coexistence.rs (/opt/rustwide/target/debug/deps/store_coexistence-b31d7c0f8da09ac7)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test disjoint_stores_build_cleanly ... ok
[INFO] [stdout] test two_disjoint_stores_each_serve_their_own_records ... ok
[INFO] [stdout] test the_combined_name_list_is_stable_across_runs ... ok
[INFO] [stdout] test a_scan_naming_an_engine_record_is_a_build_error - should panic ... ok
[INFO] [stdout] test every_overlapping_name_is_reported_at_once_and_sorted - should panic ... ok
[INFO] [stdout] test the_overlap_panic_names_a_real_builder_method - should panic ... ok
[INFO] [stdout] test an_on_put_naming_an_engine_record_is_a_build_error - should panic ... ok
[INFO] [stdout] test a_link_naming_an_engine_record_is_a_build_error - should panic ... ok
[INFO] [stdout] test two_stores_owning_the_same_record_is_a_build_error - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tier_parity.rs (/opt/rustwide/target/debug/deps/tier_parity-6218336777ee529c)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test a_custom_source_and_the_builtin_store_are_indistinguishable ... ok
[INFO] [stdout] test an_unknown_pv_looks_the_same_on_both_tiers ... ok
[INFO] [stdout] test tier_numerals_in_this_file_name_the_axis_tiers ... ok
[INFO] [stdout] test field_pvs_report_read_only_on_every_tier ... ok
[INFO] [stdout] test field_resolution_matches_across_providers ... ok
[INFO] [stdout] test an_input_record_is_not_writable_on_the_builtin_store ... ok
[INFO] [stderr]    Doc-tests spvirit_server
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test src/lib.rs - (line 9) ... ignored
[INFO] [stdout] test src/pv.rs - pv (line 10) ... ignored
[INFO] [stdout] test src/pv.rs - pv (line 28) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server (line 5) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServer (line 775) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServer::add_source (line 907) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServerBuilder (line 45) ... ignored
[INFO] [stdout] test src/pvstore.rs - pvstore::Source (line 43) ... ignored
[INFO] [stdout] test src/pv.rs - pv::Pv<ScalarValue>::scalar_out (line 584) ... ok
[INFO] [stdout] test src/pv.rs - pv::Pv<ScalarValue>::scalar_in (line 567) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 8 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServerBuilder::link (line 522) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServerBuilder::on_event (line 488) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServerBuilder::on_start (line 467) ... ignored
[INFO] [stdout] test src/pva_server.rs - pva_server::PvaServerBuilder::source (line 549) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.00s; merged doctests compilation took 0.90s
[INFO] running `Command { std: "docker" "inspect" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3", kill_on_drop: false }`
[INFO] [stdout] 8388ee53a148dfee0b6ad7e3709fae90b3b6af005e6cb5648a197c1a9bf52ee3
