[INFO] fetching crate matriarch 0.1.0...
[INFO] testing matriarch-0.1.0 against try#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] extracting crate matriarch 0.1.0 into /workspace/builds/worker-78/source
[INFO] validating manifest of crates.io crate matriarch 0.1.0 on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate matriarch 0.1.0
[INFO] finished tweaking crates.io crate matriarch 0.1.0
[INFO] tweaked toml for crates.io crate matriarch 0.1.0 written to /workspace/builds/worker-78/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f492dd2e061fd4b89586620e12442c464ca027de3e64ec2f88256c6b964a760e
[INFO] running `Command { std: "docker" "start" "-a" "f492dd2e061fd4b89586620e12442c464ca027de3e64ec2f88256c6b964a760e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f492dd2e061fd4b89586620e12442c464ca027de3e64ec2f88256c6b964a760e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f492dd2e061fd4b89586620e12442c464ca027de3e64ec2f88256c6b964a760e", kill_on_drop: false }`
[INFO] [stdout] f492dd2e061fd4b89586620e12442c464ca027de3e64ec2f88256c6b964a760e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6d0ea4bec72e97ce45b4c8c780662cfbe4e30694f7d6f887c7d3d10f36610cda
[INFO] running `Command { std: "docker" "start" "-a" "6d0ea4bec72e97ce45b4c8c780662cfbe4e30694f7d6f887c7d3d10f36610cda", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling matriarch v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.19s
[INFO] running `Command { std: "docker" "inspect" "6d0ea4bec72e97ce45b4c8c780662cfbe4e30694f7d6f887c7d3d10f36610cda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6d0ea4bec72e97ce45b4c8c780662cfbe4e30694f7d6f887c7d3d10f36610cda", kill_on_drop: false }`
[INFO] [stdout] 6d0ea4bec72e97ce45b4c8c780662cfbe4e30694f7d6f887c7d3d10f36610cda
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 35059294bf3f46181ab6542c6cc180dd1471b679be57af30b13ca203dbeb18d4
[INFO] running `Command { std: "docker" "start" "-a" "35059294bf3f46181ab6542c6cc180dd1471b679be57af30b13ca203dbeb18d4", kill_on_drop: false }`
[INFO] [stderr]    Compiling matriarch v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.94s
[INFO] running `Command { std: "docker" "inspect" "35059294bf3f46181ab6542c6cc180dd1471b679be57af30b13ca203dbeb18d4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "35059294bf3f46181ab6542c6cc180dd1471b679be57af30b13ca203dbeb18d4", kill_on_drop: false }`
[INFO] [stdout] 35059294bf3f46181ab6542c6cc180dd1471b679be57af30b13ca203dbeb18d4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-78/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8ae9f3268da280b59134a7c2ecf0d1ccaab584b4fb98f852f420a19d2bb08a4e
[INFO] running `Command { std: "docker" "start" "-a" "8ae9f3268da280b59134a7c2ecf0d1ccaab584b4fb98f852f420a19d2bb08a4e", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/matriarch-dca3bc2b9c556c40)
[INFO] [stdout] 
[INFO] [stdout] running 77 tests
[INFO] [stdout] test mat2::tests::create_new_mat2_from_array ... ok
[INFO] [stdout] test mat2::tests::create_new_mat2_from_col_array ... ok
[INFO] [stdout] test mat2::tests::create_new_mat2 ... ok
[INFO] [stdout] test mat2::tests::create_new_mat2_from_values ... ok
[INFO] [stdout] test mat2::tests::create_new_mat2_identity ... ok
[INFO] [stdout] test mat2::tests::mat2_multiplication ... ok
[INFO] [stdout] test mat2::tests::get_determinant_of_mat2 ... ok
[INFO] [stdout] test mat2::tests::get_determinant_equal_to_zero ... ok
[INFO] [stdout] test mat2::tests::mat2_scalar_multiplication ... ok
[INFO] [stdout] test mat2::tests::mat2_to_array ... ok
[INFO] [stdout] test mat2::tests::mat2_to_col_array ... ok
[INFO] [stdout] test mat3::tests::create_new_mat3 ... ok
[INFO] [stdout] test mat2::tests::multiply_mat2_by_vec2 ... ok
[INFO] [stdout] test mat2::tests::mat2_to_vec2_array ... ok
[INFO] [stdout] test mat2::tests::multiply_by_identity ... ok
[INFO] [stdout] test mat3::tests::create_new_mat3_from_array ... ok
[INFO] [stdout] test mat3::tests::get_determinant_of_mat3 ... ok
[INFO] [stdout] test mat3::tests::mat3_scalar_multiplication ... ok
[INFO] [stdout] test mat3::tests::mat3_to_array ... ok
[INFO] [stdout] test mat3::tests::mat3_multiplication ... ok
[INFO] [stdout] test mat3::tests::get_determinant_equal_to_zero ... ok
[INFO] [stdout] test mat3::tests::create_new_mat3_from_col_array ... ok
[INFO] [stdout] test mat3::tests::create_new_mat3_identity ... ok
[INFO] [stdout] test mat3::tests::mat3_to_col_array ... ok
[INFO] [stdout] test mat3::tests::multiply_by_identity ... ok
[INFO] [stdout] test mat3::tests::multiply_mat3_by_vec3 ... ok
[INFO] [stdout] test mat4::tests::create_new_mat4 ... ok
[INFO] [stdout] test mat4::tests::create_new_mat4_from_array ... ok
[INFO] [stdout] test mat3::tests::mat3_to_vec3_array ... ok
[INFO] [stdout] test mat4::tests::create_new_mat4_from_col_array ... ok
[INFO] [stdout] test mat4::tests::create_new_mat4_identity ... ok
[INFO] [stdout] test mat4::tests::get_determinant_equal_to_zero ... ok
[INFO] [stdout] test mat4::tests::get_determinant_of_mat4 ... ok
[INFO] [stdout] test mat4::tests::mat4_multiplication ... ok
[INFO] [stdout] test mat4::tests::mat4_to_array ... ok
[INFO] [stdout] test mat4::tests::mat4_scalar_multiplication ... ok
[INFO] [stdout] test mat4::tests::mat4_to_col_array ... ok
[INFO] [stdout] test mat4::tests::mat4_to_vec4_array ... ok
[INFO] [stdout] test mat4::tests::multiply_by_identity ... ok
[INFO] [stdout] test mat4::tests::multiply_mat4_by_vec4 ... ok
[INFO] [stdout] test vec2::tests::add_2_vec2s_together ... ok
[INFO] [stdout] test vec2::tests::add_assign_2_vec2s ... ok
[INFO] [stdout] test vec2::tests::create_new_vec2 ... ok
[INFO] [stdout] test vec2::tests::create_new_vec2_from_values ... ok
[INFO] [stdout] test vec2::tests::create_new_vec2_from_array ... ok
[INFO] [stdout] test vec2::tests::get_a_negative_vec2 ... ok
[INFO] [stdout] test vec2::tests::get_cross_product ... ok
[INFO] [stdout] test vec2::tests::get_dot_product ... ok
[INFO] [stdout] test vec2::tests::get_vec2_as_array ... ok
[INFO] [stdout] test vec2::tests::get_vec2_length ... ok
[INFO] [stdout] test vec2::tests::multiply_a_vec2_by_a_scalar ... ok
[INFO] [stdout] test vec2::tests::subtract_2_vec2s ... ok
[INFO] [stdout] test vec2::tests::subtract_assign_2_vec2s ... ok
[INFO] [stdout] test vec3::tests::add_2_vec3s_together ... ok
[INFO] [stdout] test vec3::tests::add_assign_2_vec3s ... ok
[INFO] [stdout] test vec3::tests::create_new_vec3 ... ok
[INFO] [stdout] test vec3::tests::create_new_vec3_from_array ... ok
[INFO] [stdout] test vec3::tests::create_new_vec3_from_values ... ok
[INFO] [stdout] test vec3::tests::get_a_negative_vec3 ... ok
[INFO] [stdout] test vec3::tests::get_cross_product ... ok
[INFO] [stdout] test vec3::tests::multiply_a_vec3_by_a_scalar ... ok
[INFO] [stdout] test vec3::tests::subtract_2_vec3s ... ok
[INFO] [stdout] test vec4::tests::create_new_vec4 ... ok
[INFO] [stdout] test vec4::tests::get_vec4_as_array ... ok
[INFO] [stdout] test vec4::tests::add_assign_2_vec4s ... ok
[INFO] [stdout] test vec4::tests::create_new_vec4_from_values ... ok
[INFO] [stdout] test vec4::tests::subtract_2_vec4s ... ok
[INFO] [stdout] test vec4::tests::get_dot_product ... ok
[INFO] [stdout] test vec3::tests::subtract_assign_2_vec3s ... ok
[INFO] [stdout] test vec4::tests::multiply_a_vec4_by_a_scalar ... ok
[INFO] [stdout] test vec3::tests::get_vec3_as_array ... ok
[INFO] [stdout] test vec4::tests::get_a_negative_vec4 ... ok
[INFO] [stdout] test vec3::tests::get_vec3_length ... ok
[INFO] [stdout] test vec4::tests::create_new_vec4_from_array ... ok
[INFO] [stdout] test vec4::tests::subtract_assign_2_vec4s ... ok
[INFO] [stdout] test vec4::tests::get_vec4_length ... ok
[INFO] [stdout] test vec3::tests::get_dot_product ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 77 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests matriarch
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::add (line 103) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::add (line 97) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::add_assign (line 122) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::f32::mul (line 138) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::new_from_values (line 29) ... ok
[INFO] [stdout] test src/mat3.rs - mat3::Mat3::mul (line 112) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::new_from_array (line 43) ... ok
[INFO] [stdout] test src/mat4.rs - mat4::Mat4::mul (line 156) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::cross_product (line 72) ... ok
[INFO] [stdout] test src/mat2.rs - mat2::Mat2::mul (line 107) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::to_array (line 55) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::neg (line 179) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::add_assign (line 117) ... ok
[INFO] [stdout] test src/mat2.rs - mat2::f32::mul (line 141) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::to_array (line 56) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::sub (line 195) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::sub_assign (line 214) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::sub (line 185) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::mul (line 162) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::mul (line 159) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::new (line 18) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::sub_assign (line 205) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::f32::mul (line 142) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::new (line 17) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::new_from_values (line 28) ... ok
[INFO] [stdout] test src/vec3.rs - vec3::Vec3::cross_product (line 71) ... ok
[INFO] [stdout] test src/vec2.rs - vec2::Vec2::new_from_array (line 43) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.75s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "8ae9f3268da280b59134a7c2ecf0d1ccaab584b4fb98f852f420a19d2bb08a4e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ae9f3268da280b59134a7c2ecf0d1ccaab584b4fb98f852f420a19d2bb08a4e", kill_on_drop: false }`
[INFO] [stdout] 8ae9f3268da280b59134a7c2ecf0d1ccaab584b4fb98f852f420a19d2bb08a4e