Oct 11 15:26:29.975 INFO testing pairing-0.14.2 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling
Oct 11 15:26:29.975 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen
Oct 11 15:26:29.975 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 15:26:30.254 INFO blam! 5c9b9f0225a1743ea8ed239421788263d97642757ed0805c77206ae50bb4fba2
Oct 11 15:26:30.268 INFO running `"docker" "start" "-a" "5c9b9f0225a1743ea8ed239421788263d97642757ed0805c77206ae50bb4fba2"`
Oct 11 15:26:31.323 INFO kablam! usermod: no changes
Oct 11 15:26:31.387 INFO kablam!    Compiling pairing v0.14.2 (/source)
Oct 11 15:26:36.440 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.11s
Oct 11 15:26:36.446 INFO kablam! su: No module specific data is present
Oct 11 15:26:36.853 INFO running `"docker" "rm" "-f" "5c9b9f0225a1743ea8ed239421788263d97642757ed0805c77206ae50bb4fba2"`
Oct 11 15:26:36.996 INFO blam! 5c9b9f0225a1743ea8ed239421788263d97642757ed0805c77206ae50bb4fba2
Oct 11 15:26:37.004 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run
Oct 11 15:26:37.004 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 15:26:37.208 INFO blam! 15b398d5dbf69bb6662506eb6abcf9ba975b4a8b24d18cbf47406df4bd17f52f
Oct 11 15:26:37.221 INFO running `"docker" "start" "-a" "15b398d5dbf69bb6662506eb6abcf9ba975b4a8b24d18cbf47406df4bd17f52f"`
Oct 11 15:26:37.705 INFO kablam! usermod: no changes
Oct 11 15:26:37.787 INFO kablam!    Compiling pairing v0.14.2 (/source)
Oct 11 15:26:47.156 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.43s
Oct 11 15:26:47.162 INFO kablam! su: No module specific data is present
Oct 11 15:26:48.370 INFO running `"docker" "rm" "-f" "15b398d5dbf69bb6662506eb6abcf9ba975b4a8b24d18cbf47406df4bd17f52f"`
Oct 11 15:26:48.644 INFO blam! 15b398d5dbf69bb6662506eb6abcf9ba975b4a8b24d18cbf47406df4bd17f52f
Oct 11 15:26:48.648 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen
Oct 11 15:26:48.648 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"`
Oct 11 15:26:49.115 INFO blam! b9fb51ae98717d6d30352546cc9780174111337a18168f07f0884b8e8192a733
Oct 11 15:26:49.116 INFO running `"docker" "start" "-a" "b9fb51ae98717d6d30352546cc9780174111337a18168f07f0884b8e8192a733"`
Oct 11 15:26:50.256 INFO kablam! usermod: no changes
Oct 11 15:26:50.460 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Oct 11 15:26:50.472 INFO kablam!      Running /target/debug/deps/pairing-6cb3b378f3d39b1d
Oct 11 15:26:50.472 INFO blam! 
Oct 11 15:26:50.472 INFO blam! running 102 tests
Oct 11 15:26:50.472 INFO blam! test bls12_381::ec::g1::test_g1_addition_correctness ... ok
Oct 11 15:26:50.476 INFO blam! test bls12_381::ec::g1::test_g1_doubling_correctness ... ok
Oct 11 15:26:50.752 INFO blam! test bls12_381::ec::g1::test_g1_same_y ... ok
Oct 11 15:26:50.772 INFO blam! test bls12_381::ec::g2::test_g2_addition_correctness ... ok
Oct 11 15:26:50.772 INFO blam! test bls12_381::ec::g2::test_g2_doubling_correctness ... ok
Oct 11 15:26:50.796 INFO blam! test bls12_381::ec::g1::g1_test_is_valid ... ok
Oct 11 15:26:51.098 INFO blam! test bls12_381::ec::g1::g1_generator ... ok
Oct 11 15:26:51.808 INFO blam! test bls12_381::ec::g2::g2_test_is_valid ... ok
Oct 11 15:26:51.810 INFO blam! test bls12_381::fq2::test_fq2_addition ... ok
Oct 11 15:26:51.810 INFO blam! test bls12_381::fq2::test_fq2_basics ... ok
Oct 11 15:26:51.810 INFO blam! test bls12_381::fq2::test_fq2_doubling ... ok
Oct 11 15:26:51.816 INFO blam! test bls12_381::fq2::test_fq2_frobenius_map ... ok
Oct 11 15:26:51.820 INFO blam! test bls12_381::fq2::test_fq2_inverse ... ok
Oct 11 15:26:51.844 INFO blam! test bls12_381::fq2::test_fq2_legendre ... ok
Oct 11 15:26:51.844 INFO blam! test bls12_381::fq2::test_fq2_mul ... ok
Oct 11 15:26:52.004 INFO blam! test bls12_381::fq2::test_fq2_mul_nonresidue ... ok
Oct 11 15:26:52.008 INFO blam! test bls12_381::fq2::test_fq2_negation ... ok
Oct 11 15:26:52.022 INFO blam! test bls12_381::fq2::test_fq2_ordering ... ok
Oct 11 15:26:52.146 INFO blam! test bls12_381::fq2::test_fq2_sqrt ... ok
Oct 11 15:26:52.160 INFO blam! test bls12_381::fq2::test_fq2_squaring ... ok
Oct 11 15:26:52.160 INFO blam! test bls12_381::fq2::test_fq2_subtraction ... ok
Oct 11 15:26:52.760 INFO blam! test bls12_381::ec::g2::g2_generator ... ok
Oct 11 15:26:54.624 INFO blam! test bls12_381::fq6::test_fq6_mul_by_01 ... ok
Oct 11 15:26:55.930 INFO blam! test bls12_381::fq12::test_fq12_mul_by_014 ... ok
Oct 11 15:26:56.143 INFO blam! test bls12_381::fq6::test_fq6_mul_nonresidue ... ok
Oct 11 15:26:56.221 INFO blam! test bls12_381::fq6::test_fq6_mul_by_1 ... ok
Oct 11 15:27:07.877 INFO blam! test bls12_381::fq::fq_repr_tests ... ok
Oct 11 15:27:07.877 INFO blam! test bls12_381::fq::test_b_coeff ... ok
Oct 11 15:27:07.913 INFO blam! test bls12_381::fq::test_fq_add_assign ... ok
Oct 11 15:27:07.961 INFO blam! test bls12_381::fq::test_fq_display ... ok
Oct 11 15:27:07.989 INFO blam! test bls12_381::fq::test_fq_double ... ok
Oct 11 15:27:08.049 INFO blam! test bls12_381::fq::test_fq_from_into_repr ... ok
Oct 11 15:27:11.913 INFO blam! test bls12_381::fq::test_fq_inverse ... ok
Oct 11 15:27:11.913 INFO blam! test bls12_381::fq::test_fq_is_valid ... ok
Oct 11 15:27:12.011 INFO blam! test bls12_381::fq::test_fq_legendre ... ok
Oct 11 15:27:50.488 INFO blam! test bls12_381::bls12_engine_tests ... test bls12_381::bls12_engine_tests has been running for over 60 seconds
Oct 11 15:27:50.488 INFO blam! test bls12_381::ec::g2::g2_curve_tests ... test bls12_381::ec::g2::g2_curve_tests has been running for over 60 seconds
Oct 11 15:27:50.488 INFO blam! test bls12_381::ec::g1::g1_curve_tests ... test bls12_381::ec::g1::g1_curve_tests has been running for over 60 seconds
Oct 11 15:27:50.772 INFO blam! test bls12_381::fq12::fq12_field_tests ... test bls12_381::fq12::fq12_field_tests has been running for over 60 seconds
Oct 11 15:27:51.116 INFO blam! test bls12_381::fq2::fq2_field_tests ... test bls12_381::fq2::fq2_field_tests has been running for over 60 seconds
Oct 11 15:27:52.169 INFO blam! test bls12_381::fq6::fq6_field_tests ... test bls12_381::fq6::fq6_field_tests has been running for over 60 seconds
Oct 11 15:27:56.148 INFO blam! test bls12_381::fq::fq_field_tests ... test bls12_381::fq::fq_field_tests has been running for over 60 seconds
Oct 11 15:28:12.014 INFO blam! test bls12_381::fq::test_fq_mul_assign ... test bls12_381::fq::test_fq_mul_assign has been running for over 60 seconds
Oct 11 15:30:55.168 INFO blam! test bls12_381::fq::test_fq_mul_assign ... ok
Oct 11 15:30:55.168 INFO blam! test bls12_381::fq::test_fq_negate ... ok
Oct 11 15:30:55.189 INFO blam! test bls12_381::fq::test_fq_num_bits ... ok
Oct 11 15:30:55.196 INFO blam! test bls12_381::fq::test_fq_ordering ... ok
Oct 11 15:31:12.716 INFO blam! test bls12_381::fq::test_fq_pow ... ok
Oct 11 15:31:12.751 INFO blam! test bls12_381::fq::test_fq_repr_add_nocarry ... ok
Oct 11 15:31:12.770 INFO blam! test bls12_381::fq::test_fq_repr_display ... ok
Oct 11 15:31:12.770 INFO blam! test bls12_381::fq::test_fq_repr_div2 ... ok
Oct 11 15:31:12.783 INFO blam! test bls12_381::fq::test_fq_repr_from ... ok
Oct 11 15:31:12.790 INFO blam! test bls12_381::fq::test_fq_repr_is_odd ... ok
Oct 11 15:31:12.804 INFO blam! test bls12_381::fq::test_fq_repr_is_zero ... ok
Oct 11 15:31:12.804 INFO blam! test bls12_381::fq::test_fq_repr_mul2 ... ok
Oct 11 15:31:12.804 INFO blam! test bls12_381::fq::test_fq_repr_num_bits ... ok
Oct 11 15:31:12.804 INFO blam! test bls12_381::fq::test_fq_repr_ordering ... ok
Oct 11 15:31:12.804 INFO blam! test bls12_381::fq::test_fq_repr_shr ... ok
Oct 11 15:31:12.824 INFO blam! test bls12_381::fq::test_fq_repr_sub_noborrow ... ok
Oct 11 15:31:12.860 INFO blam! test bls12_381::fq::test_fq_root_of_unity ... ok
Oct 11 15:31:26.557 INFO blam! test bls12_381::fq::test_fq_sqrt ... ok
Oct 11 15:31:46.136 INFO blam! test bls12_381::fq::test_fq_squaring ... ok
Oct 11 15:31:46.172 INFO blam! test bls12_381::fq::test_fq_sub_assign ... ok
Oct 11 15:31:48.484 INFO blam! test bls12_381::fq::test_frob_coeffs ... ok
Oct 11 15:31:48.514 INFO blam! test bls12_381::fq::test_neg_one ... ok
Oct 11 15:32:48.525 INFO blam! test bls12_381::fr::fr_field_tests ... test bls12_381::fr::fr_field_tests has been running for over 60 seconds
Oct 11 15:36:17.793 INFO blam! test bls12_381::fq::fq_field_tests ... ok
Oct 11 15:36:23.720 INFO blam! test bls12_381::fr::fr_repr_tests ... ok
Oct 11 15:36:23.720 INFO blam! test bls12_381::fr::test_fr_add_assign ... ok
Oct 11 15:36:23.722 INFO blam! test bls12_381::fr::test_fr_display ... ok
Oct 11 15:36:23.748 INFO blam! test bls12_381::fr::test_fr_double ... ok
Oct 11 15:36:23.774 INFO blam! test bls12_381::fr::test_fr_from_into_repr ... ok
Oct 11 15:36:24.848 INFO blam! test bls12_381::fr::test_fr_inverse ... ok
Oct 11 15:36:24.862 INFO blam! test bls12_381::fr::test_fr_is_valid ... ok
Oct 11 15:36:24.893 INFO blam! test bls12_381::fr::test_fr_legendre ... ok
Oct 11 15:36:57.793 INFO blam! test bls12_381::fr::fr_field_tests ... ok
Oct 11 15:36:57.801 INFO blam! test bls12_381::fr::test_fr_negate ... ok
Oct 11 15:36:57.801 INFO blam! test bls12_381::fr::test_fr_num_bits ... ok
Oct 11 15:37:04.305 INFO blam! test bls12_381::fr::test_fr_pow ... ok
Oct 11 15:37:04.316 INFO blam! test bls12_381::fr::test_fr_repr_add_nocarry ... ok
Oct 11 15:37:04.332 INFO blam! test bls12_381::fr::test_fr_repr_display ... ok
Oct 11 15:37:04.344 INFO blam! test bls12_381::fr::test_fr_repr_div2 ... ok
Oct 11 15:37:04.345 INFO blam! test bls12_381::fr::test_fr_repr_from ... ok
Oct 11 15:37:04.345 INFO blam! test bls12_381::fr::test_fr_repr_is_odd ... ok
Oct 11 15:37:04.345 INFO blam! test bls12_381::fr::test_fr_repr_is_zero ... ok
Oct 11 15:37:04.346 INFO blam! test bls12_381::fr::test_fr_repr_mul2 ... ok
Oct 11 15:37:04.346 INFO blam! test bls12_381::fr::test_fr_repr_num_bits ... ok
Oct 11 15:37:04.346 INFO blam! test bls12_381::fr::test_fr_repr_ordering ... ok
Oct 11 15:37:04.346 INFO blam! test bls12_381::fr::test_fr_repr_shr ... ok
Oct 11 15:37:04.372 INFO blam! test bls12_381::fr::test_fr_repr_sub_noborrow ... ok
Oct 11 15:37:04.408 INFO blam! test bls12_381::fr::test_fr_root_of_unity ... ok
Oct 11 15:37:24.893 INFO blam! test bls12_381::fr::test_fr_mul_assign ... test bls12_381::fr::test_fr_mul_assign has been running for over 60 seconds
Oct 11 15:37:25.788 INFO blam! test bls12_381::fr::test_fr_sqrt ... ok
Oct 11 15:37:49.224 INFO blam! test bls12_381::fr::test_fr_squaring ... ok
Oct 11 15:37:49.265 INFO blam! test bls12_381::fr::test_fr_sub_assign ... ok
Oct 11 15:37:49.584 INFO blam! test bls12_381::tests::test_g1_compressed_invalid_vectors ... ok
Oct 11 15:37:54.637 INFO blam! test bls12_381::fr::test_fr_mul_assign ... ok
Oct 11 15:37:54.766 INFO blam! test bls12_381::tests::test_g1_uncompressed_invalid_vectors ... ok
Oct 11 15:38:49.580 INFO blam! test bls12_381::tests::test_g1_compressed_valid_vectors ... test bls12_381::tests::test_g1_compressed_valid_vectors has been running for over 60 seconds
Oct 11 15:38:54.773 INFO blam! test bls12_381::tests::test_g1_uncompressed_valid_vectors ... test bls12_381::tests::test_g1_uncompressed_valid_vectors has been running for over 60 seconds
Oct 11 15:39:18.385 INFO blam! test bls12_381::tests::test_g1_compressed_valid_vectors ... ok
Oct 11 15:39:19.288 INFO blam! test bls12_381::tests::test_g2_compressed_invalid_vectors ... ok
Oct 11 15:39:30.054 INFO blam! test bls12_381::tests::test_g1_uncompressed_valid_vectors ... ok
Oct 11 15:39:30.212 INFO blam! test bls12_381::tests::test_g2_uncompressed_invalid_vectors ... ok
Oct 11 15:40:19.288 INFO blam! test bls12_381::tests::test_g2_compressed_valid_vectors ... test bls12_381::tests::test_g2_compressed_valid_vectors has been running for over 60 seconds
Oct 11 15:40:30.224 INFO blam! test bls12_381::tests::test_g2_uncompressed_valid_vectors ... test bls12_381::tests::test_g2_uncompressed_valid_vectors has been running for over 60 seconds
Oct 11 15:41:49.130 INFO error running command: killing a process failed: ESRCH: No such process
Oct 11 15:41:49.130 INFO running `"docker" "rm" "-f" "b9fb51ae98717d6d30352546cc9780174111337a18168f07f0884b8e8192a733"`
Oct 11 15:41:50.097 INFO blam! b9fb51ae98717d6d30352546cc9780174111337a18168f07f0884b8e8192a733