[INFO] fetching crate logform 0.5.1... [INFO] testing logform-0.5.1 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] extracting crate logform 0.5.1 into /workspace/builds/worker-1-tc1/source [INFO] started tweaking crates.io crate logform 0.5.1 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate logform 0.5.1 [INFO] tweaked toml for crates.io crate logform 0.5.1 written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate logform 0.5.1 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate logform 0.5.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" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 44b05ab25d9849c26f172e09532108dc96ba5656c71f83c3306e85e218e3721d [INFO] running `Command { std: "docker" "start" "-a" "44b05ab25d9849c26f172e09532108dc96ba5656c71f83c3306e85e218e3721d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "44b05ab25d9849c26f172e09532108dc96ba5656c71f83c3306e85e218e3721d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "44b05ab25d9849c26f172e09532108dc96ba5656c71f83c3306e85e218e3721d", kill_on_drop: false }` [INFO] [stdout] 44b05ab25d9849c26f172e09532108dc96ba5656c71f83c3306e85e218e3721d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ce89fb6a77c7f45e37646a72308e024cdcd0f4c9966994c25cea0ca06bb5df6a [INFO] running `Command { std: "docker" "start" "-a" "ce89fb6a77c7f45e37646a72308e024cdcd0f4c9966994c25cea0ca06bb5df6a", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.217 [INFO] [stderr] Compiling serde_json v1.0.125 [INFO] [stderr] Compiling iana-time-zone v0.1.60 [INFO] [stderr] Compiling colored v2.1.0 [INFO] [stderr] Compiling regex v1.10.6 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling logform v0.5.1 (/opt/rustwide/workdir) [INFO] [stdout] warning: struct `UpperCase` is never constructed [INFO] [stdout] --> src/formats/format.rs:61:8 [INFO] [stdout] | [INFO] [stdout] 61 | struct UpperCase; [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `ReverseFormat` is never constructed [INFO] [stdout] --> src/formats/format.rs:74:8 [INFO] [stdout] | [INFO] [stdout] 74 | struct ReverseFormat; [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `AddSuffix` is never constructed [INFO] [stdout] --> src/formats/format.rs:84:8 [INFO] [stdout] | [INFO] [stdout] 84 | struct AddSuffix(String); [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.02s [INFO] running `Command { std: "docker" "inspect" "ce89fb6a77c7f45e37646a72308e024cdcd0f4c9966994c25cea0ca06bb5df6a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ce89fb6a77c7f45e37646a72308e024cdcd0f4c9966994c25cea0ca06bb5df6a", kill_on_drop: false }` [INFO] [stdout] ce89fb6a77c7f45e37646a72308e024cdcd0f4c9966994c25cea0ca06bb5df6a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ec2445b0849917512a38fc46664811569f346bc337ea3d3686caed1558ed9164 [INFO] running `Command { std: "docker" "start" "-a" "ec2445b0849917512a38fc46664811569f346bc337ea3d3686caed1558ed9164", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.217 [INFO] [stderr] Compiling ciborium-io v0.2.2 [INFO] [stderr] Compiling half v2.4.1 [INFO] [stderr] Compiling quote v1.0.37 [INFO] [stderr] Compiling clap_builder v4.5.31 [INFO] [stderr] Compiling anes v0.1.6 [INFO] [stderr] Compiling oorandom v11.1.4 [INFO] [stderr] Compiling criterion-plot v0.5.0 [INFO] [stderr] Compiling is-terminal v0.4.15 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling syn v2.0.87 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling clap v4.5.31 [INFO] [stderr] Compiling serde_derive v1.0.217 [INFO] [stderr] Compiling serde_json v1.0.125 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling logform v0.5.1 (/opt/rustwide/workdir) [INFO] [stderr] Compiling criterion v0.5.1 [INFO] [stdout] warning: struct `UpperCase` is never constructed [INFO] [stdout] --> src/formats/format.rs:61:8 [INFO] [stdout] | [INFO] [stdout] 61 | struct UpperCase; [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `ReverseFormat` is never constructed [INFO] [stdout] --> src/formats/format.rs:74:8 [INFO] [stdout] | [INFO] [stdout] 74 | struct ReverseFormat; [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `AddSuffix` is never constructed [INFO] [stdout] --> src/formats/format.rs:84:8 [INFO] [stdout] | [INFO] [stdout] 84 | struct AddSuffix(String); [INFO] [stdout] | ^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 16.95s [INFO] running `Command { std: "docker" "inspect" "ec2445b0849917512a38fc46664811569f346bc337ea3d3686caed1558ed9164", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ec2445b0849917512a38fc46664811569f346bc337ea3d3686caed1558ed9164", kill_on_drop: false }` [INFO] [stdout] ec2445b0849917512a38fc46664811569f346bc337ea3d3686caed1558ed9164 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 57d0d0b2c35c608d16c36871269f94b39ad5182778061171b29a5f6a58c00461 [INFO] running `Command { std: "docker" "start" "-a" "57d0d0b2c35c608d16c36871269f94b39ad5182778061171b29a5f6a58c00461", kill_on_drop: false }` [INFO] [stderr] warning: struct `UpperCase` is never constructed [INFO] [stderr] --> src/formats/format.rs:61:8 [INFO] [stderr] | [INFO] [stderr] 61 | struct UpperCase; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: struct `ReverseFormat` is never constructed [INFO] [stderr] --> src/formats/format.rs:74:8 [INFO] [stderr] | [INFO] [stderr] 74 | struct ReverseFormat; [INFO] [stderr] | ^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: struct `AddSuffix` is never constructed [INFO] [stderr] --> src/formats/format.rs:84:8 [INFO] [stderr] | [INFO] [stderr] 84 | struct AddSuffix(String); [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: `logform` (lib) generated 3 warnings [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/logform-64deb5bde0e7bb67) [INFO] [stdout] [INFO] [stdout] running 57 tests [INFO] [stdout] test formats::align::tests::test_align_format ... ok [INFO] [stdout] test formats::cli::cli_format_tests::test_cli_format ... ok [INFO] [stdout] test formats::cli::cli_format_tests::test_cli_format_with_options ... ok [INFO] [stdout] test formats::colorize::tests::test_colorizer_format ... ok [INFO] [stdout] test formats::format::tests::test_proposed_format ... ok [INFO] [stdout] test formats::json::tests::test_json_format ... ok [INFO] [stdout] test formats::label::tests::test_label_format_message ... ok [INFO] [stdout] test formats::label::tests::test_label_format_meta ... ok [INFO] [stdout] test formats::logstash::tests::test_logstash_format ... ok [INFO] [stdout] test formats::logstash::tests::test_logstash_format_with_metadata ... ok [INFO] [stdout] test formats::logstash::tests::test_logstash_format_with_no_timestamp_in_meta ... ok [INFO] [stdout] test formats::logstash::tests::test_metadata_preservation ... ok [INFO] [stdout] test formats::metadata::tests::test_metadata_with_fill_except ... ok [INFO] [stdout] test formats::metadata::tests::test_metadata_with_fill_with ... ok [INFO] [stdout] test formats::pad_levels::tests::test_padder_with_custom_filler ... ok [INFO] [stdout] test formats::pad_levels::tests::test_padder_with_padding ... ok [INFO] [stdout] test formats::pad_levels::tests::test_padlevels_function ... ok [INFO] [stdout] test formats::printf::tests::test_printf_formatter ... ok [INFO] [stdout] test formats::pretty_print::tests::test_pretty_print_json_structure ... ok [INFO] [stdout] test formats::simple::tests::test_simple_format ... ok [INFO] [stdout] test formats::timestamp::tests::test_alias ... ok [INFO] [stdout] test legacy_format::align::tests::test_align_format ... ok [INFO] [stdout] test legacy_format::format::tests::test_custom_format ... ok [INFO] [stdout] test legacy_format::combine::tests::test_combine_formatters ... ok [INFO] [stdout] test formats::pretty_print::tests::test_pretty_print_colorization ... ok [INFO] [stdout] test legacy_format::label::tests::test_label_format_message ... ok [INFO] [stdout] test legacy_format::label::tests::test_label_format_meta ... ok [INFO] [stdout] test legacy_format::format::tests::test_ignore_private ... ok [INFO] [stdout] test legacy_format::colorize::tests::test_colorize_formatter ... ok [INFO] [stdout] test legacy_format::logstash::tests::test_logstash_format_with_metadata ... ok [INFO] [stdout] test legacy_format::logstash::tests::test_logstash_format ... ok [INFO] [stdout] test legacy_format::cli::cli_format_tests::test_cli_format_with_custom_filler_and_color ... ok [INFO] [stdout] test legacy_format::json::tests::test_json_formatter ... ok [INFO] [stdout] test legacy_format::cli::cli_format_tests::test_cli_function ... ok [INFO] [stdout] test legacy_format::logstash::tests::test_metadata_preservation ... ok [INFO] [stdout] test legacy_format::metadata::tests::test_metadata_with_fill_with_and_fill_except ... ok [INFO] [stdout] test formats::uncolorize::tests::test_uncolorize_formatter ... ok [INFO] [stdout] test legacy_format::metadata::tests::test_metadata_with_fill_except ... ok [INFO] [stdout] test legacy_format::logstash::tests::test_logstash_format_with_no_timestamp_in_meta ... ok [INFO] [stdout] test legacy_format::pad_levels::padder_tests::test_padder_with_custom_filler ... ok [INFO] [stdout] test legacy_format::metadata::tests::test_metadata_with_fill_with ... ok [INFO] [stdout] test legacy_format::pad_levels::padder_tests::test_padder_with_padding ... ok [INFO] [stdout] test legacy_format::pad_levels::padder_tests::test_padlevels_function ... ok [INFO] [stdout] test legacy_format::printf::tests::test_printf_formatter ... ok [INFO] [stdout] test legacy_format::pretty_print::tests::test_pretty_print_formatter ... ok [INFO] [stdout] test legacy_format::timestamp::tests::test_alias ... ok [INFO] [stdout] test legacy_format::simple::tests::test_simple_formatter ... ok [INFO] [stdout] test log_info::tests::test_from_value ... ok [INFO] [stdout] test legacy_format::uncolorize::tests::test_uncolorize_formatter ... ok [INFO] [stdout] test formats::timestamp::tests::test_custom_format ... ok [INFO] [stdout] test formats::timestamp::tests::test_custom_format_with_alias ... ok [INFO] [stdout] test legacy_format::timestamp::tests::test_custom_format ... ok [INFO] [stdout] test legacy_format::timestamp::tests::test_custom_format_with_alias ... ok [INFO] [stdout] test formats::timestamp::tests::test_default_timestamp ... FAILED [INFO] [stdout] test legacy_format::timestamp::tests::test_default_timestamp ... FAILED [INFO] [stdout] test formats::ms::tests::test_ms_format ... ok [INFO] [stdout] test legacy_format::ms::tests::test_time_diff_format ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- formats::timestamp::tests::test_default_timestamp stdout ---- [INFO] [stdout] 2025-09-01T09:57:26.382987380+00:00 [INFO] [stdout] [INFO] [stdout] thread 'formats::timestamp::tests::test_default_timestamp' (50) panicked at src/formats/timestamp.rs:79:9: [INFO] [stdout] assertion failed: rfc3339_regex.is_match(timestamp) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5d8e4b4360d2 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5d8e4b4360d2 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5d8e4b4360d2 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5d8e4b4360d2 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5d8e4b44872f - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5d8e4b44872f - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5d8e4b403973 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5d8e4b403973 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5d8e4b40f732 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5d8e4b41424f - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5d8e4b4140e1 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5d8e4b0f461e - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x5d8e4b0f461e - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5d8e4b4148ee - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x5d8e4b4148ee - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5d8e4b414686 - std::panicking::panic_handler::{{closure}}::h3d21149c258e5ceb [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:700:13 [INFO] [stdout] 16: 0x5d8e4b40f869 - std::sys::backtrace::__rust_end_short_backtrace::h4f5d9b9dfb3e6ec1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5d8e4b3f7bdd - __rustc[9a7a9f9af7564de1]::rust_begin_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5d8e4b451650 - core::panicking::panic_fmt::h78e817a90331d98b [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5d8e4b45162c - core::panicking::panic::h33d2d00634ffed6f [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:145:5 [INFO] [stdout] 20: 0x5d8e4b0a7f6e - logform::formats::timestamp::tests::test_default_timestamp::hb429ecf4281571c6 [INFO] [stdout] at /opt/rustwide/workdir/src/formats/timestamp.rs:79:9 [INFO] [stdout] 21: 0x5d8e4b0a7fd7 - logform::formats::timestamp::tests::test_default_timestamp::{{closure}}::h75312640c6cad49d [INFO] [stdout] at /opt/rustwide/workdir/src/formats/timestamp.rs:68:32 [INFO] [stdout] 22: 0x5d8e4b08cd36 - core::ops::function::FnOnce::call_once::hb4951a98412bf4e3 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x5d8e4b0f441b - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x5d8e4b0f441b - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 25: 0x5d8e4b109ec5 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 26: 0x5d8e4b109ec5 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x5d8e4b109ec5 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 28: 0x5d8e4b109ec5 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 29: 0x5d8e4b109ec5 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 30: 0x5d8e4b109ec5 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 31: 0x5d8e4b109ec5 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 32: 0x5d8e4b0e08e4 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 33: 0x5d8e4b0e08e4 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: 0x5d8e4b0e41ca - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: 0x5d8e4b0e41ca - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x5d8e4b0e41ca - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: 0x5d8e4b0e41ca - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 38: 0x5d8e4b0e41ca - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 39: 0x5d8e4b0e41ca - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 40: 0x5d8e4b0e41ca - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 41: 0x5d8e4b409aef - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 42: 0x5d8e4b409aef - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 43: 0x7e2326bd5aa4 - [INFO] [stdout] 44: 0x7e2326c62a34 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- legacy_format::timestamp::tests::test_default_timestamp stdout ---- [INFO] [stdout] 2025-09-01T09:57:26.387451171+00:00 [INFO] [stdout] [INFO] [stdout] thread 'legacy_format::timestamp::tests::test_default_timestamp' (79) panicked at src/legacy_format/timestamp.rs:56:9: [INFO] [stdout] assertion failed: rfc3339_regex.is_match(timestamp) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5d8e4b4360d2 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5d8e4b4360d2 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5d8e4b4360d2 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5d8e4b4360d2 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5d8e4b44872f - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5d8e4b44872f - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5d8e4b403973 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5d8e4b403973 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5d8e4b40f732 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5d8e4b41424f - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x5d8e4b4140e1 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x5d8e4b0f461e - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x5d8e4b0f461e - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5d8e4b4148ee - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x5d8e4b4148ee - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x5d8e4b414686 - std::panicking::panic_handler::{{closure}}::h3d21149c258e5ceb [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:700:13 [INFO] [stdout] 16: 0x5d8e4b40f869 - std::sys::backtrace::__rust_end_short_backtrace::h4f5d9b9dfb3e6ec1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5d8e4b3f7bdd - __rustc[9a7a9f9af7564de1]::rust_begin_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x5d8e4b451650 - core::panicking::panic_fmt::h78e817a90331d98b [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5d8e4b45162c - core::panicking::panic::h33d2d00634ffed6f [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:145:5 [INFO] [stdout] 20: 0x5d8e4b092f52 - logform::legacy_format::timestamp::tests::test_default_timestamp::hd835c76b7fdc70dd [INFO] [stdout] at /opt/rustwide/workdir/src/legacy_format/timestamp.rs:56:9 [INFO] [stdout] 21: 0x5d8e4b092fb7 - logform::legacy_format::timestamp::tests::test_default_timestamp::{{closure}}::h3310b6d6deae7cda [INFO] [stdout] at /opt/rustwide/workdir/src/legacy_format/timestamp.rs:43:32 [INFO] [stdout] 22: 0x5d8e4b08cb26 - core::ops::function::FnOnce::call_once::h9d2d8832f08c5878 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x5d8e4b0f441b - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x5d8e4b0f441b - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 25: 0x5d8e4b109ec5 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 26: 0x5d8e4b109ec5 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x5d8e4b109ec5 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 28: 0x5d8e4b109ec5 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 29: 0x5d8e4b109ec5 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 30: 0x5d8e4b109ec5 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 31: 0x5d8e4b109ec5 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 32: 0x5d8e4b0e08e4 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stdout] 33: 0x5d8e4b0e08e4 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: 0x5d8e4b0e41ca - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: 0x5d8e4b0e41ca - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x5d8e4b0e41ca - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 37: 0x5d8e4b0e41ca - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 38: 0x5d8e4b0e41ca - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 39: 0x5d8e4b0e41ca - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 40: 0x5d8e4b0e41ca - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 41: 0x5d8e4b409aef - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 42: 0x5d8e4b409aef - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 43: 0x7e2326bd5aa4 - [INFO] [stdout] 44: 0x7e2326c62a34 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] formats::timestamp::tests::test_default_timestamp [INFO] [stdout] legacy_format::timestamp::tests::test_default_timestamp [INFO] [stdout] [INFO] [stdout] test result: FAILED. 55 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "57d0d0b2c35c608d16c36871269f94b39ad5182778061171b29a5f6a58c00461", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "57d0d0b2c35c608d16c36871269f94b39ad5182778061171b29a5f6a58c00461", kill_on_drop: false }` [INFO] [stdout] 57d0d0b2c35c608d16c36871269f94b39ad5182778061171b29a5f6a58c00461