Dec 12 22:48:51.569 INFO testing unixpickle/locky-puzzle against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 12 22:48:51.569 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:52.139 INFO [stdout] 975cbb25d452c0f0124d3d3c8b4bb5d3f3c6e5f8cb76e838ee2e7dd6aa66da5d
Dec 12 22:48:52.145 INFO running `"docker" "start" "-a" "975cbb25d452c0f0124d3d3c8b4bb5d3f3c6e5f8cb76e838ee2e7dd6aa66da5d"`
Dec 12 22:48:53.491 INFO [stderr] usermod: no changes
Dec 12 22:48:53.611 INFO [stderr]    Compiling atty v0.2.9
Dec 12 22:48:53.929 INFO [stderr]    Compiling clap v2.31.2
Dec 12 22:49:07.543 INFO [stderr]    Compiling locky-puzzle v0.1.0 (/source)
Dec 12 22:49:17.821 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 24.29s
Dec 12 22:49:17.826 INFO [stderr] su: No module specific data is present
Dec 12 22:49:18.429 INFO running `"docker" "inspect" "975cbb25d452c0f0124d3d3c8b4bb5d3f3c6e5f8cb76e838ee2e7dd6aa66da5d"`
Dec 12 22:49:18.673 INFO running `"docker" "rm" "-f" "975cbb25d452c0f0124d3d3c8b4bb5d3f3c6e5f8cb76e838ee2e7dd6aa66da5d"`
Dec 12 22:49:18.892 INFO [stdout] 975cbb25d452c0f0124d3d3c8b4bb5d3f3c6e5f8cb76e838ee2e7dd6aa66da5d
Dec 12 22:49:18.897 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:19.176 INFO [stdout] 4c8801c6a92c3a4172104e4a926f5efe185e83143312780a681855c0d89c3b36
Dec 12 22:49:19.180 INFO running `"docker" "start" "-a" "4c8801c6a92c3a4172104e4a926f5efe185e83143312780a681855c0d89c3b36"`
Dec 12 22:49:20.123 INFO [stderr] usermod: no changes
Dec 12 22:49:20.184 INFO [stderr]    Compiling locky-puzzle v0.1.0 (/source)
Dec 12 22:49:24.600 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.45s
Dec 12 22:49:24.600 INFO [stderr] su: No module specific data is present
Dec 12 22:49:25.123 INFO running `"docker" "inspect" "4c8801c6a92c3a4172104e4a926f5efe185e83143312780a681855c0d89c3b36"`
Dec 12 22:49:25.305 INFO running `"docker" "rm" "-f" "4c8801c6a92c3a4172104e4a926f5efe185e83143312780a681855c0d89c3b36"`
Dec 12 22:49:25.583 INFO [stdout] 4c8801c6a92c3a4172104e4a926f5efe185e83143312780a681855c0d89c3b36
Dec 12 22:49:25.583 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:25.983 INFO [stdout] b597685786c8be29f4ff24cff450f4c3d72b91f755dc66fcf95a1491268d78fd
Dec 12 22:49:25.985 INFO running `"docker" "start" "-a" "b597685786c8be29f4ff24cff450f4c3d72b91f755dc66fcf95a1491268d78fd"`
Dec 12 22:49:26.508 INFO [stderr] usermod: no changes
Dec 12 22:49:26.569 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 12 22:49:26.569 INFO [stderr]      Running /target/debug/deps/locky_puzzle-43ee8d7f53434431
Dec 12 22:49:26.569 INFO [stdout] 
Dec 12 22:49:26.569 INFO [stdout] running 17 tests
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::long_scramble ... ok
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::b_move ... ok
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::parse_algo ... ok
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::r_move ... ok
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::u_move ... ok
Dec 12 22:49:26.570 INFO [stdout] test moves::tests::short_scramble_f2_l3 ... ok
Dec 12 22:49:26.582 INFO [stdout] test moves::tests::short_scramble_d_b2 ... ok
Dec 12 22:49:26.582 INFO [stdout] test moves::tests::short_scramble_u3_r2 ... ok
Dec 12 22:49:26.589 INFO [stdout] test moves::tests::stringify_algo ... ok
Dec 12 22:49:26.647 INFO [stdout] test solve::tests::proj_simpler_solution ... ok
Dec 12 22:49:26.647 INFO [stdout] test solve::tests::zero_move_scramble ... ok
Dec 12 22:49:26.715 INFO [stdout] test state::tests::solved_display ... ok
Dec 12 22:49:26.743 INFO [stdout] test solve::tests::one_move_scramble ... ok
Dec 12 22:49:26.790 INFO [stdout] test solve::tests::not_enough_depth ... ok
Dec 12 22:49:26.851 INFO [stdout] test solve::tests::five_move_scramble ... ok
Dec 12 22:49:26.859 INFO [stdout] test solve::tests::proj_five_move_scramble ... ok
Dec 12 22:49:32.770 INFO [stdout] test heuristic::tests::generate_heuristic ... ok
Dec 12 22:49:32.770 INFO [stdout] 
Dec 12 22:49:32.770 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.770 INFO [stdout] 
Dec 12 22:49:32.771 INFO [stderr]      Running /target/debug/deps/bench_heuristic-fe87f99f3e44aa52
Dec 12 22:49:32.773 INFO [stdout] 
Dec 12 22:49:32.773 INFO [stdout] running 0 tests
Dec 12 22:49:32.773 INFO [stdout] 
Dec 12 22:49:32.773 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.773 INFO [stdout] 
Dec 12 22:49:32.773 INFO [stderr]      Running /target/debug/deps/bench_solve-4585f61d71e87c14
Dec 12 22:49:32.774 INFO [stdout] 
Dec 12 22:49:32.774 INFO [stdout] running 0 tests
Dec 12 22:49:32.774 INFO [stdout] 
Dec 12 22:49:32.774 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.774 INFO [stdout] 
Dec 12 22:49:32.775 INFO [stderr]      Running /target/debug/deps/locky_scramble-3ee64a776671f73f
Dec 12 22:49:32.776 INFO [stdout] 
Dec 12 22:49:32.776 INFO [stdout] running 0 tests
Dec 12 22:49:32.776 INFO [stdout] 
Dec 12 22:49:32.776 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.776 INFO [stdout] 
Dec 12 22:49:32.776 INFO [stderr]      Running /target/debug/deps/locky_solve-b831f064ce4898ab
Dec 12 22:49:32.778 INFO [stdout] 
Dec 12 22:49:32.778 INFO [stdout] running 0 tests
Dec 12 22:49:32.778 INFO [stdout] 
Dec 12 22:49:32.778 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.778 INFO [stdout] 
Dec 12 22:49:32.778 INFO [stderr]    Doc-tests locky_puzzle
Dec 12 22:49:32.988 INFO [stdout] 
Dec 12 22:49:32.988 INFO [stdout] running 0 tests
Dec 12 22:49:32.988 INFO [stdout] 
Dec 12 22:49:32.988 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 12 22:49:32.988 INFO [stdout] 
Dec 12 22:49:32.996 INFO [stderr] su: No module specific data is present
Dec 12 22:49:33.289 INFO running `"docker" "inspect" "b597685786c8be29f4ff24cff450f4c3d72b91f755dc66fcf95a1491268d78fd"`
Dec 12 22:49:33.398 INFO running `"docker" "rm" "-f" "b597685786c8be29f4ff24cff450f4c3d72b91f755dc66fcf95a1491268d78fd"`
Dec 12 22:49:33.496 INFO [stdout] b597685786c8be29f4ff24cff450f4c3d72b91f755dc66fcf95a1491268d78fd