[INFO] cloning repository https://github.com/llogiq/optional
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/llogiq/optional" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fllogiq%2Foptional", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fllogiq%2Foptional'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b7ea7694cdbb7e66ae0ee1eb2eb6cc7369537f2c
[INFO] testing llogiq/optional against master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fllogiq%2Foptional" "/workspace/builds/worker-23/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-23/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/llogiq/optional on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/llogiq/optional
[INFO] finished tweaking git repo https://github.com/llogiq/optional
[INFO] tweaked toml for git repo https://github.com/llogiq/optional written to /workspace/builds/worker-23/source/Cargo.toml
[INFO] crate git repo https://github.com/llogiq/optional already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded bencher v0.1.5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b26d0f37b0b605779f4c1a828b03816f4e540543872ccd09a4e42fb64ea91a92
[INFO] running `Command { std: "docker" "start" "-a" "b26d0f37b0b605779f4c1a828b03816f4e540543872ccd09a4e42fb64ea91a92", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b26d0f37b0b605779f4c1a828b03816f4e540543872ccd09a4e42fb64ea91a92", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b26d0f37b0b605779f4c1a828b03816f4e540543872ccd09a4e42fb64ea91a92", kill_on_drop: false }`
[INFO] [stdout] b26d0f37b0b605779f4c1a828b03816f4e540543872ccd09a4e42fb64ea91a92
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 57772692c18fbfd15939ef2e19c23d2a8a9c548a45a61f6264039e8ef9729cbc
[INFO] running `Command { std: "docker" "start" "-a" "57772692c18fbfd15939ef2e19c23d2a8a9c548a45a61f6264039e8ef9729cbc", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling optional v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.80s
[INFO] running `Command { std: "docker" "inspect" "57772692c18fbfd15939ef2e19c23d2a8a9c548a45a61f6264039e8ef9729cbc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "57772692c18fbfd15939ef2e19c23d2a8a9c548a45a61f6264039e8ef9729cbc", kill_on_drop: false }`
[INFO] [stdout] 57772692c18fbfd15939ef2e19c23d2a8a9c548a45a61f6264039e8ef9729cbc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 901f0e177335ebf1624c6c7a57ef627ebf3319d27c394fca32b80a9003276031
[INFO] running `Command { std: "docker" "start" "-a" "901f0e177335ebf1624c6c7a57ef627ebf3319d27c394fca32b80a9003276031", kill_on_drop: false }`
[INFO] [stderr]    Compiling ryu v1.0.0
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling itoa v0.4.3
[INFO] [stderr]    Compiling bencher v0.1.5
[INFO] [stderr]    Compiling serde_json v1.0.64
[INFO] [stderr]    Compiling optional v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 46.17s
[INFO] running `Command { std: "docker" "inspect" "901f0e177335ebf1624c6c7a57ef627ebf3319d27c394fca32b80a9003276031", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "901f0e177335ebf1624c6c7a57ef627ebf3319d27c394fca32b80a9003276031", kill_on_drop: false }`
[INFO] [stdout] 901f0e177335ebf1624c6c7a57ef627ebf3319d27c394fca32b80a9003276031
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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" "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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9ca32b9b95dc1657377f44d8dfb3235e5d6044353c122f1dbe5e95ba4ab8a622
[INFO] running `Command { std: "docker" "start" "-a" "9ca32b9b95dc1657377f44d8dfb3235e5d6044353c122f1dbe5e95ba4ab8a622", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.65s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/optional-4b72640829c43498)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/optbool.rs (/opt/rustwide/target/debug/deps/optbool-d9ba47c453b11931)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test deref_to_option ... ok
[INFO] [stdout] test test_bool_map ... ok
[INFO] [stdout] test into_option_bool ... ok
[INFO] [stdout] test test_eq_ne ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/optioned.rs (/opt/rustwide/target/debug/deps/optioned-27631662ffe32588)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test optioned_is_some_or_none ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests optional
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] test src/lib.rs - Optioned<T>::into_option (line 1537) - compile fail ... ok
[INFO] [stdout] test src/lib.rs - (line 28) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::and_then (line 620) ... ok
[INFO] [stdout] test src/lib.rs - (line 19) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::expect (line 298) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::expect (line 290) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::and_then_bool (line 645) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::unwrap (line 321) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::and_bool (line 595) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::filter (line 674) ... ok
[INFO] [stdout] test src/lib.rs - (line 38) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::is_none (line 265) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::is_some (line 227) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool (line 943) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::map_bool (line 432) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::map (line 405) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::or (line 707) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T> (line 1379) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::iter (line 825) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::take_bool (line 879) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::or_else (line 1823) ... ok
[INFO] [stdout] test src/lib.rs - some (line 2119) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::map_or_default (line 512) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::ok_or_else (line 554) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::into_option (line 1529) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::ok_or (line 535) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::contains (line 246) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::as_slice (line 839) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::unwrap_or_else (line 377) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::or_else (line 752) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::map_or_else (line 490) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::some (line 1495) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::unwrap (line 329) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::ok_or (line 1961) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::or_bool (line 727) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::unwrap_or (line 342) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::and_then (line 1895) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::unwrap_or_default (line 362) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::xor (line 1848) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::contains (line 2094) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::and (line 1869) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::none (line 214) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::iter (line 2035) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unwrap (line 1610) ... ok
[INFO] [stdout] test src/lib.rs - wrap (line 2146) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::take (line 861) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::filter (line 1937) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unwrap (line 1603) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::take (line 2007) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::expect (line 1574) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::or_else_bool (line 778) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::or (line 1792) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::map_or_default (line 1766) ... ok
[INFO] [stdout] test src/lib.rs - none (line 2133) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::replace (line 896) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::none (line 1509) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::map_or_else (line 1742) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::replace (line 2051) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::map (line 1676) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::and (line 576) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unwrap_or_else (line 1655) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unwrap_or (line 1637) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::ok_or_else (line 1984) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::expect (line 1581) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::some (line 1490) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::map_t (line 1699) ... ok
[INFO] [stdout] test src/lib.rs - some (line 2114) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::map_or (line 468) ... ok
[INFO] [stdout] test src/lib.rs - OptionBool::xor (line 803) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unpack (line 1623) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::unwrap_or_default (line 2073) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::as_slice (line 2021) ... ok
[INFO] [stdout] test src/lib.rs - Optioned<T>::map_or (line 1721) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 14.56s
[INFO] [stdout] 
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "9ca32b9b95dc1657377f44d8dfb3235e5d6044353c122f1dbe5e95ba4ab8a622", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9ca32b9b95dc1657377f44d8dfb3235e5d6044353c122f1dbe5e95ba4ab8a622", kill_on_drop: false }`
[INFO] [stdout] 9ca32b9b95dc1657377f44d8dfb3235e5d6044353c122f1dbe5e95ba4ab8a622