[INFO] fetching crate pombase-gocam 0.91.0...
[INFO] testing pombase-gocam-0.91.0 against try#b83b707f97d809763b7861afa7638871f3339a33 for pr-145838-1
[INFO] extracting crate pombase-gocam 0.91.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate pombase-gocam 0.91.0
[INFO] finished tweaking crates.io crate pombase-gocam 0.91.0
[INFO] tweaked toml for crates.io crate pombase-gocam 0.91.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate pombase-gocam 0.91.0 on toolchain b83b707f97d809763b7861afa7638871f3339a33
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate pombase-gocam 0.91.0 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" "+b83b707f97d809763b7861afa7638871f3339a33" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9a1a73937028cc9c9fe7daedc97a7fba75924da296f5d33e882d79cd2eddd8d0
[INFO] running `Command { std: "docker" "start" "-a" "9a1a73937028cc9c9fe7daedc97a7fba75924da296f5d33e882d79cd2eddd8d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9a1a73937028cc9c9fe7daedc97a7fba75924da296f5d33e882d79cd2eddd8d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a1a73937028cc9c9fe7daedc97a7fba75924da296f5d33e882d79cd2eddd8d0", kill_on_drop: false }`
[INFO] [stdout] 9a1a73937028cc9c9fe7daedc97a7fba75924da296f5d33e882d79cd2eddd8d0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fa5954bc35404c99af6bdbd635337b24ebe40f98eb84339d07fa22e783c760ad
[INFO] running `Command { std: "docker" "start" "-a" "fa5954bc35404c99af6bdbd635337b24ebe40f98eb84339d07fa22e783c760ad", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling fixedbitset v0.5.7
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling indexmap v2.9.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling phf_generator v0.11.3
[INFO] [stderr]    Compiling phf_macros v0.11.3
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling petgraph v0.8.2
[INFO] [stderr]    Compiling pombase-gocam v0.91.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.73s
[INFO] running `Command { std: "docker" "inspect" "fa5954bc35404c99af6bdbd635337b24ebe40f98eb84339d07fa22e783c760ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fa5954bc35404c99af6bdbd635337b24ebe40f98eb84339d07fa22e783c760ad", kill_on_drop: false }`
[INFO] [stdout] fa5954bc35404c99af6bdbd635337b24ebe40f98eb84339d07fa22e783c760ad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2ecedad089a08be6f8fb1b1f68827600b6567470393ddc1d75dde88b8fb6f054
[INFO] running `Command { std: "docker" "start" "-a" "2ecedad089a08be6f8fb1b1f68827600b6567470393ddc1d75dde88b8fb6f054", kill_on_drop: false }`
[INFO] [stderr]    Compiling pombase-gocam v0.91.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.69s
[INFO] running `Command { std: "docker" "inspect" "2ecedad089a08be6f8fb1b1f68827600b6567470393ddc1d75dde88b8fb6f054", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2ecedad089a08be6f8fb1b1f68827600b6567470393ddc1d75dde88b8fb6f054", kill_on_drop: false }`
[INFO] [stdout] 2ecedad089a08be6f8fb1b1f68827600b6567470393ddc1d75dde88b8fb6f054
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9d644fd1cebcd18b08c540e2aafe881deb4a42c4c25c0d9355d1b5079c74d774
[INFO] running `Command { std: "docker" "start" "-a" "9d644fd1cebcd18b08c540e2aafe881deb4a42c4c25c0d9355d1b5079c74d774", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/pombase_gocam-4825e0de5f15781e)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test graph::tests::parse_raw_test ... ok
[INFO] [stdout] test raw::tests::parse_raw_test ... ok
[INFO] [stdout] test tests::retain_enabling_genes_test ... ok
[INFO] [stdout] test tests::test_genes_in_model ... ok
[INFO] [stdout] test tests::model_activity_enabled_by_test ... ok
[INFO] [stdout] test gocam_py::tests::parse_test ... ok
[INFO] [stdout] test tests::remove_chemicals_test ... ok
[INFO] [stdout] test tests::parse_test ... ok
[INFO] [stdout] test raw::tests::parse_raw_test_with_complement ... ok
[INFO] [stdout] test tests::merge_test_mss51 ... ok
[INFO] [stdout] test tests::retain_largest_subgraph_test ... ok
[INFO] [stdout] test tests::test_modified_genes_in_model ... ok
[INFO] [stdout] test tests::test_genes_enabling_activities ... ok
[INFO] [stdout] test tests::test_node_rel_direction ... ok
[INFO] [stdout] test tests::merge_test ... ok
[INFO] [stdout] test tests::remove_chemicals_inputs_outputs_only_test ... ok
[INFO] [stdout] test tests::merge_test_mss51_remove_chemicals ... ok
[INFO] [stdout] test tests::find_overlaps_test ... ok
[INFO] [stdout] test tests::overlap_test ... ok
[INFO] [stdout] test tests::merge_test_three_models ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests pombase_gocam
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test src/lib.rs - parse_gocam_model (line 1945) ... ok
[INFO] [stdout] test src/lib.rs - GoCamModel::new (line 212) ... ok
[INFO] [stdout] test src/raw.rs - raw::GoCamRawModel (line 289) ... ok
[INFO] [stdout] test src/raw.rs - raw::gocam_parse_raw (line 445) ... ok
[INFO] [stdout] test src/lib.rs - (line 17) ... ok
[INFO] [stdout] test src/raw.rs - raw (line 8) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "9d644fd1cebcd18b08c540e2aafe881deb4a42c4c25c0d9355d1b5079c74d774", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9d644fd1cebcd18b08c540e2aafe881deb4a42c4c25c0d9355d1b5079c74d774", kill_on_drop: false }`
[INFO] [stdout] 9d644fd1cebcd18b08c540e2aafe881deb4a42c4c25c0d9355d1b5079c74d774