[INFO] cloning repository https://github.com/mdekstrand/happylog
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/mdekstrand/happylog" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmdekstrand%2Fhappylog", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmdekstrand%2Fhappylog'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] fdb7eb9b906b2bcec85ec47a28c5bb832c1eadcc
[INFO] testing mdekstrand/happylog against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmdekstrand%2Fhappylog" "/workspace/builds/worker-65/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-65/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/mdekstrand/happylog on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/mdekstrand/happylog
[INFO] finished tweaking git repo https://github.com/mdekstrand/happylog
[INFO] tweaked toml for git repo https://github.com/mdekstrand/happylog written to /workspace/builds/worker-65/source/Cargo.toml
[INFO] crate git repo https://github.com/mdekstrand/happylog already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/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" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5be6fb080115a527976d992b19542484331735c1d55b26436a8696bf9d839544
[INFO] running `Command { std: "docker" "start" "-a" "5be6fb080115a527976d992b19542484331735c1d55b26436a8696bf9d839544", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5be6fb080115a527976d992b19542484331735c1d55b26436a8696bf9d839544", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5be6fb080115a527976d992b19542484331735c1d55b26436a8696bf9d839544", kill_on_drop: false }`
[INFO] [stdout] 5be6fb080115a527976d992b19542484331735c1d55b26436a8696bf9d839544
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/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" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 37d7da07fd1628b7d3c56897223430b0bce914bf0fe5dce5577226056c640170
[INFO] running `Command { std: "docker" "start" "-a" "37d7da07fd1628b7d3c56897223430b0bce914bf0fe5dce5577226056c640170", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.74
[INFO] [stderr]    Compiling version_check v0.9.2
[INFO] [stderr]    Compiling proc-macro2 v1.0.19
[INFO] [stderr]    Compiling unicode-xid v0.2.1
[INFO] [stderr]    Compiling syn v1.0.36
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling regex-syntax v0.6.18
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling log v0.4.11
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling number_prefix v0.3.0
[INFO] [stderr]    Compiling heck v0.3.1
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling terminal_size v0.1.13
[INFO] [stderr]    Compiling termios v0.3.2
[INFO] [stderr]    Compiling clap v2.33.1
[INFO] [stderr]    Compiling regex v1.3.9
[INFO] [stderr]    Compiling console v0.11.3
[INFO] [stderr]    Compiling indicatif v0.15.0
[INFO] [stderr]    Compiling structopt-derive v0.4.8
[INFO] [stderr]    Compiling structopt v0.3.15
[INFO] [stderr]    Compiling happylog v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: field is never read: `out`
[INFO] [stdout]   --> src/console_emitter.rs:38:3
[INFO] [stdout]    |
[INFO] [stdout] 38 |   out: &'static AtomicPtr<Target>
[INFO] [stdout]    |   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.35s
[INFO] running `Command { std: "docker" "inspect" "37d7da07fd1628b7d3c56897223430b0bce914bf0fe5dce5577226056c640170", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "37d7da07fd1628b7d3c56897223430b0bce914bf0fe5dce5577226056c640170", kill_on_drop: false }`
[INFO] [stdout] 37d7da07fd1628b7d3c56897223430b0bce914bf0fe5dce5577226056c640170
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5a5e2994f8383129807a9936e85bb36baa8dbe95305fc42c3e50df326d975744
[INFO] running `Command { std: "docker" "start" "-a" "5a5e2994f8383129807a9936e85bb36baa8dbe95305fc42c3e50df326d975744", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling happylog v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: field is never read: `out`
[INFO] [stdout]   --> src/console_emitter.rs:38:3
[INFO] [stdout]    |
[INFO] [stdout] 38 |   out: &'static AtomicPtr<Target>
[INFO] [stdout]    |   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field is never read: `out`
[INFO] [stdout]   --> src/console_emitter.rs:38:3
[INFO] [stdout]    |
[INFO] [stdout] 38 |   out: &'static AtomicPtr<Target>
[INFO] [stdout]    |   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.09s
[INFO] running `Command { std: "docker" "inspect" "5a5e2994f8383129807a9936e85bb36baa8dbe95305fc42c3e50df326d975744", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5a5e2994f8383129807a9936e85bb36baa8dbe95305fc42c3e50df326d975744", kill_on_drop: false }`
[INFO] [stdout] 5a5e2994f8383129807a9936e85bb36baa8dbe95305fc42c3e50df326d975744
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-65/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" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bb8e2704a3d6bb65d56ec31e5e16b188ba69733476687db770832b3ce3027771
[INFO] running `Command { std: "docker" "start" "-a" "bb8e2704a3d6bb65d56ec31e5e16b188ba69733476687db770832b3ce3027771", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr] warning: field is never read: `out`
[INFO] [stderr]   --> src/console_emitter.rs:38:3
[INFO] [stderr]    |
[INFO] [stderr] 38 |   out: &'static AtomicPtr<Target>
[INFO] [stderr]    |   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `happylog` (lib) generated 1 warning
[INFO] [stderr] warning: `happylog` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 41.74s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/happylog-e837d40db74d1a96)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests happylog
[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 2 tests
[INFO] [stdout] test src/console_emitter.rs - console_emitter::set_progress (line 87) ... FAILED
[INFO] [stdout] test src/args.rs - args::LogOpts (line 10) ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/console_emitter.rs - console_emitter::set_progress (line 87) stdout ----
[INFO] [stdout] error: expected one of `!`, `.`, `::`, `;`, `?`, `{`, `}`, or an operator, found `of`
[INFO] [stdout]  --> src/console_emitter.rs:89:6
[INFO] [stdout]   |
[INFO] [stdout] 5 | lots of operations
[INFO] [stdout]   |      ^^ expected one of 8 possible tokens
[INFO] [stdout] 
[INFO] [stdout] error[E0425]: cannot find value `pb` in this scope
[INFO] [stdout]  --> src/console_emitter.rs:88:36
[INFO] [stdout]   |
[INFO] [stdout] 4 | let _pbs = happylog::set_progress(&pb);
[INFO] [stdout]   |                                    ^^ not found in this scope
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 2 previous errors
[INFO] [stdout] 
[INFO] [stdout] For more information about this error, try `rustc --explain E0425`.
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/args.rs - args::LogOpts (line 10) stdout ----
[INFO] [stdout] error: cannot find derive macro `StructOpt` in this scope
[INFO] [stdout]  --> src/args.rs:11:10
[INFO] [stdout]   |
[INFO] [stdout] 3 | #[derive(StructOpt, Debug)]
[INFO] [stdout]   |          ^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: consider importing this derive macro:
[INFO] [stdout]           structopt::StructOpt
[INFO] [stdout] 
[INFO] [stdout] error: cannot find attribute `structopt` in this scope
[INFO] [stdout]  --> src/args.rs:12:3
[INFO] [stdout]   |
[INFO] [stdout] 4 | #[structopt(name="command")]
[INFO] [stdout]   |   ^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `structopt` is in scope, but it is a crate, not an attribute
[INFO] [stdout] 
[INFO] [stdout] error: cannot find attribute `structopt` in this scope
[INFO] [stdout]  --> src/args.rs:14:5
[INFO] [stdout]   |
[INFO] [stdout] 6 |   #[structopt(flatten)]
[INFO] [stdout]   |     ^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `structopt` is in scope, but it is a crate, not an attribute
[INFO] [stdout] 
[INFO] [stdout] error[E0412]: cannot find type `LogOpts` in this scope
[INFO] [stdout]  --> src/args.rs:15:12
[INFO] [stdout]   |
[INFO] [stdout] 7 |   logging: LogOpts
[INFO] [stdout]   |            ^^^^^^^ not found in this scope
[INFO] [stdout]   |
[INFO] [stdout] help: consider importing this struct
[INFO] [stdout]   |
[INFO] [stdout] 2 | use happylog::args::LogOpts;
[INFO] [stdout]   |
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 4 previous errors
[INFO] [stdout] 
[INFO] [stdout] For more information about this error, try `rustc --explain E0412`.
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/args.rs - args::LogOpts (line 10)
[INFO] [stdout]     src/console_emitter.rs - console_emitter::set_progress (line 87)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.31s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--doc'
[INFO] running `Command { std: "docker" "inspect" "bb8e2704a3d6bb65d56ec31e5e16b188ba69733476687db770832b3ce3027771", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bb8e2704a3d6bb65d56ec31e5e16b188ba69733476687db770832b3ce3027771", kill_on_drop: false }`
[INFO] [stdout] bb8e2704a3d6bb65d56ec31e5e16b188ba69733476687db770832b3ce3027771