Dec 06 23:54:18.397 INFO testing sapling-crypto-0.0.1 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 23:54:18.397 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 23:54:18.616 INFO [stdout] f6f3d190cd1dc4997866d492379b6d2a9e6b69aecee57cbce0fefae5dd4f80db
Dec 06 23:54:18.622 INFO running `"docker" "start" "-a" "f6f3d190cd1dc4997866d492379b6d2a9e6b69aecee57cbce0fefae5dd4f80db"`
Dec 06 23:54:19.119 INFO [stderr] usermod: no changes
Dec 06 23:54:19.170 INFO [stderr]    Compiling pairing v0.13.2
Dec 06 23:54:22.854 INFO [stderr]    Compiling bellman v0.0.6
Dec 06 23:54:23.755 INFO [stderr]    Compiling sapling-crypto v0.0.1 (/source)
Dec 06 23:54:26.456 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.31s
Dec 06 23:54:26.460 INFO [stderr] su: No module specific data is present
Dec 06 23:54:27.073 INFO running `"docker" "inspect" "f6f3d190cd1dc4997866d492379b6d2a9e6b69aecee57cbce0fefae5dd4f80db"`
Dec 06 23:54:27.198 INFO running `"docker" "rm" "-f" "f6f3d190cd1dc4997866d492379b6d2a9e6b69aecee57cbce0fefae5dd4f80db"`
Dec 06 23:54:27.452 INFO [stdout] f6f3d190cd1dc4997866d492379b6d2a9e6b69aecee57cbce0fefae5dd4f80db
Dec 06 23:54:27.458 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 23:54:27.874 INFO [stdout] 2cef900fb085f2cce41506da68b3b59c38294706b316aed5f46bc5411295a83a
Dec 06 23:54:27.879 INFO running `"docker" "start" "-a" "2cef900fb085f2cce41506da68b3b59c38294706b316aed5f46bc5411295a83a"`
Dec 06 23:54:29.111 INFO [stderr] usermod: no changes
Dec 06 23:54:29.172 INFO [stderr]    Compiling sapling-crypto v0.0.1 (/source)
Dec 06 23:54:36.465 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.33s
Dec 06 23:54:36.465 INFO [stderr] su: No module specific data is present
Dec 06 23:54:37.042 INFO running `"docker" "inspect" "2cef900fb085f2cce41506da68b3b59c38294706b316aed5f46bc5411295a83a"`
Dec 06 23:54:37.437 INFO running `"docker" "rm" "-f" "2cef900fb085f2cce41506da68b3b59c38294706b316aed5f46bc5411295a83a"`
Dec 06 23:54:37.799 INFO [stdout] 2cef900fb085f2cce41506da68b3b59c38294706b316aed5f46bc5411295a83a
Dec 06 23:54:37.801 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 06 23:54:38.186 INFO [stdout] 58037ec290b3cc60ed25f3ad127c6e016665a6010096798b5b91e381f7982c28
Dec 06 23:54:38.190 INFO running `"docker" "start" "-a" "58037ec290b3cc60ed25f3ad127c6e016665a6010096798b5b91e381f7982c28"`
Dec 06 23:54:39.720 INFO [stderr] usermod: no changes
Dec 06 23:54:39.844 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Dec 06 23:54:39.844 INFO [stderr]      Running /target/debug/deps/sapling_crypto-84f9191f3b006ec9
Dec 06 23:54:39.848 INFO [stdout] 
Dec 06 23:54:39.848 INFO [stdout] running 55 tests
Dec 06 23:54:39.856 INFO [stdout] test circuit::boolean::test::test_allocated_bit ... ok
Dec 06 23:54:39.856 INFO [stdout] test circuit::boolean::test::test_boolean_negation ... ok
Dec 06 23:54:39.864 INFO [stdout] test circuit::boolean::test::test_boolean_xor ... ok
Dec 06 23:54:39.876 INFO [stdout] test circuit::uint32::test::test_uint32_rotr ... ok
Dec 06 23:54:39.888 INFO [stdout] test circuit::boolean::test::test_and ... ok
Dec 06 23:54:39.888 INFO [stdout] test circuit::boolean::test::test_xor ... ok
Dec 06 23:54:39.888 INFO [stdout] test circuit::test::test_cs ... ok
Dec 06 23:54:40.118 INFO [stdout] test circuit::uint32::test::test_uint32_from_bits ... ok
Dec 06 23:54:42.328 INFO [stdout] test jubjub::edwards::test::test_identities ... ok
Dec 06 23:54:43.780 INFO [stdout] test jubjub::edwards::test::test_back_and_forth ... ok
Dec 06 23:54:46.202 INFO [stdout] test jubjub::edwards::test::test_order ... ok
Dec 06 23:54:48.484 INFO [stdout] test jubjub::edwards::test::test_rand ... ok
Dec 06 23:54:48.484 INFO [stdout] test circuit::blake2s::test::test_blake2s_constraints ... ok
Dec 06 23:54:48.524 INFO [stdout] test jubjub::fs::test_fr_repr_add_nocarry ... ok
Dec 06 23:54:48.548 INFO [stdout] test jubjub::fs::test_fs_add_assign ... ok
Dec 06 23:54:48.548 INFO [stdout] test jubjub::fs::test_fs_display ... ok
Dec 06 23:54:48.548 INFO [stdout] test jubjub::fs::test_fs_double ... ok
Dec 06 23:54:48.564 INFO [stdout] test jubjub::fs::test_fs_from_into_repr ... ok
Dec 06 23:54:49.764 INFO [stdout] test jubjub::fs::test_fs_inverse ... ok
Dec 06 23:54:49.784 INFO [stdout] test jubjub::fs::test_fs_is_valid ... ok
Dec 06 23:54:49.787 INFO [stdout] test jubjub::fs::test_fs_legendre ... ok
Dec 06 23:54:56.144 INFO [stdout] test circuit::uint32::test::test_uint32_xor ... ok
Dec 06 23:54:56.160 INFO [stdout] test jubjub::fs::test_fs_negate ... ok
Dec 06 23:54:56.161 INFO [stdout] test jubjub::fs::test_fs_num_bits ... ok
Dec 06 23:55:03.713 INFO [stdout] test jubjub::fs::test_fs_pow ... ok
Dec 06 23:55:03.724 INFO [stdout] test jubjub::fs::test_fs_repr_display ... ok
Dec 06 23:55:03.729 INFO [stdout] test jubjub::fs::test_fr_squaring ... ok
Dec 06 23:55:03.740 INFO [stdout] test jubjub::fs::test_fs_repr_divn ... ok
Dec 06 23:55:03.745 INFO [stdout] test jubjub::fs::test_fs_repr_from ... ok
Dec 06 23:55:03.748 INFO [stdout] test jubjub::fs::test_fs_repr_is_odd ... ok
Dec 06 23:55:03.748 INFO [stdout] test jubjub::fs::test_fs_repr_div2 ... ok
Dec 06 23:55:03.752 INFO [stdout] test jubjub::fs::test_fs_repr_mul2 ... ok
Dec 06 23:55:03.752 INFO [stdout] test jubjub::fs::test_fs_repr_is_zero ... ok
Dec 06 23:55:03.752 INFO [stdout] test jubjub::fs::test_fs_repr_num_bits ... ok
Dec 06 23:55:03.752 INFO [stdout] test jubjub::fs::test_fs_repr_ordering ... ok
Dec 06 23:55:03.752 INFO [stdout] test jubjub::fs::test_fs_root_of_unity ... ok
Dec 06 23:55:03.800 INFO [stdout] test jubjub::fs::test_fs_repr_sub_noborrow ... ok
Dec 06 23:55:03.808 INFO [stdout] test jubjub::fs::test_fs_sub_assign ... ok
Dec 06 23:55:03.808 INFO [stdout] test jubjub::fs::test_neg_one ... ok
Dec 06 23:55:04.616 INFO [stdout] test circuit::uint32::test::test_uint32_addmany ... ok
Dec 06 23:55:04.772 INFO [stdout] test jubjub::montgomery::test::test_awkward_points ... ok
Dec 06 23:55:05.870 INFO [stdout] test jubjub::montgomery::test::test_back_and_forth ... ok
Dec 06 23:55:10.220 INFO [stdout] test jubjub::fs::test_fs_sqrt ... ok
Dec 06 23:55:11.896 INFO [stdout] test jubjub::montgomery::test::test_identities ... ok
Dec 06 23:55:16.056 INFO [stdout] test jubjub::edwards::test::test_mul_associativity ... ok
Dec 06 23:55:33.038 INFO [stdout] test jubjub::edwards::test::test_associativity ... ok
Dec 06 23:55:33.628 INFO [stdout] test jubjub::montgomery::test::test_rand ... ok
Dec 06 23:55:33.680 INFO [stdout] test jubjub::test::test_params ... ok
Dec 06 23:55:39.852 INFO [stdout] test circuit::blake2s::test::test_blake2s ... test circuit::blake2s::test::test_blake2s has been running for over 60 seconds
Dec 06 23:55:40.124 INFO [stdout] test jubjub::edwards::test::test_montgomery_conversion ... test jubjub::edwards::test::test_montgomery_conversion has been running for over 60 seconds
Dec 06 23:55:47.684 INFO [stdout] test jubjub::montgomery::test::test_associativity ... ok
Dec 06 23:55:49.804 INFO [stdout] test jubjub::fs::test_fs_mul_assign ... test jubjub::fs::test_fs_mul_assign has been running for over 60 seconds
Dec 06 23:56:05.876 INFO [stdout] test jubjub::montgomery::test::test_edwards_conversion ... test jubjub::montgomery::test::test_edwards_conversion has been running for over 60 seconds
Dec 06 23:56:07.572 INFO [stdout] test jubjub::fs::test_fs_mul_assign ... ok
Dec 06 23:56:11.905 INFO [stdout] test jubjub::montgomery::test::test_mul_associativity ... test jubjub::montgomery::test::test_mul_associativity has been running for over 60 seconds
Dec 06 23:56:16.068 INFO [stdout] test jubjub::montgomery::test::test_order ... test jubjub::montgomery::test::test_order has been running for over 60 seconds
Dec 06 23:56:38.072 INFO [stdout] test jubjub::montgomery::test::test_edwards_conversion ... ok
Dec 06 23:56:40.668 INFO [stdout] test jubjub::montgomery::test::test_order ... ok
Dec 06 23:57:15.278 INFO [stdout] test jubjub::edwards::test::test_montgomery_conversion ... ok
Dec 07 00:00:01.900 INFO [stdout] test circuit::blake2s::test::test_blake2s ... ok
Dec 07 00:00:58.060 INFO [stdout] test jubjub::montgomery::test::test_mul_associativity ... ok
Dec 07 00:00:58.060 INFO [stdout] 
Dec 07 00:00:58.060 INFO [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 00:00:58.060 INFO [stdout] 
Dec 07 00:00:58.076 INFO [stderr]    Doc-tests sapling-crypto
Dec 07 00:01:00.416 INFO [stdout] 
Dec 07 00:01:00.416 INFO [stdout] running 0 tests
Dec 07 00:01:00.416 INFO [stdout] 
Dec 07 00:01:00.416 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 00:01:00.416 INFO [stdout] 
Dec 07 00:01:00.420 INFO [stderr] su: No module specific data is present
Dec 07 00:01:01.160 INFO running `"docker" "inspect" "58037ec290b3cc60ed25f3ad127c6e016665a6010096798b5b91e381f7982c28"`
Dec 07 00:01:01.524 INFO running `"docker" "rm" "-f" "58037ec290b3cc60ed25f3ad127c6e016665a6010096798b5b91e381f7982c28"`
Dec 07 00:01:01.892 INFO [stdout] 58037ec290b3cc60ed25f3ad127c6e016665a6010096798b5b91e381f7982c28