Dec 05 21:50:38.661 INFO testing hellopatrick/rays.rs against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 05 21:50:38.661 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 05 21:50:38.808 INFO [stdout] 266220b37438bb2e5c7e62eca6b3c314d6edebcbc517ae35394ffc727fc1e7c7 Dec 05 21:50:38.810 INFO running `"docker" "start" "-a" "266220b37438bb2e5c7e62eca6b3c314d6edebcbc517ae35394ffc727fc1e7c7"` Dec 05 21:50:39.388 INFO [stderr] usermod: no changes Dec 05 21:50:39.420 INFO [stderr] Compiling rays v0.1.0 (/source) Dec 05 21:50:40.999 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.59s Dec 05 21:50:41.004 INFO [stderr] su: No module specific data is present Dec 05 21:50:41.412 INFO running `"docker" "inspect" "266220b37438bb2e5c7e62eca6b3c314d6edebcbc517ae35394ffc727fc1e7c7"` Dec 05 21:50:41.610 INFO running `"docker" "rm" "-f" "266220b37438bb2e5c7e62eca6b3c314d6edebcbc517ae35394ffc727fc1e7c7"` Dec 05 21:50:41.807 INFO [stdout] 266220b37438bb2e5c7e62eca6b3c314d6edebcbc517ae35394ffc727fc1e7c7 Dec 05 21:50:41.810 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 05 21:50:42.116 INFO [stdout] 1d4b682b259efdcf21b4d5e54d21cafba3f02ba0885def023ecc81e2f0d5f827 Dec 05 21:50:42.118 INFO running `"docker" "start" "-a" "1d4b682b259efdcf21b4d5e54d21cafba3f02ba0885def023ecc81e2f0d5f827"` Dec 05 21:50:42.941 INFO [stderr] usermod: no changes Dec 05 21:50:42.976 INFO [stderr] Compiling rays v0.1.0 (/source) Dec 05 21:50:47.185 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4.22s Dec 05 21:50:47.192 INFO [stderr] su: No module specific data is present Dec 05 21:50:47.993 INFO running `"docker" "inspect" "1d4b682b259efdcf21b4d5e54d21cafba3f02ba0885def023ecc81e2f0d5f827"` Dec 05 21:50:48.308 INFO running `"docker" "rm" "-f" "1d4b682b259efdcf21b4d5e54d21cafba3f02ba0885def023ecc81e2f0d5f827"` Dec 05 21:50:48.536 INFO [stdout] 1d4b682b259efdcf21b4d5e54d21cafba3f02ba0885def023ecc81e2f0d5f827 Dec 05 21:50:48.542 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 05 21:50:48.910 INFO [stdout] e94da5ef29090e9ca2836c6f69869363c923b84a4519b335a6a9894791420e5a Dec 05 21:50:48.918 INFO running `"docker" "start" "-a" "e94da5ef29090e9ca2836c6f69869363c923b84a4519b335a6a9894791420e5a"` Dec 05 21:50:50.068 INFO [stderr] usermod: no changes Dec 05 21:50:50.152 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.05s Dec 05 21:50:50.152 INFO [stderr] Running /target/debug/deps/rays-2a0fe2ec46a6c8ab Dec 05 21:50:50.157 INFO [stderr] Running /target/debug/deps/clock-93a223111ee67611 Dec 05 21:50:50.157 INFO [stdout] Dec 05 21:50:50.157 INFO [stdout] running 1 test Dec 05 21:50:50.157 INFO [stdout] test roughly_equal ... ok Dec 05 21:50:50.157 INFO [stdout] Dec 05 21:50:50.157 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.157 INFO [stdout] Dec 05 21:50:50.161 INFO [stdout] Dec 05 21:50:50.161 INFO [stderr] Running /target/debug/deps/projectile-eb62d2ddecbd3d96 Dec 05 21:50:50.161 INFO [stdout] running 0 tests Dec 05 21:50:50.161 INFO [stderr] Running /target/debug/deps/sphere-af8431b08995fe6b Dec 05 21:50:50.161 INFO [stdout] Dec 05 21:50:50.161 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.161 INFO [stdout] Dec 05 21:50:50.161 INFO [stdout] Dec 05 21:50:50.162 INFO [stdout] running 0 tests Dec 05 21:50:50.162 INFO [stdout] Dec 05 21:50:50.162 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.162 INFO [stdout] Dec 05 21:50:50.162 INFO [stdout] Dec 05 21:50:50.162 INFO [stdout] running 0 tests Dec 05 21:50:50.162 INFO [stdout] Dec 05 21:50:50.162 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.162 INFO [stdout] Dec 05 21:50:50.164 INFO [stderr] Running /target/debug/deps/canvas-fbb5f539b4206d5d Dec 05 21:50:50.171 INFO [stderr] Running /target/debug/deps/color-bab1b6d181479139 Dec 05 21:50:50.171 INFO [stdout] Dec 05 21:50:50.171 INFO [stdout] running 4 tests Dec 05 21:50:50.171 INFO [stdout] test canvas_test::ppm_body ... ok Dec 05 21:50:50.171 INFO [stdout] test canvas_test::painting ... ok Dec 05 21:50:50.171 INFO [stdout] test canvas_test::ppm_header ... ok Dec 05 21:50:50.171 INFO [stdout] test canvas_test::create ... ok Dec 05 21:50:50.171 INFO [stdout] Dec 05 21:50:50.171 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.171 INFO [stdout] Dec 05 21:50:50.176 INFO [stdout] Dec 05 21:50:50.177 INFO [stdout] running 7 tests Dec 05 21:50:50.181 INFO [stdout] test color_test::creation ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::hadamard_product ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::clamp ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::add ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::sub ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::to_pixel ... ok Dec 05 21:50:50.181 INFO [stdout] test color_test::mul ... ok Dec 05 21:50:50.181 INFO [stdout] Dec 05 21:50:50.181 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.181 INFO [stdout] Dec 05 21:50:50.187 INFO [stdout] Dec 05 21:50:50.190 INFO [stderr] Running /target/debug/deps/intersection-058a69c4b6ce18d6 Dec 05 21:50:50.190 INFO [stdout] running 2 tests Dec 05 21:50:50.190 INFO [stdout] test shape_test::some_hit ... ok Dec 05 21:50:50.190 INFO [stdout] test shape_test::no_hit ... ok Dec 05 21:50:50.190 INFO [stdout] Dec 05 21:50:50.190 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.190 INFO [stdout] Dec 05 21:50:50.190 INFO [stderr] Running /target/debug/deps/light-0ecdd844a1f0fc1a Dec 05 21:50:50.194 INFO [stdout] Dec 05 21:50:50.199 INFO [stdout] running 1 test Dec 05 21:50:50.200 INFO [stdout] test light_test::create_point_light ... ok Dec 05 21:50:50.200 INFO [stderr] Running /target/debug/deps/material-833cba2387fbb3d2 Dec 05 21:50:50.200 INFO [stdout] Dec 05 21:50:50.200 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.200 INFO [stdout] Dec 05 21:50:50.202 INFO [stdout] Dec 05 21:50:50.202 INFO [stdout] running 6 tests Dec 05 21:50:50.202 INFO [stdout] test material_test::lighting_under_eye ... ok Dec 05 21:50:50.202 INFO [stdout] test material_test::lighting_above_eye ... ok Dec 05 21:50:50.208 INFO [stdout] test material_test::create ... ok Dec 05 21:50:50.208 INFO [stdout] test material_test::lighting_and_eye_reflected ... ok Dec 05 21:50:50.208 INFO [stdout] test material_test::lighting_behind ... ok Dec 05 21:50:50.208 INFO [stdout] test material_test::lighting_behind_eye ... ok Dec 05 21:50:50.208 INFO [stdout] Dec 05 21:50:50.208 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.208 INFO [stdout] Dec 05 21:50:50.209 INFO [stderr] Running /target/debug/deps/matrix-6743f0439b205ea2 Dec 05 21:50:50.216 INFO [stdout] Dec 05 21:50:50.216 INFO [stdout] running 17 tests Dec 05 21:50:50.216 INFO [stdout] test matrix_test::eq ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::determinant_4x4 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::determinant_3x3 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::determinant_2x2 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::cofactor_3x3 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::invertibility ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::minor_3x3 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::more_invert ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::mul_matrix_and_tuple ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::mul_two_matrices ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::rows_and_cols ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::submatrix_3x3 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::submatrix_4x4 ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::transpose ... ok Dec 05 21:50:50.216 INFO [stdout] test matrix_test::create_and_retrieve ... ok Dec 05 21:50:50.225 INFO [stdout] test matrix_test::identity ... ok Dec 05 21:50:50.225 INFO [stdout] test matrix_test::invert ... ok Dec 05 21:50:50.225 INFO [stdout] Dec 05 21:50:50.225 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.225 INFO [stdout] Dec 05 21:50:50.228 INFO [stderr] Running /target/debug/deps/ray-aed5b587748cc56c Dec 05 21:50:50.232 INFO [stdout] Dec 05 21:50:50.232 INFO [stdout] running 4 tests Dec 05 21:50:50.237 INFO [stdout] test ray_test::create ... ok Dec 05 21:50:50.237 INFO [stderr] Running /target/debug/deps/shape-1ad5988a2b9ce399 Dec 05 21:50:50.237 INFO [stdout] test ray_test::points_along_ray ... ok Dec 05 21:50:50.237 INFO [stdout] test ray_test::scale_ray ... ok Dec 05 21:50:50.237 INFO [stdout] test ray_test::translate_ray ... ok Dec 05 21:50:50.237 INFO [stdout] Dec 05 21:50:50.237 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.237 INFO [stdout] Dec 05 21:50:50.244 INFO [stdout] Dec 05 21:50:50.244 INFO [stdout] running 7 tests Dec 05 21:50:50.244 INFO [stdout] test sphere_test::intersect_scaled_sphere ... ok Dec 05 21:50:50.244 INFO [stdout] test sphere_test::sphere_intersect_tangent ... ok Dec 05 21:50:50.256 INFO [stdout] test sphere_test::sphere_intersect ... ok Dec 05 21:50:50.256 INFO [stderr] Running /target/debug/deps/transform-148308e3db3d750b Dec 05 21:50:50.256 INFO [stdout] test sphere_test::intersect_translated_sphere ... ok Dec 05 21:50:50.256 INFO [stdout] test sphere_test::transformed_sphere_normals ... ok Dec 05 21:50:50.256 INFO [stdout] test sphere_test::sphere_intersect_inside ... ok Dec 05 21:50:50.256 INFO [stdout] test sphere_test::sphere_normals ... ok Dec 05 21:50:50.256 INFO [stdout] Dec 05 21:50:50.256 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.256 INFO [stdout] Dec 05 21:50:50.260 INFO [stdout] Dec 05 21:50:50.260 INFO [stdout] running 13 tests Dec 05 21:50:50.269 INFO [stdout] test transform_test::chaining ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::reflect_point ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::scale_vector ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::fluent_chaining ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::scale_point ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::rotate_point_x ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::rotate_point_z ... ok Dec 05 21:50:50.269 INFO [stdout] test transform_test::rotate_point_y ... ok Dec 05 21:50:50.280 INFO [stdout] test transform_test::translate_vector ... ok Dec 05 21:50:50.280 INFO [stderr] Running /target/debug/deps/tuple-63a807e6839cf8be Dec 05 21:50:50.280 INFO [stdout] test transform_test::scale_vector_inverse ... ok Dec 05 21:50:50.280 INFO [stdout] test transform_test::shear ... ok Dec 05 21:50:50.280 INFO [stdout] test transform_test::translate_point ... ok Dec 05 21:50:50.280 INFO [stdout] test transform_test::translate_point_inverse ... ok Dec 05 21:50:50.280 INFO [stdout] Dec 05 21:50:50.280 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.280 INFO [stdout] Dec 05 21:50:50.288 INFO [stdout] Dec 05 21:50:50.288 INFO [stdout] running 10 tests Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_cross ... ok Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_add ... ok Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_div ... ok Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_dot ... ok Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_magnitude ... ok Dec 05 21:50:50.288 INFO [stdout] test tuple_test::can_neg ... ok Dec 05 21:50:50.291 INFO [stdout] test tuple_test::can_mul ... ok Dec 05 21:50:50.291 INFO [stdout] test tuple_test::can_normalize ... ok Dec 05 21:50:50.291 INFO [stdout] test tuple_test::can_sub ... ok Dec 05 21:50:50.291 INFO [stdout] test tuple_test::can_reflect ... ok Dec 05 21:50:50.291 INFO [stdout] Dec 05 21:50:50.291 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.291 INFO [stdout] Dec 05 21:50:50.295 INFO [stderr] Doc-tests rays Dec 05 21:50:50.876 INFO [stdout] Dec 05 21:50:50.876 INFO [stdout] running 0 tests Dec 05 21:50:50.876 INFO [stdout] Dec 05 21:50:50.876 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 05 21:50:50.876 INFO [stdout] Dec 05 21:50:50.882 INFO [stderr] su: No module specific data is present Dec 05 21:50:51.754 INFO running `"docker" "inspect" "e94da5ef29090e9ca2836c6f69869363c923b84a4519b335a6a9894791420e5a"` Dec 05 21:50:52.059 INFO running `"docker" "rm" "-f" "e94da5ef29090e9ca2836c6f69869363c923b84a4519b335a6a9894791420e5a"` Dec 05 21:50:52.412 INFO [stdout] e94da5ef29090e9ca2836c6f69869363c923b84a4519b335a6a9894791420e5a