[INFO] cloning repository https://github.com/jippeholwerda/rustssm
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/jippeholwerda/rustssm" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjippeholwerda%2Frustssm", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjippeholwerda%2Frustssm'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d2781d13d5314893e1e6ba85fb309b9f850546bd
[INFO] testing jippeholwerda/rustssm against 1.100.0-beta.1 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjippeholwerda%2Frustssm" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/jippeholwerda/rustssm
[INFO] finished tweaking git repo https://github.com/jippeholwerda/rustssm
[INFO] tweaked toml for git repo https://github.com/jippeholwerda/rustssm written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/jippeholwerda/rustssm 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 git repo https://github.com/jippeholwerda/rustssm 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-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d
[INFO] running `Command { std: "docker" "start" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", 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" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d" "/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" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", 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" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling quote v1.0.46
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling cmov v0.5.4
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling cpubits v0.1.1
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling base64ct v1.8.3
[INFO] [stderr]    Compiling base16ct v1.0.0
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling ff v0.14.0
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling shlex v2.0.1
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling group v0.14.0
[INFO] [stderr]    Compiling zerocopy v0.8.54
[INFO] [stderr]    Compiling memchr v2.8.2
[INFO] [stderr]    Compiling libloading v0.8.9
[INFO] [stderr]    Compiling ctutils v0.4.2
[INFO] [stderr]    Compiling pem-rfc7468 v1.0.0
[INFO] [stderr]    Compiling cc v1.2.65
[INFO] [stderr]    Compiling bindgen v0.72.1
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling either v1.16.0
[INFO] [stderr]    Compiling rustc-hash v2.1.3
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling der v0.8.0
[INFO] [stderr]    Compiling clang-sys v1.8.1
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling chacha20 v0.10.1
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling libsqlite3-sys v0.38.1
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling hybrid-array v0.4.13
[INFO] [stderr]    Compiling hashlink v0.12.1
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling block-padding v0.4.2
[INFO] [stderr]    Compiling wnaf v0.14.0
[INFO] [stderr]    Compiling spki v0.8.0
[INFO] [stderr]    Compiling inout v0.2.2
[INFO] [stderr]    Compiling pkcs8 v0.11.0
[INFO] [stderr]    Compiling pkcs1 v0.8.0-rc.4
[INFO] [stderr]    Compiling cexpr v0.6.0
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling cipher v0.5.2
[INFO] [stderr]    Compiling universal-hash v0.6.1
[INFO] [stderr]    Compiling aead v0.6.1
[INFO] [stderr]    Compiling regex v1.12.4
[INFO] [stderr]    Compiling hmac v0.13.0
[INFO] [stderr]    Compiling signature v3.0.0
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling polyval v0.7.1
[INFO] [stderr]    Compiling ghash v0.6.0
[INFO] [stderr]    Compiling aes v0.9.1
[INFO] [stderr]    Compiling ctr v0.10.1
[INFO] [stderr]    Compiling cbc v0.2.1
[INFO] [stderr]    Compiling ecb v0.2.0
[INFO] [stderr]    Compiling aes-kw v0.3.1
[INFO] [stderr]    Compiling aes-gcm v0.11.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling zerocopy-derive v0.8.54
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serdect v0.4.3
[INFO] [stderr]    Compiling crypto-bigint v0.7.5
[INFO] [stderr]    Compiling sec1 v0.8.1
[INFO] [stderr]    Compiling elliptic-curve v0.14.1
[INFO] [stderr]    Compiling rfc6979 v0.6.0
[INFO] [stderr]    Compiling primefield v0.14.0
[INFO] [stderr]    Compiling crypto-primes v0.7.2
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling rusqlite v0.40.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rustssm v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling ecdsa v0.17.0
[INFO] [stderr]    Compiling primeorder v0.14.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling p256 v0.14.0
[INFO] [stderr]    Compiling rsa v0.10.0-rc.18
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 54.76s
[INFO] running `Command { std: "docker" "inspect" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", 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" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libloading v0.9.0
[INFO] [stderr]    Compiling rustssm v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.49s
[INFO] running `Command { std: "docker" "inspect" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", 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" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rustssm/512ced09fb40ed6f/out/rustssm-512ced09fb40ed6f)
[INFO] [stdout] 
[INFO] [stdout] running 139 tests
[INFO] [stdout] test hsm::tests::aes_cbc_pad_rejects_malformed_padding ... ok
[INFO] [stdout] test hsm::tests::aes_cbc_matches_known_vectors ... ok
[INFO] [stdout] test hsm::tests::aes_gcm_encryption_matches_reference_implementation ... ok
[INFO] [stdout] test hsm::tests::aes_gcm_with_32_byte_iv_roundtrips_and_matches_reference ... ok
[INFO] [stdout] test hsm::tests::close_session_twice_is_rejected ... ok
[INFO] [stdout] test hsm::tests::aes_gcm_decrypt_detects_tampering ... ok
[INFO] [stdout] test hsm::tests::closing_last_session_logs_out ... ok
[INFO] [stdout] test hsm::tests::aes_cbc_pad_roundtrips_unaligned_plaintext ... ok
[INFO] [stdout] test hsm::tests::aes_gcm_encrypt_decrypt_roundtrip ... ok
[INFO] [stdout] test hsm::tests::closing_a_session_destroys_only_its_session_objects ... ok
[INFO] [stdout] test hsm::tests::copy_object_moves_across_the_token_boundary ... ok
[INFO] [stdout] test hsm::tests::aes_gcm_rejects_invalid_key_sizes ... ok
[INFO] [stdout] test hsm::tests::create_ec_private_key_rejects_unsupported_curve ... ok
[INFO] [stdout] test hsm::tests::copy_object_rejects_duplicate_attribute_types ... ok
[INFO] [stdout] test hsm::tests::copy_object_duplicates_material_and_applies_overrides ... ok
[INFO] [stdout] test hsm::tests::copy_object_rejects_security_downgrades_and_read_only_overrides ... ok
[INFO] [stdout] test hsm::tests::create_secret_key_object_is_stored_and_usable ... ok
[INFO] [stdout] test hsm::tests::create_object_rejects_token_managed_attributes ... ok
[INFO] [stdout] test hsm::tests::creating_a_private_object_requires_login ... ok
[INFO] [stdout] test hsm::tests::create_private_key_rejects_non_ec_and_bad_scalars ... ok
[INFO] [stdout] test hsm::tests::decrypt_init_rejects_invalid_key_size ... ok
[INFO] [stdout] test hsm::tests::decrypt_without_init_is_rejected ... ok
[INFO] [stdout] test hsm::tests::decrypt_rejects_ciphertext_shorter_than_tag ... ok
[INFO] [stdout] test hsm::tests::destroy_object_removes_it ... ok
[INFO] [stdout] test hsm::tests::create_object_rejects_bad_templates ... ok
[INFO] [stdout] test hsm::tests::create_object_rejects_duplicate_attribute_types ... ok
[INFO] [stdout] test hsm::tests::create_ec_private_key_accepts_explicit_p256_params ... ok
[INFO] [stdout] test hsm::tests::duplicate_attribute_types_check_allows_distinct_types ... ok
[INFO] [stdout] test hsm::tests::aes_ecb_roundtrips_across_key_sizes ... ok
[INFO] [stdout] test hsm::tests::create_token_object_in_read_only_session_is_rejected ... ok
[INFO] [stdout] test hsm::tests::find_objects_by_label ... ok
[INFO] [stdout] test hsm::tests::finalize_closes_sessions_and_logs_out ... ok
[INFO] [stdout] test hsm::tests::encrypt_init_rejects_non_encryption_mechanism ... ok
[INFO] [stdout] test hsm::tests::generate_aes_key_produces_usable_key ... ok
[INFO] [stdout] test hsm::tests::finalize_without_initialize_is_rejected ... ok
[INFO] [stdout] test hsm::tests::find_objects_requires_active_search ... ok
[INFO] [stdout] test hsm::tests::generate_aes_key_rejects_non_aes_lengths ... ok
[INFO] [stdout] test hsm::tests::generate_generic_secret_key_roundtrip ... ok
[INFO] [stdout] test hsm::tests::generate_key_bounds_value_length ... ok
[INFO] [stdout] test hsm::tests::generate_key_pair_accepts_explicit_p256_params ... ok
[INFO] [stdout] test hsm::tests::generate_key_pair_rejects_duplicate_attribute_types ... ok
[INFO] [stdout] test hsm::tests::generate_key_rejects_duplicate_attribute_types ... ok
[INFO] [stdout] test hsm::tests::generate_key_rejects_wrong_mechanism ... ok
[INFO] [stdout] test hsm::tests::generate_key_rejects_token_managed_attributes ... ok
[INFO] [stdout] test hsm::tests::generate_key_requires_value_length ... ok
[INFO] [stdout] test hsm::tests::generate_rsa_key_pair_bounds_modulus_bits ... ok
[INFO] [stdout] test hsm::tests::generate_rsa_key_pair_requires_modulus_bits ... ok
[INFO] [stdout] test hsm::tests::generated_aes_key_encrypts_under_gcm ... ok
[INFO] [stdout] test hsm::tests::generated_aes_keys_are_searchable_by_synthesized_and_template_attributes ... ok
[INFO] [stdout] test hsm::tests::find_objects_respects_max_count_and_drains ... ok
[INFO] [stdout] test hsm::tests::hmac_sign_verify_roundtrip ... ok
[INFO] [stdout] test hsm::tests::imported_key_carries_class_defaults ... ok
[INFO] [stdout] test hsm::tests::init_pin_requires_so_in_read_write_session ... ok
[INFO] [stdout] test hsm::tests::init_token_destroys_objects_and_resets_user_pin ... ok
[INFO] [stdout] test admin::tests::init_token_free_picks_the_slot_and_token_finds_it ... ok
[INFO] [stdout] test hsm::tests::encrypt_without_init_is_rejected ... ok
[INFO] [stdout] test hsm::tests::generate_key_pair_rejects_unsupported_curve ... ok
[INFO] [stdout] test hsm::tests::init_token_sets_status ... ok
[INFO] [stdout] test hsm::tests::initialize_twice_is_rejected ... ok
[INFO] [stdout] test hsm::tests::init_token_unknown_slot_is_rejected ... ok
[INFO] [stdout] test hsm::tests::generate_token_key_pair_persists_both_halves ... ok
[INFO] [stdout] test hsm::tests::init_token_with_open_session_is_rejected ... ok
[INFO] [stdout] test hsm::tests::login_twice_is_rejected ... ok
[INFO] [stdout] test hsm::tests::operations_on_invalid_session_are_rejected ... ok
[INFO] [stdout] test hsm::tests::operations_reject_keys_that_opt_out_of_usage ... ok
[INFO] [stdout] test hsm::tests::operations_require_initialization ... ok
[INFO] [stdout] test hsm::tests::private_objects_are_inaccessible_without_login ... ok
[INFO] [stdout] test hsm::tests::read_only_session_cannot_be_opened_while_so_is_logged_in ... ok
[INFO] [stdout] test hsm::tests::reinit_token_with_wrong_so_pin_is_rejected_and_keeps_objects ... ok
[INFO] [stdout] test hsm::tests::open_session_unknown_slot_is_rejected ... ok
[INFO] [stdout] test hsm::tests::login_with_wrong_pin_is_rejected ... ok
[INFO] [stdout] test hsm::tests::logout_without_login_is_rejected ... ok
[INFO] [stdout] test hsm::tests::session_info_reflects_state_and_user ... ok
[INFO] [stdout] test hsm::tests::find_objects_init_twice_is_rejected ... ok
[INFO] [stdout] test hsm::tests::generate_aes_key_requires_value_length ... ok
[INFO] [stdout] test hsm::tests::session_objects_live_in_memory_with_partitioned_handles ... ok
[INFO] [stdout] test hsm::tests::object_attribute_returns_ec_point_of_public_key ... ok
[INFO] [stdout] test hsm::tests::session_objects_are_destroyed_when_their_session_closes ... ok
[INFO] [stdout] test hsm::tests::create_ec_private_key_is_importable_and_signs ... ok
[INFO] [stdout] test hsm::tests::set_object_attributes_enforces_one_way_guarantees ... ok
[INFO] [stdout] test hsm::tests::set_object_attributes_rejects_read_only_and_unknown ... ok
[INFO] [stdout] test hsm::tests::set_object_attributes_rejects_token_changes ... ok
[INFO] [stdout] test hsm::tests::set_pin_changes_the_pin_of_the_logged_in_user ... ok
[INFO] [stdout] test hsm::tests::set_pin_requires_read_write_session ... ok
[INFO] [stdout] test hsm::tests::set_object_attributes_on_token_object_needs_read_write_session ... ok
[INFO] [stdout] test hsm::tests::set_pin_with_wrong_old_pin_is_rejected ... ok
[INFO] [stdout] test hsm::tests::sign_init_twice_is_rejected ... ok
[INFO] [stdout] test hsm::tests::sign_init_with_invalid_key_handle_is_rejected ... ok
[INFO] [stdout] test hsm::tests::sign_without_init_is_rejected ... ok
[INFO] [stdout] test hsm::tests::so_login_is_blocked_by_read_only_session ... ok
[INFO] [stdout] test hsm::tests::there_is_a_single_slot ... ok
[INFO] [stdout] test hsm::tests::ecdsa_verify_via_private_key_handle_derives_public_key ... ok
[INFO] [stdout] test hsm::tests::unpadded_block_modes_reject_unaligned_input ... ok
[INFO] [stdout] test hsm::tests::token_objects_cannot_be_created_in_read_only_session ... ok
[INFO] [stdout] test hsm::tests::token_objects_outlive_their_creating_session ... ok
[INFO] [stdout] test hsm::tests::unwrap_drops_untracked_template_attributes ... ok
[INFO] [stdout] test hsm::tests::unwrap_garbage_is_rejected ... ok
[INFO] [stdout] test hsm::tests::unwrap_key_rejects_duplicate_attribute_types ... ok
[INFO] [stdout] test hsm::tests::verify_with_sign_operation_is_rejected ... ok
[INFO] [stdout] test hsm::tests::wrap_unwrap_roundtrip ... ok
[INFO] [stdout] test hsm::tests::user_login_requires_initialized_pin ... ok
[INFO] [stdout] test hsm::tests::wrap_with_invalid_handles_is_rejected ... ok
[INFO] [stdout] test hsm::tests::wrap_with_wrong_size_kek_is_rejected ... ok
[INFO] [stdout] test object_store::tests::records_encode_attribute_variants_by_name ... ok
[INFO] [stdout] test hsm::tests::write_paths_materialize_class_default_booleans ... ok
[INFO] [stdout] test object_store::tests::search_excludes_private_objects_when_not_permitted ... ok
[INFO] [stdout] test object_store::tests::search_template_with_unknown_matches_nothing ... ok
[INFO] [stdout] test hsm::tests::unwrapped_key_gets_the_unwrap_templates_usage_defaults ... ok
[INFO] [stdout] test admin::tests::show_slots_reflects_initialized_tokens ... ok
[INFO] [stdout] test object_store::tests::store_rowids_never_collide_with_session_object_handles ... ok
[INFO] [stdout] test hsm::tests::session_objects_do_not_survive_a_restart ... ok
[INFO] [stdout] test hsm::tests::token_state_survives_a_restart ... ok
[INFO] [stdout] test object_store::tests::write_pair_persists_both_halves ... ok
[INFO] [stdout] test object_store::tests::unknown_record_format_is_rejected_loudly ... ok
[INFO] [stdout] test pin::tests::hash_verifies_correct_pin_and_rejects_others ... ok
[INFO] [stdout] test pin::tests::stored_hash_round_trips ... ok
[INFO] [stdout] test tests::correctly_sized_scalar_attributes_still_parse ... ok
[INFO] [stdout] test tests::function_list_is_fully_populated ... ok
[INFO] [stdout] test tests::bool_attribute_with_wrong_length_is_unknown ... ok
[INFO] [stdout] test tests::rv_name_maps_known_codes_and_tolerates_unknown_ones ... ok
[INFO] [stdout] test tests::stubbed_function_returns_not_supported_rather_than_crashing ... ok
[INFO] [stdout] test tests::ulong_attribute_with_short_length_is_unknown_not_oob ... ok
[INFO] [stdout] test tests::write_output_copies_and_reports_actual_length ... ok
[INFO] [stdout] test tests::write_output_null_buffer_requests_length ... ok
[INFO] [stdout] test tests::write_output_small_buffer_reports_required_length ... ok
[INFO] [stdout] test tests::write_output_null_length_pointer_is_rejected ... ok
[INFO] [stdout] test object_store::tests::store_read_delete_roundtrip ... ok
[INFO] [stdout] test hsm::tests::ecdsa_sign_verify_roundtrip_and_tamper_detection ... ok
[INFO] [stdout] test admin::tests::init_token_provisions_a_token_a_later_process_can_use ... ok
[INFO] [stdout] test object_store::tests::record_decoding_tolerates_unknown_fields ... ok
[INFO] [stdout] test admin::tests::import_rejects_wrong_key_length ... ok
[INFO] [stdout] test pin::tests::hash_is_salted_and_not_plaintext ... ok
[INFO] [stdout] test object_store::tests::search_matches_attribute_superset ... ok
[INFO] [stdout] test admin::tests::import_aes_key_stores_a_usable_key ... ok
[INFO] [stdout] test hsm::tests::set_object_attributes_updates_readable_value ... ok
[INFO] [stdout] test hsm::tests::rsa_pkcs_pads_raw_data_and_rejects_oversized_input ... ok
[INFO] [stdout] test hsm::tests::concurrent_sessions_from_many_threads_stay_correct ... ok
[INFO] [stdout] test hsm::tests::rsa_sign_verify_roundtrip ... ok
[INFO] [stdout] test hsm::tests::generated_rsa_key_pair_exposes_derived_attributes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 139 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.86s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/pkcs11.rs (/opt/rustwide/target/debug/build/rustssm/bfbdb3c103071889/out/pkcs11-bfbdb3c103071889)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stdout] test pkcs11_end_to_end ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- pkcs11_end_to_end stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'pkcs11_end_to_end' (1920) panicked at tests/pkcs11.rs:58:5:
[INFO] [stdout] built module not found at /opt/rustwide/target/debug/build/rustssm/bfbdb3c103071889/out/librustssm.so
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b3bfda7f011 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5b3bfda7f011 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5b3bfda7f011 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5b3bfda7f011 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5b3bfda92fca - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5b3bfda92fca - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5b3bfda8324c - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5b3bfda8324c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5b3bfda5aa76 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5b3bfda5aa76 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5b3bfda774b9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5b3bfda13050 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5b3bfda13050 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5b3bfda77672 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5b3bfda77672 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5b3bfda5ab22 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5b3bfda535f9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5b3bfda5b7cd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5b3bfda9375c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5b3bfd9fc5d3 - pkcs11[1d858c241483aecd]::module_path
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/pkcs11.rs:58:5
[INFO] [stdout]   20:     0x5b3bfd9fc86e - pkcs11[1d858c241483aecd]::pkcs11_end_to_end
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/pkcs11.rs:85:53
[INFO] [stdout]   21:     0x5b3bfd9fc107 - pkcs11[1d858c241483aecd]::pkcs11_end_to_end::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/pkcs11.rs:79:23
[INFO] [stdout]   22:     0x5b3bfda04b56 - <pkcs11[1d858c241483aecd]::pkcs11_end_to_end::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5b3bfda0649b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5b3bfda0649b - 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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   25:     0x5b3bfda13675 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   26:     0x5b3bfda13675 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   27:     0x5b3bfda13675 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   28:     0x5b3bfda13675 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   29:     0x5b3bfda13675 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5b3bfda13675 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   31:     0x5b3bfda13675 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   32:     0x5b3bfda0cf64 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   33:     0x5b3bfda0cf64 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x5b3bfda16702 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   35:     0x5b3bfda16702 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   36:     0x5b3bfda16702 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   37:     0x5b3bfda16702 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   38:     0x5b3bfda16702 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x5b3bfda16702 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   40:     0x5b3bfda16702 - <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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x5b3bfda7e86f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   42:     0x5b3bfda7e86f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x77727a55bdfa - <unknown>
[INFO] [stdout]   44:     0x77727a5ef3d4 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     pkcs11_end_to_end
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test pkcs11`
[INFO] running `Command { std: "docker" "inspect" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d", kill_on_drop: false }`
[INFO] [stdout] c7ec5df61026e52a7bc0cd4ab97ffee83af74be12947717c1efe1644d771c81d
