Broken pipe? [was: Problems svn+ssh+gpg(-agent)+smartcard]

[Guillaume Yziquel]

>> Is their anyway to monitor more closely what is happening than just
>> looking at the logs?
> 
> scdaemon can be configured to produce logs.
> 
> James

OK. I've a little more time to invest into solving this issue. I just
turned the debugging level to "basic" in both gpg-agent and scdaemon's
logging files. I also added the debug-ccid-driver into scdaemon.conf.

When failing to decrypt and failing to access the SCR 335 smartcard
reader, I get the logged output decribed below this message.

It seems I have a broken pipe issue:

> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver: usb_claim_interface failed: -1
> 2007-06-28 15:32:31 scdaemon[4291] error sending PC/SC OPEN request: Relais brisé (pipe)

Any further information, help, guidance, hints, suggested readings is
welcome.

> yziquel at seldon:~/var/log$ tail --lines=22 gpg-agent.log
> 2007-06-28 15:23:14 gpg-agent[3905] SIGTERM received - shutting down ...
> 2007-06-28 15:23:14 gpg-agent[3905] gpg-agent (GnuPG) 2.0.4 stopped
> 2007-06-28 15:30:09 gpg-agent[3876] listening on socket `/tmp/gpg-vON33D/S.gpg-agent'
> 2007-06-28 15:30:09 gpg-agent[3876] listening on socket `/tmp/gpg-j2fBIM/S.gpg-agent.ssh'
> 2007-06-28 15:32:31 gpg-agent[3877] handler 0x651790 for fd 8 started
> gpg-agent[3877.8] DBG: -> OK Pleased to meet you
> gpg-agent[3877.8] DBG: <- OPTION display=:0.0
> gpg-agent[3877.8] DBG: -> OK
> gpg-agent[3877.8] DBG: <- OPTION ttyname=/dev/tty
> gpg-agent[3877.8] DBG: -> OK
> gpg-agent[3877.8] DBG: <- OPTION lc-ctype=fr_CH.UTF-8
> gpg-agent[3877.8] DBG: -> OK
> gpg-agent[3877.8] DBG: <- OPTION lc-messages=fr_CH.UTF-8
> gpg-agent[3877.8] DBG: -> OK
> gpg-agent[3877.8] DBG: <- SCD SERIALNO openpgp
> 2007-06-28 15:32:31 gpg-agent[3877] no running SCdaemon - starting it
> 2007-06-28 15:32:31 gpg-agent[3877] DBG: first connection to SCdaemon established
> 2007-06-28 15:32:31 gpg-agent[3877] DBG: additional connections at `/tmp/gpg-IJforS/S.scdaemon'
> gpg-agent[3877.8] DBG: -> ERR 100663356 Non supporté <SCD>
> gpg-agent[3877.8] DBG: <- BYE
> gpg-agent[3877.8] DBG: -> OK closing connection
> 2007-06-28 15:32:31 gpg-agent[3877] handler 0x651790 for fd 8 terminated
> yziquel at seldon:~/var/log$

And also:

> yziquel at seldon:~/var/log$ tail --lines=50 scdaemon.log
> 2007-06-28 14:23:11 scdaemon[4591] can't select application `openpgp': Non supporté
> 2007-06-28 14:23:16 scdaemon[4591] can't select application `openpgp': Non supporté
> 2007-06-28 15:23:14 scdaemon[4591] SIGTERM received - shutting down ...
> 2007-06-28 15:23:14 scdaemon[4591] scdaemon (GnuPG) 2.0.0 stopped
> 2007-06-28 15:32:31 scdaemon[4291] listening on socket `/tmp/gpg-IJforS/S.scdaemon'
> 2007-06-28 15:32:31 scdaemon[4291] handler for fd -1 started
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver: using CCID reader 0 (ID=04E6:5115:X:0)
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver: idVendor: 04E6  idProduct: 5115  bcdDevice: 0518
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver: ChipCard Interface Descriptor:
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bLength                54
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bDescriptorType        33
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bcdCCID              1.00
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   nMaxSlotIndex           0
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bVoltageSupport         1  5.0V
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwProtocols             3  T=0 T=1
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwDefaultClock       4000
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwMaxiumumClock     12000
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bNumClockSupported      0
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwDataRate           9600 bps
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwMaxDataRate      307200 bps
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bNumDataRatesSupp.      0
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwMaxIFSD             252
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwSyncProtocols  00000000
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwMechanical     00000000
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwFeatures       000100BA
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     Auto configuration based on ATR
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     Auto voltage selection
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     Auto clock change
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     Auto baud rate change
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     Auto PPS made by CCID
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:     TPDU level exchange
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   dwMaxCCIDMsgLen       263
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bClassGetResponse    echo
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bClassEnvelope       echo
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   wlcdLayout           none
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bPINSupport             0
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver:   bMaxCCIDBusySlots       1
> 2007-06-28 15:32:31 scdaemon[4291] DBG: ccid-driver: usb_claim_interface failed: -1
> 2007-06-28 15:32:31 scdaemon[4291] error sending PC/SC OPEN request: Relais brisé (pipe)
> scdaemon[4291.0] DBG: -> OK GNU Privacy Guard's Smartcard server ready
> scdaemon[4291.0] DBG: <- GETINFO socket_name
> scdaemon[4291.0] DBG: -> D /tmp/gpg-IJforS/S.scdaemon
> scdaemon[4291.0] DBG: -> OK
> scdaemon[4291.0] DBG: <- OPTION event-signal=12
> scdaemon[4291.0] DBG: -> OK
> scdaemon[4291.0] DBG: <- SERIALNO openpgp
> 2007-06-28 15:32:31 scdaemon[4291] can't select application `openpgp': Non supporté
> scdaemon[4291.0] DBG: -> ERR 100663356 Non supporté <SCD>
> scdaemon[4291.0] DBG: <- RESTART
> scdaemon[4291.0] DBG: -> OK
> yziquel at seldon:~/var/log$