Dec 06 05:31:15.697 INFO testing bhase/raytracerchallenge against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 05:31:15.697 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 05:31:15.904 INFO [stdout] fff23d897010fdd19b776b2b8d23c588d2f3204c47d96c274f71f2a8b28ce36c
Dec 06 05:31:15.910 INFO running `"docker" "start" "-a" "fff23d897010fdd19b776b2b8d23c588d2f3204c47d96c274f71f2a8b28ce36c"`
Dec 06 05:31:16.489 INFO [stderr] usermod: no changes
Dec 06 05:31:16.568 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 06 05:31:17.552 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.04s
Dec 06 05:31:17.559 INFO [stderr] su: No module specific data is present
Dec 06 05:31:17.976 INFO running `"docker" "inspect" "fff23d897010fdd19b776b2b8d23c588d2f3204c47d96c274f71f2a8b28ce36c"`
Dec 06 05:31:18.151 INFO running `"docker" "rm" "-f" "fff23d897010fdd19b776b2b8d23c588d2f3204c47d96c274f71f2a8b28ce36c"`
Dec 06 05:31:18.296 INFO [stdout] fff23d897010fdd19b776b2b8d23c588d2f3204c47d96c274f71f2a8b28ce36c
Dec 06 05:31:18.304 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 05:31:18.698 INFO [stdout] 9fc6b47a4583f65ac0c5461537fd3a44457c1286b90afb815bdc0c3aa1b42d5c
Dec 06 05:31:18.700 INFO running `"docker" "start" "-a" "9fc6b47a4583f65ac0c5461537fd3a44457c1286b90afb815bdc0c3aa1b42d5c"`
Dec 06 05:31:20.095 INFO [stderr] usermod: no changes
Dec 06 05:31:20.340 INFO [stderr]    Compiling clap v2.32.0
Dec 06 05:31:20.344 INFO [stderr]    Compiling gherkin_rust v0.4.1
Dec 06 05:31:20.344 INFO [stderr]    Compiling globset v0.4.2
Dec 06 05:31:26.824 INFO [stderr]    Compiling ignore v0.4.4
Dec 06 05:31:31.568 INFO [stderr]    Compiling globwalk v0.5.0
Dec 06 05:31:35.420 INFO [stderr]    Compiling cucumber_rust v0.5.0
Dec 06 05:31:38.357 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 06 05:31:45.468 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 25.34s
Dec 06 05:31:45.480 INFO [stderr] su: No module specific data is present
Dec 06 05:31:46.054 INFO running `"docker" "inspect" "9fc6b47a4583f65ac0c5461537fd3a44457c1286b90afb815bdc0c3aa1b42d5c"`
Dec 06 05:31:46.162 INFO running `"docker" "rm" "-f" "9fc6b47a4583f65ac0c5461537fd3a44457c1286b90afb815bdc0c3aa1b42d5c"`
Dec 06 05:31:46.252 INFO [stdout] 9fc6b47a4583f65ac0c5461537fd3a44457c1286b90afb815bdc0c3aa1b42d5c
Dec 06 05:31:46.259 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 05:31:46.419 INFO [stdout] 90b954a0ed7e52857c9b2faabee30438c90e13219b637cb89cd54fd5d3c143f1
Dec 06 05:31:46.421 INFO running `"docker" "start" "-a" "90b954a0ed7e52857c9b2faabee30438c90e13219b637cb89cd54fd5d3c143f1"`
Dec 06 05:31:46.858 INFO [stderr] usermod: no changes
Dec 06 05:31:46.988 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 06 05:31:46.992 INFO [stderr]      Running /target/debug/deps/raytracer_challenge-d2cfea2ab189735d
Dec 06 05:31:46.992 INFO [stderr]      Running /target/debug/deps/raytracer-cfea122e62795de6
Dec 06 05:31:46.992 INFO [stdout] 
Dec 06 05:31:46.992 INFO [stdout] running 0 tests
Dec 06 05:31:46.992 INFO [stdout] 
Dec 06 05:31:46.992 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:46.992 INFO [stdout] 
Dec 06 05:31:46.994 INFO [stdout] 
Dec 06 05:31:46.994 INFO [stdout] running 0 tests
Dec 06 05:31:46.994 INFO [stdout] 
Dec 06 05:31:46.994 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:46.994 INFO [stdout] 
Dec 06 05:31:46.994 INFO [stderr]      Running /target/debug/deps/cucumber-08530c842b5b304f
Dec 06 05:31:47.067 INFO [stdout] [Cucumber v0.5.0]
Dec 06 05:31:47.067 INFO [stdout] 
Dec 06 05:31:47.100 INFO [stdout] Feature: Tuples, Vectors, and Points                features/tuples.feature:1:1
Dec 06 05:31:47.100 INFO [stdout] 
Dec 06 05:31:47.100 INFO [stdout]  Scenario: A tuple with w=1.0 is a point           features/tuples.feature:3:11
Dec 06 05:31:47.104 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 1.0)           features/tuples.feature:4:3
Dec 06 05:31:47.106 INFO [stdout]   ✔ Then a.x = 4.3                                  features/tuples.feature:5:3
Dec 06 05:31:47.107 INFO [stdout]   ✔ And a.y = -4.2                                  features/tuples.feature:6:5
Dec 06 05:31:47.107 INFO [stdout]   ✔ And a.z = 3.1                                   features/tuples.feature:7:5
Dec 06 05:31:47.107 INFO [stdout]   ✔ And a.w = 1.0                                   features/tuples.feature:8:5
Dec 06 05:31:47.108 INFO [stdout]   ✔ And a is a point                                features/tuples.feature:9:5
Dec 06 05:31:47.109 INFO [stdout]   ✔ And a is not a vector                          features/tuples.feature:10:5
Dec 06 05:31:47.109 INFO [stdout] 
Dec 06 05:31:47.109 INFO [stdout]  Scenario: A tuple with w=0 is a vector           features/tuples.feature:12:11
Dec 06 05:31:47.109 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 0.0)          features/tuples.feature:13:3
Dec 06 05:31:47.109 INFO [stdout]   ✔ Then a.x = 4.3                                 features/tuples.feature:14:3
Dec 06 05:31:47.109 INFO [stdout]   ✔ And a.y = -4.2                                 features/tuples.feature:15:5
Dec 06 05:31:47.109 INFO [stdout]   ✔ And a.z = 3.1                                  features/tuples.feature:16:5
Dec 06 05:31:47.109 INFO [stdout]   ✔ And a.w = 0.0                                  features/tuples.feature:17:5
Dec 06 05:31:47.110 INFO [stdout]   ✔ And a is not a point                           features/tuples.feature:18:5
Dec 06 05:31:47.110 INFO [stdout]   ✔ And a is a vector                              features/tuples.feature:19:5
Dec 06 05:31:47.110 INFO [stdout] 
Dec 06 05:31:47.110 INFO [stdout]  Scenario: "point" describes tuples with w=1      features/tuples.feature:21:11
Dec 06 05:31:47.112 INFO [stdout]   ✔ Given p := point(4, -4, 3)                     features/tuples.feature:22:3
Dec 06 05:31:47.115 INFO [stdout]   ✔ Then p = tuple(4, -4, 3, 1)                    features/tuples.feature:23:3
Dec 06 05:31:47.115 INFO [stdout] 
Dec 06 05:31:47.115 INFO [stdout]  Scenario: "vector" describes tuples with w=0     features/tuples.feature:25:11
Dec 06 05:31:47.117 INFO [stdout]   ✔ Given v := vector(4, -4, 3)                    features/tuples.feature:26:3
Dec 06 05:31:47.118 INFO [stdout]   ✔ Then v = tuple(4, -4, 3, 0)                    features/tuples.feature:27:3
Dec 06 05:31:47.118 INFO [stdout] 
Dec 06 05:31:47.118 INFO [stdout]  Scenario: Adding two tuples                      features/tuples.feature:29:11
Dec 06 05:31:47.118 INFO [stdout]   ✔ Given a1 := tuple(3, -2, 5, 1)                 features/tuples.feature:30:3
Dec 06 05:31:47.119 INFO [stdout]   ✔ And a2 := tuple(-2, 3, 1, 0)                   features/tuples.feature:31:5
Dec 06 05:31:47.122 INFO [stdout]   ✔ Then a1 + a2 = tuple(1, 1, 6, 1)               features/tuples.feature:32:4
Dec 06 05:31:47.122 INFO [stdout] 
Dec 06 05:31:47.122 INFO [stdout]  Scenario: Subtracting two points                 features/tuples.feature:34:11
Dec 06 05:31:47.123 INFO [stdout]   ✔ Given p1 := point(3, 2, 1)                     features/tuples.feature:35:3
Dec 06 05:31:47.123 INFO [stdout]   ✔ And p2 := point(5, 6, 7)                       features/tuples.feature:36:5
Dec 06 05:31:47.125 INFO [stdout]   ✔ Then p1 - p2 = vector(-2, -4, -6)              features/tuples.feature:37:3
Dec 06 05:31:47.126 INFO [stdout] 
Dec 06 05:31:47.126 INFO [stdout]  Scenario: Subtracting a vector from a point      features/tuples.feature:39:11
Dec 06 05:31:47.126 INFO [stdout]   ✔ Given p := point(3, 2, 1)                      features/tuples.feature:40:3
Dec 06 05:31:47.126 INFO [stdout]   ✔ And v := vector(5, 6, 7)                       features/tuples.feature:41:5
Dec 06 05:31:47.128 INFO [stdout]   ✔ Then p - v = point(-2, -4, -6)                 features/tuples.feature:42:3
Dec 06 05:31:47.128 INFO [stdout] 
Dec 06 05:31:47.128 INFO [stdout]  Scenario: Subtracting two vectors                features/tuples.feature:44:11
Dec 06 05:31:47.129 INFO [stdout]   ✔ Given v1 := vector(3, 2, 1)                    features/tuples.feature:45:3
Dec 06 05:31:47.129 INFO [stdout]   ✔ And v2 := vector(5, 6, 7)                      features/tuples.feature:46:5
Dec 06 05:31:47.132 INFO [stdout]   ✔ Then v1 - v2 = vector(-2, -4, -6)              features/tuples.feature:47:3
Dec 06 05:31:47.132 INFO [stdout] 
Dec 06 05:31:47.132 INFO [stdout]  Scenario: Subtracting a vector from the zero vector
Dec 06 05:31:47.132 INFO [stdout]                                                   features/tuples.feature:49:11
Dec 06 05:31:47.132 INFO [stdout]   ✔ Given zero := vector(0, 0, 0)                  features/tuples.feature:50:3
Dec 06 05:31:47.132 INFO [stdout]   ✔ And v2 := vector(1, -2, 3)                     features/tuples.feature:51:5
Dec 06 05:31:47.133 INFO [stdout]   ✔ Then zero - v2 = vector(-1, 2, -3)             features/tuples.feature:52:3
Dec 06 05:31:47.133 INFO [stdout] 
Dec 06 05:31:47.133 INFO [stdout]  Scenario: Negating a tuple                       features/tuples.feature:54:11
Dec 06 05:31:47.133 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:55:3
Dec 06 05:31:47.138 INFO [stdout]   ✔ Then -a = tuple(-1, 2, -3, 4)                  features/tuples.feature:56:3
Dec 06 05:31:47.138 INFO [stdout] 
Dec 06 05:31:47.138 INFO [stdout]  Scenario: Multiplying a tuple by a scalar        features/tuples.feature:58:11
Dec 06 05:31:47.138 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:59:3
Dec 06 05:31:47.141 INFO [stdout]   ✔ Then a * 3.5 = tuple(3.5, -7, 10.5, -14)       features/tuples.feature:60:3
Dec 06 05:31:47.142 INFO [stdout] 
Dec 06 05:31:47.142 INFO [stdout]  Scenario: Multiplying a tuple by a fraction      features/tuples.feature:62:11
Dec 06 05:31:47.142 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:63:3
Dec 06 05:31:47.142 INFO [stdout]   ✔ Then a * 0.5 = tuple(0.5, -1, 1.5, -2)         features/tuples.feature:64:3
Dec 06 05:31:47.142 INFO [stdout] 
Dec 06 05:31:47.142 INFO [stdout]  Scenario: Dividing a tuple by a scalar           features/tuples.feature:66:11
Dec 06 05:31:47.142 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:67:3
Dec 06 05:31:47.146 INFO [stdout]   ✔ Then a / 2 = tuple(0.5, -1, 1.5, -2)           features/tuples.feature:68:3
Dec 06 05:31:47.146 INFO [stdout] 
Dec 06 05:31:47.146 INFO [stdout]  Scenario: Magnitude of vector(1, 0, 0)           features/tuples.feature:70:11
Dec 06 05:31:47.146 INFO [stdout]   ✔ Given v := vector(1, 0, 0)                     features/tuples.feature:71:3
Dec 06 05:31:47.147 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:72:3
Dec 06 05:31:47.147 INFO [stdout] 
Dec 06 05:31:47.147 INFO [stdout]  Scenario: Magnitude of vector(0, 1, 0)           features/tuples.feature:74:11
Dec 06 05:31:47.147 INFO [stdout]   ✔ Given v := vector(0, 1, 0)                     features/tuples.feature:75:3
Dec 06 05:31:47.147 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:76:3
Dec 06 05:31:47.147 INFO [stdout] 
Dec 06 05:31:47.147 INFO [stdout]  Scenario: Magnitude of vector(0, 0, 1)           features/tuples.feature:78:11
Dec 06 05:31:47.147 INFO [stdout]   ✔ Given v := vector(0, 0, 1)                     features/tuples.feature:79:3
Dec 06 05:31:47.147 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:80:3
Dec 06 05:31:47.148 INFO [stdout] 
Dec 06 05:31:47.148 INFO [stdout]  Scenario: Magnitude of vector(1, 2, 3)           features/tuples.feature:82:11
Dec 06 05:31:47.148 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:83:3
Dec 06 05:31:47.148 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:84:3
Dec 06 05:31:47.148 INFO [stdout] 
Dec 06 05:31:47.148 INFO [stdout]  Scenario: Magnitude of vector(-1, -2, -3)        features/tuples.feature:86:11
Dec 06 05:31:47.148 INFO [stdout]   ✔ Given v := vector(-1, -2, -3)                  features/tuples.feature:87:3
Dec 06 05:31:47.148 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:88:3
Dec 06 05:31:47.148 INFO [stdout] 
Dec 06 05:31:47.149 INFO [stdout]  Scenario: Normalizing vector(4, 0, 0) gives (1, 0, 0)
Dec 06 05:31:47.149 INFO [stdout]                                                   features/tuples.feature:90:11
Dec 06 05:31:47.149 INFO [stdout]   ✔ Given v := vector(4, 0, 0)                     features/tuples.feature:91:3
Dec 06 05:31:47.151 INFO [stdout]   ✔ Then normalize(v) = vector(1, 0, 0)            features/tuples.feature:92:3
Dec 06 05:31:47.151 INFO [stdout] 
Dec 06 05:31:47.151 INFO [stdout]  Scenario: Normalizing vector(1, 2, 3)            features/tuples.feature:94:11
Dec 06 05:31:47.151 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:95:3
Dec 06 05:31:47.154 INFO [stdout]   ✔ Then normalize(v) = approximately vector(0.26726, 0.53452, 0.80178)
Dec 06 05:31:47.154 INFO [stdout]                                                    features/tuples.feature:97:3
Dec 06 05:31:47.154 INFO [stdout] 
Dec 06 05:31:47.154 INFO [stdout]  Scenario: The magnitude of a normalized vector   features/tuples.feature:99:11
Dec 06 05:31:47.154 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                    features/tuples.feature:100:3
Dec 06 05:31:47.154 INFO [stdout]   - When norm := normalize(v)                     features/tuples.feature:101:3
Dec 06 05:31:47.155 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.155 INFO [stdout]   - Then magnitude(norm) = 1                      features/tuples.feature:102:3
Dec 06 05:31:47.155 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.155 INFO [stdout] 
Dec 06 05:31:47.155 INFO [stdout]  Scenario: The dot product of two tuples         features/tuples.feature:104:11
Dec 06 05:31:47.155 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:105:3
Dec 06 05:31:47.156 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.156 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:106:5
Dec 06 05:31:47.156 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.156 INFO [stdout]   - Then a dot b = 20                             features/tuples.feature:107:3
Dec 06 05:31:47.156 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.156 INFO [stdout] 
Dec 06 05:31:47.156 INFO [stdout]  Scenario: Cross product of two vectors          features/tuples.feature:109:11
Dec 06 05:31:47.156 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:110:3
Dec 06 05:31:47.156 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.157 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:111:5
Dec 06 05:31:47.157 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.157 INFO [stdout]   - Then a cross b = vector(-1, 2, -1)            features/tuples.feature:112:3
Dec 06 05:31:47.157 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.157 INFO [stdout]   - And b cross a = vector(1, -2, 1)              features/tuples.feature:113:5
Dec 06 05:31:47.157 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.157 INFO [stdout] 
Dec 06 05:31:47.157 INFO [stdout]  Scenario: Colors are (red, green, blue) tuples  features/tuples.feature:115:11
Dec 06 05:31:47.157 INFO [stdout]   - Given c := color(-0.5, 0.4, 1.7)              features/tuples.feature:116:3
Dec 06 05:31:47.157 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.158 INFO [stdout]   - Then c.red = -0.5                             features/tuples.feature:117:3
Dec 06 05:31:47.158 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.158 INFO [stdout]   - And c.green = 0.4                             features/tuples.feature:118:5
Dec 06 05:31:47.158 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.158 INFO [stdout]   - And c.blue = 1.7                              features/tuples.feature:119:5
Dec 06 05:31:47.158 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.158 INFO [stdout] 
Dec 06 05:31:47.158 INFO [stdout]  Scenario: Adding colors                         features/tuples.feature:121:11
Dec 06 05:31:47.158 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:122:3
Dec 06 05:31:47.158 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.158 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:123:5
Dec 06 05:31:47.158 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.159 INFO [stdout]   - Then c1 + c2 = color(1.6, 0.7, 1.0)           features/tuples.feature:124:4
Dec 06 05:31:47.159 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.159 INFO [stdout] 
Dec 06 05:31:47.159 INFO [stdout]  Scenario: Subtracting colors                    features/tuples.feature:126:11
Dec 06 05:31:47.159 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:127:3
Dec 06 05:31:47.159 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.159 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:128:5
Dec 06 05:31:47.160 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.160 INFO [stdout]   - Then c1 - c2 = color(0.2, 0.5, 0.5)           features/tuples.feature:129:4
Dec 06 05:31:47.160 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.160 INFO [stdout] 
Dec 06 05:31:47.160 INFO [stdout]  Scenario: Multiplying a color by a scalar       features/tuples.feature:131:11
Dec 06 05:31:47.161 INFO [stdout]   - Given c := color(0.2, 0.3, 0.4)               features/tuples.feature:132:3
Dec 06 05:31:47.161 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.161 INFO [stdout]   - Then c * 2 = color(0.4, 0.6, 0.8)             features/tuples.feature:133:3
Dec 06 05:31:47.161 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.161 INFO [stdout] 
Dec 06 05:31:47.162 INFO [stdout]  Scenario: Multiplying colors                    features/tuples.feature:135:11
Dec 06 05:31:47.162 INFO [stdout]   - Given c1 := color(1, 0.2, 0.4)                features/tuples.feature:136:3
Dec 06 05:31:47.162 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.162 INFO [stdout]   - And c2 := color(0.9, 1, 0.1)                  features/tuples.feature:137:5
Dec 06 05:31:47.162 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.163 INFO [stdout]   - Then c1 * c2 = color(0.9, 0.2, 0.04)          features/tuples.feature:138:4
Dec 06 05:31:47.163 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.163 INFO [stdout] 
Dec 06 05:31:47.163 INFO [stdout]  Scenario: Reflecting a vector approaching at 45°
Dec 06 05:31:47.163 INFO [stdout]                                                  features/tuples.feature:140:11
Dec 06 05:31:47.164 INFO [stdout]   ✔ Given v := vector(1, -1, 0)                   features/tuples.feature:141:3
Dec 06 05:31:47.164 INFO [stdout]   - And n := vector(0, 1, 0)                      features/tuples.feature:142:5
Dec 06 05:31:47.164 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.164 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:143:3
Dec 06 05:31:47.164 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.165 INFO [stdout]   - Then r = vector(1, 1, 0)                      features/tuples.feature:144:3
Dec 06 05:31:47.165 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.165 INFO [stdout] 
Dec 06 05:31:47.165 INFO [stdout]  Scenario: Reflecting a vector off a slanted surface
Dec 06 05:31:47.165 INFO [stdout]                                                  features/tuples.feature:146:11
Dec 06 05:31:47.166 INFO [stdout]   ✔ Given v := vector(0, -1, 0)                   features/tuples.feature:147:3
Dec 06 05:31:47.166 INFO [stdout]   - And n := vector(√2/2, √2/2, 0)                features/tuples.feature:148:5
Dec 06 05:31:47.166 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.166 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:149:3
Dec 06 05:31:47.166 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.167 INFO [stdout]   - Then r = vector(1, 0, 0)                      features/tuples.feature:150:3
Dec 06 05:31:47.167 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 06 05:31:47.167 INFO [stdout] 
Dec 06 05:31:47.167 INFO [stdout] 1 features
Dec 06 05:31:47.167 INFO [stdout] 30 scenarios (10 skipped, 20 passed)
Dec 06 05:31:47.167 INFO [stdout] 88 steps (30 skipped, 58 passed)
Dec 06 05:31:47.168 INFO [stdout] 
Dec 06 05:31:47.170 INFO [stderr]    Doc-tests raytracer_challenge
Dec 06 05:31:47.616 INFO [stdout] 
Dec 06 05:31:47.616 INFO [stdout] running 0 tests
Dec 06 05:31:47.616 INFO [stdout] 
Dec 06 05:31:47.616 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 05:31:47.616 INFO [stdout] 
Dec 06 05:31:47.624 INFO [stderr] su: No module specific data is present
Dec 06 05:31:48.091 INFO running `"docker" "inspect" "90b954a0ed7e52857c9b2faabee30438c90e13219b637cb89cd54fd5d3c143f1"`
Dec 06 05:31:48.324 INFO running `"docker" "rm" "-f" "90b954a0ed7e52857c9b2faabee30438c90e13219b637cb89cd54fd5d3c143f1"`
Dec 06 05:31:48.535 INFO [stdout] 90b954a0ed7e52857c9b2faabee30438c90e13219b637cb89cd54fd5d3c143f1