[INFO] fetching crate velocity-msi 0.1.0...
[INFO] testing velocity-msi-0.1.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate velocity-msi 0.1.0 into /workspace/builds/worker-6-tc2/source
[INFO] started tweaking crates.io crate velocity-msi 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate velocity-msi 0.1.0
[INFO] tweaked toml for crates.io crate velocity-msi 0.1.0 written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate velocity-msi 0.1.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 velocity-msi 0.1.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-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5
[INFO] running `Command { std: "docker" "start" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", 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" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5" "/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" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", 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" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling crc32fast v1.5.2
[INFO] [stderr]    Compiling uuid v1.26.1
[INFO] [stderr]    Compiling miniz_oxide v0.9.1
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling flate2 v1.1.10
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling velocity-msi v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `uuid`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.uuid]
[INFO] [stderr]    |               ^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `uuid`
[INFO] [stderr] warning: `velocity-msi` (manifest) generated 1 warning
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.53s
[INFO] running `Command { std: "docker" "inspect" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", 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" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5" "/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 syn v3.0.5
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling simdutf8 v0.1.5
[INFO] [stderr]    Compiling multiversion_no_op v1.0.0
[INFO] [stderr]    Compiling core_detect v1.0.0
[INFO] [stderr]    Compiling encoding_rs v0.8.41
[INFO] [stderr]    Compiling bitflags v2.13.2
[INFO] [stderr]    Compiling time v0.3.55
[INFO] [stderr]    Compiling lzxd v0.1.4
[INFO] [stderr]    Compiling cfb v0.14.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling cfb v0.9.0
[INFO] [stderr]    Compiling cab v0.4.1
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling multiversion-macros v0.9.0
[INFO] [stderr]    Compiling multiversion v0.9.0
[INFO] [stderr]    Compiling msi v0.7.0
[INFO] [stderr]    Compiling velocity-msi v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused variable: `col_bytes`
[INFO] [stdout]    --> examples/dump_validation.rs:248:27
[INFO] [stdout]     |
[INFO] [stdout] 248 |         let col_offset = |col_bytes: usize, col_idx: usize| -> usize {
[INFO] [stdout]     |                           ^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_col_bytes`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `to_b64` is never used
[INFO] [stdout]  --> examples/dump_validation.rs:4:4
[INFO] [stdout]   |
[INFO] [stdout] 4 | fn to_b64(ch: char) -> Option<u32> {
[INFO] [stdout]   |    ^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr] warning: unused dependency `uuid`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.uuid]
[INFO] [stderr]    |               ^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `uuid`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: unused dependency `uuid`
[INFO] [stderr]   --> Cargo.toml:25:19
[INFO] [stderr]    |
[INFO] [stderr] 25 | [dev-dependencies.uuid]
[INFO] [stderr]    |                   ^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `uuid`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `velocity-msi` (manifest) generated 2 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 10s
[INFO] running `Command { std: "docker" "inspect" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", 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" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused variable: `col_bytes`
[INFO] [stderr]    --> examples/dump_validation.rs:248:27
[INFO] [stderr]     |
[INFO] [stderr] 248 |         let col_offset = |col_bytes: usize, col_idx: usize| -> usize {
[INFO] [stderr]     |                           ^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_col_bytes`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `to_b64` is never used
[INFO] [stderr]  --> examples/dump_validation.rs:4:4
[INFO] [stderr]   |
[INFO] [stderr] 4 | fn to_b64(ch: char) -> Option<u32> {
[INFO] [stderr]   |    ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `velocity-msi` (example "dump_validation") generated 2 warnings (run `cargo fix --example "dump_validation" -p velocity-msi` to apply 1 suggestion)
[INFO] [stderr] warning: unused dependency `uuid`
[INFO] [stderr]   --> Cargo.toml:10:15
[INFO] [stderr]    |
[INFO] [stderr] 10 | [dependencies.uuid]
[INFO] [stderr]    |               ^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `uuid`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: unused dependency `uuid`
[INFO] [stderr]   --> Cargo.toml:25:19
[INFO] [stderr]    |
[INFO] [stderr] 25 | [dev-dependencies.uuid]
[INFO] [stderr]    |                   ^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the dependency on `uuid`
[INFO] [stderr] help: to still use for development builds, move to `dev-dependencies`
[INFO] [stderr] warning: `velocity-msi` (manifest) generated 2 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/velocity-msi/3f23a257c34758b4/out/velocity_msi-3f23a257c34758b4)
[INFO] [stdout] 
[INFO] [stdout] running 51 tests
[INFO] [stdout] test ole::tests::test_msi_clsid_on_root ... ok
[INFO] [stdout] test ole::tests::test_directory_sector_marked_as_endofchain ... ok
[INFO] [stdout] test ole::tests::test_empty_file ... ok
[INFO] [stdout] test ole::tests::test_empty_stream ... ok
[INFO] [stdout] test ole::tests::test_mixed_streams ... ok
[INFO] [stdout] test ole::tests::test_fat_chain_integrity ... ok
[INFO] [stdout] test ole::tests::test_multiple_streams ... ok
[INFO] [stdout] test ole::tests::test_single_small_stream ... ok
[INFO] [stdout] test string_pool::tests::test_intern_strings ... ok
[INFO] [stdout] test ole::tests::test_large_stream ... ok
[INFO] [stdout] test string_pool::tests::test_intern_empty ... ok
[INFO] [stdout] test summary::tests::test_codepage_is_first_property ... ok
[INFO] [stdout] test summary::tests::test_serialize_minimal ... ok
[INFO] [stdout] test summary::tests::test_fmtid_matches_spec ... ok
[INFO] [stdout] test summary::tests::test_serialize_with_all_properties ... ok
[INFO] [stdout] test summary::tests::test_section_size_is_4byte_aligned ... ok
[INFO] [stdout] test ole::tests::test_stream_name_encoding ... ok
[INFO] [stdout] test cabinet::tests::test_cab_data_fields_are_u32 ... ok
[INFO] [stdout] test cabinet::tests::test_build_cabinet_with_file ... ok
[INFO] [stdout] test table::tests::test_row_sorting_by_primary_key ... ok
[INFO] [stdout] test table::tests::test_serialize_string_columns ... ok
[INFO] [stdout] test table::tests::test_serialize_null_values ... ok
[INFO] [stdout] test table::tests::test_serialize_long_string_refs ... ok
[INFO] [stdout] test ole::tests::test_fat_sector_marked_as_fatsect ... ok
[INFO] [stdout] test table::tests::test_serialize_int_columns ... ok
[INFO] [stdout] test table::tests::test_serialize_uninterned_string_errors ... ok
[INFO] [stdout] test table::tests::test_table_column_count_mismatch ... ok
[INFO] [stdout] test table::tests::test_table_add_row ... ok
[INFO] [stdout] test ole::tests::test_file_size_is_sector_aligned ... ok
[INFO] [stdout] test table::tests::test_column_bitfield_int32 ... ok
[INFO] [stdout] test table::tests::test_column_bitfield_binary ... ok
[INFO] [stdout] test table::tests::test_column_bitfield_int16 ... ok
[INFO] [stdout] test table::tests::test_column_type_width ... ok
[INFO] [stdout] test table::tests::test_column_bitfield_string ... ok
[INFO] [stdout] test cabinet::tests::test_build_empty_cabinet ... ok
[INFO] [stdout] test summary::tests::test_default_values ... ok
[INFO] [stdout] test validate::tests::test_decode_stream_name_roundtrip ... ok
[INFO] [stdout] test tests::test_encode_stream_name ... ok
[INFO] [stdout] test tests::test_create_simple_msi ... ok
[INFO] [stdout] test tests::test_full_msi_validation ... ok
[INFO] [stdout] test validate::tests::test_is_string_pool_stream ... ok
[INFO] [stdout] test validate::tests::test_semantic_validation_invalid_data ... ok
[INFO] [stdout] test cabinet::tests::test_build_cabinet_large_file ... ok
[INFO] [stdout] test validate::tests::test_semantic_validation_full_msi ... ok
[INFO] [stdout] test validate::tests::test_validate_bad_magic ... ok
[INFO] [stdout] test validate::tests::test_semantic_validation_multiple_tables ... ok
[INFO] [stdout] test validate::tests::test_decode_stream_name_tables ... ok
[INFO] [stdout] test validate::tests::test_validate_generated_msi ... ok
[INFO] [stdout] test validate::tests::test_validate_empty_data ... ok
[INFO] [stdout] test ole::tests::test_large_file_with_difat ... ok
[INFO] [stdout] test ole::tests::test_very_large_file_50mb ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 51 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/comprehensive_validation.rs (/opt/rustwide/target/debug/build/velocity-msi/ec749f0585b7038b/out/comprehensive_validation-ec749f0585b7038b)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test test_difat_pointer_is_endofchain_for_empty_ole ... ok
[INFO] [stdout] test test_file_size_is_sector_aligned ... ok
[INFO] [stdout] test test_difat_pointer_is_endofchain_when_no_difat_sectors ... ok
[INFO] [stdout] test test_header_difat_array_unused_entries_are_free_sect ... ok
[INFO] [stdout] test test_exactly_one_regular_sector ... ok
[INFO] [stdout] test test_difat_pointer_is_endofchain_for_full_msi ... ok
[INFO] [stdout] test test_no_duplicate_entries_in_walk ... ok
[INFO] [stdout] test test_many_streams_force_multiple_dir_sectors ... ok
[INFO] [stdout] test test_exactly_one_mini_sector ... ok
[INFO] [stdout] test test_directory_bst_all_entries_reachable ... ok
[INFO] [stdout] test test_cabinet_embed_and_extract ... ok
[INFO] [stdout] test test_ole_magic_bytes ... ok
[INFO] [stdout] test test_msi_crate_can_open_minimal_msi ... ok
[INFO] [stdout] test test_msi_crate_can_open_our_output ... ok
[INFO] [stdout] test test_msi_crate_reads_property_table ... ok
[INFO] [stdout] test test_stream_exactly_at_mini_stream_cutoff ... ok
[INFO] [stdout] test test_single_byte_stream ... ok
[INFO] [stdout] test test_deterministic_output_same_input ... ok
[INFO] [stdout] test test_stream_exactly_at_cutoff_goes_to_regular_sectors ... ok
[INFO] [stdout] test test_all_streams_readable_via_cfb ... ok
[INFO] [stdout] test test_cfb_validates_every_generated_msi ... ok
[INFO] [stdout] test test_v3_format ... ok
[INFO] [stdout] test test_minifat_chains_terminate_correctly ... ok
[INFO] [stdout] test test_stream_just_above_cutoff ... ok
[INFO] [stdout] test test_write_to_disk_and_read_back ... ok
[INFO] [stdout] test test_large_string_pool ... ok
[INFO] [stdout] test test_many_tables_many_rows ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cross_validation.rs (/opt/rustwide/target/debug/build/velocity-msi/9a80c9133839b350/out/cross_validation-9a80c9133839b350)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test test_cfb_all_streams_have_correct_sizes ... ok
[INFO] [stdout] test test_cfb_can_open_generated_msi ... ok
[INFO] [stdout] test test_cfb_small_stream_round_trip ... ok
[INFO] [stdout] test test_cfb_entry_count_and_types ... ok
[INFO] [stdout] test test_cfb_large_stream_round_trip ... ok
[INFO] [stdout] test test_cfb_stream_names_are_unique ... ok
[INFO] [stdout] test test_cfb_multiple_extra_streams_round_trip ... ok
[INFO] [stdout] test test_cfb_summary_info_stream_exists ... ok
[INFO] [stderr]    Doc-tests velocity_msi
[INFO] [stdout] test test_cfb_root_entry_has_msi_clsid ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - MsiBuilder (line 31) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5", kill_on_drop: false }`
[INFO] [stdout] 4d97b8b146a95b0ce663e469a1e1be3353ff8c0b313dcc84466a9794648219d5
