Dec 13 17:36:31.241 INFO testing hellopatrick/rays.rs against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 13 17:36:31.241 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 17:36:31.712 INFO [stdout] 97ed691159094a2edc5746c05c04e1db9c2d8e3cd14fde2faad2b705db3f2ec8 Dec 13 17:36:31.716 INFO running `"docker" "start" "-a" "97ed691159094a2edc5746c05c04e1db9c2d8e3cd14fde2faad2b705db3f2ec8"` Dec 13 17:36:33.047 INFO [stderr] usermod: no changes Dec 13 17:36:33.086 INFO [stderr] Compiling rays v0.1.0 (/source) Dec 13 17:36:36.063 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 2.95s Dec 13 17:36:36.067 INFO [stderr] su: No module specific data is present Dec 13 17:36:37.013 INFO running `"docker" "inspect" "97ed691159094a2edc5746c05c04e1db9c2d8e3cd14fde2faad2b705db3f2ec8"` Dec 13 17:36:37.159 INFO running `"docker" "rm" "-f" "97ed691159094a2edc5746c05c04e1db9c2d8e3cd14fde2faad2b705db3f2ec8"` Dec 13 17:36:37.335 INFO [stdout] 97ed691159094a2edc5746c05c04e1db9c2d8e3cd14fde2faad2b705db3f2ec8 Dec 13 17:36:37.335 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 17:36:37.639 INFO [stdout] 4ae6ee44c7cbd59774fb09c88f14227e1fe21eae88eb169dcce489b540e1740d Dec 13 17:36:37.639 INFO running `"docker" "start" "-a" "4ae6ee44c7cbd59774fb09c88f14227e1fe21eae88eb169dcce489b540e1740d"` Dec 13 17:36:38.430 INFO [stderr] usermod: no changes Dec 13 17:36:38.519 INFO [stderr] Compiling rays v0.1.0 (/source) Dec 13 17:36:44.925 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 6.46s Dec 13 17:36:44.926 INFO [stderr] su: No module specific data is present Dec 13 17:36:45.635 INFO running `"docker" "inspect" "4ae6ee44c7cbd59774fb09c88f14227e1fe21eae88eb169dcce489b540e1740d"` Dec 13 17:36:45.987 INFO running `"docker" "rm" "-f" "4ae6ee44c7cbd59774fb09c88f14227e1fe21eae88eb169dcce489b540e1740d"` Dec 13 17:36:46.397 INFO [stdout] 4ae6ee44c7cbd59774fb09c88f14227e1fe21eae88eb169dcce489b540e1740d Dec 13 17:36:46.400 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 13 17:36:47.019 INFO [stdout] 99f2a7a2e1ab0ad1b1f348a0e296875771f2c0ad6a357f8c6a5f0e0d9b9d2dac Dec 13 17:36:47.027 INFO running `"docker" "start" "-a" "99f2a7a2e1ab0ad1b1f348a0e296875771f2c0ad6a357f8c6a5f0e0d9b9d2dac"` Dec 13 17:36:49.335 INFO [stderr] usermod: no changes Dec 13 17:36:49.421 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.06s Dec 13 17:36:49.427 INFO [stderr] Running /target/debug/deps/rays-8a725d869a79c1b9 Dec 13 17:36:49.427 INFO [stdout] Dec 13 17:36:49.427 INFO [stdout] running 1 test Dec 13 17:36:49.433 INFO [stdout] test roughly_equal ... ok Dec 13 17:36:49.433 INFO [stdout] Dec 13 17:36:49.433 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.433 INFO [stdout] Dec 13 17:36:49.433 INFO [stdout] Dec 13 17:36:49.433 INFO [stdout] running 0 tests Dec 13 17:36:49.433 INFO [stdout] Dec 13 17:36:49.433 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.433 INFO [stdout] Dec 13 17:36:49.433 INFO [stderr] Running /target/debug/deps/clock-d2090086c9e4671c Dec 13 17:36:49.433 INFO [stderr] Running /target/debug/deps/projectile-eda00ec92ab1974e Dec 13 17:36:49.435 INFO [stdout] Dec 13 17:36:49.435 INFO [stdout] running 0 tests Dec 13 17:36:49.435 INFO [stdout] Dec 13 17:36:49.435 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.435 INFO [stdout] Dec 13 17:36:49.435 INFO [stderr] Running /target/debug/deps/sphere-ccc28c3505a3c4d3 Dec 13 17:36:49.443 INFO [stdout] Dec 13 17:36:49.443 INFO [stdout] running 0 tests Dec 13 17:36:49.443 INFO [stdout] Dec 13 17:36:49.443 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.443 INFO [stdout] Dec 13 17:36:49.455 INFO [stderr] Running /target/debug/deps/canvas-19b7dcbc36f9bc2a Dec 13 17:36:49.455 INFO [stdout] Dec 13 17:36:49.455 INFO [stdout] running 4 tests Dec 13 17:36:49.455 INFO [stdout] test canvas_test::painting ... ok Dec 13 17:36:49.455 INFO [stdout] test canvas_test::create ... ok Dec 13 17:36:49.457 INFO [stderr] Running /target/debug/deps/color-a2e2c1b0c4596aa3 Dec 13 17:36:49.458 INFO [stdout] test canvas_test::ppm_body ... ok Dec 13 17:36:49.458 INFO [stdout] test canvas_test::ppm_header ... ok Dec 13 17:36:49.458 INFO [stdout] Dec 13 17:36:49.458 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.458 INFO [stdout] Dec 13 17:36:49.458 INFO [stdout] Dec 13 17:36:49.458 INFO [stdout] running 7 tests Dec 13 17:36:49.458 INFO [stdout] test color_test::sub ... ok Dec 13 17:36:49.458 INFO [stdout] test color_test::mul ... ok Dec 13 17:36:49.458 INFO [stdout] test color_test::add ... ok Dec 13 17:36:49.467 INFO [stdout] test color_test::hadamard_product ... ok Dec 13 17:36:49.467 INFO [stdout] test color_test::to_pixel ... ok Dec 13 17:36:49.467 INFO [stdout] test color_test::creation ... ok Dec 13 17:36:49.467 INFO [stdout] test color_test::clamp ... ok Dec 13 17:36:49.467 INFO [stdout] Dec 13 17:36:49.467 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.467 INFO [stdout] Dec 13 17:36:49.467 INFO [stderr] Running /target/debug/deps/intersection-7c79134743bd31e5 Dec 13 17:36:49.475 INFO [stderr] Running /target/debug/deps/light-d8f22e7d66beac8c Dec 13 17:36:49.475 INFO [stdout] Dec 13 17:36:49.475 INFO [stdout] running 2 tests Dec 13 17:36:49.475 INFO [stdout] test shape_test::no_hit ... ok Dec 13 17:36:49.475 INFO [stdout] test shape_test::some_hit ... ok Dec 13 17:36:49.475 INFO [stdout] Dec 13 17:36:49.475 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.475 INFO [stdout] Dec 13 17:36:49.475 INFO [stdout] Dec 13 17:36:49.475 INFO [stdout] running 1 test Dec 13 17:36:49.479 INFO [stdout] test light_test::create_point_light ... ok Dec 13 17:36:49.479 INFO [stdout] Dec 13 17:36:49.479 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.479 INFO [stdout] Dec 13 17:36:49.479 INFO [stdout] Dec 13 17:36:49.479 INFO [stdout] running 6 tests Dec 13 17:36:49.479 INFO [stderr] Running /target/debug/deps/material-4e6b5cd75f903285 Dec 13 17:36:49.487 INFO [stderr] Running /target/debug/deps/matrix-db98b9bc746a5b23 Dec 13 17:36:49.487 INFO [stdout] test material_test::lighting_and_eye_reflected ... ok Dec 13 17:36:49.487 INFO [stdout] test material_test::lighting_above_eye ... ok Dec 13 17:36:49.487 INFO [stdout] test material_test::lighting_under_eye ... ok Dec 13 17:36:49.487 INFO [stdout] test material_test::lighting_behind_eye ... ok Dec 13 17:36:49.487 INFO [stdout] test material_test::create ... ok Dec 13 17:36:49.487 INFO [stdout] test material_test::lighting_behind ... ok Dec 13 17:36:49.487 INFO [stdout] Dec 13 17:36:49.487 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.487 INFO [stdout] Dec 13 17:36:49.487 INFO [stdout] Dec 13 17:36:49.487 INFO [stdout] running 17 tests Dec 13 17:36:49.495 INFO [stdout] test matrix_test::eq ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::determinant_4x4 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::cofactor_3x3 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::determinant_3x3 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::identity ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::determinant_2x2 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::create_and_retrieve ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::invert ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::submatrix_3x3 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::invertibility ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::rows_and_cols ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::submatrix_4x4 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::minor_3x3 ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::mul_matrix_and_tuple ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::transpose ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::mul_two_matrices ... ok Dec 13 17:36:49.503 INFO [stdout] test matrix_test::more_invert ... ok Dec 13 17:36:49.503 INFO [stdout] Dec 13 17:36:49.503 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.503 INFO [stdout] Dec 13 17:36:49.503 INFO [stdout] Dec 13 17:36:49.503 INFO [stdout] running 4 tests Dec 13 17:36:49.503 INFO [stdout] test ray_test::scale_ray ... ok Dec 13 17:36:49.503 INFO [stdout] test ray_test::translate_ray ... ok Dec 13 17:36:49.503 INFO [stderr] Running /target/debug/deps/ray-8a09520d32d3b27c Dec 13 17:36:49.531 INFO [stderr] Running /target/debug/deps/shape-8f22af56cdd82b09 Dec 13 17:36:49.532 INFO [stdout] test ray_test::create ... ok Dec 13 17:36:49.532 INFO [stdout] test ray_test::points_along_ray ... ok Dec 13 17:36:49.532 INFO [stdout] Dec 13 17:36:49.532 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.532 INFO [stdout] Dec 13 17:36:49.543 INFO [stdout] Dec 13 17:36:49.547 INFO [stdout] running 7 tests Dec 13 17:36:49.547 INFO [stdout] test sphere_test::sphere_intersect_inside ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::intersect_translated_sphere ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::sphere_intersect ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::intersect_scaled_sphere ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::sphere_intersect_tangent ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::sphere_normals ... ok Dec 13 17:36:49.547 INFO [stdout] test sphere_test::transformed_sphere_normals ... ok Dec 13 17:36:49.547 INFO [stdout] Dec 13 17:36:49.547 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.547 INFO [stdout] Dec 13 17:36:49.547 INFO [stderr] Running /target/debug/deps/transform-cddb94d6511e9188 Dec 13 17:36:49.551 INFO [stdout] Dec 13 17:36:49.551 INFO [stdout] running 13 tests Dec 13 17:36:49.551 INFO [stdout] test transform_test::chaining ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::rotate_point_z ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::scale_vector ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::rotate_point_y ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::rotate_point_x ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::fluent_chaining ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::reflect_point ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::scale_vector_inverse ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::shear ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::translate_point ... ok Dec 13 17:36:49.551 INFO [stdout] test transform_test::translate_point_inverse ... ok Dec 13 17:36:49.559 INFO [stderr] Running /target/debug/deps/tuple-5c2af78420924a91 Dec 13 17:36:49.559 INFO [stdout] test transform_test::translate_vector ... ok Dec 13 17:36:49.559 INFO [stdout] test transform_test::scale_point ... ok Dec 13 17:36:49.559 INFO [stdout] Dec 13 17:36:49.559 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.559 INFO [stdout] Dec 13 17:36:49.559 INFO [stdout] Dec 13 17:36:49.559 INFO [stdout] running 10 tests Dec 13 17:36:49.559 INFO [stdout] test tuple_test::can_add ... ok Dec 13 17:36:49.559 INFO [stdout] test tuple_test::can_magnitude ... ok Dec 13 17:36:49.559 INFO [stdout] test tuple_test::can_cross ... ok Dec 13 17:36:49.559 INFO [stdout] test tuple_test::can_dot ... ok Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_div ... ok Dec 13 17:36:49.567 INFO [stderr] Doc-tests rays Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_reflect ... ok Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_neg ... ok Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_normalize ... ok Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_mul ... ok Dec 13 17:36:49.567 INFO [stdout] test tuple_test::can_sub ... ok Dec 13 17:36:49.567 INFO [stdout] Dec 13 17:36:49.567 INFO [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:49.567 INFO [stdout] Dec 13 17:36:50.169 INFO [stdout] Dec 13 17:36:50.169 INFO [stdout] running 0 tests Dec 13 17:36:50.169 INFO [stdout] Dec 13 17:36:50.169 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 17:36:50.169 INFO [stdout] Dec 13 17:36:50.176 INFO [stderr] su: No module specific data is present Dec 13 17:36:51.473 INFO running `"docker" "inspect" "99f2a7a2e1ab0ad1b1f348a0e296875771f2c0ad6a357f8c6a5f0e0d9b9d2dac"` Dec 13 17:36:51.841 INFO running `"docker" "rm" "-f" "99f2a7a2e1ab0ad1b1f348a0e296875771f2c0ad6a357f8c6a5f0e0d9b9d2dac"` Dec 13 17:36:52.213 INFO [stdout] 99f2a7a2e1ab0ad1b1f348a0e296875771f2c0ad6a357f8c6a5f0e0d9b9d2dac