[INFO] fetching crate oysterpack_built 0.3.2...
[INFO] testing oysterpack_built-0.3.2 against beta-2020-10-09 for beta-1.48-1
[INFO] extracting crate oysterpack_built 0.3.2 into /workspace/builds/worker-4/source
[INFO] validating manifest of crates.io crate oysterpack_built 0.3.2 on toolchain beta-2020-10-09
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2020-10-09" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] started tweaking crates.io crate oysterpack_built 0.3.2
[INFO] finished tweaking crates.io crate oysterpack_built 0.3.2
[INFO] tweaked toml for crates.io crate oysterpack_built 0.3.2 written to /workspace/builds/worker-4/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2020-10-09" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2020-10-09" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d26e3671472588cc33bdb530bbca68f9c9ac3d0a67dc023ff59ef3382b5594bc
[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" "d26e3671472588cc33bdb530bbca68f9c9ac3d0a67dc023ff59ef3382b5594bc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d26e3671472588cc33bdb530bbca68f9c9ac3d0a67dc023ff59ef3382b5594bc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d26e3671472588cc33bdb530bbca68f9c9ac3d0a67dc023ff59ef3382b5594bc", kill_on_drop: false }`
[INFO] [stdout] d26e3671472588cc33bdb530bbca68f9c9ac3d0a67dc023ff59ef3382b5594bc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c85bb22144ffcaf0d15a2327203a649e31073fdf295d40a9a1d3837b0c6a2b11
[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" "c85bb22144ffcaf0d15a2327203a649e31073fdf295d40a9a1d3837b0c6a2b11", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.44
[INFO] [stderr]    Compiling curl v0.4.34
[INFO] [stderr]    Compiling crc32fast v1.2.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling hex v0.3.2
[INFO] [stderr]    Compiling rustc-workspace-hack v1.0.0
[INFO] [stderr]    Compiling fixedbitset v0.1.9
[INFO] [stderr]    Compiling glob v0.2.11
[INFO] [stderr]    Compiling crossbeam-utils v0.5.0
[INFO] [stderr]    Compiling shell-escape v0.1.5
[INFO] [stderr]    Compiling ordermap v0.3.5
[INFO] [stderr]    Compiling lazycell v1.3.0
[INFO] [stderr]    Compiling home v0.3.4
[INFO] [stderr]    Compiling num-traits v0.2.12
[INFO] [stderr]    Compiling libz-sys v1.1.2
[INFO] [stderr]    Compiling curl-sys v0.4.38+curl-7.73.0
[INFO] [stderr]    Compiling libssh2-sys v0.2.19
[INFO] [stderr]    Compiling libgit2-sys v0.7.11
[INFO] [stderr]    Compiling walkdir v2.3.1
[INFO] [stderr]    Compiling socket2 v0.3.15
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling filetime v0.2.12
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling jobserver v0.1.21
[INFO] [stderr]    Compiling bstr v0.2.13
[INFO] [stderr]    Compiling openssl-sys v0.9.58
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling tar v0.4.30
[INFO] [stderr]    Compiling env_logger v0.5.13
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling flate2 v1.0.18
[INFO] [stderr]    Compiling openssl v0.10.30
[INFO] [stderr]    Compiling globset v0.4.5
[INFO] [stderr]    Compiling num-integer v0.1.43
[INFO] [stderr]    Compiling ignore v0.4.16
[INFO] [stderr]    Compiling tempfile v3.1.0
[INFO] [stderr]    Compiling crypto-hash v0.3.4
[INFO] [stderr]    Compiling synstructure v0.12.4
[INFO] [stderr]    Compiling serde_derive v1.0.117
[INFO] [stderr]    Compiling failure_derive v0.1.8
[INFO] [stderr]    Compiling failure v0.1.8
[INFO] [stderr]    Compiling serde v1.0.117
[INFO] [stderr]    Compiling serde_json v1.0.59
[INFO] [stderr]    Compiling toml v0.4.10
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling serde_ignored v0.0.4
[INFO] [stderr]    Compiling petgraph v0.4.13
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling crates-io v0.18.0
[INFO] [stderr]    Compiling rustfix v0.4.6
[INFO] [stderr]    Compiling git2 v0.7.5
[INFO] [stderr]    Compiling git2-curl v0.8.2
[INFO] [stderr]    Compiling built v0.3.0
[INFO] [stderr]    Compiling cargo v0.30.0
[INFO] [stderr]    Compiling oysterpack_built v0.3.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused `std::result::Result` that must be used
[INFO] [stdout]    --> src/build_time/mod.rs:185:5
[INFO] [stdout]     |
[INFO] [stdout] 185 |     writeln!(file, "{}", file_contents);
[INFO] [stdout]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3m 06s
[INFO] running `Command { std: "docker" "inspect" "c85bb22144ffcaf0d15a2327203a649e31073fdf295d40a9a1d3837b0c6a2b11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c85bb22144ffcaf0d15a2327203a649e31073fdf295d40a9a1d3837b0c6a2b11", kill_on_drop: false }`
[INFO] [stdout] c85bb22144ffcaf0d15a2327203a649e31073fdf295d40a9a1d3837b0c6a2b11
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "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] ea85b231f0a92b82c2cafb83749409848d34fad1e1f6f657006575c4a90e6f89
[INFO] running `Command { std: "docker" "start" "-a" "ea85b231f0a92b82c2cafb83749409848d34fad1e1f6f657006575c4a90e6f89", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-xid v0.0.4
[INFO] [stderr]    Compiling pulldown-cmark v0.1.2
[INFO] [stderr]    Compiling bitflags v0.9.1
[INFO] [stderr]    Compiling quote v0.3.15
[INFO] [stderr]    Compiling itertools v0.7.11
[INFO] [stderr]    Compiling fern v0.5.9
[INFO] [stdout] warning: unused `std::result::Result` that must be used
[INFO] [stdout]    --> src/build_time/mod.rs:185:5
[INFO] [stdout]     |
[INFO] [stdout] 185 |     writeln!(file, "{}", file_contents);
[INFO] [stdout]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling synom v0.11.3
[INFO] [stderr]    Compiling syn v0.11.11
[INFO] [stderr]    Compiling oysterpack_testing v0.1.2
[INFO] [stderr]    Compiling version-sync v0.5.0
[INFO] [stderr]    Compiling oysterpack_built v0.3.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated constant `std::sync::atomic::ATOMIC_USIZE_INIT`: the `new` function is now preferred
[INFO] [stdout]    --> src/lib.rs:142:1
[INFO] [stdout]     |
[INFO] [stdout] 142 | op_tests_mod!();
[INFO] [stdout]     | ^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(deprecated)]` on by default
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated constant `std::sync::atomic::ATOMIC_USIZE_INIT`: the `new` function is now preferred
[INFO] [stdout]    --> src/lib.rs:142:1
[INFO] [stdout]     |
[INFO] [stdout] 142 | op_tests_mod!();
[INFO] [stdout]     | ^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused `std::result::Result` that must be used
[INFO] [stdout]    --> src/build_time/mod.rs:185:5
[INFO] [stdout]     |
[INFO] [stdout] 185 |     writeln!(file, "{}", file_contents);
[INFO] [stdout]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stdout]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 3 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 23.14s
[INFO] running `Command { std: "docker" "inspect" "ea85b231f0a92b82c2cafb83749409848d34fad1e1f6f657006575c4a90e6f89", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ea85b231f0a92b82c2cafb83749409848d34fad1e1f6f657006575c4a90e6f89", kill_on_drop: false }`
[INFO] [stdout] ea85b231f0a92b82c2cafb83749409848d34fad1e1f6f657006575c4a90e6f89
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "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" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2020-10-09" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9e8f620ca575ac2049ba6e4e993008ac9b53754228e984b495374085b2b4c5ed
[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" "9e8f620ca575ac2049ba6e4e993008ac9b53754228e984b495374085b2b4c5ed", kill_on_drop: false }`
[INFO] [stderr] warning: unused `std::result::Result` that must be used
[INFO] [stderr]    --> src/build_time/mod.rs:185:5
[INFO] [stderr]     |
[INFO] [stderr] 185 |     writeln!(file, "{}", file_contents);
[INFO] [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated constant `std::sync::atomic::ATOMIC_USIZE_INIT`: the `new` function is now preferred
[INFO] [stderr]    --> src/lib.rs:142:1
[INFO] [stderr]     |
[INFO] [stderr] 142 | op_tests_mod!();
[INFO] [stderr]     | ^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(deprecated)]` on by default
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: 1 warning emitted
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated constant `std::sync::atomic::ATOMIC_USIZE_INIT`: the `new` function is now preferred
[INFO] [stderr]    --> src/lib.rs:142:1
[INFO] [stderr]     |
[INFO] [stderr] 142 | op_tests_mod!();
[INFO] [stderr]     | ^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: unused `std::result::Result` that must be used
[INFO] [stderr]    --> src/build_time/mod.rs:185:5
[INFO] [stderr]     |
[INFO] [stderr] 185 |     writeln!(file, "{}", file_contents);
[INFO] [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr]     = note: this warning originates in a macro (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: 3 warnings emitted
[INFO] [stderr] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/oysterpack_built-f7b6e9795cdbb707
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] [09:46:57.527][INFO][oysterpack_built::tests][src/lib.rs:142] logging has been initialized for oysterpack_built
[INFO] [stdout] [09:46:57.531][INFO][oysterpack_built::tests][src/lib.rs:142] it compiles :)
[INFO] [stdout] [09:46:57.531][INFO][oysterpack_built::tests][src/lib.rs:142] compiles: test run time: 144.962µs
[INFO] [stdout] test tests::compiles ... ok
[INFO] [stdout] test build_time::tests::test_build_dependency_graph ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- build_time::tests::test_build_dependency_graph stdout ----
[INFO] [stdout] thread 'build_time::tests::test_build_dependency_graph' panicked at 'called `Result::unwrap()` on an `Err` value: Error { inner: ErrorInner { kind: Custom, line: None, col: 0, message: "invalid serialized PackageId", key: ["package", "dependencies"] } }
[INFO] [stdout] 
[INFO] [stdout]    0: failure::backtrace::internal::InternalBacktrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/failure-0.1.8/src/backtrace/internal.rs:46:44
[INFO] [stdout]    1: failure::backtrace::Backtrace::new
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/failure-0.1.8/src/backtrace/mod.rs:121:35
[INFO] [stdout]    2: <failure::error::error_impl::ErrorImpl as core::convert::From<F>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/failure-0.1.8/src/error/error_impl.rs:19:17
[INFO] [stdout]    3: <failure::error::Error as core::convert::From<F>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/failure-0.1.8/src/error/mod.rs:36:18
[INFO] [stdout]    4: cargo::ops::lockfile::load_pkg_lockfile::{{closure}}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/cargo-0.30.0/src/cargo/ops/lockfile.rs:26:67
[INFO] [stdout]    5: cargo::ops::lockfile::load_pkg_lockfile
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/cargo-0.30.0/src/cargo/ops/lockfile.rs:24:9
[INFO] [stdout]    6: cargo::ops::resolve::resolve_with_registry
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/cargo-0.30.0/src/cargo/ops/resolve.rs:109:16
[INFO] [stdout]    7: cargo::ops::resolve::resolve_ws
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/cargo-0.30.0/src/cargo/ops/resolve.rs:18:19
[INFO] [stdout]    8: oysterpack_built::build_time::resolve
[INFO] [stdout]              at src/build_time/mod.rs:341:31
[INFO] [stdout]    9: oysterpack_built::build_time::build_dependency_graph
[INFO] [stdout]              at src/build_time/mod.rs:208:31
[INFO] [stdout]   10: oysterpack_built::build_time::tests::test_build_dependency_graph::{{closure}}
[INFO] [stdout]              at src/build_time/tests.rs:26:41
[INFO] [stdout]   11: oysterpack_built::tests::run_test
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/oysterpack_testing-0.1.2/src/macros.rs:110:17
[INFO] [stdout]   12: oysterpack_built::build_time::tests::test_build_dependency_graph
[INFO] [stdout]              at src/build_time/tests.rs:25:5
[INFO] [stdout]   13: oysterpack_built::build_time::tests::test_build_dependency_graph::{{closure}}
[INFO] [stdout]              at src/build_time/tests.rs:24:1
[INFO] [stdout]   14: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   15: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227:5
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:516:5
[INFO] [stdout]   16: <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042:9
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:308:9
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:381:40
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:345:19
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:382:14
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:543:18
[INFO] [stdout]       test::run_test::run_test_inner::{{closure}}
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:449:39
[INFO] [stdout]   17: std::sys_common::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:137:18
[INFO] [stdout]   18: std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/thread/mod.rs:464:17
[INFO] [stdout]       <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:308:9
[INFO] [stdout]       std::panicking::try::do_call
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:381:40
[INFO] [stdout]       std::panicking::try
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:345:19
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:382:14
[INFO] [stdout]       std::thread::Builder::spawn_unchecked::{{closure}}
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/thread/mod.rs:463:30
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   19: <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042:9
[INFO] [stdout]       <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042:9
[INFO] [stdout]       std::sys::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys/unix/thread.rs:87:17
[INFO] [stdout]   20: start_thread
[INFO] [stdout]   21: clone
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failed to parse lock file at: /opt/rustwide/workdir/Cargo.lock', src/build_time/mod.rs:208:83
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55c85d2b8b50 - std::backtrace_rs::backtrace::libunwind::trace::h7cf3ca97bd83fbab
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/../../backtrace/src/backtrace/libunwind.rs:96
[INFO] [stdout]    1:     0x55c85d2b8b50 - std::backtrace_rs::backtrace::trace_unsynchronized::h5a69c674b2d9ea5c
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/../../backtrace/src/backtrace/mod.rs:66
[INFO] [stdout]    2:     0x55c85d2b8b50 - std::sys_common::backtrace::_print_fmt::hdf72478dfcb50678
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:79
[INFO] [stdout]    3:     0x55c85d2b8b50 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h1e0bdbb35e83deca
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:58
[INFO] [stdout]    4:     0x55c85d2e07cc - core::fmt::write::he67f9b17bcb7ee73
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/fmt/mod.rs:1080
[INFO] [stdout]    5:     0x55c85c6de036 - std::io::Write::write_fmt::h68b2e5fe4c78e61a
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/io/mod.rs:1516
[INFO] [stdout]    6:     0x55c85d2b128d - std::io::impls::<impl std::io::Write for alloc::boxed::Box<W>>::write_fmt::he9ebec865e28165c
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/io/impls.rs:179
[INFO] [stdout]    7:     0x55c85d2bc74d - std::sys_common::backtrace::_print::haa20918e28a126c5
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:61
[INFO] [stdout]    8:     0x55c85d2bc74d - std::sys_common::backtrace::print::h49e772a4873d9076
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:48
[INFO] [stdout]    9:     0x55c85d2bc74d - std::panicking::default_hook::{{closure}}::hb218b305d15e3f11
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:208
[INFO] [stdout]   10:     0x55c85d2bc3a2 - std::panicking::default_hook::h63997b7b949e1ce0
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:224
[INFO] [stdout]   11:     0x55c85d2bce31 - std::panicking::rust_panic_with_hook::hb54f09ccadf64bd3
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:577
[INFO] [stdout]   12:     0x55c85d2bc9d9 - std::panicking::begin_panic_handler::{{closure}}::h8b43445b254eeb30
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:484
[INFO] [stdout]   13:     0x55c85d2b8fdc - std::sys_common::backtrace::__rust_end_short_backtrace::hacd691450edfeae0
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:153
[INFO] [stdout]   14:     0x55c85d2bc999 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:483
[INFO] [stdout]   15:     0x55c85d2dedc1 - core::panicking::panic_fmt::h5b17d4a0e853d0f5
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/panicking.rs:85
[INFO] [stdout]   16:     0x55c85d2debe3 - core::option::expect_none_failed::hd08bf36cae411fff
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/option.rs:1234
[INFO] [stdout]   17:     0x55c85c6bc29e - core::result::Result<T,E>::unwrap::h828ab1ff4874f4d9
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/result.rs:973
[INFO] [stdout]   18:     0x55c85c6b8725 - oysterpack_built::build_time::build_dependency_graph::h6d250d561b18070a
[INFO] [stdout]                                at /opt/rustwide/workdir/src/build_time/mod.rs:208
[INFO] [stdout]   19:     0x55c85c6d67a4 - oysterpack_built::build_time::tests::test_build_dependency_graph::{{closure}}::h8507f04cebd93ad7
[INFO] [stdout]                                at /opt/rustwide/workdir/src/build_time/tests.rs:26
[INFO] [stdout]   20:     0x55c85c6bf6c2 - oysterpack_built::tests::run_test::h36960810858fa880
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/oysterpack_testing-0.1.2/src/macros.rs:110
[INFO] [stdout]   21:     0x55c85c6d6565 - oysterpack_built::build_time::tests::test_build_dependency_graph::h4bedeb5179c4e9c8
[INFO] [stdout]                                at /opt/rustwide/workdir/src/build_time/tests.rs:25
[INFO] [stdout]   22:     0x55c85c6d653a - oysterpack_built::build_time::tests::test_build_dependency_graph::{{closure}}::hdd1f6b6222cf65ae
[INFO] [stdout]                                at /opt/rustwide/workdir/src/build_time/tests.rs:24
[INFO] [stdout]   23:     0x55c85c6b474e - core::ops::function::FnOnce::call_once::h04e22ec0c3e48a13
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227
[INFO] [stdout]   24:     0x55c85c704e23 - core::ops::function::FnOnce::call_once::hcd3fa2f0696c1015
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227
[INFO] [stdout]   25:     0x55c85c704e23 - test::__rust_begin_short_backtrace::h113f97c5e5ab7d4a
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:516
[INFO] [stdout]   26:     0x55c85c703328 - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h01fd09163cae2442
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   27:     0x55c85c703328 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h0da6b685ae74a04a
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:308
[INFO] [stdout]   28:     0x55c85c703328 - std::panicking::try::do_call::h564a32f132f2c264
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:381
[INFO] [stdout]   29:     0x55c85c703328 - std::panicking::try::h477cfd4b06a2e64a
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:345
[INFO] [stdout]   30:     0x55c85c703328 - std::panic::catch_unwind::hdb5abc6d008c399d
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:382
[INFO] [stdout]   31:     0x55c85c703328 - test::run_test_in_process::he8ff7dec91b275be
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:543
[INFO] [stdout]   32:     0x55c85c703328 - test::run_test::run_test_inner::{{closure}}::hfd9f49e4a4ff9162
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/test/src/lib.rs:449
[INFO] [stdout]   33:     0x55c85c6dd3b6 - std::sys_common::backtrace::__rust_begin_short_backtrace::hee6d60d5bb466aff
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys_common/backtrace.rs:137
[INFO] [stdout]   34:     0x55c85c6e2525 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h988cecdf9ebbaf00
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/thread/mod.rs:464
[INFO] [stdout]   35:     0x55c85c6e2525 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h330cf727ec126982
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:308
[INFO] [stdout]   36:     0x55c85c6e2525 - std::panicking::try::do_call::h6cd25078f4a5ea13
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:381
[INFO] [stdout]   37:     0x55c85c6e2525 - std::panicking::try::h84b1d49961301705
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panicking.rs:345
[INFO] [stdout]   38:     0x55c85c6e2525 - std::panic::catch_unwind::h6145324593b3654b
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/panic.rs:382
[INFO] [stdout]   39:     0x55c85c6e2525 - std::thread::Builder::spawn_unchecked::{{closure}}::ha41e72e8024f9192
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/thread/mod.rs:463
[INFO] [stdout]   40:     0x55c85c6e2525 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hfb5034cf1f1be223
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/core/src/ops/function.rs:227
[INFO] [stdout]   41:     0x55c85d2c4c7a - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::hd4d30e37db281e8f
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   42:     0x55c85d2c4c7a - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h019185a215ba16e4
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   43:     0x55c85d2c4c7a - std::sys::unix::thread::Thread::new::thread_start::h33c6d7cf25f9291f
[INFO] [stdout]                                at /rustc/212e76c31d461462544b5d516d3bdc99fc8f016e/library/std/src/sys/unix/thread.rs:87
[INFO] [stdout]   44:     0x7f381b78c609 - start_thread
[INFO] [stdout]   45:     0x7f381ba19103 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     build_time::tests::test_build_dependency_graph
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--lib'
[INFO] running `Command { std: "docker" "inspect" "9e8f620ca575ac2049ba6e4e993008ac9b53754228e984b495374085b2b4c5ed", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9e8f620ca575ac2049ba6e4e993008ac9b53754228e984b495374085b2b4c5ed", kill_on_drop: false }`
[INFO] [stdout] 9e8f620ca575ac2049ba6e4e993008ac9b53754228e984b495374085b2b4c5ed
