Nov 18 04:38:34.592 INFO testing orion-0.8.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 04:38:34.592 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 04:38:34.807 INFO [stdout] ebef4f3b12cb442365df6a66dc8aed467d271428d7d79be523ac929ba960e33a
Nov 18 04:38:34.807 INFO running `"docker" "start" "-a" "ebef4f3b12cb442365df6a66dc8aed467d271428d7d79be523ac929ba960e33a"`
Nov 18 04:38:35.271 INFO [stderr] usermod: no changes
Nov 18 04:38:35.385 INFO [stderr]    Compiling memsec v0.5.4
Nov 18 04:38:35.390 INFO [stderr]    Compiling digest v0.8.0
Nov 18 04:38:35.635 INFO [stderr]    Compiling sha2 v0.8.0
Nov 18 04:38:36.363 INFO [stderr]    Compiling seckey v0.9.1
Nov 18 04:38:36.707 INFO [stderr]    Compiling orion v0.8.0 (/source)
Nov 18 04:38:38.285 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.99s
Nov 18 04:38:38.291 INFO [stderr] su: No module specific data is present
Nov 18 04:38:38.524 INFO running `"docker" "inspect" "ebef4f3b12cb442365df6a66dc8aed467d271428d7d79be523ac929ba960e33a"`
Nov 18 04:38:38.617 INFO running `"docker" "rm" "-f" "ebef4f3b12cb442365df6a66dc8aed467d271428d7d79be523ac929ba960e33a"`
Nov 18 04:38:38.717 INFO [stdout] ebef4f3b12cb442365df6a66dc8aed467d271428d7d79be523ac929ba960e33a
Nov 18 04:38:38.718 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 04:38:39.029 INFO [stdout] f1d665b4ed3b003602605e7d818c81fb097f1e072eba9b033b1d77b6c72b8e3a
Nov 18 04:38:39.032 INFO running `"docker" "start" "-a" "f1d665b4ed3b003602605e7d818c81fb097f1e072eba9b033b1d77b6c72b8e3a"`
Nov 18 04:38:39.607 INFO [stderr] usermod: no changes
Nov 18 04:38:39.715 INFO [stderr]    Compiling orion v0.8.0 (/source)
Nov 18 04:38:43.076 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.43s
Nov 18 04:38:43.080 INFO [stderr] su: No module specific data is present
Nov 18 04:38:43.370 INFO running `"docker" "inspect" "f1d665b4ed3b003602605e7d818c81fb097f1e072eba9b033b1d77b6c72b8e3a"`
Nov 18 04:38:43.462 INFO running `"docker" "rm" "-f" "f1d665b4ed3b003602605e7d818c81fb097f1e072eba9b033b1d77b6c72b8e3a"`
Nov 18 04:38:43.540 INFO [stdout] f1d665b4ed3b003602605e7d818c81fb097f1e072eba9b033b1d77b6c72b8e3a
Nov 18 04:38:43.544 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 04:38:43.685 INFO [stdout] 6a9cd0d9a85e1c7b23100699974a509affee4d84bf946e0983dd55daf2645415
Nov 18 04:38:43.687 INFO running `"docker" "start" "-a" "6a9cd0d9a85e1c7b23100699974a509affee4d84bf946e0983dd55daf2645415"`
Nov 18 04:38:44.406 INFO [stderr] usermod: no changes
Nov 18 04:38:44.486 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.05s
Nov 18 04:38:44.499 INFO [stderr]      Running /target/debug/deps/orion-5b08246b623d3e34
Nov 18 04:38:44.501 INFO [stdout] 
Nov 18 04:38:44.501 INFO [stdout] running 89 tests
Nov 18 04:38:44.501 INFO [stdout] test default::test::hkdf_verify_err ... ok
Nov 18 04:38:44.501 INFO [stdout] test default::test::hkdf_verify_exptected_too_long ... ok
Nov 18 04:38:44.501 INFO [stdout] test default::test::hkdf_verify_exptected_too_short ... ok
Nov 18 04:38:44.503 INFO [stdout] test default::test::hkdf_verify ... ok
Nov 18 04:38:44.504 INFO [stdout] test default::test::hkdf_salt_too_short ... ok
Nov 18 04:38:44.504 INFO [stdout] test default::test::hkdf_salt_allowed_len ... ok
Nov 18 04:38:44.504 INFO [stdout] test default::test::cshake_ok ... ok
Nov 18 04:38:44.507 INFO [stdout] test default::test::hmac_secret_key_allowed_len ... ok
Nov 18 04:38:44.507 INFO [stdout] test default::test::hmac_secret_key_too_short ... ok
Nov 18 04:38:44.507 INFO [stdout] test default::test::hmac_verify ... ok
Nov 18 04:38:44.507 INFO [stdout] test default::test::cshake_empty_custom_err ... ok
Nov 18 04:38:44.507 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_1 ... ok
Nov 18 04:38:44.507 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_2 ... ok
Nov 18 04:38:44.507 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_3 ... ok
Nov 18 04:38:44.507 INFO [stdout] test hazardous::aead::test_encrypt_decrypt_key_nonce_sizes ... ok
Nov 18 04:38:44.507 INFO [stdout] test default::test::pbkdf2_password_too_short ... ok
Nov 18 04:38:44.514 INFO [stdout] test hazardous::chacha20::chacha20_block_test_2 ... ok
Nov 18 04:38:44.515 INFO [stdout] test hazardous::chacha20::chacha20_block_test_3 ... ok
Nov 18 04:38:44.515 INFO [stdout] test hazardous::chacha20::chacha20_block_test_1 ... ok
Nov 18 04:38:44.515 INFO [stdout] test hazardous::chacha20::test_bad_key_nonce_size_init ... ok
Nov 18 04:38:44.516 INFO [stdout] test hazardous::chacha20::chacha20_block_test_5 ... ok
Nov 18 04:38:44.516 INFO [stdout] test hazardous::chacha20::chacha20_block_test_4 ... ok
Nov 18 04:38:44.551 INFO [stdout] test hazardous::chacha20::test_bad_key_nonce_size_keystream_block ... ok
Nov 18 04:38:44.551 INFO [stdout] test hazardous::chacha20::test_chacha20_block_results ... ok
Nov 18 04:38:44.551 INFO [stdout] test hazardous::chacha20::test_diff_ct_pt_len ... ok
Nov 18 04:38:44.571 INFO [stdout] test hazardous::chacha20::test_key_schedule ... ok
Nov 18 04:38:44.571 INFO [stdout] test hazardous::chacha20::test_pass_on_one_iter_max_initial_counter ... ok
Nov 18 04:38:44.587 INFO [stdout] test hazardous::chacha20::test_quarter_round_results ... ok
Nov 18 04:38:44.587 INFO [stdout] test hazardous::chacha20::test_quarter_round_results_on_indices ... ok
Nov 18 04:38:44.691 INFO [stdout] test hazardous::chacha20::test_err_on_empty_pt ... ok
Nov 18 04:38:44.691 INFO [stdout] test hazardous::chacha20::test_err_on_initial_counter_overflow ... ok
Nov 18 04:38:44.691 INFO [stdout] test hazardous::cshake::test::double_finalize_err ... ok
Nov 18 04:38:44.706 INFO [stdout] test hazardous::cshake::test::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::aead::test_modified_tag_error ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::empty_input_ok ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::err_on_above_max_length ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::empty_custom_ok ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::err_on_custom_max_length ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::err_on_empty_name_custom ... ok
Nov 18 04:38:44.707 INFO [stdout] test hazardous::cshake::test::double_finalize_with_reset_ok ... ok
Nov 18 04:38:44.711 INFO [stdout] test hazardous::cshake::test::non_8_div_len ... ok
Nov 18 04:38:44.711 INFO [stdout] test hazardous::cshake::test::err_on_n_c_max_length ... ok
Nov 18 04:38:44.723 INFO [stdout] test hazardous::cshake::test::err_on_zero_length ... ok
Nov 18 04:38:44.723 INFO [stdout] test hazardous::cshake::test::result_ok ... ok
Nov 18 04:38:44.723 INFO [stdout] test hazardous::cshake::test::err_on_name_max_length ... ok
Nov 18 04:38:44.727 INFO [stdout] test hazardous::cshake::test::test_left_encode ... ok
Nov 18 04:38:44.727 INFO [stdout] test hazardous::hkdf::test::hkdf_maximum_length_512 ... ok
Nov 18 04:38:44.731 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_true ... ok
Nov 18 04:38:44.731 INFO [stdout] test hazardous::cshake::test::update_after_finalize_err ... ok
Nov 18 04:38:44.731 INFO [stdout] test hazardous::cshake::test::verify_err ... ok
Nov 18 04:38:44.735 INFO [stdout] test hazardous::cshake::test::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hkdf::test::verify_diff_length ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::double_finalize_err ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::double_finalize_with_reset_ok ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::finalize_and_verify_true ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::update_after_finalize_err ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:44.739 INFO [stdout] test hazardous::hmac::veriy_false_wrong_data ... ok
Nov 18 04:38:44.745 INFO [stdout] test hazardous::hmac::veriy_false_wrong_secret_key ... ok
Nov 18 04:38:44.745 INFO [stdout] test hazardous::hkdf::test::hkdf_zero_length ... ok
Nov 18 04:38:44.745 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_wrong_salt ... ok
Nov 18 04:38:44.745 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_wrong_ikm ... ok
Nov 18 04:38:45.051 INFO [stdout] test hazardous::pbkdf2::test::verify_diff_iter_error ... ok
Nov 18 04:38:45.511 INFO [stdout] test hazardous::pbkdf2::test::verify_diff_dklen_error ... ok
Nov 18 04:38:45.524 INFO [stdout] test hazardous::pbkdf2::test::zero_dklen_err ... ok
Nov 18 04:38:45.555 INFO [stdout] test hazardous::pbkdf2::test::zero_iterations_err ... ok
Nov 18 04:38:45.559 INFO [stdout] test hazardous::poly1305::double_finalize_err ... ok
Nov 18 04:38:45.560 INFO [stdout] test hazardous::poly1305::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:45.560 INFO [stdout] test hazardous::poly1305::double_finalize_with_reset_ok ... ok
Nov 18 04:38:45.560 INFO [stdout] test hazardous::poly1305::test_init_wrong_key_len ... ok
Nov 18 04:38:45.561 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_bad_key_err_greater ... ok
Nov 18 04:38:45.561 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_bad_key_err_less ... ok
Nov 18 04:38:45.561 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_ok ... ok
Nov 18 04:38:45.562 INFO [stdout] test hazardous::poly1305::update_after_finalize_err ... ok
Nov 18 04:38:45.562 INFO [stdout] test hazardous::poly1305::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::rand_key_len_error ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::rand_key_len_ok ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::test_ct_eq_diff_len ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::test_ct_eq_ok ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::test_ct_ne ... ok
Nov 18 04:38:45.562 INFO [stdout] test utilities::util::test_ct_ne_reg ... ok
Nov 18 04:38:45.728 INFO [stdout] test hazardous::pbkdf2::test::verify_true ... ok
Nov 18 04:38:45.935 INFO [stdout] test hazardous::pbkdf2::test::verify_false_wrong_password ... ok
Nov 18 04:38:46.000 INFO [stdout] test hazardous::pbkdf2::test::verify_false_wrong_salt ... ok
Nov 18 04:39:35.855 INFO [stdout] test default::test::pbkdf2_verify_expected_dk_too_short ... ok
Nov 18 04:39:36.923 INFO [stdout] test default::test::pbkdf2_verify_expected_dk_too_long ... ok
Nov 18 04:39:44.503 INFO [stdout] test default::test::pbkdf2_verify ... test default::test::pbkdf2_verify has been running for over 60 seconds
Nov 18 04:39:44.507 INFO [stdout] test default::test::pbkdf2_verify_err ... test default::test::pbkdf2_verify_err has been running for over 60 seconds
Nov 18 04:40:16.438 INFO [stdout] test default::test::pbkdf2_verify_err ... ok
Nov 18 04:40:17.107 INFO [stdout] test default::test::pbkdf2_verify ... ok
Nov 18 04:40:17.107 INFO [stdout] 
Nov 18 04:40:17.108 INFO [stdout] test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:40:17.108 INFO [stdout] 
Nov 18 04:40:17.119 INFO [stderr]    Doc-tests orion
Nov 18 04:40:19.343 INFO [stdout] 
Nov 18 04:40:19.343 INFO [stdout] running 19 tests
Nov 18 04:40:29.335 INFO [stdout] test src/default.rs - default::hkdf_verify (line 156) ... ok
Nov 18 04:40:29.764 INFO [stdout] test src/default.rs - default::cshake (line 281) ... ok
Nov 18 04:40:30.654 INFO [stdout] test src/hazardous/mod.rs - hazardous::aead (line 51) ... ok
Nov 18 04:40:30.883 INFO [stdout] test src/default.rs - default::hmac (line 46) ... ok
Nov 18 04:40:32.552 INFO [stdout] test src/default.rs - default::hkdf (line 125) ... ok
Nov 18 04:40:32.560 INFO [stdout] test src/default.rs - default::hmac_verify (line 71) ... ok
Nov 18 04:40:40.219 INFO [stdout] test src/hazardous/mod.rs - hazardous::cshake (line 44) ... ok
Nov 18 04:40:40.777 INFO [stdout] test src/hazardous/mod.rs - hazardous::chacha20 (line 73) ... ok
Nov 18 04:40:41.055 INFO [stdout] test src/hazardous/mod.rs - hazardous::hkdf (line 62) ... ok
Nov 18 04:40:42.135 INFO [stdout] test src/hazardous/mod.rs - hazardous::hmac (line 43) ... ok
Nov 18 04:40:42.967 INFO [stdout] test src/hazardous/mod.rs - hazardous::hkdf (line 51) ... ok
Nov 18 04:40:44.824 INFO [stdout] test src/hazardous/mod.rs - hazardous::hmac (line 56) ... ok
Nov 18 04:40:50.500 INFO [stdout] test src/hazardous/mod.rs - hazardous::pbkdf2 (line 53) ... ok
Nov 18 04:40:53.499 INFO [stdout] test src/hazardous/mod.rs - hazardous::poly1305 (line 65) ... ok
Nov 18 04:40:54.579 INFO [stdout] test src/utilities/util.rs - utilities::util::gen_rand_key (line 45) ... ok
Nov 18 04:40:54.876 INFO [stdout] test src/utilities/util.rs - utilities::util::compare_ct (line 75) ... ok
Nov 18 04:40:56.959 INFO [stdout] test src/hazardous/mod.rs - hazardous::pbkdf2 (line 64) ... ok
Nov 18 04:41:19.399 INFO [stdout] test src/default.rs - default::pbkdf2_verify (line 234) ... test src/default.rs - default::pbkdf2_verify (line 234) has been running for over 60 seconds
Nov 18 04:41:19.399 INFO [stdout] test src/default.rs - default::pbkdf2 (line 201) ... test src/default.rs - default::pbkdf2 (line 201) has been running for over 60 seconds
Nov 18 04:41:33.671 INFO [stdout] test src/default.rs - default::pbkdf2 (line 201) ... ok
Nov 18 04:41:59.299 INFO [stdout] test src/default.rs - default::pbkdf2_verify (line 234) ... ok
Nov 18 04:41:59.299 INFO [stdout] 
Nov 18 04:41:59.300 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:41:59.300 INFO [stdout] 
Nov 18 04:41:59.312 INFO [stderr] su: No module specific data is present
Nov 18 04:41:59.767 INFO running `"docker" "inspect" "6a9cd0d9a85e1c7b23100699974a509affee4d84bf946e0983dd55daf2645415"`
Nov 18 04:42:00.059 INFO running `"docker" "rm" "-f" "6a9cd0d9a85e1c7b23100699974a509affee4d84bf946e0983dd55daf2645415"`
Nov 18 04:42:00.272 INFO [stdout] 6a9cd0d9a85e1c7b23100699974a509affee4d84bf946e0983dd55daf2645415