[INFO] fetching crate snazy 0.59.0...
[INFO] testing snazy-0.59.0 against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739-1
[INFO] extracting crate snazy 0.59.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate snazy 0.59.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate snazy 0.59.0
[INFO] tweaked toml for crates.io crate snazy 0.59.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate snazy 0.59.0 on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate snazy 0.59.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" "+33835004928d3bf65db4d4712e1330766263b0bd" "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" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e46b656f2af2c0292cf4df3eb23714ae98ee0799f4fcda6f65bfde00f994e1af
[INFO] running `Command { std: "docker" "start" "-a" "e46b656f2af2c0292cf4df3eb23714ae98ee0799f4fcda6f65bfde00f994e1af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e46b656f2af2c0292cf4df3eb23714ae98ee0799f4fcda6f65bfde00f994e1af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e46b656f2af2c0292cf4df3eb23714ae98ee0799f4fcda6f65bfde00f994e1af", kill_on_drop: false }`
[INFO] [stdout] e46b656f2af2c0292cf4df3eb23714ae98ee0799f4fcda6f65bfde00f994e1af
[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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0f8afd0d4aaebe04e46b9767054d57e9b556483af75d9fd0f0e948f2bd4652aa
[INFO] running `Command { std: "docker" "start" "-a" "0f8afd0d4aaebe04e46b9767054d57e9b556483af75d9fd0f0e948f2bd4652aa", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling phf_shared v0.12.1
[INFO] [stderr]    Compiling chrono-tz v0.10.4
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling phf v0.12.1
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling color-print-proc-macro v0.3.7
[INFO] [stderr]    Compiling color-print v0.3.7
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling clap_complete v4.6.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling snazy v0.59.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 42.15s
[INFO] running `Command { std: "docker" "inspect" "0f8afd0d4aaebe04e46b9767054d57e9b556483af75d9fd0f0e948f2bd4652aa", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0f8afd0d4aaebe04e46b9767054d57e9b556483af75d9fd0f0e948f2bd4652aa", kill_on_drop: false }`
[INFO] [stdout] 0f8afd0d4aaebe04e46b9767054d57e9b556483af75d9fd0f0e948f2bd4652aa
[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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bd197e0aa55302ac820776e605c6d8a06f3e1df47260e5cd827e2df354efaac4
[INFO] running `Command { std: "docker" "start" "-a" "bd197e0aa55302ac820776e605c6d8a06f3e1df47260e5cd827e2df354efaac4", kill_on_drop: false }`
[INFO] [stderr]    Compiling snazy v0.59.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.80s
[INFO] running `Command { std: "docker" "inspect" "bd197e0aa55302ac820776e605c6d8a06f3e1df47260e5cd827e2df354efaac4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bd197e0aa55302ac820776e605c6d8a06f3e1df47260e5cd827e2df354efaac4", kill_on_drop: false }`
[INFO] [stdout] bd197e0aa55302ac820776e605c6d8a06f3e1df47260e5cd827e2df354efaac4
[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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 09d35d5aa442180415cdc1f65982018669cbfe815c24b69e1ff4b0f5d0b15831
[INFO] running `Command { std: "docker" "start" "-a" "09d35d5aa442180415cdc1f65982018669cbfe815c24b69e1ff4b0f5d0b15831", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/snazy-7cf60010ac5c1841)
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test input::tests::duplicate_collapser_flushes_single_lines_unchanged ... ok
[INFO] [stdout] test input::tests::duplicate_collapser_flushes_before_non_collapsible_output ... ok
[INFO] [stdout] test input::tests::duplicate_collapser_starts_new_burst_after_window_expires ... ok
[INFO] [stdout] test input::tests::duplicate_collapser_summarizes_duplicates_within_window ... ok
[INFO] [stdout] test input::tests::read_a_file_handles_missing_and_empty_files ... ok
[INFO] [stdout] test parser::tests::applies_kail_prefix_template ... ok
[INFO] [stdout] test parser::tests::does_not_treat_generic_request_logs_as_caddy ... ok
[INFO] [stdout] test parser::tests::parses_caddy_access_logs ... ok
[INFO] [stdout] test parser::tests::parses_cloud_logging_logs ... ok
[INFO] [stdout] test input::tests::process_reader_flushes_each_rendered_line_for_stdin ... ok
[INFO] [stdout] test parser::tests::parses_custom_json_float_timestamp ... ok
[INFO] [stdout] test parser::tests::parses_custom_json_keys ... ok
[INFO] [stdout] test parser::tests::kubectl_events_are_detected ... ok
[INFO] [stdout] test parser::tests::parses_ecs_logs ... ok
[INFO] [stdout] test parser::tests::parses_zerolog_logs ... ok
[INFO] [stdout] test parser::tests::parses_logrus_logs ... ok
[INFO] [stdout] test parser::tests::normalizes_cloud_logging_critical_to_error ... ok
[INFO] [stdout] test parser::tests::parses_pac_logs ... ok
[INFO] [stdout] test parser::tests::parses_knative_logs ... ok
[INFO] [stdout] test pipeline::tests::extra_fields_skip_consumed_nested_objects ... ok
[INFO] [stdout] test parser::tests::skips_missing_custom_json_keys ... ok
[INFO] [stdout] test input::tests::read_a_file_formats_logs ... ok
[INFO] [stdout] test input::tests::stacktrace_output_can_be_hidden ... ok
[INFO] [stdout] test pipeline::tests::action_command_is_triggered ... ok
[INFO] [stdout] test pipeline::tests::extra_fields_skip_consumed_top_level_fields ... ok
[INFO] [stdout] test pipeline::tests::include_fields_support_nested_paths ... ok
[INFO] [stdout] test render::tests::colorize_object_type_assigns_color_codes ... ok
[INFO] [stdout] test render::tests::format_stack_line_respects_coloring_toggle ... ok
[INFO] [stdout] test render::tests::render_structured_log_appends_extra_fields ... ok
[INFO] [stdout] test render::tests::render_structured_log_renders_delta_slot ... ok
[INFO] [stdout] test render::tests::render_parsed_line_resets_time_delta_after_unparsable_timestamp ... ok
[INFO] [stdout] test utils::tests::test_convert_ts_float_or_str_non_float ... ok
[INFO] [stdout] test utils::tests::test_convert_ts_float_or_str ... ok
[INFO] [stdout] test utils::tests::test_convert_ts_float_or_str_keeps_subseconds ... ok
[INFO] [stdout] test utils::tests::test_convert_ts_float_or_str_with_timezone ... ok
[INFO] [stdout] test utils::tests::test_format_time_delta_compact_units ... ok
[INFO] [stdout] test utils::tests::test_apply_regexps ... ok
[INFO] [stdout] test utils::tests::test_level_symbols ... ok
[INFO] [stdout] test utils::tests::test_providers ... ok
[INFO] [stderr]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-b733f5e8dd9aba34)
[INFO] [stdout] test utils::tests::test_apply_regexps_invalid_regex ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test cloud_logging_critical_matches_error_filter ... ok
[INFO] [stdout] test custom_level_debug ... ok
[INFO] [stdout] test caddy_access_log_post ... ok
[INFO] [stdout] test caddy_access_log_basic ... ok
[INFO] [stdout] test caddy_does_not_override_generic_request_logs ... ok
[INFO] [stdout] test custom_level_info ... ok
[INFO] [stdout] test cloud_logging_autodetect ... ok
[INFO] [stdout] test extra_fields_all ... ok
[INFO] [stdout] test all_json_keys_need_tobe_specified - should panic ... ok
[INFO] [stdout] test ecs_autodetect ... ok
[INFO] [stdout] test duplicate_structured_logs_collapse_into_summary ... ok
[INFO] [stdout] test duplicate_raw_logs_collapse_into_summary ... ok
[INFO] [stdout] test filter_level_info ... ok
[INFO] [stdout] test floated_date ... ok
[INFO] [stdout] test filter_level_warning ... ok
[INFO] [stdout] test include_fields_kail_missing_fields ... ok
[INFO] [stdout] test caddy_general_log ... ok
[INFO] [stdout] test include_fields_kail_nested_fields ... ok
[INFO] [stdout] test include_fields_kail_with_colors ... ok
[INFO] [stdout] test include_fields_subset ... ok
[INFO] [stdout] test duplicate_collapse_keeps_time_delta_based_on_hidden_duplicates ... ok
[INFO] [stdout] test kail_log_and_regexp ... ok
[INFO] [stdout] test include_fields_kail_basic ... ok
[INFO] [stdout] test include_fields_kail_all_fields ... ok
[INFO] [stdout] test include_fields_kail_no_prefix ... ok
[INFO] [stdout] test kail_no_prefix ... ok
[INFO] [stdout] test multiple_regexp_raw_json ... ok
[INFO] [stdout] test pac_output_fallback_ts ... ok
[INFO] [stdout] test level_symbols ... ok
[INFO] [stdout] test pac_output_github ... ok
[INFO] [stdout] test kail_custom_format ... ok
[INFO] [stdout] test raw_non_json ... ok
[INFO] [stdout] test logrus_autodetect ... ok
[INFO] [stdout] test pac_output_bitbucket_server ... ok
[INFO] [stdout] test regexp_color_fg_bg ... ok
[INFO] [stdout] test logrus_extra_fields_skip_consumed_time ... ok
[INFO] [stdout] test regexp_rgb_colored ... ok
[INFO] [stdout] test pac_output_bitbucket_cloud ... ok
[INFO] [stdout] test pac_output_gitlab ... ok
[INFO] [stdout] test regexp_raw_json ... ok
[INFO] [stdout] test show_help ... ok
[INFO] [stdout] test simple_date ... ok
[INFO] [stdout] test simple_parsing ... ok
[INFO] [stdout] test stacktrace_hidden ... ok
[INFO] [stdout] test time_delta_between_timestamped_logs ... ok
[INFO] [stdout] test stacktrace_default_display ... ok
[INFO] [stdout] test skip_lines ... ok
[INFO] [stdout] test timezone_parsing ... ok
[INFO] [stdout] test zerolog_autodetect ... ok
[INFO] [stdout] test zerolog_extra_fields_skip_consumed_stack ... ok
[INFO] [stdout] test time_delta_disabled_keeps_existing_output ... ok
[INFO] [stdout] test time_delta_resets_after_unparsable_timestamp ... ok
[INFO] [stdout] test time_delta_ignores_raw_and_missing_timestamps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.27s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "09d35d5aa442180415cdc1f65982018669cbfe815c24b69e1ff4b0f5d0b15831", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "09d35d5aa442180415cdc1f65982018669cbfe815c24b69e1ff4b0f5d0b15831", kill_on_drop: false }`
[INFO] [stdout] 09d35d5aa442180415cdc1f65982018669cbfe815c24b69e1ff4b0f5d0b15831
