[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 1.45.2 for beta-1.46-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsoenkehahn%2Ftracing" "/workspace/builds/worker-8/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-8/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/soenkehahn/tracing on toolchain 1.45.2 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.45.2" "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-8/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" "+1.45.2" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "metadata" "--no-deps" "--format-version=1", 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] ffc5627c5ac9f0377d65c0a9da1377e3b8db3ed542d3ba826fb7b091899d115b [INFO] running `Command { std: "docker" "start" "-a" "ffc5627c5ac9f0377d65c0a9da1377e3b8db3ed542d3ba826fb7b091899d115b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "ffc5627c5ac9f0377d65c0a9da1377e3b8db3ed542d3ba826fb7b091899d115b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ffc5627c5ac9f0377d65c0a9da1377e3b8db3ed542d3ba826fb7b091899d115b", kill_on_drop: false }` [INFO] [stdout] ffc5627c5ac9f0377d65c0a9da1377e3b8db3ed542d3ba826fb7b091899d115b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 945bc7d40c414ab95379a054cd1d7529eda420f307645a65cf35a6c993acb585 [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" "945bc7d40c414ab95379a054cd1d7529eda420f307645a65cf35a6c993acb585", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.45 [INFO] [stderr] Compiling semver v0.1.20 [INFO] [stderr] Compiling bitflags v0.4.0 [INFO] [stderr] Compiling remove_dir_all v0.5.1 [INFO] [stderr] Compiling indextree v1.3.0 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand v0.6.1 [INFO] [stderr] Compiling rustc_version v0.1.7 [INFO] [stderr] Compiling nix v0.7.0 [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 14.12s [INFO] running `Command { std: "docker" "inspect" "945bc7d40c414ab95379a054cd1d7529eda420f307645a65cf35a6c993acb585", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "945bc7d40c414ab95379a054cd1d7529eda420f307645a65cf35a6c993acb585", kill_on_drop: false }` [INFO] [stdout] 945bc7d40c414ab95379a054cd1d7529eda420f307645a65cf35a6c993acb585 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "test" "--frozen" "--no-run" "--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] f276548aedadae3a4f4c8fd1199f7d457a863b50ec20c208e50e53f1ce3edbac [INFO] running `Command { std: "docker" "start" "-a" "f276548aedadae3a4f4c8fd1199f7d457a863b50ec20c208e50e53f1ce3edbac", kill_on_drop: false }` [INFO] [stderr] Compiling tracing v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 2.12s [INFO] running `Command { std: "docker" "inspect" "f276548aedadae3a4f4c8fd1199f7d457a863b50ec20c208e50e53f1ce3edbac", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f276548aedadae3a4f4c8fd1199f7d457a863b50ec20c208e50e53f1ce3edbac", kill_on_drop: false }` [INFO] [stdout] f276548aedadae3a4f4c8fd1199f7d457a863b50ec20c208e50e53f1ce3edbac [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=warn" "-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:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] c3441f998a64c8bb71ffab27b77ccc5fdfe273538f0b46a1acf6d4959a585d4d [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" "c3441f998a64c8bb71ffab27b77ccc5fdfe273538f0b46a1acf6d4959a585d4d", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tracing-89b7f5a8bd6242ef [INFO] [stdout] running 5 tests [INFO] [stdout] test run::test::format::outputs_executables ... ok [INFO] [stdout] test run::test::format::outputs_arguments ... ok [INFO] [stdout] foo [INFO] [stdout] test test::trace::includes_process_arguments ... ok [INFO] [stdout] test test::trace::returns_multiple_called_child_processes ... ok [INFO] [stderr] error: test failed, to rerun pass '--lib' [INFO] [stdout] test test::trace::returns_a_called_child_process ... 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:41:17 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55c2ada3dba5 - backtrace::backtrace::libunwind::trace::ha215bbf14e328799 [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 [INFO] [stdout] 1: 0x55c2ada3dba5 - backtrace::backtrace::trace_unsynchronized::h3384b099b135857a [INFO] [stdout] at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 [INFO] [stdout] 2: 0x55c2ada3dba5 - std::sys_common::backtrace::_print_fmt::hfec93740f7294682 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:78 [INFO] [stdout] 3: 0x55c2ada3dba5 - ::fmt::h5fe20777f4174074 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:59 [INFO] [stdout] 4: 0x55c2ada66a9c - core::fmt::write::he14c3177144df275 [INFO] [stdout] at src/libcore/fmt/mod.rs:1076 [INFO] [stdout] 5: 0x55c2ad9ae9d5 - std::io::Write::write_fmt::ha866504c9998fe5e [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/io/mod.rs:1537 [INFO] [stdout] 6: 0x55c2ada37d01 - std::io::impls::>::write_fmt::h8f0a2af895559593 [INFO] [stdout] at src/libstd/io/impls.rs:176 [INFO] [stdout] 7: 0x55c2ada40930 - std::sys_common::backtrace::_print::h9cfa4c87526ba064 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:62 [INFO] [stdout] 8: 0x55c2ada40930 - std::sys_common::backtrace::print::h94936584848b3272 [INFO] [stdout] at src/libstd/sys_common/backtrace.rs:49 [INFO] [stdout] 9: 0x55c2ada40930 - std::panicking::default_hook::{{closure}}::h733b20cc9b496e46 [INFO] [stdout] at src/libstd/panicking.rs:198 [INFO] [stdout] 10: 0x55c2ada4062a - std::panicking::default_hook::h556d8cff6c12a651 [INFO] [stdout] at src/libstd/panicking.rs:215 [INFO] [stdout] 11: 0x55c2ada40f67 - std::panicking::rust_panic_with_hook::ha0c7ca5b39baa9d5 [INFO] [stdout] at src/libstd/panicking.rs:486 [INFO] [stdout] 12: 0x55c2ada40b6b - rust_begin_unwind [INFO] [stdout] at src/libstd/panicking.rs:388 [INFO] [stdout] 13: 0x55c2ada65431 - core::panicking::panic_fmt::h106d7128cbbc7cd2 [INFO] [stdout] at src/libcore/panicking.rs:101 [INFO] [stdout] 14: 0x55c2ada65253 - core::option::expect_none_failed::hfcafefc70a7975bb [INFO] [stdout] at src/libcore/option.rs:1272 [INFO] [stdout] 15: 0x55c2ad99dd6b - core::result::Result::unwrap::hee519be8a12e8693 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libcore/result.rs:1005 [INFO] [stdout] 16: 0x55c2ad9965e4 - tracing::test::trace::TestScript::new::h9a14277a21329efb [INFO] [stdout] at src/lib.rs:41 [INFO] [stdout] 17: 0x55c2ad996813 - tracing::test::trace::returns_a_called_child_process::h6f55614ef407da31 [INFO] [stdout] at src/lib.rs:58 [INFO] [stdout] 18: 0x55c2ad9967da - tracing::test::trace::returns_a_called_child_process::{{closure}}::h14d8b06d7f1c77c4 [INFO] [stdout] at src/lib.rs:57 [INFO] [stdout] 19: 0x55c2ad9ab30e - core::ops::function::FnOnce::call_once::he712f5f8d5247955 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libcore/ops/function.rs:232 [INFO] [stdout] 20: 0x55c2ad9d5bc2 - as core::ops::function::FnOnce>::call_once::h1efcc6525b6aa145 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/liballoc/boxed.rs:1076 [INFO] [stdout] 21: 0x55c2ad9d5bc2 - as core::ops::function::FnOnce<()>>::call_once::hafd7ae2f3c23f399 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panic.rs:318 [INFO] [stdout] 22: 0x55c2ad9d5bc2 - std::panicking::try::do_call::h83baf26f5fc8b0a5 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panicking.rs:297 [INFO] [stdout] 23: 0x55c2ad9d5bc2 - std::panicking::try::h8d28a678b90bb6ac [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panicking.rs:274 [INFO] [stdout] 24: 0x55c2ad9d5bc2 - std::panic::catch_unwind::h20734e8710644f59 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panic.rs:394 [INFO] [stdout] 25: 0x55c2ad9d5bc2 - test::run_test_in_process::h2c1fb2dd0c9013bb [INFO] [stdout] at src/libtest/lib.rs:541 [INFO] [stdout] 26: 0x55c2ad9d5bc2 - test::run_test::run_test_inner::{{closure}}::h7c58b7aefdb9adf3 [INFO] [stdout] at src/libtest/lib.rs:450 [INFO] [stdout] 27: 0x55c2ad9ade96 - std::sys_common::backtrace::__rust_begin_short_backtrace::h498f21fd16bdff05 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/sys_common/backtrace.rs:130 [INFO] [stdout] 28: 0x55c2ad9b31d5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb35336a0a0430e6d [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/thread/mod.rs:475 [INFO] [stdout] 29: 0x55c2ad9b31d5 - as core::ops::function::FnOnce<()>>::call_once::h8569a86268e08ad1 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panic.rs:318 [INFO] [stdout] 30: 0x55c2ad9b31d5 - std::panicking::try::do_call::hcc5472a0f1ff31ac [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panicking.rs:297 [INFO] [stdout] 31: 0x55c2ad9b31d5 - std::panicking::try::h774b0c4fd770945c [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panicking.rs:274 [INFO] [stdout] 32: 0x55c2ad9b31d5 - std::panic::catch_unwind::hfaf7c6beb53320e7 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/panic.rs:394 [INFO] [stdout] 33: 0x55c2ad9b31d5 - std::thread::Builder::spawn_unchecked::{{closure}}::h481343a824704446 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libstd/thread/mod.rs:474 [INFO] [stdout] 34: 0x55c2ad9b31d5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9dcbc7fc2c4c6c48 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/libcore/ops/function.rs:232 [INFO] [stdout] 35: 0x55c2ada47d7a - as core::ops::function::FnOnce>::call_once::hf311c88f1fadb9b8 [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/liballoc/boxed.rs:1076 [INFO] [stdout] 36: 0x55c2ada47d7a - as core::ops::function::FnOnce>::call_once::h8cfb7235b81393ef [INFO] [stdout] at /rustc/d3fb005a39e62501b8b0b356166e515ae24e2e54/src/liballoc/boxed.rs:1076 [INFO] [stdout] 37: 0x55c2ada47d7a - std::sys::unix::thread::Thread::new::thread_start::hf745c8cf29a89648 [INFO] [stdout] at src/libstd/sys/unix/thread.rs:87 [INFO] [stdout] 38: 0x7f581c181609 - start_thread [INFO] [stdout] 39: 0x7f581c08d103 - __clone [INFO] [stdout] 40: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test::trace::returns_a_called_child_process [INFO] [stdout] [INFO] [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "c3441f998a64c8bb71ffab27b77ccc5fdfe273538f0b46a1acf6d4959a585d4d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c3441f998a64c8bb71ffab27b77ccc5fdfe273538f0b46a1acf6d4959a585d4d", kill_on_drop: false }` [INFO] [stdout] c3441f998a64c8bb71ffab27b77ccc5fdfe273538f0b46a1acf6d4959a585d4d