Dec 13 16:08:21.840 INFO testing jakecraige/pals against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 16:08:21.840 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 16:08:22.010 INFO [stdout] c701614564ef7b1c5f4ec7b8574b1ea32c81a63cb3d78f4a0b92cf2c8c0f1e65
Dec 13 16:08:22.012 INFO running `"docker" "start" "-a" "c701614564ef7b1c5f4ec7b8574b1ea32c81a63cb3d78f4a0b92cf2c8c0f1e65"`
Dec 13 16:08:22.389 INFO [stderr] usermod: no changes
Dec 13 16:08:22.483 INFO [stderr]    Compiling pals v0.1.0 (/source)
Dec 13 16:08:23.705 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.29s
Dec 13 16:08:23.709 INFO [stderr] su: No module specific data is present
Dec 13 16:08:24.292 INFO running `"docker" "inspect" "c701614564ef7b1c5f4ec7b8574b1ea32c81a63cb3d78f4a0b92cf2c8c0f1e65"`
Dec 13 16:08:24.508 INFO running `"docker" "rm" "-f" "c701614564ef7b1c5f4ec7b8574b1ea32c81a63cb3d78f4a0b92cf2c8c0f1e65"`
Dec 13 16:08:24.959 INFO [stdout] c701614564ef7b1c5f4ec7b8574b1ea32c81a63cb3d78f4a0b92cf2c8c0f1e65
Dec 13 16:08:24.967 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 16:08:25.459 INFO [stdout] 8d3ad26b15d8081813e1a3b0e2e0d6f2efb032683f4647d2fda2c57070114f4b
Dec 13 16:08:25.467 INFO running `"docker" "start" "-a" "8d3ad26b15d8081813e1a3b0e2e0d6f2efb032683f4647d2fda2c57070114f4b"`
Dec 13 16:08:27.207 INFO [stderr] usermod: no changes
Dec 13 16:08:27.345 INFO [stderr]    Compiling pals v0.1.0 (/source)
Dec 13 16:08:31.727 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.47s
Dec 13 16:08:31.732 INFO [stderr] su: No module specific data is present
Dec 13 16:08:32.411 INFO running `"docker" "inspect" "8d3ad26b15d8081813e1a3b0e2e0d6f2efb032683f4647d2fda2c57070114f4b"`
Dec 13 16:08:32.727 INFO running `"docker" "rm" "-f" "8d3ad26b15d8081813e1a3b0e2e0d6f2efb032683f4647d2fda2c57070114f4b"`
Dec 13 16:08:33.085 INFO [stdout] 8d3ad26b15d8081813e1a3b0e2e0d6f2efb032683f4647d2fda2c57070114f4b
Dec 13 16:08:33.091 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 13 16:08:33.579 INFO [stdout] 249148884c06167894788d0462d936f724de6745a6243fae29698ea3b77ef66d
Dec 13 16:08:33.587 INFO running `"docker" "start" "-a" "249148884c06167894788d0462d936f724de6745a6243fae29698ea3b77ef66d"`
Dec 13 16:08:35.293 INFO [stderr] usermod: no changes
Dec 13 16:08:35.468 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 13 16:08:35.471 INFO [stderr]      Running /target/debug/deps/pals-987b4afaf818c81f
Dec 13 16:08:35.479 INFO [stdout] 
Dec 13 16:08:35.479 INFO [stdout] running 32 tests
Dec 13 16:08:35.479 INFO [stdout] test set1::tests::byte_triple_to_6bit ... ok
Dec 13 16:08:35.479 INFO [stdout] test set1::tests::bytes_to_base64 ... ok
Dec 13 16:08:35.481 INFO [stdout] test set1::tests::bytes_to_16byte_blocks ... ok
Dec 13 16:08:35.481 INFO [stdout] test set1::tests::frequency_score ... ok
Dec 13 16:08:35.499 INFO [stdout] test set1::tests::hamming_distance ... ok
Dec 13 16:08:35.499 INFO [stdout] test set1::tests::hex_to_base64 ... ok
Dec 13 16:08:35.516 INFO [stdout] test set1::tests::aes_ecb_decrypt ... ok
Dec 13 16:08:35.516 INFO [stdout] test set1::tests::text_frequency ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::transpose ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::nibs_to_bytes ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::nibs_to_byte ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::hex_to_nibbles ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::xor ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::xor_encrypt_with_key ... ok
Dec 13 16:08:35.543 INFO [stdout] test set1::tests::decrypt_single_byte_xor ... ok
Dec 13 16:08:35.543 INFO [stdout] test set2::aes_cbc::tests::remove_padding ... ok
Dec 13 16:08:35.543 INFO [stdout] test set2::aes_cbc::tests::remove_padding_with_challenge_input ... ok
Dec 13 16:08:35.543 INFO [stdout] test set2::aes_cbc::tests::aes_cbc_encrypt_and_decrypt ... ok
Dec 13 16:08:35.543 INFO [stdout] test set2::aes_cbc::tests::aes_cbc_decrypt_example ... ok
Dec 13 16:08:35.579 INFO [stdout] test set2::cbc_bitflip::tests::encrypt_with_extra_works ... ok
Dec 13 16:08:35.579 INFO [stdout] test set2::cbc_bitflip::tests::bitflip_attack ... ok
Dec 13 16:08:35.579 INFO [stdout] test set2::cbc_bitflip::tests::is_admin ... ok
Dec 13 16:08:35.579 INFO [stdout] test set2::ecb_cut_and_paste::tests::gen_admin_cookie ... ok
Dec 13 16:08:35.591 INFO [stdout] test set2::mode_detection::tests::detect_encryption ... ok
Dec 13 16:08:35.591 INFO [stdout] test set2::ecb_cut_and_paste::tests::profile_for ... ok
Dec 13 16:08:35.591 INFO [stdout] test set2::mode_detection::tests::encryption_oracle ... ok
Dec 13 16:08:35.591 INFO [stdout] test set2::tests::pkcs_7_pad ... ok
Dec 13 16:08:36.292 INFO [stdout] test set1::tests::detect_aes_ecb_from_hex_lines ... ok
Dec 13 16:08:36.667 INFO [stdout] test set2::byte_decryption::tests::ecb_decrypt_using_oracle ... ok
Dec 13 16:08:36.843 INFO [stdout] test set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle ... ok
Dec 13 16:08:41.920 INFO [stdout] test set1::tests::detect_single_byte_xor ... ok
Dec 13 16:08:44.918 INFO [stdout] test set1::tests::decrypt_repeating_xor ... ok
Dec 13 16:08:44.918 INFO [stdout] 
Dec 13 16:08:44.918 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 16:08:44.919 INFO [stdout] 
Dec 13 16:08:44.921 INFO [stderr] su: No module specific data is present
Dec 13 16:08:46.578 INFO running `"docker" "inspect" "249148884c06167894788d0462d936f724de6745a6243fae29698ea3b77ef66d"`
Dec 13 16:08:46.952 INFO running `"docker" "rm" "-f" "249148884c06167894788d0462d936f724de6745a6243fae29698ea3b77ef66d"`
Dec 13 16:08:47.299 INFO [stdout] 249148884c06167894788d0462d936f724de6745a6243fae29698ea3b77ef66d