Dec 13 23:34:29.283 INFO testing cjgu/rust_cryptopals against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 23:34:29.283 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 23:34:29.496 INFO [stdout] 07dc25deb9c070a5a6395de96d3566b8ad0c558396be9e1aadb3697ef4b1fe52
Dec 13 23:34:29.498 INFO running `"docker" "start" "-a" "07dc25deb9c070a5a6395de96d3566b8ad0c558396be9e1aadb3697ef4b1fe52"`
Dec 13 23:34:30.247 INFO [stderr] usermod: no changes
Dec 13 23:34:30.391 INFO [stderr]    Compiling crypto_pals v0.1.0 (/source)
Dec 13 23:34:45.401 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.12s
Dec 13 23:34:45.412 INFO [stderr] su: No module specific data is present
Dec 13 23:34:46.211 INFO running `"docker" "inspect" "07dc25deb9c070a5a6395de96d3566b8ad0c558396be9e1aadb3697ef4b1fe52"`
Dec 13 23:34:46.429 INFO running `"docker" "rm" "-f" "07dc25deb9c070a5a6395de96d3566b8ad0c558396be9e1aadb3697ef4b1fe52"`
Dec 13 23:34:46.762 INFO [stdout] 07dc25deb9c070a5a6395de96d3566b8ad0c558396be9e1aadb3697ef4b1fe52
Dec 13 23:34:46.771 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 23:34:47.331 INFO [stdout] 24cca62e28d12bc5e74892135633722c5bc5539e3e4f222daf8d6add67841304
Dec 13 23:34:47.336 INFO running `"docker" "start" "-a" "24cca62e28d12bc5e74892135633722c5bc5539e3e4f222daf8d6add67841304"`
Dec 13 23:34:48.411 INFO [stderr] usermod: no changes
Dec 13 23:34:48.519 INFO [stderr]    Compiling crypto_pals v0.1.0 (/source)
Dec 13 23:35:00.363 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.93s
Dec 13 23:35:00.369 INFO [stderr] su: No module specific data is present
Dec 13 23:35:01.129 INFO running `"docker" "inspect" "24cca62e28d12bc5e74892135633722c5bc5539e3e4f222daf8d6add67841304"`
Dec 13 23:35:01.406 INFO running `"docker" "rm" "-f" "24cca62e28d12bc5e74892135633722c5bc5539e3e4f222daf8d6add67841304"`
Dec 13 23:35:01.647 INFO [stdout] 24cca62e28d12bc5e74892135633722c5bc5539e3e4f222daf8d6add67841304
Dec 13 23:35:01.653 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 23:35:02.112 INFO [stdout] ad7c62d2de8cb423ac3d82c8497f92cf767e1234a6cb41ca417bd40661352c82
Dec 13 23:35:02.115 INFO running `"docker" "start" "-a" "ad7c62d2de8cb423ac3d82c8497f92cf767e1234a6cb41ca417bd40661352c82"`
Dec 13 23:35:03.010 INFO [stderr] usermod: no changes
Dec 13 23:35:03.167 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Dec 13 23:35:03.177 INFO [stderr]      Running /target/debug/deps/challenge-30c179335e65e138
Dec 13 23:35:03.188 INFO [stdout] 
Dec 13 23:35:03.188 INFO [stdout] running 26 tests
Dec 13 23:35:03.189 INFO [stdout] test utils::tests::test_b64_char_to_u8 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_b64_2 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_b64_3 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_b64_4 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_b64_1 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_hex_2 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_hex_3 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_hex_4 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_hex_encode_b64 ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_decode_hex_encode_hex ... ok
Dec 13 23:35:03.190 INFO [stdout] test utils::tests::test_encode_b64_1 ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_encode_b64_2 ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_encode_b64_3 ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_encode_hex_1 ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_pad_length ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_pkcs_7_pad_16 ... ok
Dec 13 23:35:03.191 INFO [stdout] test utils::tests::test_pkcs_7_pad_20 ... ok
Dec 13 23:35:03.191 INFO [stdout] test xor::tests::test_hamming_distance ... ok
Dec 13 23:35:03.191 INFO [stdout] test xor::tests::test_repeating_key_long ... ok
Dec 13 23:35:03.191 INFO [stdout] test xor::tests::test_repeating_key_short ... ok
Dec 13 23:35:03.191 INFO [stdout] test xor::tests::test_xor_1 ... ok
Dec 13 23:35:03.193 INFO [stdout] test utils::tests::test_decode_hex_1 ... ok
Dec 13 23:35:03.228 INFO [stdout] test aes::tests::test_encrypt_decrypt_cbc ... ok
Dec 13 23:35:03.228 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb_multiple_blocks_pad ... ok
Dec 13 23:35:03.229 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb_multiple_blocks_no_pad ... ok
Dec 13 23:35:03.229 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb ... ok
Dec 13 23:35:03.229 INFO [stdout] 
Dec 13 23:35:03.229 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.229 INFO [stdout] 
Dec 13 23:35:03.239 INFO [stderr]      Running /target/debug/deps/aes_cbc-c671044294945e44
Dec 13 23:35:03.243 INFO [stdout] 
Dec 13 23:35:03.243 INFO [stdout] running 0 tests
Dec 13 23:35:03.243 INFO [stdout] 
Dec 13 23:35:03.243 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.243 INFO [stdout] 
Dec 13 23:35:03.247 INFO [stderr]      Running /target/debug/deps/aes_ecb-f416a8fbc376b263
Dec 13 23:35:03.251 INFO [stdout] 
Dec 13 23:35:03.251 INFO [stdout] running 0 tests
Dec 13 23:35:03.251 INFO [stdout] 
Dec 13 23:35:03.251 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.251 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stderr]      Running /target/debug/deps/break_repeating_key_xor-9718bf3e20d4c2e8
Dec 13 23:35:03.255 INFO [stderr]      Running /target/debug/deps/ecb_byte_at_a_time-e5c7c2cd4c2cdcbe
Dec 13 23:35:03.255 INFO [stderr]      Running /target/debug/deps/ecb_cut_n_paste-aa89a4542e03a8e0
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stdout] running 0 tests
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stdout] running 0 tests
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.255 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.255 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] running 0 tests
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] running 0 tests
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.267 INFO [stdout] 
Dec 13 23:35:03.267 INFO [stderr]      Running /target/debug/deps/encrypt_xor-5b8e958e5ff80a4c
Dec 13 23:35:03.267 INFO [stderr]      Running /target/debug/deps/find_aes_ecb-37b701edbc0f96cf
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] running 0 tests
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] running 0 tests
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] running 0 tests
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.271 INFO [stdout] 
Dec 13 23:35:03.271 INFO [stderr]      Running /target/debug/deps/find_single_single_char_xor_string-936466f2c28215c9
Dec 13 23:35:03.271 INFO [stderr]      Running /target/debug/deps/gen_key-5e514aafb463de8a
Dec 13 23:35:03.291 INFO [stdout] 
Dec 13 23:35:03.291 INFO [stdout] running 0 tests
Dec 13 23:35:03.291 INFO [stdout] 
Dec 13 23:35:03.291 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.291 INFO [stdout] 
Dec 13 23:35:03.291 INFO [stderr]      Running /target/debug/deps/hex-e245482f9e93d617
Dec 13 23:35:03.295 INFO [stderr]      Running /target/debug/deps/oracle_ecb-41fdb509f04baedc
Dec 13 23:35:03.295 INFO [stderr]      Running /target/debug/deps/xor-8cac0476b631a707
Dec 13 23:35:03.295 INFO [stderr]      Running /target/debug/deps/xor_search_single_char-986bb4d78fd56c3c
Dec 13 23:35:03.295 INFO [stderr]    Doc-tests challenge
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] running 0 tests
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] running 0 tests
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] running 0 tests
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:03.295 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:03.295 INFO [stdout] 
Dec 13 23:35:04.569 INFO [stdout] 
Dec 13 23:35:04.569 INFO [stdout] running 0 tests
Dec 13 23:35:04.569 INFO [stdout] 
Dec 13 23:35:04.569 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 23:35:04.569 INFO [stdout] 
Dec 13 23:35:04.577 INFO [stderr] su: No module specific data is present
Dec 13 23:35:05.518 INFO running `"docker" "inspect" "ad7c62d2de8cb423ac3d82c8497f92cf767e1234a6cb41ca417bd40661352c82"`
Dec 13 23:35:05.879 INFO running `"docker" "rm" "-f" "ad7c62d2de8cb423ac3d82c8497f92cf767e1234a6cb41ca417bd40661352c82"`
Dec 13 23:35:06.275 INFO [stdout] ad7c62d2de8cb423ac3d82c8497f92cf767e1234a6cb41ca417bd40661352c82