Dec 14 01:25:51.422 INFO testing bhase/raytracerchallenge against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 01:25:51.422 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 01:25:51.911 INFO [stdout] a01d5afe2e88c40bd3d798332b0b96ba6e9ca69414f66890e7719bdbf53e3f26
Dec 14 01:25:51.915 INFO running `"docker" "start" "-a" "a01d5afe2e88c40bd3d798332b0b96ba6e9ca69414f66890e7719bdbf53e3f26"`
Dec 14 01:25:52.964 INFO [stderr] usermod: no changes
Dec 14 01:25:53.079 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 14 01:25:54.187 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.19s
Dec 14 01:25:54.199 INFO [stderr] su: No module specific data is present
Dec 14 01:25:54.796 INFO running `"docker" "inspect" "a01d5afe2e88c40bd3d798332b0b96ba6e9ca69414f66890e7719bdbf53e3f26"`
Dec 14 01:25:55.057 INFO running `"docker" "rm" "-f" "a01d5afe2e88c40bd3d798332b0b96ba6e9ca69414f66890e7719bdbf53e3f26"`
Dec 14 01:25:55.282 INFO [stdout] a01d5afe2e88c40bd3d798332b0b96ba6e9ca69414f66890e7719bdbf53e3f26
Dec 14 01:25:55.291 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 01:25:55.712 INFO [stdout] 29598d40e869736af9dd434e1483bf868ecbad11a5d56211aea36f72f0da6d0d
Dec 14 01:25:55.717 INFO running `"docker" "start" "-a" "29598d40e869736af9dd434e1483bf868ecbad11a5d56211aea36f72f0da6d0d"`
Dec 14 01:25:57.180 INFO [stderr] usermod: no changes
Dec 14 01:25:57.381 INFO [stderr]    Compiling pathdiff v0.1.0
Dec 14 01:25:57.387 INFO [stderr]    Compiling clap v2.32.0
Dec 14 01:25:57.387 INFO [stderr]    Compiling gherkin_rust v0.4.1
Dec 14 01:25:57.387 INFO [stderr]    Compiling globset v0.4.2
Dec 14 01:26:04.484 INFO [stderr]    Compiling ignore v0.4.4
Dec 14 01:26:15.953 INFO [stderr]    Compiling globwalk v0.5.0
Dec 14 01:26:22.303 INFO [stderr]    Compiling cucumber_rust v0.5.0
Dec 14 01:26:26.065 INFO [stderr]    Compiling raytracer_challenge v0.1.0 (/source)
Dec 14 01:26:32.678 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 35.46s
Dec 14 01:26:32.683 INFO [stderr] su: No module specific data is present
Dec 14 01:26:32.987 INFO running `"docker" "inspect" "29598d40e869736af9dd434e1483bf868ecbad11a5d56211aea36f72f0da6d0d"`
Dec 14 01:26:33.215 INFO running `"docker" "rm" "-f" "29598d40e869736af9dd434e1483bf868ecbad11a5d56211aea36f72f0da6d0d"`
Dec 14 01:26:33.449 INFO [stdout] 29598d40e869736af9dd434e1483bf868ecbad11a5d56211aea36f72f0da6d0d
Dec 14 01:26:33.452 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 14 01:26:33.787 INFO [stdout] 8223fe610c13351822a514141acd137887b6f9ae61fa9854bb8709e6a42884fe
Dec 14 01:26:33.789 INFO running `"docker" "start" "-a" "8223fe610c13351822a514141acd137887b6f9ae61fa9854bb8709e6a42884fe"`
Dec 14 01:26:34.823 INFO [stderr] usermod: no changes
Dec 14 01:26:34.943 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Dec 14 01:26:34.957 INFO [stderr]      Running /target/debug/deps/raytracer_challenge-89ed76299ffe2a7b
Dec 14 01:26:34.977 INFO [stdout] 
Dec 14 01:26:34.977 INFO [stdout] running 0 tests
Dec 14 01:26:34.977 INFO [stdout] 
Dec 14 01:26:34.977 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 01:26:34.977 INFO [stdout] 
Dec 14 01:26:34.978 INFO [stderr]      Running /target/debug/deps/raytracer-1a8c1e1ad700c9fa
Dec 14 01:26:34.991 INFO [stdout] 
Dec 14 01:26:34.991 INFO [stdout] running 0 tests
Dec 14 01:26:34.991 INFO [stdout] 
Dec 14 01:26:34.991 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 01:26:34.991 INFO [stdout] 
Dec 14 01:26:34.991 INFO [stderr]      Running /target/debug/deps/cucumber-4004680c97d175b3
Dec 14 01:26:35.095 INFO [stdout] [Cucumber v0.5.0]
Dec 14 01:26:35.095 INFO [stdout] 
Dec 14 01:26:35.127 INFO [stdout] Feature: Tuples, Vectors, and Points                features/tuples.feature:1:1
Dec 14 01:26:35.127 INFO [stdout] 
Dec 14 01:26:35.127 INFO [stdout]  Scenario: A tuple with w=1.0 is a point           features/tuples.feature:3:11
Dec 14 01:26:35.128 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 1.0)           features/tuples.feature:4:3
Dec 14 01:26:35.135 INFO [stdout]   ✔ Then a.x = 4.3                                  features/tuples.feature:5:3
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.y = -4.2                                  features/tuples.feature:6:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.z = 3.1                                   features/tuples.feature:7:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.w = 1.0                                   features/tuples.feature:8:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a is a point                                features/tuples.feature:9:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a is not a vector                          features/tuples.feature:10:5
Dec 14 01:26:35.135 INFO [stdout] 
Dec 14 01:26:35.135 INFO [stdout]  Scenario: A tuple with w=0 is a vector           features/tuples.feature:12:11
Dec 14 01:26:35.135 INFO [stdout]   ✔ Given a := tuple(4.3, -4.2, 3.1, 0.0)          features/tuples.feature:13:3
Dec 14 01:26:35.135 INFO [stdout]   ✔ Then a.x = 4.3                                 features/tuples.feature:14:3
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.y = -4.2                                 features/tuples.feature:15:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.z = 3.1                                  features/tuples.feature:16:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a.w = 0.0                                  features/tuples.feature:17:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a is not a point                           features/tuples.feature:18:5
Dec 14 01:26:35.135 INFO [stdout]   ✔ And a is a vector                              features/tuples.feature:19:5
Dec 14 01:26:35.135 INFO [stdout] 
Dec 14 01:26:35.135 INFO [stdout]  Scenario: "point" describes tuples with w=1      features/tuples.feature:21:11
Dec 14 01:26:35.137 INFO [stdout]   ✔ Given p := point(4, -4, 3)                     features/tuples.feature:22:3
Dec 14 01:26:35.143 INFO [stdout]   ✔ Then p = tuple(4, -4, 3, 1)                    features/tuples.feature:23:3
Dec 14 01:26:35.143 INFO [stdout] 
Dec 14 01:26:35.143 INFO [stdout]  Scenario: "vector" describes tuples with w=0     features/tuples.feature:25:11
Dec 14 01:26:35.143 INFO [stdout]   ✔ Given v := vector(4, -4, 3)                    features/tuples.feature:26:3
Dec 14 01:26:35.143 INFO [stdout]   ✔ Then v = tuple(4, -4, 3, 0)                    features/tuples.feature:27:3
Dec 14 01:26:35.143 INFO [stdout] 
Dec 14 01:26:35.143 INFO [stdout]  Scenario: Adding two tuples                      features/tuples.feature:29:11
Dec 14 01:26:35.147 INFO [stdout]   ✔ Given a1 := tuple(3, -2, 5, 1)                 features/tuples.feature:30:3
Dec 14 01:26:35.147 INFO [stdout]   ✔ And a2 := tuple(-2, 3, 1, 0)                   features/tuples.feature:31:5
Dec 14 01:26:35.147 INFO [stdout]   ✔ Then a1 + a2 = tuple(1, 1, 6, 1)               features/tuples.feature:32:4
Dec 14 01:26:35.147 INFO [stdout] 
Dec 14 01:26:35.147 INFO [stdout]  Scenario: Subtracting two points                 features/tuples.feature:34:11
Dec 14 01:26:35.151 INFO [stdout]   ✔ Given p1 := point(3, 2, 1)                     features/tuples.feature:35:3
Dec 14 01:26:35.151 INFO [stdout]   ✔ And p2 := point(5, 6, 7)                       features/tuples.feature:36:5
Dec 14 01:26:35.151 INFO [stdout]   ✔ Then p1 - p2 = vector(-2, -4, -6)              features/tuples.feature:37:3
Dec 14 01:26:35.151 INFO [stdout] 
Dec 14 01:26:35.151 INFO [stdout]  Scenario: Subtracting a vector from a point      features/tuples.feature:39:11
Dec 14 01:26:35.151 INFO [stdout]   ✔ Given p := point(3, 2, 1)                      features/tuples.feature:40:3
Dec 14 01:26:35.151 INFO [stdout]   ✔ And v := vector(5, 6, 7)                       features/tuples.feature:41:5
Dec 14 01:26:35.153 INFO [stdout]   ✔ Then p - v = point(-2, -4, -6)                 features/tuples.feature:42:3
Dec 14 01:26:35.153 INFO [stdout] 
Dec 14 01:26:35.153 INFO [stdout]  Scenario: Subtracting two vectors                features/tuples.feature:44:11
Dec 14 01:26:35.153 INFO [stdout]   ✔ Given v1 := vector(3, 2, 1)                    features/tuples.feature:45:3
Dec 14 01:26:35.153 INFO [stdout]   ✔ And v2 := vector(5, 6, 7)                      features/tuples.feature:46:5
Dec 14 01:26:35.156 INFO [stdout]   ✔ Then v1 - v2 = vector(-2, -4, -6)              features/tuples.feature:47:3
Dec 14 01:26:35.156 INFO [stdout] 
Dec 14 01:26:35.156 INFO [stdout]  Scenario: Subtracting a vector from the zero vector
Dec 14 01:26:35.156 INFO [stdout]                                                   features/tuples.feature:49:11
Dec 14 01:26:35.163 INFO [stdout]   ✔ Given zero := vector(0, 0, 0)                  features/tuples.feature:50:3
Dec 14 01:26:35.163 INFO [stdout]   ✔ And v2 := vector(1, -2, 3)                     features/tuples.feature:51:5
Dec 14 01:26:35.163 INFO [stdout]   ✔ Then zero - v2 = vector(-1, 2, -3)             features/tuples.feature:52:3
Dec 14 01:26:35.163 INFO [stdout] 
Dec 14 01:26:35.163 INFO [stdout]  Scenario: Negating a tuple                       features/tuples.feature:54:11
Dec 14 01:26:35.163 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:55:3
Dec 14 01:26:35.163 INFO [stdout]   ✔ Then -a = tuple(-1, 2, -3, 4)                  features/tuples.feature:56:3
Dec 14 01:26:35.163 INFO [stdout] 
Dec 14 01:26:35.163 INFO [stdout]  Scenario: Multiplying a tuple by a scalar        features/tuples.feature:58:11
Dec 14 01:26:35.163 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:59:3
Dec 14 01:26:35.167 INFO [stdout]   ✔ Then a * 3.5 = tuple(3.5, -7, 10.5, -14)       features/tuples.feature:60:3
Dec 14 01:26:35.167 INFO [stdout] 
Dec 14 01:26:35.167 INFO [stdout]  Scenario: Multiplying a tuple by a fraction      features/tuples.feature:62:11
Dec 14 01:26:35.167 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:63:3
Dec 14 01:26:35.167 INFO [stdout]   ✔ Then a * 0.5 = tuple(0.5, -1, 1.5, -2)         features/tuples.feature:64:3
Dec 14 01:26:35.167 INFO [stdout] 
Dec 14 01:26:35.167 INFO [stdout]  Scenario: Dividing a tuple by a scalar           features/tuples.feature:66:11
Dec 14 01:26:35.167 INFO [stdout]   ✔ Given a := tuple(1, -2, 3, -4)                 features/tuples.feature:67:3
Dec 14 01:26:35.171 INFO [stdout]   ✔ Then a / 2 = tuple(0.5, -1, 1.5, -2)           features/tuples.feature:68:3
Dec 14 01:26:35.171 INFO [stdout] 
Dec 14 01:26:35.171 INFO [stdout]  Scenario: Magnitude of vector(1, 0, 0)           features/tuples.feature:70:11
Dec 14 01:26:35.171 INFO [stdout]   ✔ Given v := vector(1, 0, 0)                     features/tuples.feature:71:3
Dec 14 01:26:35.175 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:72:3
Dec 14 01:26:35.175 INFO [stdout] 
Dec 14 01:26:35.175 INFO [stdout]  Scenario: Magnitude of vector(0, 1, 0)           features/tuples.feature:74:11
Dec 14 01:26:35.175 INFO [stdout]   ✔ Given v := vector(0, 1, 0)                     features/tuples.feature:75:3
Dec 14 01:26:35.175 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:76:3
Dec 14 01:26:35.175 INFO [stdout] 
Dec 14 01:26:35.175 INFO [stdout]  Scenario: Magnitude of vector(0, 0, 1)           features/tuples.feature:78:11
Dec 14 01:26:35.175 INFO [stdout]   ✔ Given v := vector(0, 0, 1)                     features/tuples.feature:79:3
Dec 14 01:26:35.175 INFO [stdout]   ✔ Then magnitude(v) = 1                          features/tuples.feature:80:3
Dec 14 01:26:35.175 INFO [stdout] 
Dec 14 01:26:35.175 INFO [stdout]  Scenario: Magnitude of vector(1, 2, 3)           features/tuples.feature:82:11
Dec 14 01:26:35.175 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:83:3
Dec 14 01:26:35.175 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:84:3
Dec 14 01:26:35.175 INFO [stdout] 
Dec 14 01:26:35.175 INFO [stdout]  Scenario: Magnitude of vector(-1, -2, -3)        features/tuples.feature:86:11
Dec 14 01:26:35.175 INFO [stdout]   ✔ Given v := vector(-1, -2, -3)                  features/tuples.feature:87:3
Dec 14 01:26:35.175 INFO [stdout]   ✔ Then magnitude(v) = √14                        features/tuples.feature:88:3
Dec 14 01:26:35.175 INFO [stdout] 
Dec 14 01:26:35.175 INFO [stdout]  Scenario: Normalizing vector(4, 0, 0) gives (1, 0, 0)
Dec 14 01:26:35.175 INFO [stdout]                                                   features/tuples.feature:90:11
Dec 14 01:26:35.175 INFO [stdout]   ✔ Given v := vector(4, 0, 0)                     features/tuples.feature:91:3
Dec 14 01:26:35.179 INFO [stdout]   ✔ Then normalize(v) = vector(1, 0, 0)            features/tuples.feature:92:3
Dec 14 01:26:35.179 INFO [stdout] 
Dec 14 01:26:35.179 INFO [stdout]  Scenario: Normalizing vector(1, 2, 3)            features/tuples.feature:94:11
Dec 14 01:26:35.179 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                     features/tuples.feature:95:3
Dec 14 01:26:35.179 INFO [stdout]   ✔ Then normalize(v) = approximately vector(0.26726, 0.53452, 0.80178)
Dec 14 01:26:35.179 INFO [stdout]                                                    features/tuples.feature:97:3
Dec 14 01:26:35.179 INFO [stdout] 
Dec 14 01:26:35.179 INFO [stdout]  Scenario: The magnitude of a normalized vector   features/tuples.feature:99:11
Dec 14 01:26:35.184 INFO [stderr]    Doc-tests raytracer_challenge
Dec 14 01:26:35.185 INFO [stdout]   ✔ Given v := vector(1, 2, 3)                    features/tuples.feature:100:3
Dec 14 01:26:35.185 INFO [stdout]   - When norm := normalize(v)                     features/tuples.feature:101:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then magnitude(norm) = 1                      features/tuples.feature:102:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: The dot product of two tuples         features/tuples.feature:104:11
Dec 14 01:26:35.185 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:105:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:106:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then a dot b = 20                             features/tuples.feature:107:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Cross product of two vectors          features/tuples.feature:109:11
Dec 14 01:26:35.185 INFO [stdout]   - Given a := vector(1, 2, 3)                    features/tuples.feature:110:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And b := vector(2, 3, 4)                      features/tuples.feature:111:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then a cross b = vector(-1, 2, -1)            features/tuples.feature:112:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And b cross a = vector(1, -2, 1)              features/tuples.feature:113:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Colors are (red, green, blue) tuples  features/tuples.feature:115:11
Dec 14 01:26:35.185 INFO [stdout]   - Given c := color(-0.5, 0.4, 1.7)              features/tuples.feature:116:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then c.red = -0.5                             features/tuples.feature:117:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And c.green = 0.4                             features/tuples.feature:118:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And c.blue = 1.7                              features/tuples.feature:119:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Adding colors                         features/tuples.feature:121:11
Dec 14 01:26:35.185 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:122:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:123:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then c1 + c2 = color(1.6, 0.7, 1.0)           features/tuples.feature:124:4
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Subtracting colors                    features/tuples.feature:126:11
Dec 14 01:26:35.185 INFO [stdout]   - Given c1 := color(0.9, 0.6, 0.75)             features/tuples.feature:127:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And c2 := color(0.7, 0.1, 0.25)               features/tuples.feature:128:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then c1 - c2 = color(0.2, 0.5, 0.5)           features/tuples.feature:129:4
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Multiplying a color by a scalar       features/tuples.feature:131:11
Dec 14 01:26:35.185 INFO [stdout]   - Given c := color(0.2, 0.3, 0.4)               features/tuples.feature:132:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then c * 2 = color(0.4, 0.6, 0.8)             features/tuples.feature:133:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Multiplying colors                    features/tuples.feature:135:11
Dec 14 01:26:35.185 INFO [stdout]   - Given c1 := color(1, 0.2, 0.4)                features/tuples.feature:136:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - And c2 := color(0.9, 1, 0.1)                  features/tuples.feature:137:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then c1 * c2 = color(0.9, 0.2, 0.04)          features/tuples.feature:138:4
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Reflecting a vector approaching at 45°
Dec 14 01:26:35.185 INFO [stdout]                                                  features/tuples.feature:140:11
Dec 14 01:26:35.185 INFO [stdout]   ✔ Given v := vector(1, -1, 0)                   features/tuples.feature:141:3
Dec 14 01:26:35.185 INFO [stdout]   - And n := vector(0, 1, 0)                      features/tuples.feature:142:5
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:143:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout]   - Then r = vector(1, 1, 0)                      features/tuples.feature:144:3
Dec 14 01:26:35.185 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.185 INFO [stdout] 
Dec 14 01:26:35.185 INFO [stdout]  Scenario: Reflecting a vector off a slanted surface
Dec 14 01:26:35.186 INFO [stdout]                                                  features/tuples.feature:146:11
Dec 14 01:26:35.186 INFO [stdout]   ✔ Given v := vector(0, -1, 0)                   features/tuples.feature:147:3
Dec 14 01:26:35.186 INFO [stdout]   - And n := vector(√2/2, √2/2, 0)                features/tuples.feature:148:5
Dec 14 01:26:35.186 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.186 INFO [stdout]   - When r := reflect(v, n)                       features/tuples.feature:149:3
Dec 14 01:26:35.186 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.186 INFO [stdout]   - Then r = vector(1, 0, 0)                      features/tuples.feature:150:3
Dec 14 01:26:35.186 INFO [stdout]     ⚡ Not yet implemented (skipped)
Dec 14 01:26:35.186 INFO [stdout] 
Dec 14 01:26:35.186 INFO [stdout] 1 features
Dec 14 01:26:35.186 INFO [stdout] 30 scenarios (10 skipped, 20 passed)
Dec 14 01:26:35.186 INFO [stdout] 88 steps (30 skipped, 58 passed)
Dec 14 01:26:35.186 INFO [stdout] 
Dec 14 01:26:35.819 INFO [stdout] 
Dec 14 01:26:35.819 INFO [stdout] running 0 tests
Dec 14 01:26:35.819 INFO [stdout] 
Dec 14 01:26:35.819 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 01:26:35.819 INFO [stdout] 
Dec 14 01:26:35.826 INFO [stderr] su: No module specific data is present
Dec 14 01:26:36.923 INFO running `"docker" "inspect" "8223fe610c13351822a514141acd137887b6f9ae61fa9854bb8709e6a42884fe"`
Dec 14 01:26:37.168 INFO running `"docker" "rm" "-f" "8223fe610c13351822a514141acd137887b6f9ae61fa9854bb8709e6a42884fe"`
Dec 14 01:26:37.451 INFO [stdout] 8223fe610c13351822a514141acd137887b6f9ae61fa9854bb8709e6a42884fe