Dec 05 20:25:46.324 INFO testing jakecraige/pals against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 05 20:25:46.324 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 20:25:46.471 INFO [stdout] da63e6277527cff391a3e73fb1672b8ff69f055f1eca7cfd5d0434a365c69f7f
Dec 05 20:25:46.472 INFO running `"docker" "start" "-a" "da63e6277527cff391a3e73fb1672b8ff69f055f1eca7cfd5d0434a365c69f7f"`
Dec 05 20:25:46.944 INFO [stderr] usermod: no changes
Dec 05 20:25:46.999 INFO [stderr]    Compiling pals v0.1.0 (/source)
Dec 05 20:25:47.948 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.99s
Dec 05 20:25:47.954 INFO [stderr] su: No module specific data is present
Dec 05 20:25:48.188 INFO running `"docker" "inspect" "da63e6277527cff391a3e73fb1672b8ff69f055f1eca7cfd5d0434a365c69f7f"`
Dec 05 20:25:48.264 INFO running `"docker" "rm" "-f" "da63e6277527cff391a3e73fb1672b8ff69f055f1eca7cfd5d0434a365c69f7f"`
Dec 05 20:25:48.362 INFO [stdout] da63e6277527cff391a3e73fb1672b8ff69f055f1eca7cfd5d0434a365c69f7f
Dec 05 20:25:48.364 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 20:25:48.549 INFO [stdout] e23794b811373ad073b6940e880f5d082a299356d91eaadcca5a4df054a71e89
Dec 05 20:25:48.551 INFO running `"docker" "start" "-a" "e23794b811373ad073b6940e880f5d082a299356d91eaadcca5a4df054a71e89"`
Dec 05 20:25:48.869 INFO [stderr] usermod: no changes
Dec 05 20:25:48.925 INFO [stderr]    Compiling pals v0.1.0 (/source)
Dec 05 20:25:51.680 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.79s
Dec 05 20:25:51.688 INFO [stderr] su: No module specific data is present
Dec 05 20:25:53.058 INFO running `"docker" "inspect" "e23794b811373ad073b6940e880f5d082a299356d91eaadcca5a4df054a71e89"`
Dec 05 20:25:53.388 INFO running `"docker" "rm" "-f" "e23794b811373ad073b6940e880f5d082a299356d91eaadcca5a4df054a71e89"`
Dec 05 20:25:53.730 INFO [stdout] e23794b811373ad073b6940e880f5d082a299356d91eaadcca5a4df054a71e89
Dec 05 20:25:53.732 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 20:25:54.217 INFO [stdout] 7ec5e4790648ae68f59818d47a0c6032de200dd7a5aeea62d6ece0d99302eff0
Dec 05 20:25:54.219 INFO running `"docker" "start" "-a" "7ec5e4790648ae68f59818d47a0c6032de200dd7a5aeea62d6ece0d99302eff0"`
Dec 05 20:25:55.887 INFO [stderr] usermod: no changes
Dec 05 20:25:55.996 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 05 20:25:55.996 INFO [stderr]      Running /target/debug/deps/pals-a06191438cb95737
Dec 05 20:25:56.005 INFO [stdout] 
Dec 05 20:25:56.005 INFO [stdout] running 32 tests
Dec 05 20:25:56.052 INFO [stdout] test set1::tests::byte_triple_to_6bit ... ok
Dec 05 20:25:56.053 INFO [stdout] test set1::tests::bytes_to_base64 ... ok
Dec 05 20:25:56.053 INFO [stdout] test set1::tests::bytes_to_16byte_blocks ... ok
Dec 05 20:25:56.054 INFO [stdout] test set1::tests::frequency_score ... ok
Dec 05 20:25:56.054 INFO [stdout] test set1::tests::hex_to_nibbles ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::hamming_distance ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::hex_to_base64 ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::aes_ecb_decrypt ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::transpose ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::nibs_to_bytes ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::text_frequency ... ok
Dec 05 20:25:56.080 INFO [stdout] test set1::tests::xor_encrypt_with_key ... ok
Dec 05 20:25:56.088 INFO [stdout] test set2::aes_cbc::tests::aes_cbc_decrypt_example ... ok
Dec 05 20:25:56.088 INFO [stdout] test set2::aes_cbc::tests::aes_cbc_encrypt_and_decrypt ... ok
Dec 05 20:25:56.088 INFO [stdout] test set2::aes_cbc::tests::remove_padding_with_challenge_input ... ok
Dec 05 20:25:56.088 INFO [stdout] test set2::aes_cbc::tests::remove_padding ... ok
Dec 05 20:25:56.088 INFO [stdout] test set1::tests::nibs_to_byte ... ok
Dec 05 20:25:56.103 INFO [stdout] test set1::tests::decrypt_single_byte_xor ... ok
Dec 05 20:25:56.103 INFO [stdout] test set1::tests::xor ... ok
Dec 05 20:25:56.124 INFO [stdout] test set2::cbc_bitflip::tests::encrypt_with_extra_works ... ok
Dec 05 20:25:56.124 INFO [stdout] test set2::cbc_bitflip::tests::bitflip_attack ... ok
Dec 05 20:25:56.124 INFO [stdout] test set2::cbc_bitflip::tests::is_admin ... ok
Dec 05 20:25:56.132 INFO [stdout] test set2::mode_detection::tests::detect_encryption ... ok
Dec 05 20:25:56.132 INFO [stdout] test set2::ecb_cut_and_paste::tests::gen_admin_cookie ... ok
Dec 05 20:25:56.132 INFO [stdout] test set2::ecb_cut_and_paste::tests::profile_for ... ok
Dec 05 20:25:56.156 INFO [stdout] test set2::mode_detection::tests::encryption_oracle ... ok
Dec 05 20:25:56.156 INFO [stdout] test set2::tests::pkcs_7_pad ... ok
Dec 05 20:25:56.641 INFO [stdout] test set1::tests::detect_aes_ecb_from_hex_lines ... ok
Dec 05 20:25:57.696 INFO [stdout] test set2::byte_decryption::tests::ecb_decrypt_w_prefix_using_oracle ... ok
Dec 05 20:25:57.729 INFO [stdout] test set2::byte_decryption::tests::ecb_decrypt_using_oracle ... ok
Dec 05 20:26:01.354 INFO [stdout] test set1::tests::detect_single_byte_xor ... ok
Dec 05 20:26:05.157 INFO [stdout] test set1::tests::decrypt_repeating_xor ... ok
Dec 05 20:26:05.157 INFO [stdout] 
Dec 05 20:26:05.157 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 20:26:05.157 INFO [stdout] 
Dec 05 20:26:05.170 INFO [stderr] su: No module specific data is present
Dec 05 20:26:06.534 INFO running `"docker" "inspect" "7ec5e4790648ae68f59818d47a0c6032de200dd7a5aeea62d6ece0d99302eff0"`
Dec 05 20:26:06.898 INFO running `"docker" "rm" "-f" "7ec5e4790648ae68f59818d47a0c6032de200dd7a5aeea62d6ece0d99302eff0"`
Dec 05 20:26:07.252 INFO [stdout] 7ec5e4790648ae68f59818d47a0c6032de200dd7a5aeea62d6ece0d99302eff0