[INFO] fetching crate dataspec 0.3.11...
[INFO] testing dataspec-0.3.11 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate dataspec 0.3.11 into /workspace/builds/worker-4-tc1/source
[INFO] started tweaking crates.io crate dataspec 0.3.11
[INFO] finished tweaking crates.io crate dataspec 0.3.11
[INFO] tweaked toml for crates.io crate dataspec 0.3.11 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate dataspec 0.3.11 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 dataspec 0.3.11 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] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded postgres-protocol v0.6.12
[INFO] [stderr]   Downloaded hmac v0.13.0
[INFO] [stderr]   Downloaded tokio-postgres v0.7.18
[INFO] [stderr]   Downloaded pulldown-cmark-escape v0.11.0
[INFO] [stderr]   Downloaded log v0.3.9
[INFO] [stderr]   Downloaded time-macros v0.2.29
[INFO] [stderr]   Downloaded mustache v0.9.0
[INFO] [stderr]   Downloaded postgres-types v0.2.14
[INFO] [stderr]   Downloaded stacker v0.1.24
[INFO] [stderr]   Downloaded hyper-rustls v0.25.0
[INFO] [stderr]   Downloaded tokio-rustls v0.25.0
[INFO] [stderr]   Downloaded yup-oauth2 v9.0.0
[INFO] [stderr]   Downloaded rustls-native-certs v0.7.3
[INFO] [stderr]   Downloaded gcp-bigquery-client v0.20.0
[INFO] [stderr]   Downloaded pulldown-cmark v0.13.4
[INFO] [stderr]   Downloaded rustls-webpki v0.102.8
[INFO] [stderr]   Downloaded sqlparser v0.59.0
[INFO] [stderr]   Downloaded rustls v0.22.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd
[INFO] running `Command { std: "docker" "start" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", 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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd" "/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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", 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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling object v0.37.3
[INFO] [stderr]    Compiling memchr v2.8.2
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling log v0.4.32
[INFO] [stderr]    Compiling cc v1.2.64
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling pulldown-cmark v0.13.4
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling socket2 v0.6.4
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling getopts v0.2.24
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling bytes v1.12.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling unicase v2.9.0
[INFO] [stderr]    Compiling pulldown-cmark-escape v0.11.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling uuid v1.23.3
[INFO] [stderr]    Compiling stacker v0.1.24
[INFO] [stderr]    Compiling ar_archive_writer v0.5.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling recursive-proc-macro-impl v0.1.1
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling psm v0.1.31
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling recursive v0.1.1
[INFO] [stderr]    Compiling sqlparser v0.59.0
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling mustache v0.9.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling dataspec v0.3.11 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 34s
[INFO] running `Command { std: "docker" "inspect" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", 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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd" "/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 bitflags v2.13.0
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling pulldown-cmark v0.13.4
[INFO] [stderr]    Compiling dataspec v0.3.11 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 25.24s
[INFO] running `Command { std: "docker" "inspect" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", 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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd" "/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.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/dataspec-e383ed2d61723b2b)
[INFO] [stdout] 
[INFO] [stdout] running 70 tests
[INFO] [stdout] test build::compile::tests::compile_hook_with_template_wrapping_column_insert_renders_commas_between_columns ... ok
[INFO] [stdout] test build::ident::tests::rejects_invalid_names ... ok
[INFO] [stdout] test build::ident::tests::sanitizes_hyphens ... ok
[INFO] [stdout] test build::compile::tests::compile_hook_with_column_insert_renders_commas_between_columns ... ok
[INFO] [stdout] test build::compile::tests::compile_transformation_template_renders_model_context ... ok
[INFO] [stdout] test build::compile::tests::compile_leaves_mention_props_in_template_definition ... ok
[INFO] [stdout] test build::emit::tests::includes_test_usage_import_when_needed ... ok
[INFO] [stdout] test build::compile::tests::compile_hook_with_template_renders_model_handler ... ok
[INFO] [stdout] test build::compile::tests::compile_applies_template_mention_props_to_wrapper_only ... ok
[INFO] [stdout] test build::compile::tests::compile_hook_usage_sql_with_model_context ... ok
[INFO] [stdout] test build::compile::tests::compile_preserves_caller_props_when_template_nests_template ... ok
[INFO] [stdout] test build::tests::rejects_multiple_config_files ... ok
[INFO] [stdout] test build::tests::rejects_duplicate_model_names ... ok
[INFO] [stdout] test build::tests::generated_catalog_registers_entities ... ok
[INFO] [stdout] test build::compile::tests::compile_column_test_usage_sets_tested_column_in_sql ... ok
[INFO] [stdout] test build::compile::tests::compile_applies_template_mention_props ... ok
[INFO] [stdout] test build::ident::tests::accepts_valid_names ... ok
[INFO] [stdout] test build::emit::tests::omits_unused_usage_imports ... ok
[INFO] [stdout] test build::compile::tests::compile_renders_props_in_sql ... ok
[INFO] [stdout] test build::compile::tests::compile_leaves_props_code_in_template_definition ... ok
[INFO] [stdout] test context::render::tests::disable_html_escaping_leaves_existing_triple_stache_untouched ... ok
[INFO] [stdout] test context::render::tests::render_compile_deferred_preserve_model_keeps_model_tags_when_props_resolve ... ok
[INFO] [stdout] test context::render::tests::render_compile_deferred_preserves_last_inside_model_columns ... ok
[INFO] [stdout] test context::render::tests::render_compile_leaves_runtime_vars_untouched ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_sets_last_on_final_column ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_insert_template_renders_commas_between_columns ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_sets_last_on_final_label ... ok
[INFO] [stdout] test context::render::tests::render_runtime_resolves_handler_tag_from_compile_output ... ok
[INFO] [stdout] test context::render::tests::render_compile_panics_on_missing_prop - should panic ... ok
[INFO] [stdout] test context::render::tests::render_runtime_supports_var_prefix_alias ... ok
[INFO] [stdout] test context::render::tests::render_runtime_leaves_props_untouched ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_leaves_runtime_tags ... ok
[INFO] [stdout] test context::render::tests::render_compile_substitutes_props_only ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_expands_columns_section ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_sets_last_on_final_tag ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_includes_tested_column_for_column_tests ... ok
[INFO] [stdout] test context::render::tests::render_runtime_panics_on_missing_var - should panic ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_sets_last_on_final_meta_entry ... ok
[INFO] [stdout] test context::render::tests::render_compile_with_model_substitutes_props_and_model_fields ... ok
[INFO] [stdout] test context::render::tests::render_runtime_falls_back_to_env_for_vars ... ok
[INFO] [stdout] test context::render::tests::render_runtime_step_substitutes_hook_props ... ok
[INFO] [stdout] test context::render::tests::render_runtime_substitutes_vars_session_id_and_model ... ok
[INFO] [stdout] test handler::tests::build_transform_plan_includes_init_hooks_when_requested ... ok
[INFO] [stdout] test build::tests::spec_builder_generates_expected_output ... ok
[INFO] [stdout] test handler::tests::get_tests_for_model_includes_column_tests ... ok
[INFO] [stdout] test build::compile::tests::compile_fills_dependent_tables_after_render ... ok
[INFO] [stdout] test parser::markdown::tests::parses_heading_tree ... ok
[INFO] [stdout] test parser::tests::parses_config ... ok
[INFO] [stdout] test parser::tests::parses_dummy_transformation ... ok
[INFO] [stdout] test engines::common::tests::evaluate_test_status_positive_rows_is_failed ... ok
[INFO] [stdout] test context::render::tests::render_runtime_does_not_html_escape_quotes ... ok
[INFO] [stdout] test engines::common::tests::evaluate_test_status_zero_rows_is_success ... ok
[INFO] [stdout] test handler::tests::build_test_plan_collects_model_tests ... ok
[INFO] [stdout] test handler::tests::build_transform_plan_includes_hook_steps ... ok
[INFO] [stdout] test handler::tests::parsed_transformation_file_has_hooks ... ok
[INFO] [stdout] test parser::tests::parses_embedded_model_hooks ... ok
[INFO] [stdout] test parser::tests::parses_dummy_model ... ok
[INFO] [stdout] test sql::tests::ignores_props_vars_and_session_id_mustache_tags ... ok
[INFO] [stdout] test sql::tests::mustache_refs_prevent_sql_parse_of_mixed_query ... ok
[INFO] [stdout] test handler::tests::build_apply_plan_resolves_operations_by_name ... ok
[INFO] [stdout] test handler::tests::parsed_transformation_file_has_tests ... ok
[INFO] [stdout] test parser::extract::tests::parses_link_with_anchor ... ok
[INFO] [stdout] test parser::extract::tests::parses_tests_section_body_from_dummy_model_v1 ... ok
[INFO] [stdout] test sql::tests::excludes_cte_aliases ... ok
[INFO] [stdout] test sql::tests::extracts_mustache_model_refs ... ok
[INFO] [stdout] test sql::tests::extracts_tables_from_select_join ... ok
[INFO] [stdout] test sql::tests::merges_sql_tables_and_mustache_refs_when_parseable ... ok
[INFO] [stdout] test parser::extract::tests::parses_multiline_markdown_link ... ok
[INFO] [stdout] test parser::extract::tests::parses_props_table ... ok
[INFO] [stdout] test parser::tests::parses_transformation_tests_from_path ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 70 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/dataspec-a275538cf60148d3)
[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]    Doc-tests dataspec
[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" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd", kill_on_drop: false }`
[INFO] [stdout] 31c9ba29a63dba64a5bef3c8237578a4573b4a7a414ee24526d3f64317e7dffd
