[INFO] crate loggy 0.3.2 is already in cache [INFO] testing loggy-0.3.2 against try#41997647ba6a77908f6ef64401414feb00bccf16 for pr-71274 [INFO] extracting crate loggy 0.3.2 into /workspace/builds/worker-3/source [INFO] validating manifest of crates.io crate loggy 0.3.2 on toolchain 41997647ba6a77908f6ef64401414feb00bccf16 [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started tweaking crates.io crate loggy 0.3.2 [INFO] finished tweaking crates.io crate loggy 0.3.2 [INFO] tweaked toml for crates.io crate loggy 0.3.2 written to /workspace/builds/worker-3/source/Cargo.toml [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"` [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "build" "--frozen"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] a94aa7677aa08f1f87f81df140eec1c4f8ddd7a8155ed06e5ba53315377f17cd [INFO] running `"docker" "start" "-a" "a94aa7677aa08f1f87f81df140eec1c4f8ddd7a8155ed06e5ba53315377f17cd"` [INFO] [stderr] Compiling libc v0.2.69 [INFO] [stderr] Compiling log v0.4.8 [INFO] [stderr] Compiling cfg-if v0.1.10 [INFO] [stderr] Compiling time v0.1.42 [INFO] [stderr] Compiling loggy v0.3.2 (/opt/rustwide/workdir) [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:23:30 [INFO] [stderr] | [INFO] [stderr] 23 | use std::sync::{Mutex, Once, ONCE_INIT}; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:328:28 [INFO] [stderr] | [INFO] [stderr] 328 | static LOGGER_INIT: Once = ONCE_INIT; [INFO] [stderr] | ^^^^^^^^^ help: replace the use of the deprecated item: `Once::new()` [INFO] [stderr] [INFO] [stderr] warning: 2 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4.31s [INFO] running `"docker" "inspect" "a94aa7677aa08f1f87f81df140eec1c4f8ddd7a8155ed06e5ba53315377f17cd"` [INFO] running `"docker" "rm" "-f" "a94aa7677aa08f1f87f81df140eec1c4f8ddd7a8155ed06e5ba53315377f17cd"` [INFO] [stdout] a94aa7677aa08f1f87f81df140eec1c4f8ddd7a8155ed06e5ba53315377f17cd [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen" "--no-run"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 679dca615ec1c0860c8ef3b5e4ce363fadce8a6b4a35600a3d3bb27962b80c18 [INFO] running `"docker" "start" "-a" "679dca615ec1c0860c8ef3b5e4ce363fadce8a6b4a35600a3d3bb27962b80c18"` [INFO] [stderr] Compiling loggy v0.3.2 (/opt/rustwide/workdir) [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:23:30 [INFO] [stderr] | [INFO] [stderr] 23 | use std::sync::{Mutex, Once, ONCE_INIT}; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:328:28 [INFO] [stderr] | [INFO] [stderr] 328 | static LOGGER_INIT: Once = ONCE_INIT; [INFO] [stderr] | ^^^^^^^^^ help: replace the use of the deprecated item: `Once::new()` [INFO] [stderr] [INFO] [stderr] warning: 2 warnings emitted [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:23:30 [INFO] [stderr] | [INFO] [stderr] 23 | use std::sync::{Mutex, Once, ONCE_INIT}; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:328:28 [INFO] [stderr] | [INFO] [stderr] 328 | static LOGGER_INIT: Once = ONCE_INIT; [INFO] [stderr] | ^^^^^^^^^ help: replace the use of the deprecated item: `Once::new()` [INFO] [stderr] [INFO] [stderr] warning: 2 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 1.89s [INFO] running `"docker" "inspect" "679dca615ec1c0860c8ef3b5e4ce363fadce8a6b4a35600a3d3bb27962b80c18"` [INFO] running `"docker" "rm" "-f" "679dca615ec1c0860c8ef3b5e4ce363fadce8a6b4a35600a3d3bb27962b80c18"` [INFO] [stdout] 679dca615ec1c0860c8ef3b5e4ce363fadce8a6b4a35600a3d3bb27962b80c18 [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] e7edac538d0d5c1a246f78fa173e3c23eeaf9cb70b91496787bb97c1c4d35413 [INFO] running `"docker" "start" "-a" "e7edac538d0d5c1a246f78fa173e3c23eeaf9cb70b91496787bb97c1c4d35413"` [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:23:30 [INFO] [stderr] | [INFO] [stderr] 23 | use std::sync::{Mutex, Once, ONCE_INIT}; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:328:28 [INFO] [stderr] | [INFO] [stderr] 328 | static LOGGER_INIT: Once = ONCE_INIT; [INFO] [stderr] | ^^^^^^^^^ help: replace the use of the deprecated item: `Once::new()` [INFO] [stderr] [INFO] [stderr] warning: 2 warnings emitted [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:23:30 [INFO] [stderr] | [INFO] [stderr] 23 | use std::sync::{Mutex, Once, ONCE_INIT}; [INFO] [stderr] | ^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'std::sync::ONCE_INIT': the `new` function is now preferred [INFO] [stderr] --> src/lib.rs:328:28 [INFO] [stderr] | [INFO] [stderr] 328 | static LOGGER_INIT: Once = ONCE_INIT; [INFO] [stderr] | ^^^^^^^^^ help: replace the use of the deprecated item: `Once::new()` [INFO] [stderr] [INFO] [stderr] warning: 2 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.14s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/loggy-1d519e8b563ce841 [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/test_log-800470d93ebfcb48 [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test error_should_be_captured ... ok [INFO] [stdout] test info_should_be_captured ... ok [INFO] [stdout] test debug_should_not_be_captured ... ok [INFO] [stdout] test notice_should_be_controlled ... ok [INFO] [stderr] thread '' panicked at 'called `Result::unwrap()` on an `Err` value: "PoisonError { inner: .. }"', src/lib.rs:179:42 [INFO] [stderr] stack backtrace: [INFO] [stderr] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stderr] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stderr] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stderr] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stderr] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stderr] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stderr] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stderr] at src/libcore/fmt/mod.rs:1069 [INFO] [stderr] 5: 0x5614a0986ef3 - std::io::Write::write_fmt::hfb486fa6ccb90f14 [INFO] [stderr] at src/libstd/io/mod.rs:1504 [INFO] [stderr] 6: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stderr] 7: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stderr] 8: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stderr] at src/libstd/panicking.rs:198 [INFO] [stderr] 9: 0x5614a098c922 - std::panicking::default_hook::h9386f7e484840463 [INFO] [stderr] at src/libstd/panicking.rs:218 [INFO] [stderr] 10: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stderr] at src/libstd/panicking.rs:511 [INFO] [stderr] 11: 0x5614a098ce2b - rust_begin_unwind [INFO] [stderr] at src/libstd/panicking.rs:419 [INFO] [stderr] 12: 0x5614a09b22d1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stderr] at src/libcore/panicking.rs:111 [INFO] [stderr] 13: 0x5614a09b20f3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stderr] at src/libcore/option.rs:1268 [INFO] [stderr] 14: 0x5614a095cd20 - core::result::Result::unwrap::hdada4eef271500ca [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stderr] 15: 0x5614a0959ac0 - loggy::Loggy::append_prefix::{{closure}}::h23b8bd159f4be7df [INFO] [stderr] at src/lib.rs:179 [INFO] [stderr] 16: 0x5614a096247d - std::thread::local::LocalKey::try_with::hd172d6f72c422b1e [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/local.rs:263 [INFO] [stderr] 17: 0x5614a0961f14 - std::thread::local::LocalKey::with::hd7ec5a737ee6a8bc [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/local.rs:239 [INFO] [stderr] 18: 0x5614a0959591 - loggy::Loggy::append_prefix::h08ea04a633a5d9b2 [INFO] [stderr] at src/lib.rs:176 [INFO] [stderr] 19: 0x5614a09592ca - loggy::Loggy::format_message::h3ccb384bf4e258fb [INFO] [stderr] at src/lib.rs:166 [INFO] [stderr] 20: 0x5614a0958ce2 - ::log::h19f25737e373f2ec [INFO] [stderr] at src/lib.rs:130 [INFO] [stderr] 21: 0x5614a097b071 - log::__private_api_log::hb6feb931002704e7 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/log-0.4.8/src/lib.rs:1393 [INFO] [stderr] 22: 0x5614a091347c - test_log::errors_should_be_counted::{{closure}}::h5f449534ca0a1737 [INFO] [stderr] at tests/test_log.rs:116 [INFO] [stderr] 23: 0x5614a0914276 - std::sys_common::backtrace::__rust_begin_short_backtrace::h79feb7d2566c3107 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stderr] 24: 0x5614a09173e6 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h4685807c2a76a5c1 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stderr] 25: 0x5614a0914b19 - as core::ops::function::FnOnce<()>>::call_once::hd924c8f87a5803fd [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stderr] 26: 0x5614a091879c - std::panicking::try::do_call::h7fbf7b8ad998f269 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stderr] 27: 0x5614a09188ed - __rust_try [INFO] [stderr] 28: 0x5614a09186e4 - std::panicking::try::hf60dd71aef2d9c0e [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stderr] 29: 0x5614a0914b39 - std::panic::catch_unwind::h629c87a1afef13f0 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stderr] 30: 0x5614a0917228 - std::thread::Builder::spawn_unchecked::{{closure}}::hfd172770ec3e82c6 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stderr] 31: 0x5614a0914b88 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h10cffc143d949acc [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stderr] 32: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stderr] 33: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stderr] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stderr] 34: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stderr] at src/libstd/sys/unix/thread.rs:87 [INFO] [stderr] 35: 0x7f93a09d26db - start_thread [INFO] [stderr] 36: 0x7f93a04e388f - __clone [INFO] [stderr] 37: 0x0 - [INFO] [stdout] test notice_should_be_captured ... FAILED [INFO] [stdout] test worker_threads_should_be_reported ... FAILED [INFO] [stdout] test multi_line_should_be_captured ... FAILED [INFO] [stdout] test warning_should_be_captured ... FAILED [INFO] [stdout] test errors_should_be_counted ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- notice_should_be_captured stdout ---- [INFO] [stdout] ACTUAL LOG: [INFO] [stdout] test: [ERROR] test_log: error [INFO] [stdout] test: [WARN] test_log: warning [INFO] [stdout] test[1]: [ERROR] test_log: error [INFO] [stdout] test[1]: [error] test_log: continuation [INFO] [stdout] test[1]: [error] test_log: lines [INFO] [stdout] [INFO] [stdout] IS DIFFERENT FROM EXPECTED LOG: [INFO] [stdout] test: [ERROR] test_log: error [INFO] [stdout] test: [WARN] test_log: warning [INFO] [stdout] [INFO] [stdout] thread 'notice_should_be_captured' panicked at 'assertion failed: `(left == right)` [INFO] [stdout] left: `"ACTUAL LOG"`, [INFO] [stdout] right: `"EXPECTED LOG"`', src/lib.rs:277:17 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5614a091ab95 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x5614a0984851 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5614a098c8cb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x5614a098ce2b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x5614a098cd9b - std::panicking::begin_panic_fmt::h2fa40d03719daab1 [INFO] [stdout] at src/libstd/panicking.rs:373 [INFO] [stdout] 14: 0x5614a095a92b - loggy::assert_log::h96e17c227bf90037 [INFO] [stdout] at src/lib.rs:277 [INFO] [stdout] 15: 0x5614a0911efa - test_log::notice_should_be_captured::h7a50cc46f6c6553a [INFO] [stdout] at tests/test_log.rs:58 [INFO] [stdout] 16: 0x5614a0911cfa - test_log::notice_should_be_captured::{{closure}}::h527e5ebecf719c2d [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:360 [INFO] [stdout] 17: 0x5614a0914e0e - core::ops::function::FnOnce::call_once::hd39e8cd669262ece [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 18: 0x5614a0926a8f - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 19: 0x5614a094208e - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 20: 0x5614a094208e - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 21: 0x5614a094208e - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 22: 0x5614a094208e - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 23: 0x5614a094208e - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 24: 0x5614a094208e - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 25: 0x5614a091a046 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 26: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 27: 0x5614a091f3d5 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 28: 0x5614a091f3d5 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 29: 0x5614a091f3d5 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 30: 0x5614a091f3d5 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 31: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 32: 0x5614a091f3d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 33: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 34: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 35: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 36: 0x7f93a09d26db - start_thread [INFO] [stdout] 37: 0x7f93a04e388f - __clone [INFO] [stdout] 38: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- worker_threads_should_be_reported stdout ---- [INFO] [stdout] thread 'worker_threads_should_be_reported' panicked at 'called `Result::unwrap()` on an `Err` value: "PoisonError { inner: .. }"', src/lib.rs:241:27 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5614a091ab95 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x5614a0984851 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5614a098c8cb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x5614a098ce2b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x5614a09b22d1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x5614a09b20f3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5614a095cc70 - core::result::Result::unwrap::hda70979c122795ed [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5614a095a0cf - loggy::set_log_sink::h267bc4408d7d1973 [INFO] [stdout] at src/lib.rs:241 [INFO] [stdout] 17: 0x5614a095b16b - loggy::before_test::h465c9c7242b8c140 [INFO] [stdout] at src/lib.rs:393 [INFO] [stdout] 18: 0x5614a091204d - test_log::worker_threads_should_be_reported::hdd4c0446af74044f [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:361 [INFO] [stdout] 19: 0x5614a091202a - test_log::worker_threads_should_be_reported::{{closure}}::h71b83aa491e6f9d5 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:360 [INFO] [stdout] 20: 0x5614a0914cbe - core::ops::function::FnOnce::call_once::h4fa77bf373f9e1bb [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 21: 0x5614a0926a8f - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 22: 0x5614a094208e - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 23: 0x5614a094208e - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 24: 0x5614a094208e - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 25: 0x5614a094208e - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 26: 0x5614a094208e - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 27: 0x5614a094208e - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 28: 0x5614a091a046 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 29: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 30: 0x5614a091f3d5 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 31: 0x5614a091f3d5 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 32: 0x5614a091f3d5 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 33: 0x5614a091f3d5 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 34: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 35: 0x5614a091f3d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 36: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 37: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 38: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 39: 0x7f93a09d26db - start_thread [INFO] [stdout] 40: 0x7f93a04e388f - __clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- multi_line_should_be_captured stdout ---- [INFO] [stdout] thread 'multi_line_should_be_captured' panicked at 'called `Result::unwrap()` on an `Err` value: "PoisonError { inner: .. }"', src/lib.rs:264:27 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5614a091ab95 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x5614a0984851 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5614a098c8cb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x5614a098ce2b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x5614a09b22d1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x5614a09b20f3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5614a095cc70 - core::result::Result::unwrap::hda70979c122795ed [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5614a095a509 - loggy::assert_log::h96e17c227bf90037 [INFO] [stdout] at src/lib.rs:264 [INFO] [stdout] 17: 0x5614a091188f - test_log::multi_line_should_be_captured::h89e6354c8643dd04 [INFO] [stdout] at tests/test_log.rs:23 [INFO] [stdout] 18: 0x5614a091178a - test_log::multi_line_should_be_captured::{{closure}}::h796c127d910775a3 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:360 [INFO] [stdout] 19: 0x5614a0914dde - core::ops::function::FnOnce::call_once::hbcf7ce4914ae95b6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 20: 0x5614a0926a8f - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 21: 0x5614a094208e - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 22: 0x5614a094208e - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 23: 0x5614a094208e - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 24: 0x5614a094208e - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 25: 0x5614a094208e - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 26: 0x5614a094208e - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 27: 0x5614a091a046 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 28: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 29: 0x5614a091f3d5 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 30: 0x5614a091f3d5 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 31: 0x5614a091f3d5 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 32: 0x5614a091f3d5 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 33: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 34: 0x5614a091f3d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 35: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 37: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 38: 0x7f93a09d26db - start_thread [INFO] [stdout] 39: 0x7f93a04e388f - __clone [INFO] [stdout] 40: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- warning_should_be_captured stdout ---- [INFO] [stdout] thread 'warning_should_be_captured' panicked at 'called `Result::unwrap()` on an `Err` value: "PoisonError { inner: .. }"', src/lib.rs:379:30 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5614a091ab95 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x5614a0984851 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5614a098c8cb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x5614a098ce2b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x5614a09b22d1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x5614a09b20f3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5614a095cd20 - core::result::Result::unwrap::hdada4eef271500ca [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5614a095af82 - loggy::before_test::h465c9c7242b8c140 [INFO] [stdout] at src/lib.rs:379 [INFO] [stdout] 17: 0x5614a09118ca - test_log::warning_should_be_captured::h7b5678291e24f807 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:361 [INFO] [stdout] 18: 0x5614a09118aa - test_log::warning_should_be_captured::{{closure}}::h63e7d7abf3294b99 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:360 [INFO] [stdout] 19: 0x5614a0914c0e - core::ops::function::FnOnce::call_once::h021b0955617bfb7f [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 20: 0x5614a0926a8f - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 21: 0x5614a094208e - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 22: 0x5614a094208e - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 23: 0x5614a094208e - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 24: 0x5614a094208e - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 25: 0x5614a094208e - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 26: 0x5614a094208e - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 27: 0x5614a091a046 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 28: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 29: 0x5614a091f3d5 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 30: 0x5614a091f3d5 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 31: 0x5614a091f3d5 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 32: 0x5614a091f3d5 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 33: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 34: 0x5614a091f3d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 35: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 37: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 38: 0x7f93a09d26db - start_thread [INFO] [stdout] 39: 0x7f93a04e388f - __clone [INFO] [stdout] 40: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- errors_should_be_counted stdout ---- [INFO] [stdout] test[1]: [ERROR] test_log: outer [INFO] [stdout] thread 'errors_should_be_counted' panicked at 'called `Result::unwrap()` on an `Err` value: Any', tests/test_log.rs:121:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5614a098a314 - backtrace::backtrace::libunwind::trace::h61666fa4b9834a45 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5614a098a314 - backtrace::backtrace::trace_unsynchronized::h01d3725fb5da8653 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5614a098a314 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5614a098a314 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5614a09b38ac - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5614a091ab95 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x5614a0984851 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x5614a098cbe5 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5614a098cbe5 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5614a098cbe5 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5614a098c8cb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x5614a098d242 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x5614a098ce2b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x5614a09b22d1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x5614a09b20f3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5614a0919913 - core::result::Result::unwrap::hdfaab352cfb54e39 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5614a0912da6 - test_log::errors_should_be_counted::h199d001b48b06aa4 [INFO] [stdout] at tests/test_log.rs:121 [INFO] [stdout] 17: 0x5614a09123ba - test_log::errors_should_be_counted::{{closure}}::h949200b4332c0f37 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:360 [INFO] [stdout] 18: 0x5614a0914d7e - core::ops::function::FnOnce::call_once::h7803bf0cfdc6e4c0 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 19: 0x5614a0926a8f - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 20: 0x5614a094208e - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x5614a094208e - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 22: 0x5614a094208e - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 23: 0x5614a094208e - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x5614a094208e - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x5614a094208e - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5614a091a046 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5614a091f3d5 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5614a091f3d5 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 30: 0x5614a091f3d5 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 31: 0x5614a091f3d5 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5614a091f3d5 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5614a091f3d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 34: 0x5614a0980a3f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 35: 0x5614a0993a53 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x5614a0993a53 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f93a09d26db - start_thread [INFO] [stdout] 38: 0x7f93a04e388f - __clone [INFO] [stdout] 39: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] errors_should_be_counted [INFO] [stdout] multi_line_should_be_captured [INFO] [stdout] notice_should_be_captured [INFO] [stdout] warning_should_be_captured [INFO] [stdout] worker_threads_should_be_reported [INFO] [stdout] [INFO] [stdout] test result: FAILED. 4 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass '--test test_log' [INFO] running `"docker" "inspect" "e7edac538d0d5c1a246f78fa173e3c23eeaf9cb70b91496787bb97c1c4d35413"` [INFO] running `"docker" "rm" "-f" "e7edac538d0d5c1a246f78fa173e3c23eeaf9cb70b91496787bb97c1c4d35413"` [INFO] [stdout] e7edac538d0d5c1a246f78fa173e3c23eeaf9cb70b91496787bb97c1c4d35413