Nov 01 07:20:34.534 INFO testing jakecraige/pals against 1.30.0 for beta-1.31-1
Nov 01 07:20:34.534 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/1.30.0:/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 +1.30.0 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 07:20:34.737 INFO blam! 1c3d3e442d76769b1706a807b0d3cbeb8c828b28396bc7c3bb03016ec91ac1cd
Nov 01 07:20:34.740 INFO running `"docker" "start" "-a" "1c3d3e442d76769b1706a807b0d3cbeb8c828b28396bc7c3bb03016ec91ac1cd"`
Nov 01 07:20:35.134 INFO kablam! usermod: no changes
Nov 01 07:20:35.211 INFO kablam!    Compiling openssl-sys v0.9.36
Nov 01 07:20:36.284 INFO kablam!    Compiling openssl v0.10.12
Nov 01 07:20:42.201 INFO kablam!    Compiling pals v0.1.0 (/source)
Nov 01 07:20:43.715 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 8.55s
Nov 01 07:20:43.715 INFO kablam! su: No module specific data is present
Nov 01 07:20:44.254 INFO running `"docker" "rm" "-f" "1c3d3e442d76769b1706a807b0d3cbeb8c828b28396bc7c3bb03016ec91ac1cd"`
Nov 01 07:20:44.483 INFO blam! 1c3d3e442d76769b1706a807b0d3cbeb8c828b28396bc7c3bb03016ec91ac1cd
Nov 01 07:20:44.499 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 07:20:44.916 INFO blam! b6743165eede9efd4750acf5e115426f86f7018f2e201c7848fb6a3a7fcbb496
Nov 01 07:20:44.918 INFO running `"docker" "start" "-a" "b6743165eede9efd4750acf5e115426f86f7018f2e201c7848fb6a3a7fcbb496"`
Nov 01 07:20:46.912 INFO kablam! usermod: no changes
Nov 01 07:20:47.047 INFO kablam!    Compiling pals v0.1.0 (/source)
Nov 01 07:20:51.340 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 4.41s
Nov 01 07:20:51.344 INFO kablam! su: No module specific data is present
Nov 01 07:20:52.827 INFO running `"docker" "rm" "-f" "b6743165eede9efd4750acf5e115426f86f7018f2e201c7848fb6a3a7fcbb496"`
Nov 01 07:20:53.095 INFO blam! b6743165eede9efd4750acf5e115426f86f7018f2e201c7848fb6a3a7fcbb496
Nov 01 07:20:53.103 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-3/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/beta-1.31-1/1.30.0:/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 +1.30.0 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 01 07:20:53.521 INFO blam! b16b13f9b01be70ce4dd1358528328ebee5c41afcc7f07d6f8d98650e3722fc6
Nov 01 07:20:53.525 INFO running `"docker" "start" "-a" "b16b13f9b01be70ce4dd1358528328ebee5c41afcc7f07d6f8d98650e3722fc6"`
Nov 01 07:20:55.267 INFO kablam! usermod: no changes
Nov 01 07:20:55.379 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Nov 01 07:20:55.387 INFO kablam!      Running /target/debug/deps/pals-406941341d1b4765
Nov 01 07:20:55.400 INFO blam! 
Nov 01 07:20:55.400 INFO blam! running 32 tests
Nov 01 07:20:55.427 INFO blam! test set1::tests::bytes_to_16byte_blocks ... ok
Nov 01 07:20:55.427 INFO blam! test set1::tests::aes_ecb_decrypt ... ok
Nov 01 07:20:55.455 INFO blam! test set1::tests::bytes_to_base64 ... ok
Nov 01 07:20:55.455 INFO blam! test set1::tests::byte_triple_to_6bit ... ok
Nov 01 07:20:55.455 INFO blam! test set1::tests::hamming_distance ... ok
Nov 01 07:20:55.455 INFO blam! test set1::tests::frequency_score ... ok
Nov 01 07:20:55.459 INFO blam! test set1::tests::hex_to_base64 ... ok
Nov 01 07:20:55.459 INFO blam! test set1::tests::hex_to_nibbles ... ok
Nov 01 07:20:55.459 INFO blam! test set1::tests::nibs_to_byte ... ok
Nov 01 07:20:55.459 INFO blam! test set1::tests::nibs_to_bytes ... ok
Nov 01 07:20:55.461 INFO blam! test set1::tests::xor_encrypt_with_key ... ok
Nov 01 07:20:55.461 INFO blam! test set1::tests::xor ... ok
Nov 01 07:20:55.461 INFO blam! test set1::tests::transpose ... ok
Nov 01 07:20:55.461 INFO blam! test set1::tests::text_frequency ... ok
Nov 01 07:20:55.467 INFO blam! test set2::aes_cbc::tests::aes_cbc_decrypt_example ... ok
Nov 01 07:20:55.467 INFO blam! test set2::aes_cbc::tests::remove_padding ... ok
Nov 01 07:20:55.467 INFO blam! test set2::aes_cbc::tests::remove_padding_with_challenge_input ... ok
Nov 01 07:20:55.487 INFO blam! test set2::cbc_bitflip::tests::bitflip_attack ... ok
Nov 01 07:20:55.487 INFO blam! test set2::aes_cbc::tests::aes_cbc_encrypt_and_decrypt ... ok
Nov 01 07:20:55.495 INFO blam! test set1::tests::decrypt_single_byte_xor ... ok
Nov 01 07:20:55.503 INFO blam! test set2::ecb_cut_and_paste::tests::gen_admin_cookie ... ok
Nov 01 07:20:55.503 INFO blam! test set2::cbc_bitflip::tests::encrypt_with_extra_works ... ok
Nov 01 07:20:55.503 INFO blam! test set2::cbc_bitflip::tests::is_admin ... ok
Nov 01 07:20:55.507 INFO blam! test set2::mode_detection::tests::encryption_oracle ... ok
Nov 01 07:20:55.507 INFO blam! test set2::mode_detection::tests::detect_encryption ... ok
Nov 01 07:20:55.507 INFO blam! test set2::ecb_cut_and_paste::tests::profile_for ... ok
Nov 01 07:20:55.523 INFO blam! test set2::tests::pkcs_7_pad ... ok
Nov 01 07:20:55.587 INFO blam! test set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle ... FAILED
Nov 01 07:20:56.079 INFO blam! test set1::tests::detect_aes_ecb_from_hex_lines ... ok
Nov 01 07:20:56.443 INFO blam! test set2::byte_decryption::tests::ecb_decrypt_using_oracle ... ok
Nov 01 07:21:01.407 INFO blam! test set1::tests::detect_single_byte_xor ... ok
Nov 01 07:21:04.835 INFO blam! test set1::tests::decrypt_repeating_xor ... ok
Nov 01 07:21:04.835 INFO blam! 
Nov 01 07:21:04.835 INFO blam! failures:
Nov 01 07:21:04.835 INFO blam! 
Nov 01 07:21:04.836 INFO blam! ---- set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle stdout ----
Nov 01 07:21:04.836 INFO blam! Prefix len: 0
Nov 01 07:21:04.836 INFO blam! B: [252, 214, 26, 51, 134, 135, 228, 46, 238, 163, 128, 247, 136, 246, 3, 225]
Nov 01 07:21:04.836 INFO blam! B: [252, 214, 26, 51, 134, 135, 228, 46, 238, 163, 128, 247, 136, 246, 3, 225]
Nov 01 07:21:04.836 INFO blam! thread 'set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle' panicked at 'attempt to subtract with overflow', src/set2/byte_decryption.rs:133:28
Nov 01 07:21:04.836 INFO blam! stack backtrace:
Nov 01 07:21:04.836 INFO blam!    0:     0x55862d44579e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 01 07:21:04.836 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 01 07:21:04.836 INFO blam!    1:     0x55862d438816 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 01 07:21:04.836 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 01 07:21:04.836 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 01 07:21:04.836 INFO blam!    2:     0x55862d436bed - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:211
Nov 01 07:21:04.836 INFO blam!    3:     0x55862d4368fb - std::panicking::default_hook::h3a27b204a313406b
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:221
Nov 01 07:21:04.836 INFO blam!    4:     0x55862d43729c - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:477
Nov 01 07:21:04.836 INFO blam!    5:     0x55862d436e59 - std::panicking::continue_panic_fmt::h141671b29fe0e27d
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:391
Nov 01 07:21:04.836 INFO blam!    6:     0x55862d436d55 - rust_begin_unwind
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:326
Nov 01 07:21:04.836 INFO blam!    7:     0x55862d47fa1b - core::panicking::panic_fmt::h429a06507aba9228
Nov 01 07:21:04.836 INFO blam!                                at libcore/panicking.rs:77
Nov 01 07:21:04.836 INFO blam!    8:     0x55862d47f94a - core::panicking::panic::haa57ffd51eb03b56
Nov 01 07:21:04.836 INFO blam!                                at libcore/panicking.rs:52
Nov 01 07:21:04.836 INFO blam!    9:     0x55862d37ba6e - pals::set2::byte_decryption::ecb_decrypt_w_prefix_using_oracle::h293693401fefef6a
Nov 01 07:21:04.836 INFO blam!                                at src/set2/byte_decryption.rs:133
Nov 01 07:21:04.836 INFO blam!   10:     0x55862d37fcc7 - pals::set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle::h642a6b70bf0992c7
Nov 01 07:21:04.836 INFO blam!                                at src/set2/byte_decryption.rs:186
Nov 01 07:21:04.836 INFO blam!   11:     0x55862d37fbe9 - pals::set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle::{{closure}}::h998efc0651ba15ed
Nov 01 07:21:04.836 INFO blam!                                at src/set2/byte_decryption.rs:182
Nov 01 07:21:04.836 INFO blam!   12:     0x55862d3803dd - core::ops::function::FnOnce::call_once::h05458b36704c21ab
Nov 01 07:21:04.836 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 07:21:04.836 INFO blam!   13:     0x55862d3b5d5e - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 01 07:21:04.836 INFO blam!                                at libtest/lib.rs:1461
Nov 01 07:21:04.836 INFO blam!                                at libcore/ops/function.rs:238
Nov 01 07:21:04.836 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 07:21:04.836 INFO blam!   14:     0x55862d4543d9 - __rust_maybe_catch_panic
Nov 01 07:21:04.836 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 07:21:04.836 INFO blam!   15:     0x55862d3c166d - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 01 07:21:04.836 INFO blam!                                at libstd/panicking.rs:289
Nov 01 07:21:04.837 INFO blam!                                at libstd/panic.rs:392
Nov 01 07:21:04.837 INFO blam!                                at libtest/lib.rs:1416
Nov 01 07:21:04.837 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 01 07:21:04.837 INFO blam!   16:     0x55862d3d9ca4 - std::panicking::try::do_call::h1f0578498122cc00
Nov 01 07:21:04.837 INFO blam!                                at libstd/thread/mod.rs:409
Nov 01 07:21:04.837 INFO blam!                                at libstd/panic.rs:313
Nov 01 07:21:04.837 INFO blam!                                at libstd/panicking.rs:310
Nov 01 07:21:04.837 INFO blam!   17:     0x55862d4543d9 - __rust_maybe_catch_panic
Nov 01 07:21:04.837 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 01 07:21:04.837 INFO blam!   18:     0x55862d3ca576 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 01 07:21:04.837 INFO blam!                                at libstd/panicking.rs:289
Nov 01 07:21:04.837 INFO blam!                                at libstd/panic.rs:392
Nov 01 07:21:04.837 INFO blam!                                at libstd/thread/mod.rs:408
Nov 01 07:21:04.837 INFO blam!                                at liballoc/boxed.rs:646
Nov 01 07:21:04.837 INFO blam!   19:     0x55862d4470da - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 01 07:21:04.837 INFO blam!                                at liballoc/boxed.rs:656
Nov 01 07:21:04.837 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 01 07:21:04.837 INFO blam!   20:     0x55862d437a55 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 01 07:21:04.837 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 01 07:21:04.837 INFO blam!   21:     0x7fc7079696b9 - start_thread
Nov 01 07:21:04.837 INFO blam!   22:     0x7fc70748941c - clone
Nov 01 07:21:04.837 INFO blam!   23:                0x0 - <unknown>
Nov 01 07:21:04.837 INFO blam! 
Nov 01 07:21:04.845 INFO blam! 
Nov 01 07:21:04.845 INFO blam! failures:
Nov 01 07:21:04.845 INFO blam!     set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle
Nov 01 07:21:04.845 INFO blam! 
Nov 01 07:21:04.845 INFO blam! test result: FAILED. 31 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 07:21:04.845 INFO blam! 
Nov 01 07:21:04.848 INFO kablam! error: test failed, to rerun pass '--bin pals'
Nov 01 07:21:04.850 INFO kablam! su: No module specific data is present
Nov 01 07:21:05.747 INFO running `"docker" "rm" "-f" "b16b13f9b01be70ce4dd1358528328ebee5c41afcc7f07d6f8d98650e3722fc6"`
Nov 01 07:21:06.094 INFO blam! b16b13f9b01be70ce4dd1358528328ebee5c41afcc7f07d6f8d98650e3722fc6
