[INFO] cloning repository https://github.com/soenkehahn/tracing [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/soenkehahn/tracing" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoenkehahn%2Ftracing", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoenkehahn%2Ftracing'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] b793582ae575f144d7aab954d459664c1f558489 [INFO] testing soenkehahn/tracing against try#03a1ea71b075ab964b5278bc6e74cd6c52c36ee0 for pr-74409 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoenkehahn%2Ftracing" "/workspace/builds/worker-1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/soenkehahn/tracing on toolchain 03a1ea71b075ab964b5278bc6e74cd6c52c36ee0 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [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-1/source/Cargo.toml [INFO] crate git repo https://github.com/soenkehahn/tracing already has a lockfile, it will not be regenerated [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded indextree v1.3.0 [INFO] [stderr] Downloaded spawn-ptrace v0.1.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 57e0abe97dd9f01981a84cf8fb6061d309e55d146282cb78f4f8503ee585cefd [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "57e0abe97dd9f01981a84cf8fb6061d309e55d146282cb78f4f8503ee585cefd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "57e0abe97dd9f01981a84cf8fb6061d309e55d146282cb78f4f8503ee585cefd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "57e0abe97dd9f01981a84cf8fb6061d309e55d146282cb78f4f8503ee585cefd", kill_on_drop: false }` [INFO] [stdout] 57e0abe97dd9f01981a84cf8fb6061d309e55d146282cb78f4f8503ee585cefd [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 58bc96b18bb789d893329bad64101481526b3c6dcb1b4759901f7893fc336b32 [INFO] running `Command { std: "docker" "start" "-a" "58bc96b18bb789d893329bad64101481526b3c6dcb1b4759901f7893fc336b32", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling semver-parser v0.7.0 [INFO] [stderr] Compiling libc v0.2.45 [INFO] [stderr] Compiling rand_core v0.3.0 [INFO] [stderr] Compiling semver v0.1.20 [INFO] [stderr] Compiling void v1.0.2 [INFO] [stderr] Compiling bitflags v0.4.0 [INFO] [stderr] Compiling remove_dir_all v0.5.1 [INFO] [stderr] Compiling bitflags v0.7.0 [INFO] [stderr] Compiling indextree v1.3.0 [INFO] [stderr] Compiling log v0.4.6 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling semver v0.9.0 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_xorshift v0.1.0 [INFO] [stderr] Compiling rustc_version v0.1.7 [INFO] [stderr] Compiling rustc_version v0.2.3 [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 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.25s [INFO] running `Command { std: "docker" "inspect" "58bc96b18bb789d893329bad64101481526b3c6dcb1b4759901f7893fc336b32", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "58bc96b18bb789d893329bad64101481526b3c6dcb1b4759901f7893fc336b32", kill_on_drop: false }` [INFO] [stdout] 58bc96b18bb789d893329bad64101481526b3c6dcb1b4759901f7893fc336b32 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7df1a0d194e1c3aa7e3e42a44a5937eea55631aaed7331a1fa10a0e5f69998f7 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "7df1a0d194e1c3aa7e3e42a44a5937eea55631aaed7331a1fa10a0e5f69998f7", kill_on_drop: false }` [INFO] [stderr] Compiling tracing v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 2.27s [INFO] running `Command { std: "docker" "inspect" "7df1a0d194e1c3aa7e3e42a44a5937eea55631aaed7331a1fa10a0e5f69998f7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7df1a0d194e1c3aa7e3e42a44a5937eea55631aaed7331a1fa10a0e5f69998f7", kill_on_drop: false }` [INFO] [stdout] 7df1a0d194e1c3aa7e3e42a44a5937eea55631aaed7331a1fa10a0e5f69998f7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+03a1ea71b075ab964b5278bc6e74cd6c52c36ee0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 1983b7f2738e0c9327b4e334988c889f287a1be1bbcf4bef8033b73eb72201e9 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "1983b7f2738e0c9327b4e334988c889f287a1be1bbcf4bef8033b73eb72201e9", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.09s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tracing-4d97eda1d8d57f37 [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::returns_a_called_child_process ... FAILED [INFO] [stdout] test test::trace::includes_process_arguments ... FAILED [INFO] [stdout] test test::trace::returns_multiple_called_child_processes ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [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: Os { code: 10, kind: Other, message: "No child processes" }', src/lib.rs:47:22 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5623d233df55 - backtrace::backtrace::libunwind::trace::hea89dfbbfe67f40d [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5623d233df55 - backtrace::backtrace::trace_unsynchronized::h0513dd713d7644d4 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5623d233df55 - std::sys_common::backtrace::_print_fmt::h040abae64e4a24a9 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5623d233df55 - ::fmt::hb4bdb37c3c335733 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5623d2366bfc - core::fmt::write::h540ac4a6a1232abc [INFO] [stdout] at src/libcore/fmt/mod.rs:1076 [INFO] [stdout] 5: 0x5623d22b03d5 - std::io::Write::write_fmt::h4be0daababb55bba [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/io/mod.rs:1539 [INFO] [stdout] 6: 0x5623d23383b1 - std::io::impls::>::write_fmt::h83c1e1aa73f374f5 [INFO] [stdout] at src/libstd/io/impls.rs:176 [INFO] [stdout] 7: 0x5623d2340ba0 - std::sys_common::backtrace::_print::hb55cc635eaf1882e [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5623d2340ba0 - std::sys_common::backtrace::print::h2b2218fa55714084 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5623d2340ba0 - std::panicking::default_hook::{{closure}}::h578517c025765cf3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5623d234089a - std::panicking::default_hook::h4cafc94c2fc640fb [INFO] [stdout] at src/libstd/panicking.rs:214 [INFO] [stdout] 11: 0x5623d23411e3 - std::panicking::rust_panic_with_hook::h3eeb5001beeca5c3 [INFO] [stdout] at src/libstd/panicking.rs:526 [INFO] [stdout] 12: 0x5623d2340ddb - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:437 [INFO] [stdout] 13: 0x5623d2365591 - core::panicking::panic_fmt::h78830ea6a34e7206 [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 14: 0x5623d23653b3 - core::option::expect_none_failed::hf79bbb5ca7e09e08 [INFO] [stdout] at src/libcore/option.rs:1269 [INFO] [stdout] 15: 0x5623d22aab5b - core::result::Result::unwrap::h102d777c6b1896f3 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5623d2298644 - tracing::test::trace::TestScript::new::h01ccfab57c917061 [INFO] [stdout] at src/lib.rs:41 [INFO] [stdout] 17: 0x5623d2298873 - tracing::test::trace::returns_a_called_child_process::he335194ed64efd25 [INFO] [stdout] at src/lib.rs:58 [INFO] [stdout] 18: 0x5623d229883a - tracing::test::trace::returns_a_called_child_process::{{closure}}::h78dcf119faa58d20 [INFO] [stdout] at src/lib.rs:57 [INFO] [stdout] 19: 0x5623d22acc8e - core::ops::function::FnOnce::call_once::h97831d72607b906b [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 20: 0x5623d22d794c - as core::ops::function::FnOnce>::call_once::h8340ba6798e0c4ff [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 21: 0x5623d22d794c - as core::ops::function::FnOnce<()>>::call_once::h5590e98676605094 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 22: 0x5623d22d794c - std::panicking::try::do_call::hd8680a2dbb0ed3c4 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 23: 0x5623d22d794c - std::panicking::try::h46b4de2d705e7a8d [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 24: 0x5623d22d794c - std::panic::catch_unwind::h059b3f06991e0a9c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 25: 0x5623d22d794c - test::run_test_in_process::h5ae08a65430494a4 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 26: 0x5623d22d794c - test::run_test::run_test_inner::{{closure}}::h4b36073838376ae8 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 27: 0x5623d22af8c6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0169d0be588b8bf0 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 28: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h71bfc37e887d3973 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:475 [INFO] [stdout] 29: 0x5623d22b48b5 - as core::ops::function::FnOnce<()>>::call_once::h36c65072907dc180 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 30: 0x5623d22b48b5 - std::panicking::try::do_call::h4e0f426e17cd216c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 31: 0x5623d22b48b5 - std::panicking::try::hd05e4ef6b4303d29 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 32: 0x5623d22b48b5 - std::panic::catch_unwind::h19a1127ec729c5dd [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 33: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h011300735f06b1e2 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:474 [INFO] [stdout] 34: 0x5623d22b48b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h1e3c55f31f1d122e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 35: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::hc0e15d78a519721e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 36: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::h211da8fffb09f192 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 37: 0x5623d2347eca - std::sys::unix::thread::Thread::new::thread_start::h29594ddebf23e421 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 38: 0x7f4b60fb3609 - start_thread [INFO] [stdout] 39: 0x7f4b60ebf103 - __clone [INFO] [stdout] 40: 0x0 - [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("Failed to get pid of forked process: Sys(ESRCH)")', src/lib.rs:80:40 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5623d233df55 - backtrace::backtrace::libunwind::trace::hea89dfbbfe67f40d [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5623d233df55 - backtrace::backtrace::trace_unsynchronized::h0513dd713d7644d4 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5623d233df55 - std::sys_common::backtrace::_print_fmt::h040abae64e4a24a9 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5623d233df55 - ::fmt::hb4bdb37c3c335733 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5623d2366bfc - core::fmt::write::h540ac4a6a1232abc [INFO] [stdout] at src/libcore/fmt/mod.rs:1076 [INFO] [stdout] 5: 0x5623d22b03d5 - std::io::Write::write_fmt::h4be0daababb55bba [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/io/mod.rs:1539 [INFO] [stdout] 6: 0x5623d23383b1 - std::io::impls::>::write_fmt::h83c1e1aa73f374f5 [INFO] [stdout] at src/libstd/io/impls.rs:176 [INFO] [stdout] 7: 0x5623d2340ba0 - std::sys_common::backtrace::_print::hb55cc635eaf1882e [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5623d2340ba0 - std::sys_common::backtrace::print::h2b2218fa55714084 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5623d2340ba0 - std::panicking::default_hook::{{closure}}::h578517c025765cf3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5623d234089a - std::panicking::default_hook::h4cafc94c2fc640fb [INFO] [stdout] at src/libstd/panicking.rs:214 [INFO] [stdout] 11: 0x5623d23411e3 - std::panicking::rust_panic_with_hook::h3eeb5001beeca5c3 [INFO] [stdout] at src/libstd/panicking.rs:526 [INFO] [stdout] 12: 0x5623d2340ddb - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:437 [INFO] [stdout] 13: 0x5623d2365591 - core::panicking::panic_fmt::h78830ea6a34e7206 [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 14: 0x5623d23653b3 - core::option::expect_none_failed::hf79bbb5ca7e09e08 [INFO] [stdout] at src/libcore/option.rs:1269 [INFO] [stdout] 15: 0x5623d22aae20 - core::result::Result::unwrap::had3448856ff409e6 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5623d22992fd - tracing::test::trace::includes_process_arguments::h62bd4fa7bea053b6 [INFO] [stdout] at src/lib.rs:80 [INFO] [stdout] 17: 0x5623d229925a - tracing::test::trace::includes_process_arguments::{{closure}}::h2fae13288243799d [INFO] [stdout] at src/lib.rs:78 [INFO] [stdout] 18: 0x5623d22acc5e - core::ops::function::FnOnce::call_once::h62fb3d4322b9eb11 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 19: 0x5623d22d794c - as core::ops::function::FnOnce>::call_once::h8340ba6798e0c4ff [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 20: 0x5623d22d794c - as core::ops::function::FnOnce<()>>::call_once::h5590e98676605094 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x5623d22d794c - std::panicking::try::do_call::hd8680a2dbb0ed3c4 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 22: 0x5623d22d794c - std::panicking::try::h46b4de2d705e7a8d [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 23: 0x5623d22d794c - std::panic::catch_unwind::h059b3f06991e0a9c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x5623d22d794c - test::run_test_in_process::h5ae08a65430494a4 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x5623d22d794c - test::run_test::run_test_inner::{{closure}}::h4b36073838376ae8 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5623d22af8c6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0169d0be588b8bf0 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h71bfc37e887d3973 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5623d22b48b5 - as core::ops::function::FnOnce<()>>::call_once::h36c65072907dc180 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5623d22b48b5 - std::panicking::try::do_call::h4e0f426e17cd216c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 30: 0x5623d22b48b5 - std::panicking::try::hd05e4ef6b4303d29 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 31: 0x5623d22b48b5 - std::panic::catch_unwind::h19a1127ec729c5dd [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h011300735f06b1e2 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5623d22b48b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h1e3c55f31f1d122e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 34: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::hc0e15d78a519721e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 35: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::h211da8fffb09f192 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 36: 0x5623d2347eca - std::sys::unix::thread::Thread::new::thread_start::h29594ddebf23e421 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f4b60fb3609 - start_thread [INFO] [stdout] 38: 0x7f4b60ebf103 - __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 continuing process: Sys(ESRCH)")', src/lib.rs:68:40 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5623d233df55 - backtrace::backtrace::libunwind::trace::hea89dfbbfe67f40d [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x5623d233df55 - backtrace::backtrace::trace_unsynchronized::h0513dd713d7644d4 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x5623d233df55 - std::sys_common::backtrace::_print_fmt::h040abae64e4a24a9 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x5623d233df55 - ::fmt::hb4bdb37c3c335733 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x5623d2366bfc - core::fmt::write::h540ac4a6a1232abc [INFO] [stdout] at src/libcore/fmt/mod.rs:1076 [INFO] [stdout] 5: 0x5623d22b03d5 - std::io::Write::write_fmt::h4be0daababb55bba [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/io/mod.rs:1539 [INFO] [stdout] 6: 0x5623d23383b1 - std::io::impls::>::write_fmt::h83c1e1aa73f374f5 [INFO] [stdout] at src/libstd/io/impls.rs:176 [INFO] [stdout] 7: 0x5623d2340ba0 - std::sys_common::backtrace::_print::hb55cc635eaf1882e [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x5623d2340ba0 - std::sys_common::backtrace::print::h2b2218fa55714084 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x5623d2340ba0 - std::panicking::default_hook::{{closure}}::h578517c025765cf3 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x5623d234089a - std::panicking::default_hook::h4cafc94c2fc640fb [INFO] [stdout] at src/libstd/panicking.rs:214 [INFO] [stdout] 11: 0x5623d23411e3 - std::panicking::rust_panic_with_hook::h3eeb5001beeca5c3 [INFO] [stdout] at src/libstd/panicking.rs:526 [INFO] [stdout] 12: 0x5623d2340ddb - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:437 [INFO] [stdout] 13: 0x5623d2365591 - core::panicking::panic_fmt::h78830ea6a34e7206 [INFO] [stdout] at src/libcore/panicking.rs:85 [INFO] [stdout] 14: 0x5623d23653b3 - core::option::expect_none_failed::hf79bbb5ca7e09e08 [INFO] [stdout] at src/libcore/option.rs:1269 [INFO] [stdout] 15: 0x5623d22aae20 - core::result::Result::unwrap::had3448856ff409e6 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x5623d2298cfd - tracing::test::trace::returns_multiple_called_child_processes::h32a4d2abeef7c7f9 [INFO] [stdout] at src/lib.rs:68 [INFO] [stdout] 17: 0x5623d2298c5a - tracing::test::trace::returns_multiple_called_child_processes::{{closure}}::h8dfc18c7c81737f0 [INFO] [stdout] at src/lib.rs:66 [INFO] [stdout] 18: 0x5623d22acc2e - core::ops::function::FnOnce::call_once::h0b1b1ab9cd05b597 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 19: 0x5623d22d794c - as core::ops::function::FnOnce>::call_once::h8340ba6798e0c4ff [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 20: 0x5623d22d794c - as core::ops::function::FnOnce<()>>::call_once::h5590e98676605094 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 21: 0x5623d22d794c - std::panicking::try::do_call::hd8680a2dbb0ed3c4 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 22: 0x5623d22d794c - std::panicking::try::h46b4de2d705e7a8d [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 23: 0x5623d22d794c - std::panic::catch_unwind::h059b3f06991e0a9c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 24: 0x5623d22d794c - test::run_test_in_process::h5ae08a65430494a4 [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 25: 0x5623d22d794c - test::run_test::run_test_inner::{{closure}}::h4b36073838376ae8 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 26: 0x5623d22af8c6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h0169d0be588b8bf0 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 27: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h71bfc37e887d3973 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:475 [INFO] [stdout] 28: 0x5623d22b48b5 - as core::ops::function::FnOnce<()>>::call_once::h36c65072907dc180 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:318 [INFO] [stdout] 29: 0x5623d22b48b5 - std::panicking::try::do_call::h4e0f426e17cd216c [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:348 [INFO] [stdout] 30: 0x5623d22b48b5 - std::panicking::try::hd05e4ef6b4303d29 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panicking.rs:325 [INFO] [stdout] 31: 0x5623d22b48b5 - std::panic::catch_unwind::h19a1127ec729c5dd [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/panic.rs:394 [INFO] [stdout] 32: 0x5623d22b48b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h011300735f06b1e2 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libstd/thread/mod.rs:474 [INFO] [stdout] 33: 0x5623d22b48b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h1e3c55f31f1d122e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/libcore/ops/function.rs:233 [INFO] [stdout] 34: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::hc0e15d78a519721e [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 35: 0x5623d2347eca - as core::ops::function::FnOnce>::call_once::h211da8fffb09f192 [INFO] [stdout] at /rustc/03a1ea71b075ab964b5278bc6e74cd6c52c36ee0/src/liballoc/boxed.rs:1081 [INFO] [stdout] 36: 0x5623d2347eca - std::sys::unix::thread::Thread::new::thread_start::h29594ddebf23e421 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 37: 0x7f4b60fb3609 - start_thread [INFO] [stdout] 38: 0x7f4b60ebf103 - __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] [stdout] foo [INFO] running `Command { std: "docker" "inspect" "1983b7f2738e0c9327b4e334988c889f287a1be1bbcf4bef8033b73eb72201e9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1983b7f2738e0c9327b4e334988c889f287a1be1bbcf4bef8033b73eb72201e9", kill_on_drop: false }` [INFO] [stdout] 1983b7f2738e0c9327b4e334988c889f287a1be1bbcf4bef8033b73eb72201e9