Nov 18 04:38:34.272 INFO testing orion-0.8.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 04:38:34.272 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18 04:38:34.473 INFO [stdout] b797f63f6169125ba9a3a3f2c3b6c6907efcde3a394c5b33f133023e85099a76
Nov 18 04:38:34.476 INFO running `"docker" "start" "-a" "b797f63f6169125ba9a3a3f2c3b6c6907efcde3a394c5b33f133023e85099a76"`
Nov 18 04:38:34.911 INFO [stderr] usermod: no changes
Nov 18 04:38:35.007 INFO [stderr]    Compiling memsec v0.5.4
Nov 18 04:38:35.007 INFO [stderr]    Compiling block-buffer v0.7.0
Nov 18 04:38:35.303 INFO [stderr]    Compiling sha2 v0.8.0
Nov 18 04:38:35.807 INFO [stderr]    Compiling seckey v0.9.1
Nov 18 04:38:36.437 INFO [stderr]    Compiling orion v0.8.0 (/source)
Nov 18 04:38:37.914 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.98s
Nov 18 04:38:37.917 INFO [stderr] su: No module specific data is present
Nov 18 04:38:38.284 INFO running `"docker" "inspect" "b797f63f6169125ba9a3a3f2c3b6c6907efcde3a394c5b33f133023e85099a76"`
Nov 18 04:38:38.352 INFO running `"docker" "rm" "-f" "b797f63f6169125ba9a3a3f2c3b6c6907efcde3a394c5b33f133023e85099a76"`
Nov 18 04:38:38.452 INFO [stdout] b797f63f6169125ba9a3a3f2c3b6c6907efcde3a394c5b33f133023e85099a76
Nov 18 04:38:38.455 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18 04:38:38.633 INFO [stdout] 852e51b693b4bd7fc48a853a1fc2516369228a1d1bf58f06a5d852e1802a6361
Nov 18 04:38:38.636 INFO running `"docker" "start" "-a" "852e51b693b4bd7fc48a853a1fc2516369228a1d1bf58f06a5d852e1802a6361"`
Nov 18 04:38:39.187 INFO [stderr] usermod: no changes
Nov 18 04:38:39.294 INFO [stderr]    Compiling orion v0.8.0 (/source)
Nov 18 04:38:42.506 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.29s
Nov 18 04:38:42.515 INFO [stderr] su: No module specific data is present
Nov 18 04:38:42.798 INFO running `"docker" "inspect" "852e51b693b4bd7fc48a853a1fc2516369228a1d1bf58f06a5d852e1802a6361"`
Nov 18 04:38:42.876 INFO running `"docker" "rm" "-f" "852e51b693b4bd7fc48a853a1fc2516369228a1d1bf58f06a5d852e1802a6361"`
Nov 18 04:38:42.964 INFO [stdout] 852e51b693b4bd7fc48a853a1fc2516369228a1d1bf58f06a5d852e1802a6361
Nov 18 04:38:42.971 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 18 04:38:43.175 INFO [stdout] ff33982a2cdb266e521df97c36290c51c769191597223a7a0a2bd80f700dc278
Nov 18 04:38:43.178 INFO running `"docker" "start" "-a" "ff33982a2cdb266e521df97c36290c51c769191597223a7a0a2bd80f700dc278"`
Nov 18 04:38:43.644 INFO [stderr] usermod: no changes
Nov 18 04:38:43.700 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 18 04:38:43.704 INFO [stderr]      Running /target/debug/deps/orion-86f92762691b7ac3
Nov 18 04:38:43.705 INFO [stdout] 
Nov 18 04:38:43.705 INFO [stdout] running 89 tests
Nov 18 04:38:43.705 INFO [stdout] test default::test::hkdf_verify_exptected_too_long ... ok
Nov 18 04:38:43.705 INFO [stdout] test default::test::cshake_empty_custom_err ... ok
Nov 18 04:38:43.705 INFO [stdout] test default::test::hkdf_verify_exptected_too_short ... ok
Nov 18 04:38:43.705 INFO [stdout] test default::test::hmac_secret_key_allowed_len ... ok
Nov 18 04:38:43.705 INFO [stdout] test default::test::hmac_secret_key_too_short ... ok
Nov 18 04:38:43.706 INFO [stdout] test default::test::cshake_ok ... ok
Nov 18 04:38:43.706 INFO [stdout] test default::test::hkdf_salt_too_short ... ok
Nov 18 04:38:43.706 INFO [stdout] test default::test::hmac_verify ... ok
Nov 18 04:38:43.706 INFO [stdout] test default::test::hkdf_verify ... ok
Nov 18 04:38:43.706 INFO [stdout] test default::test::pbkdf2_password_too_short ... ok
Nov 18 04:38:43.707 INFO [stdout] test default::test::hkdf_verify_err ... ok
Nov 18 04:38:43.708 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_2 ... ok
Nov 18 04:38:43.708 INFO [stdout] test hazardous::aead::test_encrypt_decrypt_key_nonce_sizes ... ok
Nov 18 04:38:43.708 INFO [stdout] test default::test::hkdf_salt_allowed_len ... ok
Nov 18 04:38:43.708 INFO [stdout] test hazardous::chacha20::chacha20_block_test_1 ... ok
Nov 18 04:38:43.709 INFO [stdout] test hazardous::chacha20::chacha20_block_test_2 ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::chacha20_block_test_3 ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::chacha20_block_test_4 ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::chacha20_block_test_5 ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::test_bad_key_nonce_size_init ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::test_bad_key_nonce_size_keystream_block ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::test_chacha20_block_results ... ok
Nov 18 04:38:43.710 INFO [stdout] test hazardous::chacha20::test_diff_ct_pt_len ... ok
Nov 18 04:38:43.714 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_3 ... ok
Nov 18 04:38:43.763 INFO [stdout] test hazardous::aead::rfc_8439_test_poly1305_key_gen_1 ... ok
Nov 18 04:38:43.771 INFO [stdout] test hazardous::chacha20::test_key_schedule ... ok
Nov 18 04:38:43.771 INFO [stdout] test hazardous::chacha20::test_pass_on_one_iter_max_initial_counter ... ok
Nov 18 04:38:43.781 INFO [stdout] test hazardous::chacha20::test_quarter_round_results ... ok
Nov 18 04:38:43.784 INFO [stdout] test hazardous::chacha20::test_quarter_round_results_on_indices ... ok
Nov 18 04:38:43.793 INFO [stdout] test hazardous::chacha20::test_err_on_empty_pt ... ok
Nov 18 04:38:43.795 INFO [stdout] test hazardous::aead::test_modified_tag_error ... ok
Nov 18 04:38:43.795 INFO [stdout] test hazardous::chacha20::test_err_on_initial_counter_overflow ... ok
Nov 18 04:38:43.795 INFO [stdout] test hazardous::cshake::test::double_finalize_with_reset_ok ... ok
Nov 18 04:38:43.800 INFO [stdout] test hazardous::cshake::test::double_finalize_err ... ok
Nov 18 04:38:43.800 INFO [stdout] test hazardous::cshake::test::empty_input_ok ... ok
Nov 18 04:38:43.800 INFO [stdout] test hazardous::cshake::test::err_on_above_max_length ... ok
Nov 18 04:38:43.803 INFO [stdout] test hazardous::cshake::test::err_on_custom_max_length ... ok
Nov 18 04:38:43.805 INFO [stdout] test hazardous::cshake::test::err_on_n_c_max_length ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::empty_custom_ok ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::err_on_empty_name_custom ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::non_8_div_len ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::result_ok ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::test_left_encode ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::update_after_finalize_err ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::verify_err ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::hkdf::test::hkdf_maximum_length_512 ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::cshake::test::err_on_zero_length ... ok
Nov 18 04:38:43.811 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_true ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_wrong_ikm ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::cshake::test::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hkdf::test::hkdf_verify_wrong_salt ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hkdf::test::verify_diff_length ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hkdf::test::hkdf_zero_length ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::double_finalize_with_reset_ok ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::finalize_and_verify_true ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::update_after_finalize_err ... ok
Nov 18 04:38:43.813 INFO [stdout] test hazardous::hmac::veriy_false_wrong_data ... ok
Nov 18 04:38:43.815 INFO [stdout] test hazardous::cshake::test::err_on_name_max_length ... ok
Nov 18 04:38:43.815 INFO [stdout] test hazardous::hmac::double_finalize_err ... ok
Nov 18 04:38:43.829 INFO [stdout] test hazardous::hmac::veriy_false_wrong_secret_key ... ok
Nov 18 04:38:43.907 INFO [stdout] test hazardous::pbkdf2::test::verify_diff_iter_error ... ok
Nov 18 04:38:44.270 INFO [stdout] test hazardous::pbkdf2::test::verify_false_wrong_password ... ok
Nov 18 04:38:44.271 INFO [stdout] test hazardous::pbkdf2::test::zero_dklen_err ... ok
Nov 18 04:38:44.275 INFO [stdout] test hazardous::pbkdf2::test::zero_iterations_err ... ok
Nov 18 04:38:44.292 INFO [stdout] test hazardous::poly1305::double_finalize_err ... ok
Nov 18 04:38:44.296 INFO [stdout] test hazardous::poly1305::double_finalize_with_reset_no_update_ok ... ok
Nov 18 04:38:44.299 INFO [stdout] test hazardous::poly1305::double_finalize_with_reset_ok ... ok
Nov 18 04:38:44.306 INFO [stdout] test hazardous::poly1305::test_init_wrong_key_len ... ok
Nov 18 04:38:44.314 INFO [stdout] test hazardous::pbkdf2::test::verify_diff_dklen_error ... ok
Nov 18 04:38:44.314 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_bad_key_err_greater ... ok
Nov 18 04:38:44.314 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_ok ... ok
Nov 18 04:38:44.314 INFO [stdout] test hazardous::poly1305::update_after_finalize_err ... ok
Nov 18 04:38:44.315 INFO [stdout] test hazardous::poly1305::update_after_finalize_with_reset_ok ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::rand_key_len_error ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::rand_key_len_ok ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::test_ct_eq_diff_len ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::test_ct_eq_ok ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::test_ct_ne ... ok
Nov 18 04:38:44.315 INFO [stdout] test utilities::util::test_ct_ne_reg ... ok
Nov 18 04:38:44.317 INFO [stdout] test hazardous::poly1305::test_poly1305_oneshot_bad_key_err_less ... ok
Nov 18 04:38:44.331 INFO [stdout] test hazardous::pbkdf2::test::verify_true ... ok
Nov 18 04:38:44.398 INFO [stdout] test hazardous::pbkdf2::test::verify_false_wrong_salt ... ok
Nov 18 04:39:32.551 INFO [stdout] test default::test::pbkdf2_verify_expected_dk_too_long ... ok
Nov 18 04:39:36.431 INFO [stdout] test default::test::pbkdf2_verify_expected_dk_too_short ... ok
Nov 18 04:39:43.723 INFO [stdout] test default::test::pbkdf2_verify_err ... test default::test::pbkdf2_verify_err has been running for over 60 seconds
Nov 18 04:39:43.723 INFO [stdout] test default::test::pbkdf2_verify ... test default::test::pbkdf2_verify has been running for over 60 seconds
Nov 18 04:40:14.183 INFO [stdout] test default::test::pbkdf2_verify_err ... ok
Nov 18 04:40:14.326 INFO [stdout] test default::test::pbkdf2_verify ... ok
Nov 18 04:40:14.326 INFO [stdout] 
Nov 18 04:40:14.326 INFO [stdout] test result: ok. 89 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:40:14.326 INFO [stdout] 
Nov 18 04:40:14.331 INFO [stderr]    Doc-tests orion
Nov 18 04:40:16.147 INFO [stdout] 
Nov 18 04:40:16.147 INFO [stdout] running 19 tests
Nov 18 04:40:22.975 INFO [stdout] test src/default.rs - default::hmac (line 46) ... ok
Nov 18 04:40:24.983 INFO [stdout] test src/hazardous/mod.rs - hazardous::aead (line 51) ... ok
Nov 18 04:40:25.870 INFO [stdout] test src/default.rs - default::hmac_verify (line 71) ... ok
Nov 18 04:40:26.829 INFO [stdout] test src/default.rs - default::hkdf (line 125) ... ok
Nov 18 04:40:27.315 INFO [stdout] test src/default.rs - default::cshake (line 281) ... ok
Nov 18 04:40:29.239 INFO [stdout] test src/default.rs - default::hkdf_verify (line 156) ... ok
Nov 18 04:40:33.971 INFO [stdout] test src/hazardous/mod.rs - hazardous::cshake (line 44) ... ok
Nov 18 04:40:34.491 INFO [stdout] test src/hazardous/mod.rs - hazardous::hkdf (line 62) ... ok
Nov 18 04:40:36.463 INFO [stdout] test src/hazardous/mod.rs - hazardous::hmac (line 56) ... ok
Nov 18 04:40:36.791 INFO [stdout] test src/hazardous/mod.rs - hazardous::chacha20 (line 73) ... ok
Nov 18 04:40:37.519 INFO [stdout] test src/hazardous/mod.rs - hazardous::hkdf (line 51) ... ok
Nov 18 04:40:38.103 INFO [stdout] test src/hazardous/mod.rs - hazardous::hmac (line 43) ... ok
Nov 18 04:40:46.047 INFO [stdout] test src/hazardous/mod.rs - hazardous::poly1305 (line 65) ... ok
Nov 18 04:40:46.376 INFO [stdout] test src/utilities/util.rs - utilities::util::gen_rand_key (line 45) ... ok
Nov 18 04:40:46.515 INFO [stdout] test src/hazardous/mod.rs - hazardous::pbkdf2 (line 53) ... ok
Nov 18 04:40:46.735 INFO [stdout] test src/utilities/util.rs - utilities::util::compare_ct (line 75) ... ok
Nov 18 04:40:49.175 INFO [stdout] test src/hazardous/mod.rs - hazardous::pbkdf2 (line 64) ... ok
Nov 18 04:41:16.174 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:16.175 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:25.619 INFO [stdout] test src/default.rs - default::pbkdf2 (line 201) ... ok
Nov 18 04:41:52.918 INFO [stdout] test src/default.rs - default::pbkdf2_verify (line 234) ... ok
Nov 18 04:41:52.918 INFO [stdout] 
Nov 18 04:41:52.919 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:41:52.919 INFO [stdout] 
Nov 18 04:41:52.943 INFO [stderr] su: No module specific data is present
Nov 18 04:41:53.290 INFO running `"docker" "inspect" "ff33982a2cdb266e521df97c36290c51c769191597223a7a0a2bd80f700dc278"`
Nov 18 04:41:53.647 INFO running `"docker" "rm" "-f" "ff33982a2cdb266e521df97c36290c51c769191597223a7a0a2bd80f700dc278"`
Nov 18 04:41:54.044 INFO [stdout] ff33982a2cdb266e521df97c36290c51c769191597223a7a0a2bd80f700dc278