Dec 12 22:48:54.653 INFO testing unixpickle/locky-puzzle against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 12 22:48:54.653 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 12 22:48:55.381 INFO [stdout] 00afe37c1b1065a5b245931b2677d8c6235f536334f112cc2ec6d63e08703793
Dec 12 22:48:55.383 INFO running `"docker" "start" "-a" "00afe37c1b1065a5b245931b2677d8c6235f536334f112cc2ec6d63e08703793"`
Dec 12 22:48:56.966 INFO [stderr] usermod: no changes
Dec 12 22:48:57.128 INFO [stderr]    Compiling atty v0.2.9
Dec 12 22:48:57.519 INFO [stderr]    Compiling clap v2.31.2
Dec 12 22:49:11.167 INFO [stderr]    Compiling locky-puzzle v0.1.0 (/source)
Dec 12 22:49:19.702 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.70s
Dec 12 22:49:19.709 INFO [stderr] su: No module specific data is present
Dec 12 22:49:20.366 INFO running `"docker" "inspect" "00afe37c1b1065a5b245931b2677d8c6235f536334f112cc2ec6d63e08703793"`
Dec 12 22:49:20.657 INFO running `"docker" "rm" "-f" "00afe37c1b1065a5b245931b2677d8c6235f536334f112cc2ec6d63e08703793"`
Dec 12 22:49:20.951 INFO [stdout] 00afe37c1b1065a5b245931b2677d8c6235f536334f112cc2ec6d63e08703793
Dec 12 22:49:20.955 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 12 22:49:21.549 INFO [stdout] 4d6790ab190752a2014b28262c6f442d4a59e2f21514c0287a1c1f4917b2c60a
Dec 12 22:49:21.551 INFO running `"docker" "start" "-a" "4d6790ab190752a2014b28262c6f442d4a59e2f21514c0287a1c1f4917b2c60a"`
Dec 12 22:49:22.745 INFO [stderr] usermod: no changes
Dec 12 22:49:22.865 INFO [stderr]    Compiling locky-puzzle v0.1.0 (/source)
Dec 12 22:49:26.511 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.68s
Dec 12 22:49:26.511 INFO [stderr] su: No module specific data is present
Dec 12 22:49:27.059 INFO running `"docker" "inspect" "4d6790ab190752a2014b28262c6f442d4a59e2f21514c0287a1c1f4917b2c60a"`
Dec 12 22:49:27.351 INFO running `"docker" "rm" "-f" "4d6790ab190752a2014b28262c6f442d4a59e2f21514c0287a1c1f4917b2c60a"`
Dec 12 22:49:27.686 INFO [stdout] 4d6790ab190752a2014b28262c6f442d4a59e2f21514c0287a1c1f4917b2c60a
Dec 12 22:49:27.692 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 12 22:49:28.107 INFO [stdout] 3b4ee5c0077e1b3d27e4a27f583ac879d822966c88d5ce5db1e8854dafbc2369
Dec 12 22:49:28.109 INFO running `"docker" "start" "-a" "3b4ee5c0077e1b3d27e4a27f583ac879d822966c88d5ce5db1e8854dafbc2369"`
Dec 12 22:49:29.299 INFO [stderr] usermod: no changes
Dec 12 22:49:29.400 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 12 22:49:29.419 INFO [stderr]      Running /target/debug/deps/locky_puzzle-417572a058b8a986
Dec 12 22:49:29.419 INFO [stdout] 
Dec 12 22:49:29.419 INFO [stdout] running 17 tests
Dec 12 22:49:29.443 INFO [stdout] test moves::tests::parse_algo ... ok
Dec 12 22:49:29.443 INFO [stdout] test moves::tests::long_scramble ... ok
Dec 12 22:49:29.443 INFO [stdout] test moves::tests::b_move ... ok
Dec 12 22:49:29.443 INFO [stdout] test moves::tests::short_scramble_d_b2 ... ok
Dec 12 22:49:29.450 INFO [stdout] test moves::tests::r_move ... ok
Dec 12 22:49:29.451 INFO [stdout] test moves::tests::short_scramble_u3_r2 ... ok
Dec 12 22:49:29.451 INFO [stdout] test moves::tests::u_move ... ok
Dec 12 22:49:29.455 INFO [stdout] test moves::tests::stringify_algo ... ok
Dec 12 22:49:29.455 INFO [stdout] test moves::tests::short_scramble_f2_l3 ... ok
Dec 12 22:49:29.455 INFO [stdout] test solve::tests::proj_simpler_solution ... ok
Dec 12 22:49:29.471 INFO [stdout] test solve::tests::zero_move_scramble ... ok
Dec 12 22:49:29.487 INFO [stdout] test state::tests::solved_display ... ok
Dec 12 22:49:29.640 INFO [stdout] test solve::tests::one_move_scramble ... ok
Dec 12 22:49:29.739 INFO [stdout] test solve::tests::not_enough_depth ... ok
Dec 12 22:49:29.767 INFO [stdout] test solve::tests::five_move_scramble ... ok
Dec 12 22:49:29.767 INFO [stdout] test solve::tests::proj_five_move_scramble ... ok
Dec 12 22:49:35.379 INFO [stdout] test heuristic::tests::generate_heuristic ... ok
Dec 12 22:49:35.379 INFO [stdout] 
Dec 12 22:49:35.379 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.379 INFO [stdout] 
Dec 12 22:49:35.379 INFO [stdout] 
Dec 12 22:49:35.379 INFO [stdout] running 0 tests
Dec 12 22:49:35.379 INFO [stdout] 
Dec 12 22:49:35.379 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.379 INFO [stdout] 
Dec 12 22:49:35.379 INFO [stderr]      Running /target/debug/deps/bench_heuristic-a6a56f9deb95b803
Dec 12 22:49:35.379 INFO [stderr]      Running /target/debug/deps/bench_solve-cca51566b9a504e8
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.382 INFO [stderr]      Running /target/debug/deps/locky_scramble-c99058b73fa8cc9a
Dec 12 22:49:35.382 INFO [stdout] running 0 tests
Dec 12 22:49:35.382 INFO [stderr]      Running /target/debug/deps/locky_solve-22ae96d2e76aed75
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.382 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.382 INFO [stdout] running 0 tests
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.382 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.382 INFO [stdout] 
Dec 12 22:49:35.383 INFO [stdout] 
Dec 12 22:49:35.383 INFO [stderr]    Doc-tests locky_puzzle
Dec 12 22:49:35.383 INFO [stdout] running 0 tests
Dec 12 22:49:35.383 INFO [stdout] 
Dec 12 22:49:35.383 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.383 INFO [stdout] 
Dec 12 22:49:35.572 INFO [stdout] 
Dec 12 22:49:35.572 INFO [stdout] running 0 tests
Dec 12 22:49:35.572 INFO [stdout] 
Dec 12 22:49:35.573 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:35.573 INFO [stdout] 
Dec 12 22:49:35.575 INFO [stderr] su: No module specific data is present
Dec 12 22:49:35.964 INFO running `"docker" "inspect" "3b4ee5c0077e1b3d27e4a27f583ac879d822966c88d5ce5db1e8854dafbc2369"`
Dec 12 22:49:36.023 INFO running `"docker" "rm" "-f" "3b4ee5c0077e1b3d27e4a27f583ac879d822966c88d5ce5db1e8854dafbc2369"`
Dec 12 22:49:36.160 INFO [stdout] 3b4ee5c0077e1b3d27e4a27f583ac879d822966c88d5ce5db1e8854dafbc2369