[INFO] fetching crate winevt-binxml 0.1.3...
[INFO] testing winevt-binxml-0.1.3 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate winevt-binxml 0.1.3 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate winevt-binxml 0.1.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate winevt-binxml 0.1.3
[INFO] tweaked toml for crates.io crate winevt-binxml 0.1.3 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate winevt-binxml 0.1.3 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 winevt-binxml 0.1.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.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-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d
[INFO] running `Command { std: "docker" "start" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", 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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d" "/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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", 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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling uuid v1.23.4
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling forensicnomicon-core v1.0.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling forensicnomicon-data v1.0.0
[INFO] [stderr]    Compiling forensicnomicon v1.0.0
[INFO] [stderr]    Compiling winevt-core v0.3.0
[INFO] [stderr]    Compiling winevt-binxml v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.98s
[INFO] running `Command { std: "docker" "inspect" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", 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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling encoding_index_tests v0.1.4
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling zerocopy v0.8.52
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling time-macros v0.2.30
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling sonic-number v0.1.2
[INFO] [stderr]    Compiling sonic-simd v0.1.4
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling scroll_derive v0.13.1
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling bumpalo v3.20.3
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling console v0.16.3
[INFO] [stderr]    Compiling encoding-index-korean v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-tradchinese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-japanese v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-singlebyte v1.20141219.5
[INFO] [stderr]    Compiling encoding-index-simpchinese v1.20141219.5
[INFO] [stderr]    Compiling shell-words v1.1.1
[INFO] [stderr]    Compiling termcolor v1.4.1
[INFO] [stderr]    Compiling plain v0.2.3
[INFO] [stderr]    Compiling indoc v2.0.7
[INFO] [stderr]    Compiling encoding v0.2.33
[INFO] [stderr]    Compiling scroll v0.13.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling goblin v0.10.7
[INFO] [stderr]    Compiling time v0.3.51
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling jiff v0.2.29
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling simplelog v0.12.2
[INFO] [stderr]    Compiling dialoguer v0.12.0
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling forensicnomicon-core v1.0.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling faststr v0.2.34
[INFO] [stderr]    Compiling winstructs v0.3.2
[INFO] [stderr]    Compiling sonic-rs v0.5.8
[INFO] [stderr]    Compiling forensicnomicon-data v1.0.0
[INFO] [stderr]    Compiling utf16-simd v0.1.0
[INFO] [stderr]    Compiling evtx v0.11.2
[INFO] [stderr]    Compiling forensicnomicon v1.0.0
[INFO] [stderr]    Compiling winevt-core v0.3.0
[INFO] [stderr]    Compiling winevt-binxml v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 38.04s
[INFO] running `Command { std: "docker" "inspect" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", 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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/winevt-binxml/3cd9680df5d4830a/out/winevt_binxml-3cd9680df5d4830a)
[INFO] [stdout] running 117 tests
[INFO] [stdout] test cursor::tests::at_clamps_position ... ok
[INFO] [stdout] test cursor::tests::read_u32_i32_le ... ok
[INFO] [stdout] test cursor::tests::read_u32_truncated_is_error ... ok
[INFO] [stdout] test cursor::tests::read_u64_i64_le ... ok
[INFO] [stdout] test cursor::tests::read_u8_and_i8 ... ok
[INFO] [stdout] test cursor::tests::seek_past_end_is_error ... ok
[INFO] [stdout] test cursor::tests::take_past_end_is_error ... ok
[INFO] [stdout] test cursor::tests::read_u16_i16_le ... ok
[INFO] [stdout] test cursor::tests::skip_valid_and_past_end ... ok
[INFO] [stdout] test cursor::tests::utf16le_chars ... ok
[INFO] [stdout] test cursor::tests::utf16le_chars_lossy_on_bad_surrogate ... ok
[INFO] [stdout] test cursor::tests::utf16le_chars_overflow_guard ... ok
[INFO] [stdout] test deserializer::tests::iteration_cap_is_enforced ... ok
[INFO] [stdout] test cursor::tests::utf16le_len_prefixed ... ok
[INFO] [stdout] test deserializer::tests::depth_cap_is_enforced ... ok
[INFO] [stdout] test deserializer::tests::empty_element_with_children_close ... ok
[INFO] [stdout] test deserializer::tests::element_with_attribute ... ok
[INFO] [stdout] test deserializer::tests::flushes_unclosed_elements_without_data_loss ... ok
[INFO] [stdout] test cursor::tests::utf16le_chars_truncated_is_error ... ok
[INFO] [stdout] test cursor::tests::new_and_getters ... ok
[INFO] [stdout] test deserializer::tests::self_closing_empty_element ... ok
[INFO] [stdout] test deserializer::tests::substituted_attribute_value_outside_template_is_unsupported ... ok
[INFO] [stdout] test cursor::tests::read_f32_f64_le ... ok
[INFO] [stdout] test deserializer::tests::top_level_substitution_is_unsupported ... ok
[INFO] [stdout] test cursor::tests::seek_valid_and_to_end ... ok
[INFO] [stdout] test deserializer::tests::invalid_attribute_value_token_is_error ... ok
[INFO] [stdout] test deserializer::tests::nested_elements ... ok
[INFO] [stdout] test deserializer::tests::element_with_text_child ... ok
[INFO] [stdout] test deserializer::tests::truncated_value_is_error ... ok
[INFO] [stdout] test deserializer::tests::unbalanced_close_is_error ... ok
[INFO] [stdout] test deserializer::tests::valid_but_unexpected_token_is_unknown ... ok
[INFO] [stdout] test deserializer::tests::truncated_template_instance_errors ... ok
[INFO] [stdout] test extract::tests::empty_channel_is_none_and_loose_text_is_skipped ... ok
[INFO] [stdout] test deserializer::tests::unexpected_attribute_value_token_is_error ... ok
[INFO] [stdout] test extract::tests::extracts_system_envelope_fields ... ok
[INFO] [stdout] test extract::tests::missing_fields_are_none_and_empty ... ok
[INFO] [stdout] test extract::tests::flattens_userdata_recursively ... ok
[INFO] [stdout] test extract::tests::flattens_named_data_eventdata ... ok
[INFO] [stdout] test extract::tests::unnamed_data_elements_kept_positionally ... ok
[INFO] [stdout] test deserializer::tests::optional_substituted_attribute_outside_template_is_unsupported ... ok
[INFO] [stdout] test json::tests::attribute_only_element ... ok
[INFO] [stdout] test json::tests::leaf_text_element_is_bare_string ... ok
[INFO] [stdout] test name::tests::back_reference_resolves_from_offset ... ok
[INFO] [stdout] test json::tests::attribute_plus_text_uses_text_key ... ok
[INFO] [stdout] test name::tests::inline_name_is_read_and_cursor_advances ... ok
[INFO] [stdout] test json::tests::empty_nodes_is_empty_object ... ok
[INFO] [stdout] test name::tests::new_cache_is_empty ... ok
[INFO] [stdout] test name::tests::out_of_range_offset_is_error_not_panic ... ok
[INFO] [stdout] test deserializer::tests::unknown_token_is_error ... ok
[INFO] [stdout] test extract::tests::flattens_flat_sysmon_eventdata ... ok
[INFO] [stdout] test reader::tests::decodes_a_synthetic_record ... ok
[INFO] [stdout] test reader::tests::invalid_header_errors_not_silently_empty ... ok
[INFO] [stdout] test reader::tests::non_evtx_input_yields_no_records ... ok
[INFO] [stdout] test reader::tests::record_extending_past_chunk_stops_the_chunk ... ok
[INFO] [stdout] test reader::tests::valid_header_zero_records_is_ok_empty ... ok
[INFO] [stdout] test template::tests::another_substitution_value_resolves ... ok
[INFO] [stdout] test template::tests::null_attribute_value_is_empty ... ok
[INFO] [stdout] test template::tests::excessive_substitution_count_is_rejected ... ok
[INFO] [stdout] test template::tests::template_instance_resolves_scalar_substitution ... ok
[INFO] [stdout] test extract::tests::no_event_element_yields_default ... ok
[INFO] [stdout] test json::tests::nested_event_structure ... ok
[INFO] [stdout] test tests::end_of_stream_terminates_successfully ... ok
[INFO] [stdout] test tests::empty_binxml_returns_error ... ok
[INFO] [stdout] test json::tests::loose_text_node_is_ignored_at_top_level ... ok
[INFO] [stdout] test tests::iteration_limit_not_triggered_on_normal_payload ... ok
[INFO] [stdout] test json::tests::repeated_children_become_array ... ok
[INFO] [stdout] test name::tests::back_reference_uses_cache_on_second_hit ... ok
[INFO] [stdout] test cursor::tests::take_advances_and_borrows ... ok
[INFO] [stdout] test template::tests::embedded_binxml_substitution_splices_subtree ... ok
[INFO] [stdout] test reader::tests::absurd_record_size_stops_the_chunk ... ok
[INFO] [stdout] test reader::tests::bad_record_magic_stops_the_chunk ... ok
[INFO] [stdout] test template::tests::back_referenced_definition_resolves ... ok
[INFO] [stdout] test template::tests::substituted_scalar_attribute_resolves ... ok
[INFO] [stdout] test tests::iteration_limit_is_enforced ... ok
[INFO] [stdout] test tests::truncated_binxml_returns_error ... ok
[INFO] [stdout] test tests::binxml_summary_fields_are_optional ... ok
[INFO] [stdout] test tests::scan_binxml_random_bytes_does_not_panic ... ok
[INFO] [stdout] test tests::unknown_opcode_returns_error ... ok
[INFO] [stdout] test template::tests::null_substitution_value_is_omitted ... ok
[INFO] [stdout] test template::tests::optional_null_substitution_is_omitted ... ok
[INFO] [stdout] test tests::string_table_overflow_returns_error ... ok
[INFO] [stdout] test tests::other_known_token_advances_one_byte ... ok
[INFO] [stdout] test tests::scan_binxml_empty_payload_returns_all_none ... ok
[INFO] [stdout] test tests::scan_binxml_very_large_payload_does_not_panic ... ok
[INFO] [stdout] test tests::truncated_open_start_element_returns_error ... ok
[INFO] [stdout] test tests::valid_binxml_passes_validation ... ok
[INFO] [stdout] test tests::valid_open_start_element_walks_to_end ... ok
[INFO] [stdout] test tokens::tests::attribute_name_resolves ... ok
[INFO] [stdout] test tokens::tests::truncated_fragment_header_is_error ... ok
[INFO] [stdout] test tokens::tests::substitution_descriptor_optional_null_is_ignored ... ok
[INFO] [stdout] test tokens::tests::valid_token_bytes ... ok
[INFO] [stdout] test tokens::tests::token_base_and_flag ... ok
[INFO] [stdout] test value::tests::binary_renders_hex ... ok
[INFO] [stdout] test value::tests::filetime_renders_utc_iso ... ok
[INFO] [stdout] test value::tests::guid_renders_canonical ... ok
[INFO] [stdout] test value::tests::null_renders_empty ... ok
[INFO] [stdout] test value::tests::array_and_binxml_are_unsupported_here ... ok
[INFO] [stdout] test value::tests::hex_ints_render_prefixed ... ok
[INFO] [stdout] test value::tests::binary_unsized_uses_u16_prefix ... ok
[INFO] [stdout] test value::tests::reals ... ok
[INFO] [stdout] test value::tests::render_covers_every_scalar_arm ... ok
[INFO] [stdout] test value::tests::signed_and_unsigned_ints ... ok
[INFO] [stdout] test value::tests::truncated_value_is_error_not_panic ... ok
[INFO] [stderr]      Running tests/real_corpus_parity.rs (/opt/rustwide/target/debug/build/winevt-binxml/6eabe8c68108c79d/out/real_corpus_parity-6eabe8c68108c79d)
[INFO] [stdout] test value::tests::string_sized_reads_byte_length_no_prefix ... ok
[INFO] [stdout] test value::tests::bool_is_four_bytes_nonzero_true ... ok
[INFO] [stdout] test value::tests::systime_renders_utc_iso ... ok
[INFO] [stdout] test value::tests::sid_rejects_absurd_subauthority_count ... ok
[INFO] [stdout] test value::tests::sid_renders_s_string ... ok
[INFO] [stdout] test value::tests::unknown_type_errors ... ok
[INFO] [stdout] test tests::wrong_first_byte_returns_error ... ok
[INFO] [stdout] test tokens::tests::fragment_header_reads_three_bytes ... ok
[INFO] [stdout] test tokens::tests::open_element_with_dependency_id_and_attributes ... ok
[INFO] [stdout] test tokens::tests::open_element_plain_inline_name ... ok
[INFO] [stdout] test tokens::tests::substitution_descriptor_normal ... ok
[INFO] [stdout] test value::tests::ansi_string_sized_and_len_prefixed_drops_nul ... ok
[INFO] [stdout] test value::tests::sizet_renders_hex_by_width ... ok
[INFO] [stdout] test value::tests::string_len_prefixed_when_unsized ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 117 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test decoded_records_match_omerbenamram_event_ids ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests winevt_binxml
[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" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d", kill_on_drop: false }`
[INFO] [stdout] aaf345ea608c02e2272bfcb02ef39e69a488eec463a918e5284f8b44af6a8b8d
