[INFO] cloning repository https://github.com/jgouly/cube-tools
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jgouly/cube-tools" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjgouly%2Fcube-tools", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjgouly%2Fcube-tools'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7c3cdf2b5327eb1dd247b5811175adfa7e91cc45
[INFO] testing jgouly/cube-tools against 1.55.0 for beta-1.56-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjgouly%2Fcube-tools" "/workspace/builds/worker-10/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-10/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/jgouly/cube-tools on toolchain 1.55.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/jgouly/cube-tools
[INFO] finished tweaking git repo https://github.com/jgouly/cube-tools
[INFO] tweaked toml for git repo https://github.com/jgouly/cube-tools written to /workspace/builds/worker-10/source/Cargo.toml
[INFO] crate git repo https://github.com/jgouly/cube-tools already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f2330ef21029a8e70c272a6587b82a84ab2e0c14f9661f92d60c2b10900c4dd0
[INFO] running `Command { std: "docker" "start" "-a" "f2330ef21029a8e70c272a6587b82a84ab2e0c14f9661f92d60c2b10900c4dd0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f2330ef21029a8e70c272a6587b82a84ab2e0c14f9661f92d60c2b10900c4dd0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2330ef21029a8e70c272a6587b82a84ab2e0c14f9661f92d60c2b10900c4dd0", kill_on_drop: false }`
[INFO] [stdout] f2330ef21029a8e70c272a6587b82a84ab2e0c14f9661f92d60c2b10900c4dd0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6a9e499a7802be08877f70861ee8279db1b6cff76e7848526e6d4fa71a5d8d07
[INFO] running `Command { std: "docker" "start" "-a" "6a9e499a7802be08877f70861ee8279db1b6cff76e7848526e6d4fa71a5d8d07", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.71
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling unicode-width v0.1.7
[INFO] [stderr]    Compiling getopts v0.2.21
[INFO] [stderr]    Compiling quote v1.0.7
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling rand_core v0.5.1
[INFO] [stderr]    Compiling rand_chacha v0.2.2
[INFO] [stderr]    Compiling rand v0.7.3
[INFO] [stderr]    Compiling cube v0.1.0 (/opt/rustwide/workdir/cube)
[INFO] [stderr]    Compiling cycles v0.1.0 (/opt/rustwide/workdir/cycles)
[INFO] [stderr]    Compiling bld-solver v0.1.0 (/opt/rustwide/workdir/bld-solver)
[INFO] [stderr]    Compiling mini-internal v0.1.13
[INFO] [stderr]    Compiling miniserde v0.1.13
[INFO] [stderr]    Compiling alg-list-gen v0.1.0 (/opt/rustwide/workdir/alg-list-gen)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.09s
[INFO] running `Command { std: "docker" "inspect" "6a9e499a7802be08877f70861ee8279db1b6cff76e7848526e6d4fa71a5d8d07", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6a9e499a7802be08877f70861ee8279db1b6cff76e7848526e6d4fa71a5d8d07", kill_on_drop: false }`
[INFO] [stdout] 6a9e499a7802be08877f70861ee8279db1b6cff76e7848526e6d4fa71a5d8d07
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1e7fcd777a6347b1fcaa84eaad6d87bbf6b59ab4dc6ac95c57db3f70fd059dca
[INFO] running `Command { std: "docker" "start" "-a" "1e7fcd777a6347b1fcaa84eaad6d87bbf6b59ab4dc6ac95c57db3f70fd059dca", kill_on_drop: false }`
[INFO] [stderr]    Compiling cube v0.1.0 (/opt/rustwide/workdir/cube)
[INFO] [stderr]    Compiling cycles v0.1.0 (/opt/rustwide/workdir/cycles)
[INFO] [stderr]    Compiling bld-solver v0.1.0 (/opt/rustwide/workdir/bld-solver)
[INFO] [stderr]    Compiling alg-list-gen v0.1.0 (/opt/rustwide/workdir/alg-list-gen)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 2.94s
[INFO] running `Command { std: "docker" "inspect" "1e7fcd777a6347b1fcaa84eaad6d87bbf6b59ab4dc6ac95c57db3f70fd059dca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1e7fcd777a6347b1fcaa84eaad6d87bbf6b59ab4dc6ac95c57db3f70fd059dca", kill_on_drop: false }`
[INFO] [stdout] 1e7fcd777a6347b1fcaa84eaad6d87bbf6b59ab4dc6ac95c57db3f70fd059dca
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-10/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a65ca216b5dec396bb96a36e5d85263cc0e53f0a0138e5b267b9f45bba47b156
[INFO] running `Command { std: "docker" "start" "-a" "a65ca216b5dec396bb96a36e5d85263cc0e53f0a0138e5b267b9f45bba47b156", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.37s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/alg_list_gen-5c3945f44e9a6f24)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test tests::corner_cycle ... ok
[INFO] [stdout] test tests::letter_scheme ... ok
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/alg_list_gen-4d93f12dcaaee18a)
[INFO] [stdout] test tests::edge_cycle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/bld_solver-6e8e013e805d8373)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test subsets::tests::test_parity ... ok
[INFO] [stdout] test subsets::tests::test_buffer_in_place_cycle_break ... ok
[INFO] [stdout] test tests::basic_corner_3twist ... ok
[INFO] [stdout] test subsets::tests::test_cycle_break ... ok
[INFO] [stdout] test subsets::tests::test_cycle_break_index ... ok
[INFO] [stdout] test subsets::tests::basic_3cycle ... ok
[INFO] [stdout] test subsets::tests::basic_2twist ... ok
[INFO] [stdout] test tests::basic_corner_2twist ... ok
[INFO] [stdout] test subsets::tests::basic_3twist ... ok
[INFO] [stdout] test tests::basic_corner_3cycles ... ok
[INFO] [stdout] test tests::basic_edge_2twist ... ok
[INFO] [stdout] test tests::test_parity ... ok
[INFO] [stdout] test tests::test_2c2c ... ok
[INFO] [stdout] test tests::basic_edge_3cycles ... ok
[INFO] [stdout] test tests::test_fallible_solve ... ok
[INFO] [stdout] test tests::test_2e2e ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cube-5d98e9f3ecbc42f2)
[INFO] [stdout] 
[INFO] [stdout] running 64 tests
[INFO] [stdout] test alg::tests::cancel_move ... ok
[INFO] [stdout] test alg::tests::display ... ok
[INFO] [stdout] test alg::tests::same_movement ... ok
[INFO] [stdout] test alg::tests::expand ... ok
[INFO] [stdout] test alg::parser::tests::conjugates ... ok
[INFO] [stdout] test corner_pos::tests::corner_twists ... ok
[INFO] [stdout] test alg::parser::tests::simple_algs ... ok
[INFO] [stdout] test alg::parser::tests::commutators ... ok
[INFO] [stdout] test alg::parser::tests::invalid ... ok
[INFO] [stdout] test alg::tests::invert ... ok
[INFO] [stdout] test alg::parser::tests::alg_pair ... ok
[INFO] [stdout] test sticker_cube::tests::exhaustive_position_of ... ok
[INFO] [stdout] test edge_pos::tests::try_from ... ok
[INFO] [stdout] test piece::tests::test_num_orientations ... ok
[INFO] [stdout] test piece::tests::piece_rotations ... ok
[INFO] [stdout] test sticker_cube::tests::face_dprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_f ... ok
[INFO] [stdout] test sticker_cube::tests::face_b ... ok
[INFO] [stdout] test alg::tests::cancel_alg ... ok
[INFO] [stdout] test sticker_cube::tests::corner_lookup ... ok
[INFO] [stdout] test sticker_cube::tests::face_d ... ok
[INFO] [stdout] test sticker_cube::tests::face_u ... ok
[INFO] [stdout] test sticker_cube::tests::face_uprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_rprime ... ok
[INFO] [stdout] test edge_pos::tests::exhaustive_edge_orient ... ok
[INFO] [stdout] test alg::parser::tests::moves ... ok
[INFO] [stdout] test corner_pos::tests::exhaustive_corner_from ... ok
[INFO] [stdout] test sticker_cube::tests::edge_lookup ... ok
[INFO] [stdout] test sticker_cube::tests::face_b2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_bprime ... ok
[INFO] [stdout] test sticker_cube::tests::slice_e2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_f2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_fprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_l ... ok
[INFO] [stdout] test sticker_cube::tests::face_l2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_lprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_r ... ok
[INFO] [stdout] test edge_pos::tests::exhaustive_edge_from ... ok
[INFO] [stdout] test sticker_cube::tests::face_r2 ... ok
[INFO] [stdout] test sticker_cube::tests::piece_set ... ok
[INFO] [stdout] test sticker_cube::tests::position_of ... ok
[INFO] [stdout] test sticker_cube::tests::slice_e ... ok
[INFO] [stdout] test sticker_cube::tests::is_solved ... ok
[INFO] [stdout] test sticker_cube::tests::face_u2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_d2 ... ok
[INFO] [stdout] test sticker_cube::tests::parity ... ok
[INFO] [stdout] test corner_pos::tests::exhaustive_corner_orient ... ok
[INFO] [stdout] test sticker_cube::tests::slice_mprime ... ok
[INFO] [stdout] test sticker_cube::tests::slice_sprime ... ok
[INFO] [stdout] test sticker_cube::tests::slice_m ... ok
[INFO] [stdout] test sticker_cube::tests::slice_m2 ... ok
[INFO] [stdout] test sticker_cube::tests::test_validity ... ok
[INFO] [stdout] test sticker_cube::tests::slice_eprime ... ok
[INFO] [stdout] test edge_pos::tests::edge_flips ... ok
[INFO] [stdout] test sticker_cube::tests::wide_l ... ok
[INFO] [stdout] test sticker_cube::tests::wide_r ... ok
[INFO] [stdout] test tests::test_num_inversions ... ok
[INFO] [stdout] test sticker_cube::tests::wide_u ... ok
[INFO] [stdout] test sticker_cube::tests::wide_f ... ok
[INFO] [stdout] test sticker_cube::tests::wide_d ... ok
[INFO] [stdout] test sticker_cube::tests::wide_b ... ok
[INFO] [stdout] test sticker_cube::tests::slice_s ... ok
[INFO] [stdout] test sticker_cube::tests::slice_s2 ... ok
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cycles-07d5a3fa1d445f93)
[INFO] [stdout] test random_state::tests::test_random_states_are_valid ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 64 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stderr]    Doc-tests alg-list-gen
[INFO] [stdout] test tests::test_len ... ok
[INFO] [stdout] test tests::edge_cycles ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests bld-solver
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests cube
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests cycles
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a65ca216b5dec396bb96a36e5d85263cc0e53f0a0138e5b267b9f45bba47b156", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a65ca216b5dec396bb96a36e5d85263cc0e53f0a0138e5b267b9f45bba47b156", kill_on_drop: false }`
[INFO] [stdout] a65ca216b5dec396bb96a36e5d85263cc0e53f0a0138e5b267b9f45bba47b156