[INFO] fetching crate substrait-explain 0.3.1...
[INFO] testing substrait-explain-0.3.1 against beta-2026-04-21 for beta-1.96-2
[INFO] extracting crate substrait-explain 0.3.1 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate substrait-explain 0.3.1
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate substrait-explain 0.3.1
[INFO] tweaked toml for crates.io crate substrait-explain 0.3.1 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate substrait-explain 0.3.1 on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate substrait-explain 0.3.1 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" "+beta-2026-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+beta-2026-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 3ab1aecf2f648e08dccfcefec791a667703cd032c02ff3a62db09d6999b4b825
[INFO] running `Command { std: "docker" "start" "-a" "3ab1aecf2f648e08dccfcefec791a667703cd032c02ff3a62db09d6999b4b825", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3ab1aecf2f648e08dccfcefec791a667703cd032c02ff3a62db09d6999b4b825", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3ab1aecf2f648e08dccfcefec791a667703cd032c02ff3a62db09d6999b4b825", kill_on_drop: false }`
[INFO] [stdout] 3ab1aecf2f648e08dccfcefec791a667703cd032c02ff3a62db09d6999b4b825
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 93e0386ae29f265fde108445666d495f3388becd1dfa7b41ad166f6e102a99e1
[INFO] running `Command { std: "docker" "start" "-a" "93e0386ae29f265fde108445666d495f3388becd1dfa7b41ad166f6e102a99e1", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling semver v1.0.27
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling zmij v1.0.17
[INFO] [stderr]    Compiling fixedbitset v0.5.7
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling multimap v0.10.1
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling schemars v0.8.22
[INFO] [stderr]    Compiling itertools v0.14.0
[INFO] [stderr]    Compiling beef v0.5.2
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling logos-codegen v0.15.1
[INFO] [stderr]    Compiling regress v0.10.5
[INFO] [stderr]    Compiling unicode-width v0.1.14
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling ryu v1.0.22
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling pest v2.8.5
[INFO] [stderr]    Compiling colorchoice v1.0.4
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling clap_lex v0.7.7
[INFO] [stderr]    Compiling petgraph v0.8.3
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling clap_builder v4.5.56
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling pest_meta v2.8.5
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling pest_generator v2.8.5
[INFO] [stderr]    Compiling prost-derive v0.14.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling schemars_derive v0.8.22
[INFO] [stderr]    Compiling miette-derive v7.6.0
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling pest_derive v2.8.5
[INFO] [stderr]    Compiling logos-derive v0.15.1
[INFO] [stderr]    Compiling miette v7.6.0
[INFO] [stderr]    Compiling logos v0.15.1
[INFO] [stderr]    Compiling prost v0.14.3
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling prost-types v0.14.3
[INFO] [stderr]    Compiling clap v4.5.56
[INFO] [stderr]    Compiling prost-build v0.14.3
[INFO] [stderr]    Compiling pbjson-build v0.8.0
[INFO] [stderr]    Compiling protox-parse v0.9.0
[INFO] [stderr]    Compiling prost-reflect v0.16.3
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling pbjson-types v0.8.0
[INFO] [stderr]    Compiling serde_tokenstream v0.2.2
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling pbjson v0.8.0
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling typify-impl v0.5.0
[INFO] [stderr]    Compiling protox v0.9.1
[INFO] [stderr]    Compiling substrait-explain v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling typify-macro v0.5.0
[INFO] [stderr]    Compiling typify v0.5.0
[INFO] [stderr]    Compiling substrait v0.62.2
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 00s
[INFO] running `Command { std: "docker" "inspect" "93e0386ae29f265fde108445666d495f3388becd1dfa7b41ad166f6e102a99e1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "93e0386ae29f265fde108445666d495f3388becd1dfa7b41ad166f6e102a99e1", kill_on_drop: false }`
[INFO] [stdout] 93e0386ae29f265fde108445666d495f3388becd1dfa7b41ad166f6e102a99e1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a0d2fa7deb7ac57a14e1fbb0feac8e9064ef55cf7d32cc43d24a0f09c2d4337f
[INFO] running `Command { std: "docker" "start" "-a" "a0d2fa7deb7ac57a14e1fbb0feac8e9064ef55cf7d32cc43d24a0f09c2d4337f", kill_on_drop: false }`
[INFO] [stderr]    Compiling substrait-explain v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 20.28s
[INFO] running `Command { std: "docker" "inspect" "a0d2fa7deb7ac57a14e1fbb0feac8e9064ef55cf7d32cc43d24a0f09c2d4337f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a0d2fa7deb7ac57a14e1fbb0feac8e9064ef55cf7d32cc43d24a0f09c2d4337f", kill_on_drop: false }`
[INFO] [stdout] a0d2fa7deb7ac57a14e1fbb0feac8e9064ef55cf7d32cc43d24a0f09c2d4337f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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" "+beta-2026-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] da321f5946565aed2d071435642448afe49d79de426db40952c757bf950270e2
[INFO] running `Command { std: "docker" "start" "-a" "da321f5946565aed2d071435642448afe49d79de426db40952c757bf950270e2", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.22s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/substrait_explain-71eefdbd579ea974)
[INFO] [stdout] 
[INFO] [stdout] running 176 tests
[INFO] [stdout] test cli::tests::test_auto_detect_from_extension ... ok
[INFO] [stdout] test cli::tests::test_auto_detection_error_unknown_output_extension ... ok
[INFO] [stdout] test cli::tests::test_auto_detection_error_unknown_input_extension ... ok
[INFO] [stdout] test cli::tests::test_convert_with_auto_detection ... ok
[INFO] [stdout] test cli::tests::test_convert_text_to_json ... ok
[INFO] [stdout] test cli::tests::test_convert_with_formatting_options ... ok
[INFO] [stdout] test cli::tests::test_convert_with_protobuf_output ... ok
[INFO] [stdout] test cli::tests::test_explicit_format_overrides_auto_detection ... ok
[INFO] [stdout] test cli::tests::test_protobuf_roundtrip ... ok
[INFO] [stdout] test cli::tests::test_convert_text_to_text ... ok
[INFO] [stdout] test cli::tests::test_validate_command ... ok
[INFO] [stdout] test extensions::args::tests::extension_multi_allows_multiple_children ... ok
[INFO] [stdout] test extensions::args::tests::extension_multi_allows_single_child ... ok
[INFO] [stdout] test cli::tests::test_validate_with_extensions ... ok
[INFO] [stdout] test cli::tests::test_write_plan_reports_formatting_issues ... ok
[INFO] [stdout] test extensions::args::tests::extension_multi_allows_zero_children ... ok
[INFO] [stdout] test extensions::args::tests::extension_single_rejects_wrong_child_counts ... ok
[INFO] [stdout] test extensions::examples::tests::from_args_rejects_extra_named_args ... ok
[INFO] [stdout] test extensions::examples::tests::from_args_rejects_unknown_strategy ... ok
[INFO] [stdout] test extensions::examples::tests::to_args_omits_zero_count ... ok
[INFO] [stdout] test extensions::examples::tests::registry_roundtrip ... ok
[INFO] [stdout] test extensions::examples::tests::to_args_produces_enum_and_named ... ok
[INFO] [stdout] test extensions::registry::tests::test_enhancement_duplicate_registration_returns_error ... ok
[INFO] [stdout] test extensions::registry::tests::test_extension_error_cases ... ok
[INFO] [stdout] test extensions::registry::tests::test_conflicting_type_url_leaves_registry_unchanged ... ok
[INFO] [stdout] test extensions::registry::tests::test_enhancement_not_found_error ... ok
[INFO] [stdout] test extensions::registry::tests::test_extension_registry_basic ... ok
[INFO] [stdout] test extensions::simple::tests::test_compound_name_plain ... ok
[INFO] [stdout] test extensions::simple::tests::test_compound_name_trailing_colon ... ok
[INFO] [stdout] test extensions::simple::tests::test_compound_name_roundtrip_in_extensions_section ... ok
[INFO] [stdout] test extensions::registry::tests::test_namespace_separation ... ok
[INFO] [stdout] test extensions::examples::tests::from_args_round_trip ... ok
[INFO] [stdout] test extensions::simple::tests::test_display_extension_lookup_empty ... ok
[INFO] [stdout] test extensions::examples::tests::round_trip_via_any ... ok
[INFO] [stdout] test extensions::simple::tests::test_display_extension_lookup_with_content ... ok
[INFO] [stdout] test extensions::simple::tests::test_from_extensions_duplicates ... ok
[INFO] [stdout] test extensions::simple::tests::test_from_extensions_basic ... ok
[INFO] [stdout] test extensions::simple::tests::test_from_extensions_invalid_mapping_type ... ok
[INFO] [stdout] test extensions::simple::tests::test_lookup_function_different_base_names_each_unique ... ok
[INFO] [stdout] test extensions::simple::tests::test_lookup_function_plain_name_overloaded_across_urns ... ok
[INFO] [stdout] test extensions::simple::tests::test_lookup_function_missing_anchor ... ok
[INFO] [stdout] test extensions::simple::tests::test_lookup_function_uniqueness_flags ... ok
[INFO] [stdout] test extensions::simple::tests::test_compound_name_with_signature ... ok
[INFO] [stdout] test extensions::registry::tests::test_extension_args ... ok
[INFO] [stdout] test extensions::simple::tests::test_extension_lookup_empty ... ok
[INFO] [stdout] test extensions::simple::tests::test_extensions_output ... ok
[INFO] [stdout] test extensions::simple::tests::test_find_by_name ... ok
[INFO] [stdout] test parser::expressions::tests::test_if_clause_structure ... ok
[INFO] [stdout] test parser::expressions::tests::test_if_then_preserves_clause_order ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_expression_nested ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_expression_via_expression_rule ... ok
[INFO] [stdout] test parser::expressions::tests::test_if_then_structure ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_boolean_false_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_boolean_true_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_expression_basic ... ok
[INFO] [stdout] test extensions::simple::tests::test_resolve_function_plain_stored_name ... ok
[INFO] [stdout] test extensions::simple::tests::test_resolve_function_without_anchor ... ok
[INFO] [stdout] test parser::expressions::tests::test_compound_name_stops_at_opening_paren ... ok
[INFO] [stdout] test extensions::simple::tests::test_resolve_function_not_found ... ok
[INFO] [stdout] test extensions::simple::tests::test_resolve_function_with_anchor ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_expression_with_boolean ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_expression_with_whitespace ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_throw_exception_failure_behavior ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_field_reference ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_date_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_unspecified_failure_behavior ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_mixed_types_in_conditions ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_with_date_literals ... ok
[INFO] [stdout] test parser::expressions::tests::test_compound_name_with_signature ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_clause_with_whitespace_variations ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_with_time_literals ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_integer_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_single_clause ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_with_typed_literals ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_float_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_if_then_with_timestamp_literals ... ok
[INFO] [stdout] test parser::expressions::tests::test_compound_name_plain ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_float_literal_with_fp32_type ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_negative_float_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_nullable_float_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_nullable_integer_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_time_literal ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_timestamp_literal_with_t ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_timestamp_literal_with_space ... ok
[INFO] [stdout] test parser::extensions::tests::test_parse_simple_extension_declaration ... ok
[INFO] [stdout] test parser::extensions::tests::test_parse_simple_extension_declaration_compound_name_multi_segment ... ok
[INFO] [stdout] test parser::extensions::tests::test_extensions_round_trip_plan_with_compound_names ... ok
[INFO] [stdout] test parser::extensions::tests::test_parse_simple_extension_declaration_compound_name ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_base_name_unique_overload ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_base_name_with_anchor ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_second_overload ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_wrong_name_for_anchor_fails ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_full_compound_name ... ok
[INFO] [stdout] test parser::extensions::tests::test_extensions_round_trip_plan ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_cast_return_null_failure_behavior ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_compound_name_with_anchor ... ok
[INFO] [stdout] test parser::extensions::tests::test_parse_urn_extension_declaration ... ok
[INFO] [stdout] test parser::expressions::tests::test_scalar_function_base_name_ambiguous_fails ... ok
[INFO] [stdout] test parser::expressions::tests::test_parse_nullable_boolean_literal ... ok
[INFO] [stdout] test parser::relations::tests::test_fetch_relation_negative_limit_rejected ... ok
[INFO] [stdout] test parser::extensions::tests::test_parse_urn_extension_declaration_str ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_aggregate_relation ... ok
[INFO] [stdout] test parser::relations::tests::test_fetch_relation_negative_offset_rejected ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_join_relation_requires_two_children ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_aggregate_relation_global_aggregate ... ok
[INFO] [stdout] test parser::relations::tests::test_fetch_relation_positive_values ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_filter_relation ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_aggregate_relation_grouping_sets ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_join_relation_left_outer ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_project_relation_complex ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_join_relation ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_read_relation ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_aggregate_relation_maintain_column_order ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_project_relation ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_relation ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_basic_block ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_aggregate_relation_simple ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_root_relation ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_complete_extension_block ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_full_plan ... ok
[INFO] [stdout] test parser::types::tests::test_parse_list_type ... ok
[INFO] [stdout] test parser::relations::tests::test_parse_join_relation_left_semi ... ok
[INFO] [stdout] test parser::types::tests::test_parse_type ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_invalid_failure_behavior ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify ... ok
[INFO] [stdout] test parser::types::tests::test_parse_parameters ... ok
[INFO] [stdout] test parser::types::tests::test_parse_simple_type ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_missing_input ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_missing_type ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_nested ... ok
[INFO] [stdout] test parser::types::tests::test_udts ... ok
[INFO] [stdout] test textify::expressions::tests::test_ifthen_textify_missing_else ... ok
[INFO] [stdout] test textify::expressions::tests::test_nullable_boolean_literal_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_expression_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_literal_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_nullable_float_literal_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_nullable_integer_literal_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_return_null ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_throw_exception ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_relation_tree ... ok
[INFO] [stdout] test textify::expressions::tests::test_ifthen_textify ... ok
[INFO] [stdout] test textify::expressions::tests::test_cast_textify_via_rextype ... ok
[INFO] [stdout] test textify::expressions::tests::test_rextype_textify ... ok
[INFO] [stdout] test textify::rels::tests::test_arguments_textify_both ... ok
[INFO] [stdout] test parser::structural::tests::test_parse_root_relation_no_names ... ok
[INFO] [stdout] test textify::rels::tests::test_arguments_textify_named_only ... ok
[INFO] [stdout] test textify::rels::tests::test_arguments_textify_empty ... ok
[INFO] [stdout] test textify::plan::tests::test_plan_writer ... ok
[INFO] [stdout] test textify::rels::tests::test_aggregate_function_textify ... ok
[INFO] [stdout] test textify::rels::tests::test_arguments_textify_positional_only ... ok
[INFO] [stdout] test textify::rels::tests::test_join_output_columns ... ok
[INFO] [stdout] test textify::rels::tests::test_aggregate_relation_textify ... ok
[INFO] [stdout] test textify::rels::tests::test_filter_rel ... ok
[INFO] [stdout] test textify::rels::tests::test_join_type_enum_textify ... ok
[INFO] [stdout] test textify::rels::tests::test_multiple_groupings_on_aggregate ... ok
[INFO] [stdout] test textify::rels::tests::test_join_relation_unknown_type ... ok
[INFO] [stdout] test textify::rels::tests::test_multiple_groupings_with_measure_deprecated ... ok
[INFO] [stdout] test textify::rels::tests::test_multiple_groupings_on_aggregate_deprecated ... ok
[INFO] [stdout] test textify::rels::tests::test_unsupported_rel_type_produces_failure_token ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_compact_overloaded_shows_signature ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_compact_plain_name_non_unique_shows_anchor ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_compact_plain_name_unique_no_signature_no_anchor ... ok
[INFO] [stdout] test textify::rels::tests::test_read_rel ... ok
[INFO] [stdout] test textify::rels::tests::test_named_arg_textify_error_token ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_verbose_unique_base_name_shows_signature_and_anchor ... ok
[INFO] [stdout] test textify::types::tests::names_display ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_compact_unique_base_name_no_signature ... ok
[INFO] [stdout] test textify::types::tests::struct_display ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_verbose_overloaded_shows_signature_and_anchor ... ok
[INFO] [stdout] test textify::types::tests::type_display ... ok
[INFO] [stdout] test textify::types::tests::type_display_with_errors ... ok
[INFO] [stdout] test textify::types::tests::named_anchor_compact_same_compound_name_two_urns_shows_anchor ... ok
[INFO] [stdout] test extensions::examples::tests::from_args_rejects_non_enum_positional ... ok
[INFO] [stdout] test extensions::examples::tests::from_args_empty_strategies_roundtrip ... ok
[INFO] [stdout] test cli::tests::test_convert_json_to_text ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 176 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/substrait_explain-5a47b26cafa8bc81)
[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] [stderr]      Running tests/adv_extension_roundtrip.rs (/opt/rustwide/target/debug/deps/adv_extension_roundtrip-41bcd055282b5ff8)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test_adv_ext_output_columns_produces_failure_token ... ok
[INFO] [stdout] test test_enhancement_depth_three_nesting_roundtrip ... ok
[INFO] [stdout] test test_enhancement_and_optimization_on_nested_relation_roundtrip ... ok
[INFO] [stdout] test test_enhancement_on_join_roundtrip ... ok
[INFO] [stdout] test test_enhancement_on_filter_roundtrip ... ok
[INFO] [stdout] test test_multiple_enhancements_fails_to_parse ... ok
[INFO] [stdout] test test_enhancement_on_nested_filter_roundtrip ... ok
[INFO] [stdout] test test_enhancement_on_project_roundtrip ... ok
[INFO] [stdout] test test_multiple_optimizations_on_nested_read_roundtrip ... ok
[INFO] [stdout] test test_partition_hint_roundtrip ... ok
[INFO] [stdout] test test_enhancement_on_sort_roundtrip ... ok
[INFO] [stdout] test test_partition_strategy_str_names_roundtrip ... ok
[INFO] [stdout] test test_enhancement_with_child_relation ... ok
[INFO] [stdout] test test_partition_hint_with_count ... ok
[INFO] [stdout] test test_enhancement_on_fetch_roundtrip ... ok
[INFO] [stdout] test test_partition_hint_all_strategies ... ok
[INFO] [stdout] test test_multiple_optimizations_roundtrip ... ok
[INFO] [stdout] test test_partition_hint_empty ... ok
[INFO] [stdout] test test_optimization_on_nested_read_roundtrip ... ok
[INFO] [stdout] test test_adv_extension_as_standalone_root_fails_with_error ... ok
[INFO] [stdout] test test_enhancement_on_nested_read_roundtrip ... ok
[INFO] [stdout] test test_enhancement_on_aggregate_roundtrip ... ok
[INFO] [stdout] test test_project_over_extension_leaf_emit_mapping ... ok
[INFO] [stdout] test test_unknown_enhancement_url_textify_is_lenient ... ok
[INFO] [stdout] test test_unregistered_enhancement_fails_to_parse ... ok
[INFO] [stdout] test test_unregistered_enhancement_produces_failure_token_at_textify_time ... ok
[INFO] [stdout] test test_unregistered_optimization_fails_to_parse ... ok
[INFO] [stdout] test test_wrong_argument_type_for_enhancement_fails ... ok
[INFO] [stdout] test test_adv_extension_as_roots_only_child_fails_with_error ... ok
[INFO] [stdout] test test_enhancement_and_optimization_on_same_relation ... ok
[INFO] [stdout] test test_enhancement_attaches_to_filter_not_read ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extension_roundtrip.rs (/opt/rustwide/target/debug/deps/extension_roundtrip-97cf4c33c3f81bc5)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/json_parsing.rs (/opt/rustwide/target/debug/deps/json_parsing-5ee309859fb5600c)
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_extension_leaf_unknown_type_url_textify_is_lenient ... ok
[INFO] [stdout] test test_enhancement_on_read_under_extension_single ... ok
[INFO] [stdout] test test_extension_multi_roundtrip ... ok
[INFO] [stdout] test test_extension_empty_args_roundtrip ... ok
[INFO] [stdout] test test_extension_unknown_arguments ... ok
[INFO] [stdout] test test_extension_leaf_roundtrip ... ok
[INFO] [stdout] test test_extension_string_escaping_roundtrip ... ok
[INFO] [stdout] test test_extension_without_registry ... ok
[INFO] [stdout] test test_extension_single_over_read_roundtrip ... ok
[INFO] [stdout] test test_extension_literal_roundtrip ... ok
[INFO] [stdout] test test_multiple_extensions_in_plan ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_text_path ... ok
[INFO] [stdout] test test_cli_parses_gojson ... ok
[INFO] [stdout] test test_cli_parses_rustjson ... ok
[INFO] [stdout] test test_gojson_parsing ... ok
[INFO] [stdout] test test_failed_parse_without_schema_on_gojson ... ok
[INFO] [stdout] test test_protojson_fixture_matches_text_plan ... ok
[INFO] [stdout] test test_cli_parses_standard_plan_json ... ok
[INFO] [stdout] test test_parse_rustjson ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/literal_roundtrip.rs (/opt/rustwide/target/debug/deps/literal_roundtrip-40c7ee514b2027a7)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_if_then_expression_roundtrip ... ok
[INFO] [stdout] test test_mixed_literal_roundtrip ... ok
[INFO] [stdout] test test_nullable_boolean_literal_roundtrip ... ok
[INFO] [stdout] test test_boolean_literal_roundtrip ... ok
[INFO] [stdout] test test_date_literal_roundtrip ... ok
[INFO] [stdout] test test_nullable_float_literal_roundtrip ... ok
[INFO] [stdout] test test_float_literal_roundtrip ... ok
[INFO] [stdout] test test_negative_literals_roundtrip ... ok
[INFO] [stdout] test test_nullable_integer_literal_roundtrip ... ok
[INFO] [stdout] test test_timestamp_literal_roundtrip ... ok
[INFO] [stdout] test test_time_literal_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/plan_roundtrip.rs (/opt/rustwide/target/debug/deps/plan_roundtrip-bbc199f1dd4d3641)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test test_complex_plan_roundtrip ... ok
[INFO] [stdout] test test_aggregate_multiple_grouping_sets_roundtrip ... ok
[INFO] [stdout] test test_aggregate_relation_roundtrip ... ok
[INFO] [stdout] test test_global_aggregate_relation_roundtrip ... ok
[INFO] [stdout] test test_fetch_relation_roundtrip ... ok
[INFO] [stdout] test test_join_relation_right_mark_roundtrip ... ok
[INFO] [stdout] test test_join_relation_semi_types_roundtrip ... ok
[INFO] [stdout] test test_join_relation_roundtrip ... ok
[INFO] [stdout] test test_multiple_relations_roundtrip ... ok
[INFO] [stdout] test test_plan_with_fetch_and_sort_roundtrip ... ok
[INFO] [stdout] test test_mixed_unique_and_overloaded_functions ... ok
[INFO] [stdout] test test_plan_with_extension_leaf_roundtrip ... ok
[INFO] [stdout] test test_sort_relation_roundtrip ... ok
[INFO] [stdout] test test_same_compound_name_two_urns_requires_anchor ... ok
[INFO] [stdout] test test_plan_with_verbose_and_simple_output ... ok
[INFO] [stdout] test test_unregistered_extension_error ... ok
[INFO] [stdout] test test_simple_plan_roundtrip ... ok
[INFO] [stdout] test test_unique_function_compact_omits_signature ... ok
[INFO] [stdout] test test_malformed_input_error_handling ... ok
[INFO] [stdout] test test_overloaded_functions_verbose_shows_signature_and_anchor ... ok
[INFO] [stdout] test test_plan_with_extensions_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/types.rs (/opt/rustwide/target/debug/deps/types-0089c9b583094d03)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_types ... ok
[INFO] [stdout] test test_expression ... ok
[INFO] [stdout] test test_verbose_and_simple_output ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests substrait_explain
[INFO] [stdout] 
[INFO] [stdout] running 33 tests
[INFO] [stdout] test src/../API.md - (line 133) - compile ... ok
[INFO] [stdout] test src/../API.md - (line 54) ... ok
[INFO] [stdout] test src/../API.md - (line 24) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 513) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 128) ... ok
[INFO] [stdout] test src/../API.md - (line 222) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 304) ... ok
[INFO] [stdout] test src/cli.rs - cli::Cli::run_with_extensions (line 46) ... ignored
[INFO] [stdout] test src/json.rs - json (line 17) ... ignored
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 486) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 407) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 351) ... ok
[INFO] [stdout] test src/extensions/registry.rs - extensions::registry (line 25) ... ok
[INFO] [stdout] test src/../API.md - (line 80) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 543) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 576) ... ok
[INFO] [stdout] test src/lib.rs - format (line 78) ... ok
[INFO] [stdout] test src/extensions/examples.rs - extensions::examples::PartitionHint (line 70) ... ok
[INFO] [stdout] test src/lib.rs - format_with_registry (line 152) ... ignored
[INFO] [stdout] test src/textify/foundation.rs - textify::foundation::Scope::separated (line 501) ... ignored
[INFO] [stdout] test src/parser/structural.rs - parser::structural::Parser (line 712) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 606) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 66) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 252) ... ok
[INFO] [stdout] test src/lib.rs - parse (line 51) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 667) ... ok
[INFO] [stdout] test src/lib.rs - format_with_options (line 113) ... ok
[INFO] [stdout] test src/parser/structural.rs - parser::structural::Parser (line 751) ... ok
[INFO] [stdout] test src/lib.rs - parse (line 32) ... ok
[INFO] [stdout] test src/parser/structural.rs - parser::structural::Parser<'a>::parse (line 837) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 858) ... ok
[INFO] [stdout] test src/parser/structural.rs - parser::structural::Parser (line 781) ... ok
[INFO] [stdout] test src/../GRAMMAR.md - grammar (line 788) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.43s; merged doctests compilation took 1.33s
[INFO] running `Command { std: "docker" "inspect" "da321f5946565aed2d071435642448afe49d79de426db40952c757bf950270e2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "da321f5946565aed2d071435642448afe49d79de426db40952c757bf950270e2", kill_on_drop: false }`
[INFO] [stdout] da321f5946565aed2d071435642448afe49d79de426db40952c757bf950270e2
