[INFO] cloning repository https://github.com/OxideAV/oxideav-mpeg4video
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/OxideAV/oxideav-mpeg4video" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOxideAV%2Foxideav-mpeg4video", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOxideAV%2Foxideav-mpeg4video'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 928f9d1e9a785908e15a6cb47549a2c37d3315fe
[INFO] testing OxideAV/oxideav-mpeg4video against master#ec6f9a5b4413f74386267ef8efc93712c2ce6db6 for pr-155739
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FOxideAV%2Foxideav-mpeg4video" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/OxideAV/oxideav-mpeg4video
[INFO] finished tweaking git repo https://github.com/OxideAV/oxideav-mpeg4video
[INFO] tweaked toml for git repo https://github.com/OxideAV/oxideav-mpeg4video written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/OxideAV/oxideav-mpeg4video on toolchain ec6f9a5b4413f74386267ef8efc93712c2ce6db6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 55 packages to latest compatible versions
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded alloca v0.4.0
[INFO] [stderr]   Downloaded cc v1.2.62
[INFO] [stderr]   Downloaded criterion-plot v0.8.2
[INFO] [stderr]   Downloaded criterion v0.8.2
[INFO] [stderr]   Downloaded libc v0.2.186
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8617e3777f25798c8174719ffdfe82ea023893bd568795e2627d8e3d05d09c24
[INFO] running `Command { std: "docker" "start" "-a" "8617e3777f25798c8174719ffdfe82ea023893bd568795e2627d8e3d05d09c24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8617e3777f25798c8174719ffdfe82ea023893bd568795e2627d8e3d05d09c24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8617e3777f25798c8174719ffdfe82ea023893bd568795e2627d8e3d05d09c24", kill_on_drop: false }`
[INFO] [stdout] 8617e3777f25798c8174719ffdfe82ea023893bd568795e2627d8e3d05d09c24
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d50299fbb5b45b0e5828e2f80b681358500068951a82aa2f61520baf44a25390
[INFO] running `Command { std: "docker" "start" "-a" "d50299fbb5b45b0e5828e2f80b681358500068951a82aa2f61520baf44a25390", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling oxideav-core v0.1.26
[INFO] [stderr]    Compiling oxideav-mpeg4video v0.1.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.77s
[INFO] running `Command { std: "docker" "inspect" "d50299fbb5b45b0e5828e2f80b681358500068951a82aa2f61520baf44a25390", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d50299fbb5b45b0e5828e2f80b681358500068951a82aa2f61520baf44a25390", kill_on_drop: false }`
[INFO] [stdout] d50299fbb5b45b0e5828e2f80b681358500068951a82aa2f61520baf44a25390
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b815f9a23437618ab5d170f1792c48e1bada5e3e3858313aa08e1fc175f100ce
[INFO] running `Command { std: "docker" "start" "-a" "b815f9a23437618ab5d170f1792c48e1bada5e3e3858313aa08e1fc175f100ce", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling cc v1.2.62
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling oxideav-core v0.1.26
[INFO] [stderr]    Compiling oxideav-mpeg4video v0.1.5 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 30.46s
[INFO] running `Command { std: "docker" "inspect" "b815f9a23437618ab5d170f1792c48e1bada5e3e3858313aa08e1fc175f100ce", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b815f9a23437618ab5d170f1792c48e1bada5e3e3858313aa08e1fc175f100ce", kill_on_drop: false }`
[INFO] [stdout] b815f9a23437618ab5d170f1792c48e1bada5e3e3858313aa08e1fc175f100ce
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e6d64f2d08ce7aaf5199acd389b57691890b21b903fd20e9c71027cdd35bfdd3
[INFO] running `Command { std: "docker" "start" "-a" "e6d64f2d08ce7aaf5199acd389b57691890b21b903fd20e9c71027cdd35bfdd3", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/oxideav_mpeg4video-61545b2812faf22b)
[INFO] [stdout] 
[INFO] [stdout] running 133 tests
[INFO] [stdout] test block::tests::choose_predictor_left_wins_when_horizontal_smoother ... ok
[INFO] [stdout] test bvop::tests::bmv_grid_round_trip ... ok
[INFO] [stdout] test block::tests::dc_only_roundtrip ... ok
[INFO] [stdout] test bvop::tests::direct_mode_4mv_per_block ... ok
[INFO] [stdout] test bvop::tests::direct_mode_scales_linearly ... ok
[INFO] [stdout] test bvop::tests::direct_mode_per_component_delta ... ok
[INFO] [stdout] test bvop::tests::direct_mode_with_delta_uses_fwd_diff ... ok
[INFO] [stdout] test bvop::tests::quad_builder_preserves_flag ... ok
[INFO] [stdout] test bvop::tests::direct_mode_4mv_delta_shared ... ok
[INFO] [stdout] test bvop_enc::tests::chroma_mv_average_in_4mv_mode ... ok
[INFO] [stdout] test bvop_enc::tests::mode_costs_prefer_direct_on_static_scene ... ok
[INFO] [stdout] test bvop_enc::tests::predict_luma_mb_4mv_uses_per_block_mvs ... ok
[INFO] [stdout] test decoder::decoder_tests::send_packet_accepts_iso_bitstream ... ok
[INFO] [stdout] test decoder::decoder_tests::send_packet_rejects_msmpeg4_bitstream ... ok
[INFO] [stdout] test encoder::tests::dc_size_round_trip_luma ... ok
[INFO] [stdout] test decoder::decoder_tests::sniff_only_runs_on_first_packet ... ok
[INFO] [stdout] test encoder::tests::fdct_idct_round_trip ... ok
[INFO] [stdout] test encoder::tests::mcbpc_p_intra_roundtrip ... ok
[INFO] [stdout] test gmc::tests::decode_warping_mv_length_three ... ok
[INFO] [stdout] test dp::tests::marker_bit_patterns ... ok
[INFO] [stdout] test gmc::tests::encode_decode_warping_mv_roundtrip ... ok
[INFO] [stdout] test gmc::tests::decode_warping_mv_length_three_negative ... ok
[INFO] [stdout] test gmc::tests::brightness_change_factor_medium_values ... ok
[INFO] [stdout] test gmc::tests::brightness_change_factor_large_values ... ok
[INFO] [stdout] test gmc::tests::warp_zero_points_is_identity ... ok
[INFO] [stdout] test gmc::tests::brightness_change_factor_small_values ... ok
[INFO] [stdout] test encoder::tests::dc_size_round_trip_chroma ... ok
[INFO] [stdout] test headers::vol::tests::bits_needed_basic ... ok
[INFO] [stdout] test headers::vol::tests::vol_parse_accepts_interlaced_flag ... ok
[INFO] [stdout] test headers::vol::tests::vol_parse_accepts_sprite_brightness_change ... ok
[INFO] [stdout] test headers::vol::tests::vol_parse_accepts_sprite_enable_1 ... ok
[INFO] [stdout] test headers::vol::tests::vol_parse_accepts_sprite_enable_2_gmc ... ok
[INFO] [stdout] test gmc::tests::decode_warping_mv_zero ... ok
[INFO] [stdout] test interlaced::tests::alt_vertical_scan_overrides_direction ... ok
[INFO] [stdout] test interlaced::tests::field_predict_chroma_mb_interleaves_fields ... ok
[INFO] [stdout] test interlaced::tests::field_predict_luma_mb_interleaves_fields ... ok
[INFO] [stdout] test interlaced::tests::inter_single_mv_no_cbp_no_dct_type ... ok
[INFO] [stdout] test headers::vol::tests::vol_parse_rejects_reserved_sprite_enable_3 ... ok
[INFO] [stdout] test headers::vos::tests::classifies_profiles ... ok
[INFO] [stdout] test headers::vos::tests::parses_simple_vos ... ok
[INFO] [stdout] test interlaced::tests::field_dct_reorder_mb_layout ... ok
[INFO] [stdout] test interlaced::tests::no_alt_vertical_scan_falls_back_to_zigzag ... ok
[INFO] [stdout] test interlaced::tests::intra_mb_reads_dct_type_only ... ok
[INFO] [stdout] test iq::tests::dc_scaler_tables ... ok
[INFO] [stdout] test bvop_enc::tests::encode_b_vop_4mv_direct_records_quad_motion ... ok
[INFO] [stdout] test iq::tests::h263_intra_even_quant ... ok
[INFO] [stdout] test iq::tests::h263_intra_odd_quant ... ok
[INFO] [stdout] test iq::tests::mpeg_inter_forward_round_trip_lattice ... ok
[INFO] [stdout] test iq::tests::mpeg_intra_forward_picks_closest ... ok
[INFO] [stdout] test iq::tests::mpeg_intra_forward_round_trip_lattice ... ok
[INFO] [stdout] test lib_tests::probe_accepts_vol_start_code ... ok
[INFO] [stdout] test lib_tests::probe_accepts_vop_start_code ... ok
[INFO] [stdout] test lib_tests::probe_accepts_vos_start_code ... ok
[INFO] [stdout] test lib_tests::probe_rejects_msmpeg4_picture_header ... ok
[INFO] [stdout] test lib_tests::probe_rejects_too_short_bytes ... ok
[INFO] [stdout] test lib_tests::probe_without_data_is_middling ... ok
[INFO] [stdout] test mc::tests::chroma_mv_mapping ... ok
[INFO] [stdout] test mc::tests::luma_qmv_to_chroma_table ... ok
[INFO] [stdout] test mc::tests::predict_half_pel_h ... ok
[INFO] [stdout] test interlaced::tests::inter_single_mv_with_cbp_and_field_pred ... ok
[INFO] [stdout] test mc::chroma_mv_tests::luma_4mv_4x_uniform_matches_1mv_chroma ... ok
[INFO] [stdout] test mc::chroma_mv_tests::luma_4mv_sum_to_chroma_table_7_10 ... ok
[INFO] [stdout] test mc::tests::predict_integer_copy ... ok
[INFO] [stdout] test mc::tests::qpel_half_h_symmetric_gradient ... ok
[INFO] [stdout] test mc::tests::qpel_integer_copy_equals_bilinear_integer ... ok
[INFO] [stdout] test mc::tests::qpel_rounding_flag_reduces_offset ... ok
[INFO] [stdout] test mc::tests::rounding_flag_floors ... ok
[INFO] [stdout] test pvop::tests::four_mv_chroma_avg_matches_decoder_formula ... ok
[INFO] [stdout] test pvop::tests::mcbpc_inter4mv_roundtrip ... ok
[INFO] [stdout] test pvop::tests::mv_component_small_positive_roundtrip ... ok
[INFO] [stdout] test pvop::tests::mv_component_zero_roundtrip ... ok
[INFO] [stdout] test lib_tests::registered_tag_claims_route_correctly ... ok
[INFO] [stdout] test resync::tests::detect_aligned_marker ... ok
[INFO] [stdout] test resync::tests::mb_num_bits_smoke ... ok
[INFO] [stdout] test pvop::tests::quantise_inter_monotonic ... ok
[INFO] [stdout] test register_tests::register_via_runtime_context_installs_codec_factory ... ok
[INFO] [stdout] test resync::tests::no_marker_on_random_bits ... ok
[INFO] [stdout] test resync::tests::no_marker_when_disabled ... ok
[INFO] [stdout] test resync::tests::prefix_length_i_vop ... ok
[INFO] [stdout] test rvlc::tests::bit_reverse_buffer_is_involutive ... ok
[INFO] [stdout] test rvlc::tests::escape_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::escape_total_bits_is_30 ... ok
[INFO] [stdout] test rvlc::tests::full_inter_ac_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::full_intra_ac_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::forward_and_reverse_agree_on_full_block ... ok
[INFO] [stdout] test rvlc::tests::inter_short_roundtrip_smoke ... ok
[INFO] [stdout] test rvlc::tests::intra_short_roundtrip_smoke ... ok
[INFO] [stdout] test block::tests::choose_predictor_top_wins_when_vertical_smoother ... ok
[INFO] [stdout] test rvlc::tests::mixed_short_and_escape_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::negative_sign_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::no_short_codeword_starts_with_0000 ... ok
[INFO] [stdout] test rvlc::tests::picker_bit_misaligned_start_offset_works ... ok
[INFO] [stdout] test rvlc::tests::picker_clean_partition_recovers_all ... ok
[INFO] [stdout] test rvlc::tests::picker_corrupt_middle_strategy_one ... ok
[INFO] [stdout] test rvlc::tests::picker_head_corruption_does_not_crash_and_preserves_invariants ... ok
[INFO] [stdout] test rvlc::tests::prefix_width_matches ... ok
[INFO] [stdout] test rvlc::tests::reverse_escape_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::reverse_inter_block_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::reverse_intra_block_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::reverse_short_intra_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::reverse_escape_negative_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::reverse_short_negative_roundtrip ... ok
[INFO] [stdout] test rvlc::tests::table_has_169_entries ... ok
[INFO] [stdout] test rvlc::tests::try_decode_intra_ac_reports_partial_progress ... ok
[INFO] [stdout] test simd::tests::add_residual_matches_scalar ... ok
[INFO] [stdout] test simd::tests::clip_block_matches_scalar ... ok
[INFO] [stdout] test simd::tests::copy_mb_chroma_matches_scalar ... ok
[INFO] [stdout] test simd::tests::copy_mb_luma_matches_scalar ... ok
[INFO] [stdout] test simd::tests::dequant_matches_scalar ... ok
[INFO] [stdout] test simd::tests::fdct_matches_scalar ... ok
[INFO] [stdout] test simd::tests::fdct_idct_round_trip ... ok
[INFO] [stdout] test start_codes::tests::classifies_layers ... ok
[INFO] [stdout] test simd::tests::idct_matches_scalar ... ok
[INFO] [stdout] test start_codes::tests::finds_typical_sequence ... ok
[INFO] [stdout] test tables::bvop::tests::mbtype_decodes_all_variants ... ok
[INFO] [stdout] test tables::bvop::tests::modb_decodes_all_variants ... ok
[INFO] [stdout] test tables::mv::tests::table_is_prefix_code ... ok
[INFO] [stdout] test tables::mv::tests::table_size ... ok
[INFO] [stdout] test tables::tcoef::tests::inter_has_expected_entry_count ... ok
[INFO] [stdout] test simd::tests::copy_block_matches_scalar ... ok
[INFO] [stdout] test tables::tcoef::tests::inter_table_is_prefix_code ... ok
[INFO] [stdout] test tables::tcoef::tests::intra_has_expected_entry_count ... ok
[INFO] [stdout] test tables::tcoef::tests::max_level_and_run_smoke ... ok
[INFO] [stdout] test tables::tcoef::tests::intra_table_is_prefix_code ... ok
[INFO] [stdout] test rvlc::tests::reverse_table_is_prefix_code ... ok
[INFO] [stdout] test rvlc::tests::prefix_codes_unique ... ok
[INFO] [stdout] test pvop::tests::qpel_refine_finds_quarter_pel_shift ... ok
[INFO] [stdout] test gmc::tests::warp_sampler_integer_shift_roundtrip ... ok
[INFO] [stdout] test gmc::tests::warp_one_point_translates_correctly ... ok
[INFO] [stdout] test gmc::tests::encode_decode_warping_mv_large_values ... ok
[INFO] [stdout] test gmc::tests::encode_decode_single_point_trajectory ... ok
[INFO] [stdout] test gmc::tests::decode_warping_mv_plus_one_minus_one ... ok
[INFO] [stdout] test bvop::tests::trb_trd_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 133 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/b_vop.rs (/opt/rustwide/target/debug/deps/b_vop-73458188036d73ed)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test decode_bvop_clip_matches_ffmpeg ... ok
[INFO] [stdout] test decode_bvop_interlaced_clip_runs ... ok
[INFO] [stdout] test decode_bvop_qpel_clip_runs ... ok
[INFO] [stdout] test bvop_fixture_contains_b_vops ... ok
[INFO] [stdout] test encoder_b_vops::encoder_b_vop_small_dump ... ok
[INFO] [stdout] test decode_bvop_4mv_clip_runs ... ok
[INFO] [stdout] test encoder_b_vops::encoder_b_vop_ffmpeg_decode ... ok
[INFO] [stdout] test encoder_b_vops::encoder_emits_b_vops ... ok
[INFO] [stdout] test encoder_b_vops::encoder_b_vop_self_consistency ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.35s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/dp.rs (/opt/rustwide/target/debug/deps/dp-1180d5820c35a65f)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test dp_rejects_unsupported_combos ... ok
[INFO] [stdout] test dp_i_vop_emits_dc_marker ... ok
[INFO] [stdout] test dp_ffmpeg_decode ... ok
[INFO] [stdout] test dp_p_vop_emits_motion_marker ... ok
[INFO] [stdout] test dp_vol_advertises_partitioning ... ok
[INFO] [stdout] test dp_p_vop_intra_in_p_scene_change_roundtrip ... ok
[INFO] [stdout] test dp_p_vop_inter4mv_roundtrip ... FAILED
[INFO] [stdout] test dp_self_roundtrip_psnr_passes ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- dp_p_vop_inter4mv_roundtrip stdout ----
[INFO] [stdout] DP Inter4MV frame 0: PSNR = 39.20 dB
[INFO] [stdout] DP Inter4MV frame 1: PSNR = 38.21 dB
[INFO] [stdout] DP Inter4MV frame 2: PSNR = 38.77 dB
[INFO] [stdout] DP Inter4MV frame 3: PSNR = 38.22 dB
[INFO] [stdout] 
[INFO] [stdout] thread 'dp_p_vop_inter4mv_roundtrip' (167) panicked at tests/dp.rs:764:9:
[INFO] [stdout] ffmpeg required for DP Inter4MV cross-decode test
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x643b21d3094a - std[29689e6404d28ef9]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x643b21d3094a - std[29689e6404d28ef9]::backtrace_rs::backtrace::trace_unsynchronized::<std[29689e6404d28ef9]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x643b21d3094a - std[29689e6404d28ef9]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x643b21d3094a - <<std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[e929cb53b82a81ca]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x643b21d44c0a - <core[e929cb53b82a81ca]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x643b21d44c0a - core[e929cb53b82a81ca]::fmt::write
[INFO] [stdout]    6:     0x643b21d35182 - std[29689e6404d28ef9]::io::default_write_fmt::<alloc[9d7caffeb3b5d2c6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x643b21d35182 - <alloc[9d7caffeb3b5d2c6]::vec::Vec<u8> as std[29689e6404d28ef9]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x643b21d0ef6f - <std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x643b21d0ef6f - std[29689e6404d28ef9]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x643b21d28829 - std[29689e6404d28ef9]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x643b21cb515c - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x643b21cb515c - test[a24b3028667022f7]::test_main_inner::<test[a24b3028667022f7]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x643b21d289e2 - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x643b21d289e2 - std[29689e6404d28ef9]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x643b21d0f05a - std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x643b21d04039 - std[29689e6404d28ef9]::sys::backtrace::__rust_end_short_backtrace::<std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x643b21d0fd3d - __rustc[3aed6af316653e63]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x643b21d4539c - core[e929cb53b82a81ca]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x643b21bffbb7 - dp[3ef94c5314edcd3]::dp_p_vop_inter4mv_roundtrip
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/dp.rs:764:9
[INFO] [stdout]   20:     0x643b21bfa1c7 - dp[3ef94c5314edcd3]::dp_p_vop_inter4mv_roundtrip::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/dp.rs:659:33
[INFO] [stdout]   21:     0x643b21c0c996 - <dp[3ef94c5314edcd3]::dp_p_vop_inter4mv_roundtrip::{closure#0} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x643b21ca843b - <fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x643b21ca843b - test[a24b3028667022f7]::__rust_begin_short_backtrace::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x643b21cb5c2b - test[a24b3028667022f7]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x643b21cb5c2b - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x643b21cb5c2b - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x643b21cb5c2b - std[29689e6404d28ef9]::panicking::catch_unwind::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x643b21cb5c2b - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x643b21cb5c2b - test[a24b3028667022f7]::run_test_in_process
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x643b21cb5c2b - test[a24b3028667022f7]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x643b21caf344 - test[a24b3028667022f7]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x643b21caf344 - std[29689e6404d28ef9]::sys::backtrace::__rust_begin_short_backtrace::<test[a24b3028667022f7]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x643b21cb8832 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x643b21cb8832 - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x643b21cb8832 - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x643b21cb8832 - std[29689e6404d28ef9]::panicking::catch_unwind::<(), core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x643b21cb8832 - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x643b21cb8832 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x643b21cb8832 - <std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x643b21d2fd8f - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn core[e929cb53b82a81ca]::ops::function::FnOnce<(), Output = ()> + core[e929cb53b82a81ca]::marker::Send> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   41:     0x643b21d2fd8f - <std[29689e6404d28ef9]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x73827a063aa4 - <unknown>
[INFO] [stdout]   43:     0x73827a0f0a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     dp_p_vop_inter4mv_roundtrip
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 7 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test dp`
[INFO] running `Command { std: "docker" "inspect" "e6d64f2d08ce7aaf5199acd389b57691890b21b903fd20e9c71027cdd35bfdd3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e6d64f2d08ce7aaf5199acd389b57691890b21b903fd20e9c71027cdd35bfdd3", kill_on_drop: false }`
[INFO] [stdout] e6d64f2d08ce7aaf5199acd389b57691890b21b903fd20e9c71027cdd35bfdd3
