[INFO] cloning repository https://github.com/brsnb/ray_trace
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/brsnb/ray_trace" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbrsnb%2Fray_trace", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbrsnb%2Fray_trace'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 092ad07cd86ff75a47d1a15278a1de609903da78
[INFO] checking brsnb/ray_trace against try#ad93f57d532978d6739002a21bc66b45ed22f998 for pr-155418
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbrsnb%2Fray_trace" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/brsnb/ray_trace
[INFO] finished tweaking git repo https://github.com/brsnb/ray_trace
[INFO] tweaked toml for git repo https://github.com/brsnb/ray_trace written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/brsnb/ray_trace on toolchain ad93f57d532978d6739002a21bc66b45ed22f998
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad93f57d532978d6739002a21bc66b45ed22f998" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/brsnb/ray_trace 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" "+ad93f57d532978d6739002a21bc66b45ed22f998" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[ERROR] error running command: no output for 300 seconds
[INFO] checking brsnb/ray_trace against try#ad93f57d532978d6739002a21bc66b45ed22f998 for pr-155418
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fbrsnb%2Fray_trace" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/brsnb/ray_trace
[INFO] finished tweaking git repo https://github.com/brsnb/ray_trace
[INFO] tweaked toml for git repo https://github.com/brsnb/ray_trace written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/brsnb/ray_trace on toolchain ad93f57d532978d6739002a21bc66b45ed22f998
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad93f57d532978d6739002a21bc66b45ed22f998" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/brsnb/ray_trace 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" "+ad93f57d532978d6739002a21bc66b45ed22f998" "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 lazy_static v1.3.0
[INFO] [stderr]   Downloaded rand_core v0.5.0
[INFO] [stderr]   Downloaded rand v0.7.0
[INFO] [stderr]   Downloaded c2-chacha v0.2.2
[INFO] [stderr]   Downloaded spin v0.5.0
[INFO] [stderr]   Downloaded autocfg v0.1.4
[INFO] [stderr]   Downloaded rand_chacha v0.2.0
[INFO] [stderr]   Downloaded ppv-lite86 v0.2.5
[INFO] [stderr]   Downloaded getrandom v0.1.6
[INFO] [stderr]   Downloaded libc v0.2.58
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ad93f57d532978d6739002a21bc66b45ed22f998" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] e5df3ae28cfae57ab5984444c418856ae22d410f320ca7807d8f988ec292844f
[INFO] running `Command { std: "docker" "start" "-a" "e5df3ae28cfae57ab5984444c418856ae22d410f320ca7807d8f988ec292844f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e5df3ae28cfae57ab5984444c418856ae22d410f320ca7807d8f988ec292844f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e5df3ae28cfae57ab5984444c418856ae22d410f320ca7807d8f988ec292844f", kill_on_drop: false }`
[INFO] [stdout] e5df3ae28cfae57ab5984444c418856ae22d410f320ca7807d8f988ec292844f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ad93f57d532978d6739002a21bc66b45ed22f998" "check" "--frozen" "--all" "--all-targets" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e937d6b5fda2aabc40a1309229dfc171ebc6ca67303383aac5858e2e95acfd72
[INFO] running `Command { std: "docker" "start" "-a" "e937d6b5fda2aabc40a1309229dfc171ebc6ca67303383aac5858e2e95acfd72", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.58
[INFO] [stderr]     Checking spin v0.5.0
[INFO] [stderr]    Compiling autocfg v0.1.4
[INFO] [stderr]     Checking ppv-lite86 v0.2.5
[INFO] [stderr]     Checking lazy_static v1.3.0
[INFO] [stderr]    Compiling rand_chacha v0.2.0
[INFO] [stderr]     Checking c2-chacha v0.2.2
[INFO] [stderr]     Checking getrandom v0.1.6
[INFO] [stderr]     Checking rand_core v0.5.0
[INFO] [stderr]     Checking rand v0.7.0
[INFO] [stderr]     Checking ray_trace v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unnecessary parentheses around block return value
[INFO] [stdout]   --> src/util.rs:50:9
[INFO] [stdout]    |
[INFO] [stdout] 50 |         (self.x * self.x + self.y * self.y + self.z * self.z)
[INFO] [stdout]    |         ^                                                   ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] help: remove these parentheses
[INFO] [stdout]    |
[INFO] [stdout] 50 -         (self.x * self.x + self.y * self.y + self.z * self.z)
[INFO] [stdout] 50 +         self.x * self.x + self.y * self.y + self.z * self.z 
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unnecessary parentheses around block return value
[INFO] [stdout]   --> src/util.rs:50:9
[INFO] [stdout]    |
[INFO] [stdout] 50 |         (self.x * self.x + self.y * self.y + self.z * self.z)
[INFO] [stdout]    |         ^                                                   ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] help: remove these parentheses
[INFO] [stdout]    |
[INFO] [stdout] 50 -         (self.x * self.x + self.y * self.y + self.z * self.z)
[INFO] [stdout] 50 +         self.x * self.x + self.y * self.y + self.z * self.z 
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `ray_in`
[INFO] [stdout]   --> src/material.rs:43:9
[INFO] [stdout]    |
[INFO] [stdout] 43 |         ray_in: &Ray,
[INFO] [stdout]    |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_ray_in`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `outward_normal` is never read
[INFO] [stdout]   --> src/material.rs:80:34
[INFO] [stdout]    |
[INFO] [stdout] 80 |         let mut outward_normal = Vec3f::new_unit_vec3f();
[INFO] [stdout]    |                                  ^^^^^^^^^^^^^^^^^^^^^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 92 |             outward_normal = record.normal;
[INFO] [stdout]    |             ------------------------------ `outward_normal` is overwritten here before the previous value is read
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `ni_over_nt` is never read
[INFO] [stdout]   --> src/material.rs:81:35
[INFO] [stdout]    |
[INFO] [stdout] 81 |         let mut ni_over_nt: f64 = 0.0;
[INFO] [stdout]    |                                   ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 93 |             ni_over_nt = 1.0 / *refractive_index;
[INFO] [stdout]    |             ------------------------------------ `ni_over_nt` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `cosine` is never read
[INFO] [stdout]   --> src/material.rs:83:31
[INFO] [stdout]    |
[INFO] [stdout] 83 |         let mut cosine: f64 = 0.0;
[INFO] [stdout]    |                               ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 94 |             cosine = -(ray_in.direction().dot(&record.normal)) / ray_in.direction().length();
[INFO] [stdout]    |             -------------------------------------------------------------------------------- `cosine` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `reflect_prob` is never read
[INFO] [stdout]    --> src/material.rs:97:37
[INFO] [stdout]     |
[INFO] [stdout]  97 |         let mut reflect_prob: f64 = 0.0;
[INFO] [stdout]     |                                     ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 107 |             reflect_prob = 1.0;
[INFO] [stdout]     |             ------------------ `reflect_prob` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `w` is never read
[INFO] [stdout]   --> src/camera.rs:13:5
[INFO] [stdout]    |
[INFO] [stdout]  6 | pub struct Camera {
[INFO] [stdout]    |            ------ field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 13 |     w: Vec3f,
[INFO] [stdout]    |     ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `ray_in`
[INFO] [stdout]   --> src/material.rs:43:9
[INFO] [stdout]    |
[INFO] [stdout] 43 |         ray_in: &Ray,
[INFO] [stdout]    |         ^^^^^^ help: if this is intentional, prefix it with an underscore: `_ray_in`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `outward_normal` is never read
[INFO] [stdout]   --> src/material.rs:80:34
[INFO] [stdout]    |
[INFO] [stdout] 80 |         let mut outward_normal = Vec3f::new_unit_vec3f();
[INFO] [stdout]    |                                  ^^^^^^^^^^^^^^^^^^^^^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 92 |             outward_normal = record.normal;
[INFO] [stdout]    |             ------------------------------ `outward_normal` is overwritten here before the previous value is read
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `ni_over_nt` is never read
[INFO] [stdout]   --> src/material.rs:81:35
[INFO] [stdout]    |
[INFO] [stdout] 81 |         let mut ni_over_nt: f64 = 0.0;
[INFO] [stdout]    |                                   ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 93 |             ni_over_nt = 1.0 / *refractive_index;
[INFO] [stdout]    |             ------------------------------------ `ni_over_nt` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `cosine` is never read
[INFO] [stdout]   --> src/material.rs:83:31
[INFO] [stdout]    |
[INFO] [stdout] 83 |         let mut cosine: f64 = 0.0;
[INFO] [stdout]    |                               ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 94 |             cosine = -(ray_in.direction().dot(&record.normal)) / ray_in.direction().length();
[INFO] [stdout]    |             -------------------------------------------------------------------------------- `cosine` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `reflect_prob` is never read
[INFO] [stdout]    --> src/material.rs:97:37
[INFO] [stdout]     |
[INFO] [stdout]  97 |         let mut reflect_prob: f64 = 0.0;
[INFO] [stdout]     |                                     ^^^ this value is reassigned later and never used
[INFO] [stdout] ...
[INFO] [stdout] 107 |             reflect_prob = 1.0;
[INFO] [stdout]     |             ------------------ `reflect_prob` is overwritten here before the previous value is read
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `w` is never read
[INFO] [stdout]   --> src/camera.rs:13:5
[INFO] [stdout]    |
[INFO] [stdout]  6 | pub struct Camera {
[INFO] [stdout]    |            ------ field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 13 |     w: Vec3f,
[INFO] [stdout]    |     ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1.64s
[INFO] running `Command { std: "docker" "inspect" "e937d6b5fda2aabc40a1309229dfc171ebc6ca67303383aac5858e2e95acfd72", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e937d6b5fda2aabc40a1309229dfc171ebc6ca67303383aac5858e2e95acfd72", kill_on_drop: false }`
[INFO] [stdout] e937d6b5fda2aabc40a1309229dfc171ebc6ca67303383aac5858e2e95acfd72
