Dec 06 05:31:15.239 INFO testing bhase/raytracerchallenge against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 05:31:15.240 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 05:31:15.435 INFO [stdout] c5b7c4c23b63818474127efb2b4604615d5aee6b4e87f533ebeb23322d68e878
Dec 06 05:31:15.438 INFO running `"docker" "start" "-a" "c5b7c4c23b63818474127efb2b4604615d5aee6b4e87f533ebeb23322d68e878"`
Dec 06 05:31:16.026 INFO [stderr] usermod: no changes
Dec 06 05:31:16.130 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 06 05:31:17.122 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.07s
Dec 06 05:31:17.130 INFO [stderr] su: No module specific data is present
Dec 06 05:31:17.660 INFO running `"docker" "inspect" "c5b7c4c23b63818474127efb2b4604615d5aee6b4e87f533ebeb23322d68e878"`
Dec 06 05:31:17.831 INFO running `"docker" "rm" "-f" "c5b7c4c23b63818474127efb2b4604615d5aee6b4e87f533ebeb23322d68e878"`
Dec 06 05:31:17.956 INFO [stdout] c5b7c4c23b63818474127efb2b4604615d5aee6b4e87f533ebeb23322d68e878
Dec 06 05:31:17.959 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 05:31:18.190 INFO [stdout] fc7369b8ba78ff515f81f7b271c72f9bc9e5ac1c05df54eb375bb0a2d514daeb
Dec 06 05:31:18.196 INFO running `"docker" "start" "-a" "fc7369b8ba78ff515f81f7b271c72f9bc9e5ac1c05df54eb375bb0a2d514daeb"`
Dec 06 05:31:19.344 INFO [stderr] usermod: no changes
Dec 06 05:31:19.684 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 06 05:31:29.186 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.82s
Dec 06 05:31:29.196 INFO [stderr] su: No module specific data is present
Dec 06 05:31:29.581 INFO running `"docker" "inspect" "fc7369b8ba78ff515f81f7b271c72f9bc9e5ac1c05df54eb375bb0a2d514daeb"`
Dec 06 05:31:29.686 INFO running `"docker" "rm" "-f" "fc7369b8ba78ff515f81f7b271c72f9bc9e5ac1c05df54eb375bb0a2d514daeb"`
Dec 06 05:31:29.767 INFO [stdout] fc7369b8ba78ff515f81f7b271c72f9bc9e5ac1c05df54eb375bb0a2d514daeb
Dec 06 05:31:29.770 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 06 05:31:30.058 INFO [stdout] 9fc0aa05cca861d2616eb0d9e32f6528934e425c0d1a7388d16d45ade389d4c1
Dec 06 05:31:30.060 INFO running `"docker" "start" "-a" "9fc0aa05cca861d2616eb0d9e32f6528934e425c0d1a7388d16d45ade389d4c1"`
Dec 06 05:31:31.076 INFO [stderr] usermod: no changes
Dec 06 05:31:31.216 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 06 05:31:31.225 INFO [stderr]      Running /target/debug/deps/raytracer_challenge-b993ee623345eda9
Dec 06 05:31:31.230 INFO [stdout] 
Dec 06 05:31:31.230 INFO [stdout] running 0 tests
Dec 06 05:31:31.230 INFO [stdout] 
Dec 06 05:31:31.230 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:31.230 INFO [stdout] 
Dec 06 05:31:31.230 INFO [stderr]      Running /target/debug/deps/raytracer-f44163bb0d79552e
Dec 06 05:31:31.232 INFO [stdout] 
Dec 06 05:31:31.232 INFO [stdout] running 0 tests
Dec 06 05:31:31.232 INFO [stdout] 
Dec 06 05:31:31.232 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:31.232 INFO [stdout] 
Dec 06 05:31:31.232 INFO [stderr]      Running /target/debug/deps/cucumber-e4b0d1b8a72da0b9
Dec 06 05:31:31.305 INFO [stdout] [Cucumber v0.5.0]
Dec 06 05:31:31.305 INFO [stdout] 
Dec 06 05:31:31.338 INFO [stdout] Feature: Tuples, Vectors, and Points                features/tuples.feature:1:1
Dec 06 05:31:31.338 INFO [stdout] 
Dec 06 05:31:31.338 INFO [stdout]  Scenario: A tuple with w=1.0 is a point           features/tuples.feature:3:11
Dec 06 05:31:31.342 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 1.0)           features/tuples.feature:4:3
Dec 06 05:31:31.345 INFO [stdout]   ✔ Then a.x = 4.3                                  features/tuples.feature:5:3
Dec 06 05:31:31.345 INFO [stdout]   ✔ And a.y = -4.2                                  features/tuples.feature:6:5
Dec 06 05:31:31.345 INFO [stdout]   ✔ And a.z = 3.1                                   features/tuples.feature:7:5
Dec 06 05:31:31.345 INFO [stdout]   ✔ And a.w = 1.0                                   features/tuples.feature:8:5
Dec 06 05:31:31.346 INFO [stdout]   ✔ And a is a point                                features/tuples.feature:9:5
Dec 06 05:31:31.347 INFO [stdout]   ✔ And a is not a vector                          features/tuples.feature:10:5
Dec 06 05:31:31.347 INFO [stdout] 
Dec 06 05:31:31.347 INFO [stdout]  Scenario: A tuple with w=0 is a vector           features/tuples.feature:12:11
Dec 06 05:31:31.348 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 0.0)          features/tuples.feature:13:3
Dec 06 05:31:31.348 INFO [stdout]   ✔ Then a.x = 4.3                                 features/tuples.feature:14:3
Dec 06 05:31:31.348 INFO [stdout]   ✔ And a.y = -4.2                                 features/tuples.feature:15:5
Dec 06 05:31:31.348 INFO [stdout]   ✔ And a.z = 3.1                                  features/tuples.feature:16:5
Dec 06 05:31:31.348 INFO [stdout]   ✔ And a.w = 0.0                                  features/tuples.feature:17:5
Dec 06 05:31:31.348 INFO [stdout]   ✔ And a is not a point                           features/tuples.feature:18:5
Dec 06 05:31:31.348 INFO [stdout]   ✔ And a is a vector                              features/tuples.feature:19:5
Dec 06 05:31:31.348 INFO [stdout] 
Dec 06 05:31:31.348 INFO [stdout]  Scenario: "point" describes tuples with w=1      features/tuples.feature:21:11
Dec 06 05:31:31.351 INFO [stdout]   ✔ Given p := point(4, -4, 3)                     features/tuples.feature:22:3
Dec 06 05:31:31.354 INFO [stdout]   ✔ Then p = tuple(4, -4, 3, 1)                    features/tuples.feature:23:3
Dec 06 05:31:31.354 INFO [stdout] 
Dec 06 05:31:31.354 INFO [stdout]  Scenario: "vector" describes tuples with w=0     features/tuples.feature:25:11
Dec 06 05:31:31.356 INFO [stdout]   ✔ Given v := vector(4, -4, 3)                    features/tuples.feature:26:3
Dec 06 05:31:31.357 INFO [stdout]   ✔ Then v = tuple(4, -4, 3, 0)                    features/tuples.feature:27:3
Dec 06 05:31:31.357 INFO [stdout] 
Dec 06 05:31:31.357 INFO [stdout]  Scenario: Adding two tuples                      features/tuples.feature:29:11
Dec 06 05:31:31.357 INFO [stdout]   ✔ Given a1 := tuple(3, -2, 5, 1)                 features/tuples.feature:30:3
Dec 06 05:31:31.358 INFO [stdout]   ✔ And a2 := tuple(-2, 3, 1, 0)                   features/tuples.feature:31:5
Dec 06 05:31:31.360 INFO [stdout]   ✔ Then a1 + a2 = tuple(1, 1, 6, 1)               features/tuples.feature:32:4
Dec 06 05:31:31.361 INFO [stdout] 
Dec 06 05:31:31.361 INFO [stdout]  Scenario: Subtracting two points                 features/tuples.feature:34:11
Dec 06 05:31:31.361 INFO [stdout]   ✔ Given p1 := point(3, 2, 1)                     features/tuples.feature:35:3
Dec 06 05:31:31.361 INFO [stdout]   ✔ And p2 := point(5, 6, 7)                       features/tuples.feature:36:5
Dec 06 05:31:31.364 INFO [stdout]   ✔ Then p1 - p2 = vector(-2, -4, -6)              features/tuples.feature:37:3
Dec 06 05:31:31.364 INFO [stdout] 
Dec 06 05:31:31.364 INFO [stdout]  Scenario: Subtracting a vector from a point      features/tuples.feature:39:11
Dec 06 05:31:31.364 INFO [stdout]   ✔ Given p := point(3, 2, 1)                      features/tuples.feature:40:3
Dec 06 05:31:31.364 INFO [stdout]   ✔ And v := vector(5, 6, 7)                       features/tuples.feature:41:5
Dec 06 05:31:31.367 INFO [stdout]   ✔ Then p - v = point(-2, -4, -6)                 features/tuples.feature:42:3
Dec 06 05:31:31.367 INFO [stdout] 
Dec 06 05:31:31.367 INFO [stdout]  Scenario: Subtracting two vectors                features/tuples.feature:44:11
Dec 06 05:31:31.367 INFO [stdout]   ✔ Given v1 := vector(3, 2, 1)                    features/tuples.feature:45:3
Dec 06 05:31:31.367 INFO [stdout]   ✔ And v2 := vector(5, 6, 7)                      features/tuples.feature:46:5
Dec 06 05:31:31.370 INFO [stdout]   ✔ Then v1 - v2 = vector(-2, -4, -6)              features/tuples.feature:47:3
Dec 06 05:31:31.370 INFO [stdout] 
Dec 06 05:31:31.370 INFO [stdout]  Scenario: Subtracting a vector from the zero vector
Dec 06 05:31:31.370 INFO [stdout]                                                   features/tuples.feature:49:11
Dec 06 05:31:31.370 INFO [stdout]   ✔ Given zero := vector(0, 0, 0)                  features/tuples.feature:50:3
Dec 06 05:31:31.371 INFO [stdout]   ✔ And v2 := vector(1, -2, 3)                     features/tuples.feature:51:5
Dec 06 05:31:31.371 INFO [stdout]   ✔ Then zero - v2 = vector(-1, 2, -3)             features/tuples.feature:52:3
Dec 06 05:31:31.372 INFO [stdout] 
Dec 06 05:31:31.372 INFO [stdout]  Scenario: Negating a tuple                       features/tuples.feature:54:11
Dec 06 05:31:31.372 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:55:3
Dec 06 05:31:31.375 INFO [stdout]   ✔ Then -a = tuple(-1, 2, -3, 4)                  features/tuples.feature:56:3
Dec 06 05:31:31.376 INFO [stdout] 
Dec 06 05:31:31.376 INFO [stdout]  Scenario: Multiplying a tuple by a scalar        features/tuples.feature:58:11
Dec 06 05:31:31.376 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:59:3
Dec 06 05:31:31.381 INFO [stdout]   ✔ Then a * 3.5 = tuple(3.5, -7, 10.5, -14)       features/tuples.feature:60:3
Dec 06 05:31:31.381 INFO [stdout] 
Dec 06 05:31:31.381 INFO [stdout]  Scenario: Multiplying a tuple by a fraction      features/tuples.feature:62:11
Dec 06 05:31:31.381 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:63:3
Dec 06 05:31:31.382 INFO [stdout]   ✔ Then a * 0.5 = tuple(0.5, -1, 1.5, -2)         features/tuples.feature:64:3
Dec 06 05:31:31.382 INFO [stdout] 
Dec 06 05:31:31.382 INFO [stdout]  Scenario: Dividing a tuple by a scalar           features/tuples.feature:66:11
Dec 06 05:31:31.382 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:67:3
Dec 06 05:31:31.386 INFO [stdout]   ✔ Then a / 2 = tuple(0.5, -1, 1.5, -2)           features/tuples.feature:68:3
Dec 06 05:31:31.386 INFO [stdout] 
Dec 06 05:31:31.387 INFO [stdout]  Scenario: Magnitude of vector(1, 0, 0)           features/tuples.feature:70:11
Dec 06 05:31:31.387 INFO [stdout]   ✔ Given v := vector(1, 0, 0)                     features/tuples.feature:71:3
Dec 06 05:31:31.388 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:72:3
Dec 06 05:31:31.388 INFO [stdout] 
Dec 06 05:31:31.388 INFO [stdout]  Scenario: Magnitude of vector(0, 1, 0)           features/tuples.feature:74:11
Dec 06 05:31:31.389 INFO [stdout]   ✔ Given v := vector(0, 1, 0)                     features/tuples.feature:75:3
Dec 06 05:31:31.389 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:76:3
Dec 06 05:31:31.389 INFO [stdout] 
Dec 06 05:31:31.389 INFO [stdout]  Scenario: Magnitude of vector(0, 0, 1)           features/tuples.feature:78:11
Dec 06 05:31:31.389 INFO [stdout]   ✔ Given v := vector(0, 0, 1)                     features/tuples.feature:79:3
Dec 06 05:31:31.389 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:80:3
Dec 06 05:31:31.389 INFO [stdout] 
Dec 06 05:31:31.389 INFO [stdout]  Scenario: Magnitude of vector(1, 2, 3)           features/tuples.feature:82:11
Dec 06 05:31:31.389 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:83:3
Dec 06 05:31:31.389 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:84:3
Dec 06 05:31:31.389 INFO [stdout] 
Dec 06 05:31:31.389 INFO [stdout]  Scenario: Magnitude of vector(-1, -2, -3)        features/tuples.feature:86:11
Dec 06 05:31:31.390 INFO [stdout]   ✔ Given v := vector(-1, -2, -3)                  features/tuples.feature:87:3
Dec 06 05:31:31.390 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:88:3
Dec 06 05:31:31.390 INFO [stdout] 
Dec 06 05:31:31.390 INFO [stdout]  Scenario: Normalizing vector(4, 0, 0) gives (1, 0, 0)
Dec 06 05:31:31.390 INFO [stdout]                                                   features/tuples.feature:90:11
Dec 06 05:31:31.391 INFO [stdout]   ✔ Given v := vector(4, 0, 0)                     features/tuples.feature:91:3
Dec 06 05:31:31.394 INFO [stdout]   ✔ Then normalize(v) = vector(1, 0, 0)            features/tuples.feature:92:3
Dec 06 05:31:31.394 INFO [stdout] 
Dec 06 05:31:31.394 INFO [stdout]  Scenario: Normalizing vector(1, 2, 3)            features/tuples.feature:94:11
Dec 06 05:31:31.394 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:95:3
Dec 06 05:31:31.400 INFO [stdout]   ✔ Then normalize(v) = approximately vector(0.26726, 0.53452, 0.80178)
Dec 06 05:31:31.400 INFO [stdout]                                                    features/tuples.feature:97:3
Dec 06 05:31:31.400 INFO [stdout] 
Dec 06 05:31:31.400 INFO [stdout]  Scenario: The magnitude of a normalized vector   features/tuples.feature:99:11
Dec 06 05:31:31.400 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                    features/tuples.feature:100:3
Dec 06 05:31:31.400 INFO [stdout]   - When norm := normalize(v)                     features/tuples.feature:101:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then magnitude(norm) = 1                      features/tuples.feature:102:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: The dot product of two tuples         features/tuples.feature:104:11
Dec 06 05:31:31.401 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:105:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:106:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then a dot b = 20                             features/tuples.feature:107:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Cross product of two vectors          features/tuples.feature:109:11
Dec 06 05:31:31.401 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:110:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:111:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then a cross b = vector(-1, 2, -1)            features/tuples.feature:112:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And b cross a = vector(1, -2, 1)              features/tuples.feature:113:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Colors are (red, green, blue) tuples  features/tuples.feature:115:11
Dec 06 05:31:31.401 INFO [stdout]   - Given c := color(-0.5, 0.4, 1.7)              features/tuples.feature:116:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then c.red = -0.5                             features/tuples.feature:117:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And c.green = 0.4                             features/tuples.feature:118:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And c.blue = 1.7                              features/tuples.feature:119:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Adding colors                         features/tuples.feature:121:11
Dec 06 05:31:31.401 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:122:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:123:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then c1 + c2 = color(1.6, 0.7, 1.0)           features/tuples.feature:124:4
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Subtracting colors                    features/tuples.feature:126:11
Dec 06 05:31:31.401 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:127:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:128:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then c1 - c2 = color(0.2, 0.5, 0.5)           features/tuples.feature:129:4
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Multiplying a color by a scalar       features/tuples.feature:131:11
Dec 06 05:31:31.401 INFO [stdout]   - Given c := color(0.2, 0.3, 0.4)               features/tuples.feature:132:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then c * 2 = color(0.4, 0.6, 0.8)             features/tuples.feature:133:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Multiplying colors                    features/tuples.feature:135:11
Dec 06 05:31:31.401 INFO [stdout]   - Given c1 := color(1, 0.2, 0.4)                features/tuples.feature:136:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - And c2 := color(0.9, 1, 0.1)                  features/tuples.feature:137:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then c1 * c2 = color(0.9, 0.2, 0.04)          features/tuples.feature:138:4
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Reflecting a vector approaching at 45°
Dec 06 05:31:31.401 INFO [stdout]                                                  features/tuples.feature:140:11
Dec 06 05:31:31.401 INFO [stdout]   ✔ Given v := vector(1, -1, 0)                   features/tuples.feature:141:3
Dec 06 05:31:31.401 INFO [stdout]   - And n := vector(0, 1, 0)                      features/tuples.feature:142:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:143:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout]   - Then r = vector(1, 1, 0)                      features/tuples.feature:144:3
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.401 INFO [stdout] 
Dec 06 05:31:31.401 INFO [stdout]  Scenario: Reflecting a vector off a slanted surface
Dec 06 05:31:31.401 INFO [stdout]                                                  features/tuples.feature:146:11
Dec 06 05:31:31.401 INFO [stdout]   ✔ Given v := vector(0, -1, 0)                   features/tuples.feature:147:3
Dec 06 05:31:31.401 INFO [stdout]   - And n := vector(√2/2, √2/2, 0)                features/tuples.feature:148:5
Dec 06 05:31:31.401 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.403 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:149:3
Dec 06 05:31:31.403 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.403 INFO [stdout]   - Then r = vector(1, 0, 0)                      features/tuples.feature:150:3
Dec 06 05:31:31.403 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:31.403 INFO [stdout] 
Dec 06 05:31:31.403 INFO [stdout] 1 features
Dec 06 05:31:31.403 INFO [stdout] 30 scenarios (10 skipped, 20 passed)
Dec 06 05:31:31.403 INFO [stdout] 88 steps (30 skipped, 58 passed)
Dec 06 05:31:31.403 INFO [stdout] 
Dec 06 05:31:31.404 INFO [stderr]    Doc-tests raytracer_challenge
Dec 06 05:31:31.944 INFO [stdout] 
Dec 06 05:31:31.944 INFO [stderr] su: No module specific data is present
Dec 06 05:31:31.944 INFO [stdout] running 0 tests
Dec 06 05:31:31.944 INFO [stdout] 
Dec 06 05:31:31.944 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:31.944 INFO [stdout] 
Dec 06 05:31:32.728 INFO running `"docker" "inspect" "9fc0aa05cca861d2616eb0d9e32f6528934e425c0d1a7388d16d45ade389d4c1"`
Dec 06 05:31:32.942 INFO running `"docker" "rm" "-f" "9fc0aa05cca861d2616eb0d9e32f6528934e425c0d1a7388d16d45ade389d4c1"`
Dec 06 05:31:33.272 INFO [stdout] 9fc0aa05cca861d2616eb0d9e32f6528934e425c0d1a7388d16d45ade389d4c1