[INFO] cloning repository https://github.com/ymgyt/proconio-handson
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ymgyt/proconio-handson" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fymgyt%2Fproconio-handson", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fymgyt%2Fproconio-handson'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f340cf330d9976566f4eced5674d8d52d27965d8
[INFO] building ymgyt/proconio-handson against master#461e8078010433ff7de2db2aaae8a3cfb0847215 for pr-94962
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fymgyt%2Fproconio-handson" "/workspace/builds/worker-71/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-71/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/ymgyt/proconio-handson on toolchain 461e8078010433ff7de2db2aaae8a3cfb0847215
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+461e8078010433ff7de2db2aaae8a3cfb0847215" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] removed /workspace/builds/worker-71/source/rust-toolchain
[INFO] started tweaking git repo https://github.com/ymgyt/proconio-handson
[INFO] finished tweaking git repo https://github.com/ymgyt/proconio-handson
[INFO] tweaked toml for git repo https://github.com/ymgyt/proconio-handson written to /workspace/builds/worker-71/source/Cargo.toml
[INFO] crate git repo https://github.com/ymgyt/proconio-handson already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+461e8078010433ff7de2db2aaae8a3cfb0847215" "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-71/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-71/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:eaef2f80f755933c374d2ae5edccceec0c1312ceb8a4e0b6404e8ab76561e1e5" "/opt/rustwide/cargo-home/bin/cargo" "+461e8078010433ff7de2db2aaae8a3cfb0847215" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 75a347b75f23de09513ca8b37907e5cfe8e3c51f6eca1e905deaef1b1b184b19
[INFO] running `Command { std: "docker" "start" "-a" "75a347b75f23de09513ca8b37907e5cfe8e3c51f6eca1e905deaef1b1b184b19", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "75a347b75f23de09513ca8b37907e5cfe8e3c51f6eca1e905deaef1b1b184b19", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "75a347b75f23de09513ca8b37907e5cfe8e3c51f6eca1e905deaef1b1b184b19", kill_on_drop: false }`
[INFO] [stdout] 75a347b75f23de09513ca8b37907e5cfe8e3c51f6eca1e905deaef1b1b184b19
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-71/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-71/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:eaef2f80f755933c374d2ae5edccceec0c1312ceb8a4e0b6404e8ab76561e1e5" "/opt/rustwide/cargo-home/bin/cargo" "+461e8078010433ff7de2db2aaae8a3cfb0847215" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9a993bd85c6ffc266045d6b5e491392714f13ecc692863b093f518db4e814c84
[INFO] running `Command { std: "docker" "start" "-a" "9a993bd85c6ffc266045d6b5e491392714f13ecc692863b093f518db4e814c84", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling proconio-handson v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] note: trace_macro
[INFO] [stdout]    --> src/main.rs:370:5
[INFO] [stdout]     |
[INFO] [stdout] 370 | /     input! {
[INFO] [stdout] 371 | |         from source,
[INFO] [stdout] 372 | |         n: usize,
[INFO] [stdout] 373 | |         ts: [(i8, String); n],
[INFO] [stdout] 374 | |     }
[INFO] [stdout]     | |_____^
[INFO] [stdout]     |
[INFO] [stdout]     = note: expanding `input! { from source, n : usize, ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `let mut s = source ; input!
[INFO] [stdout]             { @ from [& mut s] @ rest n : usize, ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: expanding `input! { @ from [& mut s] @ rest n : usize, ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input! { @ from [&mut s] @ mut [] @ rest n : usize, ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ rest n : usize, ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input!
[INFO] [stdout]             {
[INFO] [stdout]                 @ from [&mut s] @ mut [] @ var n @ kind [] @ rest usize, ts :
[INFO] [stdout]                 [(i8, String) ; n],
[INFO] [stdout]             }`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var n @ kind [] @ rest usize, ts :
[INFO] [stdout]             [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input!
[INFO] [stdout]             (@ from [&mut s] @ mut [] @ var n @ kind [usize] @ rest, ts :
[INFO] [stdout]             [(i8, String) ; n],) ;`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var n @ kind [usize] @ rest, ts :
[INFO] [stdout]             [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input! (@ from [&mut s] @ mut [] @ var n @ kind [usize] @ rest) ; input!
[INFO] [stdout]             (@ from [&mut s] @ rest ts : [(i8, String) ; n],) ;`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var n @ kind [usize] @ rest }`
[INFO] [stdout]     = note: to `let n = read_value! (@ source [&mut s] @ kind [usize]) ;`
[INFO] [stdout]     = note: expanding `read_value! { @ source [&mut s] @ kind [usize] }`
[INFO] [stdout]     = note: to `< usize as Readable > :: read(&mut s)`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ rest ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input! { @ from [&mut s] @ mut [] @ rest ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ rest ts : [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input!
[INFO] [stdout]             { @ from [&mut s] @ mut [] @ var ts @ kind [] @ rest [(i8, String) ; n], }`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var ts @ kind [] @ rest [(i8, String) ; n], }`
[INFO] [stdout]     = note: to `input! (@ from [&mut s] @ mut [] @ var ts @ kind [[(i8, String) ; n]] @ rest,)
[INFO] [stdout]             ;`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var ts @ kind [[(i8, String) ; n]] @ rest, }`
[INFO] [stdout]     = note: to `input! (@ from [&mut s] @ mut [] @ var ts @ kind [[(i8, String) ; n]] @ rest)
[INFO] [stdout]             ; input! (@ from [&mut s] @ rest) ;`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ mut [] @ var ts @ kind [[(i8, String) ; n]] @ rest }`
[INFO] [stdout]     = note: to `let ts = read_value! (@ source [&mut s] @ kind [[(i8, String) ; n]]) ;`
[INFO] [stdout]     = note: expanding `read_value! { @ source [&mut s] @ kind [[(i8, String) ; n]] }`
[INFO] [stdout]     = note: to `read_value! (@ array @ source [&mut s] @ kind [] @ rest(i8, String) ; n)`
[INFO] [stdout]     = note: expanding `read_value! { @ array @ source [&mut s] @ kind [] @ rest(i8, String) ; n }`
[INFO] [stdout]     = note: to `read_value! (@ array @ source [&mut s] @ kind [(i8, String)] @ rest ; n)`
[INFO] [stdout]     = note: expanding `read_value! { @ array @ source [&mut s] @ kind [(i8, String)] @ rest ; n }`
[INFO] [stdout]     = note: to `read_value! (@ array @ source [&mut s] @ kind [(i8, String)] @ len [n])`
[INFO] [stdout]     = note: expanding `read_value! { @ array @ source [&mut s] @ kind [(i8, String)] @ len [n] }`
[INFO] [stdout]     = note: to `{
[INFO] [stdout]                 let len = n ;
[INFO] [stdout]                 (0 ..
[INFO] [stdout]                 len).map(| _ | read_value!
[INFO] [stdout]                 (@ source [&mut s] @ kind [(i8, String)])).collect :: < Vec < _ >> ()
[INFO] [stdout]             }`
[INFO] [stdout]     = note: expanding `read_value! { @ source [&mut s] @ kind [(i8, String)] }`
[INFO] [stdout]     = note: to `read_value!
[INFO] [stdout]             (@ tuple @ source [&mut s] @ kinds [] @ current [] @ rest i8, String)`
[INFO] [stdout]     = note: expanding `read_value! { @ tuple @ source [&mut s] @ kinds [] @ current [] @ rest i8, String }`
[INFO] [stdout]     = note: to `read_value!
[INFO] [stdout]             (@ tuple @ source [&mut s] @ kinds [] @ current [i8] @ rest, String)`
[INFO] [stdout]     = note: expanding `read_value! { @ tuple @ source [&mut s] @ kinds [] @ current [i8] @ rest, String }`
[INFO] [stdout]     = note: to `read_value!
[INFO] [stdout]             (@ tuple @ source [&mut s] @ kinds [[i8]] @ current [] @ rest String)`
[INFO] [stdout]     = note: expanding `read_value! { @ tuple @ source [&mut s] @ kinds [[i8]] @ current [] @ rest String }`
[INFO] [stdout]     = note: to `read_value!
[INFO] [stdout]             (@ tuple @ source [&mut s] @ kinds [[i8]] @ current [String] @ rest)`
[INFO] [stdout]     = note: expanding `read_value! { @ tuple @ source [&mut s] @ kinds [[i8]] @ current [String] @ rest }`
[INFO] [stdout]     = note: to `read_value!
[INFO] [stdout]             (@ tuple @ source [&mut s] @ kinds [[i8] [String]] @ current [] @ rest)`
[INFO] [stdout]     = note: expanding `read_value! { @ tuple @ source [&mut s] @ kinds [[i8] [String]] @ current [] @ rest }`
[INFO] [stdout]     = note: to `(read_value! (@ source [&mut s] @ kind [i8]), read_value!
[INFO] [stdout]             (@ source [&mut s] @ kind [String]),)`
[INFO] [stdout]     = note: expanding `read_value! { @ source [&mut s] @ kind [i8] }`
[INFO] [stdout]     = note: to `< i8 as Readable > :: read(&mut s)`
[INFO] [stdout]     = note: expanding `read_value! { @ source [&mut s] @ kind [String] }`
[INFO] [stdout]     = note: to `< String as Readable > :: read(&mut s)`
[INFO] [stdout]     = note: expanding `input! { @ from [&mut s] @ rest }`
[INFO] [stdout]     = note: to ``
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.57s
[INFO] running `Command { std: "docker" "inspect" "9a993bd85c6ffc266045d6b5e491392714f13ecc692863b093f518db4e814c84", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a993bd85c6ffc266045d6b5e491392714f13ecc692863b093f518db4e814c84", kill_on_drop: false }`
[INFO] [stdout] 9a993bd85c6ffc266045d6b5e491392714f13ecc692863b093f518db4e814c84
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-71/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-71/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:eaef2f80f755933c374d2ae5edccceec0c1312ceb8a4e0b6404e8ab76561e1e5" "/opt/rustwide/cargo-home/bin/cargo" "+461e8078010433ff7de2db2aaae8a3cfb0847215" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 80fbbcc62e07a85e92a93eaedbc630dc9a8566500261ac9b084ec36a15820894
[INFO] running `Command { std: "docker" "start" "-a" "80fbbcc62e07a85e92a93eaedbc630dc9a8566500261ac9b084ec36a15820894", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "80fbbcc62e07a85e92a93eaedbc630dc9a8566500261ac9b084ec36a15820894", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "80fbbcc62e07a85e92a93eaedbc630dc9a8566500261ac9b084ec36a15820894", kill_on_drop: false }`
[INFO] [stdout] 80fbbcc62e07a85e92a93eaedbc630dc9a8566500261ac9b084ec36a15820894