[INFO] fetching crate rusty_zstd 0.2.5...
[INFO] testing rusty_zstd-0.2.5 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rusty_zstd 0.2.5 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate rusty_zstd 0.2.5
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rusty_zstd 0.2.5
[INFO] tweaked toml for crates.io crate rusty_zstd 0.2.5 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rusty_zstd 0.2.5 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 rusty_zstd 0.2.5 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-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] 527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda
[INFO] running `Command { std: "docker" "start" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", 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" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda" "/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" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", 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" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rusty_zstd v0.2.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.43s
[INFO] running `Command { std: "docker" "inspect" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", 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" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda" "/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 rusty_zstd v0.2.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.74s
[INFO] running `Command { std: "docker" "inspect" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", 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" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda" "/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.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rusty_zstd/44e5100983c1ccf8/out/rusty_zstd-44e5100983c1ccf8)
[INFO] [stdout] 
[INFO] [stdout] running 146 tests
[INFO] [stdout] test bit::tests::left_justified_look_matches_c_shift ... ok
[INFO] [stdout] test block::tests::reserved_type ... ok
[INFO] [stdout] test census64::tests::census_counts_wherever_tests_run ... ok
[INFO] [stdout] test compressed::tests::copy_from_decoded_publishes_exactly_len ... ok
[INFO] [stdout] test compressed::tests::code_lut_exhaustive_over_lut_domain ... ok
[INFO] [stdout] test bit::tests::look_bits_fast_zero_pads_at_start ... ok
[INFO] [stdout] test compressed::tests::copy_literals_rejects_overrun ... ok
[INFO] [stdout] test compressed::tests::repeat_offset_rfc_table18 ... ok
[INFO] [stdout] test compressed::tests::rle_seq_table_rejects_out_of_range_symbol ... ok
[INFO] [stdout] test compressed::tests::copy_literals_fast_matches_checked ... ok
[INFO] [stdout] test compressed::tests::packed_tables_match_rfc ... ok
[INFO] [stdout] test decode::tests::c_empty ... ok
[INFO] [stdout] test bit::tests::flush_writes_container_words ... ok
[INFO] [stdout] test block::tests::raw_one_byte ... ok
[INFO] [stdout] test decode::tests::c_raw_small ... ok
[INFO] [stdout] test decode::tests::checksum_mismatch ... ok
[INFO] [stdout] test decode::tests::c_zeros16_compressed ... ok
[INFO] [stdout] test decode::tests::dict_id_needed ... ok
[INFO] [stdout] test decode::tests::force_ignore_checksum_skips_verification_not_parsing ... ok
[INFO] [stdout] test block::tests::empty_raw_last ... ok
[INFO] [stdout] test decode::tests::into_propagates_errors ... ok
[INFO] [stdout] test decode::tests::multi_frame ... ok
[INFO] [stdout] test decode::tests::reserved_bit ... ok
[INFO] [stdout] test decode::tests::skippable_then_zstd ... ok
[INFO] [stdout] test decode::tests::trailing_garbage ... ok
[INFO] [stdout] test decode::tests::truncated ... ok
[INFO] [stdout] test decode::tests::rle_handcrafted ... ok
[INFO] [stdout] test decode::tests::window_too_large ... ok
[INFO] [stdout] test dict::tests::public_id_avoids_reserved ... ok
[INFO] [stdout] test dict::tests::raw_has_id_zero ... ok
[INFO] [stdout] test encode::tests::count_match_words_match_byte_loop ... ok
[INFO] [stdout] test decode::tests::into_appends_without_disturbing_prefix ... ok
[INFO] [stdout] test compressed::tests::ll_ml_code_lut_matches_linear_scan ... ok
[INFO] [stdout] test encode::tests::empty_frame_has_checksum ... ok
[INFO] [stdout] test compressed::tests::copy_from_decoded_matches_byte_push ... ok
[INFO] [stdout] test encode::tests::enable_ldm_zstd_keys_roundtrip ... ok
[INFO] [stdout] test encode::tests::early_raw_skip_fast_rung_low_matches ... ok
[INFO] [stdout] test encode::tests::higher_level_never_larger_osdb ... ok
[INFO] [stdout] test encode::tests::huffman_literals_emitted_on_text ... ok
[INFO] [stdout] test encode::tests::census_zeros_all_rle ... ok
[INFO] [stdout] test encode::tests::decompress_long_raises_window_cap ... ok
[INFO] [stdout] test encode::tests::min_gain_matches_c_fast ... ok
[INFO] [stdout] test encode::tests::probe_density_truth_table ... ignored
[INFO] [stdout] test encode::tests::frame_checksum_matches_oneshot_xxh64 ... ok
[INFO] [stdout] test encode::tests::push_literals_matches_extend_from_slice ... ok
[INFO] [stdout] test decode::tests::into_reuse_across_frames_is_stable ... ok
[INFO] [stdout] test encode::tests::rle_fse_large_match ... ok
[INFO] [stdout] test encode::tests::rle_byte_word_matches_byte_all ... ok
[INFO] [stdout] test encode::tests::long_forces_window_descriptor ... ok
[INFO] [stdout] test encode::tests::roundtrip_greedy_explicit ... ok
[INFO] [stdout] test encode::tests::roundtrip_ultra_levels ... ok
[INFO] [stdout] test encode::tests::roundtrip_small_all_fast_levels ... ok
[INFO] [stdout] test encode::tests::silesia_all_oneshot_roundtrip_l1 ... ok
[INFO] [stdout] test encode::tests::silesia_mr_prefix_entropy_oracle ... ok
[INFO] [stdout] test encode::tests::silesia_mr_prefix_finder_recon ... ok
[INFO] [stdout] test encode::tests::silesia_mr_prefix_roundtrip ... ok
[INFO] [stdout] test encode::tests::size_table_silesia ... ignored
[INFO] [stdout] test encode::tests::roundtrip_all_strategies ... ok
[INFO] [stdout] test encode::tests::rsyncable_splits_blocks ... ok
[INFO] [stdout] test encode::tests::skip_off_fast_roundtrip_and_on_skips_noise ... ok
[INFO] [stdout] test encode::tests::target_cblock_caps_uncompressed_blocks ... ok
[INFO] [stdout] test encode::tests::skip_off_l1_bytes_match_unset ... ok
[INFO] [stdout] test encode::tests::zeros_and_text_shrink ... ok
[INFO] [stdout] test frame::tests::bad_magic ... ok
[INFO] [stdout] test encode::tests::streaming_xxh64_matches_oneshot_at_every_boundary_phase ... ok
[INFO] [stdout] test frame::tests::parses_c_a_no_check ... ok
[INFO] [stdout] test frame::tests::skippable_header ... ok
[INFO] [stdout] test fse::tests::bit_cost_rejects_zero_prob_and_missing_symbol ... ok
[INFO] [stdout] test fse::tests::default_ll_matches_rfc_appendix ... ok
[INFO] [stdout] test fse::tests::init_state2_matches_dtable_compressed ... ok
[INFO] [stdout] test fse::tests::last_symbol_init_only_roundtrips_when_in_table ... ok
[INFO] [stdout] test fse::tests::ncount_roundtrip_default_ll ... ok
[INFO] [stdout] test fse::tests::fse_custom_table_symbol_roundtrip ... ok
[INFO] [stdout] test fse::tests::init_state2_matches_dtable_defaults ... ok
[INFO] [stdout] test fse::tests::ncount_roundtrip_default_of ... ok
[INFO] [stdout] test huffman::tests::covers_freq_matches_covers_oracle ... ok
[INFO] [stdout] test huffman::tests::covers_rejects_unseen_symbol ... ok
[INFO] [stdout] test huffman::tests::decode_4x_matches_sequential ... ok
[INFO] [stdout] test huffman::tests::encode_4_streams_matches_sequential_1x ... ok
[INFO] [stdout] test huffman::tests::encode_stream_unrolled_matches_scalar ... ok
[INFO] [stdout] test huffman::tests::huff_pack_dispatch_separates_peaked_from_flat ... ok
[INFO] [stdout] test huffman::tests::fox_literals_still_huffman ... ok
[INFO] [stdout] test huffman::tests::decode_stream_unrolled_matches_scalar ... ok
[INFO] [stdout] test huffman::tests::huffman_four_stream_and_tree_encodings ... ok
[INFO] [stdout] test huffman::tests::huffman_five_byte_header_csize_matches ... ok
[INFO] [stdout] test huffman::tests::huffman_one_stream_below_256 ... ok
[INFO] [stdout] test encode::tests::fast_sparse_match_fill_roundtrips_repeating_text ... ok
[INFO] [stdout] test huffman::tests::huffman_section_roundtrip_via_read_table ... ok
[INFO] [stdout] test huffman::tests::incompressible_literals_stay_raw ... ok
[INFO] [stdout] test huffman::tests::rle_literals_section_emits_type_1_and_round_trips ... ok
[INFO] [stdout] test huffman::tests::silesia_huff_nbits_census ... ignored, needs corpora/data/silesia; run with --ignored --nocapture
[INFO] [stdout] test huffman::tests::select_x2_follows_c_breakpoints ... ok
[INFO] [stdout] test in_bench::tests::mbps_best_uses_one_loop_not_the_sum ... ok
[INFO] [stdout] test in_bench::tests::one_loop_roundtrip ... ok
[INFO] [stdout] test huffman::tests::huffman_length_sweep ... ok
[INFO] [stdout] test ldm::tests::collect_ldm_finds_aligned_repeat ... ok
[INFO] [stdout] test ldm::tests::rsync_cut_hits_mask ... ok
[INFO] [stdout] test mt::tests::job_size_enforces_min ... ok
[INFO] [stdout] test ldm::tests::ldm_roundtrip ... ok
[INFO] [stdout] test decode::tests::into_matches_decompress ... ok
[INFO] [stdout] test mt::tests::overlap_size_table ... ok
[INFO] [stdout] test mt::tests::single_job_is_one_frame ... ok
[INFO] [stdout] test params::tests::every_level_resolves ... ok
[INFO] [stdout] test params::tests::level19_large_is_btultra2 ... ok
[INFO] [stdout] test params::tests::level1_large_is_fast ... ok
[INFO] [stdout] test params::tests::level3_large_is_dfast ... ok
[INFO] [stdout] test params::tests::negative_sets_target_length ... ok
[INFO] [stdout] test params::tests::zstd_option_roundtrip_keys ... ok
[INFO] [stdout] test prof::tests::scope_is_safe_to_call ... ok
[INFO] [stdout] test rowfind::tests::kernel_matches_oracle ... ok
[INFO] [stdout] test rowfind::tests::mixed_tags_filter ... ok
[INFO] [stdout] test rowfind::tests::ring_order_is_newest_first ... ok
[INFO] [stdout] test seekable::tests::seekable_roundtrip_and_table ... ok
[INFO] [stdout] test seekable::tests::seekable_without_checksums ... ok
[INFO] [stdout] test simd::tests::count_eq_len_matches_byte_and_words ... ok
[INFO] [stdout] test mt::tests::mt_overlap_repeating_independent_frames ... ok
[INFO] [stdout] test simd::tests::eqlen_bucket_matches_compare_ladder ... ok
[INFO] [stdout] test simd::tests::load_u32_u64_le_matches_from_le_bytes ... ok
[INFO] [stdout] test simd::tests::look_n_bits_bmi2_matches_shift ... ok
[INFO] [stdout] test in_bench::tests::best_is_never_slower_than_the_mean_loop ... ok
[INFO] [stdout] test stream::tests::oneshot_and_stream_decompress ... ok
[INFO] [stdout] test stream::tests::silesia_mr_prefix_stream_roundtrip ... ok
[INFO] [stdout] test stream::tests::slide_threshold_caps_the_extra_history ... ok
[INFO] [stdout] test tests::compress_bound_grows_with_input ... ok
[INFO] [stdout] test stream::tests::stream_compress_chunked_roundtrip ... ok
[INFO] [stdout] test tests::compress_roundtrip_default ... ok
[INFO] [stdout] test tests::compress_rejects_bad_level ... ok
[INFO] [stdout] test tests::decompress_truncated_magic_is_eof ... ok
[INFO] [stdout] test simd::tests::eq_oracle_exhaustive ... ok
[INFO] [stdout] test train::tests::prefix_roundtrip ... ok
[INFO] [stdout] test train::tests::raw_dict_roundtrip ... ok
[INFO] [stdout] test train::tests::raw_dict_roundtrip_l3_long ... ok
[INFO] [stdout] test xxh64::locality_probe::xxh64_throughput_by_working_set ... ignored
[INFO] [stdout] test xxh64::tests::c_zstd_157_checksums ... ok
[INFO] [stdout] test xxh64::tests::empty_seed0 ... ok
[INFO] [stdout] test xxh64::tests::hasher_matches_oneshot ... ok
[INFO] [stdout] test mt::tests::mt_two_jobs_roundtrip ... ok
[INFO] [stdout] test xxh64::tests::premul_decomposition_matches_mul ... ok
[INFO] [stdout] test xxh64::tests::thirty_two_zeros_stripe_path ... ok
[INFO] [stdout] test mt::tests::mt_overlap_roundtrip ... ok
[INFO] [stdout] test encode::tests::roundtrip_mid_and_high_levels ... ok
[INFO] [stdout] test encode::tests::literals_and_sequence_modes_coverage ... ok
[INFO] [stdout] test xxh64::tests::matches_spec_oracle_at_every_length ... ok
[INFO] [stdout] test decode::tests::into_appends_correctly_at_every_prefix_length ... ok
[INFO] [stdout] test train::tests::cover_and_legacy_parse ... ok
[INFO] [stdout] test train::tests::fastcover_roundtrip_our_decoder ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 142 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 3.26s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/c_cross.rs (/opt/rustwide/target/debug/build/rusty_zstd/5cda610777b9ed74/out/c_cross-5cda610777b9ed74)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test c_holdout_incomp_32m ... ok
[INFO] [stdout] test c_small_corpus ... ok
[INFO] [stdout] test c_train_zeros_and_text_32m ... ok
[INFO] [stdout] test oversized_hash_log_does_not_panic ... ok
[INFO] [stdout] test compression_is_independent_of_call_history has been running for over 60 seconds
[INFO] [stdout] test out_of_range_parameters_never_panic has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda", kill_on_drop: false }`
[INFO] [stdout] 527fb27c3a21f47a0dc7ac1a31f0d2bae91b1251dc494810f18abf0d01835bda
