[INFO] updating cached repository https://github.com/soenkehahn/tracing [INFO] running `"git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "-c" "remote.origin.fetch=refs/heads/*:refs/heads/*" "fetch" "origin" "--force" "--prune"` [INFO] running `"git" "rev-parse" "HEAD"` [INFO] [stdout] b793582ae575f144d7aab954d459664c1f558489 [INFO] testing soenkehahn/tracing against try#41997647ba6a77908f6ef64401414feb00bccf16 for pr-71274 [INFO] running `"git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoenkehahn%2Ftracing" "/workspace/builds/worker-5/source"` [INFO] [stderr] Cloning into '/workspace/builds/worker-5/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/soenkehahn/tracing on toolchain 41997647ba6a77908f6ef64401414feb00bccf16 [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started tweaking git repo https://github.com/soenkehahn/tracing [INFO] finished tweaking git repo https://github.com/soenkehahn/tracing [INFO] tweaked toml for git repo https://github.com/soenkehahn/tracing written to /workspace/builds/worker-5/source/Cargo.toml [INFO] crate git repo https://github.com/soenkehahn/tracing already has a lockfile, it will not be regenerated [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-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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] d4459106051c0268541f166182d18360da33520e31c97a1d72e1c007cf684cfa [INFO] running `"docker" "start" "-a" "d4459106051c0268541f166182d18360da33520e31c97a1d72e1c007cf684cfa"` [INFO] [stderr] Compiling remove_dir_all v0.5.1 [INFO] [stderr] Compiling indextree v1.3.0 [INFO] [stderr] Compiling log v0.4.6 [INFO] [stderr] Compiling nix v0.7.0 [INFO] [stderr] Compiling rand_pcg v0.1.1 [INFO] [stderr] Compiling rand_chacha v0.1.0 [INFO] [stderr] Compiling rand v0.6.1 [INFO] [stderr] Compiling nix v0.8.1 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling tempfile v3.0.5 [INFO] [stderr] Compiling spawn-ptrace v0.1.1 [INFO] [stderr] Compiling tracing v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 16.47s [INFO] running `"docker" "inspect" "d4459106051c0268541f166182d18360da33520e31c97a1d72e1c007cf684cfa"` [INFO] running `"docker" "rm" "-f" "d4459106051c0268541f166182d18360da33520e31c97a1d72e1c007cf684cfa"` [INFO] [stdout] d4459106051c0268541f166182d18360da33520e31c97a1d72e1c007cf684cfa [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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] 237b340e4e5c4db613437ec3adeadb996bd66ce4cd799c0fe479d654065705fd [INFO] running `"docker" "start" "-a" "237b340e4e5c4db613437ec3adeadb996bd66ce4cd799c0fe479d654065705fd"` [INFO] [stderr] Compiling tracing v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 2.69s [INFO] running `"docker" "inspect" "237b340e4e5c4db613437ec3adeadb996bd66ce4cd799c0fe479d654065705fd"` [INFO] running `"docker" "rm" "-f" "237b340e4e5c4db613437ec3adeadb996bd66ce4cd799c0fe479d654065705fd"` [INFO] [stdout] 237b340e4e5c4db613437ec3adeadb996bd66ce4cd799c0fe479d654065705fd [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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] 2860220a0d358e87998550d093b27242f58ddb5753b5b315bef0241521412746 [INFO] running `"docker" "start" "-a" "2860220a0d358e87998550d093b27242f58ddb5753b5b315bef0241521412746"` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.16s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tracing-bbca04fcc8de6243 [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] [stdout] test test::trace::returns_multiple_called_child_processes ... FAILED [INFO] [stdout] test test::trace::returns_a_called_child_process ... 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/lib.rs:80:19 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x564753874be4 - 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: 0x564753874be4 - 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: 0x564753874be4 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x564753874be4 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x56475389e89c - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5647537e38c5 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x56475386edf1 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x564753877a15 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x564753877a15 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x564753877a15 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5647538776fb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x564753878072 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x564753877c5b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x56475389d2c1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x56475389d0e3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5647537dd5ce - core::result::Result::unwrap::h33789150615a8384 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5647537d7f0d - tracing::test::trace::includes_process_arguments::h36cef843499c3542 [INFO] [stdout] at src/lib.rs:80 [INFO] [stdout] 17: 0x5647537d7e6a - tracing::test::trace::includes_process_arguments::{{closure}}::h671fed807ee1731f [INFO] [stdout] at src/lib.rs:78 [INFO] [stdout] 18: 0x5647537c36ce - core::ops::function::FnOnce::call_once::hf0fb429660afe654 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 19: 0x5647537ef7bf - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 20: 0x56475380adbe - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x56475380adbe - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 22: 0x56475380adbe - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 23: 0x56475380adbe - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x56475380adbe - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x56475380adbe - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5647537e2d76 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5647537e8105 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5647537e8105 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 30: 0x5647537e8105 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 31: 0x5647537e8105 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5647537e8105 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 34: 0x56475386a80f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 35: 0x56475387f393 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x56475387f393 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f69e867d6db - start_thread [INFO] [stdout] 38: 0x7f69e818e88f - __clone [INFO] [stdout] 39: 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/lib.rs:68:19 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x564753874be4 - 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: 0x564753874be4 - 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: 0x564753874be4 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x564753874be4 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x56475389e89c - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5647537e38c5 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x56475386edf1 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x564753877a15 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x564753877a15 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x564753877a15 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5647538776fb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x564753878072 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x564753877c5b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x56475389d2c1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x56475389d0e3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5647537dd5ce - core::result::Result::unwrap::h33789150615a8384 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5647537d790d - tracing::test::trace::returns_multiple_called_child_processes::h4e59d8ade0cf4e57 [INFO] [stdout] at src/lib.rs:68 [INFO] [stdout] 17: 0x5647537d786a - tracing::test::trace::returns_multiple_called_child_processes::{{closure}}::hca64374fa5e6f2fd [INFO] [stdout] at src/lib.rs:66 [INFO] [stdout] 18: 0x5647537c363e - core::ops::function::FnOnce::call_once::h84d366ea589d80df [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 19: 0x5647537ef7bf - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 20: 0x56475380adbe - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x56475380adbe - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 22: 0x56475380adbe - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 23: 0x56475380adbe - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x56475380adbe - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x56475380adbe - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5647537e2d76 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5647537e8105 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5647537e8105 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 30: 0x5647537e8105 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 31: 0x5647537e8105 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5647537e8105 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 34: 0x56475386a80f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 35: 0x56475387f393 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x56475387f393 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f69e867d6db - start_thread [INFO] [stdout] 38: 0x7f69e818e88f - __clone [INFO] [stdout] 39: 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/lib.rs:59:19 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x564753874be4 - 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: 0x564753874be4 - 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: 0x564753874be4 - std::sys_common::backtrace::_print_fmt::h5c7c4751bc296f90 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x564753874be4 - ::fmt::h7729313ad2d729c7 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x56475389e89c - core::fmt::write::hb0049f6c12bdaffc [INFO] [stdout] at src/libcore/fmt/mod.rs:1069 [INFO] [stdout] 5: 0x5647537e38c5 - std::io::Write::write_fmt::h5d052af13df32ea6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/io/mod.rs:1504 [INFO] [stdout] 6: 0x56475386edf1 - std::io::impls::>::write_fmt::h8fe8a9bdc421b4b6 [INFO] [stdout] at src/libstd/io/impls.rs:156 [INFO] [stdout] 7: 0x564753877a15 - std::sys_common::backtrace::_print::ha087cac92e84a414 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x564753877a15 - std::sys_common::backtrace::print::h6b013d3bfe6cbcb8 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x564753877a15 - std::panicking::default_hook::{{closure}}::h016d7de02def2736 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5647538776fb - std::panicking::default_hook::h9386f7e484840463 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x564753878072 - std::panicking::rust_panic_with_hook::h3846eb1b2e5a9312 [INFO] [stdout] at src/libstd/panicking.rs:511 [INFO] [stdout] 12: 0x564753877c5b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:419 [INFO] [stdout] 13: 0x56475389d2c1 - core::panicking::panic_fmt::hc5953a7331f9805c [INFO] [stdout] at src/libcore/panicking.rs:111 [INFO] [stdout] 14: 0x56475389d0e3 - core::option::expect_none_failed::hf2c575a7b931ce1f [INFO] [stdout] at src/libcore/option.rs:1268 [INFO] [stdout] 15: 0x5647537dd5ce - core::result::Result::unwrap::h33789150615a8384 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5647537d74ed - tracing::test::trace::returns_a_called_child_process::h9fb7bf0b76ff8750 [INFO] [stdout] at src/lib.rs:59 [INFO] [stdout] 17: 0x5647537d744a - tracing::test::trace::returns_a_called_child_process::{{closure}}::h11bd5cbef75ec9cf [INFO] [stdout] at src/lib.rs:57 [INFO] [stdout] 18: 0x5647537c360e - core::ops::function::FnOnce::call_once::h17f7c910e746f094 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 19: 0x5647537ef7bf - as core::ops::function::FnOnce>::call_once::h1f6ee40732e80908 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 20: 0x56475380adbe - as core::ops::function::FnOnce<()>>::call_once::h85dff111be9c2dc6 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x56475380adbe - std::panicking::try::do_call::hee6f5a4bef67c840 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 22: 0x56475380adbe - std::panicking::try::h3384d3b948e84dbc [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 23: 0x56475380adbe - std::panic::catch_unwind::h5ae91218e578066d [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x56475380adbe - test::run_test_in_process::he37a49587889b910 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x56475380adbe - test::run_test::run_test_inner::{{closure}}::h4f0d25136ca480c2 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5647537e2d76 - std::sys_common::backtrace::__rust_begin_short_backtrace::h969eceda34247af3 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h912007997cb04e92 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5647537e8105 - as core::ops::function::FnOnce<()>>::call_once::h8795d8b7bd223988 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5647537e8105 - std::panicking::try::do_call::hb29b86f86d4d722e [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:331 [INFO] [stdout] 30: 0x5647537e8105 - std::panicking::try::h4bfefb3eddc0d900 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panicking.rs:274 [INFO] [stdout] 31: 0x5647537e8105 - std::panic::catch_unwind::h774c24d4b38d5828 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5647537e8105 - std::thread::Builder::spawn_unchecked::{{closure}}::ha50a32fa99413dde [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5647537e8105 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dacb8f28d699f86 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/libcore/ops/function.rs:232 [INFO] [stdout] 34: 0x56475386a80f - as core::ops::function::FnOnce>::call_once::h7deaeeba480c6ce2 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 35: 0x56475387f393 - as core::ops::function::FnOnce>::call_once::h968b1dfb87756523 [INFO] [stdout] at /rustc/41997647ba6a77908f6ef64401414feb00bccf16/src/liballoc/boxed.rs:1008 [INFO] [stdout] 36: 0x56475387f393 - std::sys::unix::thread::Thread::new::thread_start::h2399addeb6370595 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f69e867d6db - start_thread [INFO] [stdout] 38: 0x7f69e818e88f - __clone [INFO] [stdout] 39: 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] [stderr] error: test failed, to rerun pass '--lib' [INFO] running `"docker" "inspect" "2860220a0d358e87998550d093b27242f58ddb5753b5b315bef0241521412746"` [INFO] running `"docker" "rm" "-f" "2860220a0d358e87998550d093b27242f58ddb5753b5b315bef0241521412746"` [INFO] [stdout] 2860220a0d358e87998550d093b27242f58ddb5753b5b315bef0241521412746