[INFO] fetching crate nar_dev_utils 0.44.0...
[INFO] testing nar_dev_utils-0.44.0 against master#9e136a30a965bf4e63f03095c57df7257bf96fd6 for pr-134530
[INFO] extracting crate nar_dev_utils 0.44.0 into /workspace/builds/worker-7-tc1/source
[INFO] validating manifest of crates.io crate nar_dev_utils 0.44.0 on toolchain 9e136a30a965bf4e63f03095c57df7257bf96fd6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate nar_dev_utils 0.44.0
[INFO] finished tweaking crates.io crate nar_dev_utils 0.44.0
[INFO] tweaked toml for crates.io crate nar_dev_utils 0.44.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 14cc07cd11b5c5621e10b41c746881b85e589017d2ea9afcf239817c59e99d64
[INFO] running `Command { std: "docker" "start" "-a" "14cc07cd11b5c5621e10b41c746881b85e589017d2ea9afcf239817c59e99d64", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "14cc07cd11b5c5621e10b41c746881b85e589017d2ea9afcf239817c59e99d64", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "14cc07cd11b5c5621e10b41c746881b85e589017d2ea9afcf239817c59e99d64", kill_on_drop: false }`
[INFO] [stdout] 14cc07cd11b5c5621e10b41c746881b85e589017d2ea9afcf239817c59e99d64
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b6c4e693be3fc12fd37357a1e8a26324010308847558a1082b90177e011c6c44
[INFO] running `Command { std: "docker" "start" "-a" "b6c4e693be3fc12fd37357a1e8a26324010308847558a1082b90177e011c6c44", kill_on_drop: false }`
[INFO] [stderr]    Compiling nar_dev_utils v0.44.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] running `Command { std: "docker" "inspect" "b6c4e693be3fc12fd37357a1e8a26324010308847558a1082b90177e011c6c44", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b6c4e693be3fc12fd37357a1e8a26324010308847558a1082b90177e011c6c44", kill_on_drop: false }`
[INFO] [stdout] b6c4e693be3fc12fd37357a1e8a26324010308847558a1082b90177e011c6c44
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1c6e8e628b9c95372d38ff48c62f5b2a6d47701586a438051bdf50cfbcc7f005
[INFO] running `Command { std: "docker" "start" "-a" "1c6e8e628b9c95372d38ff48c62f5b2a6d47701586a438051bdf50cfbcc7f005", kill_on_drop: false }`
[INFO] [stderr]    Compiling nar_dev_utils v0.44.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.50s
[INFO] running `Command { std: "docker" "inspect" "1c6e8e628b9c95372d38ff48c62f5b2a6d47701586a438051bdf50cfbcc7f005", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1c6e8e628b9c95372d38ff48c62f5b2a6d47701586a438051bdf50cfbcc7f005", kill_on_drop: false }`
[INFO] [stdout] 1c6e8e628b9c95372d38ff48c62f5b2a6d47701586a438051bdf50cfbcc7f005
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/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:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+9e136a30a965bf4e63f03095c57df7257bf96fd6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d136bb028474f659385581edf2daa0e0e6abb26d92dd19491cf7488503fc8c4a
[INFO] running `Command { std: "docker" "start" "-a" "d136bb028474f659385581edf2daa0e0e6abb26d92dd19491cf7488503fc8c4a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.01s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nar_dev_utils-405c375946723f2e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test prelude::tests::test_linear_search ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 15.74s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests nar_dev_utils
[INFO] [stdout] 
[INFO] [stdout] running 37 tests
[INFO] [stdout] test src/macros.rs - macros::f_tensor (line 622) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 39) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 61) ... ok
[INFO] [stdout] test src/macros.rs - macros::if_return (line 942) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 111) ... ok
[INFO] [stdout] test src/macros.rs - macros::catch_flow (line 571) ... ok
[INFO] [stdout] test src/macros.rs - macros::fail_tests (line 341) ... ok
[INFO] [stdout] test src/macros.rs - macros::fail_tests (line 382) ... ok
[INFO] [stdout] test src/macros.rs - macros::impl_once (line 2769) ... ignored
[INFO] [stdout] test src/macros.rs - macros::fail_tests (line 366) ... ok
[INFO] [stdout] test src/macros.rs - macros::for_in_ifs (line 1885) ... ok
[INFO] [stdout] test src/macros.rs - macros::catch_flow (line 553) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 89) ... ok
[INFO] [stdout] test src/macros.rs - macros::f_parallel (line 826) ... ok
[INFO] [stdout] test src/macros.rs - macros::if_return (line 967) ... ok
[INFO] [stdout] test src/macros.rs - macros::asserts (line 450) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 22) ... ok
[INFO] [stdout] test src/macros.rs - macros::first (line 126) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 3011) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 3036) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 3079) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 2995) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 3058) ... ok
[INFO] [stdout] test src/macros.rs - macros::macro_once (line 2223) ... ok
[INFO] [stdout] test src/macros.rs - macros::push_str (line 521) ... ok
[INFO] [stdout] test src/macros.rs - macros::ifs (line 3094) ... ok
[INFO] [stdout] test src/macros.rs - macros::macro_once (line 2235) ... ok
[INFO] [stdout] test src/macros.rs - macros::pipe (line 1271) ... ok
[INFO] [stdout] test src/macros.rs - macros::macro_once (line 2247) ... ok
[INFO] [stdout] test src/macros.rs - macros::show (line 269) ... ok
[INFO] [stdout] test src/macros.rs - macros::matches_or (line 2485) ... ok
[INFO] [stdout] test src/macros.rs - macros::show (line 257) ... ok
[INFO] [stdout] test src/macros.rs - macros::list (line 2085) ... ok
[INFO] [stdout] test src/macros.rs - macros::impl_once (line 2781) ... ok
[INFO] [stdout] test src/macros.rs - macros::pipe (line 1304) ... ok
[INFO] [stdout] test src/macros.rs - macros::unwrap_or_return (line 2550) ... ok
[INFO] [stdout] test src/macros.rs - macros::manipulate (line 1613) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 36 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 3.02s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "d136bb028474f659385581edf2daa0e0e6abb26d92dd19491cf7488503fc8c4a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d136bb028474f659385581edf2daa0e0e6abb26d92dd19491cf7488503fc8c4a", kill_on_drop: false }`
[INFO] [stdout] d136bb028474f659385581edf2daa0e0e6abb26d92dd19491cf7488503fc8c4a