[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 master#b70888601af92f6cdc0364abab3446e418b91d36 for pr-86479
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjgouly%2Fcube-tools" "/workspace/builds/worker-3/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/jgouly/cube-tools on toolchain b70888601af92f6cdc0364abab3446e418b91d36
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "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-3/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" "+b70888601af92f6cdc0364abab3446e418b91d36" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7c25944a4b1e335e046c472426db0a1340d471777b386e360b88bc263046d89a
[INFO] running `Command { std: "docker" "start" "-a" "7c25944a4b1e335e046c472426db0a1340d471777b386e360b88bc263046d89a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7c25944a4b1e335e046c472426db0a1340d471777b386e360b88bc263046d89a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c25944a4b1e335e046c472426db0a1340d471777b386e360b88bc263046d89a", kill_on_drop: false }`
[INFO] [stdout] 7c25944a4b1e335e046c472426db0a1340d471777b386e360b88bc263046d89a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7bbd6e4f3687c920af90a42409d22d2656b80ddb6ba986f2f2fce86386bb0ae5
[INFO] running `Command { std: "docker" "start" "-a" "7bbd6e4f3687c920af90a42409d22d2656b80ddb6ba986f2f2fce86386bb0ae5", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.71
[INFO] [stderr]    Compiling getrandom v0.1.14
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling proc-macro2 v1.0.18
[INFO] [stderr]    Compiling ppv-lite86 v0.2.8
[INFO] [stderr]    Compiling unicode-xid v0.2.1
[INFO] [stderr]    Compiling syn v1.0.33
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling itoa v0.4.6
[INFO] [stderr]    Compiling quote v1.0.7
[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 14.44s
[INFO] running `Command { std: "docker" "inspect" "7bbd6e4f3687c920af90a42409d22d2656b80ddb6ba986f2f2fce86386bb0ae5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7bbd6e4f3687c920af90a42409d22d2656b80ddb6ba986f2f2fce86386bb0ae5", kill_on_drop: false }`
[INFO] [stdout] 7bbd6e4f3687c920af90a42409d22d2656b80ddb6ba986f2f2fce86386bb0ae5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9dc0ab11ad25d9cc5954284697bec98ae23d24d3f624278a8b3643ce89face69
[INFO] running `Command { std: "docker" "start" "-a" "9dc0ab11ad25d9cc5954284697bec98ae23d24d3f624278a8b3643ce89face69", 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 3.33s
[INFO] running `Command { std: "docker" "inspect" "9dc0ab11ad25d9cc5954284697bec98ae23d24d3f624278a8b3643ce89face69", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9dc0ab11ad25d9cc5954284697bec98ae23d24d3f624278a8b3643ce89face69", kill_on_drop: false }`
[INFO] [stdout] 9dc0ab11ad25d9cc5954284697bec98ae23d24d3f624278a8b3643ce89face69
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+b70888601af92f6cdc0364abab3446e418b91d36" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] f62780306d85a5eb548d73178a598998740ec891b9c1438f37dd732b59a20a4b
[INFO] running `Command { std: "docker" "start" "-a" "f62780306d85a5eb548d73178a598998740ec891b9c1438f37dd732b59a20a4b", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stdout] running 3 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/alg_list_gen-320a2b38693b93e5)
[INFO] [stdout] test tests::edge_cycle ... ok
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/alg_list_gen-7a9d8a916f52add1)
[INFO] [stdout] test tests::corner_cycle ... ok
[INFO] [stdout] test tests::letter_scheme ... 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-c3e22fde23b90d06)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test subsets::tests::basic_2twist ... ok
[INFO] [stdout] test subsets::tests::basic_3cycle ... ok
[INFO] [stdout] test subsets::tests::test_cycle_break_index ... ok
[INFO] [stdout] test subsets::tests::test_parity ... ok
[INFO] [stdout] test tests::basic_corner_2twist ... ok
[INFO] [stdout] test tests::test_2c2c ... ok
[INFO] [stdout] test tests::basic_corner_3cycles ... ok
[INFO] [stdout] test subsets::tests::test_buffer_in_place_cycle_break ... ok
[INFO] [stdout] test tests::test_2e2e ... ok
[INFO] [stdout] test tests::test_fallible_solve ... ok
[INFO] [stdout] test tests::test_parity ... ok
[INFO] [stdout] test tests::basic_edge_3cycles ... ok
[INFO] [stdout] test tests::basic_corner_3twist ... ok
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cube-64bc4f6f8b1b3a06)
[INFO] [stdout] test subsets::tests::basic_3twist ... ok
[INFO] [stdout] test subsets::tests::test_cycle_break ... ok
[INFO] [stdout] test tests::basic_edge_2twist ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 64 tests
[INFO] [stdout] test alg::parser::tests::alg_pair ... ok
[INFO] [stdout] test alg::tests::expand ... ok
[INFO] [stdout] test alg::tests::invert ... ok
[INFO] [stdout] test edge_pos::tests::exhaustive_edge_orient ... ok
[INFO] [stdout] test alg::tests::cancel_move ... ok
[INFO] [stdout] test edge_pos::tests::try_from ... ok
[INFO] [stdout] test piece::tests::piece_rotations ... ok
[INFO] [stdout] test alg::tests::display ... ok
[INFO] [stdout] test corner_pos::tests::corner_twists ... ok
[INFO] [stdout] test sticker_cube::tests::face_b ... ok
[INFO] [stdout] test sticker_cube::tests::face_fprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_r ... ok
[INFO] [stdout] test sticker_cube::tests::face_r2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_rprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_u ... ok
[INFO] [stdout] test sticker_cube::tests::face_u2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_uprime ... ok
[INFO] [stdout] test sticker_cube::tests::is_solved ... ok
[INFO] [stdout] test sticker_cube::tests::parity ... ok
[INFO] [stdout] test sticker_cube::tests::piece_set ... ok
[INFO] [stdout] test sticker_cube::tests::position_of ... ok
[INFO] [stdout] test alg::tests::same_movement ... ok
[INFO] [stdout] test alg::parser::tests::conjugates ... ok
[INFO] [stdout] test alg::parser::tests::commutators ... ok
[INFO] [stdout] test sticker_cube::tests::face_f ... ok
[INFO] [stdout] test sticker_cube::tests::face_bprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_b2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_lprime ... ok
[INFO] [stdout] test sticker_cube::tests::face_l2 ... ok
[INFO] [stdout] test piece::tests::test_num_orientations ... ok
[INFO] [stdout] test corner_pos::tests::exhaustive_corner_from ... ok
[INFO] [stdout] test sticker_cube::tests::face_dprime ... ok
[INFO] [stdout] test alg::parser::tests::simple_algs ... ok
[INFO] [stdout] test edge_pos::tests::exhaustive_edge_from ... ok
[INFO] [stdout] test alg::parser::tests::moves ... ok
[INFO] [stdout] test edge_pos::tests::edge_flips ... ok
[INFO] [stdout] test sticker_cube::tests::face_f2 ... ok
[INFO] [stdout] test alg::tests::cancel_alg ... ok
[INFO] [stdout] test corner_pos::tests::exhaustive_corner_orient ... ok
[INFO] [stdout] test sticker_cube::tests::slice_e ... ok
[INFO] [stdout] test alg::parser::tests::invalid ... ok
[INFO] [stdout] test sticker_cube::tests::wide_l ... ok
[INFO] [stdout] test sticker_cube::tests::slice_e2 ... ok
[INFO] [stdout] test sticker_cube::tests::slice_eprime ... ok
[INFO] [stdout] test tests::test_num_inversions ... ok
[INFO] [stdout] test sticker_cube::tests::edge_lookup ... 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::wide_d ... ok
[INFO] [stdout] test sticker_cube::tests::slice_mprime ... ok
[INFO] [stdout] test sticker_cube::tests::slice_m ... ok
[INFO] [stdout] test sticker_cube::tests::wide_b ... ok
[INFO] [stdout] test sticker_cube::tests::wide_u ... ok
[INFO] [stdout] test sticker_cube::tests::corner_lookup ... ok
[INFO] [stdout] test sticker_cube::tests::slice_s2 ... ok
[INFO] [stdout] test sticker_cube::tests::slice_s ... ok
[INFO] [stdout] test sticker_cube::tests::exhaustive_position_of ... ok
[INFO] [stdout] test sticker_cube::tests::face_d ... ok
[INFO] [stdout] test sticker_cube::tests::face_d2 ... ok
[INFO] [stdout] test sticker_cube::tests::face_l ... ok
[INFO] [stdout] test sticker_cube::tests::wide_f ... ok
[INFO] [stdout] test sticker_cube::tests::wide_r ... ok
[INFO] [stdout] test sticker_cube::tests::slice_sprime ... ok
[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.09s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cycles-4276e92fa00be39c)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stderr]    Doc-tests alg-list-gen
[INFO] [stdout] test tests::edge_cycles ... ok
[INFO] [stdout] test tests::test_len ... 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] [stderr]    Doc-tests cycles
[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] [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" "f62780306d85a5eb548d73178a598998740ec891b9c1438f37dd732b59a20a4b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f62780306d85a5eb548d73178a598998740ec891b9c1438f37dd732b59a20a4b", kill_on_drop: false }`
[INFO] [stdout] f62780306d85a5eb548d73178a598998740ec891b9c1438f37dd732b59a20a4b