[INFO] cloning repository soenkehahn/tracing [INFO] running `"git" "clone" "--bare" "git://github.com/soenkehahn/tracing.git" "work/cache/sources/gh/soenkehahn/tracing"` [INFO] [stderr] Cloning into bare repository 'work/cache/sources/gh/soenkehahn/tracing'... [INFO] running `"git" "clone" "work/cache/sources/gh/soenkehahn/tracing" "work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/soenkehahn/tracing"` [INFO] [stderr] Cloning into 'work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/soenkehahn/tracing'... [INFO] [stderr] done. [INFO] running `"git" "clone" "work/cache/sources/gh/soenkehahn/tracing" "work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing"` [INFO] [stderr] Cloning into 'work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing'... [INFO] [stderr] done. [INFO] running `"git" "rev-parse" "HEAD"` [INFO] [stdout] b793582ae575f144d7aab954d459664c1f558489 [INFO] sha for GitHub repo soenkehahn/tracing: b793582ae575f144d7aab954d459664c1f558489 [INFO] validating manifest of soenkehahn/tracing on toolchain master#c8865d8e195813ade6b84434ac9f8850e7112d1a [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] validating manifest of soenkehahn/tracing on toolchain try#e212b7d492c96d3837691ba29d32d5f468933fb8 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started frobbing soenkehahn/tracing [INFO] finished frobbing soenkehahn/tracing [INFO] frobbed toml for soenkehahn/tracing written to work/ex/pr-61629/sources/master#c8865d8e195813ade6b84434ac9f8850e7112d1a/gh/soenkehahn/tracing/Cargo.toml [INFO] started frobbing soenkehahn/tracing [INFO] finished frobbing soenkehahn/tracing [INFO] frobbed toml for soenkehahn/tracing written to work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing/Cargo.toml [INFO] crate soenkehahn/tracing already has a lockfile, it will not be regenerated [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c8865d8e195813ade6b84434ac9f8850e7112d1a-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded spawn-ptrace v0.1.1 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] testing soenkehahn/tracing against try#e212b7d492c96d3837691ba29d32d5f468933fb8 for pr-61629 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "build" "--frozen"` [INFO] [stdout] 2ce27aa0b90a2c7aeb8e2a0452528b0e56fd662597b23c2f6cc5c2d130e4203d [INFO] running `"docker" "start" "-a" "2ce27aa0b90a2c7aeb8e2a0452528b0e56fd662597b23c2f6cc5c2d130e4203d"` [INFO] [stderr] Compiling indextree v1.3.0 [INFO] [stderr] Compiling nix v0.8.1 [INFO] [stderr] Compiling nix v0.7.0 [INFO] [stderr] Compiling tempfile v3.0.5 [INFO] [stderr] Compiling spawn-ptrace v0.1.1 [INFO] [stderr] Compiling tracing v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 10.57s [INFO] running `"docker" "inspect" "2ce27aa0b90a2c7aeb8e2a0452528b0e56fd662597b23c2f6cc5c2d130e4203d"` [INFO] running `"docker" "rm" "-f" "2ce27aa0b90a2c7aeb8e2a0452528b0e56fd662597b23c2f6cc5c2d130e4203d"` [INFO] [stdout] 2ce27aa0b90a2c7aeb8e2a0452528b0e56fd662597b23c2f6cc5c2d130e4203d [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen" "--no-run"` [INFO] [stdout] e49270d10e11abe558405aa1dd927df184d66e5964ea5cd5f7594dbba7004f48 [INFO] running `"docker" "start" "-a" "e49270d10e11abe558405aa1dd927df184d66e5964ea5cd5f7594dbba7004f48"` [INFO] [stderr] Compiling tracing v0.1.0 (/opt/crater/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.71s [INFO] running `"docker" "inspect" "e49270d10e11abe558405aa1dd927df184d66e5964ea5cd5f7594dbba7004f48"` [INFO] running `"docker" "rm" "-f" "e49270d10e11abe558405aa1dd927df184d66e5964ea5cd5f7594dbba7004f48"` [INFO] [stdout] e49270d10e11abe558405aa1dd927df184d66e5964ea5cd5f7594dbba7004f48 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-61629/worker-3/try#e212b7d492c96d3837691ba29d32d5f468933fb8:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-61629/sources/try#e212b7d492c96d3837691ba29d32d5f468933fb8/gh/soenkehahn/tracing:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+e212b7d492c96d3837691ba29d32d5f468933fb8-alt" "test" "--frozen"` [INFO] [stdout] 791760923aa0bc8ac7b6a36c464f82a67d75507cc613ac0fcafecd430a70c990 [INFO] running `"docker" "start" "-a" "791760923aa0bc8ac7b6a36c464f82a67d75507cc613ac0fcafecd430a70c990"` [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.03s [INFO] [stderr] Running /opt/crater/target/debug/deps/tracing-391c2b990835bf5c [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test run::test::format::outputs_arguments ... ok [INFO] [stdout] test run::test::format::outputs_executables ... ok [INFO] [stdout] test test::trace::includes_process_arguments ... FAILED [INFO] [stderr] error: test failed, to rerun pass '--lib' [INFO] [stdout] test test::trace::returns_a_called_child_process ... FAILED [INFO] [stdout] test test::trace::returns_multiple_called_child_processes ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test::trace::includes_process_arguments stdout ---- [INFO] [stdout] thread 'test::trace::includes_process_arguments' panicked at 'called `Result::unwrap()` on an `Err` value: AppError("Error spawning process: Os { code: 1, kind: PermissionDenied, message: \"Operation not permitted\" }")', src/libcore/result.rs:999:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55d137f22b1b - backtrace::backtrace::libunwind::trace::h98ebe34dc63b52e3 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/libunwind.rs:88 [INFO] [stdout] 1: 0x55d137f22b1b - backtrace::backtrace::trace_unsynchronized::hd041341a38fdc1ca [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55d137f22b1b - std::sys_common::backtrace::_print::hc8c584aa09df5c15 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:47 [INFO] [stdout] 3: 0x55d137f22b1b - std::sys_common::backtrace::print::h4828f56ae571c1ad [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:36 [INFO] [stdout] 4: 0x55d137f22b1b - std::panicking::default_hook::{{closure}}::h69fce690c9f4b3d3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 5: 0x55d137f227af - std::panicking::default_hook::h231e0e11400caad4 [INFO] [stdout] at src/libstd/panicking.rs:209 [INFO] [stdout] 6: 0x55d137f23290 - std::panicking::rust_panic_with_hook::h8c0d039880f491f2 [INFO] [stdout] at src/libstd/panicking.rs:475 [INFO] [stdout] 7: 0x55d137f22e12 - std::panicking::continue_panic_fmt::hd71af4da4e8103ab [INFO] [stdout] at src/libstd/panicking.rs:382 [INFO] [stdout] 8: 0x55d137f22cf6 - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:309 [INFO] [stdout] 9: 0x55d137f3e0ad - core::panicking::panic_fmt::h5b6e1f42b8cc12be [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 10: 0x55d137e709b3 - core::result::unwrap_failed::h0767137357697dc7 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/macros.rs:18 [INFO] [stdout] 11: 0x55d137e71d8c - core::result::Result::unwrap::h088c9122be780cfb [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/result.rs:800 [INFO] [stdout] 12: 0x55d137e8c296 - tracing::test::trace::includes_process_arguments::h832b227dea617863 [INFO] [stdout] at src/lib.rs:80 [INFO] [stdout] 13: 0x55d137e8c1fa - tracing::test::trace::includes_process_arguments::{{closure}}::h36d114fcbb4009ee [INFO] [stdout] at src/lib.rs:78 [INFO] [stdout] 14: 0x55d137e74a6e - core::ops::function::FnOnce::call_once::h3fa0ea21ff44293a [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 15: 0x55d137e9ea0f - as core::ops::function::FnOnce>::call_once::h7d12c58d88f85f52 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 16: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 17: 0x55d137eb93e8 - std::panicking::try::h5ed77697f4b9cf5a [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 18: 0x55d137eb93e8 - std::panic::catch_unwind::hc3df70fd601bf96f [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 19: 0x55d137eb93e8 - test::run_test::run_test_inner::{{closure}}::haf52e514bd7b7a7a [INFO] [stdout] at src/libtest/lib.rs:1466 [INFO] [stdout] 20: 0x55d137e93f85 - std::sys_common::backtrace::__rust_begin_short_backtrace::he61de6f42984ba1b [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/sys_common/backtrace.rs:77 [INFO] [stdout] 21: 0x55d137e97f85 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h583331a58084cfa6 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:470 [INFO] [stdout] 22: 0x55d137e97f85 - as core::ops::function::FnOnce<()>>::call_once::h730d3f044d244375 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:309 [INFO] [stdout] 23: 0x55d137e97f85 - std::panicking::try::do_call::h11328ad090f12422 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:294 [INFO] [stdout] 24: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 25: 0x55d137e98612 - std::panicking::try::hd00f486f5358e439 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 26: 0x55d137e98612 - std::panic::catch_unwind::h78adeaedb9e59cb0 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 27: 0x55d137e98612 - std::thread::Builder::spawn_unchecked::{{closure}}::ha2637f22a0fb0434 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:469 [INFO] [stdout] 28: 0x55d137e98612 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h86e6656f4f46649d [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 29: 0x55d137f1734f - as core::ops::function::FnOnce>::call_once::h146d737fe1c8556c [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 30: 0x55d137f2a310 - as core::ops::function::FnOnce>::call_once::h44ec2de830c4be09 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 31: 0x55d137f2a310 - std::sys_common::thread::start_thread::hed7ed434f3e17f57 [INFO] [stdout] at src/libstd/sys_common/thread.rs:13 [INFO] [stdout] 32: 0x55d137f2a310 - std::sys::unix::thread::Thread::new::thread_start::h62b89907378dc35b [INFO] [stdout] at src/libstd/sys/unix/thread.rs:79 [INFO] [stdout] 33: 0x7f59b0d664a4 - start_thread [INFO] [stdout] 34: 0x7f59b0891d0f - __clone [INFO] [stdout] 35: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- test::trace::returns_a_called_child_process stdout ---- [INFO] [stdout] thread 'test::trace::returns_a_called_child_process' panicked at 'called `Result::unwrap()` on an `Err` value: AppError("Error spawning process: Os { code: 1, kind: PermissionDenied, message: \"Operation not permitted\" }")', src/libcore/result.rs:999:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55d137f22b1b - backtrace::backtrace::libunwind::trace::h98ebe34dc63b52e3 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/libunwind.rs:88 [INFO] [stdout] 1: 0x55d137f22b1b - backtrace::backtrace::trace_unsynchronized::hd041341a38fdc1ca [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55d137f22b1b - std::sys_common::backtrace::_print::hc8c584aa09df5c15 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:47 [INFO] [stdout] 3: 0x55d137f22b1b - std::sys_common::backtrace::print::h4828f56ae571c1ad [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:36 [INFO] [stdout] 4: 0x55d137f22b1b - std::panicking::default_hook::{{closure}}::h69fce690c9f4b3d3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 5: 0x55d137f227af - std::panicking::default_hook::h231e0e11400caad4 [INFO] [stdout] at src/libstd/panicking.rs:209 [INFO] [stdout] 6: 0x55d137f23290 - std::panicking::rust_panic_with_hook::h8c0d039880f491f2 [INFO] [stdout] at src/libstd/panicking.rs:475 [INFO] [stdout] 7: 0x55d137f22e12 - std::panicking::continue_panic_fmt::hd71af4da4e8103ab [INFO] [stdout] at src/libstd/panicking.rs:382 [INFO] [stdout] 8: 0x55d137f22cf6 - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:309 [INFO] [stdout] 9: 0x55d137f3e0ad - core::panicking::panic_fmt::h5b6e1f42b8cc12be [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 10: 0x55d137e709b3 - core::result::unwrap_failed::h0767137357697dc7 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/macros.rs:18 [INFO] [stdout] 11: 0x55d137e71d8c - core::result::Result::unwrap::h088c9122be780cfb [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/result.rs:800 [INFO] [stdout] 12: 0x55d137e8b93a - tracing::test::trace::returns_a_called_child_process::h3c8539acb5197e17 [INFO] [stdout] at src/lib.rs:59 [INFO] [stdout] 13: 0x55d137e8b8aa - tracing::test::trace::returns_a_called_child_process::{{closure}}::hc2d195b7d2f8f449 [INFO] [stdout] at src/lib.rs:57 [INFO] [stdout] 14: 0x55d137e74b6e - core::ops::function::FnOnce::call_once::he162421f5a9be687 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 15: 0x55d137e9ea0f - as core::ops::function::FnOnce>::call_once::h7d12c58d88f85f52 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 16: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 17: 0x55d137eb93e8 - std::panicking::try::h5ed77697f4b9cf5a [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 18: 0x55d137eb93e8 - std::panic::catch_unwind::hc3df70fd601bf96f [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 19: 0x55d137eb93e8 - test::run_test::run_test_inner::{{closure}}::haf52e514bd7b7a7a [INFO] [stdout] at src/libtest/lib.rs:1466 [INFO] [stdout] 20: 0x55d137e93f85 - std::sys_common::backtrace::__rust_begin_short_backtrace::he61de6f42984ba1b [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/sys_common/backtrace.rs:77 [INFO] [stdout] 21: 0x55d137e97f85 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h583331a58084cfa6 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:470 [INFO] [stdout] 22: 0x55d137e97f85 - as core::ops::function::FnOnce<()>>::call_once::h730d3f044d244375 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:309 [INFO] [stdout] 23: 0x55d137e97f85 - std::panicking::try::do_call::h11328ad090f12422 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:294 [INFO] [stdout] 24: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 25: 0x55d137e98612 - std::panicking::try::hd00f486f5358e439 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 26: 0x55d137e98612 - std::panic::catch_unwind::h78adeaedb9e59cb0 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 27: 0x55d137e98612 - std::thread::Builder::spawn_unchecked::{{closure}}::ha2637f22a0fb0434 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:469 [INFO] [stdout] 28: 0x55d137e98612 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h86e6656f4f46649d [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 29: 0x55d137f1734f - as core::ops::function::FnOnce>::call_once::h146d737fe1c8556c [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 30: 0x55d137f2a310 - as core::ops::function::FnOnce>::call_once::h44ec2de830c4be09 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 31: 0x55d137f2a310 - std::sys_common::thread::start_thread::hed7ed434f3e17f57 [INFO] [stdout] at src/libstd/sys_common/thread.rs:13 [INFO] [stdout] 32: 0x55d137f2a310 - std::sys::unix::thread::Thread::new::thread_start::h62b89907378dc35b [INFO] [stdout] at src/libstd/sys/unix/thread.rs:79 [INFO] [stdout] 33: 0x7f59b0d664a4 - start_thread [INFO] [stdout] 34: 0x7f59b0891d0f - __clone [INFO] [stdout] 35: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- test::trace::returns_multiple_called_child_processes stdout ---- [INFO] [stdout] thread 'test::trace::returns_multiple_called_child_processes' panicked at 'called `Result::unwrap()` on an `Err` value: AppError("Error spawning process: Os { code: 1, kind: PermissionDenied, message: \"Operation not permitted\" }")', src/libcore/result.rs:999:5 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55d137f22b1b - backtrace::backtrace::libunwind::trace::h98ebe34dc63b52e3 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/libunwind.rs:88 [INFO] [stdout] 1: 0x55d137f22b1b - backtrace::backtrace::trace_unsynchronized::hd041341a38fdc1ca [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.29/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55d137f22b1b - std::sys_common::backtrace::_print::hc8c584aa09df5c15 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:47 [INFO] [stdout] 3: 0x55d137f22b1b - std::sys_common::backtrace::print::h4828f56ae571c1ad [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:36 [INFO] [stdout] 4: 0x55d137f22b1b - std::panicking::default_hook::{{closure}}::h69fce690c9f4b3d3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 5: 0x55d137f227af - std::panicking::default_hook::h231e0e11400caad4 [INFO] [stdout] at src/libstd/panicking.rs:209 [INFO] [stdout] 6: 0x55d137f23290 - std::panicking::rust_panic_with_hook::h8c0d039880f491f2 [INFO] [stdout] at src/libstd/panicking.rs:475 [INFO] [stdout] 7: 0x55d137f22e12 - std::panicking::continue_panic_fmt::hd71af4da4e8103ab [INFO] [stdout] at src/libstd/panicking.rs:382 [INFO] [stdout] 8: 0x55d137f22cf6 - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:309 [INFO] [stdout] 9: 0x55d137f3e0ad - core::panicking::panic_fmt::h5b6e1f42b8cc12be [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 10: 0x55d137e709b3 - core::result::unwrap_failed::h0767137357697dc7 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/macros.rs:18 [INFO] [stdout] 11: 0x55d137e71d8c - core::result::Result::unwrap::h088c9122be780cfb [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/result.rs:800 [INFO] [stdout] 12: 0x55d137e8bd06 - tracing::test::trace::returns_multiple_called_child_processes::h235fd31fb10b63e4 [INFO] [stdout] at src/lib.rs:68 [INFO] [stdout] 13: 0x55d137e8bc6a - tracing::test::trace::returns_multiple_called_child_processes::{{closure}}::h69856f64fb4c57f7 [INFO] [stdout] at src/lib.rs:66 [INFO] [stdout] 14: 0x55d137e74aae - core::ops::function::FnOnce::call_once::h4512523ef8fc55ea [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 15: 0x55d137e9ea0f - as core::ops::function::FnOnce>::call_once::h7d12c58d88f85f52 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 16: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 17: 0x55d137eb93e8 - std::panicking::try::h5ed77697f4b9cf5a [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 18: 0x55d137eb93e8 - std::panic::catch_unwind::hc3df70fd601bf96f [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 19: 0x55d137eb93e8 - test::run_test::run_test_inner::{{closure}}::haf52e514bd7b7a7a [INFO] [stdout] at src/libtest/lib.rs:1466 [INFO] [stdout] 20: 0x55d137e93f85 - std::sys_common::backtrace::__rust_begin_short_backtrace::he61de6f42984ba1b [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/sys_common/backtrace.rs:77 [INFO] [stdout] 21: 0x55d137e97f85 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h583331a58084cfa6 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:470 [INFO] [stdout] 22: 0x55d137e97f85 - as core::ops::function::FnOnce<()>>::call_once::h730d3f044d244375 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:309 [INFO] [stdout] 23: 0x55d137e97f85 - std::panicking::try::do_call::h11328ad090f12422 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:294 [INFO] [stdout] 24: 0x55d137f2abca - __rust_maybe_catch_panic [INFO] [stdout] at src/libpanic_unwind/lib.rs:85 [INFO] [stdout] 25: 0x55d137e98612 - std::panicking::try::hd00f486f5358e439 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panicking.rs:273 [INFO] [stdout] 26: 0x55d137e98612 - std::panic::catch_unwind::h78adeaedb9e59cb0 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/panic.rs:388 [INFO] [stdout] 27: 0x55d137e98612 - std::thread::Builder::spawn_unchecked::{{closure}}::ha2637f22a0fb0434 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libstd/thread/mod.rs:469 [INFO] [stdout] 28: 0x55d137e98612 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h86e6656f4f46649d [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/libcore/ops/function.rs:231 [INFO] [stdout] 29: 0x55d137f1734f - as core::ops::function::FnOnce>::call_once::h146d737fe1c8556c [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 30: 0x55d137f2a310 - as core::ops::function::FnOnce>::call_once::h44ec2de830c4be09 [INFO] [stdout] at /rustc/e212b7d492c96d3837691ba29d32d5f468933fb8/src/liballoc/boxed.rs:746 [INFO] [stdout] 31: 0x55d137f2a310 - std::sys_common::thread::start_thread::hed7ed434f3e17f57 [INFO] [stdout] at src/libstd/sys_common/thread.rs:13 [INFO] [stdout] 32: 0x55d137f2a310 - std::sys::unix::thread::Thread::new::thread_start::h62b89907378dc35b [INFO] [stdout] at src/libstd/sys/unix/thread.rs:79 [INFO] [stdout] 33: 0x7f59b0d664a4 - start_thread [INFO] [stdout] 34: 0x7f59b0891d0f - __clone [INFO] [stdout] 35: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test::trace::includes_process_arguments [INFO] [stdout] test::trace::returns_a_called_child_process [INFO] [stdout] test::trace::returns_multiple_called_child_processes [INFO] [stdout] [INFO] [stdout] test result: FAILED. 2 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "791760923aa0bc8ac7b6a36c464f82a67d75507cc613ac0fcafecd430a70c990"` [INFO] running `"docker" "rm" "-f" "791760923aa0bc8ac7b6a36c464f82a67d75507cc613ac0fcafecd430a70c990"` [INFO] [stdout] 791760923aa0bc8ac7b6a36c464f82a67d75507cc613ac0fcafecd430a70c990