[INFO] fetching crate vpi 0.5.3...
[INFO] testing vpi-0.5.3 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate vpi 0.5.3 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate vpi 0.5.3
[INFO] finished tweaking crates.io crate vpi 0.5.3
[INFO] tweaked toml for crates.io crate vpi 0.5.3 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vpi 0.5.3 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 vpi 0.5.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded num-derive v0.5.1
[INFO] [stderr]   Downloaded vpi-shim v0.5.3
[INFO] [stderr]   Downloaded num-bigint v0.5.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985
[INFO] running `Command { std: "docker" "start" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", 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" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985" "/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" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", 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" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985" "/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.46
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling either v1.18.0
[INFO] [stderr]    Compiling bindgen v0.72.1
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling regex-automata v0.4.15
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling clang-sys v1.8.1
[INFO] [stderr]    Compiling regex v1.13.0
[INFO] [stderr]    Compiling cexpr v0.6.0
[INFO] [stderr]    Compiling num-derive v0.5.1
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling vpi-sys v0.5.3
[INFO] [stderr]    Compiling vpi v0.5.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 22.70s
[INFO] running `Command { std: "docker" "inspect" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", 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" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985" "/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 vpi v0.5.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.63s
[INFO] running `Command { std: "docker" "inspect" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", 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" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985" "/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.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vpi-bfa78adc49996352)
[INFO] [stdout] 
[INFO] [stdout] running 101 tests
[INFO] [stdout] test delays::tests::decode_time_supports_all_known_variants ... ok
[INFO] [stdout] test logic::tests::logic_vec_as_vcd_value_trims_redundant_leading_z ... ok
[INFO] [stdout] test logic::tests::logic_vec_as_vcd_value_keeps_single_zero ... ok
[INFO] [stdout] test logic::tests::logic_vec_from_slice ... ok
[INFO] [stdout] test logic::tests::logic_vec_len ... ok
[INFO] [stdout] test logic::tests::logic_vec_empty_vcd ... ok
[INFO] [stdout] test delays::tests::with_time_type_preserves_values_and_initial_flags ... ok
[INFO] [stdout] test delays::tests::mixed_delay_time_variants_are_rejected ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i128_large_asymmetric_pattern ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i16_rejects_too_many_bits ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i128_large_negative_pattern ... ok
[INFO] [stdout] test delays::tests::infer_time_type_from_sim_delays ... ok
[INFO] [stdout] test logic::tests::logic_vec_as_vcd_value_trims_redundant_leading_x ... ok
[INFO] [stdout] test delays::tests::raw_time_type_round_trip ... ok
[INFO] [stdout] test delays::tests::empty_delay_data_defaults_to_sim_time_type ... ok
[INFO] [stdout] test delays::tests::decode_time_rejects_unknown_type ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i32_negative_pattern ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i32_positive_pattern ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i64_non_symmetric_pattern ... ok
[INFO] [stdout] test logic::tests::logic_vec_is_empty ... ok
[INFO] [stdout] test logic::tests::logic_vec_as_vcd_value_does_not_trim_mixed_unknown_prefixes ... ok
[INFO] [stdout] test logic::tests::logic_vec_as_vcd_value_trims_leading_zeros ... ok
[INFO] [stdout] test logic::tests::logicvec_to_i8_msb_first_sign_extension ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u16_pattern_1101001010110011 ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u16_small_pattern_3bits ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u32_rejects_z_symbol ... ok
[INFO] [stdout] test logic::tests::round_trip_i32_partial_width_24bits_negative ... ok
[INFO] [stdout] test logic::tests::round_trip_i32_positive_1437227853 ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u64_full_width_nonsymmetric ... ok
[INFO] [stdout] test logic::tests::round_trip_i8_max_value ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u64_rejects_over_64_bits ... ok
[INFO] [stdout] test logic::tests::round_trip_i16_pattern_neg_12345 ... ok
[INFO] [stdout] test logic::tests::round_trip_i64_negative_small ... ok
[INFO] [stdout] test logic::tests::round_trip_i8_positive_small ... ok
[INFO] [stdout] test logic::tests::round_trip_u32_pattern_10011001_10011001_10011001_10011001 ... ok
[INFO] [stdout] test logic::tests::round_trip_u64_alternating ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u8_pattern_10010110 ... ok
[INFO] [stdout] test logic::tests::round_trip_u64_asymmetric ... ok
[INFO] [stdout] test logic::tests::logicvec_to_u8_rejects_x_symbol ... ok
[INFO] [stdout] test logic::tests::round_trip_i16_partial_width_12bits_positive ... ok
[INFO] [stdout] test logic::tests::round_trip_u8_max ... ok
[INFO] [stdout] test logic::tests::round_trip_i8_negative_one ... ok
[INFO] [stdout] test logic::tests::round_trip_u8_pattern_10101010 ... ok
[INFO] [stdout] test logic::tests::round_trip_u8_zero ... ok
[INFO] [stdout] test logic::tests::scalar_vector_to_vecval_round_trips_common_states ... ok
[INFO] [stdout] test logic::tests::vector_value_uses_zero_when_words_are_missing ... ok
[INFO] [stdout] test simulator::tests::timescale_display_formats_as_unit_slash_precision ... ok
[INFO] [stdout] test logic::tests::round_trip_u16_pattern_01010101_10101010 ... ok
[INFO] [stdout] test logic::tests::round_trip_i64_positive_7723089878572259660 ... ok
[INFO] [stdout] test logic::tests::round_trip_i32_negative_large ... ok
[INFO] [stdout] test logic::tests::round_trip_i8_min_value ... ok
[INFO] [stdout] test tests::ascii_is_preserved ... ok
[INFO] [stdout] test logic::tests::scalar_to_ab_bits_maps_four_state_logic ... ok
[INFO] [stdout] test time::tests::scaled_real_round_trip_preserves_value ... ok
[INFO] [stdout] test logic::tests::scalar_vector_to_string_renders_expected_symbols ... ok
[INFO] [stdout] test logic::tests::vector_value_decodes_ab_encoding_and_reverses_bit_order ... ok
[INFO] [stdout] test simulator::tests::maps_known_power_values_to_expected_units ... ok
[INFO] [stdout] test simulator::tests::timescale_unit_and_precision_helpers_use_power_mapping ... ok
[INFO] [stdout] test tests::mixed_ascii_and_non_ascii_is_converted_correctly ... ok
[INFO] [stdout] test time::tests::display_formats_match_variants ... ok
[INFO] [stdout] test time::tests::sim_time_round_trip_preserves_high_low_parts ... ok
[INFO] [stdout] test time::tests::suppress_round_trip_preserves_type ... ok
[INFO] [stdout] test time::tests::to_u64_returns_sim_value_only ... ok
[INFO] [stdout] test value::tests::encode_value_for_put_string_uses_string_format_and_storage ... ok
[INFO] [stdout] test value::tests::cstring_lossy_no_nul_strips_interior_nuls ... ok
[INFO] [stdout] test value::tests::encode_value_for_put_time_uses_time_format_and_storage ... ok
[INFO] [stdout] test value::tests::encode_value_for_put_vector_allocates_vecval_storage ... ok
[INFO] [stdout] test value::tests::encode_value_for_put_longint_uses_decimal_string_path ... ok
[INFO] [stdout] test value::tests::get_value_array_on_null_handle_returns_none ... ok
[INFO] [stdout] test value::tests::put_int_value_on_null_handle_returns_null ... ok
[INFO] [stdout] test value::tests::put_value_on_null_handle_returns_null ... ok
[INFO] [stdout] test value::tests::put_value_array_flags_empty_is_zero ... ok
[INFO] [stdout] test value::tests::put_value_scheduled_on_null_handle_returns_null ... ok
[INFO] [stdout] test value::tests::put_value_delay_matches_vpi_constants ... ok
[INFO] [stdout] test value::tests::strength_value_display_renders_logic_and_drive_strengths ... ok
[INFO] [stdout] test value::tests::string_array_to_value_array_promotes_by_requested_format ... ok
[INFO] [stdout] test value::tests::string_array_to_value_array_rejects_non_string_formats ... ok
[INFO] [stdout] test value::tests::scalar_vector_to_string_renders_expected_symbols ... ok
[INFO] [stdout] test value::tests::strength_array_to_value_array_round_trips_through_demotion ... ok
[INFO] [stdout] test value::tests::string_array_to_value_array_supports_vector_like_formats ... ok
[INFO] [stdout] test value::tests::string_array_to_value_array_rejects_invalid_vector_like_strings ... ok
[INFO] [stdout] test value::tests::time_array_to_value_array_round_trips_through_demotion ... ok
[INFO] [stdout] test value::tests::value_array_to_int_array_rejects_mixed_values ... ok
[INFO] [stdout] test value::tests::value_array_to_string_array_rejects_non_string_format ... ok
[INFO] [stdout] test value::tests::value_array_to_string_array_string_rejects_vector_variants ... ok
[INFO] [stdout] test logic::tests::round_trip_u16_pattern_11001100_11110000 ... ok
[INFO] [stdout] test logic::tests::round_trip_u32_large_value ... ok
[INFO] [stdout] test time::tests::time_type_matches_vpi_constants ... ok
[INFO] [stdout] test tests::non_ascii_chars_are_replaced_with_question_mark ... ok
[INFO] [stdout] test logic::tests::round_trip_i64_negative_large ... ok
[INFO] [stdout] test value::tests::put_value_flags_empty_is_zero ... ok
[INFO] [stdout] test value::tests::value_array_to_string_array_accepts_string_backed_formats ... ok
[INFO] [stdout] test value::tests::value_array_to_strength_array_demotes_strength_values ... ok
[INFO] [stdout] test value::tests::value_array_to_strength_array_rejects_mixed_values ... ok
[INFO] [stdout] test value::tests::value_array_to_time_array_demotes_time_values ... ok
[INFO] [stdout] test value::tests::value_type_display_has_human_readable_labels ... ok
[INFO] [stdout] test systf::tests::get_systf_info_on_null_handle_returns_none ... ok
[INFO] [stdout] test logic::tests::round_trip_u8_partial_width_4bits ... ok
[INFO] [stdout] test value::tests::value_array_to_string_array_supports_vector_like_formats_directly ... ok
[INFO] [stdout] test tests::interior_nul_panics - should panic ... ok
[INFO] [stdout] test time::tests::from_unknown_type_panics - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 101 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vpi
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test src/logic.rs - logic::LogicVec (line 171) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec (line 176) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec (line 181) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec (line 190) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::empty (line 206) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::fmt (line 584) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from (line 657) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from (line 675) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from (line 691) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from (line 708) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from_int (line 399) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from_int (line 406) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from_str (line 287) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from_uint (line 441) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::from_uint (line 448) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::is_empty (line 261) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::iter (line 235) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::len (line 248) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::raw_data (line 219) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVec::try_from_str (line 312) ... ignored
[INFO] [stdout] test src/logic.rs - logic::LogicVecToIntError (line 742) ... ignored
[INFO] [stdout] test src/macros.rs - macros::startup_routines (line 7) ... ignored
[INFO] [stdout] test src/systf.rs - systf (line 9) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 22 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985", kill_on_drop: false }`
[INFO] [stdout] e16f40023b84f6d40da4a0900f3c9af85d7ff2755c31b8ceb7c47ddb02b28985
