[INFO] fetching crate tsg-btsg 0.1.2...
[INFO] testing tsg-btsg-0.1.2 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1
[INFO] extracting crate tsg-btsg 0.1.2 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate tsg-btsg 0.1.2
[INFO] removed 0 missing examples
[INFO] finished tweaking crates.io crate tsg-btsg 0.1.2
[INFO] tweaked toml for crates.io crate tsg-btsg 0.1.2 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate tsg-btsg 0.1.2 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tsg-btsg 0.1.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 51150db7f3a65bcdae960473ef4c3b7e588aa52fd7b937a8d8266853124f0704
[INFO] running `Command { std: "docker" "start" "-a" "51150db7f3a65bcdae960473ef4c3b7e588aa52fd7b937a8d8266853124f0704", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "51150db7f3a65bcdae960473ef4c3b7e588aa52fd7b937a8d8266853124f0704", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "51150db7f3a65bcdae960473ef4c3b7e588aa52fd7b937a8d8266853124f0704", kill_on_drop: false }`
[INFO] [stdout] 51150db7f3a65bcdae960473ef4c3b7e588aa52fd7b937a8d8266853124f0704
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b60b5aa8f37eb615b3c301fff114b5e76920dc20455568989725f00ad36cac36
[INFO] running `Command { std: "docker" "start" "-a" "b60b5aa8f37eb615b3c301fff114b5e76920dc20455568989725f00ad36cac36", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling libc v0.2.171
[INFO] [stderr]    Compiling zlib-rs v0.4.2
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling prettyplease v0.2.31
[INFO] [stderr]    Compiling rustversion v1.0.20
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling rustix v1.0.2
[INFO] [stderr]    Compiling getrandom v0.3.1
[INFO] [stderr]    Compiling zstd-safe v7.2.3
[INFO] [stderr]    Compiling syn v2.0.100
[INFO] [stderr]    Compiling rayon-core v1.12.1
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling anyhow v1.0.97
[INFO] [stderr]    Compiling bitflags v2.9.0
[INFO] [stderr]    Compiling hashbrown v0.15.2
[INFO] [stderr]    Compiling linux-raw-sys v0.9.3
[INFO] [stderr]    Compiling overload v0.1.1
[INFO] [stderr]    Compiling log v0.4.26
[INFO] [stderr]    Compiling nu-ansi-term v0.46.0
[INFO] [stderr]    Compiling thread_local v1.1.8
[INFO] [stderr]    Compiling fixedbitset v0.5.7
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling tracing-subscriber v0.3.19
[INFO] [stderr]    Compiling jobserver v0.1.32
[INFO] [stderr]    Compiling rayon v1.10.0
[INFO] [stderr]    Compiling indexmap v2.8.0
[INFO] [stderr]    Compiling cc v1.2.16
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling ahash v0.8.11
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling zstd-sys v2.0.14+zstd.1.5.7
[INFO] [stderr]    Compiling tempfile v3.19.0
[INFO] [stderr]    Compiling libz-rs-sys v0.4.2
[INFO] [stderr]    Compiling darling_core v0.20.10
[INFO] [stderr]    Compiling flate2 v1.1.0
[INFO] [stderr]    Compiling noodles-bgzf v0.37.0
[INFO] [stderr]    Compiling bstr v1.11.3
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling tracing-attributes v0.1.28
[INFO] [stderr]    Compiling thiserror-impl v2.0.12
[INFO] [stderr]    Compiling noodles-core v0.17.0
[INFO] [stderr]    Compiling noodles-fasta v0.50.0
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling noodles v0.94.0
[INFO] [stderr]    Compiling thiserror v2.0.12
[INFO] [stderr]    Compiling darling_macro v0.20.10
[INFO] [stderr]    Compiling darling v0.20.10
[INFO] [stderr]    Compiling bon-macros v3.5.0
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling zstd v0.13.3
[INFO] [stderr]    Compiling bon v3.5.0
[INFO] [stderr]    Compiling petgraph v0.7.1
[INFO] [stderr]    Compiling tsg-core v0.1.2
[INFO] [stderr]    Compiling tsg-btsg v0.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: methods `str` and `id` are never used
[INFO] [stdout]   --> src/lib.rs:78:8
[INFO] [stdout]    |
[INFO] [stdout] 58 | impl StringDictionary {
[INFO] [stdout]    | --------------------- methods in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 78 |     fn str(&self, id: u32) -> Option<&BStr> {
[INFO] [stdout]    |        ^^^
[INFO] [stdout] ...
[INFO] [stdout] 82 |     fn id(&self, s: &BStr) -> Option<u32> {
[INFO] [stdout]    |        ^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 15.22s
[INFO] running `Command { std: "docker" "inspect" "b60b5aa8f37eb615b3c301fff114b5e76920dc20455568989725f00ad36cac36", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b60b5aa8f37eb615b3c301fff114b5e76920dc20455568989725f00ad36cac36", kill_on_drop: false }`
[INFO] [stdout] b60b5aa8f37eb615b3c301fff114b5e76920dc20455568989725f00ad36cac36
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7b036c3a3196a893bb839b57eb60ddaf7f1f75e7fb51b25632d22bc15373069c
[INFO] running `Command { std: "docker" "start" "-a" "7b036c3a3196a893bb839b57eb60ddaf7f1f75e7fb51b25632d22bc15373069c", kill_on_drop: false }`
[INFO] [stderr]    Compiling tsg-btsg v0.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: methods `str` and `id` are never used
[INFO] [stdout]   --> src/lib.rs:78:8
[INFO] [stdout]    |
[INFO] [stdout] 58 | impl StringDictionary {
[INFO] [stdout]    | --------------------- methods in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 78 |     fn str(&self, id: u32) -> Option<&BStr> {
[INFO] [stdout]    |        ^^^
[INFO] [stdout] ...
[INFO] [stdout] 82 |     fn id(&self, s: &BStr) -> Option<u32> {
[INFO] [stdout]    |        ^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.71s
[INFO] running `Command { std: "docker" "inspect" "7b036c3a3196a893bb839b57eb60ddaf7f1f75e7fb51b25632d22bc15373069c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7b036c3a3196a893bb839b57eb60ddaf7f1f75e7fb51b25632d22bc15373069c", kill_on_drop: false }`
[INFO] [stdout] 7b036c3a3196a893bb839b57eb60ddaf7f1f75e7fb51b25632d22bc15373069c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 48e8d7756c07bf559a48c821ba4c4248ee7681798406324c22298c3c0bbcde26
[INFO] running `Command { std: "docker" "start" "-a" "48e8d7756c07bf559a48c821ba4c4248ee7681798406324c22298c3c0bbcde26", kill_on_drop: false }`
[INFO] [stderr] warning: methods `str` and `id` are never used
[INFO] [stderr]   --> src/lib.rs:78:8
[INFO] [stderr]    |
[INFO] [stderr] 58 | impl StringDictionary {
[INFO] [stderr]    | --------------------- methods in this implementation
[INFO] [stderr] ...
[INFO] [stderr] 78 |     fn str(&self, id: u32) -> Option<&BStr> {
[INFO] [stderr]    |        ^^^
[INFO] [stderr] ...
[INFO] [stderr] 82 |     fn id(&self, s: &BStr) -> Option<u32> {
[INFO] [stderr]    |        ^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `tsg-btsg` (lib) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tsg_btsg-e77c4575a3942481)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test tests::test_block_serialization ... ok
[INFO] [stdout] test tests::test_string_dictionary ... ok
[INFO] [stdout] test tests::test_from_btsg_roundtrip2 ... FAILED
[INFO] [stdout] test tests::test_compression_round_trip ... ok
[INFO] [stdout] test tests::test_from_btsg ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_from_btsg_roundtrip2 stdout ----
[INFO] [stdout] Error: Failed to parse strand: Invalid strand: 1000-2000
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error as core::convert::From<std::io::error::Error>>::from
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.97/src/backtrace.rs:27:14
[INFO] [stdout]    1: <core::result::Result<(), anyhow::Error> as core::ops::try_trait::FromResidual<core::result::Result<core::convert::Infallible, std::io::error::Error>>>::from_residual
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:2189:27
[INFO] [stdout]    2: <tsg_core::graph::TSGraph>::parse_node_line
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:632:25
[INFO] [stdout]    3: <tsg_core::graph::TSGraph>::from_reader::<&mut std::io::buffered::bufreader::BufReader<std::io::cursor::Cursor<alloc::string::String>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:948:32
[INFO] [stdout]    4: <tsg_core::graph::TSGraph as tsg_btsg::BTSG>::from_btsg::<&std::path::PathBuf>
[INFO] [stdout]              at ./src/lib.rs:645:9
[INFO] [stdout]    5: tsg_btsg::tests::test_from_btsg_roundtrip2
[INFO] [stdout]              at ./src/lib.rs:951:28
[INFO] [stdout]    6: tsg_btsg::tests::test_from_btsg_roundtrip2::{closure#0}
[INFO] [stdout]              at ./src/lib.rs:900:39
[INFO] [stdout]    7: <tsg_btsg::tests::test_from_btsg_roundtrip2::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    8: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    9: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18
[INFO] [stdout]   10: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74
[INFO] [stdout]   11: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   12: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   13: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   14: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   15: test::run_test_in_process
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27
[INFO] [stdout]   16: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43
[INFO] [stdout]   17: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41
[INFO] [stdout]   18: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   19: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   20: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   21: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   22: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   23: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   24: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   25: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   27: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   28: <unknown>
[INFO] [stdout]   29: clone
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_from_btsg stdout ----
[INFO] [stdout] Error: Sink node with ID n2 not found
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <anyhow::Error>::msg::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.97/src/backtrace.rs:27:14
[INFO] [stdout]    1: <tsg_core::graph::GraphSection>::add_edge::{closure#1}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:108:28
[INFO] [stdout]    2: <core::option::Option<&petgraph::graph_impl::NodeIndex>>::ok_or_else::<anyhow::Error, <tsg_core::graph::GraphSection>::add_edge::{closure#1}>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/option.rs:1369:25
[INFO] [stdout]    3: <tsg_core::graph::GraphSection>::add_edge
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:108:14
[INFO] [stdout]    4: <tsg_core::graph::TSGraph>::parse_edge_line
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:656:15
[INFO] [stdout]    5: <tsg_core::graph::TSGraph>::from_reader::<&mut std::io::buffered::bufreader::BufReader<std::io::cursor::Cursor<alloc::string::String>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tsg-core-0.1.2/src/graph.rs:949:32
[INFO] [stdout]    6: <tsg_core::graph::TSGraph as tsg_btsg::BTSG>::from_btsg::<&std::path::PathBuf>
[INFO] [stdout]              at ./src/lib.rs:645:9
[INFO] [stdout]    7: tsg_btsg::tests::test_from_btsg
[INFO] [stdout]              at ./src/lib.rs:890:21
[INFO] [stdout]    8: tsg_btsg::tests::test_from_btsg::{closure#0}
[INFO] [stdout]              at ./src/lib.rs:877:28
[INFO] [stdout]    9: <tsg_btsg::tests::test_from_btsg::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   10: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   11: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18
[INFO] [stdout]   12: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74
[INFO] [stdout]   13: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   14: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   15: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   16: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   17: test::run_test_in_process
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27
[INFO] [stdout]   18: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43
[INFO] [stdout]   19: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41
[INFO] [stdout]   20: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   21: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   22: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   23: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   24: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   25: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   26: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   27: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   29: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   30: <unknown>
[INFO] [stdout]   31: clone
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_from_btsg
[INFO] [stdout]     tests::test_from_btsg_roundtrip2
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 3 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "48e8d7756c07bf559a48c821ba4c4248ee7681798406324c22298c3c0bbcde26", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48e8d7756c07bf559a48c821ba4c4248ee7681798406324c22298c3c0bbcde26", kill_on_drop: false }`
[INFO] [stdout] 48e8d7756c07bf559a48c821ba4c4248ee7681798406324c22298c3c0bbcde26