[INFO] cloning repository https://github.com/oliverpauffley/ray_tracing_challenge
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/oliverpauffley/ray_tracing_challenge" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Foliverpauffley%2Fray_tracing_challenge", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Foliverpauffley%2Fray_tracing_challenge'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 8244d14fd1240d09540fff149c762a90961510d1
[INFO] testing oliverpauffley/ray_tracing_challenge against beta-2022-04-10 for beta-1.61-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Foliverpauffley%2Fray_tracing_challenge" "/workspace/builds/worker-66/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-66/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/oliverpauffley/ray_tracing_challenge on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/oliverpauffley/ray_tracing_challenge
[INFO] finished tweaking git repo https://github.com/oliverpauffley/ray_tracing_challenge
[INFO] tweaked toml for git repo https://github.com/oliverpauffley/ray_tracing_challenge written to /workspace/builds/worker-66/source/Cargo.toml
[INFO] crate git repo https://github.com/oliverpauffley/ray_tracing_challenge already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "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-66/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f188f8cb44124d6d0356aa2e78afeb989497f84268fe91da7e4a42d2f8412056
[INFO] running `Command { std: "docker" "start" "-a" "f188f8cb44124d6d0356aa2e78afeb989497f84268fe91da7e4a42d2f8412056", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f188f8cb44124d6d0356aa2e78afeb989497f84268fe91da7e4a42d2f8412056", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f188f8cb44124d6d0356aa2e78afeb989497f84268fe91da7e4a42d2f8412056", kill_on_drop: false }`
[INFO] [stdout] f188f8cb44124d6d0356aa2e78afeb989497f84268fe91da7e4a42d2f8412056
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 68eb447607d643d9aaf2b8b0d7db62fe592578d0345ea4a0824dd1a07c04d938
[INFO] running `Command { std: "docker" "start" "-a" "68eb447607d643d9aaf2b8b0d7db62fe592578d0345ea4a0824dd1a07c04d938", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.36
[INFO] [stderr]    Compiling libc v0.2.123
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.90
[INFO] [stderr]    Compiling rawpointer v0.2.1
[INFO] [stderr]    Compiling array v0.0.1
[INFO] [stderr]    Compiling matrixmultiply v0.3.2
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling quote v1.0.17
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling num-complex v0.4.0
[INFO] [stderr]    Compiling ndarray v0.15.4
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling builder-derive v0.1.0 (/opt/rustwide/workdir/builder-derive)
[INFO] [stderr]    Compiling ray_tracer v0.0.1 (/opt/rustwide/workdir/ray-tracer)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 30s
[INFO] running `Command { std: "docker" "inspect" "68eb447607d643d9aaf2b8b0d7db62fe592578d0345ea4a0824dd1a07c04d938", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68eb447607d643d9aaf2b8b0d7db62fe592578d0345ea4a0824dd1a07c04d938", kill_on_drop: false }`
[INFO] [stdout] 68eb447607d643d9aaf2b8b0d7db62fe592578d0345ea4a0824dd1a07c04d938
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fccdce0bab6afc49a6be349444b5985fc3f83e6558b1984dcd00c342eea6b7f4
[INFO] running `Command { std: "docker" "start" "-a" "fccdce0bab6afc49a6be349444b5985fc3f83e6558b1984dcd00c342eea6b7f4", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling trybuild v1.0.58
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling glob v0.3.0
[INFO] [stderr]    Compiling termcolor v1.1.3
[INFO] [stderr]    Compiling dissimilar v1.0.3
[INFO] [stderr]    Compiling once_cell v1.10.0
[INFO] [stderr]    Compiling ray_tracer v0.0.1 (/opt/rustwide/workdir/ray-tracer)
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling builder-derive v0.1.0 (/opt/rustwide/workdir/builder-derive)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 32.97s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/builder_derive-6369bc5e85ac2270)
[INFO] [stderr]   Executable tests/tests.rs (/opt/rustwide/target/debug/deps/tests-ae1507c960575839)
[INFO] [stderr]   Executable unittests src/main.rs (/opt/rustwide/target/debug/deps/ray_tracer-e63d10165d2b0032)
[INFO] running `Command { std: "docker" "inspect" "fccdce0bab6afc49a6be349444b5985fc3f83e6558b1984dcd00c342eea6b7f4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fccdce0bab6afc49a6be349444b5985fc3f83e6558b1984dcd00c342eea6b7f4", kill_on_drop: false }`
[INFO] [stdout] fccdce0bab6afc49a6be349444b5985fc3f83e6558b1984dcd00c342eea6b7f4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-66/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8755bd990e2074c09e48c1b3c9e916312d8eb02e1784120bd91b8c1d2aa959ff
[INFO] running `Command { std: "docker" "start" "-a" "8755bd990e2074c09e48c1b3c9e916312d8eb02e1784120bd91b8c1d2aa959ff", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 7.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/builder_derive-6369bc5e85ac2270)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/tests.rs (/opt/rustwide/target/debug/deps/tests-ae1507c960575839)
[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 1 test
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling proc-macro2 v1.0.36
[INFO] [stderr]    Compiling syn v1.0.90
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stdout] test builder has been running for over 60 seconds
[INFO] [stderr]    Compiling quote v1.0.17
[INFO] [stderr]    Compiling builder-derive v0.1.0 (/opt/rustwide/workdir/builder-derive)
[INFO] [stderr]    Compiling builder-derive-tests v0.0.0 (/opt/rustwide/target/tests/builder-derive)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 09s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] test tests/builder/happy_path.rs [should pass] ... ok
[INFO] [stderr] test tests/builder/unrecognized-attribute.rs [should fail to compile] ... ok
[INFO] [stdout] test builder ... ok
[INFO] [stderr] 
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 69.95s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/ray_tracer-e63d10165d2b0032)
[INFO] [stdout] 
[INFO] [stdout] running 89 tests
[INFO] [stdout] test primatives::color::test_color::test_adding_color ... ok
[INFO] [stdout] test primatives::color::test_color::test_multiplying_color ... ok
[INFO] [stdout] test primatives::color::test_color::test_subtracting_color ... ok
[INFO] [stdout] test primatives::color::test_color::test_new ... ok
[INFO] [stdout] test primatives::color::test_color::test_multiplying_scalar_color ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_2x2 ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_determinant ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_3x3 ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_determinant_3x3 ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_cofactors ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_equal ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_minors ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_determinant_4x4 ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_multiply ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_multiply_identity ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_multiply_tuple ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_submatrix ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_transpose ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_new ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_is_invertible ... ok
[INFO] [stdout] test primatives::ray::test_ray::test_at ... ok
[INFO] [stdout] test primatives::point::test_point::test_new ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_inverser_reverse_product ... ok
[INFO] [stdout] test primatives::ray::test_ray::test_new ... ok
[INFO] [stdout] test primatives::ray::test_ray::test_transform ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_chaining ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_rotation_z ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_rotation_y ... ok
[INFO] [stdout] test primatives::tuple::test_tuple::test_subtraction ... ok
[INFO] [stdout] test primatives::tuple::test_tuple::test_addition ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_division ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_dot_product ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_cross_product ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_magnitude ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_translation ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_view_transformation ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_rotation_x ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_shearing ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_new ... ok
[INFO] [stdout] test primatives::transformation::test_transformation::test_scaling ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_negate ... ok
[INFO] [stdout] test primatives::matrix::test_matrix::test_inverse ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_normalize ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_reflection ... ok
[INFO] [stdout] test primatives::vector::test_vector::test_scalar_multiplication ... ok
[INFO] [stdout] test shapes::material::test_materials::test_builder ... ok
[INFO] [stdout] test shapes::material::test_materials::test_new ... ok
[INFO] [stdout] test shapes::plane::test_planes::test_intersects ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_hits_sphere_behind_ray ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_hits_misses ... ok
[INFO] [stdout] test shapes::patterns::ring::test_ring_pattern::test_ring_pattern ... ok
[INFO] [stdout] test shapes::patterns::striped::test_striped_pattern::test_stripe_new ... ok
[INFO] [stdout] test shapes::plane::test_planes::test_normal ... ok
[INFO] [stdout] test shapes::patterns::checkered::test_checkered_pattern::test_checkered ... ok
[INFO] [stdout] test shapes::patterns::gradient::test_gradient::test_color_at ... ok
[INFO] [stdout] test shapes::patterns::test_patterns::test_default_pattern_transformation ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_hits_ray_inside_sphere ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_hits_two_intersections ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_hits_tangent ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_normals ... ok
[INFO] [stdout] test shapes::patterns::test_patterns::test_assign_transform ... ok
[INFO] [stdout] test world::camera::test_camera::test_new ... ok
[INFO] [stdout] test world::camera::test_camera::test_pixel_size ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_sphere_materials ... ok
[INFO] [stdout] test world::canvas::test_canvas::test_new ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_sphere_set_transform ... ok
[INFO] [stdout] test world::canvas::test_canvas::test_save_canvas ... ok
[INFO] [stdout] test world::canvas::test_canvas::test_save_canvas_writes_4_pixels ... ok
[INFO] [stdout] test world::canvas::test_canvas::test_write_pixel ... ok
[INFO] [stdout] test world::canvas::test_canvas::test_save_canvas_writes_pixels ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_normal_of_transformed_sphere ... ok
[INFO] [stdout] test world::intersection::test_intersection::test_hit ... ok
[INFO] [stdout] test world::intersection::test_intersection::test_intersections ... ok
[INFO] [stdout] test world::intersection::test_intersection::test_new_intersection ... ok
[INFO] [stdout] test world::light::test_lights::test_lighting ... ok
[INFO] [stdout] test world::camera::test_camera::test_ray_for_pixel ... ok
[INFO] [stdout] test world::light::test_lights::test_new_point_light ... ok
[INFO] [stdout] test shapes::sphere::test_sphere::test_tranform_intersects ... ok
[INFO] [stdout] test shapes::test_shapes::test_transformation ... ok
[INFO] [stdout] test shapes::test_shapes::test_normal ... ok
[INFO] [stdout] test world::intersection::test_intersection::test_pre_compute ... ok
[INFO] [stdout] test world::light::test_lights::test_lighting_with_pattern ... ok
[INFO] [stdout] test world::test_world::test_intersect_ray ... ok
[INFO] [stdout] test world::test_world::test_is_shadowed ... ok
[INFO] [stdout] test shapes::patterns::test_patterns::test_at_shape ... ok
[INFO] [stdout] test world::test_world::test_default ... ok
[INFO] [stdout] test world::test_world::test_shade_hit ... ok
[INFO] [stdout] test world::test_world::test_color_at ... ok
[INFO] [stdout] test world::camera::test_camera::test_render ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests builder-derive
[INFO] [stdout] 
[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] running `Command { std: "docker" "inspect" "8755bd990e2074c09e48c1b3c9e916312d8eb02e1784120bd91b8c1d2aa959ff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8755bd990e2074c09e48c1b3c9e916312d8eb02e1784120bd91b8c1d2aa959ff", kill_on_drop: false }`
[INFO] [stdout] 8755bd990e2074c09e48c1b3c9e916312d8eb02e1784120bd91b8c1d2aa959ff