Dec 15 00:42:25.342 INFO testing puzzle-solver-0.4.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 00:42:25.342 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 00:42:25.534 INFO [stdout] a87b0c9132cc1c375fc0a0764679e6401c7a2f3ec7f788492b2bc972e4775435
Dec 15 00:42:25.536 INFO running `"docker" "start" "-a" "a87b0c9132cc1c375fc0a0764679e6401c7a2f3ec7f788492b2bc972e4775435"`
Dec 15 00:42:26.218 INFO [stderr] usermod: no changes
Dec 15 00:42:26.277 INFO [stderr]    Compiling num-rational v0.1.42
Dec 15 00:42:27.699 INFO [stderr]    Compiling puzzle-solver v0.4.1 (/source)
Dec 15 00:42:28.541 INFO [stderr] warning: variable does not need to be mutable
Dec 15 00:42:28.541 INFO [stderr]    --> src/puzzle.rs:898:21
Dec 15 00:42:28.541 INFO [stderr]     |
Dec 15 00:42:28.541 INFO [stderr] 898 |         let (mut l, mut r) = slice.split_at_mut(b);
Dec 15 00:42:28.541 INFO [stderr]     |                     ----^
Dec 15 00:42:28.541 INFO [stderr]     |                     |
Dec 15 00:42:28.541 INFO [stderr]     |                     help: remove this `mut`
Dec 15 00:42:28.541 INFO [stderr]     |
Dec 15 00:42:28.541 INFO [stderr]     = note: #[warn(unused_mut)] on by default
Dec 15 00:42:28.541 INFO [stderr] 
Dec 15 00:42:28.541 INFO [stderr] warning: variable does not need to be mutable
Dec 15 00:42:28.541 INFO [stderr]    --> src/puzzle.rs:898:14
Dec 15 00:42:28.541 INFO [stderr]     |
Dec 15 00:42:28.541 INFO [stderr] 898 |         let (mut l, mut r) = slice.split_at_mut(b);
Dec 15 00:42:28.541 INFO [stderr]     |              ----^
Dec 15 00:42:28.541 INFO [stderr]     |              |
Dec 15 00:42:28.541 INFO [stderr]     |              help: remove this `mut`
Dec 15 00:42:28.541 INFO [stderr] 
Dec 15 00:42:29.799 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.55s
Dec 15 00:42:29.803 INFO [stderr] su: No module specific data is present
Dec 15 00:42:30.065 INFO running `"docker" "inspect" "a87b0c9132cc1c375fc0a0764679e6401c7a2f3ec7f788492b2bc972e4775435"`
Dec 15 00:42:30.166 INFO running `"docker" "rm" "-f" "a87b0c9132cc1c375fc0a0764679e6401c7a2f3ec7f788492b2bc972e4775435"`
Dec 15 00:42:30.263 INFO [stdout] a87b0c9132cc1c375fc0a0764679e6401c7a2f3ec7f788492b2bc972e4775435
Dec 15 00:42:30.273 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 00:42:30.449 INFO [stdout] 4621e1b600edfbc0c04ac72c68e08c9169c7a952942e8a2a118f66f30249a632
Dec 15 00:42:30.450 INFO running `"docker" "start" "-a" "4621e1b600edfbc0c04ac72c68e08c9169c7a952942e8a2a118f66f30249a632"`
Dec 15 00:42:31.110 INFO [stderr] usermod: no changes
Dec 15 00:42:31.183 INFO [stderr]    Compiling puzzle-solver v0.4.1 (/source)
Dec 15 00:42:34.214 INFO [stderr] warning: variable does not need to be mutable
Dec 15 00:42:34.214 INFO [stderr]    --> src/puzzle.rs:898:21
Dec 15 00:42:34.214 INFO [stderr]     |
Dec 15 00:42:34.214 INFO [stderr] 898 |         let (mut l, mut r) = slice.split_at_mut(b);
Dec 15 00:42:34.214 INFO [stderr]     |                     ----^
Dec 15 00:42:34.214 INFO [stderr]     |                     |
Dec 15 00:42:34.214 INFO [stderr]     |                     help: remove this `mut`
Dec 15 00:42:34.214 INFO [stderr]     |
Dec 15 00:42:34.214 INFO [stderr]     = note: #[warn(unused_mut)] on by default
Dec 15 00:42:34.214 INFO [stderr] 
Dec 15 00:42:34.214 INFO [stderr] warning: variable does not need to be mutable
Dec 15 00:42:34.214 INFO [stderr]    --> src/puzzle.rs:898:14
Dec 15 00:42:34.214 INFO [stderr]     |
Dec 15 00:42:34.214 INFO [stderr] 898 |         let (mut l, mut r) = slice.split_at_mut(b);
Dec 15 00:42:34.214 INFO [stderr]     |              ----^
Dec 15 00:42:34.214 INFO [stderr]     |              |
Dec 15 00:42:34.214 INFO [stderr]     |              help: remove this `mut`
Dec 15 00:42:34.214 INFO [stderr] 
Dec 15 00:42:42.874 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.74s
Dec 15 00:42:42.882 INFO [stderr] su: No module specific data is present
Dec 15 00:42:43.167 INFO running `"docker" "inspect" "4621e1b600edfbc0c04ac72c68e08c9169c7a952942e8a2a118f66f30249a632"`
Dec 15 00:42:43.263 INFO running `"docker" "rm" "-f" "4621e1b600edfbc0c04ac72c68e08c9169c7a952942e8a2a118f66f30249a632"`
Dec 15 00:42:43.430 INFO [stdout] 4621e1b600edfbc0c04ac72c68e08c9169c7a952942e8a2a118f66f30249a632
Dec 15 00:42:43.432 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 15 00:42:43.635 INFO [stdout] f35df3eae627e1732e525caa9f143f23e0131a29e767923dc903dececb7ac787
Dec 15 00:42:43.637 INFO running `"docker" "start" "-a" "f35df3eae627e1732e525caa9f143f23e0131a29e767923dc903dececb7ac787"`
Dec 15 00:42:44.127 INFO [stderr] usermod: no changes
Dec 15 00:42:44.164 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 15 00:42:44.165 INFO [stderr]      Running /target/debug/deps/puzzle_solver-bbfac5494e58e0bd
Dec 15 00:42:44.174 INFO [stdout] 
Dec 15 00:42:44.174 INFO [stdout] running 13 tests
Dec 15 00:42:44.174 INFO [stdout] test constraint::alldifferent::tests::test_contradiction ... ok
Dec 15 00:42:44.179 INFO [stdout] test constraint::alldifferent::tests::test_constrain_by_value ... ok
Dec 15 00:42:44.179 INFO [stdout] test constraint::alldifferent::tests::test_elimination ... ok
Dec 15 00:42:44.188 INFO [stdout] test constraint::alldifferent::tests::test_contradiction_by_length ... ok
Dec 15 00:42:44.188 INFO [stdout] test constraint::equality::tests::test_reduce_range ... ok
Dec 15 00:42:44.188 INFO [stdout] test constraint::equality::tests::test_contradiction ... ok
Dec 15 00:42:44.188 INFO [stdout] test constraint::unify::tests::test_unify_equality ... ok
Dec 15 00:42:44.189 INFO [stdout] test constraint::unify::tests::test_unify_alldifferent ... ok
Dec 15 00:42:44.189 INFO [stdout] test constraint::equality::tests::test_assign ... ok
Dec 15 00:42:44.189 INFO [stdout] test constraint::unify::tests::test_unify_unify ... ok
Dec 15 00:42:44.191 INFO [stderr]      Running /target/debug/deps/hidato-3b7e404d3ac76546
Dec 15 00:42:44.191 INFO [stdout] test linexpr::tests::test_ops ... ok
Dec 15 00:42:44.191 INFO [stdout] test puzzle::tests::test_no_vars ... ok
Dec 15 00:42:44.191 INFO [stdout] test linexpr::tests::test_coef_zero ... ok
Dec 15 00:42:44.191 INFO [stdout] 
Dec 15 00:42:44.191 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.191 INFO [stdout] 
Dec 15 00:42:44.192 INFO [stdout] 
Dec 15 00:42:44.192 INFO [stdout] running 1 test
Dec 15 00:42:44.213 INFO [stdout] test hidato_wikipedia ... ok
Dec 15 00:42:44.213 INFO [stdout] 
Dec 15 00:42:44.214 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.214 INFO [stdout] 
Dec 15 00:42:44.215 INFO [stderr]      Running /target/debug/deps/kakuro-fd7b83d97e5d5912
Dec 15 00:42:44.217 INFO [stdout] 
Dec 15 00:42:44.217 INFO [stdout] running 1 test
Dec 15 00:42:44.232 INFO [stdout] test kakuro_wikipedia ... ok
Dec 15 00:42:44.232 INFO [stdout] 
Dec 15 00:42:44.232 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.232 INFO [stdout] 
Dec 15 00:42:44.234 INFO [stderr]      Running /target/debug/deps/killersudoku-4bab4188c82df104
Dec 15 00:42:44.236 INFO [stdout] 
Dec 15 00:42:44.236 INFO [stdout] running 1 test
Dec 15 00:42:44.309 INFO [stdout] test killersudoku_wikipedia ... ok
Dec 15 00:42:44.309 INFO [stdout] 
Dec 15 00:42:44.309 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.309 INFO [stdout] 
Dec 15 00:42:44.309 INFO [stderr]      Running /target/debug/deps/magicsquare-77004b47bbac295a
Dec 15 00:42:44.311 INFO [stdout] 
Dec 15 00:42:44.311 INFO [stdout] running 2 tests
Dec 15 00:42:44.340 INFO [stdout] test magicsquare_4x4 ... ok
Dec 15 00:42:44.345 INFO [stdout] test magicsquare_3x3 ... ok
Dec 15 00:42:44.345 INFO [stdout] 
Dec 15 00:42:44.345 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.345 INFO [stdout] 
Dec 15 00:42:44.345 INFO [stderr]      Running /target/debug/deps/nonogram-03c99227b9134598
Dec 15 00:42:44.347 INFO [stdout] 
Dec 15 00:42:44.347 INFO [stdout] running 1 test
Dec 15 00:42:44.453 INFO [stdout] test nonogram_wikipedia ... ok
Dec 15 00:42:44.453 INFO [stdout] 
Dec 15 00:42:44.453 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.453 INFO [stdout] 
Dec 15 00:42:44.453 INFO [stderr]      Running /target/debug/deps/queens-169c25746568c715
Dec 15 00:42:44.457 INFO [stdout] 
Dec 15 00:42:44.457 INFO [stdout] running 5 tests
Dec 15 00:42:44.462 INFO [stdout] test queens_6x6 ... ok
Dec 15 00:42:44.472 INFO [stdout] test queens_5x5 ... ok
Dec 15 00:42:44.473 INFO [stdout] test queens_4x4 ... ok
Dec 15 00:42:44.478 INFO [stdout] test queens_7x7 ... ok
Dec 15 00:42:44.501 INFO [stdout] test queens_8x8 ... ok
Dec 15 00:42:44.501 INFO [stdout] 
Dec 15 00:42:44.501 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.501 INFO [stdout] 
Dec 15 00:42:44.501 INFO [stderr]      Running /target/debug/deps/samuraisudoku-f3fa5ae9f20588b2
Dec 15 00:42:44.503 INFO [stdout] 
Dec 15 00:42:44.503 INFO [stdout] running 1 test
Dec 15 00:42:44.589 INFO [stdout] test samuraisudoku_easy ... ok
Dec 15 00:42:44.589 INFO [stdout] 
Dec 15 00:42:44.589 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.589 INFO [stdout] 
Dec 15 00:42:44.590 INFO [stderr]      Running /target/debug/deps/sendmoremoney-5b435c21bc7c3b22
Dec 15 00:42:44.591 INFO [stdout] 
Dec 15 00:42:44.591 INFO [stdout] running 2 tests
Dec 15 00:42:44.594 INFO [stdout] test sendmoremoney_carry ... ok
Dec 15 00:42:44.596 INFO [stdout] test sendmoremoney_naive ... ok
Dec 15 00:42:44.596 INFO [stdout] 
Dec 15 00:42:44.596 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.596 INFO [stdout] 
Dec 15 00:42:44.597 INFO [stderr]      Running /target/debug/deps/sudoku-e76e3571a3a04772
Dec 15 00:42:44.599 INFO [stdout] 
Dec 15 00:42:44.599 INFO [stdout] running 2 tests
Dec 15 00:42:44.619 INFO [stdout] test sudoku_wikipedia ... ok
Dec 15 00:42:44.806 INFO [stdout] test sudoku_hardest ... ok
Dec 15 00:42:44.806 INFO [stdout] 
Dec 15 00:42:44.806 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.806 INFO [stdout] 
Dec 15 00:42:44.806 INFO [stderr]      Running /target/debug/deps/sujiko-9095f3f3fd52c505
Dec 15 00:42:44.812 INFO [stdout] 
Dec 15 00:42:44.812 INFO [stdout] running 1 test
Dec 15 00:42:44.827 INFO [stdout] test sujiko_simetric ... ok
Dec 15 00:42:44.827 INFO [stdout] 
Dec 15 00:42:44.827 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:44.827 INFO [stdout] 
Dec 15 00:42:44.828 INFO [stderr]      Running /target/debug/deps/takuzu-d359e18d1a7d4349
Dec 15 00:42:44.831 INFO [stdout] 
Dec 15 00:42:44.831 INFO [stdout] running 4 tests
Dec 15 00:42:44.870 INFO [stdout] test takuzu_grid1 ... ok
Dec 15 00:42:45.345 INFO [stdout] test takuzu_grid3 ... ok
Dec 15 00:42:45.380 INFO [stdout] test takuzu_grid2 ... ok
Dec 15 00:42:45.883 INFO [stdout] test takuzu_grid4 ... ok
Dec 15 00:42:45.883 INFO [stdout] 
Dec 15 00:42:45.883 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:45.883 INFO [stdout] 
Dec 15 00:42:45.883 INFO [stderr]      Running /target/debug/deps/xkcd-9aadb80580d299c5
Dec 15 00:42:45.885 INFO [stdout] 
Dec 15 00:42:45.885 INFO [stdout] running 1 test
Dec 15 00:42:45.891 INFO [stdout] test xkcd_knapsack ... ok
Dec 15 00:42:45.891 INFO [stdout] 
Dec 15 00:42:45.891 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:45.891 INFO [stdout] 
Dec 15 00:42:45.891 INFO [stderr]      Running /target/debug/deps/zebra-ce494cce8a8d2753
Dec 15 00:42:45.895 INFO [stdout] 
Dec 15 00:42:45.895 INFO [stdout] running 1 test
Dec 15 00:42:45.907 INFO [stdout] test zebra ... ok
Dec 15 00:42:45.907 INFO [stdout] 
Dec 15 00:42:45.907 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:42:45.907 INFO [stdout] 
Dec 15 00:42:45.907 INFO [stderr]    Doc-tests puzzle-solver
Dec 15 00:42:47.035 INFO [stdout] 
Dec 15 00:42:47.035 INFO [stdout] running 18 tests
Dec 15 00:42:54.067 INFO [stdout] test src/constraint/alldifferent.rs - constraint::alldifferent::AllDifferent::new (line 17) ... ok
Dec 15 00:42:54.516 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::insert_candidates (line 208) ... ok
Dec 15 00:42:54.602 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::new (line 97) ... ok
Dec 15 00:42:55.031 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::all_different (line 308) ... ok
Dec 15 00:42:55.049 INFO [stdout] test src/constraint/unify.rs - constraint::unify::Unify::new (line 18) ... ok
Dec 15 00:42:55.063 INFO [stdout] test src/constraint/equality.rs - constraint::equality::Equality::new (line 19) ... ok
Dec 15 00:42:56.219 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::intersect_candidates (line 272) ... ok
Dec 15 00:42:56.431 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::equals (line 324) ... ok
Dec 15 00:43:00.743 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::new_vars_with_candidates_1d (line 145) ... ok
Dec 15 00:43:01.512 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::new_var (line 114) ... ok
Dec 15 00:43:02.067 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::solve_any (line 357) ... ok
Dec 15 00:43:02.383 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::new_vars_with_candidates_2d (line 163) ... ok
Dec 15 00:43:02.707 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::solve_all (line 408) ... ok
Dec 15 00:43:02.799 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::remove_candidates (line 239) ... ok
Dec 15 00:43:03.199 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::set_value (line 185) ... ok
Dec 15 00:43:03.435 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::new_var_with_candidates (line 130) ... ok
Dec 15 00:43:04.407 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::unify (line 340) ... ok
Dec 15 00:43:04.439 INFO [stdout] test src/puzzle.rs - puzzle::Puzzle::solve_unique (line 382) ... ok
Dec 15 00:43:04.439 INFO [stdout] 
Dec 15 00:43:04.439 INFO [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 00:43:04.439 INFO [stdout] 
Dec 15 00:43:04.463 INFO [stderr] su: No module specific data is present
Dec 15 00:43:04.785 INFO running `"docker" "inspect" "f35df3eae627e1732e525caa9f143f23e0131a29e767923dc903dececb7ac787"`
Dec 15 00:43:04.889 INFO running `"docker" "rm" "-f" "f35df3eae627e1732e525caa9f143f23e0131a29e767923dc903dececb7ac787"`
Dec 15 00:43:05.009 INFO [stdout] f35df3eae627e1732e525caa9f143f23e0131a29e767923dc903dececb7ac787