[INFO] fetching crate ecs-logger 1.1.0... [INFO] testing ecs-logger-1.1.0 against master#1ddedbaa5919b7b3e70d984660e21e844c615c97 for pr-128400 [INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-1-tc1/source [INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain 1ddedbaa5919b7b3e70d984660e21e844c615c97 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "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-1-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "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" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 38f123a13db072b60824547e887b45a621eb358448e88786f4acacef10484875 [INFO] running `Command { std: "docker" "start" "-a" "38f123a13db072b60824547e887b45a621eb358448e88786f4acacef10484875", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "38f123a13db072b60824547e887b45a621eb358448e88786f4acacef10484875", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "38f123a13db072b60824547e887b45a621eb358448e88786f4acacef10484875", kill_on_drop: false }` [INFO] [stdout] 38f123a13db072b60824547e887b45a621eb358448e88786f4acacef10484875 [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] a3d5c3ff72e2b7ea981f27290c8ab216ffd716906c1a381a5cbb8632d42fa6fc [INFO] running `Command { std: "docker" "start" "-a" "a3d5c3ff72e2b7ea981f27290c8ab216ffd716906c1a381a5cbb8632d42fa6fc", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling serde_json v1.0.122 [INFO] [stderr] Compiling env_logger v0.10.2 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling thiserror-impl v1.0.63 [INFO] [stderr] Compiling thiserror v1.0.63 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.25s [INFO] running `Command { std: "docker" "inspect" "a3d5c3ff72e2b7ea981f27290c8ab216ffd716906c1a381a5cbb8632d42fa6fc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a3d5c3ff72e2b7ea981f27290c8ab216ffd716906c1a381a5cbb8632d42fa6fc", kill_on_drop: false }` [INFO] [stdout] a3d5c3ff72e2b7ea981f27290c8ab216ffd716906c1a381a5cbb8632d42fa6fc [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 691d7efa69a32a5de36a4507da6f30e67eb417509007019c3d86c8446b7f4018 [INFO] running `Command { std: "docker" "start" "-a" "691d7efa69a32a5de36a4507da6f30e67eb417509007019c3d86c8446b7f4018", kill_on_drop: false }` [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling regex-syntax v0.8.4 [INFO] [stderr] Compiling regex-automata v0.4.7 [INFO] [stderr] Compiling regex v1.10.6 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 4.67s [INFO] running `Command { std: "docker" "inspect" "691d7efa69a32a5de36a4507da6f30e67eb417509007019c3d86c8446b7f4018", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "691d7efa69a32a5de36a4507da6f30e67eb417509007019c3d86c8446b7f4018", kill_on_drop: false }` [INFO] [stdout] 691d7efa69a32a5de36a4507da6f30e67eb417509007019c3d86c8446b7f4018 [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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+1ddedbaa5919b7b3e70d984660e21e844c615c97" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] f5676d95775f86d8003cdc350c73a084b25c77fc4abdbd8ab36b5b238866199d [INFO] running `Command { std: "docker" "start" "-a" "f5676d95775f86d8003cdc350c73a084b25c77fc4abdbd8ab36b5b238866199d", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-1dbbd0f35698a966) [INFO] [stdout] [INFO] [stdout] running 12 tests [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_set_extra_fields_err ... ok [INFO] [stdout] test ecs::tests::test_from_log_record ... ok [INFO] [stdout] test tests::test_format ... ok [INFO] [stdout] test extra_fields::tests::test_merge_extra_fields ... 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 ecs::tests::test_serialize_with_none ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/example.rs (/opt/rustwide/target/debug/deps/example-e4ef4a2fda7a2b3f) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test tests::main ... ok [INFO] [stderr] {"@timestamp":"2024-08-05T13:08:05.979335758Z","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-023bc9b724593732) [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.06s [INFO] [stdout] [INFO] [stderr] Doc-tests ecs_logger [INFO] [stdout] [INFO] [stdout] running 13 tests [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/lib.rs - (line 103) ... 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::SetExtraFieldsError::InvalidJson (line 41) ... ok [INFO] [stdout] test src/extra_fields.rs - extra_fields::set_extra_fields (line 76) ... ok [INFO] [stdout] test src/extra_fields.rs - extra_fields (line 7) ... ok [INFO] [stdout] test src/lib.rs - (line 26) ... 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 - format (line 200) ... ok [INFO] [stdout] test src/lib.rs - try_init (line 178) ... ok [INFO] [stdout] test src/lib.rs - init (line 155) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.57s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "f5676d95775f86d8003cdc350c73a084b25c77fc4abdbd8ab36b5b238866199d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f5676d95775f86d8003cdc350c73a084b25c77fc4abdbd8ab36b5b238866199d", kill_on_drop: false }` [INFO] [stdout] f5676d95775f86d8003cdc350c73a084b25c77fc4abdbd8ab36b5b238866199d [INFO] testing ecs-logger-1.1.0 against try#33f1d8bb92813a1bfc23c093c9f443ab09290307 for pr-128400 [INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-1-tc2/source [INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain 33f1d8bb92813a1bfc23c093c9f443ab09290307 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "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-1-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "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" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 435c762426f4d83b1dbd334ee4799c239c09f6304deb48e70976320184453566 [INFO] running `Command { std: "docker" "start" "-a" "435c762426f4d83b1dbd334ee4799c239c09f6304deb48e70976320184453566", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "435c762426f4d83b1dbd334ee4799c239c09f6304deb48e70976320184453566", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "435c762426f4d83b1dbd334ee4799c239c09f6304deb48e70976320184453566", kill_on_drop: false }` [INFO] [stdout] 435c762426f4d83b1dbd334ee4799c239c09f6304deb48e70976320184453566 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 99424b49089bf7b504e87cb0ce548c91f30f9c0dd10625d0fc6609a9349aeb63 [INFO] running `Command { std: "docker" "start" "-a" "99424b49089bf7b504e87cb0ce548c91f30f9c0dd10625d0fc6609a9349aeb63", kill_on_drop: false }` [INFO] [stderr] Compiling serde v1.0.204 [INFO] [stderr] Compiling serde_json v1.0.122 [INFO] [stderr] Compiling env_logger v0.10.2 [INFO] [stderr] Compiling serde_derive v1.0.204 [INFO] [stderr] Compiling thiserror-impl v1.0.63 [INFO] [stderr] Compiling thiserror v1.0.63 [INFO] [stderr] Compiling chrono v0.4.38 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.99s [INFO] running `Command { std: "docker" "inspect" "99424b49089bf7b504e87cb0ce548c91f30f9c0dd10625d0fc6609a9349aeb63", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "99424b49089bf7b504e87cb0ce548c91f30f9c0dd10625d0fc6609a9349aeb63", kill_on_drop: false }` [INFO] [stdout] 99424b49089bf7b504e87cb0ce548c91f30f9c0dd10625d0fc6609a9349aeb63 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 83c1128f5c3fed8b356cf1875700cab4fd6da808d70ac631c0af9d323edc6fcd [INFO] running `Command { std: "docker" "start" "-a" "83c1128f5c3fed8b356cf1875700cab4fd6da808d70ac631c0af9d323edc6fcd", kill_on_drop: false }` [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling regex-syntax v0.8.4 [INFO] [stderr] Compiling regex-automata v0.4.7 [INFO] [stderr] Compiling regex v1.10.6 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 4.92s [INFO] running `Command { std: "docker" "inspect" "83c1128f5c3fed8b356cf1875700cab4fd6da808d70ac631c0af9d323edc6fcd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "83c1128f5c3fed8b356cf1875700cab4fd6da808d70ac631c0af9d323edc6fcd", kill_on_drop: false }` [INFO] [stdout] 83c1128f5c3fed8b356cf1875700cab4fd6da808d70ac631c0af9d323edc6fcd [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:923055f121b5182466d55868a8b05e67af8ba4a3a3f6bad814e953ca3cd3ac2a" "/opt/rustwide/cargo-home/bin/cargo" "+33f1d8bb92813a1bfc23c093c9f443ab09290307" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 11a53f5488d0b4f59f274151c55b0d54718b6d0816be56d5b8ded6b09d1d3c05 [INFO] running `Command { std: "docker" "start" "-a" "11a53f5488d0b4f59f274151c55b0d54718b6d0816be56d5b8ded6b09d1d3c05", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ecs_logger-1dbbd0f35698a966) [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 ... 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: 0x56078e128375 - std::backtrace_rs::backtrace::libunwind::trace::h644651cb29d88bb0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x56078e128375 - std::backtrace_rs::backtrace::trace_unsynchronized::h4f227a4714c93f22 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x56078e128375 - std::sys::backtrace::_print_fmt::hfe88767e38ff4b4e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x56078e128375 - ::fmt::h0e27af9f1d310e0a [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x56078e14f18b - core::fmt::rt::Argument::fmt::h900f10175a69b72e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x56078e14f18b - core::fmt::write::h3a97e76bd9b733fa [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/fmt/mod.rs:1178:21 [INFO] [stdout] 6: 0x56078e12578f - std::io::Write::write_fmt::he4611995baa28da3 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/io/mod.rs:1823:15 [INFO] [stdout] 7: 0x56078e129b91 - std::sys::backtrace::BacktraceLock::print::h52f15558f43bfe2f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x56078e129b91 - std::panicking::default_hook::{{closure}}::h77edc81607b06015 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:266:22 [INFO] [stdout] 9: 0x56078e129738 - std::panicking::default_hook::h4a889c281e863e6e [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:290:9 [INFO] [stdout] 10: 0x56078e0c67fa - as core::ops::function::Fn>::call::h87ae598a6618f03f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 11: 0x56078e0c67fa - test::test_main::{{closure}}::haa35c2695dfcc048 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:132:21 [INFO] [stdout] 12: 0x56078e12a49f - as core::ops::function::Fn>::call::h0934a01d4a1cdb06 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2162:9 [INFO] [stdout] 13: 0x56078e12a49f - std::panicking::rust_panic_with_hook::h74d8fff54c965152 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:805:13 [INFO] [stdout] 14: 0x56078e12a0c7 - std::panicking::begin_panic_handler::{{closure}}::h82b61517a14ab3ae [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:671:13 [INFO] [stdout] 15: 0x56078e128839 - std::sys::backtrace::__rust_end_short_backtrace::h7f24a51d7748d3e4 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:170:18 [INFO] [stdout] 16: 0x56078e129d54 - rust_begin_unwind [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:662:5 [INFO] [stdout] 17: 0x56078e14dac3 - core::panicking::panic_fmt::h7de3a20d92caa6a2 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panicking.rs:74:14 [INFO] [stdout] 18: 0x56078e14de8e - core::panicking::assert_failed_inner::hb4e1d95e5f1c8b95 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panicking.rs:412:17 [INFO] [stdout] 19: 0x56078e0511de - core::panicking::assert_failed::h3338676019ca1f4c [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panicking.rs:367:5 [INFO] [stdout] 20: 0x56078e06581e - ecs_logger::tests::test_format::hba2960b5dc6f56a1 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:250:9 [INFO] [stdout] 21: 0x56078e064027 - ecs_logger::tests::test_format::{{closure}}::hf8b51634f3ce462b [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:242:21 [INFO] [stdout] 22: 0x56078e057ed6 - core::ops::function::FnOnce::call_once::hcfef515c4b51d0e0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x56078e0cb37b - core::ops::function::FnOnce::call_once::h24a6f01be65f4680 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x56078e0cb37b - test::__rust_begin_short_backtrace::h7c53016b80d5a608 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:620:18 [INFO] [stdout] 25: 0x56078e0cab95 - test::run_test_in_process::{{closure}}::hde6a274ce897aaad [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:643:60 [INFO] [stdout] 26: 0x56078e0cab95 - as core::ops::function::FnOnce<()>>::call_once::hd431ac2f54963786 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x56078e0cab95 - std::panicking::try::do_call::h10362e01a063baa0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:554:40 [INFO] [stdout] 28: 0x56078e0cab95 - std::panicking::try::h30318180e4290dce [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:518:19 [INFO] [stdout] 29: 0x56078e0cab95 - std::panic::catch_unwind::h97d52b1b1f400ecc [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panic.rs:345:14 [INFO] [stdout] 30: 0x56078e0cab95 - test::run_test_in_process::haec7bb1f728b765d [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:643:27 [INFO] [stdout] 31: 0x56078e0cab95 - test::run_test::{{closure}}::ha988a53ee25f80e1 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:564:43 [INFO] [stdout] 32: 0x56078e090464 - test::run_test::{{closure}}::hddc4d91f14475af0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/test/src/lib.rs:594:41 [INFO] [stdout] 33: 0x56078e090464 - std::sys::backtrace::__rust_begin_short_backtrace::h550bd80387fd32b0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/backtrace.rs:154:18 [INFO] [stdout] 34: 0x56078e093b92 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h0907e6530d89c432 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/thread/mod.rs:524:17 [INFO] [stdout] 35: 0x56078e093b92 - as core::ops::function::FnOnce<()>>::call_once::h1426168782d20988 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x56078e093b92 - std::panicking::try::do_call::h9e10c7df8de701e0 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:554:40 [INFO] [stdout] 37: 0x56078e093b92 - std::panicking::try::hc0169bb633716dc9 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panicking.rs:518:19 [INFO] [stdout] 38: 0x56078e093b92 - std::panic::catch_unwind::h2d365a7fbba378ff [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/panic.rs:345:14 [INFO] [stdout] 39: 0x56078e093b92 - std::thread::Builder::spawn_unchecked_::{{closure}}::h99b3d5b0eb8d8349 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/thread/mod.rs:523:30 [INFO] [stdout] 40: 0x56078e093b92 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h785f99837c772588 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x56078e12e5fb - as core::ops::function::FnOnce>::call_once::h9d8f2204f4fcbe73 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 42: 0x56078e12e5fb - as core::ops::function::FnOnce>::call_once::h817ffd7b41f007e2 [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/alloc/src/boxed.rs:2148:9 [INFO] [stdout] 43: 0x56078e12e5fb - std::sys::pal::unix::thread::Thread::new::thread_start::hfffda88b0cd3e69f [INFO] [stdout] at /rustc/33f1d8bb92813a1bfc23c093c9f443ab09290307/library/std/src/sys/pal/unix/thread.rs:105:17 [INFO] [stdout] 44: 0x7f83cfc68ac3 - [INFO] [stdout] 45: 0x7f83cfcf9a04 - clone [INFO] [stdout] 46: 0x0 - [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" "11a53f5488d0b4f59f274151c55b0d54718b6d0816be56d5b8ded6b09d1d3c05", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "11a53f5488d0b4f59f274151c55b0d54718b6d0816be56d5b8ded6b09d1d3c05", kill_on_drop: false }` [INFO] [stdout] 11a53f5488d0b4f59f274151c55b0d54718b6d0816be56d5b8ded6b09d1d3c05