[INFO] fetching crate bls12_381_plus 0.5.1...
[INFO] testing bls12_381_plus-0.5.1 against 1.54.0 for beta-1.55-1
[INFO] extracting crate bls12_381_plus 0.5.1 into /workspace/builds/worker-34/source
[INFO] validating manifest of crates.io crate bls12_381_plus 0.5.1 on toolchain 1.54.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate bls12_381_plus 0.5.1
[INFO] finished tweaking crates.io crate bls12_381_plus 0.5.1
[INFO] tweaked toml for crates.io crate bls12_381_plus 0.5.1 written to /workspace/builds/worker-34/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "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" "+1.54.0" "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-34/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/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" "+1.54.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 27838c0c505413d7265d9fcab239b38e40e8b9a7ef0cdb0c9542a3ace28a6ea3
[INFO] running `Command { std: "docker" "start" "-a" "27838c0c505413d7265d9fcab239b38e40e8b9a7ef0cdb0c9542a3ace28a6ea3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27838c0c505413d7265d9fcab239b38e40e8b9a7ef0cdb0c9542a3ace28a6ea3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "27838c0c505413d7265d9fcab239b38e40e8b9a7ef0cdb0c9542a3ace28a6ea3", kill_on_drop: false }`
[INFO] [stdout] 27838c0c505413d7265d9fcab239b38e40e8b9a7ef0cdb0c9542a3ace28a6ea3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 36886187da18918551f6106a44681ccab68d80f907150c977723c88173271ce4
[INFO] running `Command { std: "docker" "start" "-a" "36886187da18918551f6106a44681ccab68d80f907150c977723c88173271ce4", 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] [stderr]    Compiling radium v0.6.2
[INFO] [stderr]    Compiling tap v1.0.1
[INFO] [stderr]    Compiling funty v1.2.0
[INFO] [stderr]    Compiling typenum v1.13.0
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling subtle v2.4.1
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling heapless v0.7.4
[INFO] [stderr]    Compiling stable_deref_trait v1.2.0
[INFO] [stderr]    Compiling zeroize v1.4.1
[INFO] [stderr]    Compiling generic-array v0.14.4
[INFO] [stderr]    Compiling serde v1.0.127
[INFO] [stderr]    Compiling wyz v0.4.0
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling bitvec v0.22.3
[INFO] [stderr]    Compiling digest v0.9.0
[INFO] [stderr]    Compiling ff v0.10.0
[INFO] [stderr]    Compiling group v0.10.0
[INFO] [stderr]    Compiling pairing v0.20.0
[INFO] [stderr]    Compiling bls12_381_plus v0.5.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.40s
[INFO] running `Command { std: "docker" "inspect" "36886187da18918551f6106a44681ccab68d80f907150c977723c88173271ce4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36886187da18918551f6106a44681ccab68d80f907150c977723c88173271ce4", kill_on_drop: false }`
[INFO] [stdout] 36886187da18918551f6106a44681ccab68d80f907150c977723c88173271ce4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b7fff6864a46ebae09416b2080c7b63a6220f5d061b3d49d5444a5799764dab7
[INFO] running `Command { std: "docker" "start" "-a" "b7fff6864a46ebae09416b2080c7b63a6220f5d061b3d49d5444a5799764dab7", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling block-padding v0.2.1
[INFO] [stderr]    Compiling keccak v0.1.0
[INFO] [stderr]    Compiling cpufeatures v0.1.5
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling rand_xorshift v0.3.0
[INFO] [stderr]    Compiling serde_json v1.0.66
[INFO] [stderr]    Compiling bstr v0.2.16
[INFO] [stderr]    Compiling serde_cbor v0.11.1
[INFO] [stderr]    Compiling serde_bare v0.4.0
[INFO] [stderr]    Compiling block-buffer v0.9.0
[INFO] [stderr]    Compiling sha3 v0.9.1
[INFO] [stderr]    Compiling sha2 v0.9.5
[INFO] [stderr]    Compiling csv v1.1.6
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling criterion v0.3.5
[INFO] [stderr]    Compiling bls12_381_plus v0.5.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 18.60s
[INFO] running `Command { std: "docker" "inspect" "b7fff6864a46ebae09416b2080c7b63a6220f5d061b3d49d5444a5799764dab7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b7fff6864a46ebae09416b2080c7b63a6220f5d061b3d49d5444a5799764dab7", kill_on_drop: false }`
[INFO] [stdout] b7fff6864a46ebae09416b2080c7b63a6220f5d061b3d49d5444a5799764dab7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-34/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 93b8393e828fcb3248612eb071da6fc949627867bfc7fff09113b26b7f4d2f46
[INFO] running `Command { std: "docker" "start" "-a" "93b8393e828fcb3248612eb071da6fc949627867bfc7fff09113b26b7f4d2f46", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.65s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/bls12_381_plus-50b37e5df474a7ec)
[INFO] [stdout] 
[INFO] [stdout] running 107 tests
[INFO] [stdout] test fp2::test_addition ... ok
[INFO] [stdout] test fp2::test_conditional_selection ... ok
[INFO] [stdout] test fp2::test_equality ... ok
[INFO] [stdout] test fp2::test_lexicographic_largest ... ok
[INFO] [stdout] test fp2::test_inversion ... ok
[INFO] [stdout] test fp2::test_subtraction ... ok
[INFO] [stdout] test fp::test_conditional_selection ... ok
[INFO] [stdout] test fp::test_debug ... ok
[INFO] [stdout] test fp2::test_negation ... ok
[INFO] [stdout] test fp2::test_multiplication ... ok
[INFO] [stdout] test fp::test_negation ... ok
[INFO] [stdout] test fp::test_subtraction ... ok
[INFO] [stdout] test g1::test_affine_point_equality ... ok
[INFO] [stdout] test fp::test_squaring ... ok
[INFO] [stdout] test fp::test_equality ... ok
[INFO] [stdout] test fp::test_addition ... ok
[INFO] [stdout] test fp::test_from_bytes ... ok
[INFO] [stdout] test fp::test_sqrt ... ok
[INFO] [stdout] test fp2::test_squaring ... ok
[INFO] [stdout] test fp2::test_sqrt ... ok
[INFO] [stdout] test g1::test_conditionally_select_affine ... ok
[INFO] [stdout] test fp::test_multiplication ... ok
[INFO] [stdout] test fp6::test_arithmetic ... ok
[INFO] [stdout] test g1::test_is_torsion_free ... ok
[INFO] [stdout] test g2::test_affine_point_equality ... ok
[INFO] [stdout] test g1::test_batch_normalize ... ok
[INFO] [stdout] test g1::test_clear_cofactor ... ok
[INFO] [stdout] test g1::test_serialization ... ok
[INFO] [stdout] test g1::test_mixed_addition ... ok
[INFO] [stdout] test g1::test_is_on_curve ... ok
[INFO] [stdout] test g1::test_affine_to_projective ... ok
[INFO] [stdout] test g1::test_doubling ... ok
[INFO] [stdout] test fp::test_lexicographic_largest ... ok
[INFO] [stdout] test g1::test_projective_negation_and_subtraction ... ok
[INFO] [stdout] test g1::test_projective_point_equality ... ok
[INFO] [stdout] test g1::test_projective_to_affine ... ok
[INFO] [stdout] test g2::test_affine_negation_and_subtraction ... ok
[INFO] [stdout] test g1::test_affine_negation_and_subtraction ... ok
[INFO] [stdout] test fp::test_inversion ... ok
[INFO] [stdout] test g2::test_hash ... ignored
[INFO] [stdout] test g2::test_conditionally_select_projective ... ok
[INFO] [stdout] test g2::test_is_on_curve ... ok
[INFO] [stdout] test g2::test_conditionally_select_affine ... ok
[INFO] [stdout] test g1::test_conditionally_select_projective ... ok
[INFO] [stdout] test g1::test_mul_by_x ... ok
[INFO] [stdout] test g2::test_affine_to_projective ... ok
[INFO] [stdout] test g2::test_doubling ... ok
[INFO] [stdout] test g2::test_batch_normalize ... ok
[INFO] [stdout] test fp::gen_constants ... ok
[INFO] [stdout] test g1::test_projective_addition ... ok
[INFO] [stdout] test scalar::gen_cs ... ok
[INFO] [stdout] test scalar::test_double ... ok
[INFO] [stdout] test g2::test_projective_negation_and_subtraction ... ok
[INFO] [stdout] test tests::hash_to_curve_g2_ro ... ignored
[INFO] [stdout] test scalar::test_from_u512_max ... ok
[INFO] [stdout] test scalar::test_from_bytes_wide_negative_one ... ok
[INFO] [stdout] test scalar::test_from_bytes_wide_r2 ... ok
[INFO] [stdout] test g2::test_affine_scalar_multiplication ... ok
[INFO] [stdout] test g2::test_projective_point_equality ... ok
[INFO] [stdout] test scalar::test_from_raw ... ok
[INFO] [stdout] test scalar::test_from_bytes ... ok
[INFO] [stdout] test g1::test_sum_of_products_alloc ... ok
[INFO] [stdout] test g2::test_projective_to_affine ... ok
[INFO] [stdout] test scalar::test_equality ... ok
[INFO] [stdout] test g1::test_projective_scalar_multiplication ... ok
[INFO] [stdout] test g2::test_psi ... ok
[INFO] [stdout] test scalar::test_addition ... ok
[INFO] [stdout] test scalar::test_negation ... ok
[INFO] [stdout] test scalar::test_zero ... ok
[INFO] [stdout] test scalar::test_from_u512_zero ... ok
[INFO] [stdout] test g2::test_serialization ... ok
[INFO] [stdout] test scalar::test_subtraction ... ok
[INFO] [stdout] test scalar::test_to_bytes ... ok
[INFO] [stdout] test scalar::test_inv ... ok
[INFO] [stdout] test g1::test_affine_scalar_multiplication ... ok
[INFO] [stdout] test scalar::test_from_bytes_wide_maximum ... ok
[INFO] [stdout] test scalar::test_from_okm ... ok
[INFO] [stdout] test scalar::test_from_u512_r ... ok
[INFO] [stdout] test scalar::test_from_u512_r2 ... ok
[INFO] [stdout] test scalar::test_multiplication ... ok
[INFO] [stdout] test scalar::test_serialization ... ok
[INFO] [stdout] test g1::test_hash ... ok
[INFO] [stdout] test scalar::test_squaring ... ok
[INFO] [stdout] test fp12::test_arithmetic ... ok
[INFO] [stdout] test scalar::test_inversion ... ok
[INFO] [stdout] test g2::test_mul_by_x ... ok
[INFO] [stdout] test pairings::test_gt_generator ... ok
[INFO] [stdout] test g2::test_projective_addition ... ok
[INFO] [stdout] test g2::test_mixed_addition ... ok
[INFO] [stdout] test scalar::test_invert_is_pow ... ok
[INFO] [stdout] test tests::hash_to_curve_g1_ro ... ok
[INFO] [stdout] test g2::test_projective_scalar_multiplication ... ok
[INFO] [stdout] test g1::test_sum_of_products ... ok
[INFO] [stdout] test pairings::test_unitary ... ok
[INFO] [stdout] test scalar::test_sqrt ... ok
[INFO] [stdout] test tests::encode_to_curve_g1 ... ok
[INFO] [stdout] test tests::test_pairing_result_against_relic ... ok
[INFO] [stdout] test g2::test_is_torsion_free ... ok
[INFO] [stdout] test g2::test_clear_cofactor ... ok
[INFO] [stdout] test pairings::test_bilinearity ... ok
[INFO] [stdout] test pairings::test_multi_miller_loop ... ok
[INFO] [stdout] test g2::test_sum_of_products_alloc ... ok
[INFO] [stdout] test g2::test_sum_of_products ... ok
[INFO] [stdout] test tests::g1_uncompressed_valid_test_vectors ... ok
[INFO] [stdout] test tests::g1_compressed_valid_test_vectors ... ok
[INFO] [stdout] test tests::g2_uncompressed_valid_test_vectors ... ok
[INFO] [stdout] test tests::g2_compressed_valid_test_vectors ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 105 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 35.56s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests bls12_381_plus
[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" "93b8393e828fcb3248612eb071da6fc949627867bfc7fff09113b26b7f4d2f46", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "93b8393e828fcb3248612eb071da6fc949627867bfc7fff09113b26b7f4d2f46", kill_on_drop: false }`
[INFO] [stdout] 93b8393e828fcb3248612eb071da6fc949627867bfc7fff09113b26b7f4d2f46
