Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

help needed: using Yubikey, no touch ID prompt, bad PIN error #20

Open
davidxia opened this issue Feb 6, 2022 · 6 comments
Open

help needed: using Yubikey, no touch ID prompt, bad PIN error #20

davidxia opened this issue Feb 6, 2022 · 6 comments

Comments

@davidxia
Copy link

davidxia commented Feb 6, 2022 •
edited
Loading

Thanks for creating this tool!

environment

I'm using M1 Mac, OS X 12.1, gpg 2.3.4, pinentry-mac 1.1.1.1, pinentry-touchid 0.0.2. My GPG private key is on my Yubikey 5C NFC.

Problem

If I use pinentry-touchid in ~/.gnupg/gpg-agent.conf

pinentry-program /opt/homebrew/opt/pinentry-touchid/bin/pinentry-touchid

and run echo 1234 | gpg -as -, I see

gpg: signing failed: Bad PIN
-----BEGIN PGP MESSAGE-----

gpg: signing failed: Bad PIN

I'm not prompted for a PIN or for touch ID.

What I expect

To be prompted for touch ID, then get encrypted message.

More info

/tmp/pinentry-touchid.log says 2022/02/06 18:19:49 main.go:105: Ready!

gpg-agent.log says

2022-02-06 18:19:49 gpg-agent[95746] starting a new PIN Entry
2022-02-06 18:19:49 gpg-agent[95746] DBG: connection to PIN entry established
2022-02-06 18:19:49 gpg-agent[95746] You may want to update to a newer pinentry
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_9 -> [ 44 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ...(76 byte(s) skipped) ]
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_9 -> END
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_9 <- ERR 100663383 Bad PIN <SCD>
2022-02-06 18:19:50 gpg-agent[95746] smartcard signing failed: Bad PIN
2022-02-06 18:19:50 gpg-agent[95746] command 'PKSIGN' failed: Bad PIN <SCD>
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_8 -> ERR 100663383 Bad PIN <SCD>
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_8 <- [eof]
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_9 -> RESTART
2022-02-06 18:19:50 gpg-agent[95746] DBG: chan_9 <- OK

I know my Yubikey and pinentry-mac are working because if I use pinentry-mac in ~/.gnupg/gpg-agent.conf

pinentry-program /opt/homebrew/bin/pinentry-mac

and run echo 1234 | gpg -as -, I'm prompted for my PIN, then the encrypted message shows up in my terminal.
@ka2er
Copy link

ka2er commented Feb 8, 2022

same issue

@jorgelbg
Copy link
Owner

jorgelbg commented Aug 4, 2022

Could you enable the debug options for gpg-agent? It seems that it is sending something to pinentry-touchid and but it is just getting an END as a reply? I haven't currently tested this with a Yubikey, but I will give it a try. You can also check the output of $TMPDIR/pinentry-touchid.log to see if anything interesting gets logged.

@davidxia
Copy link
Author

davidxia commented Aug 8, 2022

@jorgelbg Here's my ~/.gnupg/gpg-agent.conf

enable-ssh-support
default-cache-ttl 600
max-cache-ttl 7200
pinentry-program /opt/homebrew/opt/pinentry-touchid/bin/pinentry-touchid
debug-level guru
log-file /Users/dxia/.gnupg/gpg-agent.log

Here's the output of tail -f /Users/dxia/.gnupg/gpg-agent.log. First I ran gpgconf --kill gpg-agent && gpg-connect-agent reloadagent /bye. Then after the gap below, I ran echo 1234 | gpg -as -. It looks like gpg-agent is trying to ask me to enter the key's secret key, but I am not prompted for it, and the encryption fails.

2022-08-07 21:41:23 gpg-agent[89402] listening on socket '/Users/dxia/.gnupg/S.gpg-agent'
2022-08-07 21:41:23 gpg-agent[89402] listening on socket '/Users/dxia/.gnupg/S.gpg-agent.extra'
2022-08-07 21:41:23 gpg-agent[89402] listening on socket '/Users/dxia/.gnupg/S.gpg-agent.browser'
2022-08-07 21:41:23 gpg-agent[89402] listening on socket '/Users/dxia/.gnupg/S.gpg-agent.ssh'
2022-08-07 21:41:23 gpg-agent[89403] gpg-agent (GnuPG) 2.3.7 started
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK Pleased to meet you, process 89401
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- RESET
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- OPTION ttyname=/dev/ttys005
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- OPTION ttytype=xterm-256color
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- OPTION lc-ctype=en_US.UTF-8
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- OPTION lc-messages=en_US.UTF-8
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- reloadagent
2022-08-07 21:41:23 gpg-agent[89403] SIGHUP received - re-reading configuration and flushing cache
2022-08-07 21:41:23 gpg-agent[89403] DBG: agent_flush_cache
2022-08-07 21:41:23 gpg-agent[89403] reading options from '/Users/dxia/.gnupg/gpg-agent.conf'
2022-08-07 21:41:23 gpg-agent[89403] enabled debug flags: mpi crypto memory cache memstat hashing ipc
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:23 gpg-agent[89403] DBG: chan_8 <- [eof]
2022-08-07 21:41:27 gpg-agent[89403] DBG: agent_cache_housekeeping



2022-08-07 21:41:31 gpg-agent[89403] DBG: agent_cache_housekeeping
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK Pleased to meet you, process 89415
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- RESET
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION ttyname=/dev/ttys005
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION ttytype=xterm-256color
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION lc-ctype=en_US.UTF-8
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION lc-messages=en_US.UTF-8
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- GETINFO version
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> D 2.3.7
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION allow-pinentry-notify
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- OPTION agent-awareness=2.1.0
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SCD SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] no running /opt/homebrew/Cellar/gnupg/2.3.7_1/libexec/scdaemon daemon - starting it
2022-08-07 21:41:33 gpg-agent[89403] DBG: agent_flush_cache (pincache only)
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK GNU Privacy Guard's Smartcard server ready
2022-08-07 21:41:33 gpg-agent[89403] first connection to daemon /opt/homebrew/Cellar/gnupg/2.3.7_1/libexec/scdaemon established
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETINFO socket_name
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- D /Users/dxia/.gnupg/S.scdaemon
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: additional connections at '/Users/dxia/.gnupg/S.scdaemon'
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> OPTION event-signal=31
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SCD SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] SIGUSR2 received - updating card event counter
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SCD GETATTR KEY-FPR
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETATTR KEY-FPR
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEY-FPR 1 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S KEY-FPR 1 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEY-FPR 2 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S KEY-FPR 2 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEY-FPR 3 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S KEY-FPR 3 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- READKEY --card --no-data -- $SIGNKEYID
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETATTR SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETATTR $SIGNKEYID
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S $SIGNKEYID OPENPGP.1
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> READKEY -- OPENPGP.1
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- [ 44 20 28 31 30 3a 70 75 62 6c 69 63 2d 6b 65 79 ...(546 byte(s) skipped) ]
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- READKEY --card --no-data -- $ENCRKEYID
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETATTR SERIALNO
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> GETATTR $ENCRKEYID
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S $ENCRKEYID OPENPGP.2
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> READKEY -- OPENPGP.2
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- [ 44 20 28 31 30 3a 70 75 62 6c 69 63 2d 6b 65 79 ...(546 byte(s) skipped) ]
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- KEYINFO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> KEYINFO --list
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEYINFO *** T *** OPENPGP.1 sc
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEYINFO *** T *** OPENPGP.2 e
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEYINFO *** T *** OPENPGP.3 sa
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: agent_get_cache '***'.0 (mode 2) ...
2022-08-07 21:41:33 gpg-agent[89403] DBG: ... miss
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> S KEYINFO *** T *** OPENPGP.1 - - - - A
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- RESET
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SIGKEY ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SETKEYDESC Please+enter+the+passphrase+to+unlock+the+OpenPGP+secret+key:%0A%22David+Xia+(personal+PGP+key)+<[email protected]>%22%0A4096-bit+RSA+key,+ID+0x5F38B0A1B60734DF,%0Acreated+2015-05-01+(main+key+ID+0x34469DFBDC90312C).%0A
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- SETHASH 10 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 -> OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_8 <- PKSIGN
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> SERIALNO --all
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S SERIALNO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> KEYINFO ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- S KEYINFO *** T *** OPENPGP.1 sc
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: encoded hash: *** \
2022-08-07 21:41:33 gpg-agent[89403] DBG:  *** \
2022-08-07 21:41:33 gpg-agent[89403] DBG:  ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> SETDATA ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- OK
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 -> PKSIGN --hash=sha512 ***
2022-08-07 21:41:33 gpg-agent[89403] DBG: chan_9 <- [ 49 4e 51 55 49 52 45 20 4e 45 45 44 50 49 4e 20 ...(88 byte(s) skipped) ]
2022-08-07 21:41:33 gpg-agent[89403] starting a new PIN Entry
2022-08-07 21:41:33 gpg-agent[89403] DBG: connection to PIN entry established
2022-08-07 21:41:33 gpg-agent[89403] You may want to update to a newer pinentry
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_9 -> [ 44 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ...(76 byte(s) skipped) ]
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_9 -> END
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_9 <- ERR 100663383 Bad PIN <SCD>
2022-08-07 21:41:34 gpg-agent[89403] smartcard signing failed: Bad PIN
2022-08-07 21:41:34 gpg-agent[89403] command 'PKSIGN' failed: Bad PIN <SCD>
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_8 -> ERR 100663383 Bad PIN <SCD>
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_8 <- [eof]
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_9 -> RESTART
2022-08-07 21:41:34 gpg-agent[89403] DBG: chan_9 <- OK

@whoo
Copy link

whoo commented Sep 26, 2023 •
edited
Loading

Hi,
I've got the same issue.
Normal key ... it works (following documentation).
Key on Yubikey not.

image

Many thanks.

@phoerious
Copy link

I tested it, smart cards seem to be unsupported, you only get this double PIN prompt. Since you have to enter the PIN twice every time, this is objectively worse than the normal pinentry-mac programme.

@marvinruder
Copy link

I have a similar issue:

echo 1234 | gpg -as -
gpg: using "…" as default secret key for signing
gpg: signing failed: Operation cancelled
-----BEGIN PGP MESSAGE-----

gpg: signing failed: Operation cancelled
gpg-agent.log 
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- RESET
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION ttyname=/dev/ttys002
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION ttytype=xterm-ghostty
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION display=/private/tmp/com.apple.launchd.LpM1g6XxPd/org.xquartz:0
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION lc-ctype=en_US.UTF-8
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION lc-messages=en_US.UTF-8
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- GETINFO version
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> D 2.4.6
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION allow-pinentry-notify
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- OPTION agent-awareness=2.1.0
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SCD SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] new connection to /opt/homebrew/Cellar/gnupg/2.4.6/libexec/scdaemon daemon established (reusing)
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SCD SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SCD GETATTR KEY-FPR
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> GETATTR KEY-FPR
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEY-FPR 1 BED76F3CEFF2B7AB8A5AD1CE0450082FA5ECD91C
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S KEY-FPR 1 BED76F3CEFF2B7AB8A5AD1CE0450082FA5ECD91C
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEY-FPR 2 78B727744A5A76A078C94FFF81E9FD7DD7745C35
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S KEY-FPR 2 78B727744A5A76A078C94FFF81E9FD7DD7745C35
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEY-FPR 3 852176C60BDB7777E03707B129AF9481935FE98A
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S KEY-FPR 3 852176C60BDB7777E03707B129AF9481935FE98A
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- READKEY --card --no-data -- $SIGNKEYID
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> GETATTR SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> GETATTR $SIGNKEYID
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S $SIGNKEYID OPENPGP.1
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> READKEY -- OPENPGP.1
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- [ 44 20 28 31 30 3a 70 75 62 6c 69 63 2d 6b 65 79 ...(135 byte(s) skipped) ]
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- READKEY --card --no-data -- $ENCRKEYID
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> GETATTR SERIALNO
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> GETATTR $ENCRKEYID
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S $ENCRKEYID OPENPGP.2
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> READKEY -- OPENPGP.2
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- [ 44 20 28 31 30 3a 70 75 62 6c 69 63 2d 6b 65 79 ...(137 byte(s) skipped) ]
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- KEYINFO 4ECD7925EA21EBDFE2132DC61215B4467E602B99
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> KEYINFO --list
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEYINFO 4ECD7925EA21EBDFE2132DC61215B4467E602B99 T D2760001240100000006152818850000 OPENPGP.1 sc
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEYINFO 096F855207422C4B86183E5216078B456E4784A1 T D2760001240100000006152818850000 OPENPGP.2 e
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEYINFO 74DBF2F8CF62368AD43F3FEE2C29577E156FB957 T D2760001240100000006152818850000 OPENPGP.3 sa
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEYINFO DE8E1C98F4821BB0E7A950F3E2065028E7BD2A01 T D2760001240100000006152818850000 PIV.9C sc
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> S KEYINFO 4ECD7925EA21EBDFE2132DC61215B4467E602B99 T D2760001240103040006152818850000 OPENPGP.1 - - - - A
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- HAVEKEY --list=1000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> [ 44 20 74 db f2 f8 cf 62 36 8a d4 3f 3f ee 2c 29 ...(646 byte(s) skipped) ]
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- RESET
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SIGKEY 4ECD7925EA21EBDFE2132DC61215B4467E602B99
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SETKEYDESC Please+enter+the+passphrase+to+unlock+the+OpenPGP+secret+key:%0A%22Marvin+Ruder+<[email protected]>%22%0A384-bit+ECDSA+key,+ID+0450082FA5ECD91C,%0Acreated+2024-01-30.%0A
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- SETHASH 9 0A6CB1D5185BBE0FAA6DBBF1C6CE0D2E880626217CCD68317D6ABEE06DFFD1C49B49E41D8B6ADFBB90A71C2358E34985
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- PKSIGN
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> SERIALNO --all
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S SERIALNO D2760001240100000006152818850000
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> KEYINFO 4ECD7925EA21EBDFE2132DC61215B4467E602B99
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- S KEYINFO 4ECD7925EA21EBDFE2132DC61215B4467E602B99 T D2760001240100000006152818850000 OPENPGP.1 sc
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> SETDATA 3041300D0609608648016503040202050004300A6CB1D5185BBE0FAA6DBBF1C6CE0D2E880626217CCD68317D6ABEE06DFFD1C49B49E41D8B6ADFBB90A71C2358E34985
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> PKSIGN --hash=sha384 4ECD7925EA21EBDFE2132DC61215B4467E602B99
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- [ 49 4e 51 55 49 52 45 20 4e 45 45 44 50 49 4e 20 ...(92 byte(s) skipped) ]
2024-11-22 17:00:14 gpg-agent[96589] starting a new PIN Entry
2024-11-22 17:00:14 gpg-agent[96589] DBG: connection to PIN entry established
2024-11-22 17:00:14 gpg-agent[96589] You may want to update to a newer pinentry
2024-11-22 17:00:14 gpg-agent[96589] DBG: error calling pinentry: Operation cancelled <Pinentry>
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> CAN
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- ERR 100663573 IPC call has been cancelled <SCD>
2024-11-22 17:00:14 gpg-agent[96589] smartcard signing failed: Operation cancelled
2024-11-22 17:00:14 gpg-agent[96589] command 'PKSIGN' failed: Operation cancelled <Pinentry>
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 -> ERR 83886179 Operation cancelled <Pinentry>
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_8 <- [eof]
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 -> RESTART
2024-11-22 17:00:14 gpg-agent[96589] DBG: chan_9 <- OK

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
6 participants
@ka2er @davidxia @phoerious @jorgelbg @whoo @marvinruder