[INFO] cloning repository https://github.com/dunkyl/justlogfox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/dunkyl/justlogfox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdunkyl%2Fjustlogfox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdunkyl%2Fjustlogfox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c7aca5517898b888e34ce320ba5a3ff3ee350572
[INFO] testing dunkyl/justlogfox against 1.75.0 for beta-1.76-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdunkyl%2Fjustlogfox" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/dunkyl/justlogfox on toolchain 1.75.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.75.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/dunkyl/justlogfox
[INFO] finished tweaking git repo https://github.com/dunkyl/justlogfox
[INFO] tweaked toml for git repo https://github.com/dunkyl/justlogfox written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] crate git repo https://github.com/dunkyl/justlogfox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.75.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] de3eddbbea031702a82746cfe0671433b68d29f3cfcb4b04184ea28de93ba923
[INFO] running `Command { std: "docker" "start" "-a" "de3eddbbea031702a82746cfe0671433b68d29f3cfcb4b04184ea28de93ba923", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "de3eddbbea031702a82746cfe0671433b68d29f3cfcb4b04184ea28de93ba923", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "de3eddbbea031702a82746cfe0671433b68d29f3cfcb4b04184ea28de93ba923", kill_on_drop: false }`
[INFO] [stdout] de3eddbbea031702a82746cfe0671433b68d29f3cfcb4b04184ea28de93ba923
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a36302677df0da57ea78ac4e7d1a38f3345358072210decb382ed3233a4feb01
[INFO] running `Command { std: "docker" "start" "-a" "a36302677df0da57ea78ac4e7d1a38f3345358072210decb382ed3233a4feb01", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.147
[INFO] [stderr]    Compiling iana-time-zone v0.1.57
[INFO] [stderr]    Compiling owo-colors v3.5.0
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling time v0.1.45
[INFO] [stderr]    Compiling chrono v0.4.26
[INFO] [stderr]    Compiling justlogfox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.58s
[INFO] running `Command { std: "docker" "inspect" "a36302677df0da57ea78ac4e7d1a38f3345358072210decb382ed3233a4feb01", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a36302677df0da57ea78ac4e7d1a38f3345358072210decb382ed3233a4feb01", kill_on_drop: false }`
[INFO] [stdout] a36302677df0da57ea78ac4e7d1a38f3345358072210decb382ed3233a4feb01
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c0df1b279373d8bf3c91669e8827cf57d43d9510b115574b98938574d40ad056
[INFO] running `Command { std: "docker" "start" "-a" "c0df1b279373d8bf3c91669e8827cf57d43d9510b115574b98938574d40ad056", kill_on_drop: false }`
[INFO] [stderr]    Compiling justlogfox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.48s
[INFO] running `Command { std: "docker" "inspect" "c0df1b279373d8bf3c91669e8827cf57d43d9510b115574b98938574d40ad056", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c0df1b279373d8bf3c91669e8827cf57d43d9510b115574b98938574d40ad056", kill_on_drop: false }`
[INFO] [stdout] c0df1b279373d8bf3c91669e8827cf57d43d9510b115574b98938574d40ad056
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.75.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] cd1e664a2343291d6049f0fabeb0a4d4f68536016a000513afd77b0e24ed3de8
[INFO] running `Command { std: "docker" "start" "-a" "cd1e664a2343291d6049f0fabeb0a4d4f68536016a000513afd77b0e24ed3de8", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/justlogfox-63a52ec4d7c2945a)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test tests::custom_sink_gets_info ... ok
[INFO] [stdout] test tests::filters_level ... ok
[INFO] [stdout] test tests::custom_sink_is_called ... ok
[INFO] [stdout] test tests::parallel_logging ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::parallel_logging stdout ----
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [32mINFO[39m Logging a thing
[INFO] [stdout] thread 'tests::parallel_logging' panicked at src/lib.rs:378:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: 1
[INFO] [stdout]  right: 100
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55b98ae2e8ac - std::backtrace_rs::backtrace::libunwind::trace::ha637c64ce894333a
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x55b98ae2e8ac - std::backtrace_rs::backtrace::trace_unsynchronized::h47f62dea28e0c88d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55b98ae2e8ac - std::sys_common::backtrace::_print_fmt::h9eef0abe20ede486
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x55b98ae2e8ac - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hed7f999df88cc644
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x55b98ae58af0 - core::fmt::rt::Argument::fmt::h1539a9308b8d058d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x55b98ae58af0 - core::fmt::write::h3a39390d8560d9c9
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x55b98ae2c04f - std::io::Write::write_fmt::h040b369f3a0f9cbc
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/io/mod.rs:1762:15
[INFO] [stdout]    7:     0x55b98ae2e694 - std::sys_common::backtrace::_print::h894006fb5c6f3d45
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x55b98ae2e694 - std::sys_common::backtrace::print::h23a2d212c6fff936
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x55b98ae30357 - std::panicking::default_hook::{{closure}}::h8a1d2ee00185001a
[INFO] [stdout]   10:     0x55b98ae30043 - std::panicking::default_hook::h6038f2eba384e475
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x55b98adff047 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h0ad024c9bdc6d768
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2021:9
[INFO] [stdout]   12:     0x55b98adff047 - test::test_main::{{closure}}::h7500b54257359db6
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x55b98ae30968 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1f8f335eaa9cfaee
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2021:9
[INFO] [stdout]   14:     0x55b98ae30968 - std::panicking::rust_panic_with_hook::h2b5517d590cab22e
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x55b98ae306be - std::panicking::begin_panic_handler::{{closure}}::h233112c06e0ef43e
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x55b98ae2ed76 - std::sys_common::backtrace::__rust_end_short_backtrace::h6e893f24d7ebbff8
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:170:18
[INFO] [stdout]   17:     0x55b98ae30422 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x55b98ad6df25 - core::panicking::panic_fmt::hbf0e066aabfa482c
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x55b98ad6e33b - core::panicking::assert_failed_inner::h0bba8b7c26ee4492
[INFO] [stdout]   20:     0x55b98ad7d3be - core::panicking::assert_failed::h2823b9529962b91d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panicking.rs:279:5
[INFO] [stdout]   21:     0x55b98ad84372 - justlogfox::tests::parallel_logging::h584dbc68469e3830
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:378:9
[INFO] [stdout]   22:     0x55b98ad841a7 - justlogfox::tests::parallel_logging::{{closure}}::h7d14f9db90fbb085
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:360:26
[INFO] [stdout]   23:     0x55b98ad7b256 - core::ops::function::FnOnce::call_once::h378df5470b07b117
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x55b98ae0469f - core::ops::function::FnOnce::call_once::h2b5d431d5046312d
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x55b98ae0469f - test::__rust_begin_short_backtrace::h7714669af176e207
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:628:18
[INFO] [stdout]   26:     0x55b98ae036b5 - test::run_test_in_process::{{closure}}::h85218797dcbcec12
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:651:60
[INFO] [stdout]   27:     0x55b98ae036b5 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h76c106ea31c14d7b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   28:     0x55b98ae036b5 - std::panicking::try::do_call::h3bd5d0f6620ee946
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:552:40
[INFO] [stdout]   29:     0x55b98ae036b5 - std::panicking::try::h232e535e4744fe31
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:516:19
[INFO] [stdout]   30:     0x55b98ae036b5 - std::panic::catch_unwind::h3ce3bd6ca8dbac68
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panic.rs:142:14
[INFO] [stdout]   31:     0x55b98ae036b5 - test::run_test_in_process::hc97b257c9621286a
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:651:27
[INFO] [stdout]   32:     0x55b98ae036b5 - test::run_test::{{closure}}::h2aa798823a438d38
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:574:43
[INFO] [stdout]   33:     0x55b98adcb976 - test::run_test::{{closure}}::h87d231c78fb59832
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/test/src/lib.rs:602:41
[INFO] [stdout]   34:     0x55b98adcb976 - std::sys_common::backtrace::__rust_begin_short_backtrace::he6df152596625789
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys_common/backtrace.rs:154:18
[INFO] [stdout]   35:     0x55b98add0a27 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::ha94be15d1e0acf36
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   36:     0x55b98add0a27 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h9dc6bd6372f16606
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   37:     0x55b98add0a27 - std::panicking::try::do_call::h9057517f80303cd4
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:552:40
[INFO] [stdout]   38:     0x55b98add0a27 - std::panicking::try::he3a30b11ee45340c
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panicking.rs:516:19
[INFO] [stdout]   39:     0x55b98add0a27 - std::panic::catch_unwind::h410458cfd3528b8b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/panic.rs:142:14
[INFO] [stdout]   40:     0x55b98add0a27 - std::thread::Builder::spawn_unchecked_::{{closure}}::h72ac3bdb567b737b
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   41:     0x55b98add0a27 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h38f34da3654a8344
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x55b98ae35685 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hc7eafaff61e32df9
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2007:9
[INFO] [stdout]   43:     0x55b98ae35685 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h6ba4a5de48dd2304
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/alloc/src/boxed.rs:2007:9
[INFO] [stdout]   44:     0x55b98ae35685 - std::sys::unix::thread::Thread::new::thread_start::he469335aef763e45
[INFO] [stdout]                                at /rustc/82e1608dfa6e0b5569232559e3d385fea5a93112/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   45:     0x7fd87d84fac3 - <unknown>
[INFO] [stdout]   46:     0x7fd87d8e0bf4 - __clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::parallel_logging
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "cd1e664a2343291d6049f0fabeb0a4d4f68536016a000513afd77b0e24ed3de8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cd1e664a2343291d6049f0fabeb0a4d4f68536016a000513afd77b0e24ed3de8", kill_on_drop: false }`
[INFO] [stdout] cd1e664a2343291d6049f0fabeb0a4d4f68536016a000513afd77b0e24ed3de8
[INFO] testing dunkyl/justlogfox against beta-2023-12-22 for beta-1.76-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdunkyl%2Fjustlogfox" "/workspace/builds/worker-0-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/dunkyl/justlogfox on toolchain beta-2023-12-22
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-12-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/dunkyl/justlogfox
[INFO] finished tweaking git repo https://github.com/dunkyl/justlogfox
[INFO] tweaked toml for git repo https://github.com/dunkyl/justlogfox written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] crate git repo https://github.com/dunkyl/justlogfox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2023-12-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 093b95bd233ce733a1a2b7170ffa5c6f998800969178db0a3ed97111597e984e
[INFO] running `Command { std: "docker" "start" "-a" "093b95bd233ce733a1a2b7170ffa5c6f998800969178db0a3ed97111597e984e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "093b95bd233ce733a1a2b7170ffa5c6f998800969178db0a3ed97111597e984e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "093b95bd233ce733a1a2b7170ffa5c6f998800969178db0a3ed97111597e984e", kill_on_drop: false }`
[INFO] [stdout] 093b95bd233ce733a1a2b7170ffa5c6f998800969178db0a3ed97111597e984e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8d0a4e336ec803d4cf6172285d303c6e36c676b569cb54d5f6ceb4d3abb4a05e
[INFO] running `Command { std: "docker" "start" "-a" "8d0a4e336ec803d4cf6172285d303c6e36c676b569cb54d5f6ceb4d3abb4a05e", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.147
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling iana-time-zone v0.1.57
[INFO] [stderr]    Compiling owo-colors v3.5.0
[INFO] [stderr]    Compiling time v0.1.45
[INFO] [stderr]    Compiling chrono v0.4.26
[INFO] [stderr]    Compiling justlogfox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.83s
[INFO] running `Command { std: "docker" "inspect" "8d0a4e336ec803d4cf6172285d303c6e36c676b569cb54d5f6ceb4d3abb4a05e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8d0a4e336ec803d4cf6172285d303c6e36c676b569cb54d5f6ceb4d3abb4a05e", kill_on_drop: false }`
[INFO] [stdout] 8d0a4e336ec803d4cf6172285d303c6e36c676b569cb54d5f6ceb4d3abb4a05e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 920041d4d8dd837d5192fdf78bd63c00956c0c92ed8185fff764a50ed5a4ff7c
[INFO] running `Command { std: "docker" "start" "-a" "920041d4d8dd837d5192fdf78bd63c00956c0c92ed8185fff764a50ed5a4ff7c", kill_on_drop: false }`
[INFO] [stderr]    Compiling justlogfox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.52s
[INFO] running `Command { std: "docker" "inspect" "920041d4d8dd837d5192fdf78bd63c00956c0c92ed8185fff764a50ed5a4ff7c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "920041d4d8dd837d5192fdf78bd63c00956c0c92ed8185fff764a50ed5a4ff7c", kill_on_drop: false }`
[INFO] [stdout] 920041d4d8dd837d5192fdf78bd63c00956c0c92ed8185fff764a50ed5a4ff7c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2023-12-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e6bf442790bc7345b112b93b28c73d80ed1c1434c2332efe3a8e56945e9cb8d4
[INFO] running `Command { std: "docker" "start" "-a" "e6bf442790bc7345b112b93b28c73d80ed1c1434c2332efe3a8e56945e9cb8d4", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/justlogfox-f96de86e58ac4eaf)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test tests::filters_level ... FAILED
[INFO] [stdout] test tests::custom_sink_is_called ... FAILED
[INFO] [stdout] test tests::custom_sink_gets_info ... FAILED
[INFO] [stdout] test tests::parallel_logging ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::filters_level stdout ----
[INFO] [stdout] [90m[39m]8;id=hi;file:/opt/rustwide/src/lib.rs\[38;2;255;165;000m[justlogfox::tests][39m]8;;\ [33mWARN[39m warn
[INFO] [stdout] thread 'tests::filters_level' panicked at src/lib.rs:308:13:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: "warn"
[INFO] [stdout]  right: "Hello, world! 1 2"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56153a0184d6 - std::backtrace_rs::backtrace::libunwind::trace::h261606ad26a499c1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x56153a0184d6 - std::backtrace_rs::backtrace::trace_unsynchronized::hd183f0625477fb28
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x56153a0184d6 - std::sys_common::backtrace::_print_fmt::hd7f8fef21b4eda67
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x56153a0184d6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h623d9b51f2f453f1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x56153a041a50 - core::fmt::rt::Argument::fmt::h9f417ac76a86f368
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x56153a041a50 - core::fmt::write::hc9b6faf4ac2aeff7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x56153a015d2f - std::io::Write::write_fmt::he04625a3a989fec3
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x56153a0182b4 - std::sys_common::backtrace::_print::hd5d66399c7df88a2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x56153a0182b4 - std::sys_common::backtrace::print::he81ed2da642d37e5
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x56153a01a027 - std::panicking::default_hook::{{closure}}::h85773f4fa117163d
[INFO] [stdout]   10:     0x56153a019d0d - std::panicking::default_hook::h6a1694a91d09b278
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x561539fe8387 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h616ac37fa3cd6c8f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x561539fe8387 - test::test_main::{{closure}}::h00d84697cefdcbb6
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x56153a01a646 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hcb3fe3b9bb537ba8
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x56153a01a646 - std::panicking::rust_panic_with_hook::h0a7ad96e90f7145b
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x56153a01a392 - std::panicking::begin_panic_handler::{{closure}}::h7919090525c6485e
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x56153a0189d6 - std::sys_common::backtrace::__rust_end_short_backtrace::hf3289479464f7dfe
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x56153a01a0f0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x561539f56f05 - core::panicking::panic_fmt::hcc9f77c5c132af55
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x561539f5733b - core::panicking::assert_failed_inner::h9f13e27dd63c3a08
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:342:17
[INFO] [stdout]   20:     0x561539f6de5e - core::panicking::assert_failed::ha8195ddf47e2f565
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:297:5
[INFO] [stdout]   21:     0x561539f6bbeb - justlogfox::tests::custom_sink_gets_info::{{closure}}::h34ea8584571cf8b1
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:308:13
[INFO] [stdout]   22:     0x561539f5f4fb - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9fabbd63fd2f53c
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   23:     0x561539f61215 - justlogfox::StaticLogger::log::hab3e5666cf718d3b
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:119:13
[INFO] [stdout]   24:     0x561539f61726 - justlogfox::log::h2297a3674ecfbff8
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:153:5
[INFO] [stdout]   25:     0x561539f6c7ee - justlogfox::tests::filters_level::h37447d9231472849
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:344:9
[INFO] [stdout]   26:     0x561539f6c097 - justlogfox::tests::filters_level::{{closure}}::h1601716135c6457e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:332:23
[INFO] [stdout]   27:     0x561539f646e6 - core::ops::function::FnOnce::call_once::he88f88827fc81276
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x561539fedc5f - core::ops::function::FnOnce::call_once::hd64d26f405258157
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x561539fedc5f - test::__rust_begin_short_backtrace::he21b0b7a6d731bf9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:627:18
[INFO] [stdout]   30:     0x561539fec9e1 - test::run_test_in_process::{{closure}}::hf5c73986d7bdfde2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:60
[INFO] [stdout]   31:     0x561539fec9e1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h330011ad032aadc0
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   32:     0x561539fec9e1 - std::panicking::try::do_call::he61b0a958b795a41
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   33:     0x561539fec9e1 - std::panicking::try::h7309da130f906eb7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   34:     0x561539fec9e1 - std::panic::catch_unwind::hc5c3a06fdaf0a92a
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   35:     0x561539fec9e1 - test::run_test_in_process::h7e7b7c71c3324e0f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:27
[INFO] [stdout]   36:     0x561539fec9e1 - test::run_test::{{closure}}::h1f509f28f5cf20b1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:573:43
[INFO] [stdout]   37:     0x561539fb3d16 - test::run_test::{{closure}}::h1754218abf443f6d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:601:41
[INFO] [stdout]   38:     0x561539fb3d16 - std::sys_common::backtrace::__rust_begin_short_backtrace::h2ee174b9d5954948
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   39:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hf60bee9c1a17ef09
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   40:     0x561539fb8d77 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h33d0dddec5216160
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   41:     0x561539fb8d77 - std::panicking::try::do_call::h144b3048b546f835
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   42:     0x561539fb8d77 - std::panicking::try::h978244d04646189f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   43:     0x561539fb8d77 - std::panic::catch_unwind::hdf436369d188fe86
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   44:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::h87329cbdd33c79ec
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   45:     0x561539fb8d77 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h401abbe67897d450
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5d7c8f150ab9c84c
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   47:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h570d5b005c242c15
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   48:     0x56153a01f3b5 - std::sys::unix::thread::Thread::new::thread_start::h36b0a20c0b2361ad
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   49:     0x7f4945bdeac3 - <unknown>
[INFO] [stdout]   50:     0x7f4945c6fbf4 - __clone
[INFO] [stdout]   51:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::custom_sink_is_called stdout ----
[INFO] [stdout] thread 'tests::custom_sink_is_called' panicked at src/lib.rs:139:42:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56153a0184d6 - std::backtrace_rs::backtrace::libunwind::trace::h261606ad26a499c1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x56153a0184d6 - std::backtrace_rs::backtrace::trace_unsynchronized::hd183f0625477fb28
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x56153a0184d6 - std::sys_common::backtrace::_print_fmt::hd7f8fef21b4eda67
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x56153a0184d6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h623d9b51f2f453f1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x56153a041a50 - core::fmt::rt::Argument::fmt::h9f417ac76a86f368
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x56153a041a50 - core::fmt::write::hc9b6faf4ac2aeff7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x56153a015d2f - std::io::Write::write_fmt::he04625a3a989fec3
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x56153a0182b4 - std::sys_common::backtrace::_print::hd5d66399c7df88a2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x56153a0182b4 - std::sys_common::backtrace::print::he81ed2da642d37e5
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x56153a01a027 - std::panicking::default_hook::{{closure}}::h85773f4fa117163d
[INFO] [stdout]   10:     0x56153a019d0d - std::panicking::default_hook::h6a1694a91d09b278
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x561539fe8387 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h616ac37fa3cd6c8f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x561539fe8387 - test::test_main::{{closure}}::h00d84697cefdcbb6
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x56153a01a646 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hcb3fe3b9bb537ba8
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x56153a01a646 - std::panicking::rust_panic_with_hook::h0a7ad96e90f7145b
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x56153a01a392 - std::panicking::begin_panic_handler::{{closure}}::h7919090525c6485e
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x56153a0189d6 - std::sys_common::backtrace::__rust_end_short_backtrace::hf3289479464f7dfe
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x56153a01a0f0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x561539f56f05 - core::panicking::panic_fmt::hcc9f77c5c132af55
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x561539f574a3 - core::result::unwrap_failed::hbe14f498be1452f9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1649:5
[INFO] [stdout]   20:     0x561539f5d279 - core::result::Result<T,E>::unwrap::ha710833beabe1d3d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1073:23
[INFO] [stdout]   21:     0x561539f61376 - justlogfox::ensure_init_logger::h647aae62d5c5312e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:139:28
[INFO] [stdout]   22:     0x561539f6164d - justlogfox::log::h2297a3674ecfbff8
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:149:17
[INFO] [stdout]   23:     0x561539f6bfa8 - justlogfox::tests::custom_sink_is_called::h3c7907dce8726e01
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:324:9
[INFO] [stdout]   24:     0x561539f6bd27 - justlogfox::tests::custom_sink_is_called::{{closure}}::h109811c2b655a145
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:318:31
[INFO] [stdout]   25:     0x561539f643b6 - core::ops::function::FnOnce::call_once::h1e29bab312814590
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x561539fedc5f - core::ops::function::FnOnce::call_once::hd64d26f405258157
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x561539fedc5f - test::__rust_begin_short_backtrace::he21b0b7a6d731bf9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:627:18
[INFO] [stdout]   28:     0x561539fec9e1 - test::run_test_in_process::{{closure}}::hf5c73986d7bdfde2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:60
[INFO] [stdout]   29:     0x561539fec9e1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h330011ad032aadc0
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   30:     0x561539fec9e1 - std::panicking::try::do_call::he61b0a958b795a41
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   31:     0x561539fec9e1 - std::panicking::try::h7309da130f906eb7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   32:     0x561539fec9e1 - std::panic::catch_unwind::hc5c3a06fdaf0a92a
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   33:     0x561539fec9e1 - test::run_test_in_process::h7e7b7c71c3324e0f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:27
[INFO] [stdout]   34:     0x561539fec9e1 - test::run_test::{{closure}}::h1f509f28f5cf20b1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:573:43
[INFO] [stdout]   35:     0x561539fb3d16 - test::run_test::{{closure}}::h1754218abf443f6d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:601:41
[INFO] [stdout]   36:     0x561539fb3d16 - std::sys_common::backtrace::__rust_begin_short_backtrace::h2ee174b9d5954948
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   37:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hf60bee9c1a17ef09
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   38:     0x561539fb8d77 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h33d0dddec5216160
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   39:     0x561539fb8d77 - std::panicking::try::do_call::h144b3048b546f835
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   40:     0x561539fb8d77 - std::panicking::try::h978244d04646189f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   41:     0x561539fb8d77 - std::panic::catch_unwind::hdf436369d188fe86
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   42:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::h87329cbdd33c79ec
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   43:     0x561539fb8d77 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h401abbe67897d450
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5d7c8f150ab9c84c
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   45:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h570d5b005c242c15
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   46:     0x56153a01f3b5 - std::sys::unix::thread::Thread::new::thread_start::h36b0a20c0b2361ad
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   47:     0x7f4945bdeac3 - <unknown>
[INFO] [stdout]   48:     0x7f4945c6fbf4 - __clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::custom_sink_gets_info stdout ----
[INFO] [stdout] thread 'tests::custom_sink_gets_info' panicked at src/lib.rs:139:42:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56153a0184d6 - std::backtrace_rs::backtrace::libunwind::trace::h261606ad26a499c1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x56153a0184d6 - std::backtrace_rs::backtrace::trace_unsynchronized::hd183f0625477fb28
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x56153a0184d6 - std::sys_common::backtrace::_print_fmt::hd7f8fef21b4eda67
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x56153a0184d6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h623d9b51f2f453f1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x56153a041a50 - core::fmt::rt::Argument::fmt::h9f417ac76a86f368
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x56153a041a50 - core::fmt::write::hc9b6faf4ac2aeff7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x56153a015d2f - std::io::Write::write_fmt::he04625a3a989fec3
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x56153a0182b4 - std::sys_common::backtrace::_print::hd5d66399c7df88a2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x56153a0182b4 - std::sys_common::backtrace::print::he81ed2da642d37e5
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x56153a01a027 - std::panicking::default_hook::{{closure}}::h85773f4fa117163d
[INFO] [stdout]   10:     0x56153a019d0d - std::panicking::default_hook::h6a1694a91d09b278
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x561539fe8387 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h616ac37fa3cd6c8f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x561539fe8387 - test::test_main::{{closure}}::h00d84697cefdcbb6
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x56153a01a646 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hcb3fe3b9bb537ba8
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x56153a01a646 - std::panicking::rust_panic_with_hook::h0a7ad96e90f7145b
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x56153a01a392 - std::panicking::begin_panic_handler::{{closure}}::h7919090525c6485e
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x56153a0189d6 - std::sys_common::backtrace::__rust_end_short_backtrace::hf3289479464f7dfe
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x56153a01a0f0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x561539f56f05 - core::panicking::panic_fmt::hcc9f77c5c132af55
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x561539f574a3 - core::result::unwrap_failed::hbe14f498be1452f9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1649:5
[INFO] [stdout]   20:     0x561539f5d279 - core::result::Result<T,E>::unwrap::ha710833beabe1d3d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1073:23
[INFO] [stdout]   21:     0x561539f61376 - justlogfox::ensure_init_logger::h647aae62d5c5312e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:139:28
[INFO] [stdout]   22:     0x561539f6164d - justlogfox::log::h2297a3674ecfbff8
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:149:17
[INFO] [stdout]   23:     0x561539f6bad8 - justlogfox::tests::custom_sink_gets_info::h38a0f68caf86d471
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:312:9
[INFO] [stdout]   24:     0x561539f6b857 - justlogfox::tests::custom_sink_gets_info::{{closure}}::hf359d30939763356
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:306:31
[INFO] [stdout]   25:     0x561539f64476 - core::ops::function::FnOnce::call_once::h558a18ba3cdca0f9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x561539fedc5f - core::ops::function::FnOnce::call_once::hd64d26f405258157
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x561539fedc5f - test::__rust_begin_short_backtrace::he21b0b7a6d731bf9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:627:18
[INFO] [stdout]   28:     0x561539fec9e1 - test::run_test_in_process::{{closure}}::hf5c73986d7bdfde2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:60
[INFO] [stdout]   29:     0x561539fec9e1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h330011ad032aadc0
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   30:     0x561539fec9e1 - std::panicking::try::do_call::he61b0a958b795a41
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   31:     0x561539fec9e1 - std::panicking::try::h7309da130f906eb7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   32:     0x561539fec9e1 - std::panic::catch_unwind::hc5c3a06fdaf0a92a
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   33:     0x561539fec9e1 - test::run_test_in_process::h7e7b7c71c3324e0f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:27
[INFO] [stdout]   34:     0x561539fec9e1 - test::run_test::{{closure}}::h1f509f28f5cf20b1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:573:43
[INFO] [stdout]   35:     0x561539fb3d16 - test::run_test::{{closure}}::h1754218abf443f6d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:601:41
[INFO] [stdout]   36:     0x561539fb3d16 - std::sys_common::backtrace::__rust_begin_short_backtrace::h2ee174b9d5954948
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   37:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hf60bee9c1a17ef09
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   38:     0x561539fb8d77 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h33d0dddec5216160
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   39:     0x561539fb8d77 - std::panicking::try::do_call::h144b3048b546f835
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   40:     0x561539fb8d77 - std::panicking::try::h978244d04646189f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   41:     0x561539fb8d77 - std::panic::catch_unwind::hdf436369d188fe86
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   42:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::h87329cbdd33c79ec
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   43:     0x561539fb8d77 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h401abbe67897d450
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5d7c8f150ab9c84c
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   45:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h570d5b005c242c15
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   46:     0x56153a01f3b5 - std::sys::unix::thread::Thread::new::thread_start::h36b0a20c0b2361ad
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   47:     0x7f4945bdeac3 - <unknown>
[INFO] [stdout]   48:     0x7f4945c6fbf4 - __clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::parallel_logging stdout ----
[INFO] [stdout] thread 'tests::parallel_logging' panicked at src/lib.rs:139:42:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56153a0184d6 - std::backtrace_rs::backtrace::libunwind::trace::h261606ad26a499c1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x56153a0184d6 - std::backtrace_rs::backtrace::trace_unsynchronized::hd183f0625477fb28
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x56153a0184d6 - std::sys_common::backtrace::_print_fmt::hd7f8fef21b4eda67
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x56153a0184d6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h623d9b51f2f453f1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x56153a041a50 - core::fmt::rt::Argument::fmt::h9f417ac76a86f368
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x56153a041a50 - core::fmt::write::hc9b6faf4ac2aeff7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x56153a015d2f - std::io::Write::write_fmt::he04625a3a989fec3
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x56153a0182b4 - std::sys_common::backtrace::_print::hd5d66399c7df88a2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x56153a0182b4 - std::sys_common::backtrace::print::he81ed2da642d37e5
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x56153a01a027 - std::panicking::default_hook::{{closure}}::h85773f4fa117163d
[INFO] [stdout]   10:     0x56153a019d0d - std::panicking::default_hook::h6a1694a91d09b278
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x561539fe8387 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h616ac37fa3cd6c8f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x561539fe8387 - test::test_main::{{closure}}::h00d84697cefdcbb6
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x56153a01a646 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hcb3fe3b9bb537ba8
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x56153a01a646 - std::panicking::rust_panic_with_hook::h0a7ad96e90f7145b
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x56153a01a392 - std::panicking::begin_panic_handler::{{closure}}::h7919090525c6485e
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x56153a0189d6 - std::sys_common::backtrace::__rust_end_short_backtrace::hf3289479464f7dfe
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x56153a01a0f0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x561539f56f05 - core::panicking::panic_fmt::hcc9f77c5c132af55
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x561539f574a3 - core::result::unwrap_failed::hbe14f498be1452f9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1649:5
[INFO] [stdout]   20:     0x561539f5d279 - core::result::Result<T,E>::unwrap::ha710833beabe1d3d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/result.rs:1073:23
[INFO] [stdout]   21:     0x561539f61376 - justlogfox::ensure_init_logger::h647aae62d5c5312e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:139:28
[INFO] [stdout]   22:     0x561539f61d86 - justlogfox::add_logger::hebd7859f39e01f66
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:167:21
[INFO] [stdout]   23:     0x561539f6cc4c - justlogfox::tests::parallel_logging::hd5652731255dd07c
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:364:9
[INFO] [stdout]   24:     0x561539f6cc27 - justlogfox::tests::parallel_logging::{{closure}}::hc699fccc36e9996c
[INFO] [stdout]                                at /opt/rustwide/workdir/src/lib.rs:360:26
[INFO] [stdout]   25:     0x561539f64656 - core::ops::function::FnOnce::call_once::hb3314f379537fadb
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x561539fedc5f - core::ops::function::FnOnce::call_once::hd64d26f405258157
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x561539fedc5f - test::__rust_begin_short_backtrace::he21b0b7a6d731bf9
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:627:18
[INFO] [stdout]   28:     0x561539fec9e1 - test::run_test_in_process::{{closure}}::hf5c73986d7bdfde2
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:60
[INFO] [stdout]   29:     0x561539fec9e1 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h330011ad032aadc0
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   30:     0x561539fec9e1 - std::panicking::try::do_call::he61b0a958b795a41
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   31:     0x561539fec9e1 - std::panicking::try::h7309da130f906eb7
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   32:     0x561539fec9e1 - std::panic::catch_unwind::hc5c3a06fdaf0a92a
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   33:     0x561539fec9e1 - test::run_test_in_process::h7e7b7c71c3324e0f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:650:27
[INFO] [stdout]   34:     0x561539fec9e1 - test::run_test::{{closure}}::h1f509f28f5cf20b1
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:573:43
[INFO] [stdout]   35:     0x561539fb3d16 - test::run_test::{{closure}}::h1754218abf443f6d
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/test/src/lib.rs:601:41
[INFO] [stdout]   36:     0x561539fb3d16 - std::sys_common::backtrace::__rust_begin_short_backtrace::h2ee174b9d5954948
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   37:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hf60bee9c1a17ef09
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   38:     0x561539fb8d77 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h33d0dddec5216160
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   39:     0x561539fb8d77 - std::panicking::try::do_call::h144b3048b546f835
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:552:40
[INFO] [stdout]   40:     0x561539fb8d77 - std::panicking::try::h978244d04646189f
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panicking.rs:516:19
[INFO] [stdout]   41:     0x561539fb8d77 - std::panic::catch_unwind::hdf436369d188fe86
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/panic.rs:142:14
[INFO] [stdout]   42:     0x561539fb8d77 - std::thread::Builder::spawn_unchecked_::{{closure}}::h87329cbdd33c79ec
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   43:     0x561539fb8d77 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h401abbe67897d450
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5d7c8f150ab9c84c
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   45:     0x56153a01f3b5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h570d5b005c242c15
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   46:     0x56153a01f3b5 - std::sys::unix::thread::Thread::new::thread_start::h36b0a20c0b2361ad
[INFO] [stdout]                                at /rustc/0e09125c6c3c2fd70d7de961bcf0e51575235fad/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   47:     0x7f4945bdeac3 - <unknown>
[INFO] [stdout]   48:     0x7f4945c6fbf4 - __clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::custom_sink_gets_info
[INFO] [stdout]     tests::custom_sink_is_called
[INFO] [stdout]     tests::filters_level
[INFO] [stdout]     tests::parallel_logging
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 4 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" "e6bf442790bc7345b112b93b28c73d80ed1c1434c2332efe3a8e56945e9cb8d4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e6bf442790bc7345b112b93b28c73d80ed1c1434c2332efe3a8e56945e9cb8d4", kill_on_drop: false }`
[INFO] [stdout] e6bf442790bc7345b112b93b28c73d80ed1c1434c2332efe3a8e56945e9cb8d4
