[INFO] fetching crate sva-ast 0.7.15...
[INFO] testing sva-ast-0.7.15 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate sva-ast 0.7.15 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate sva-ast 0.7.15
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sva-ast 0.7.15
[INFO] tweaked toml for crates.io crate sva-ast 0.7.15 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sva-ast 0.7.15 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 sva-ast 0.7.15 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-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63
[INFO] running `Command { std: "docker" "start" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", 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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63" "/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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", 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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sva-ast v0.7.15 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.60s
[INFO] running `Command { std: "docker" "inspect" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", 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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63" "/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 sva-ast v0.7.15 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.16s
[INFO] running `Command { std: "docker" "inspect" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", 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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63" "/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.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sva_ast-c9cedf23e3ebc46c)
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test doc_comment::tests::a_parsed_line_prints_back_to_what_parse_reads ... ok
[INFO] [stdout] test doc_comment::tests::a_well_formed_line_parses_into_its_four_fields ... ok
[INFO] [stdout] test doc_comment::tests::any_tag_count_or_shape_is_one_the_grammar_accepts ... ok
[INFO] [stdout] test expr::tests::float_literals_compare_by_bit_pattern ... ok
[INFO] [stdout] test diag::tests::registry_codes_are_unique_kebab_case ... ok
[INFO] [stdout] test expr::tests::structural_equality_ignores_spans ... ok
[INFO] [stdout] test graph::tests::a_composition_built_node_by_node_refuses_until_the_reached_set_is_whole ... ok
[INFO] [stdout] test graph::tests::a_dangling_ref_truncated_by_the_digit_segment_rule_names_what_fell_off ... ok
[INFO] [stdout] test graph::tests::a_delay_free_leg_is_found_even_when_a_delayed_one_reaches_it_first ... ok
[INFO] [stdout] test filename::tests::bars_suffix_parses ... ok
[INFO] [stdout] test filename::tests::seconds_suffix_parses_including_fractional ... ok
[INFO] [stdout] test graph::tests::a_cycle_read_only_at_bare_t_is_refused_where_the_read_is_written ... ok
[INFO] [stdout] test filename::tests::a_non_span_trailing_segment_leaves_the_name_plain ... ok
[INFO] [stdout] test doc_comment::tests::each_missing_piece_names_itself ... ok
[INFO] [stdout] test diag::tests::display_is_located_and_ascii ... ok
[INFO] [stdout] test graph::tests::a_mutual_cycle_with_a_shifted_read_on_it_parses ... ok
[INFO] [stdout] test graph::tests::a_ref_reaching_a_sibling_directory_resolves_and_renders_as_one_graph ... ok
[INFO] [stdout] test graph::tests::a_node_merely_referencing_a_cycle_member_is_not_itself_flagged ... ok
[INFO] [stdout] test graph::tests::a_function_invoking_itself_is_refused_but_self_inside_one_is_not ... ok
[INFO] [stdout] test graph::tests::a_global_is_read_from_variables_first_and_the_root_second ... ok
[INFO] [stdout] test graph::tests::a_pull_load_never_asks_for_a_node_the_roots_do_not_reach ... ok
[INFO] [stdout] test graph::tests::a_dot_segment_normalizes_in_the_middle_of_a_path_too ... ok
[INFO] [stdout] test graph::tests::a_dot_segment_resolves_against_the_referencing_files_own_directory ... ok
[INFO] [stdout] test graph::tests::a_ref_resolves_relative_to_its_own_files_directory ... ok
[INFO] [stdout] test graph::tests::a_zero_count_repeat_and_an_argument_less_concat_are_silence ... ok
[INFO] [stdout] test graph::tests::a_ref_walking_above_the_root_is_a_located_refusal_naming_it ... ok
[INFO] [stdout] test graph::tests::a_walk_above_the_composition_root_resolves_to_nothing ... ok
[INFO] [stdout] test graph::tests::concat_before_resolve_bar_spans_is_refused_not_guessed ... ok
[INFO] [stdout] test graph::tests::cross_file_refs_resolve ... ok
[INFO] [stdout] test graph::tests::repeat_produces_n_back_to_back_crop_shifted_copies ... ok
[INFO] [stdout] test graph::tests::repeat_with_a_non_integer_count_is_refused_not_guessed ... ok
[INFO] [stdout] test graph::tests::deep_ref_chain_cycle_is_refused ... ok
[INFO] [stdout] test graph::tests::concat_places_differently_spanned_args_sequentially_with_no_manual_offsets ... ok
[INFO] [stdout] test graph::tests::resolve_bar_spans_rescales_a_grids_row_shifts_to_match_its_crop_window ... ok
[INFO] [stdout] test graph::tests::concat_with_a_custom_time_arg_ref_is_refused_not_silently_dropped ... ok
[INFO] [stdout] test graph::tests::concat_with_a_non_ref_argument_is_refused_not_guessed ... ok
[INFO] [stdout] test graph::tests::resolve_bar_spans_converts_only_bars_units_to_seconds ... ok
[INFO] [stdout] test graph::tests::same_file_cycle_is_refused ... ok
[INFO] [stdout] test graph::tests::self_ref_is_not_flagged_a_cycle ... ok
[INFO] [stdout] test graph::tests::resolve_bar_spans_rewrites_bar_literals_everywhere_they_appear ... ok
[INFO] [stdout] test ingest::tests::a_tabbed_single_line_file_is_still_a_tsv_grid ... ok
[INFO] [stdout] test ingest::tests::a_header_comment_leaves_every_scalar_shape_alone ... ok
[INFO] [stdout] test ingest::tests::a_tsv_file_without_a_span_suffix_is_a_located_refusal ... ok
[INFO] [stdout] test graph::tests::two_graphs_over_the_same_text_hold_structurally_equal_expressions ... ok
[INFO] [stdout] test ingest::tests::bare_token_files_are_string_literals_not_expressions ... ok
[INFO] [stdout] test ingest::tests::a_grids_bar_span_comes_from_its_filename_suffix ... ok
[INFO] [stdout] test ingest::tests::a_file_holding_one_identifier_is_that_identifier_not_a_string ... ok
[INFO] [stdout] test ingest::tests::comment_lines_count_as_neither_expression_lines_nor_grid_rows ... ok
[INFO] [stdout] test lexer::tests::a_dot_segment_joins_a_ref_path_wherever_a_segment_begins ... ok
[INFO] [stdout] test lexer::tests::a_semicolon_comments_out_the_rest_of_its_line ... ok
[INFO] [stdout] test ingest::tests::plain_number_files_are_literals ... ok
[INFO] [stdout] test lexer::tests::a_slash_continues_a_ref_path_only_into_a_name_so_a_number_divides ... ok
[INFO] [stdout] test lexer::tests::every_unit_suffix_resolves_at_parse_time_and_the_longest_one_wins ... ok
[INFO] [stdout] test dir::tests::walk_and_get_skip_a_fifo_instead_of_blocking ... ok
[INFO] [stdout] test lexer::tests::hostile_bytes_are_located_never_panic ... ok
[INFO] [stdout] test lexer::tests::numbers_lex ... ok
[INFO] [stdout] test lexer::tests::ref_spans_cover_only_the_at_path_head_never_a_call_or_non_ref_content ... ok
[INFO] [stdout] test lexer::tests::a_unit_suffix_makes_a_time_literal_but_only_when_the_word_ends_there ... ok
[INFO] [stdout] test lexer::tests::spans_are_sliceable_on_every_token ... ok
[INFO] [stdout] test ingest::tests::html_content_refuses_as_not_a_node_rather_than_a_tsv_missing_span ... ok
[INFO] [stdout] test lexer::tests::idents_and_operators_lex ... ok
[INFO] [stdout] test ingest::tests::malformed_expression_syntax_refuses ... ok
[INFO] [stdout] test ingest::tests::single_line_expression_files_parse_as_expressions ... ok
[INFO] [stdout] test lexer::tests::ref_paths_lex_as_one_token_across_hyphens_and_slashes ... ok
[INFO] [stdout] test parser::tests::a_bare_number_in_a_time_position_refuses_naming_the_units ... ok
[INFO] [stdout] test lexer::tests::a_dot_joins_a_ref_path_only_when_a_digit_follows_it ... ok
[INFO] [stdout] test parser::tests::brackets_read_an_index_on_a_ref_or_self ... ok
[INFO] [stdout] test parser::tests::located_refusals ... ok
[INFO] [stdout] test parser::tests::a_ref_takes_named_bindings_after_its_time_argument ... ok
[INFO] [stdout] test parser::tests::refs_bare_and_time_shifted ... ok
[INFO] [stdout] test parser::tests::a_seconds_literal_is_a_plain_number_and_a_bars_literal_is_not ... ok
[INFO] [stdout] test parser::tests::calls_mix_positional_and_named_args ... ok
[INFO] [stdout] test parser::tests::a_unit_is_a_number_by_the_time_the_tree_holds_it ... ok
[INFO] [stdout] test parser::tests::chain_dot_desugars_to_nested_calls ... ok
[INFO] [stdout] test parser::tests::a_minus_before_a_logarithmic_unit_is_part_of_the_literal ... ok
[INFO] [stdout] test parser::tests::precedence_and_grouping ... ok
[INFO] [stdout] test parser::tests::sum_survives_parse_unexpanded ... ok
[INFO] [stdout] test parser::tests::unary_minus_desugars_to_a_binary_subtraction ... ok
[INFO] [stdout] test source::tests::a_composition_collected_from_pairs_equals_one_built_node_by_node ... ok
[INFO] [stdout] test parser::tests::self_ref_requires_exactly_one_positional_arg ... ok
[INFO] [stdout] test tsv::tests::row_offset_shifts_every_t_in_the_cell ... ok
[INFO] [stdout] test tsv::tests::blank_lines_and_cells_contribute_nothing_but_count_as_rows ... ok
[INFO] [stdout] test tsv::tests::a_malformed_cell_refuses_located ... ok
[INFO] [stdout] test tsv::tests::a_wholly_blank_grid_desugars_to_zero ... ok
[INFO] [stdout] test parser::tests::nesting_is_bounded_not_a_stack_overflow ... ok
[INFO] [stdout] test tsv::tests::one_parsed_grid_rescales_to_any_span_without_reparsing ... ok
[INFO] [stdout] test tsv::tests::row_count_counts_every_row_a_trailing_blank_included ... ok
[INFO] [stdout] test source::tests::a_composition_is_a_sorted_path_to_text_map_a_caller_fills_one_node_at_a_time ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 50 tests
[INFO] [stdout] test fixtures::a_cropped_cell_moves_with_its_row ... ok
[INFO] [stdout] test fixtures::a_file_that_is_not_text_refuses_naming_what_a_composition_holds ... ok
[INFO] [stderr]      Running tests/it/main.rs (/opt/rustwide/target/debug/deps/it-71f3a0de99ee85df)
[INFO] [stdout] test fixtures::dangling_ref_fixture_refuses ... ok
[INFO] [stdout] test fixtures::a_default_only_a_dead_default_reads_is_dead_with_it ... ok
[INFO] [stdout] test fixtures::malformed_expression_fixture_refuses ... ok
[INFO] [stdout] test fixtures::a_dot_directory_is_never_walked ... ok
[INFO] [stdout] test fixtures::a_defaulted_parameter_called_with_a_shift_is_mentioned ... ok
[INFO] [stdout] test fixtures::a_default_line_is_read_off_the_top_of_a_file_and_bounded_to_arithmetic ... ok
[INFO] [stdout] test fixtures::the_frequency_variable_the_imaginary_unit_and_an_infinite_bound_are_plain_names ... ok
[INFO] [stdout] test fixtures::the_singular_constructors_parse_as_ordinary_calls ... ok
[INFO] [stdout] test fixtures::tsv_without_a_span_suffix_fixture_refuses ... ok
[INFO] [stdout] test fixtures::valid_composition_resolves_every_ref ... ok
[INFO] [stdout] test fixtures::missing_file_fixture_refuses ... ok
[INFO] [stdout] test outline::a_text_that_does_not_parse_refuses_as_the_parser_does ... ok
[INFO] [stdout] test fuzz::a_nonexistent_composition_directory_refuses_not_panics ... ok
[INFO] [stdout] test fuzz::hostile_expression_text_never_panics ... ok
[INFO] [stdout] test outline::each_span_names_the_bytes_a_node_was_written_in ... ok
[INFO] [stdout] test outline::the_nodes_the_parser_supplies_say_so ... ok
[INFO] [stdout] test fixtures::a_series_parses_to_one_call_carrying_its_inf_bound ... ok
[INFO] [stdout] test parameters::a_bareword_call_naming_a_node_invokes_it ... ok
[INFO] [stdout] test parameters::a_call_or_ref_argument_value_is_read_and_its_key_is_not ... ok
[INFO] [stdout] test parameters::a_default_line_sees_only_the_lines_above_it ... ok
[INFO] [stdout] test parameters::a_name_the_caller_binds_is_bound_and_its_default_line_is_not_read ... ok
[INFO] [stdout] test parameters::a_parameter_read_by_index_or_called_at_a_time_is_a_parameter ... ok
[INFO] [stdout] test parameters::a_name_shaped_like_a_note_beyond_midi_is_a_parameter ... ok
[INFO] [stdout] test parameters::a_series_index_is_bound_only_in_its_own_term ... ok
[INFO] [stdout] test parameters::each_is_named_once_body_first_then_the_default_lines ... ok
[INFO] [stdout] test parameters::the_languages_own_names_are_no_parameters ... ok
[INFO] [stdout] test series::a_sum_no_binding_reads_stays_a_series ... ok
[INFO] [stdout] test series::an_index_a_binding_reads_writes_the_sum_out_one_ref_per_index ... ok
[INFO] [stdout] test series::a_sum_with_no_written_count_of_nodes_refuses ... ok
[INFO] [stdout] test parameters::a_parameter_with_a_default_line_is_bound ... ok
[INFO] [stdout] test outline::an_outline_is_the_tree_the_parser_builds ... ok
[INFO] [stdout] test series::an_inner_sum_rebinding_the_index_keeps_its_own ... ok
[INFO] [stdout] test series::an_outer_index_bounding_an_inner_written_sum_writes_both_out ... ok
[INFO] [stdout] test text::a_bare_number_in_a_joined_time_slot_refuses_like_a_plain_one ... ok
[INFO] [stdout] test fixtures::cross_file_cycle_fixture_refuses ... ok
[INFO] [stdout] test text::a_bare_ref_prints_without_its_implicit_time_argument ... ok
[INFO] [stdout] test text::print_round_trips_a_unit_inside_a_join ... ok
[INFO] [stdout] test text::a_negative_shift_round_trips ... ok
[INFO] [stdout] test vocabulary::an_octave_beyond_midi_is_no_note ... ok
[INFO] [stdout] test vocabulary::anything_that_is_not_a_note_is_none_rather_than_a_guess ... ok
[INFO] [stdout] test text::printed_text_reparses_to_the_same_expression ... ok
[INFO] [stdout] test vocabulary::builtins_include_the_filters_the_series_and_the_sample_index ... ok
[INFO] [stdout] test vocabulary::every_reserved_name_is_reserved_and_all_but_self_read_as_values ... ok
[INFO] [stdout] test vocabulary::the_tuning_anchor_is_midi_69 ... ok
[INFO] [stdout] test text::precedence_is_restored_by_parentheses_only_where_it_is_needed ... ok
[INFO] [stdout] test vocabulary::accidentals_are_spelled_with_letters_and_meet_in_equal_temperament ... ok
[INFO] [stdout] test fuzz::truncated_and_deeply_nested_never_panics ... ok
[INFO] [stdout] test fuzz::a_deep_cross_file_ref_chain_never_panics_or_hangs ... ok
[INFO] [stderr]    Doc-tests sva_ast
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.46s
[INFO] [stdout] 
[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" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63", kill_on_drop: false }`
[INFO] [stdout] b65819f9c670aecb4acaa927aeb63e24c4aa900d31f90cb612ef4b8fc7ae2f63
