[INFO] fetching crate ecs-logger 1.1.0...
[INFO] testing ecs-logger-1.1.0 against 1.80.0 for beta-1.81-2
[INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-3-tc1/source
[INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain 1.80.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.80.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-3-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.80.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] Updating crates.io index
[INFO] [stderr] Locking 51 packages to latest compatible versions
[INFO] [stderr] Adding env_logger v0.10.2 (latest: v0.11.5)
[INFO] [stderr] Adding windows-core v0.52.0 (latest: v0.58.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.80.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.80.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] eed790dc098751b3b6d57e7f61da1dd3d21308da364818c0491322bae7e3c6a0
[INFO] running `Command { std: "docker" "start" "-a" "eed790dc098751b3b6d57e7f61da1dd3d21308da364818c0491322bae7e3c6a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "eed790dc098751b3b6d57e7f61da1dd3d21308da364818c0491322bae7e3c6a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eed790dc098751b3b6d57e7f61da1dd3d21308da364818c0491322bae7e3c6a0", kill_on_drop: false }`
[INFO] [stdout] eed790dc098751b3b6d57e7f61da1dd3d21308da364818c0491322bae7e3c6a0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.80.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 35a0c16a3a48d40458ab969048a832c081db0292a5c9f38568675fff1f9c4245
[INFO] running `Command { std: "docker" "start" "-a" "35a0c16a3a48d40458ab969048a832c081db0292a5c9f38568675fff1f9c4245", kill_on_drop: false }`
[INFO] [stderr] Compiling log v0.4.22
[INFO] [stderr] Compiling chrono v0.4.38
[INFO] [stderr] Compiling serde_json v1.0.121
[INFO] [stderr] Compiling env_logger v0.10.2
[INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.14s
[INFO] running `Command { std: "docker" "inspect" "35a0c16a3a48d40458ab969048a832c081db0292a5c9f38568675fff1f9c4245", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "35a0c16a3a48d40458ab969048a832c081db0292a5c9f38568675fff1f9c4245", kill_on_drop: false }`
[INFO] [stdout] 35a0c16a3a48d40458ab969048a832c081db0292a5c9f38568675fff1f9c4245
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.80.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7e1a2ef23253c934d5b6cdc647bbd82a41ef505cfb334ece565ef736679aca2a
[INFO] running `Command { std: "docker" "start" "-a" "7e1a2ef23253c934d5b6cdc647bbd82a41ef505cfb334ece565ef736679aca2a", kill_on_drop: false }`
[INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.48s
[INFO] running `Command { std: "docker" "inspect" "7e1a2ef23253c934d5b6cdc647bbd82a41ef505cfb334ece565ef736679aca2a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7e1a2ef23253c934d5b6cdc647bbd82a41ef505cfb334ece565ef736679aca2a", kill_on_drop: false }`
[INFO] [stdout] 7e1a2ef23253c934d5b6cdc647bbd82a41ef505cfb334ece565ef736679aca2a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+1.80.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] eb87eaa8939c199ed019c853e7b379be3a3beb0d2ca5b551395141602ee46bf5
[INFO] running `Command { std: "docker" "start" "-a" "eb87eaa8939c199ed019c853e7b379be3a3beb0d2ca5b551395141602ee46bf5", kill_on_drop: false }`
[INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-7cd62957251fce2c)
[INFO] [stdout]
[INFO] [stdout] running 12 tests
[INFO] [stdout] test ecs::tests::test_from_log_record ... ok
[INFO] [stdout] test ecs::tests::test_serialize_with_none ... ok
[INFO] [stdout] test ecs::tests::test_serialize ... ok
[INFO] [stdout] test extra_fields::tests::test_clear_extra_fields ... 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 ... 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: 0x55935fdc3c85 - std::backtrace_rs::backtrace::libunwind::trace::h58eed11393533053
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout] 1: 0x55935fdc3c85 - std::backtrace_rs::backtrace::trace_unsynchronized::h6af9bae28ebb6388
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout] 2: 0x55935fdc3c85 - std::sys_common::backtrace::_print_fmt::hb6748916642a4fb2
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout] 3: 0x55935fdc3c85 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h3692694645b1bb6a
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout] 4: 0x55935fdea24b - core::fmt::rt::Argument::fmt::h7aa93977ba74ae0f
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/fmt/rt.rs:165:63
[INFO] [stdout] 5: 0x55935fdea24b - core::fmt::write::h5131d80b4c69b88d
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/fmt/mod.rs:1168:21
[INFO] [stdout] 6: 0x55935fdc148f - std::io::Write::write_fmt::h8142063d64ec1ffc
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/io/mod.rs:1835:15
[INFO] [stdout] 7: 0x55935fdc3a5e - std::sys_common::backtrace::_print::he6ebb7b9d89f4456
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout] 8: 0x55935fdc3a5e - std::sys_common::backtrace::print::h998d75b840f75a73
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout] 9: 0x55935fdc54c9 - std::panicking::default_hook::{{closure}}::h18ec7fe6a38b9da0
[INFO] [stdout] 10: 0x55935fdc5136 - std::panicking::default_hook::hfb3f22c2e4075a6a
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:295:9
[INFO] [stdout] 11: 0x55935fd66d6a - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h9b6b503c8a5fa2e3
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/alloc/src/boxed.rs:2077:9
[INFO] [stdout] 12: 0x55935fd66d6a - test::test_main::{{closure}}::h238c9a9a662cfeed
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:137:21
[INFO] [stdout] 13: 0x55935fdc5acb - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb8210adad49183e7
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/alloc/src/boxed.rs:2077:9
[INFO] [stdout] 14: 0x55935fdc5acb - std::panicking::rust_panic_with_hook::h51af00bcb4660c4e
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:799:13
[INFO] [stdout] 15: 0x55935fdc5844 - std::panicking::begin_panic_handler::{{closure}}::h39f76aa863fbe8ce
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:664:13
[INFO] [stdout] 16: 0x55935fdc4149 - std::sys_common::backtrace::__rust_end_short_backtrace::h4d10fc2251b89840
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout] 17: 0x55935fdc5577 - rust_begin_unwind
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:652:5
[INFO] [stdout] 18: 0x55935fce8aa3 - core::panicking::panic_fmt::h319840fcbcd912ef
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/panicking.rs:72:14
[INFO] [stdout] 19: 0x55935fce8e8e - core::panicking::assert_failed_inner::h18aff98a9c33883a
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/panicking.rs:408:17
[INFO] [stdout] 20: 0x55935fcf291e - core::panicking::assert_failed::h4e1602f5e230e024
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/panicking.rs:363:5
[INFO] [stdout] 21: 0x55935fd0f04e - ecs_logger::tests::test_format::hcf66efe443a6fac4
[INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:250:9
[INFO] [stdout] 22: 0x55935fd0d857 - ecs_logger::tests::test_format::{{closure}}::hfc82b76718f27d6f
[INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:242:21
[INFO] [stdout] 23: 0x55935fd09d76 - core::ops::function::FnOnce::call_once::h14383956407f134b
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/ops/function.rs:250:5
[INFO] [stdout] 24: 0x55935fd6b5bb - core::ops::function::FnOnce::call_once::h384146465e88f7be
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/ops/function.rs:250:5
[INFO] [stdout] 25: 0x55935fd6b5bb - test::__rust_begin_short_backtrace::hae44b86aaeda3a0f
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:625:18
[INFO] [stdout] 26: 0x55935fd6ad11 - test::run_test_in_process::{{closure}}::hd85dcdfbb890f561
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:648:60
[INFO] [stdout] 27: 0x55935fd6ad11 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h0e31567dfbb1842d
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout] 28: 0x55935fd6ad11 - std::panicking::try::do_call::h21b13b77488972c9
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:559:40
[INFO] [stdout] 29: 0x55935fd6ad11 - std::panicking::try::h8696c153e1e4253f
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:523:19
[INFO] [stdout] 30: 0x55935fd6ad11 - std::panic::catch_unwind::h5cd1cddd6367954f
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panic.rs:149:14
[INFO] [stdout] 31: 0x55935fd6ad11 - test::run_test_in_process::h59ce94f07fa84554
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:648:27
[INFO] [stdout] 32: 0x55935fd6ad11 - test::run_test::{{closure}}::h4148d3c10c6c98f1
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:569:43
[INFO] [stdout] 33: 0x55935fd33c34 - test::run_test::{{closure}}::hfda3d929e6d1b5c1
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/test/src/lib.rs:599:41
[INFO] [stdout] 34: 0x55935fd33c34 - std::sys_common::backtrace::__rust_begin_short_backtrace::h91e427ea6df4cf63
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout] 35: 0x55935fd384f2 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h88142129462d0de6
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/thread/mod.rs:542:17
[INFO] [stdout] 36: 0x55935fd384f2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h7a94a6989c4568c7
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout] 37: 0x55935fd384f2 - std::panicking::try::do_call::h07d6ddb104cff605
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:559:40
[INFO] [stdout] 38: 0x55935fd384f2 - std::panicking::try::heeeb9f9f6492fe02
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panicking.rs:523:19
[INFO] [stdout] 39: 0x55935fd384f2 - std::panic::catch_unwind::h947df39c62294dd7
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/panic.rs:149:14
[INFO] [stdout] 40: 0x55935fd384f2 - std::thread::Builder::spawn_unchecked_::{{closure}}::hba1c75acae318d67
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/thread/mod.rs:541:30
[INFO] [stdout] 41: 0x55935fd384f2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::had7513e31342a613
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/core/src/ops/function.rs:250:5
[INFO] [stdout] 42: 0x55935fdc9d7b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h3af90da315d4b185
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/alloc/src/boxed.rs:2063:9
[INFO] [stdout] 43: 0x55935fdc9d7b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h4e7f3b3405b4b88b
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/alloc/src/boxed.rs:2063:9
[INFO] [stdout] 44: 0x55935fdc9d7b - std::sys::pal::unix::thread::Thread::new::thread_start::h3b8e81128811868f
[INFO] [stdout] at /rustc/051478957371ee0084a7c0913941d2a8c4757bb9/library/std/src/sys/pal/unix/thread.rs:108:17
[INFO] [stdout] 45: 0x7f5ef2599ac3 - <unknown>
[INFO] [stdout] 46: 0x7f5ef262aa04 - __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" "eb87eaa8939c199ed019c853e7b379be3a3beb0d2ca5b551395141602ee46bf5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eb87eaa8939c199ed019c853e7b379be3a3beb0d2ca5b551395141602ee46bf5", kill_on_drop: false }`
[INFO] [stdout] eb87eaa8939c199ed019c853e7b379be3a3beb0d2ca5b551395141602ee46bf5
[INFO] testing ecs-logger-1.1.0 against beta-2024-07-26 for beta-1.81-2
[INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-3-tc2/source
[INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain beta-2024-07-26
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-07-26" "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-3-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-07-26" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] Updating crates.io index
[INFO] [stderr] Locking 51 packages to latest compatible versions
[INFO] [stderr] Adding env_logger v0.10.2 (latest: v0.11.5)
[INFO] [stderr] Adding windows-core v0.52.0 (latest: v0.58.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2024-07-26" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-07-26" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9982edbf046be2944fc2c79a6217f8f72fda865ec7dc192c1743b50577939ed1
[INFO] running `Command { std: "docker" "start" "-a" "9982edbf046be2944fc2c79a6217f8f72fda865ec7dc192c1743b50577939ed1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9982edbf046be2944fc2c79a6217f8f72fda865ec7dc192c1743b50577939ed1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9982edbf046be2944fc2c79a6217f8f72fda865ec7dc192c1743b50577939ed1", kill_on_drop: false }`
[INFO] [stdout] 9982edbf046be2944fc2c79a6217f8f72fda865ec7dc192c1743b50577939ed1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-07-26" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 114828e95d657078287d5d32f80a47c415cc9f58422941e2c03d05e5440735d0
[INFO] running `Command { std: "docker" "start" "-a" "114828e95d657078287d5d32f80a47c415cc9f58422941e2c03d05e5440735d0", kill_on_drop: false }`
[INFO] [stderr] Compiling log v0.4.22
[INFO] [stderr] Compiling serde_json v1.0.121
[INFO] [stderr] Compiling chrono v0.4.38
[INFO] [stderr] Compiling env_logger v0.10.2
[INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.03s
[INFO] running `Command { std: "docker" "inspect" "114828e95d657078287d5d32f80a47c415cc9f58422941e2c03d05e5440735d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "114828e95d657078287d5d32f80a47c415cc9f58422941e2c03d05e5440735d0", kill_on_drop: false }`
[INFO] [stdout] 114828e95d657078287d5d32f80a47c415cc9f58422941e2c03d05e5440735d0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-07-26" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 165e9c66c0daadf62dc201c5df1ed0684436cb0bc9249d25fe969c98a19407cf
[INFO] running `Command { std: "docker" "start" "-a" "165e9c66c0daadf62dc201c5df1ed0684436cb0bc9249d25fe969c98a19407cf", kill_on_drop: false }`
[INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir)
[INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.50s
[INFO] running `Command { std: "docker" "inspect" "165e9c66c0daadf62dc201c5df1ed0684436cb0bc9249d25fe969c98a19407cf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "165e9c66c0daadf62dc201c5df1ed0684436cb0bc9249d25fe969c98a19407cf", kill_on_drop: false }`
[INFO] [stdout] 165e9c66c0daadf62dc201c5df1ed0684436cb0bc9249d25fe969c98a19407cf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:dff56e7819e73ed36160586b3445e93eb0be776c16704aeeded9c3fb668b2384" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2024-07-26" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 92c4593d8ae2b2db090300633a33ffd6e5e54d2f8098e59e4519fa1801cf7777
[INFO] running `Command { std: "docker" "start" "-a" "92c4593d8ae2b2db090300633a33ffd6e5e54d2f8098e59e4519fa1801cf7777", kill_on_drop: false }`
[INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-1a1ffac4a128f5ad)
[INFO] [stdout]
[INFO] [stdout] running 12 tests
[INFO] [stdout] test ecs::tests::test_serialize_with_none ... ok
[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 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 tests::test_format ... ok
[INFO] [stdout] test tests::test_init ... ok
[INFO] [stdout] test timestamp::tests::test_get_timestamp ... ok
[INFO] [stdout] test tests::test_format_with_extra_fields ... 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-8c06ce701f3f9b76)
[INFO] [stdout]
[INFO] [stdout] running 1 test
[INFO] [stdout] test tests::main ... ok
[INFO] [stderr] {"@timestamp":"2024-08-01T07:07:51.744596527Z","log.level":"ERROR","message":"this is printed by default","ecs.version":"1.12.1","log.origin":{"file":{"line":36,"name":"example.rs"},"rust":{"target":"example::tests","module_path":"example::tests","file_path":"tests/example.rs"}},"organization.name":"Example Company","service":{"name":"example-service","version":"1.2.3"}}
[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-204f8731b86fba27)
[INFO] [stdout]
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::test_extra_fields ... ok
[INFO] [stdout] test tests::test_logs ... ok
[INFO] [stdout] test tests::test_target ... 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/lib.rs - format (line 200) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::NotObject (line 57) ... ok
[INFO] [stdout] test src/lib.rs - (line 26) ... ok
[INFO] [stdout] test src/lib.rs - init (line 155) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::clear_extra_fields (line 108) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::set_extra_fields (line 76) ... ok
[INFO] [stdout] test src/lib.rs - (line 89) ... ok
[INFO] [stdout] test src/lib.rs - (line 103) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields (line 7) ... ok
[INFO] [stdout] test src/lib.rs - try_init (line 178) ... ok
[INFO] [stdout] test src/ecs.rs - ecs (line 7) ... ok
[INFO] [stdout] test src/lib.rs - (line 55) ... ok
[INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::InvalidJson (line 41) ... ok
[INFO] [stdout]
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.43s
[INFO] [stdout]
[INFO] running `Command { std: "docker" "inspect" "92c4593d8ae2b2db090300633a33ffd6e5e54d2f8098e59e4519fa1801cf7777", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "92c4593d8ae2b2db090300633a33ffd6e5e54d2f8098e59e4519fa1801cf7777", kill_on_drop: false }`
[INFO] [stdout] 92c4593d8ae2b2db090300633a33ffd6e5e54d2f8098e59e4519fa1801cf7777