[INFO] cloning repository https://github.com/blueedgenick/datafusion-plan-viz
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/blueedgenick/datafusion-plan-viz" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueedgenick%2Fdatafusion-plan-viz", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueedgenick%2Fdatafusion-plan-viz'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 0b936b7dde0274e1d7e80f70a049a529c38de55c
[INFO] testing blueedgenick/datafusion-plan-viz against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fblueedgenick%2Fdatafusion-plan-viz" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/blueedgenick/datafusion-plan-viz
[INFO] finished tweaking git repo https://github.com/blueedgenick/datafusion-plan-viz
[INFO] tweaked toml for git repo https://github.com/blueedgenick/datafusion-plan-viz written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/blueedgenick/datafusion-plan-viz 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 git repo https://github.com/blueedgenick/datafusion-plan-viz 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-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1
[INFO] running `Command { std: "docker" "start" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", 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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1" "/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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", 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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling nom v8.0.0
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling planviz v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 10.23s
[INFO] running `Command { std: "docker" "inspect" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", 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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling planviz v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 5.98s
[INFO] running `Command { std: "docker" "inspect" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", 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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/planviz-85e7e24f91dc3351)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test excalidraw::tests::a_long_statement_title_folds_wraps_and_caps_in_the_band ... ok
[INFO] [stdout] test excalidraw::tests::arrow_binds_both_boxes_and_the_boxes_name_it_back ... ok
[INFO] [stdout] test excalidraw::tests::left_child_flips_the_first_leg_instead_of_the_width ... ok
[INFO] [stdout] test layout::tests::an_unbreakable_token_overflows_instead_of_splitting ... ok
[INFO] [stdout] test excalidraw::tests::details_and_metrics_add_an_unbound_body_in_the_group ... ok
[INFO] [stdout] test excalidraw::tests::appendix_cards_follow_the_arrows_and_skip_unmeasured_nodes ... ok
[INFO] [stdout] test excalidraw::tests::document_carries_the_app_wrapper ... ok
[INFO] [stdout] test excalidraw::tests::heat_tint_lands_as_the_rectangle_fill ... ok
[INFO] [stdout] test excalidraw::tests::heading_centers_in_the_band_above_the_tree ... ok
[INFO] [stdout] test excalidraw::tests::bare_node_is_a_rectangle_bound_title_and_plain_suffix ... ok
[INFO] [stdout] test layout::tests::single_node_stays_neutral_even_with_metrics ... ok
[INFO] [stdout] test layout::tests::footer_is_the_last_line_and_only_when_metrics_exist ... ok
[INFO] [stdout] test layout::tests::heat_top_bucket_lands_only_on_the_slowest_node ... ok
[INFO] [stdout] test layout::tests::boxes_carry_their_title_and_pre_order_indexes ... ok
[INFO] [stdout] test layout::tests::metrics_less_trees_draw_no_appendix ... ok
[INFO] [stdout] test layout::tests::zero_duration_does_not_rank ... ok
[INFO] [stdout] test layout::tests::wide_parent_centers_narrow_children_under_it ... ok
[INFO] [stdout] test metric::tests::captured_footer_is_compact ... ok
[INFO] [stdout] test metric::tests::byte_shapes ... ok
[INFO] [stdout] test metric::tests::duration_shapes ... ok
[INFO] [stdout] test metric::tests::captured_list_parses_to_typed_values ... ok
[INFO] [stdout] test layout::tests::metrics_appendix_deals_cards_into_three_columns ... ok
[INFO] [stdout] test layout::tests::three_level_rows_pack_without_overlap ... ok
[INFO] [stdout] test metric::tests::captured_list_is_full_in_the_appendix ... ok
[INFO] [stdout] test metric::tests::edge_values_fall_back_to_raw ... ok
[INFO] [stdout] test metric::tests::other_captured_shapes_parse ... ok
[INFO] [stdout] test metric::tests::unrecognized_metrics_leave_the_footer_for_the_appendix ... ok
[INFO] [stdout] test parse::tests::analyze_row_outranks_physical_rows ... ok
[INFO] [stdout] test metric::tests::rows_get_thousands_separators ... ok
[INFO] [stdout] test parse::tests::empty_metrics_list_is_present_but_empty ... ok
[INFO] [stdout] test metric::tests::spills_appear_only_when_nonzero ... ok
[INFO] [stdout] test parse::tests::depth_jump_of_more_than_one_demotes ... ok
[INFO] [stdout] test parse::tests::a_pipe_inside_plan_text_survives_cell_slicing ... ok
[INFO] [stdout] test parse::tests::empty_plan_cell_refuses ... ok
[INFO] [stdout] test parse::tests::a_second_root_line_is_tolerated_as_a_sibling ... ok
[INFO] [stdout] test layout::tests::heat_tint_gate_turns_the_ramp_off ... ok
[INFO] [stdout] test excalidraw::tests::seeds_are_distinct_and_the_render_is_byte_stable ... ok
[INFO] [stdout] test layout::tests::metrics_less_tree_is_all_neutral ... ok
[INFO] [stdout] test layout::tests::long_details_wrap_at_56_columns_and_cap_with_an_ellipsis ... ok
[INFO] [stdout] test parse::tests::header_pipe_offsets_stand_in_for_a_lost_separator ... ok
[INFO] [stdout] test parse::tests::headerless_or_foreign_tables_are_ignored ... ok
[INFO] [stdout] test parse::tests::name_shaped_line_without_metrics_continues_in_an_analyze_cell ... ok
[INFO] [stdout] test parse::tests::terminal_document_yields_the_physical_row ... ok
[INFO] [stdout] test parse::tests::the_last_table_wins_within_a_rung ... ok
[INFO] [stdout] test parse::tests::truncation_marker_costs_the_tail_not_the_list ... ok
[INFO] [stdout] test parse::tests::statistics_suffix_strips_before_metrics ... ok
[INFO] [stdout] test parse::tests::error_types_are_displayed_and_errors ... ok
[INFO] [stdout] test parse::tests::verbose_physical_variant_is_picked_and_full_metrics_never ... ok
[INFO] [stdout] test parse::tests::wrapped_label_lines_continue_details ... ok
[INFO] [stdout] test parse::tests::operatorless_text_refuses_with_line_context ... ok
[INFO] [stdout] test parse::tests::prefer_logical_takes_the_logical_row ... ok
[INFO] [stdout] test parse::tests::indent_tree_reads_a_metrics_cell ... ok
[INFO] [stderr]      Running unittests src/bin/planviz.rs (/opt/rustwide/target/release/deps/planviz-91173fd7faa58d35)
[INFO] [stdout] test parse::tests::logical_names_may_carry_spaces_and_no_details ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli.rs (/opt/rustwide/target/release/deps/cli-45742312cfb9106c)
[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] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test an_unknown_flag_is_a_usage_error ... ok
[INFO] [stdout] test the_logical_flag_diagrams_the_logical_row ... ok
[INFO] [stdout] test the_title_flag_draws_a_heading ... ok
[INFO] [stdout] test help_exits_zero_with_the_usage_line ... ok
[INFO] [stdout] test empty_and_binary_input_refuse_with_the_planviz_line ... ok
[INFO] [stdout] test the_default_run_diagrams_the_physical_row ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/parse_fixtures.rs (/opt/rustwide/target/release/deps/parse_fixtures-9dccf98e7480d066)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test terminal_noise_without_a_table_refuses ... ok
[INFO] [stderr]      Running tests/render_surface.rs (/opt/rustwide/target/release/deps/render_surface-310ea630fd7362a5)
[INFO] [stdout] test olapsh_explain_document_yields_physical_and_logical ... ok
[INFO] [stdout] test olapsh_analyze_document_yields_the_metrics_tree ... ok
[INFO] [stdout] test analyze_outranks_a_later_physical_table ... ok
[INFO] [stdout] test a_plan_row_with_an_empty_cell_refuses ... ok
[INFO] [stdout] test datafusion_cli_document_parses_with_pipes_intact ... 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] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test a_tree_renders_as_bound_boxes_and_symmetric_bindings ... ok
[INFO] [stdout] test a_titled_render_carries_exactly_one_heading ... ok
[INFO] [stdout] test the_hottest_node_alone_carries_the_hottest_fill ... ok
[INFO] [stdout] test write_file_round_trips_through_a_real_path ... ok
[INFO] [stdout] test the_same_tree_renders_byte_identical ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests planviz
[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" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1", kill_on_drop: false }`
[INFO] [stdout] 2137dba8f45bc0a3946f8f7c10be5ec578992f2a6857b2beede5962e0e8293f1