[INFO] fetching crate vrf 0.2.2...
[INFO] testing vrf-0.2.2 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate vrf 0.2.2 into /workspace/builds/worker-96/source
[INFO] validating manifest of crates.io crate vrf 0.2.2 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 vrf 0.2.2
[INFO] finished tweaking crates.io crate vrf 0.2.2
[INFO] tweaked toml for crates.io crate vrf 0.2.2 written to /workspace/builds/worker-96/source/Cargo.toml
[INFO] crate crates.io crate vrf 0.2.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded pkg-config v0.3.16
[INFO] [stderr]   Downloaded openssl-sys v0.9.52
[INFO] [stderr]   Downloaded hmac-sha256 v0.1.2
[INFO] [stderr]   Downloaded openssl v0.10.25
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/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] e24c935e9a1519d31c526de543a752e33c639484802c0fa56eb8e6a56dae3380
[INFO] running `Command { std: "docker" "start" "-a" "e24c935e9a1519d31c526de543a752e33c639484802c0fa56eb8e6a56dae3380", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "e24c935e9a1519d31c526de543a752e33c639484802c0fa56eb8e6a56dae3380", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e24c935e9a1519d31c526de543a752e33c639484802c0fa56eb8e6a56dae3380", kill_on_drop: false }`
[INFO] [stdout] e24c935e9a1519d31c526de543a752e33c639484802c0fa56eb8e6a56dae3380
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/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] 73748ad13352227b8894b58c6a4a237333eaf7c0a65941c0115473bc18405be9
[INFO] running `Command { std: "docker" "start" "-a" "73748ad13352227b8894b58c6a4a237333eaf7c0a65941c0115473bc18405be9", kill_on_drop: false }`
[INFO] [stderr]    Compiling cc v1.0.46
[INFO] [stderr]    Compiling libc v0.2.65
[INFO] [stderr]    Compiling proc-macro2 v1.0.6
[INFO] [stderr]    Compiling unicode-xid v0.2.0
[INFO] [stderr]    Compiling syn v1.0.5
[INFO] [stderr]    Compiling pkg-config v0.3.16
[INFO] [stderr]    Compiling failure_derive v0.1.6
[INFO] [stderr]    Compiling openssl v0.10.25
[INFO] [stderr]    Compiling rustc-demangle v0.1.16
[INFO] [stderr]    Compiling hmac-sha256 v0.1.2
[INFO] [stderr]    Compiling hex v0.3.2
[INFO] [stderr]    Compiling openssl-sys v0.9.52
[INFO] [stderr]    Compiling backtrace-sys v0.1.32
[INFO] [stderr]    Compiling quote v1.0.2
[INFO] [stderr]    Compiling backtrace v0.3.40
[INFO] [stderr]    Compiling synstructure v0.12.1
[INFO] [stderr]    Compiling failure v0.1.6
[INFO] [stderr]    Compiling vrf v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.27s
[INFO] running `Command { std: "docker" "inspect" "73748ad13352227b8894b58c6a4a237333eaf7c0a65941c0115473bc18405be9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73748ad13352227b8894b58c6a4a237333eaf7c0a65941c0115473bc18405be9", kill_on_drop: false }`
[INFO] [stdout] 73748ad13352227b8894b58c6a4a237333eaf7c0a65941c0115473bc18405be9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/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] 741866647e6e49dc83e2c6a077227365c6a1c0eeed6cf0413b072c9c0e9b6027
[INFO] running `Command { std: "docker" "start" "-a" "741866647e6e49dc83e2c6a077227365c6a1c0eeed6cf0413b072c9c0e9b6027", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde v1.0.101
[INFO] [stderr]    Compiling ryu v1.0.2
[INFO] [stderr]    Compiling itoa v0.4.4
[INFO] [stderr]    Compiling serde_derive v1.0.101
[INFO] [stderr]    Compiling serde_json v1.0.41
[INFO] [stderr]    Compiling vrf v0.2.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused return value of `Vec::<T, A>::split_off` that must be used
[INFO] [stdout]    --> src/openssl/mod.rs:963:9
[INFO] [stdout]     |
[INFO] [stdout] 963 |         expected_c.split_off(16);
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout]     = note: use `.truncate()` if you don't need the other half
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused return value of `Vec::<T, A>::split_off` that must be used
[INFO] [stdout]    --> src/openssl/mod.rs:982:9
[INFO] [stdout]     |
[INFO] [stdout] 982 |         c_hex.split_off(16);
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: use `.truncate()` if you don't need the other half
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1m 02s
[INFO] running `Command { std: "docker" "inspect" "741866647e6e49dc83e2c6a077227365c6a1c0eeed6cf0413b072c9c0e9b6027", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "741866647e6e49dc83e2c6a077227365c6a1c0eeed6cf0413b072c9c0e9b6027", kill_on_drop: false }`
[INFO] [stdout] 741866647e6e49dc83e2c6a077227365c6a1c0eeed6cf0413b072c9c0e9b6027
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-96/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] 5dec579fdbdeb7b695b5b6bac352054347a1292533bceb57cf9aa78c722fdba0
[INFO] running `Command { std: "docker" "start" "-a" "5dec579fdbdeb7b695b5b6bac352054347a1292533bceb57cf9aa78c722fdba0", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr] warning: unused return value of `Vec::<T, A>::split_off` that must be used
[INFO] [stderr]    --> src/openssl/mod.rs:963:9
[INFO] [stderr]     |
[INFO] [stderr] 963 |         expected_c.split_off(16);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr]     = note: use `.truncate()` if you don't need the other half
[INFO] [stderr] 
[INFO] [stderr] warning: unused return value of `Vec::<T, A>::split_off` that must be used
[INFO] [stderr]    --> src/openssl/mod.rs:982:9
[INFO] [stderr]     |
[INFO] [stderr] 982 |         c_hex.split_off(16);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: use `.truncate()` if you don't need the other half
[INFO] [stderr] 
[INFO] [stderr] warning: `vrf` (lib test) generated 2 warnings
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.14s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/vrf-bc0cb372270e9bd1)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test dummy::test::test_prove ... ok
[INFO] [stdout] test dummy::test::test_verify ... ok
[INFO] [stdout] test openssl::test::test_decode_proof ... ok
[INFO] [stdout] test openssl::test::test_hash_to_try_and_increment_2 ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_p256_5 ... ok
[INFO] [stdout] test openssl::utils::test::test_bits2octets ... ok
[INFO] [stdout] test openssl::test::test_hash_points ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_k163 ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_p256_3 ... ok
[INFO] [stdout] test openssl::test::test_hash_to_try_and_increment_1 ... ok
[INFO] [stdout] test openssl::utils::test::test_bits2int ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_p256_4 ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_p256_1 ... ok
[INFO] [stdout] test openssl::test::test_generate_nonce_p256_2 ... ok
[INFO] [stdout] test openssl::test::test_derive_public_key ... ok
[INFO] [stdout] test openssl::test::test_verify_p256_sha256_tai_2 ... ok
[INFO] [stdout] test openssl::test::test_prove_secp256k1_sha256_tai ... ok
[INFO] [stdout] test openssl::test::test_verify_p256_sha256_tai_3 ... ok
[INFO] [stdout] test openssl::test::test_verify_p256_sha256_tai_1 ... ok
[INFO] [stdout] test openssl::test::test_verify_secp256k1_sha256_tai ... ok
[INFO] [stdout] test openssl::test::test_prove_p256_sha256_tai_1 ... ok
[INFO] [stdout] test openssl::test::test_prove_p256_sha256_tai_2 ... ok
[INFO] [stdout] test openssl::test::test_prove_p256_sha256_tai_3 ... ok
[INFO] [stdout] test openssl::test::test_verify_secp256k1_sha256_tai_bad_message ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vrf
[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" "5dec579fdbdeb7b695b5b6bac352054347a1292533bceb57cf9aa78c722fdba0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5dec579fdbdeb7b695b5b6bac352054347a1292533bceb57cf9aa78c722fdba0", kill_on_drop: false }`
[INFO] [stdout] 5dec579fdbdeb7b695b5b6bac352054347a1292533bceb57cf9aa78c722fdba0