[INFO] fetching crate cryptoki 0.10.1...
[INFO] testing cryptoki-0.10.1 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate cryptoki 0.10.1 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate cryptoki 0.10.1
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate cryptoki 0.10.1
[INFO] tweaked toml for crates.io crate cryptoki 0.10.1 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate cryptoki 0.10.1 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate cryptoki 0.10.1 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57
[INFO] running `Command { std: "docker" "start" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling cryptoki-sys v0.4.0
[INFO] [stderr]    Compiling zeroize v1.7.0
[INFO] [stderr]    Compiling log v0.4.20
[INFO] [stderr]    Compiling libloading v0.8.6
[INFO] [stderr]    Compiling secrecy v0.8.0
[INFO] [stderr]    Compiling cryptoki v0.10.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:227:25
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                         ^^^^^ the lifetime is elided here        ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                                                                      ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:248:9
[INFO] [stdout]     |
[INFO] [stdout] 248 |         &self,
[INFO] [stdout]     |         ^^^^^ the lifetime is elided here
[INFO] [stdout] ...
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                 ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                     ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 3.63s
[INFO] running `Command { std: "docker" "inspect" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.153
[INFO] [stderr]    Compiling lock_api v0.4.11
[INFO] [stderr]    Compiling parking_lot_core v0.8.6
[INFO] [stderr]    Compiling smallvec v1.13.1
[INFO] [stderr]    Compiling num-traits v0.2.18
[INFO] [stderr]    Compiling instant v0.1.12
[INFO] [stderr]    Compiling testresult v0.4.1
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:227:25
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                         ^^^^^ the lifetime is elided here        ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                                                                      ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:248:9
[INFO] [stdout]     |
[INFO] [stdout] 248 |         &self,
[INFO] [stdout]     |         ^^^^^ the lifetime is elided here
[INFO] [stdout] ...
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                 ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                     ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling parking_lot v0.11.2
[INFO] [stderr]    Compiling serial_test_derive v0.5.1
[INFO] [stderr]    Compiling serial_test v0.5.1
[INFO] [stderr]    Compiling cryptoki v0.10.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]     --> tests/basic.rs:1200:9
[INFO] [stdout]      |
[INFO] [stdout] 1200 |     let mut priv_attribs = [
[INFO] [stdout]      |         ----^^^^^^^^^^^^
[INFO] [stdout]      |         |
[INFO] [stdout]      |         help: remove this `mut`
[INFO] [stdout]      |
[INFO] [stdout]      = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:227:25
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                         ^^^^^ the lifetime is elided here        ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                                                                      ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stdout]    --> src/session/object_management.rs:248:9
[INFO] [stdout]     |
[INFO] [stdout] 248 |         &self,
[INFO] [stdout]     |         ^^^^^ the lifetime is elided here
[INFO] [stdout] ...
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator> {
[INFO] [stdout]     |                 ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stdout]     |
[INFO] [stdout]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stdout] help: use `'_` for type paths
[INFO] [stdout]     |
[INFO] [stdout] 251 |     ) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stdout]     |                                     ++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 11.22s
[INFO] running `Command { std: "docker" "inspect" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stderr]    --> src/session/object_management.rs:227:25
[INFO] [stderr]     |
[INFO] [stderr] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator> {
[INFO] [stderr]     |                         ^^^^^ the lifetime is elided here        ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stderr]     |
[INFO] [stderr]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stderr]     = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default
[INFO] [stderr] help: use `'_` for type paths
[INFO] [stderr]     |
[INFO] [stderr] 227 |     pub fn iter_objects(&self, template: &[Attribute]) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stderr]     |                                                                                      ++++
[INFO] [stderr] 
[INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing
[INFO] [stderr]    --> src/session/object_management.rs:248:9
[INFO] [stderr]     |
[INFO] [stderr] 248 |         &self,
[INFO] [stderr]     |         ^^^^^ the lifetime is elided here
[INFO] [stderr] ...
[INFO] [stderr] 251 |     ) -> Result<ObjectHandleIterator> {
[INFO] [stderr]     |                 ^^^^^^^^^^^^^^^^^^^^ the same lifetime is hidden here
[INFO] [stderr]     |
[INFO] [stderr]     = help: the same lifetime is referred to in inconsistent ways, making the signature confusing
[INFO] [stderr] help: use `'_` for type paths
[INFO] [stderr]     |
[INFO] [stderr] 251 |     ) -> Result<ObjectHandleIterator<'_>> {
[INFO] [stderr]     |                                     ++++
[INFO] [stderr] 
[INFO] [stderr] warning: `cryptoki` (lib) generated 2 warnings (run `cargo fix --lib -p cryptoki` to apply 2 suggestions)
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]     --> tests/basic.rs:1200:9
[INFO] [stderr]      |
[INFO] [stderr] 1200 |     let mut priv_attribs = [
[INFO] [stderr]      |         ----^^^^^^^^^^^^
[INFO] [stderr]      |         |
[INFO] [stderr]      |         help: remove this `mut`
[INFO] [stderr]      |
[INFO] [stderr]      = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `cryptoki` (test "basic") generated 1 warning (run `cargo fix --test "basic" -p cryptoki` to apply 1 suggestion)
[INFO] [stderr] warning: `cryptoki` (lib test) generated 2 warnings (2 duplicates)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/cryptoki/d62df301529bbb68/out/cryptoki-d62df301529bbb68)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test session::session_info::test::debug_flags_all ... ok
[INFO] [stdout] test session::session_info::test::debug_info ... ok
[INFO] [stdout] test slot::token_info::test::debug_info ... ok
[INFO] [stdout] test types::test::utc_time_convert_bad ... ok
[INFO] [stdout] test slot::slot_info::test::debug_flags_all ... ok
[INFO] [stdout] test mechanism::mechanism_info::test::debug_info ... ok
[INFO] [stdout] test types::test::utc_time_display_fmt ... ok
[INFO] [stdout] test types::test::utc_time_convert_good ... ok
[INFO] [stderr]      Running tests/allowed_mechanisms.rs (/opt/rustwide/target/release/build/cryptoki/a23408b7dc8a8533/out/allowed_mechanisms-a23408b7dc8a8533)
[INFO] [stdout] test slot::slot_info::test::debug_info ... ok
[INFO] [stdout] test mechanism::mechanism_info::test::debug_flags_all ... ok
[INFO] [stdout] test slot::token_info::test::debug_flags_all ... ok
[INFO] [stdout] test types::test::utc_time_debug_fmt ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test unaligned_allowed_mechanisms_value_is_decoded_as_bytes ... ok
[INFO] [stdout] test null_nonempty_allowed_mechanisms_value_is_rejected ... ok
[INFO] [stdout] test malformed_allowed_mechanisms_byte_length_is_rejected ... ok
[INFO] [stdout] test nonempty_allowed_mechanisms_round_trip_preserves_element_count ... ok
[INFO] [stdout] test allowed_mechanisms_raw_length_is_measured_in_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/basic.rs (/opt/rustwide/target/release/build/cryptoki/e2f4c445127b34ee/out/basic-e2f4c445127b34ee)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test encrypt_decrypt_gcm_message_with_aad ... FAILED
[INFO] [stdout] test aes_cbc_encrypt ... FAILED
[INFO] [stdout] test encrypt_decrypt_gcm_message_no_aad ... FAILED
[INFO] [stdout] test generate_generic_secret_key ... FAILED
[INFO] [stdout] test generate_random_test ... FAILED
[INFO] [stdout] test aes_cmac_sign ... FAILED
[INFO] [stdout] test aes_key_attributes_test ... FAILED
[INFO] [stdout] test aes_cbc_pad_encrypt ... FAILED
[INFO] [stdout] test encrypt_decrypt ... FAILED
[INFO] [stdout] test encrypt_decrypt_multipart_already_initialized ... FAILED
[INFO] [stdout] test aes_gcm_no_aad ... ok
[INFO] [stdout] test ekdf_aes_cbc_encrypt_data ... FAILED
[INFO] [stdout] test get_attribute_info_test ... FAILED
[INFO] [stdout] test aes_gcm_with_aad ... FAILED
[INFO] [stdout] test kbkdf_additional_keys_double_pipeline_mode ... ignored, unsupported by both SoftHSM and Kryoptic for the moment
[INFO] [stdout] test kbkdf_additional_keys_feedback_mode ... ok
[INFO] [stdout] test get_info_test ... FAILED
[INFO] [stdout] test gcm_param_graceful_failure ... ok
[INFO] [stdout] test kbkdf_double_pipeline_mode ... ignored, unsupported by both SoftHSM and Kryoptic for the moment
[INFO] [stdout] test get_slot_event ... FAILED
[INFO] [stdout] test kbkdf_feedback_mode ... ok
[INFO] [stdout] test aes_cmac_verify ... FAILED
[INFO] [stdout] test import_export ... FAILED
[INFO] [stdout] test kbkdf_counter_mode ... ok
[INFO] [stdout] test kbkdf_invalid_data_params_double_pipeline_mode ... ok
[INFO] [stdout] test is_initialized_test ... FAILED
[INFO] [stdout] test kbkdf_additional_keys_counter_mode ... ok
[INFO] [stdout] test ro_rw_session_test ... FAILED
[INFO] [stdout] test get_token_info ... FAILED
[INFO] [stdout] test is_fn_supported_test ... FAILED
[INFO] [stdout] test kbkdf_invalid_data_params_feedback_mode ... ok
[INFO] [stdout] test rsa_pkcs_oaep_empty ... FAILED
[INFO] [stdout] test kbkdf_invalid_data_params_counter_mode ... ok
[INFO] [stdout] test sha256_digest_multipart_already_initialized ... FAILED
[INFO] [stdout] test login_feast ... FAILED
[INFO] [stdout] test session_objecthandle_iterator ... FAILED
[INFO] [stdout] test session_find_objects ... FAILED
[INFO] [stdout] test set_pin_test ... FAILED
[INFO] [stdout] test sha256_digest_multipart_not_initialized ... FAILED
[INFO] [stdout] test sha256_digest ... FAILED
[INFO] [stdout] test sha256_digest_multipart ... FAILED
[INFO] [stdout] test sha256_digest_multipart_with_key ... FAILED
[INFO] [stdout] test sign_verify_multipart ... FAILED
[INFO] [stdout] test sign_verify_multipart_already_initialized ... FAILED
[INFO] [stdout] test sign_verify_eddsa ... FAILED
[INFO] [stdout] test sign_verify_eddsa_with_ed448_schemes ... FAILED
[INFO] [stdout] test sign_verify ... FAILED
[INFO] [stdout] test sign_verify_sha224_hmac ... FAILED
[INFO] [stdout] test test_clone_initialize ... FAILED
[INFO] [stdout] test sign_verify_sha512_hmac ... FAILED
[INFO] [stdout] test unique_id ... FAILED
[INFO] [stdout] test wait_for_slot_event ... FAILED
[INFO] [stdout] test rsa_pkcs_oaep_with_data ... ok
[INFO] [stdout] test sign_verify_sha384_hmac ... FAILED
[INFO] [stdout] test sign_verify_sha256_hmac ... FAILED
[INFO] [stdout] test wrap_and_unwrap_key ... FAILED
[INFO] [stdout] test update_attributes_key ... FAILED
[INFO] [stdout] test sign_verify_sha1_hmac ... FAILED
[INFO] [stdout] test sign_verify_multipart_not_initialized ... FAILED
[INFO] [stdout] test encrypt_decrypt_multipart ... FAILED
[INFO] [stdout] test sign_verify_eddsa_with_ed25519_schemes ... FAILED
[INFO] [stdout] test encrypt_decrypt_multipart_not_initialized ... FAILED
[INFO] [stdout] test get_slot_info_test ... FAILED
[INFO] [stdout] test get_session_info_test ... FAILED
[INFO] [stdout] test session_copy_object ... FAILED
[INFO] [stdout] test derive_key ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt_gcm_message_with_aad stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt_gcm_message_with_aad' (733) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394adc0e - <basic[debeee045214edd8]::encrypt_decrypt_gcm_message_with_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491777 - <basic[debeee045214edd8]::encrypt_decrypt_gcm_message_with_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_cbc_encrypt stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_cbc_encrypt' (722) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394956cb - <basic[debeee045214edd8]::aes_cbc_encrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490fc7 - <basic[debeee045214edd8]::aes_cbc_encrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt_gcm_message_no_aad stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt_gcm_message_no_aad' (732) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a8e7e - <basic[debeee045214edd8]::encrypt_decrypt_gcm_message_no_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394916e7 - <basic[debeee045214edd8]::encrypt_decrypt_gcm_message_no_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- generate_generic_secret_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'generate_generic_secret_key' (738) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a6629 - <basic[debeee045214edd8]::generate_generic_secret_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491657 - <basic[debeee045214edd8]::generate_generic_secret_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- generate_random_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'generate_random_test' (739) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949c339 - <basic[debeee045214edd8]::generate_random_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491297 - <basic[debeee045214edd8]::generate_random_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_cmac_sign stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_cmac_sign' (724) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563948f0f8 - basic[debeee045214edd8]::aes_cmac_sign_impl
[INFO] [stdout]   14:     0x625639493739 - <basic[debeee045214edd8]::aes_cmac_sign::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   16:     0x625639490e77 - <basic[debeee045214edd8]::aes_cmac_sign::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   17:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   18:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   19:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   20:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   21:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   22:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   23:     0x711eb773a3d4 - clone
[INFO] [stdout]   24:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_key_attributes_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_key_attributes_test' (728) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949fbbe - <basic[debeee045214edd8]::aes_key_attributes_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491417 - <basic[debeee045214edd8]::aes_key_attributes_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_cbc_pad_encrypt stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_cbc_pad_encrypt' (723) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639498cf8 - <basic[debeee045214edd8]::aes_cbc_pad_encrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491147 - <basic[debeee045214edd8]::aes_cbc_pad_encrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt' (731) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639495d3e - <basic[debeee045214edd8]::encrypt_decrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491027 - <basic[debeee045214edd8]::encrypt_decrypt::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt_multipart_already_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt_multipart_already_initialized' (735) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b55ea - <basic[debeee045214edd8]::encrypt_decrypt_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491987 - <basic[debeee045214edd8]::encrypt_decrypt_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- ekdf_aes_cbc_encrypt_data stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ekdf_aes_cbc_encrypt_data' (730) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a3e09 - <basic[debeee045214edd8]::ekdf_aes_cbc_encrypt_data::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491597 - <basic[debeee045214edd8]::ekdf_aes_cbc_encrypt_data::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_attribute_info_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_attribute_info_test' (740) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a047e - <basic[debeee045214edd8]::get_attribute_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491447 - <basic[debeee045214edd8]::get_attribute_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_gcm_with_aad stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_gcm_with_aad' (727) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394966b8 - <basic[debeee045214edd8]::aes_gcm_with_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491057 - <basic[debeee045214edd8]::aes_gcm_with_aad::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_info_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_info_test' (741) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639493846 - <basic[debeee045214edd8]::get_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490ea7 - <basic[debeee045214edd8]::get_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_slot_event stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_slot_event' (743) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394952b1 - <basic[debeee045214edd8]::get_slot_event::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490f67 - <basic[debeee045214edd8]::get_slot_event::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- aes_cmac_verify stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'aes_cmac_verify' (725) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563948f7e8 - basic[debeee045214edd8]::aes_cmac_verify_impl
[INFO] [stdout]   14:     0x625639495c29 - <basic[debeee045214edd8]::aes_cmac_verify::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   16:     0x625639490ff7 - <basic[debeee045214edd8]::aes_cmac_verify::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   17:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   18:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   19:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   20:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   21:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   22:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   23:     0x711eb773a3d4 - clone
[INFO] [stdout]   24:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- import_export stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'import_export' (746) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639493ace - <basic[debeee045214edd8]::import_export::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490ed7 - <basic[debeee045214edd8]::import_export::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- is_initialized_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'is_initialized_test' (748) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x625639499322 - <basic[debeee045214edd8]::is_initialized_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   14:     0x625639491178 - <basic[debeee045214edd8]::is_initialized_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   21:     0x711eb773a3d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- ro_rw_session_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'ro_rw_session_test' (757) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394984d4 - <basic[debeee045214edd8]::ro_rw_session_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491117 - <basic[debeee045214edd8]::ro_rw_session_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_token_info stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_token_info' (745) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639495444 - <basic[debeee045214edd8]::get_token_info::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490f97 - <basic[debeee045214edd8]::get_token_info::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- is_fn_supported_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'is_fn_supported_test' (747) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949c781 - <basic[debeee045214edd8]::is_fn_supported_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   15:     0x6256394912c8 - <basic[debeee045214edd8]::is_fn_supported_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- rsa_pkcs_oaep_empty stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'rsa_pkcs_oaep_empty' (758) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949a54b - <basic[debeee045214edd8]::rsa_pkcs_oaep_empty::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394911d7 - <basic[debeee045214edd8]::rsa_pkcs_oaep_empty::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sha256_digest_multipart_already_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sha256_digest_multipart_already_initialized' (766) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b52a2 - <basic[debeee045214edd8]::sha256_digest_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491957 - <basic[debeee045214edd8]::sha256_digest_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- login_feast stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'login_feast' (756) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949287e - <basic[debeee045214edd8]::login_feast::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   15:     0x625639490de8 - <basic[debeee045214edd8]::login_feast::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session_objecthandle_iterator stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session_objecthandle_iterator' (762) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a6f79 - <basic[debeee045214edd8]::session_objecthandle_iterator::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491687 - <basic[debeee045214edd8]::session_objecthandle_iterator::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session_find_objects stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session_find_objects' (761) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949c8fb - <basic[debeee045214edd8]::session_find_objects::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394912f7 - <basic[debeee045214edd8]::session_find_objects::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- set_pin_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'set_pin_test' (763) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639493337 - <basic[debeee045214edd8]::set_pin_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490e47 - <basic[debeee045214edd8]::set_pin_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sha256_digest_multipart_not_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sha256_digest_multipart_not_initialized' (767) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b21c2 - <basic[debeee045214edd8]::sha256_digest_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491897 - <basic[debeee045214edd8]::sha256_digest_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sha256_digest stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sha256_digest' (764) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639494858 - <basic[debeee045214edd8]::sha256_digest::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490f07 - <basic[debeee045214edd8]::sha256_digest::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sha256_digest_multipart stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sha256_digest_multipart' (765) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a1ff7 - <basic[debeee045214edd8]::sha256_digest_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394914a7 - <basic[debeee045214edd8]::sha256_digest_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sha256_digest_multipart_with_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sha256_digest_multipart_with_key' (768) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a839c - <basic[debeee045214edd8]::sha256_digest_multipart_with_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394916b7 - <basic[debeee045214edd8]::sha256_digest_multipart_with_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_multipart stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_multipart' (773) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949e0fe - <basic[debeee045214edd8]::sign_verify_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491357 - <basic[debeee045214edd8]::sign_verify_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_multipart_already_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_multipart_already_initialized' (774) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b2b8e - <basic[debeee045214edd8]::sign_verify_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394918f7 - <basic[debeee045214edd8]::sign_verify_multipart_already_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_eddsa stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_eddsa' (770) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639496cab - <basic[debeee045214edd8]::sign_verify_eddsa::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491087 - <basic[debeee045214edd8]::sign_verify_eddsa::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_eddsa_with_ed448_schemes stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_eddsa_with_ed448_schemes' (772) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394ae6ce - <basic[debeee045214edd8]::sign_verify_eddsa_with_ed448_schemes::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394917a7 - <basic[debeee045214edd8]::sign_verify_eddsa_with_ed448_schemes::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify' (769) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639492bae - <basic[debeee045214edd8]::sign_verify::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490e17 - <basic[debeee045214edd8]::sign_verify::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_sha224_hmac stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_sha224_hmac' (777) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a250c - <basic[debeee045214edd8]::sign_verify_sha224_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394914d7 - <basic[debeee045214edd8]::sign_verify_sha224_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_clone_initialize stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_clone_initialize' (781) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x62563949f18e - <basic[debeee045214edd8]::test_clone_initialize::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   14:     0x6256394913b8 - <basic[debeee045214edd8]::test_clone_initialize::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   15:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   16:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   17:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   18:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   19:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   20:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   21:     0x711eb773a3d4 - clone
[INFO] [stdout]   22:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_sha512_hmac stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_sha512_hmac' (780) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a37cc - <basic[debeee045214edd8]::sign_verify_sha512_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491567 - <basic[debeee045214edd8]::sign_verify_sha512_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- unique_id stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'unique_id' (782) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b5cce - <basic[debeee045214edd8]::unique_id::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394919e7 - <basic[debeee045214edd8]::unique_id::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- wait_for_slot_event stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'wait_for_slot_event' (784) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949b641 - <basic[debeee045214edd8]::wait_for_slot_event::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   15:     0x625639491238 - <basic[debeee045214edd8]::wait_for_slot_event::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_sha384_hmac stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_sha384_hmac' (779) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a318c - <basic[debeee045214edd8]::sign_verify_sha384_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491537 - <basic[debeee045214edd8]::sign_verify_sha384_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_sha256_hmac stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_sha256_hmac' (778) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a2b4c - <basic[debeee045214edd8]::sign_verify_sha256_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491507 - <basic[debeee045214edd8]::sign_verify_sha256_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- wrap_and_unwrap_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'wrap_and_unwrap_key' (785) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949b76b - <basic[debeee045214edd8]::wrap_and_unwrap_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x625639504964 - serial_test[6c247e5351c6102b]::serial_core
[INFO] [stdout]   15:     0x625639491268 - <basic[debeee045214edd8]::wrap_and_unwrap_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- update_attributes_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'update_attributes_key' (783) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949f2ee - <basic[debeee045214edd8]::update_attributes_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394913e7 - <basic[debeee045214edd8]::update_attributes_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_sha1_hmac stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_sha1_hmac' (776) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949e9fc - <basic[debeee045214edd8]::sign_verify_sha1_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491387 - <basic[debeee045214edd8]::sign_verify_sha1_hmac::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_multipart_not_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_multipart_not_initialized' (775) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394af094 - <basic[debeee045214edd8]::sign_verify_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394917d7 - <basic[debeee045214edd8]::sign_verify_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt_multipart stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt_multipart' (734) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394a4d4e - <basic[debeee045214edd8]::encrypt_decrypt_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394915c7 - <basic[debeee045214edd8]::encrypt_decrypt_multipart::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sign_verify_eddsa_with_ed25519_schemes stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sign_verify_eddsa_with_ed25519_schemes' (771) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b0751 - <basic[debeee045214edd8]::sign_verify_eddsa_with_ed25519_schemes::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491837 - <basic[debeee045214edd8]::sign_verify_eddsa_with_ed25519_schemes::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- encrypt_decrypt_multipart_not_initialized stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'encrypt_decrypt_multipart_not_initialized' (736) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x6256394b25d2 - <basic[debeee045214edd8]::encrypt_decrypt_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394918c7 - <basic[debeee045214edd8]::encrypt_decrypt_multipart_not_initialized::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_slot_info_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_slot_info_test' (744) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639497534 - <basic[debeee045214edd8]::get_slot_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr] error: test failed, to rerun pass `--test basic`
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x6256394910b7 - <basic[debeee045214edd8]::get_slot_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- get_session_info_test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'get_session_info_test' (742) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949d5fc - <basic[debeee045214edd8]::get_session_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491327 - <basic[debeee045214edd8]::get_session_info_test::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- session_copy_object stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'session_copy_object' (760) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x62563949aef5 - <basic[debeee045214edd8]::session_copy_object::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639491207 - <basic[debeee045214edd8]::session_copy_object::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- derive_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'derive_key' (729) panicked at tests/common.rs:24:36:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: LibraryLoading(DlOpen { desc: "/usr/local/lib/softhsm/libsofthsm2.so: cannot open shared object file: No such file or directory" })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62563954da11 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x625639561d7a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x625639551c2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x625639529e76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6256395460a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6256394d08d0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x625639546262 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x625639529f22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x625639522c39 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x62563952ab2d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6256395624ac - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x625639562282 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6256394c37c3 - basic[debeee045214edd8]::common::init_pins
[INFO] [stdout]   13:     0x625639491a1e - <basic[debeee045214edd8]::derive_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6256394b6f34 - serial_test[6c247e5351c6102b]::serial_core_with_return::<testresult[aebd0d736487f41]::TestError>
[INFO] [stdout]   15:     0x625639490db7 - <basic[debeee045214edd8]::derive_key::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   16:     0x6256394c3d1b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   17:     0x6256394d0ef5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   18:     0x6256394ca7e4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   19:     0x6256394d3f82 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   20:     0x62563954d26f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   21:     0x711eb76a6dfa - <unknown>
[INFO] [stdout]   22:     0x711eb773a3d4 - clone
[INFO] [stdout]   23:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     aes_cbc_encrypt
[INFO] [stdout]     aes_cbc_pad_encrypt
[INFO] [stdout]     aes_cmac_sign
[INFO] [stdout]     aes_cmac_verify
[INFO] [stdout]     aes_gcm_with_aad
[INFO] [stdout]     aes_key_attributes_test
[INFO] [stdout]     derive_key
[INFO] [stdout]     ekdf_aes_cbc_encrypt_data
[INFO] [stdout]     encrypt_decrypt
[INFO] [stdout]     encrypt_decrypt_gcm_message_no_aad
[INFO] [stdout]     encrypt_decrypt_gcm_message_with_aad
[INFO] [stdout]     encrypt_decrypt_multipart
[INFO] [stdout]     encrypt_decrypt_multipart_already_initialized
[INFO] [stdout]     encrypt_decrypt_multipart_not_initialized
[INFO] [stdout]     generate_generic_secret_key
[INFO] [stdout]     generate_random_test
[INFO] [stdout]     get_attribute_info_test
[INFO] [stdout]     get_info_test
[INFO] [stdout]     get_session_info_test
[INFO] [stdout]     get_slot_event
[INFO] [stdout]     get_slot_info_test
[INFO] [stdout]     get_token_info
[INFO] [stdout]     import_export
[INFO] [stdout]     is_fn_supported_test
[INFO] [stdout]     is_initialized_test
[INFO] [stdout]     login_feast
[INFO] [stdout]     ro_rw_session_test
[INFO] [stdout]     rsa_pkcs_oaep_empty
[INFO] [stdout]     session_copy_object
[INFO] [stdout]     session_find_objects
[INFO] [stdout]     session_objecthandle_iterator
[INFO] [stdout]     set_pin_test
[INFO] [stdout]     sha256_digest
[INFO] [stdout]     sha256_digest_multipart
[INFO] [stdout]     sha256_digest_multipart_already_initialized
[INFO] [stdout]     sha256_digest_multipart_not_initialized
[INFO] [stdout]     sha256_digest_multipart_with_key
[INFO] [stdout]     sign_verify
[INFO] [stdout]     sign_verify_eddsa
[INFO] [stdout]     sign_verify_eddsa_with_ed25519_schemes
[INFO] [stdout]     sign_verify_eddsa_with_ed448_schemes
[INFO] [stdout]     sign_verify_multipart
[INFO] [stdout]     sign_verify_multipart_already_initialized
[INFO] [stdout]     sign_verify_multipart_not_initialized
[INFO] [stdout]     sign_verify_sha1_hmac
[INFO] [stdout]     sign_verify_sha224_hmac
[INFO] [stdout]     sign_verify_sha256_hmac
[INFO] [stdout]     sign_verify_sha384_hmac
[INFO] [stdout]     sign_verify_sha512_hmac
[INFO] [stdout]     test_clone_initialize
[INFO] [stdout]     unique_id
[INFO] [stdout]     update_attributes_key
[INFO] [stdout]     wait_for_slot_event
[INFO] [stdout]     wrap_and_unwrap_key
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 10 passed; 54 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57", kill_on_drop: false }`
[INFO] [stdout] 73b17cac4664a2b9bbce40f9163d8aafd356323208639e0910c03c1903e0ae57
