[INFO] fetching crate ecs-logger 1.1.0...
[INFO] testing ecs-logger-1.1.0 against 1.75.0 for beta-1.76-1
[INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-4-tc1/source
[INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain 1.75.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.75.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ecs-logger 1.1.0
[INFO] finished tweaking crates.io crate ecs-logger 1.1.0
[INFO] tweaked toml for crates.io crate ecs-logger 1.1.0 written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.75.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.75.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0ce2c2b40bf147a5dc69732b1fc5a5e9a08f818df789cf1e38d98c71e964f135
[INFO] running `Command { std: "docker" "start" "-a" "0ce2c2b40bf147a5dc69732b1fc5a5e9a08f818df789cf1e38d98c71e964f135", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0ce2c2b40bf147a5dc69732b1fc5a5e9a08f818df789cf1e38d98c71e964f135", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0ce2c2b40bf147a5dc69732b1fc5a5e9a08f818df789cf1e38d98c71e964f135", kill_on_drop: false }`
[INFO] [stdout] 0ce2c2b40bf147a5dc69732b1fc5a5e9a08f818df789cf1e38d98c71e964f135
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a3b1383375edd197e6de4005a8f4be4eebc824a44c70f39d14add9265402b01d
[INFO] running `Command { std: "docker" "start" "-a" "a3b1383375edd197e6de4005a8f4be4eebc824a44c70f39d14add9265402b01d", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.111
[INFO] [stderr]    Compiling env_logger v0.10.1
[INFO] [stderr]    Compiling chrono v0.4.31
[INFO] [stderr]    Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.04s
[INFO] running `Command { std: "docker" "inspect" "a3b1383375edd197e6de4005a8f4be4eebc824a44c70f39d14add9265402b01d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3b1383375edd197e6de4005a8f4be4eebc824a44c70f39d14add9265402b01d", kill_on_drop: false }`
[INFO] [stdout] a3b1383375edd197e6de4005a8f4be4eebc824a44c70f39d14add9265402b01d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b654470dc4a170634fa9c693a31b72f3af3970825ef4834d794e41d9f36ce2ba
[INFO] running `Command { std: "docker" "start" "-a" "b654470dc4a170634fa9c693a31b72f3af3970825ef4834d794e41d9f36ce2ba", kill_on_drop: false }`
[INFO] [stderr]    Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.95s
[INFO] running `Command { std: "docker" "inspect" "b654470dc4a170634fa9c693a31b72f3af3970825ef4834d794e41d9f36ce2ba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b654470dc4a170634fa9c693a31b72f3af3970825ef4834d794e41d9f36ce2ba", kill_on_drop: false }`
[INFO] [stdout] b654470dc4a170634fa9c693a31b72f3af3970825ef4834d794e41d9f36ce2ba
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] dbdab505ed5d6f4b1c2e90585a83411d53205a159da0bc88b2c99aa3ce45c08c
[INFO] running `Command { std: "docker" "start" "-a" "dbdab505ed5d6f4b1c2e90585a83411d53205a159da0bc88b2c99aa3ce45c08c", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-ba1ed77bdeecb8f6)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test extra_fields::tests::test_clear_extra_fields ... ok
[INFO] [stdout] test ecs::tests::test_serialize ... ok
[INFO] [stdout] test ecs::tests::test_from_log_record ... ok
[INFO] [stdout] test ecs::tests::test_serialize_with_none ... ok
[INFO] [stdout] test extra_fields::tests::test_merge_extra_fields ... ok
[INFO] [stdout] test extra_fields::tests::test_extend_json_map ... ok
[INFO] [stdout] test extra_fields::tests::test_set_extra_fields_err ... ok
[INFO] [stdout] test extra_fields::tests::test_set_extra_fields_ok ... ok
[INFO] [stdout] test timestamp::tests::test_get_timestamp ... ok
[INFO] [stdout] test tests::test_init ... ok
[INFO] [stdout] test tests::test_format_with_extra_fields ... ok
[INFO] [stdout] test tests::test_format ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_format stdout ----
[INFO] [stdout] thread 'tests::test_format' panicked at src/lib.rs:250:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: "{\"@timestamp\":\"2000-01-23T01:23:45.678901200Z\",\"log.level\":\"ERROR\",\"message\":\"hello world\",\"ecs.version\":\"1.12.1\",\"log.origin\":{\"file\":{\"line\":13,\"name\":\"example.rs\"},\"rust\":{\"target\":\"example\",\"module_path\":\"example::tests\",\"file_path\":\"tests/example.rs\"}},\"a\":1,\"b\":{\"c\":2}}\n"
[INFO] [stdout]  right: "{\"@timestamp\":\"2000-01-23T01:23:45.678901200Z\",\"log.level\":\"ERROR\",\"message\":\"hello world\",\"ecs.version\":\"1.12.1\",\"log.origin\":{\"file\":{\"line\":13,\"name\":\"example.rs\"},\"rust\":{\"target\":\"example\",\"module_path\":\"example::tests\",\"file_path\":\"tests/example.rs\"}}}\n"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x558a377e51ac - std::backtrace_rs::backtrace::libunwind::trace::ha637c64ce894333a
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x558a377e51ac - std::backtrace_rs::backtrace::trace_unsynchronized::h47f62dea28e0c88d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x558a377e51ac - std::sys_common::backtrace::_print_fmt::h9eef0abe20ede486
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x558a377e51ac - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hed7f999df88cc644
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x558a3780ed70 - core::fmt::rt::Argument::fmt::h1539a9308b8d058d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x558a3780ed70 - core::fmt::write::h3a39390d8560d9c9
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x558a377e2aff - std::io::Write::write_fmt::h040b369f3a0f9cbc
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/io/mod.rs:1762:15
[INFO] [stdout]    7:     0x558a377e4f94 - std::sys_common::backtrace::_print::h894006fb5c6f3d45
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x558a377e4f94 - std::sys_common::backtrace::print::h23a2d212c6fff936
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x558a377e6c57 - std::panicking::default_hook::{{closure}}::h8a1d2ee00185001a
[INFO] [stdout]   10:     0x558a377e6943 - std::panicking::default_hook::h6038f2eba384e475
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x558a37785657 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h0ad024c9bdc6d768
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2021:9
[INFO] [stdout]   12:     0x558a37785657 - test::test_main::{{closure}}::h7500b54257359db6
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x558a377e7268 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1f8f335eaa9cfaee
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2021:9
[INFO] [stdout]   14:     0x558a377e7268 - std::panicking::rust_panic_with_hook::h2b5517d590cab22e
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x558a377e6fbe - std::panicking::begin_panic_handler::{{closure}}::h233112c06e0ef43e
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x558a377e5676 - std::sys_common::backtrace::__rust_end_short_backtrace::h6e893f24d7ebbff8
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:170:18
[INFO] [stdout]   17:     0x558a377e6d22 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x558a37708d55 - core::panicking::panic_fmt::hbf0e066aabfa482c
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x558a3770916b - core::panicking::assert_failed_inner::h0bba8b7c26ee4492
[INFO] [stdout]   20:     0x558a37720e5e - core::panicking::assert_failed::h1b1a91e56b270cf7
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panicking.rs:279:5
[INFO] [stdout]   21:     0x558a37719366 - ecs_logger::tests::test_format::hd984cc5be719a7eb
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:250:9
[INFO] [stdout]   22:     0x558a37718467 - ecs_logger::tests::test_format::{{closure}}::hf1242e23c483d9bf
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:242:21
[INFO] [stdout]   23:     0x558a37711f36 - core::ops::function::FnOnce::call_once::h2dd85123599b4c17
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x558a3778acaf - core::ops::function::FnOnce::call_once::h2b5d431d5046312d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x558a3778acaf - test::__rust_begin_short_backtrace::h7714669af176e207
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:628:18
[INFO] [stdout]   26:     0x558a37789cc5 - test::run_test_in_process::{{closure}}::h85218797dcbcec12
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:651:60
[INFO] [stdout]   27:     0x558a37789cc5 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h76c106ea31c14d7b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   28:     0x558a37789cc5 - std::panicking::try::do_call::h3bd5d0f6620ee946
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:552:40
[INFO] [stdout]   29:     0x558a37789cc5 - std::panicking::try::h232e535e4744fe31
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:516:19
[INFO] [stdout]   30:     0x558a37789cc5 - std::panic::catch_unwind::h3ce3bd6ca8dbac68
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panic.rs:142:14
[INFO] [stdout]   31:     0x558a37789cc5 - test::run_test_in_process::hc97b257c9621286a
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:651:27
[INFO] [stdout]   32:     0x558a37789cc5 - test::run_test::{{closure}}::h2aa798823a438d38
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:574:43
[INFO] [stdout]   33:     0x558a37751f86 - test::run_test::{{closure}}::h87d231c78fb59832
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:602:41
[INFO] [stdout]   34:     0x558a37751f86 - std::sys_common::backtrace::__rust_begin_short_backtrace::he6df152596625789
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:154:18
[INFO] [stdout]   35:     0x558a37757037 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::ha94be15d1e0acf36
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   36:     0x558a37757037 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h9dc6bd6372f16606
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   37:     0x558a37757037 - std::panicking::try::do_call::h9057517f80303cd4
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:552:40
[INFO] [stdout]   38:     0x558a37757037 - std::panicking::try::he3a30b11ee45340c
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:516:19
[INFO] [stdout]   39:     0x558a37757037 - std::panic::catch_unwind::h410458cfd3528b8b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panic.rs:142:14
[INFO] [stdout]   40:     0x558a37757037 - std::thread::Builder::spawn_unchecked_::{{closure}}::h72ac3bdb567b737b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   41:     0x558a37757037 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h38f34da3654a8344
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x558a377ebcf5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hc7eafaff61e32df9
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2007:9
[INFO] [stdout]   43:     0x558a377ebcf5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h6ba4a5de48dd2304
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2007:9
[INFO] [stdout]   44:     0x558a377ebcf5 - std::sys::unix::thread::Thread::new::thread_start::he469335aef763e45
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   45:     0x7f78da559ac3 - <unknown>
[INFO] [stdout]   46:     0x7f78da5eabf4 - __clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_format
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 11 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "dbdab505ed5d6f4b1c2e90585a83411d53205a159da0bc88b2c99aa3ce45c08c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dbdab505ed5d6f4b1c2e90585a83411d53205a159da0bc88b2c99aa3ce45c08c", kill_on_drop: false }`
[INFO] [stdout] dbdab505ed5d6f4b1c2e90585a83411d53205a159da0bc88b2c99aa3ce45c08c
[INFO] testing ecs-logger-1.1.0 against beta-2023-12-22 for beta-1.76-1
[INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-4-tc2/source
[INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain beta-2023-12-22
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-12-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate ecs-logger 1.1.0
[INFO] finished tweaking crates.io crate ecs-logger 1.1.0
[INFO] tweaked toml for crates.io crate ecs-logger 1.1.0 written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-12-22" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-12-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 6ab66eb3bb2af87b7b9bdfdb0366ba7a70b3bb25d7171b7e15fc302c6890df56
[INFO] running `Command { std: "docker" "start" "-a" "6ab66eb3bb2af87b7b9bdfdb0366ba7a70b3bb25d7171b7e15fc302c6890df56", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6ab66eb3bb2af87b7b9bdfdb0366ba7a70b3bb25d7171b7e15fc302c6890df56", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6ab66eb3bb2af87b7b9bdfdb0366ba7a70b3bb25d7171b7e15fc302c6890df56", kill_on_drop: false }`
[INFO] [stdout] 6ab66eb3bb2af87b7b9bdfdb0366ba7a70b3bb25d7171b7e15fc302c6890df56
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d8b3db077d13b18a7bead870ce609cca6e289bbe9dc08b9feedac6054f48e806
[INFO] running `Command { std: "docker" "start" "-a" "d8b3db077d13b18a7bead870ce609cca6e289bbe9dc08b9feedac6054f48e806", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.111
[INFO] [stderr]    Compiling env_logger v0.10.1
[INFO] [stderr]    Compiling chrono v0.4.31
[INFO] [stderr]    Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.14s
[INFO] running `Command { std: "docker" "inspect" "d8b3db077d13b18a7bead870ce609cca6e289bbe9dc08b9feedac6054f48e806", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d8b3db077d13b18a7bead870ce609cca6e289bbe9dc08b9feedac6054f48e806", kill_on_drop: false }`
[INFO] [stdout] d8b3db077d13b18a7bead870ce609cca6e289bbe9dc08b9feedac6054f48e806
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6d89c5ce0a2173d049989657c37ef14b9bca1885a8d7e27e4687aeff584882d7
[INFO] running `Command { std: "docker" "start" "-a" "6d89c5ce0a2173d049989657c37ef14b9bca1885a8d7e27e4687aeff584882d7", kill_on_drop: false }`
[INFO] [stderr]    Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.04s
[INFO] running `Command { std: "docker" "inspect" "6d89c5ce0a2173d049989657c37ef14b9bca1885a8d7e27e4687aeff584882d7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6d89c5ce0a2173d049989657c37ef14b9bca1885a8d7e27e4687aeff584882d7", kill_on_drop: false }`
[INFO] [stdout] 6d89c5ce0a2173d049989657c37ef14b9bca1885a8d7e27e4687aeff584882d7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] b85eff10bfa345cf431fd968d1d78830cc387835fabdaae21a581152e90a850a
[INFO] running `Command { std: "docker" "start" "-a" "b85eff10bfa345cf431fd968d1d78830cc387835fabdaae21a581152e90a850a", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-618c038fc022c61a)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test ecs::tests::test_from_log_record ... ok
[INFO] [stdout] test ecs::tests::test_serialize ... ok
[INFO] [stdout] test extra_fields::tests::test_clear_extra_fields ... ok
[INFO] [stdout] test ecs::tests::test_serialize_with_none ... ok
[INFO] [stdout] test extra_fields::tests::test_extend_json_map ... ok
[INFO] [stdout] test extra_fields::tests::test_merge_extra_fields ... ok
[INFO] [stdout] test extra_fields::tests::test_set_extra_fields_err ... ok
[INFO] [stdout] test extra_fields::tests::test_set_extra_fields_ok ... ok
[INFO] [stdout] test tests::test_init ... ok
[INFO] [stdout] test tests::test_format_with_extra_fields ... ok
[INFO] [stdout] test timestamp::tests::test_get_timestamp ... ok
[INFO] [stdout] test tests::test_format ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/example.rs (/opt/rustwide/target/debug/deps/example-fcc53dca0f81fc20)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test tests::main ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/log.rs (/opt/rustwide/target/debug/deps/log-ab1bec9d90fcfa08)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::test_target ... ok
[INFO] [stdout] test tests::test_extra_fields ... ok
[INFO] [stdout] test tests::test_logs ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests ecs-logger
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test src/extra_fields.rs - extra_fields::clear_extra_fields (line 108) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::NotObject (line 57) ... ok
[INFO] [stdout] test src/ecs.rs - ecs (line 7) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::set_extra_fields (line 76) ... ok
[INFO] [stdout] test src/lib.rs - (line 103) ... ok
[INFO] [stdout] test src/lib.rs - (line 26) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields (line 7) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::InvalidJson (line 41) ... ok
[INFO] [stdout] test src/lib.rs - (line 89) ... ok
[INFO] [stdout] test src/lib.rs - (line 55) ... ok
[INFO] [stdout] test src/lib.rs - init (line 155) ... ok
[INFO] [stdout] test src/lib.rs - format (line 200) ... ok
[INFO] [stdout] test src/lib.rs - try_init (line 178) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.63s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "b85eff10bfa345cf431fd968d1d78830cc387835fabdaae21a581152e90a850a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b85eff10bfa345cf431fd968d1d78830cc387835fabdaae21a581152e90a850a", kill_on_drop: false }`
[INFO] [stdout] b85eff10bfa345cf431fd968d1d78830cc387835fabdaae21a581152e90a850a
