[INFO] fetching crate signstar-crypto 0.1.0...
[INFO] testing signstar-crypto-0.1.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate signstar-crypto 0.1.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate signstar-crypto 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate signstar-crypto 0.1.0
[INFO] tweaked toml for crates.io crate signstar-crypto 0.1.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate signstar-crypto 0.1.0 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 signstar-crypto 0.1.0 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-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62
[INFO] running `Command { std: "docker" "start" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", 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" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62" "/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" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", 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" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling unicode-ident v1.0.26
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling cfg-if v1.0.5
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling base64ct v1.8.3
[INFO] [stderr]    Compiling const-oid v0.9.6
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling cmov v0.5.4
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling ctutils v0.4.2
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling pem-rfc7468 v1.0.0
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling pem-rfc7468 v0.7.0
[INFO] [stderr]    Compiling cpubits v0.1.1
[INFO] [stderr]    Compiling radium v0.7.0
[INFO] [stderr]    Compiling ff v0.14.0
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling syn v3.0.6
[INFO] [stderr]    Compiling tap v1.0.1
[INFO] [stderr]    Compiling wyz v0.5.1
[INFO] [stderr]    Compiling funty v2.0.0
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling cpufeatures v0.3.1
[INFO] [stderr]    Compiling base16ct v0.2.0
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling bitvec v1.1.1
[INFO] [stderr]    Compiling group v0.14.0
[INFO] [stderr]    Compiling base16ct v1.0.0
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling zerocopy v0.8.59
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling thiserror v2.0.21
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling curve25519-dalek v4.1.3
[INFO] [stderr]    Compiling num-integer v0.1.47
[INFO] [stderr]    Compiling num-bigint-dig v0.8.6
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling num-iter v0.1.46
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling opaque-debug v0.3.1
[INFO] [stderr]    Compiling smallvec v1.16.2
[INFO] [stderr]    Compiling unicode-segmentation v1.13.3
[INFO] [stderr]    Compiling rustversion v1.0.23
[INFO] [stderr]    Compiling value-bag v1.14.1
[INFO] [stderr]    Compiling convert_case v0.10.0
[INFO] [stderr]    Compiling curve25519-dalek v5.0.0
[INFO] [stderr]    Compiling unicode-xid v0.2.6
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling log v0.4.34
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.21
[INFO] [stderr]    Compiling crc24 v0.1.6
[INFO] [stderr]    Compiling cfg_aliases v0.2.2
[INFO] [stderr]    Compiling keccak v0.1.6
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling ff v0.13.1
[INFO] [stderr]    Compiling group v0.13.0
[INFO] [stderr]    Compiling password-hash v0.5.0
[INFO] [stderr]    Compiling password-hash v0.6.1
[INFO] [stderr]    Compiling chacha20 v0.10.2
[INFO] [stderr]    Compiling mcf v0.6.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling zlib-rs v0.6.8
[INFO] [stderr]    Compiling rand v0.10.3
[INFO] [stderr]    Compiling which v8.0.6
[INFO] [stderr]    Compiling nom v8.0.0
[INFO] [stderr]    Compiling buffer-redux v1.1.0
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling replace_with v0.1.8
[INFO] [stderr]    Compiling bitflags v2.13.2
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling signstar-common v0.2.0
[INFO] [stderr]    Compiling zeroize_derive v1.5.0
[INFO] [stderr]    Compiling curve25519-dalek-derive v0.1.1
[INFO] [stderr]    Compiling num_enum_derive v0.7.6
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling bitfields-impl v1.0.3
[INFO] [stderr]    Compiling hybrid-array v0.4.15
[INFO] [stderr]    Compiling der v0.8.2
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling der v0.7.10
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling crypto-bigint v0.7.5
[INFO] [stderr]    Compiling signature v2.2.0
[INFO] [stderr]    Compiling serdect v0.2.0
[INFO] [stderr]    Compiling hkdf v0.12.4
[INFO] [stderr]    Compiling crypto-bigint v0.5.5
[INFO] [stderr]    Compiling rfc6979 v0.4.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling spki v0.8.0
[INFO] [stderr]    Compiling sec1 v0.8.1
[INFO] [stderr]    Compiling pkcs8 v0.11.0
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling spki v0.7.3
[INFO] [stderr]    Compiling hmac v0.13.0
[INFO] [stderr]    Compiling signature v3.0.0
[INFO] [stderr]    Compiling pkcs8 v0.10.2
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling rand v0.8.8
[INFO] [stderr]    Compiling ctr v0.9.2
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling inout v0.2.2
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling polyval v0.6.2
[INFO] [stderr]    Compiling cipher v0.5.2
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling aes v0.8.4
[INFO] [stderr]    Compiling dbl v0.3.2
[INFO] [stderr]    Compiling cmac v0.7.2
[INFO] [stderr]    Compiling salsa20 v0.11.0
[INFO] [stderr]    Compiling ghash v0.5.1
[INFO] [stderr]    Compiling pkcs1 v0.7.5
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling pbkdf2 v0.13.0
[INFO] [stderr]    Compiling ed25519 v2.2.3
[INFO] [stderr]    Compiling blake2 v0.10.6
[INFO] [stderr]    Compiling sha1 v0.10.7
[INFO] [stderr]    Compiling sha3 v0.10.9
[INFO] [stderr]    Compiling serdect v0.3.0
[INFO] [stderr]    Compiling snafu-derive v0.9.2
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling cx448 v0.1.1
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling argon2 v0.5.3
[INFO] [stderr]    Compiling sha1-checked v0.10.0
[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 ecdsa v0.17.0
[INFO] [stderr]    Compiling wnaf v0.14.1
[INFO] [stderr]    Compiling dsa v0.6.3
[INFO] [stderr]    Compiling primeorder v0.14.0
[INFO] [stderr]    Compiling rsa v0.9.10
[INFO] [stderr]    Compiling x25519-dalek v2.0.1
[INFO] [stderr]    Compiling ed25519-dalek v2.2.0
[INFO] [stderr]    Compiling crypto-primes v0.7.2
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling p521 v0.13.3
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling yescrypt v0.1.0
[INFO] [stderr]    Compiling aes-kw v0.2.1
[INFO] [stderr]    Compiling aes-gcm v0.10.3
[INFO] [stderr]    Compiling snafu v0.9.2
[INFO] [stderr]    Compiling eax v0.5.0
[INFO] [stderr]    Compiling ocb3 v0.1.0
[INFO] [stderr]    Compiling flate2 v1.1.10
[INFO] [stderr]    Compiling ed25519 v3.0.0
[INFO] [stderr]    Compiling pkcs1 v0.8.0-rc.4
[INFO] [stderr]    Compiling twofish v0.7.1
[INFO] [stderr]    Compiling blowfish v0.9.1
[INFO] [stderr]    Compiling des v0.8.1
[INFO] [stderr]    Compiling cfb-mode v0.8.2
[INFO] [stderr]    Compiling idea v0.5.1
[INFO] [stderr]    Compiling cast5 v0.11.1
[INFO] [stderr]    Compiling camellia v0.1.0
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling ripemd v0.1.3
[INFO] [stderr]    Compiling bitfields v1.0.3
[INFO] [stderr]    Compiling block-padding v0.3.3
[INFO] [stderr]    Compiling num_enum v0.7.6
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling rsa v0.10.0-rc.18
[INFO] [stderr]    Compiling change-user-run v0.1.3
[INFO] [stderr]    Compiling k256 v0.14.0
[INFO] [stderr]    Compiling bp384 v0.14.0
[INFO] [stderr]    Compiling p521 v0.14.0
[INFO] [stderr]    Compiling bp256 v0.14.0
[INFO] [stderr]    Compiling ed25519-dalek v3.0.0
[INFO] [stderr]    Compiling p256 v0.14.0
[INFO] [stderr]    Compiling p224 v0.14.0-rc.15
[INFO] [stderr]    Compiling pgp v0.20.0
[INFO] [stderr]    Compiling p384 v0.14.0
[INFO] [stderr]    Compiling email_address v0.2.9
[INFO] [stderr]    Compiling secrecy v0.10.3
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling signstar-crypto v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 29s
[INFO] running `Command { std: "docker" "inspect" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", 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" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62" "/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 syn v3.0.6
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling rustix v1.1.5
[INFO] [stderr]    Compiling bitflags v2.13.2
[INFO] [stderr]    Compiling num-conv v0.2.2
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling time-core v0.1.9
[INFO] [stderr]    Compiling hmac v0.13.0
[INFO] [stderr]    Compiling signature v3.0.0
[INFO] [stderr]    Compiling elliptic-curve v0.14.1
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling rfc6979 v0.6.0
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling winnow v1.0.4
[INFO] [stderr]    Compiling ecdsa v0.17.0
[INFO] [stderr]    Compiling primeorder v0.14.0
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling indexmap v2.14.2
[INFO] [stderr]    Compiling time-macros v0.2.32
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling curve25519-dalek v5.0.0
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml_parser v1.1.3+spec-1.1.0
[INFO] [stderr]    Compiling num_threads v0.1.7
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling toml_edit v0.25.15+spec-1.1.0
[INFO] [stderr]    Compiling pbkdf2 v0.13.0
[INFO] [stderr]    Compiling rstest_macros v0.27.0
[INFO] [stderr]    Compiling clap_lex v1.1.1
[INFO] [stderr]    Compiling termcolor v1.4.1
[INFO] [stderr]    Compiling clap_builder v4.6.7
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling yescrypt v0.1.0
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling time v0.3.55
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling proc-macro-crate v3.5.0
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling ed25519 v3.0.0
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.21
[INFO] [stderr]    Compiling systemd-journal-logger v2.2.2
[INFO] [stderr]    Compiling clap_derive v4.6.7
[INFO] [stderr]    Compiling futures-task v0.3.34
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling glob v0.3.4
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling thiserror v2.0.21
[INFO] [stderr]    Compiling simplelog v0.12.2
[INFO] [stderr]    Compiling bitfields-impl v1.0.3
[INFO] [stderr]    Compiling change-user-run v0.1.3
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling clap v4.6.7
[INFO] [stderr]    Compiling signstar-common v0.2.0
[INFO] [stderr]    Compiling ed25519-dalek v3.0.0
[INFO] [stderr]    Compiling rsa v0.10.0-rc.18
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling p521 v0.14.0
[INFO] [stderr]    Compiling bp384 v0.14.0
[INFO] [stderr]    Compiling bitfields v1.0.3
[INFO] [stderr]    Compiling p256 v0.14.0
[INFO] [stderr]    Compiling p224 v0.14.0-rc.15
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling bp256 v0.14.0
[INFO] [stderr]    Compiling k256 v0.14.0
[INFO] [stderr]    Compiling serdect v0.2.0
[INFO] [stderr]    Compiling sec1 v0.7.3
[INFO] [stderr]    Compiling elliptic-curve v0.13.8
[INFO] [stderr]    Compiling num-bigint-dig v0.8.6
[INFO] [stderr]    Compiling ecdsa v0.16.9
[INFO] [stderr]    Compiling primeorder v0.13.6
[INFO] [stderr]    Compiling serdect v0.3.0
[INFO] [stderr]    Compiling cx448 v0.1.1
[INFO] [stderr]    Compiling k256 v0.13.4
[INFO] [stderr]    Compiling p384 v0.13.1
[INFO] [stderr]    Compiling dsa v0.6.3
[INFO] [stderr]    Compiling rsa v0.9.10
[INFO] [stderr]    Compiling p521 v0.13.3
[INFO] [stderr]    Compiling p256 v0.13.2
[INFO] [stderr]    Compiling email_address v0.2.9
[INFO] [stderr]    Compiling secrecy v0.10.3
[INFO] [stderr]    Compiling p384 v0.14.0
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling futures-timer v3.0.4
[INFO] [stderr]    Compiling clap-verbosity-flag v3.0.4
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling testresult v0.4.2
[INFO] [stderr]    Compiling rstest v0.27.0
[INFO] [stderr]    Compiling pgp v0.20.0
[INFO] [stderr]    Compiling signstar-crypto v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 01s
[INFO] running `Command { std: "docker" "inspect" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", 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" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62" "/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.34s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/signstar-crypto/5c11568a882d5bc0/out/signstar_crypto-5c11568a882d5bc0)
[INFO] [stdout] 
[INFO] [stdout] running 127 tests
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_01 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_04 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_02 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_03 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_05 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_06 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_08 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_07 ... ok
[INFO] [stdout] test key::base::tests::encryptmode_fromstr::case_2 ... ok
[INFO] [stdout] test key::base::tests::encryptmode_fromstr::case_1 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_04 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_05 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_01 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_06 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_02 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_07 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_08 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_03 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_09 ... ok
[INFO] [stdout] test key::base::tests::decryptmode_fromstr::case_10 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_10 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_11 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_12 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_13 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_14 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_15 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_16 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_20 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_09 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_17 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_19 ... ok
[INFO] [stdout] test key::base::tests::keymechanism_fromstr::case_18 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_1 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_2 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_3 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_4 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_6 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_5 ... ok
[INFO] [stdout] test key::base::tests::keytype_fromstr::case_7 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_01 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_04 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_05 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_06 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_07 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_08 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_09 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_10 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_11 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_01 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_02 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_06 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_09 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_03 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_07 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_04 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_02 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_08 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_05 ... ok
[INFO] [stdout] test key::base::tests::signaturetype_fromstr::case_03 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_10 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_16 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_17 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_18 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_14 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_15 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_13 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_11 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_12 ... ok
[INFO] [stdout] test key::base::tests::test_key_type_and_mechanisms_match_signature_type::case_19 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_5_ecp224 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_6_ecp256 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_1_curve_25519 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_2_ecbp256 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_3_ecbp384 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_4_eck256 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_5_ecp224 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_6_ecp256 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_7_ecp384 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_8_ecp521 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_4_eck256 ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_length_required_or_too_short::case_1_rsa_too_short ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_length_required_or_too_short::case_2_rsa_no_length ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_length_unsupported::case_1_curve25519_with_length ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_length_unsupported::case_2_ecp521_with_length ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_type_mechanism_mismatch::case_1_curve25519_ecdsa ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_on_key_type_mechanism_mismatch::case_2_rsa_ecdsa ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_signature_type_mismatch::case_1_curve25519_ecdsap521 ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_fails_signature_type_mismatch::case_2_ecdsap521_eddsa ... ok
[INFO] [stdout] test key::setup::tests::signing_key_setup_new_succeeds ... ok
[INFO] [stdout] test passphrase::tests::passphrase_check_against_policy ... ok
[INFO] [stdout] test key::import::tests::key_data::case_9_rsa ... ok
[INFO] [stdout] test passphrase::tests::passphrase_generate::case_1_too_short_use_default ... ok
[INFO] [stdout] test passphrase::tests::passphrase_generate::case_2_none_use_default ... ok
[INFO] [stdout] test passphrase::tests::passphrase_generate::case_3_longer_than_default ... ok
[INFO] [stdout] test passphrase::tests::passphrase_display ... ok
[INFO] [stdout] test passphrase::tests::passphrase_is_empty::case_1_with_len ... ok
[INFO] [stdout] test passphrase::tests::passphrase_is_empty::case_2_empty ... ok
[INFO] [stdout] test passphrase::tests::passphrase_len::case_2_empty ... ok
[INFO] [stdout] test passphrase::tests::passphrase_new_with_policy_fails_on_short_passphrase::case_1_empty_policy_allows_one_char ... ok
[INFO] [stdout] test passphrase::tests::passphrase_len::case_1_with_len ... ok
[INFO] [stdout] test passphrase::tests::passphrase_new_with_policy_fails_on_short_passphrase::case_2_shorter_than_minimum_requirement ... ok
[INFO] [stderr] 18:27:08 [DEBUG] (113) signstar_crypto::secret_file::common::tests: Created "/tmp/.tmpnmJ7Ug" with mode 40755
[INFO] [stdout] test passphrase::tests::passphrase_new_with_policy_succeeds::case_2_longer_than_minimum_requirement ... ok
[INFO] [stdout] test passphrase::tests::passphrase_try_from_path_fails_on_path_is_dir ... ok
[INFO] [stdout] test passphrase::tests::passphrase_try_from_path_succeeds ... ok
[INFO] [stdout] test secret_file::common::tests::check_secrets_file_fails_on_dir ... ok
[INFO] [stdout] test passphrase::tests::passphrase_new_with_policy_succeeds::case_1_empty_policy_allows_empty ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::config: signing certification CertPositive
[INFO] [stdout] test secret_file::common::tests::check_secrets_file_fails_on_missing_file ... FAILED
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stdout] test secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions ... FAILED
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: SignatureCreationTime(
[INFO] [stderr]         Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         5,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerFingerprint(
[INFO] [stderr]         0c342b8a86af7aa70d0980fe23902f1936556a27,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         22,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(0c342b8a86af7aa70d0980fe23902f1936556a27)
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: KeyFlags(
[INFO] [stderr]         KeyFlags {
[INFO] [stderr]             known: KnownKeyFlags {
[INFO] [stderr]                 _padding2: 0,
[INFO] [stderr]                 timestamping: 0,
[INFO] [stderr]                 adsk: 0,
[INFO] [stderr]                 _padding1: 0,
[INFO] [stderr]                 group: 0,
[INFO] [stderr]                 draft_decrypt_forwarded: 0,
[INFO] [stderr]                 authentication: 0,
[INFO] [stderr]                 shared: 0,
[INFO] [stderr]                 encrypt_storage: 0,
[INFO] [stderr]                 encrypt_comms: 0,
[INFO] [stderr]                 sign: 1,
[INFO] [stderr]                 certify: 0,
[INFO] [stderr]             },
[INFO] [stderr]             rest: None,
[INFO] [stderr]             original_len: 1,
[INFO] [stderr]         },
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IsPrimary(
[INFO] [stderr]         true,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stdout] test secret_file::common::tests::check_secrets_file_succeeds ... FAILED
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stdout] test signer::openpgp::tests::convert_ed25519_to_pgp ... ok
[INFO] [stdout] test signer::openpgp::tests::convert_p256_to_pgp ... ok
[INFO] [stdout] test signer::openpgp::tests::convert_p384_to_pgp ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stdout] test signer::openpgp::tests::convert_p521_to_pgp ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(0c342b8a86af7aa70d0980fe23902f1936556a27)
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(23902f1936556a27))
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::types::signature: writing 2 signature MPIs
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: fixed packet 51
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::public_key_parser: public params for key version V4
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   signatures
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: fixed packet 4
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   user
[INFO] [stdout] test signer::openpgp::tests::convert_rsa_to_pgp ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser: peek UserId
[INFO] [stdout] test signer::openpgp::tests::check_empty_user_ids ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   user data: UserId
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: fixed packet 123
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: reading subpacket SignatureCreationTime: critical? false, len: 4
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: parsing subpacket: SignatureCreationTime
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 })), len: One(5) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: reading subpacket IssuerFingerprint: critical? false, len: 21
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: parsing subpacket: IssuerFingerprint
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IssuerFingerprint(0c342b8a86af7aa70d0980fe23902f1936556a27), len: One(22) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: reading subpacket KeyFlags: critical? false, len: 1
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: parsing subpacket: KeyFlags
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 }), len: One(2) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: reading subpacket PrimaryUserId: critical? false, len: 1
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: parsing subpacket: PrimaryUserId
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IsPrimary(true), len: One(2) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found 4 hashed subpackets in 35 bytes
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: reading subpacket IssuerKeyId: critical? false, len: 8
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: parsing subpacket: IssuerKeyId
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IssuerKeyId(KeyId(23902f1936556a27)), len: One(9) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: found 1 unhashed subpackets in 10 bytes
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::signature::de: signature data Mpis([Mpi(a26efa9414f244e2cd4500ef21cedfce4c800e5ee0c0835b19ca9e59aa155e6a), Mpi(c1ad73a015f7888b979bbf5abc63abf9d66b98f420c24e2947d888abe4abef04)])
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   subkeys
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: fixed packet 51
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::packet::public_key_parser: public params for key version V4
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   signatures
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   user
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser: missing user ids
[INFO] [stderr] 18:27:09 [DEBUG] (117) pgp::composed::signed_key::key_parser:   subkeys
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: signing certification CertPositive
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: SignatureCreationTime(
[INFO] [stderr]         Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stdout] test signer::openpgp::tests::estimate_certificate_size ... ok
[INFO] [stderr]         5,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerFingerprint(
[INFO] [stderr]         50028415cd6d2f237156150c00f711b9e9361276,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         22,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(50028415cd6d2f237156150c00f711b9e9361276)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: KeyFlags(
[INFO] [stderr]         KeyFlags {
[INFO] [stderr]             known: KnownKeyFlags {
[INFO] [stderr]                 _padding2: 0,
[INFO] [stderr]                 timestamping: 0,
[INFO] [stderr]                 adsk: 0,
[INFO] [stderr]                 _padding1: 0,
[INFO] [stderr]                 group: 0,
[INFO] [stderr]                 draft_decrypt_forwarded: 0,
[INFO] [stderr]                 authentication: 0,
[INFO] [stderr]                 shared: 0,
[INFO] [stderr]                 encrypt_storage: 0,
[INFO] [stderr]                 encrypt_comms: 0,
[INFO] [stderr]                 sign: 1,
[INFO] [stderr]                 certify: 0,
[INFO] [stderr]             },
[INFO] [stderr]             rest: None,
[INFO] [stderr]             original_len: 1,
[INFO] [stderr]         },
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IsPrimary(
[INFO] [stderr]         true,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(59) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(50028415cd6d2f237156150c00f711b9e9361276)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(00f711b9e9361276))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::types::signature: writing 2 signature MPIs
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: signing certification CertPositive
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: SignatureCreationTime(
[INFO] [stderr]         Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         5,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerFingerprint(
[INFO] [stderr]         50028415cd6d2f237156150c00f711b9e9361276,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         22,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(50028415cd6d2f237156150c00f711b9e9361276)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: KeyFlags(
[INFO] [stderr]         KeyFlags {
[INFO] [stderr]             known: KnownKeyFlags {
[INFO] [stderr]                 _padding2: 0,
[INFO] [stderr]                 timestamping: 0,
[INFO] [stderr]                 adsk: 0,
[INFO] [stderr]                 _padding1: 0,
[INFO] [stderr]                 group: 0,
[INFO] [stderr]                 draft_decrypt_forwarded: 0,
[INFO] [stderr]                 authentication: 0,
[INFO] [stderr]                 shared: 0,
[INFO] [stderr]                 encrypt_storage: 0,
[INFO] [stderr]                 encrypt_comms: 0,
[INFO] [stderr]                 sign: 1,
[INFO] [stderr]                 certify: 0,
[INFO] [stderr]             },
[INFO] [stderr]             rest: None,
[INFO] [stderr]             original_len: 1,
[INFO] [stderr]         },
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IsPrimary(
[INFO] [stderr]         true,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(9) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(59) }
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(50028415cd6d2f237156150c00f711b9e9361276)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(00f711b9e9361276))
[INFO] [stderr] 18:27:09 [DEBUG] (123) pgp::types::signature: writing 2 signature MPIs
[INFO] [stdout] test signer::openpgp::tests::import_ecdsa::case_1_p256 ... ok
[INFO] [stdout] test signer::openpgp::tests::import_ecdsa::case_2_p384 ... ok
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: signing certification CertPositive
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: SignatureCreationTime(
[INFO] [stderr]         Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         5,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerFingerprint(
[INFO] [stderr]         3da1686e4bd87d8d7f819662b279015aabada98d,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         22,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(3da1686e4bd87d8d7f819662b279015aabada98d)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: KeyFlags(
[INFO] [stderr]         KeyFlags {
[INFO] [stderr]             known: KnownKeyFlags {
[INFO] [stderr]                 _padding2: 0,
[INFO] [stderr]                 timestamping: 0,
[INFO] [stderr]                 adsk: 0,
[INFO] [stderr]                 _padding1: 0,
[INFO] [stderr]                 group: 0,
[INFO] [stderr]                 draft_decrypt_forwarded: 0,
[INFO] [stderr]                 authentication: 0,
[INFO] [stderr]                 shared: 0,
[INFO] [stderr]                 encrypt_storage: 0,
[INFO] [stderr]                 encrypt_comms: 0,
[INFO] [stderr]                 sign: 1,
[INFO] [stderr]                 certify: 0,
[INFO] [stderr]             },
[INFO] [stderr]             rest: None,
[INFO] [stderr]             original_len: 1,
[INFO] [stderr]         },
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IsPrimary(
[INFO] [stderr]         true,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         2,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(3da1686e4bd87d8d7f819662b279015aabada98d)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 })
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IsPrimary(true)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(b279015aabada98d))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::types::signature: writing 2 signature MPIs
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: fixed packet 51
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::public_key_parser: public params for key version V4
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: PublicKey }, length: Fixed(51) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::signed_key::key_parser:   signatures
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: fixed packet 4
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: UserId }, length: Fixed(4) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::signed_key::key_parser:   user
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::signed_key::key_parser: peek UserId
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::signed_key::key_parser:   user data: UserId
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::many: found header: New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: fixed packet 123
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: reading subpacket SignatureCreationTime: critical? false, len: 4
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: parsing subpacket: SignatureCreationTime
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 })), len: One(5) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: reading subpacket IssuerFingerprint: critical? false, len: 21
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: parsing subpacket: IssuerFingerprint
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IssuerFingerprint(3da1686e4bd87d8d7f819662b279015aabada98d), len: One(22) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: reading subpacket KeyFlags: critical? false, len: 1
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: parsing subpacket: KeyFlags
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: KeyFlags(KeyFlags { known: KnownKeyFlags { _padding2: 0, timestamping: 0, adsk: 0, _padding1: 0, group: 0, draft_decrypt_forwarded: 0, authentication: 0, shared: 0, encrypt_storage: 0, encrypt_comms: 0, sign: 1, certify: 0 }, rest: None, original_len: 1 }), len: One(2) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: reading subpacket PrimaryUserId: critical? false, len: 1
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: parsing subpacket: PrimaryUserId
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IsPrimary(true), len: One(2) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found 4 hashed subpackets in 35 bytes
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: reading subpacket IssuerKeyId: critical? false, len: 8
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: parsing subpacket: IssuerKeyId
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found subpacket Subpacket { is_critical: false, data: IssuerKeyId(KeyId(b279015aabada98d)), len: One(9) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: found 1 unhashed subpackets in 10 bytes
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::de: signature data Mpis([Mpi(b92d93b9ca5d2a37729f41a4bb142453780058490601162e0b929ed592809362), Mpi(6a635f79480298d385816a01d231ac40a1dcdd649ceb2c05043a589e8d2cc602)])
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::message::reader::packet_body: body source done: New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(123) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::composed::signed_key::key_parser:   subkeys
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: SignatureCreationTime(
[INFO] [stderr]         Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         5,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerKeyId(
[INFO] [stderr]         KeyId(
[INFO] [stderr]             b279015aabada98d,
[INFO] [stderr]         ),
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         9,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(b279015aabada98d))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::config: hashing Subpacket {
[INFO] [stderr]     is_critical: false,
[INFO] [stderr]     data: IssuerFingerprint(
[INFO] [stderr]         3da1686e4bd87d8d7f819662b279015aabada98d,
[INFO] [stderr]     ),
[INFO] [stderr]     len: One(
[INFO] [stderr]         22,
[INFO] [stderr]     ),
[INFO] [stderr] }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(3da1686e4bd87d8d7f819662b279015aabada98d)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::header: writing packet header New { header: NewPacketHeader { padding: 1, version: 1, tag: Signature }, length: Fixed(117) }
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: SignatureCreationTime(Timestamp(SystemTime { tv_sec: 1791311229, tv_nsec: 0 }))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerKeyId(KeyId(b279015aabada98d))
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::packet::signature::ser: writing subpacket: IssuerFingerprint(3da1686e4bd87d8d7f819662b279015aabada98d)
[INFO] [stderr] 18:27:09 [DEBUG] (127) pgp::types::signature: writing 2 signature MPIs
[INFO] [stdout] test signer::openpgp::tests::sign_dummy ... ok
[INFO] [stderr] 18:27:09 [WARN] Unsupported ECDSA parameters: Secp256k1 { key: PublicKey { point: AffinePoint { x: FieldElement(FieldElementImpl { value: FieldElement5x52([3479722801554192, 3603278365693123, 1923350234656235, 2133278559621934, 38518482530241]), magnitude: 1, normalized: true }), y: FieldElement(FieldElementImpl { value: FieldElement5x52([2883099304805697, 3227728923505665, 589983841097736, 3763814381100476, 206779536144023]), magnitude: 1, normalized: true }), infinity: 0 } } }
[INFO] [stdout] test signer::openpgp::tests::test_unsupported_ecdsa_curve ... ok
[INFO] [stdout] test signer::openpgp::tests::import_ecdsa::case_3_p521 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_1_curve25519 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_2_ecbp256 ... ok
[INFO] [stdout] test key::import::tests::private_key_import_key_data_matches::case_9_rsa ... ok
[INFO] [stdout] test key::import::tests::key_data::case_3_ecbp384 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_8_ecp521 ... ok
[INFO] [stdout] test key::import::tests::key_data::case_7_ecp384 ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- secret_file::common::tests::check_secrets_file_fails_on_missing_file stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'secret_file::common::tests::check_secrets_file_fails_on_missing_file' (3861) panicked at src/secret_file/common.rs:100:9:
[INFO] [stdout] error: signstar_common::error::Error - Logger initialization error: attempted to set a logger after the logging system was already initialized
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x597b4ebbbc11 - <<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:     0x597b4ebd1aca - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x597b4ebd1aca - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x597b4ebc0a4c - 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:     0x597b4ebc0a4c - <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:     0x597b4eb95e56 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x597b4eb95e56 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x597b4ebb32e9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x597b4e64ec90 - <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:     0x597b4e64ec90 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x597b4ebb34a2 - <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:     0x597b4ebb34a2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x597b4eb95f02 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x597b4eb8e3d9 - 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:     0x597b4eb96c1d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x597b4ebd22ec - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x597b4e5673a1 - <testresult[b64a0866c9e8ddb2]::TestError as core[a373ad73a504971]::convert::From<signstar_common[16a23373ef9f8cd0]::error::Error>>::from
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/testresult-0.4.2/src/lib.rs:24:9
[INFO] [stdout]   20:     0x597b4e5cf1cd - <core[a373ad73a504971]::result::Result<(), testresult[b64a0866c9e8ddb2]::TestError> as core[a373ad73a504971]::ops::try_trait::FromResidual<core[a373ad73a504971]::result::Result<!, signstar_common[16a23373ef9f8cd0]::error::Error>>>::from_residual
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/result.rs:2190:27
[INFO] [stdout]   21:     0x597b4e52363b - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_missing_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:100:9
[INFO] [stdout]   22:     0x597b4e520977 - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_missing_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:99:54
[INFO] [stdout]   23:     0x597b4e5cfa56 - <signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_missing_file::{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]   24:     0x597b4e6420db - <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]   25:     0x597b4e6420db - 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]   26:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   27:     0x597b4e64f2b5 - <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]   28:     0x597b4e64f2b5 - 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]   29:     0x597b4e64f2b5 - 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]   30:     0x597b4e64f2b5 - 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]   31:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   32:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   33:     0x597b4e648ba4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   34:     0x597b4e648ba4 - 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]   35:     0x597b4e652342 - 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]   36:     0x597b4e652342 - <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]   37:     0x597b4e652342 - 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]   38:     0x597b4e652342 - 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]   39:     0x597b4e652342 - 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]   40:     0x597b4e652342 - 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]   41:     0x597b4e652342 - <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]   42:     0x597b4ebbaf2f - <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]   43:     0x597b4ebbaf2f - <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]   44:     0x725454555dfa - <unknown>
[INFO] [stdout]   45:     0x7254545e93d4 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions' (3860) panicked at src/secret_file/common.rs:135:9:
[INFO] [stdout] error: signstar_common::error::Error - Logger initialization error: attempted to set a logger after the logging system was already initialized
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x597b4ebbbc11 - <<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:     0x597b4ebd1aca - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x597b4ebd1aca - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x597b4ebc0a4c - 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:     0x597b4ebc0a4c - <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:     0x597b4eb95e56 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x597b4eb95e56 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x597b4ebb32e9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x597b4e64ec90 - <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:     0x597b4e64ec90 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x597b4ebb34a2 - <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:     0x597b4ebb34a2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x597b4eb95f02 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x597b4eb8e3d9 - 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:     0x597b4eb96c1d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x597b4ebd22ec - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x597b4e5673a1 - <testresult[b64a0866c9e8ddb2]::TestError as core[a373ad73a504971]::convert::From<signstar_common[16a23373ef9f8cd0]::error::Error>>::from
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/testresult-0.4.2/src/lib.rs:24:9
[INFO] [stdout]   20:     0x597b4e5cf1cd - <core[a373ad73a504971]::result::Result<(), testresult[b64a0866c9e8ddb2]::TestError> as core[a373ad73a504971]::ops::try_trait::FromResidual<core[a373ad73a504971]::result::Result<!, signstar_common[16a23373ef9f8cd0]::error::Error>>>::from_residual
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/result.rs:2190:27
[INFO] [stdout]   21:     0x597b4e523a02 - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:135:9
[INFO] [stdout]   22:     0x597b4e5209a7 - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:134:61
[INFO] [stdout]   23:     0x597b4e5cfa76 - <signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions::{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]   24:     0x597b4e6420db - <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]   25:     0x597b4e6420db - 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]   26:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   27:     0x597b4e64f2b5 - <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]   28:     0x597b4e64f2b5 - 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]   29:     0x597b4e64f2b5 - 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]   30:     0x597b4e64f2b5 - 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]   31:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   32:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   33:     0x597b4e648ba4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   34:     0x597b4e648ba4 - 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]   35:     0x597b4e652342 - 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]   36:     0x597b4e652342 - <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]   37:     0x597b4e652342 - 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]   38:     0x597b4e652342 - 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]   39:     0x597b4e652342 - 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]   40:     0x597b4e652342 - 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]   41:     0x597b4e652342 - <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]   42:     0x597b4ebbaf2f - <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]   43:     0x597b4ebbaf2f - <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]   44:     0x725454555dfa - <unknown>
[INFO] [stdout]   45:     0x7254545e93d4 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- secret_file::common::tests::check_secrets_file_succeeds stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'secret_file::common::tests::check_secrets_file_succeeds' (3862) panicked at src/secret_file/common.rs:82:9:
[INFO] [stdout] error: signstar_common::error::Error - Logger initialization error: attempted to set a logger after the logging system was already initialized
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - 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:     0x597b4ebbbc11 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x597b4ebbbc11 - <<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:     0x597b4ebd1aca - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x597b4ebd1aca - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x597b4ebc0a4c - 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:     0x597b4ebc0a4c - <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:     0x597b4eb95e56 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x597b4eb95e56 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x597b4ebb32e9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x597b4e64ec90 - <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:     0x597b4e64ec90 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x597b4ebb34a2 - <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:     0x597b4ebb34a2 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x597b4eb95f02 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x597b4eb8e3d9 - 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:     0x597b4eb96c1d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x597b4ebd22ec - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x597b4e5673a1 - <testresult[b64a0866c9e8ddb2]::TestError as core[a373ad73a504971]::convert::From<signstar_common[16a23373ef9f8cd0]::error::Error>>::from
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/testresult-0.4.2/src/lib.rs:24:9
[INFO] [stdout]   20:     0x597b4e5cf1cd - <core[a373ad73a504971]::result::Result<(), testresult[b64a0866c9e8ddb2]::TestError> as core[a373ad73a504971]::ops::try_trait::FromResidual<core[a373ad73a504971]::result::Result<!, signstar_common[16a23373ef9f8cd0]::error::Error>>>::from_residual
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/result.rs:2190:27
[INFO] [stdout]   21:     0x597b4e5229f2 - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_succeeds
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:82:9
[INFO] [stdout]   22:     0x597b4e520917 - signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_succeeds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/secret_file/common.rs:81:41
[INFO] [stdout]   23:     0x597b4e5cfa16 - <signstar_crypto[c0841e5fb79de1ab]::secret_file::common::tests::check_secrets_file_succeeds::{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]   24:     0x597b4e6420db - <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]   25:     0x597b4e6420db - 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]   26:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   27:     0x597b4e64f2b5 - <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]   28:     0x597b4e64f2b5 - 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]   29:     0x597b4e64f2b5 - 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]   30:     0x597b4e64f2b5 - 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]   31:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   32:     0x597b4e64f2b5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   33:     0x597b4e648ba4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   34:     0x597b4e648ba4 - 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]   35:     0x597b4e652342 - 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]   36:     0x597b4e652342 - <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]   37:     0x597b4e652342 - 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]   38:     0x597b4e652342 - 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]   39:     0x597b4e652342 - 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]   40:     0x597b4e652342 - 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]   41:     0x597b4e652342 - <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]   42:     0x597b4ebbaf2f - <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]   43:     0x597b4ebbaf2f - <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]   44:     0x725454555dfa - <unknown>
[INFO] [stdout]   45:     0x7254545e93d4 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     secret_file::common::tests::check_secrets_file_fails_on_invalid_permissions
[INFO] [stdout]     secret_file::common::tests::check_secrets_file_fails_on_missing_file
[INFO] [stdout]     secret_file::common::tests::check_secrets_file_succeeds
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 124 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.00s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62", kill_on_drop: false }`
[INFO] [stdout] 0a343a4caa19f44df8d2e8b6878d167c60e2b0fd9132a61dadff38e419ac0f62
