Nov 17 06:30:12.326 INFO testing cjgu/rust_cryptopals against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 06:30:12.326 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 06:30:12.833 INFO [stdout] 61d55daecf7a72191a3aa32b40bded0b5a1e71de8654e6dae98504223b8cd67d
Nov 17 06:30:12.835 INFO running `"docker" "start" "-a" "61d55daecf7a72191a3aa32b40bded0b5a1e71de8654e6dae98504223b8cd67d"`
Nov 17 06:30:14.853 INFO [stderr] usermod: no changes
Nov 17 06:30:15.007 INFO [stderr]    Compiling openssl v0.9.23
Nov 17 06:30:22.043 INFO [stderr]    Compiling crypto_pals v0.1.0 (/source)
Nov 17 06:30:37.611 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.73s
Nov 17 06:30:37.633 INFO [stderr] su: No module specific data is present
Nov 17 06:30:38.971 INFO running `"docker" "inspect" "61d55daecf7a72191a3aa32b40bded0b5a1e71de8654e6dae98504223b8cd67d"`
Nov 17 06:30:39.335 INFO running `"docker" "rm" "-f" "61d55daecf7a72191a3aa32b40bded0b5a1e71de8654e6dae98504223b8cd67d"`
Nov 17 06:30:39.623 INFO [stdout] 61d55daecf7a72191a3aa32b40bded0b5a1e71de8654e6dae98504223b8cd67d
Nov 17 06:30:39.630 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 06:30:40.092 INFO [stdout] 097e20d0ebf90a28504f42bf1a138e6e457f915c9a3bd7c61594e70faf94b2a6
Nov 17 06:30:40.094 INFO running `"docker" "start" "-a" "097e20d0ebf90a28504f42bf1a138e6e457f915c9a3bd7c61594e70faf94b2a6"`
Nov 17 06:30:41.597 INFO [stderr] usermod: no changes
Nov 17 06:30:41.735 INFO [stderr]    Compiling crypto_pals v0.1.0 (/source)
Nov 17 06:30:53.091 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.46s
Nov 17 06:30:53.099 INFO [stderr] su: No module specific data is present
Nov 17 06:30:54.616 INFO running `"docker" "inspect" "097e20d0ebf90a28504f42bf1a138e6e457f915c9a3bd7c61594e70faf94b2a6"`
Nov 17 06:30:54.993 INFO running `"docker" "rm" "-f" "097e20d0ebf90a28504f42bf1a138e6e457f915c9a3bd7c61594e70faf94b2a6"`
Nov 17 06:30:55.239 INFO [stdout] 097e20d0ebf90a28504f42bf1a138e6e457f915c9a3bd7c61594e70faf94b2a6
Nov 17 06:30:55.241 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 06:30:55.671 INFO [stdout] 2a740a48ce31d70c54679e4f0beeb81d90c4070aeddc62b890ed2e4969305a76
Nov 17 06:30:55.675 INFO running `"docker" "start" "-a" "2a740a48ce31d70c54679e4f0beeb81d90c4070aeddc62b890ed2e4969305a76"`
Nov 17 06:30:56.788 INFO [stderr] usermod: no changes
Nov 17 06:30:56.874 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Nov 17 06:30:56.888 INFO [stderr]      Running /target/debug/deps/challenge-45259502bbf332f1
Nov 17 06:30:56.895 INFO [stdout] 
Nov 17 06:30:56.896 INFO [stdout] running 26 tests
Nov 17 06:30:56.896 INFO [stdout] test utils::tests::test_decode_b64_2 ... ok
Nov 17 06:30:56.896 INFO [stdout] test utils::tests::test_decode_b64_1 ... ok
Nov 17 06:30:56.897 INFO [stdout] test utils::tests::test_b64_char_to_u8 ... ok
Nov 17 06:30:56.897 INFO [stdout] test utils::tests::test_decode_hex_1 ... ok
Nov 17 06:30:56.897 INFO [stdout] test utils::tests::test_decode_hex_2 ... ok
Nov 17 06:30:56.898 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb_multiple_blocks_no_pad ... ok
Nov 17 06:30:56.898 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb ... ok
Nov 17 06:30:56.898 INFO [stdout] test aes::tests::test_encrypt_decrypt_ecb_multiple_blocks_pad ... ok
Nov 17 06:30:56.899 INFO [stdout] test utils::tests::test_decode_hex_encode_b64 ... ok
Nov 17 06:30:56.899 INFO [stdout] test utils::tests::test_decode_hex_encode_hex ... ok
Nov 17 06:30:56.899 INFO [stdout] test utils::tests::test_encode_b64_1 ... ok
Nov 17 06:30:56.900 INFO [stdout] test utils::tests::test_decode_hex_4 ... ok
Nov 17 06:30:56.900 INFO [stdout] test utils::tests::test_decode_hex_3 ... ok
Nov 17 06:30:56.900 INFO [stdout] test utils::tests::test_encode_b64_3 ... ok
Nov 17 06:30:56.901 INFO [stdout] test utils::tests::test_encode_hex_1 ... ok
Nov 17 06:30:56.901 INFO [stdout] test utils::tests::test_encode_b64_2 ... ok
Nov 17 06:30:56.901 INFO [stdout] test utils::tests::test_pad_length ... ok
Nov 17 06:30:56.901 INFO [stdout] test utils::tests::test_pkcs_7_pad_16 ... ok
Nov 17 06:30:56.902 INFO [stdout] test utils::tests::test_pkcs_7_pad_20 ... ok
Nov 17 06:30:56.902 INFO [stdout] test xor::tests::test_hamming_distance ... ok
Nov 17 06:30:56.902 INFO [stdout] test xor::tests::test_repeating_key_long ... ok
Nov 17 06:30:56.903 INFO [stdout] test xor::tests::test_repeating_key_short ... ok
Nov 17 06:30:56.903 INFO [stdout] test xor::tests::test_xor_1 ... ok
Nov 17 06:30:56.903 INFO [stdout] test utils::tests::test_decode_b64_3 ... ok
Nov 17 06:30:56.904 INFO [stdout] test utils::tests::test_decode_b64_4 ... ok
Nov 17 06:30:56.904 INFO [stdout] test aes::tests::test_encrypt_decrypt_cbc ... ok
Nov 17 06:30:56.904 INFO [stdout] 
Nov 17 06:30:56.905 INFO [stdout] test result: ok. 26 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.905 INFO [stdout] 
Nov 17 06:30:56.907 INFO [stderr]      Running /target/debug/deps/aes_cbc-18814917b8432fdb
Nov 17 06:30:56.916 INFO [stdout] 
Nov 17 06:30:56.916 INFO [stdout] running 0 tests
Nov 17 06:30:56.917 INFO [stdout] 
Nov 17 06:30:56.918 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.919 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stderr]      Running /target/debug/deps/aes_ecb-b37a7479e9082fb5
Nov 17 06:30:56.923 INFO [stderr]      Running /target/debug/deps/break_repeating_key_xor-a7ab69e55367eaa0
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stderr]      Running /target/debug/deps/ecb_byte_at_a_time-71a76b3d604358de
Nov 17 06:30:56.923 INFO [stdout] running 0 tests
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stdout] running 0 tests
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.923 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.923 INFO [stdout] 
Nov 17 06:30:56.934 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stderr]      Running /target/debug/deps/ecb_cut_n_paste-f8c5d505aa82fa22
Nov 17 06:30:56.935 INFO [stdout] running 0 tests
Nov 17 06:30:56.935 INFO [stderr]      Running /target/debug/deps/encrypt_xor-9c287cb7bccddc50
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stderr]      Running /target/debug/deps/find_aes_ecb-30d46ae1dd1ae3fa
Nov 17 06:30:56.935 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] running 0 tests
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] running 0 tests
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.935 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.935 INFO [stdout] 
Nov 17 06:30:56.956 INFO [stdout] 
Nov 17 06:30:56.956 INFO [stderr]      Running /target/debug/deps/find_single_single_char_xor_string-850aa848cbf56354
Nov 17 06:30:56.956 INFO [stdout] running 0 tests
Nov 17 06:30:56.956 INFO [stdout] 
Nov 17 06:30:56.956 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.956 INFO [stdout] 
Nov 17 06:30:56.969 INFO [stdout] 
Nov 17 06:30:56.973 INFO [stdout] running 0 tests
Nov 17 06:30:56.973 INFO [stdout] 
Nov 17 06:30:56.973 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.973 INFO [stdout] 
Nov 17 06:30:56.973 INFO [stderr]      Running /target/debug/deps/gen_key-40eb237d58102ccd
Nov 17 06:30:56.976 INFO [stdout] 
Nov 17 06:30:56.976 INFO [stdout] running 0 tests
Nov 17 06:30:56.976 INFO [stdout] 
Nov 17 06:30:56.976 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.976 INFO [stdout] 
Nov 17 06:30:56.979 INFO [stderr]      Running /target/debug/deps/hex-1ba8f8411b852788
Nov 17 06:30:56.983 INFO [stdout] 
Nov 17 06:30:56.983 INFO [stdout] running 0 tests
Nov 17 06:30:56.983 INFO [stdout] 
Nov 17 06:30:56.983 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.983 INFO [stdout] 
Nov 17 06:30:56.983 INFO [stderr]      Running /target/debug/deps/oracle_ecb-0c9a05e396c736e4
Nov 17 06:30:56.987 INFO [stdout] 
Nov 17 06:30:56.987 INFO [stdout] running 0 tests
Nov 17 06:30:56.987 INFO [stdout] 
Nov 17 06:30:56.987 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.987 INFO [stdout] 
Nov 17 06:30:56.987 INFO [stderr]      Running /target/debug/deps/xor-81e7278924c2b9cc
Nov 17 06:30:56.989 INFO [stdout] 
Nov 17 06:30:56.989 INFO [stdout] running 0 tests
Nov 17 06:30:56.989 INFO [stdout] 
Nov 17 06:30:56.989 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.989 INFO [stdout] 
Nov 17 06:30:56.990 INFO [stderr]      Running /target/debug/deps/xor_search_single_char-9c49720fe1e1166d
Nov 17 06:30:56.996 INFO [stdout] 
Nov 17 06:30:56.996 INFO [stdout] running 0 tests
Nov 17 06:30:56.996 INFO [stdout] 
Nov 17 06:30:56.996 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:56.996 INFO [stdout] 
Nov 17 06:30:56.997 INFO [stderr]    Doc-tests challenge
Nov 17 06:30:57.897 INFO [stdout] 
Nov 17 06:30:57.897 INFO [stdout] running 0 tests
Nov 17 06:30:57.897 INFO [stdout] 
Nov 17 06:30:57.897 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 06:30:57.897 INFO [stdout] 
Nov 17 06:30:57.905 INFO [stderr] su: No module specific data is present
Nov 17 06:30:58.364 INFO running `"docker" "inspect" "2a740a48ce31d70c54679e4f0beeb81d90c4070aeddc62b890ed2e4969305a76"`
Nov 17 06:30:58.517 INFO running `"docker" "rm" "-f" "2a740a48ce31d70c54679e4f0beeb81d90c4070aeddc62b890ed2e4969305a76"`
Nov 17 06:30:58.704 INFO [stdout] 2a740a48ce31d70c54679e4f0beeb81d90c4070aeddc62b890ed2e4969305a76