[INFO] fetching crate ecs-logger 1.1.0... [INFO] testing ecs-logger-1.1.0 against master#ff2c56344c764af598ad33027e9c7a48881808ef for pr-118825 [INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-6-tc1/source [INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain ff2c56344c764af598ad33027e9c7a48881808ef [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "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-6-tc1/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 70ea3f75d2c08addba4a211c542e94ac24b6ed68ca033eb2104794fa47c2944b [INFO] running `Command { std: "docker" "start" "-a" "70ea3f75d2c08addba4a211c542e94ac24b6ed68ca033eb2104794fa47c2944b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "70ea3f75d2c08addba4a211c542e94ac24b6ed68ca033eb2104794fa47c2944b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "70ea3f75d2c08addba4a211c542e94ac24b6ed68ca033eb2104794fa47c2944b", kill_on_drop: false }` [INFO] [stdout] 70ea3f75d2c08addba4a211c542e94ac24b6ed68ca033eb2104794fa47c2944b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] d87e3356127ed493bad380370673e6650e5289612adf63fc931dbeb9fb08ee1e [INFO] running `Command { std: "docker" "start" "-a" "d87e3356127ed493bad380370673e6650e5289612adf63fc931dbeb9fb08ee1e", kill_on_drop: false }` [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling indexmap v2.1.0 [INFO] [stderr] Compiling thiserror-impl v1.0.50 [INFO] [stderr] Compiling chrono v0.4.31 [INFO] [stderr] Compiling env_logger v0.10.1 [INFO] [stderr] Compiling serde_json v1.0.108 [INFO] [stderr] Compiling thiserror v1.0.50 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.22s [INFO] running `Command { std: "docker" "inspect" "d87e3356127ed493bad380370673e6650e5289612adf63fc931dbeb9fb08ee1e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d87e3356127ed493bad380370673e6650e5289612adf63fc931dbeb9fb08ee1e", kill_on_drop: false }` [INFO] [stdout] d87e3356127ed493bad380370673e6650e5289612adf63fc931dbeb9fb08ee1e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 75b9b8ae74e9b8b3426b5f7b70848fa98ac43f05b408587a26948dafd558bbcc [INFO] running `Command { std: "docker" "start" "-a" "75b9b8ae74e9b8b3426b5f7b70848fa98ac43f05b408587a26948dafd558bbcc", 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.14s [INFO] running `Command { std: "docker" "inspect" "75b9b8ae74e9b8b3426b5f7b70848fa98ac43f05b408587a26948dafd558bbcc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "75b9b8ae74e9b8b3426b5f7b70848fa98ac43f05b408587a26948dafd558bbcc", kill_on_drop: false }` [INFO] [stdout] 75b9b8ae74e9b8b3426b5f7b70848fa98ac43f05b408587a26948dafd558bbcc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 18be8b4b4e47ef4dc7dbb0c4d04d1f06d9dd6ad27fdb3d4365340669d002c835 [INFO] running `Command { std: "docker" "start" "-a" "18be8b4b4e47ef4dc7dbb0c4d04d1f06d9dd6ad27fdb3d4365340669d002c835", 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-fb5263ca5f7a0e99) [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 ecs::tests::test_serialize_with_none ... 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_format ... 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] [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-54b25350f585418e) [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-c6ca668dc8f4d672) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test tests::test_logs ... ok [INFO] [stdout] test tests::test_target ... ok [INFO] [stdout] test tests::test_extra_fields ... 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 - (line 55) ... ok [INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::NotObject (line 57) ... ok [INFO] [stdout] test src/lib.rs - try_init (line 178) ... ok [INFO] [stdout] test src/lib.rs - format (line 200) ... 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/ecs.rs - ecs (line 7) ... ok [INFO] [stdout] test src/lib.rs - (line 26) ... ok [INFO] [stdout] test src/extra_fields.rs - extra_fields::SetExtraFieldsError::InvalidJson (line 41) ... 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.47s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "18be8b4b4e47ef4dc7dbb0c4d04d1f06d9dd6ad27fdb3d4365340669d002c835", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "18be8b4b4e47ef4dc7dbb0c4d04d1f06d9dd6ad27fdb3d4365340669d002c835", kill_on_drop: false }` [INFO] [stdout] 18be8b4b4e47ef4dc7dbb0c4d04d1f06d9dd6ad27fdb3d4365340669d002c835 [INFO] testing ecs-logger-1.1.0 against try#5e4273472e1b2f223618a9b3def795f6224f241d for pr-118825 [INFO] extracting crate ecs-logger 1.1.0 into /workspace/builds/worker-6-tc2/source [INFO] validating manifest of crates.io crate ecs-logger 1.1.0 on toolchain 5e4273472e1b2f223618a9b3def795f6224f241d [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "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-6-tc2/source/Cargo.toml [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] a03944ee428399e57520c5926533b260ec098272ae378faf0a265e4d3daed10b [INFO] running `Command { std: "docker" "start" "-a" "a03944ee428399e57520c5926533b260ec098272ae378faf0a265e4d3daed10b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "a03944ee428399e57520c5926533b260ec098272ae378faf0a265e4d3daed10b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a03944ee428399e57520c5926533b260ec098272ae378faf0a265e4d3daed10b", kill_on_drop: false }` [INFO] [stdout] a03944ee428399e57520c5926533b260ec098272ae378faf0a265e4d3daed10b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5e627a851d201169fe7fc455e83d05d91cec60043b0f7a292ed088516898b56e [INFO] running `Command { std: "docker" "start" "-a" "5e627a851d201169fe7fc455e83d05d91cec60043b0f7a292ed088516898b56e", kill_on_drop: false }` [INFO] [stderr] Compiling log v0.4.20 [INFO] [stderr] Compiling indexmap v2.1.0 [INFO] [stderr] Compiling thiserror-impl v1.0.50 [INFO] [stderr] Compiling chrono v0.4.31 [INFO] [stderr] Compiling env_logger v0.10.1 [INFO] [stderr] Compiling serde_json v1.0.108 [INFO] [stderr] Compiling thiserror v1.0.50 [INFO] [stderr] Compiling ecs-logger v1.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.25s [INFO] running `Command { std: "docker" "inspect" "5e627a851d201169fe7fc455e83d05d91cec60043b0f7a292ed088516898b56e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5e627a851d201169fe7fc455e83d05d91cec60043b0f7a292ed088516898b56e", kill_on_drop: false }` [INFO] [stdout] 5e627a851d201169fe7fc455e83d05d91cec60043b0f7a292ed088516898b56e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] a0bf4edd91150244b5eae6708519ade1bf3a2b3a98ea0ecf3e458eff1e4dd09c [INFO] running `Command { std: "docker" "start" "-a" "a0bf4edd91150244b5eae6708519ade1bf3a2b3a98ea0ecf3e458eff1e4dd09c", 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.00s [INFO] running `Command { std: "docker" "inspect" "a0bf4edd91150244b5eae6708519ade1bf3a2b3a98ea0ecf3e458eff1e4dd09c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a0bf4edd91150244b5eae6708519ade1bf3a2b3a98ea0ecf3e458eff1e4dd09c", kill_on_drop: false }` [INFO] [stdout] a0bf4edd91150244b5eae6708519ade1bf3a2b3a98ea0ecf3e458eff1e4dd09c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 7ec9067756033cf6db579adfa89f8920dc69fe1e1cbc1cca49dd7f22f22ec08b [INFO] running `Command { std: "docker" "start" "-a" "7ec9067756033cf6db579adfa89f8920dc69fe1e1cbc1cca49dd7f22f22ec08b", 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-fb5263ca5f7a0e99) [INFO] [stdout] [INFO] [stdout] running 12 tests [INFO] [stdout] test ecs::tests::test_from_log_record ... 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 ecs::tests::test_serialize ... 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_format_with_extra_fields ... ok [INFO] [stdout] test tests::test_init ... 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: 0x56553efd074c - std::backtrace_rs::backtrace::libunwind::trace::he911f9c907837597 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5 [INFO] [stdout] 1: 0x56553efd074c - std::backtrace_rs::backtrace::trace_unsynchronized::hb30926ceb4a5ace8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x56553efd074c - std::sys_common::backtrace::_print_fmt::h20b4c3cb4b8e01de [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:68:5 [INFO] [stdout] 3: 0x56553efd074c - ::fmt::hcb347474accdbb50 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x56553effa3c0 - core::fmt::rt::Argument::fmt::h0959df1f48ddd7a2 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/rt.rs:142:9 [INFO] [stdout] 5: 0x56553effa3c0 - core::fmt::write::hb02961b4380cf4e7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/fmt/mod.rs:1120:17 [INFO] [stdout] 6: 0x56553efce1bf - std::io::Write::write_fmt::h529bf90db5fd4c64 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/io/mod.rs:1810:15 [INFO] [stdout] 7: 0x56553efd0534 - std::sys_common::backtrace::_print::h9f31dc4772e05e46 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 8: 0x56553efd0534 - std::sys_common::backtrace::print::he1974c4a8ca14219 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 9: 0x56553efd2267 - std::panicking::default_hook::{{closure}}::h9663c9f0f27e933c [INFO] [stdout] 10: 0x56553efd1f53 - std::panicking::default_hook::h232155e937189ed1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:289:9 [INFO] [stdout] 11: 0x56553ef70b87 - as core::ops::function::Fn>::call::h30a8b128ee88d398 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 12: 0x56553ef70b87 - test::test_main::{{closure}}::hcafb25d50378ac2e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:138:21 [INFO] [stdout] 13: 0x56553efd2878 - as core::ops::function::Fn>::call::hb0500737c93e9fc8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2029:9 [INFO] [stdout] 14: 0x56553efd2878 - std::panicking::rust_panic_with_hook::hc8764d1694e821a7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:783:13 [INFO] [stdout] 15: 0x56553efd25ce - std::panicking::begin_panic_handler::{{closure}}::hf3ed629468815f4d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:657:13 [INFO] [stdout] 16: 0x56553efd0c16 - std::sys_common::backtrace::__rust_end_short_backtrace::h4e08af68b8432e1a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:171:18 [INFO] [stdout] 17: 0x56553efd2332 - rust_begin_unwind [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:645:5 [INFO] [stdout] 18: 0x56553eef3d55 - core::panicking::panic_fmt::hd274135c92cc1bf8 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panicking.rs:72:14 [INFO] [stdout] 19: 0x56553eef418b - core::panicking::assert_failed_inner::h0e7060ac34fab196 [INFO] [stdout] 20: 0x56553eefd01e - core::panicking::assert_failed::he5cdf1cf1269f9bc [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panicking.rs:297:5 [INFO] [stdout] 21: 0x56553ef02a16 - ecs_logger::tests::test_format::h12ddca44f764857b [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:250:9 [INFO] [stdout] 22: 0x56553ef01b17 - ecs_logger::tests::test_format::{{closure}}::h33ac172859e55293 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:242:21 [INFO] [stdout] 23: 0x56553ef04d26 - core::ops::function::FnOnce::call_once::hca0410078f125ba5 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x56553ef7627f - core::ops::function::FnOnce::call_once::he101e5838844b848 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x56553ef7627f - test::__rust_begin_short_backtrace::hed21417c1aa71a94 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:627:18 [INFO] [stdout] 26: 0x56553ef751f5 - test::run_test_in_process::{{closure}}::h46df11f5a11dd433 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:650:60 [INFO] [stdout] 27: 0x56553ef751f5 - as core::ops::function::FnOnce<()>>::call_once::ha2e64525433f53a7 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x56553ef751f5 - std::panicking::try::do_call::hb1d734fe97663a7f [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:552:40 [INFO] [stdout] 29: 0x56553ef751f5 - std::panicking::try::h9d066276df30f924 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:516:19 [INFO] [stdout] 30: 0x56553ef751f5 - std::panic::catch_unwind::heaada02e29cd999e [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panic.rs:142:14 [INFO] [stdout] 31: 0x56553ef751f5 - test::run_test_in_process::hcb05ee14075e628d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:650:27 [INFO] [stdout] 32: 0x56553ef751f5 - test::run_test::{{closure}}::hf9413b0f448cd684 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:573:43 [INFO] [stdout] 33: 0x56553ef3d436 - test::run_test::{{closure}}::h1bb15d2e8774a33f [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/test/src/lib.rs:601:41 [INFO] [stdout] 34: 0x56553ef3d436 - std::sys_common::backtrace::__rust_begin_short_backtrace::h530f23a50d8639c1 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys_common/backtrace.rs:155:18 [INFO] [stdout] 35: 0x56553ef42617 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h37b8455d57d25f70 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:529:17 [INFO] [stdout] 36: 0x56553ef42617 - as core::ops::function::FnOnce<()>>::call_once::h3867791011bf949d [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x56553ef42617 - std::panicking::try::do_call::h1cac0f1203e6219b [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:552:40 [INFO] [stdout] 38: 0x56553ef42617 - std::panicking::try::h82de9f14e7a5f0ca [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panicking.rs:516:19 [INFO] [stdout] 39: 0x56553ef42617 - std::panic::catch_unwind::hcea1081d521eaa80 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/panic.rs:142:14 [INFO] [stdout] 40: 0x56553ef42617 - std::thread::Builder::spawn_unchecked_::{{closure}}::ha7aa8f4eaae79bb5 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/thread/mod.rs:528:30 [INFO] [stdout] 41: 0x56553ef42617 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h5c1a4829e968af7c [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x56553efd72d5 - as core::ops::function::FnOnce>::call_once::h6cea6bfb156e0019 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 43: 0x56553efd72d5 - as core::ops::function::FnOnce>::call_once::h3c499441c8da3187 [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/alloc/src/boxed.rs:2015:9 [INFO] [stdout] 44: 0x56553efd72d5 - std::sys::unix::thread::Thread::new::thread_start::h45692980afba103a [INFO] [stdout] at /rustc/5e4273472e1b2f223618a9b3def795f6224f241d/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 45: 0x7ff8823ebac3 - [INFO] [stdout] 46: 0x7ff88247cbf4 - __clone [INFO] [stdout] 47: 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" "7ec9067756033cf6db579adfa89f8920dc69fe1e1cbc1cca49dd7f22f22ec08b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7ec9067756033cf6db579adfa89f8920dc69fe1e1cbc1cca49dd7f22f22ec08b", kill_on_drop: false }` [INFO] [stdout] 7ec9067756033cf6db579adfa89f8920dc69fe1e1cbc1cca49dd7f22f22ec08b