[INFO] fetching crate blstrs 0.3.1...
[INFO] testing blstrs-0.3.1 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate blstrs 0.3.1 into /workspace/builds/worker-2/source
[INFO] validating manifest of crates.io crate blstrs 0.3.1 on toolchain beta-2021-09-08
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate blstrs 0.3.1
[INFO] finished tweaking crates.io crate blstrs 0.3.1
[INFO] tweaked toml for crates.io crate blstrs 0.3.1 written to /workspace/builds/worker-2/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 44caf0dc318cc81d69ee2dc5ea750be72bd4ae5c4b03a5a94bde1b9771289123
[INFO] running `Command { std: "docker" "start" "-a" "44caf0dc318cc81d69ee2dc5ea750be72bd4ae5c4b03a5a94bde1b9771289123", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "44caf0dc318cc81d69ee2dc5ea750be72bd4ae5c4b03a5a94bde1b9771289123", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "44caf0dc318cc81d69ee2dc5ea750be72bd4ae5c4b03a5a94bde1b9771289123", kill_on_drop: false }`
[INFO] [stdout] 44caf0dc318cc81d69ee2dc5ea750be72bd4ae5c4b03a5a94bde1b9771289123
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8a3046d31304b6982ba88c098b44fdc2f778353a4166f9c746dde1e0534c3ee5
[INFO] running `Command { std: "docker" "start" "-a" "8a3046d31304b6982ba88c098b44fdc2f778353a4166f9c746dde1e0534c3ee5", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.76
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling fff v0.3.1
[INFO] [stderr]    Compiling blst v0.3.3
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling rand_xorshift v0.2.0
[INFO] [stderr]    Compiling threadpool v1.8.1
[INFO] [stderr]    Compiling synstructure v0.12.5
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling zeroize_derive v1.1.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.29
[INFO] [stderr]    Compiling zeroize v1.4.1
[INFO] [stderr]    Compiling thiserror v1.0.29
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling groupy v0.4.1
[INFO] [stderr]    Compiling blstrs v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 25.96s
[INFO] running `Command { std: "docker" "inspect" "8a3046d31304b6982ba88c098b44fdc2f778353a4166f9c746dde1e0534c3ee5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8a3046d31304b6982ba88c098b44fdc2f778353a4166f9c746dde1e0534c3ee5", kill_on_drop: false }`
[INFO] [stdout] 8a3046d31304b6982ba88c098b44fdc2f778353a4166f9c746dde1e0534c3ee5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 57dc4779b90bbcacfaad8da25af970683bec3bc5b2ff1e7aecc66f6a10ea6f64
[INFO] running `Command { std: "docker" "start" "-a" "57dc4779b90bbcacfaad8da25af970683bec3bc5b2ff1e7aecc66f6a10ea6f64", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling serde_json v1.0.67
[INFO] [stderr]    Compiling blstrs v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 8.47s
[INFO] running `Command { std: "docker" "inspect" "57dc4779b90bbcacfaad8da25af970683bec3bc5b2ff1e7aecc66f6a10ea6f64", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "57dc4779b90bbcacfaad8da25af970683bec3bc5b2ff1e7aecc66f6a10ea6f64", kill_on_drop: false }`
[INFO] [stdout] 57dc4779b90bbcacfaad8da25af970683bec3bc5b2ff1e7aecc66f6a10ea6f64
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 78bd3a56202945302c1d25b56596aaac525e19ffc1d9fc0654a4db2a6c1411c6
[INFO] running `Command { std: "docker" "start" "-a" "78bd3a56202945302c1d25b56596aaac525e19ffc1d9fc0654a4db2a6c1411c6", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/blstrs-2fa2870e05d6734f)
[INFO] [stdout] 
[INFO] [stdout] running 121 tests
[INFO] [stdout] test fp2::tests::test_fp2_doubling ... ok
[INFO] [stdout] test fp2::tests::test_fp2_mul ... ok
[INFO] [stdout] test fp2::tests::test_fp2_negation ... ok
[INFO] [stdout] test fp2::tests::test_fp2_mul_nonresidue ... ok
[INFO] [stdout] test fp2::tests::test_fp2_inverse ... ok
[INFO] [stdout] test fp2::tests::test_fp2_legendre ... ok
[INFO] [stdout] test fp2::tests::test_fp2_frobenius_map ... ok
[INFO] [stdout] test fp2::tests::test_fp2_addition ... ok
[INFO] [stdout] test fp2::tests::test_fp2_basics ... ok
[INFO] [stdout] test fp12::tests::test_fp12_eq ... ok
[INFO] [stdout] test fp2::tests::test_fp2_ordering ... ok
[INFO] [stdout] test fp2::tests::test_fp2_squaring ... ok
[INFO] [stdout] test fp2::tests::test_fp2_sqrt ... ok
[INFO] [stdout] test fp2::tests::test_fp2_subtraction ... ok
[INFO] [stdout] test fp::tests::test_fp_ordering ... ok
[INFO] [stdout] test fp::tests::test_fp_from_into_repr ... ok
[INFO] [stdout] test fp::tests::test_fp_double ... ok
[INFO] [stdout] test fp::tests::test_fp_display ... ok
[INFO] [stdout] test fp::tests::test_fp_negate ... ok
[INFO] [stdout] test fp::tests::test_fp_add_assign ... ok
[INFO] [stdout] test fp6::tests::test_fp6_mul_nonresidue ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_display ... ok
[INFO] [stdout] test fp::tests::test_fp_num_bits ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_add_nocarry ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_is_zero ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_mul2 ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_ordering ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_shr ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_num_bits ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_div2 ... ok
[INFO] [stdout] test fp::tests::test_fp_inverse ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_conversion ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_from ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_is_odd ... ok
[INFO] [stdout] test g1::tests::test_mixed_addition ... ok
[INFO] [stdout] test g1::tests::test_projective_addition ... ok
[INFO] [stdout] test fp::tests::test_fp_root_of_unity ... ok
[INFO] [stdout] test g1::tests::test_affine_point_equality ... ok
[INFO] [stdout] test g1::tests::test_affine_negation_and_subtraction ... ok
[INFO] [stdout] test g1::tests::test_g1_is_zero ... ok
[INFO] [stdout] test g1::tests::test_doubling ... ok
[INFO] [stdout] test fp::tests::test_fp_repr_sub_noborrow ... ok
[INFO] [stdout] test g1::tests::test_is_on_curve ... ok
[INFO] [stdout] test g1::tests::curve_tests ... ok
[INFO] [stdout] test g1::tests::test_projective_negation_and_subtraction ... ok
[INFO] [stdout] test fp::tests::test_fp_sub_assign ... ok
[INFO] [stdout] test fp::tests::test_neg_one ... ok
[INFO] [stdout] test fp::tests::test_modulus ... ok
[INFO] [stdout] test g1::tests::test_projective_to_affine ... ok
[INFO] [stdout] test g1::tests::test_affine_scalar_multiplication ... ok
[INFO] [stdout] test scalar::tests::test_equality ... ok
[INFO] [stdout] test scalar::tests::test_inv ... ok
[INFO] [stdout] test scalar::tests::test_modulus ... ok
[INFO] [stdout] test scalar::tests::test_negation ... ok
[INFO] [stdout] test scalar::tests::test_double ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_is_odd ... ok
[INFO] [stdout] test scalar::tests::test_debug ... ok
[INFO] [stdout] test g1::tests::test_projective_scalar_multiplication ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_is_zero ... ok
[INFO] [stdout] test scalar::tests::test_from_bytes ... ok
[INFO] [stdout] test scalar::tests::test_scalar_display ... ok
[INFO] [stdout] test scalar::tests::test_inverse_is_pow ... ok
[INFO] [stdout] test scalar::tests::test_scalar_num_bits ... ok
[INFO] [stdout] test g1::tests::test_affine_to_projective ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_mul2 ... ok
[INFO] [stdout] test g1::tests::test_projective_point_equality ... ok
[INFO] [stdout] test g2::tests::test_affine_point_equality ... ok
[INFO] [stdout] test g2::tests::g2_test_is_valid ... ok
[INFO] [stdout] test g2::tests::test_g2_is_zero ... ok
[INFO] [stdout] test scalar::tests::test_addition ... ok
[INFO] [stdout] test g2::tests::test_projective_point_equality ... ok
[INFO] [stdout] test scalar::tests::test_scalar_double ... ok
[INFO] [stdout] test scalar::tests::test_scalar_from_into_repr ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_from ... ok
[INFO] [stdout] test scalar::tests::test_scalar_inverse ... ok
[INFO] [stdout] test scalar::tests::test_scalar_negate ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_conversion ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_display ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_div2 ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_add_nocarry ... ok
[INFO] [stdout] test scalar::tests::test_scalar_add_assign ... ok
[INFO] [stdout] test g2::tests::test_g2_doubling_correctness ... ok
[INFO] [stdout] test g2::tests::test_g2_addition_correctness ... ok
[INFO] [stdout] test scalar::tests::test_scalar_legendre ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_shr ... ok
[INFO] [stdout] test scalar::tests::test_scalar_root_of_unity ... ok
[INFO] [stdout] test scalar::tests::test_zero ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_ordering ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_num_bits ... ok
[INFO] [stdout] test scalar::tests::test_scalar_sub_assign ... ok
[INFO] [stdout] test scalar::tests::test_scalar_pow ... ok
[INFO] [stdout] test scalar::tests::test_subtraction ... ok
[INFO] [stdout] test scalar::tests::test_scalar_repr_sub_noborrow ... ok
[INFO] [stdout] test scalar::tests::test_sqrt ... ok
[INFO] [stdout] test serde_impl::tests::serde_fp ... ok
[INFO] [stdout] test serde_impl::tests::serde_scalar ... ok
[INFO] [stdout] test scalar::tests::test_to_bytes ... ok
[INFO] [stdout] test fp::tests::test_fp_pow ... ok
[INFO] [stdout] test fp12::tests::fp12_compression ... ok
[INFO] [stdout] test serde_impl::tests::serde_fp12 ... ok
[INFO] [stdout] test fp::tests::test_fp_sqrt ... ok
[INFO] [stdout] test serde_impl::tests::serde_g2 ... ok
[INFO] [stdout] test serde_impl::tests::serde_g1 ... ok
[INFO] [stdout] test scalar::tests::test_scalar_sqrt ... ok
[INFO] [stdout] test scalar::tests::test_scalar_squaring ... ok
[INFO] [stdout] test fp::tests::test_fp_squaring ... ok
[INFO] [stdout] test scalar::tests::scalar_repr_tests ... ok
[INFO] [stdout] test fp6::tests::fp6_random_field_tests ... ok
[INFO] [stdout] test scalar::tests::scalar_field_tests ... ok
[INFO] [stdout] test fp::tests::fp_field_tests ... ok
[INFO] [stdout] test fp12::tests::fp12_random_field_tests ... ok
[INFO] [stdout] test fp::tests::fp_repr_tests ... ok
[INFO] [stdout] test g1::tests::groupy_g1_curve_tests ... ok
[INFO] [stdout] test scalar::tests::test_multiplication ... ok
[INFO] [stdout] test scalar::tests::test_scalar_mul_assign ... ok
[INFO] [stdout] test fp2::tests::fp2_field_tests ... ok
[INFO] [stdout] test fp::tests::test_fp_mul_assign ... ok
[INFO] [stdout] test fp6::tests::fp6_random_frobenius_tests ... ok
[INFO] [stdout] test g2::tests::groupy_g2_curve_tests ... ok
[INFO] [stdout] test fp12::tests::fp12_random_frobenius_tests ... ok
[INFO] [stderr]    Doc-tests blstrs
[INFO] [stdout] test bls12_engine_tests ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 121 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 30.17s
[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" "78bd3a56202945302c1d25b56596aaac525e19ffc1d9fc0654a4db2a6c1411c6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "78bd3a56202945302c1d25b56596aaac525e19ffc1d9fc0654a4db2a6c1411c6", kill_on_drop: false }`
[INFO] [stdout] 78bd3a56202945302c1d25b56596aaac525e19ffc1d9fc0654a4db2a6c1411c6