On Sun, 11 Sep 2016 21:17:29 +0200, Guilhem Moulin
<guil...@guilhem.org> wrote:

>  Could you try to replace ‘--debug-level basic’ by ‘--debug ipc’?
> The output is quite verbose as it includes the downloaded armored
> key, but I'd like to see how gpg interacts with dirmngr.  For
> instance,

These are the steps I did:

1) I patched a local copy of caff and edited the dirmngr.conf:

---cut---
$ grep ipc ~/bin/local-caff 
        $gpg->options->push_extra_args(qw/--debug ipc/);
---cut---

---cut---
$ cat ~/.gnupg/dirmngr.conf 
debug ipc
log-file /run/user/1000/dirmngr.log

hkp-cacert /home/marcus/.gnupg/sks-keyservers.netCA.pem
---cut---

2) Then I rebooted (I had to reboot for other reasons, but it had
the advantage that I start from a fresh login).

3) After my login I ran the patched caff:

---cut---
$ ~/bin/local-caff --debug 39278DA8109E6244
---cut---

4) Afterwards I also manually ran gpg like in your example:

>     ~$ gpg --keyserver hkps://hkps.pool.sks-keyservers.net --debug
> ipc --recv-keys 39278DA8109E6244 

I have attached both the output of caff and the dirmngr logfile.

The log entries at 11:13 stem from the caff call and the log entries at
11:14 are the result the manual gpg command.

Best regards,
Marcus
-- 
RWTH Aachen University, Institute of Organic Chemistry,
Landoltweg 1, 52074 Aachen, Germany

GPG key: 6C3458EE737CE22C0EF9FA3BE88FEBBE202CE599 (ed25519, preferred)
GPG key: 0138DA92EDFFB27DD270F86DB475E207BAB58229 (dsa1024, disfavoured)
[NOTICE] Importing GnuPG options from ~/.gnupg/gpg.conf:
[NOTICE]     use-agent
[NOTICE]     keyserver hkps://hkps.pool.sks-keyservers.net
[NOTICE]     keyserver-options no-honor-keyserver-url
[NOTICE]     keyserver-options include-revoked
[NOTICE]     no-greeting
[NOTICE]     ask-cert-level
[NOTICE]     set-policy-url http://www.gothgoose.net/pgp/
[NOTICE]     personal-cipher-preferences AES256 AES192 AES CAST5
[NOTICE]     personal-digest-preferences SHA512 SHA384 SHA256 SHA224
[NOTICE]     cert-digest-algo SHA512
[DEBUG] gpg (GnuPG) 2.1.15
gpg-connect-agent: no running gpg-agent - starting '/usr/bin/gpg-agent'
gpg-connect-agent: waiting for the agent to come up ... (5s)
gpg-connect-agent: connection to agent established
gpg-connect-agent: no running Dirmngr - starting '/usr/bin/dirmngr'
gpg-connect-agent: waiting for the dirmngr to come up ... (5s)
gpg-connect-agent: connection to the dirmngr established
[DEBUG] Creating symlink /home/marcus/.caff/gnupghome/S.gpg-agent to 
/run/user/1000/gnupg/S.gpg-agent
[DEBUG] Creating symlink /home/marcus/.caff/gnupghome/S.dirmngr to 
/run/user/1000/gnupg/S.dirmngr
[DEBUG] Exporting key(s) 
E88FEBBE202CE599,B475E207BAB58229,948FD6A0E10F502E,7A0685421E899042 from your 
normal GnuPGHOME to /home/marcus/.caff/gnupghome
[DEBUG] gpg --batch --no-tty --trust-model=always --no-autostart 
--no-auto-check-trustdb ...
[DEBUG] stdin: <&=4, stdout: IO::Handle=GLOB(0x469ad80)
[DEBUG] gpg --batch --no-tty --homedir /home/marcus/.caff/gnupghome --quiet 
--trust-model=always --no-options --use-agent 
--keyserver=hkps://hkps.pool.sks-keyservers.net 
--keyserver-options=no-honor-keyserver-url --keyserver-options=include-revoked 
--no-greeting --ask-cert-level --set-policy-url=http://www.gothgoose.net/pgp/ 
--personal-cipher-preferences=AES256 AES192 AES CAST5 
--personal-digest-preferences=SHA512 SHA384 SHA256 SHA224 
--cert-digest-algo=SHA512 --no-autostart --no-auto-check-trustdb ...
[DEBUG] status: IO::Handle=GLOB(0x3507f48), stdin: 5, stdout: >&=4
[DEBUG] Imported 0138DA92EDFFB27DD270F86DB475E207BAB58229 for B475E207BAB58229
[INFO] Key B475E207BAB58229 not changed
[DEBUG] Imported 53FC5A8727BE1D30FEB4861A948FD6A0E10F502E for 948FD6A0E10F502E
[INFO] Key 948FD6A0E10F502E not changed
[DEBUG] Imported 69E1EE07F7A6AA8E4A777B2A7A0685421E899042 for 7A0685421E899042
[INFO] Key 7A0685421E899042 not changed
[DEBUG] Imported 6C3458EE737CE22C0EF9FA3BE88FEBBE202CE599 for E88FEBBE202CE599
[INFO] Key E88FEBBE202CE599 not changed
[DEBUG] Exporting key(s) 
E88FEBBE202CE599,B475E207BAB58229,948FD6A0E10F502E,7A0685421E899042 from your 
normal GnuPGHOME to /home/marcus/.caff/gnupghome
[DEBUG] gpg --batch --no-tty --trust-model=always --no-autostart 
--no-auto-check-trustdb --no-autostart --no-auto-check-trustdb ...
[DEBUG] stdin: <&=4, stdout: IO::Handle=GLOB(0x469ad80)
[DEBUG] gpg --batch --no-tty --homedir /home/marcus/.caff/gnupghome --quiet 
--trust-model=always --no-options --use-agent 
--keyserver=hkps://hkps.pool.sks-keyservers.net 
--keyserver-options=no-honor-keyserver-url --keyserver-options=include-revoked 
--no-greeting --ask-cert-level --set-policy-url=http://www.gothgoose.net/pgp/ 
--personal-cipher-preferences=AES256 AES192 AES CAST5 
--personal-digest-preferences=SHA512 SHA384 SHA256 SHA224 
--cert-digest-algo=SHA512 --no-autostart --no-auto-check-trustdb ...
[DEBUG] status: IO::Handle=GLOB(0x46ab138), stdout: >&=4, stdin: 5
[DEBUG] Imported 0138DA92EDFFB27DD270F86DB475E207BAB58229 for B475E207BAB58229
[INFO] Key B475E207BAB58229 not changed
[DEBUG] Imported 53FC5A8727BE1D30FEB4861A948FD6A0E10F502E for 948FD6A0E10F502E
[INFO] Key 948FD6A0E10F502E not changed
[DEBUG] Imported 69E1EE07F7A6AA8E4A777B2A7A0685421E899042 for 7A0685421E899042
[INFO] Key 7A0685421E899042 not changed
[DEBUG] Imported 6C3458EE737CE22C0EF9FA3BE88FEBBE202CE599 for E88FEBBE202CE599
[INFO] Key E88FEBBE202CE599 not changed
[DEBUG] gpg --batch --no-tty --homedir /home/marcus/.caff/gnupghome 
--trust-model=always --no-options --use-agent 
--keyserver=hkps://hkps.pool.sks-keyservers.net 
--keyserver-options=no-honor-keyserver-url --keyserver-options=include-revoked 
--no-greeting --ask-cert-level --set-policy-url=http://www.gothgoose.net/pgp/ 
--personal-cipher-preferences=AES256 AES192 AES CAST5 
--personal-digest-preferences=SHA512 SHA384 SHA256 SHA224 
--cert-digest-algo=SHA512 --no-autostart --no-auto-check-trustdb 
--with-fingerprint --with-colons ...
[DEBUG] stdin: <&=4, stdout: IO::Handle=GLOB(0x47af9c0)
gpg: error reading key: No public key
[WARN] gpg exited with value 2
[WARN] No public keys found with list-key 39278DA8109E6244 (note that caff uses 
its own keyring in /home/marcus/.caff/gnupghome)
[NOTICE] No keys to sign found
2016-09-12 11:13:48 dirmngr[2184.0] listening on socket '/run/user/1000/gnupg/S.dirmngr'
2016-09-12 11:13:48 dirmngr[2185.0] permanently loaded certificates: 0
2016-09-12 11:13:48 dirmngr[2185.0]     runtime cached certificates: 0
2016-09-12 11:13:49 dirmngr[2185.0] handler for fd 0 started
2016-09-12 11:13:49 dirmngr[2185.0] DBG: chan_0 -> # Home: /home/marcus/.gnupg
2016-09-12 11:13:49 dirmngr[2185.0] DBG: chan_0 -> # Config: /home/marcus/.gnupg/dirmngr.conf
2016-09-12 11:13:49 dirmngr[2185.0] DBG: chan_0 -> OK Dirmngr 2.1.15 at your service
2016-09-12 11:13:49 dirmngr[2185.0] connection from process 2182 (1000:100)
2016-09-12 11:13:49 dirmngr[2185.0] DBG: chan_0 <- [eof]
2016-09-12 11:13:49 dirmngr[2185.0] handler for fd 0 terminated



2016-09-12 11:14:26 dirmngr[2185.0] handler for fd 0 started
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> # Home: /home/marcus/.gnupg
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> # Config: /home/marcus/.gnupg/dirmngr.conf
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> OK Dirmngr 2.1.15 at your service
2016-09-12 11:14:26 dirmngr[2185.0] connection from process 2243 (1000:100)
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 <- GETINFO version
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> D 2.1.15
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> OK
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 <- KEYSERVER --clear hkps://hkps.pool.sks-keyservers.net
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 -> OK
2016-09-12 11:14:26 dirmngr[2185.0] DBG: chan_0 <- KS_GET -- 0x39278DA8109E6244
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'sks.spodhuis.org'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'prod00.keyserver.dca.witopia.net'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': '[2001:ba8:1f1:f2d4::2]'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'sks.fidocon.de'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': '[2a01:7c8:aabc:45a:5054:ff:fe9b:59a3]'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'b4ckbone.de'
2016-09-12 11:14:27 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'oteiza.siccegge.de'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'keys02.fedoraproject.org'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'sks.spodhuis.org' [already known]
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'flippylosaurus.eu'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'sks.srv.dumain.com'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'ip-209-135-211-141.ragingwire.net'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'cryptonomicon.mit.edu'
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'sks.fidocon.de' [already known]
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'oteiza.siccegge.de' [already known]
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'b4ckbone.de' [already known]
2016-09-12 11:14:28 dirmngr[2185.0] resolve_dns_addr for 'hkps.pool.sks-keyservers.net': 'host-37-191-220-247.lynet.no'
2016-09-12 11:14:29 dirmngr[2185.0] DBG: chan_0 -> S PROGRESS tick ? 0 0
2016-09-12 11:14:29 dirmngr[2185.0] DBG: chan_0 -> S SOURCE https://cryptonomicon.mit.edu:443
2016-09-12 11:14:29 dirmngr[2185.0] DBG: chan_0 -> D -----BEGIN PGP PUBLIC KEY BLOCK-----%0A
2016-09-12 11:14:29 dirmngr[2185.0] DBG: chan_0 -> D Version: SKS 1.1.5%0A
[...]
2016-09-12 11:14:30 dirmngr[2185.0] DBG: chan_0 -> D -----END PGP PUBLIC KEY BLOCK-----%0D%0A
2016-09-12 11:14:30 dirmngr[2185.0] DBG: chan_0 -> OK
2016-09-12 11:14:31 dirmngr[2185.0] DBG: chan_0 <- BYE
2016-09-12 11:14:31 dirmngr[2185.0] DBG: chan_0 -> OK closing connection
2016-09-12 11:14:31 dirmngr[2185.0] handler for fd 0 terminated

Attachment: pgpy3oqopubzF.pgp
Description: OpenPGP digital signature

Reply via email to