[INFO] fetching crate subcue 0.1.0...
[INFO] testing subcue-0.1.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate subcue 0.1.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate subcue 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate subcue 0.1.0
[INFO] tweaked toml for crates.io crate subcue 0.1.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate subcue 0.1.0 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 subcue 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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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] 76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b
[INFO] running `Command { std: "docker" "start" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", 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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b" "/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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", 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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling subcue v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.23s
[INFO] running `Command { std: "docker" "inspect" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", 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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b" "/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 serde_core v1.0.229
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling subcue v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 6.00s
[INFO] running `Command { std: "docker" "inspect" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", 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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b" "/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.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/subcue-c0a1cba09310f274)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test text::tests::a_bare_less_than_does_not_swallow_the_next_tag ... ok
[INFO] [stdout] test text::tests::ass_breaks_spaces_and_alignment ... ok
[INFO] [stdout] test text::tests::colour_round_trips_between_srt_and_ass ... ok
[INFO] [stdout] test text::tests::ass_toggles_map_to_the_same_tree ... ok
[INFO] [stdout] test text::tests::ass_reset_closes_every_open_span ... ok
[INFO] [stdout] test text::tests::empty_lines_are_never_rendered_for_srt_or_vtt ... ok
[INFO] [stdout] test text::tests::cross_dialect_round_trip_preserves_the_tree ... ok
[INFO] [stdout] test text::tests::crossing_tags_close_and_reopen ... ok
[INFO] [stdout] test text::tests::nested_markup_builds_a_tree ... ok
[INFO] [stdout] test text::tests::strike_and_colour_drop_in_vtt_but_keep_their_text ... ok
[INFO] [stdout] test text::tests::unknown_ass_commands_become_raw_and_are_reported ... ok
[INFO] [stdout] test text::tests::rendering_is_a_fixed_point_in_every_dialect ... ok
[INFO] [stdout] test text::tests::unknown_markup_stays_literal ... ok
[INFO] [stdout] test text::tests::vtt_only_tags_are_unwrapped_and_reported_once ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conformance.rs (/opt/rustwide/target/debug/deps/conformance-e13b2d5e54aaa862)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test ass_digit_run_overflow_is_a_typed_error ... ok
[INFO] [stdout] test blank_after_timing_keeps_the_empty_cue ... ok
[INFO] [stdout] test utf16_typed_error ... ok
[INFO] [stdout] test digit_run_overflow_is_a_typed_error ... ok
[INFO] [stdout] test digits_in_cue_text_do_not_start_a_block ... ok
[INFO] [stdout] test checked_time_multiplication_reports_overflow ... ok
[INFO] [stdout] test accepted_inputs_reach_a_compose_fixed_point ... ok
[INFO] [stdout] test conversions_parse_and_reach_a_fixed_point ... ok
[INFO] [stdout] test replays_all_conformance_vectors ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.48s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conversion.rs (/opt/rustwide/target/debug/deps/conversion-aac5ecc7bc375bc4)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test crossing_tags_are_normalized_to_a_tree ... ok
[INFO] [stdout] test ass_overrides_map_to_settings_text_and_warnings ... ok
[INFO] [stdout] test converted_text_with_repeated_breaks_reparses_to_the_same_cues ... ok
[INFO] [stdout] test spans_are_a_public_model_with_colour_values ... ok
[INFO] [stdout] test srt_formatting_survives_a_trip_through_ass ... ok
[INFO] [stdout] test vtt_voice_and_class_tags_take_their_documented_paths ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/coordinates.rs (/opt/rustwide/target/debug/deps/coordinates-0afe29086e624c80)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test invalid_utf8_after_a_bom_counts_the_bom_bytes ... ok
[INFO] [stdout] test invalid_utf8_at_file_start_is_offset_zero ... ok
[INFO] [stdout] test invalid_utf8_after_crlf_counts_both_ending_bytes ... ok
[INFO] [stdout] test srt_and_vtt_timestamp_errors_name_the_physical_line ... ok
[INFO] [stdout] test invalid_utf8_inside_a_multibyte_line_is_the_byte_position ... ok
[INFO] [stdout] test streaming_reports_the_same_offsets_as_whole_file_parsing ... ok
[INFO] [stdout] test ass_timestamp_errors_name_the_physical_dialogue_line ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/malformed_input.rs (/opt/rustwide/target/debug/deps/malformed_input-5a2e4f99eebb7ceb)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test a_parseable_timing_line_still_starts_a_block_in_text_position ... ok
[INFO] [stdout] test a_vtt_block_without_a_timing_line_is_discarded_with_a_warning ... ok
[INFO] [stdout] test an_arrow_inside_srt_text_is_text ... ok
[INFO] [stdout] test a_line_feed_in_ass_text_composes_as_a_break ... ok
[INFO] [stdout] test ass_to_vtt_escapes_plain_text_only_and_the_voice_name ... ok
[INFO] [stdout] test negative_times_compose_as_zero ... ok
[INFO] [stdout] test duplicate_ass_sections_merge_and_compose_once ... ok
[INFO] [stdout] test missing_arrow_needs_a_line_that_reads_as_a_time ... ok
[INFO] [stdout] test converted_line_breaks_never_produce_an_empty_line ... ok
[INFO] [stdout] test events_and_blocks_survive_cue_removal ... ok
[INFO] [stdout] test streaming_reads_a_styles_section_that_follows_the_events ... ok
[INFO] [stdout] test raw_sections_keep_their_position_and_header_supports_struct_update ... ok
[INFO] [stdout] test non_ascii_bytes_inside_colour_values_never_panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/model_traits.rs (/opt/rustwide/target/debug/deps/model_traits-9a345828ba71ac63)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test owned_model_types_implement_eq ... ok
[INFO] [stdout] test reader_failures_are_io_errors_not_decoding_errors ... ok
[INFO] [stdout] test a_reader_that_fails_mid_stream_yields_the_cues_before_the_failure ... ok
[INFO] [stdout] test style_font_fields_use_rust_names ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/performance.rs (/opt/rustwide/target/debug/deps/performance-1292ccd2b449861c)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test generated_performance_inputs_match_the_pins ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.29s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/streaming_alloc.rs (/opt/rustwide/target/debug/deps/streaming_alloc-85033afac772592f)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test streaming_allocations_are_bounded_per_cue_on_the_perf_shapes ... ok
[INFO] [stdout] test streaming_allocations_do_not_grow_with_lines_per_cue ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/streaming_behavior.rs (/opt/rustwide/target/debug/deps/streaming_behavior-6495c076c4407435)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test streaming_constructors_do_not_consume_cue_bodies ... ok
[INFO] [stdout] test ass_stream_reports_a_bad_dialogue_and_continues ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/streaming_parity.rs (/opt/rustwide/target/debug/deps/streaming_parity-399f96a606bf5bba)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test streaming_surfaces_the_whole_file_error_on_every_error_record ... ok
[INFO] [stdout] test streaming_matches_whole_file_parsing_on_every_accepted_record ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/text_position.rs (/opt/rustwide/target/debug/deps/text_position-998876ce4264e8cb)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test a_bad_timing_line_after_text_is_still_an_error_in_block_position ... ok
[INFO] [stdout] test a_non_parsing_arrow_line_inside_vtt_text_stays_text ... ok
[INFO] [stdout] test a_vtt_timing_line_inside_cue_text_starts_a_new_cue ... ok
[INFO] [stdout] test a_bare_less_than_keeps_the_following_tags ... ok
[INFO] [stderr]      Running tests/totality.rs (/opt/rustwide/target/debug/deps/totality-bbcee20fce2c0eac)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test arbitrary_bytes_never_panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.76s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests subcue
[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" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b", kill_on_drop: false }`
[INFO] [stdout] 76f5fff17664799f6f68b713644e906b3bdda7d1b40ea356e92c6383e0f3487b
