[Bug 197307] upgrade to thunderbird 31.4.0_1 broke enigmail?

bugzilla-noreply at freebsd.org bugzilla-noreply at freebsd.org
Wed Feb 4 02:27:28 UTC 2015 

https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=197307

            Bug ID: 197307
           Summary: upgrade to thunderbird 31.4.0_1 broke enigmail?
           Product: Ports & Packages
           Version: Latest
          Hardware: Any
                OS: Any
            Status: New
          Severity: Affects Only Me
          Priority: ---
         Component: Individual Port(s)
          Assignee: freebsd-ports-bugs at FreeBSD.org
          Reporter: heikki at suonsivu.net

I upgraded my packages including thunderbird to from 31.3.0 to 31.4.0_1.  This
also upgraded gnupg from 2.1.0_1 to 2.1.1_1.  After upgrade and restarting
thunderbird, enigmail fails in various ways:

- Cannot send mail, as Enigmail says "Send operation aborted   Error -
encryption command failed".

- When trying to open a draft email thunderbird says it cannot decrypt it. 
Enigmail security info says 

Observations:  

- If I save the draft email which cannot be opened and gpg -d it, it decrypts
fine. 

- If I sign with gpg, it also works.

So it seems that something broke in Thunderbird in the last upgrade.

In the enigdbug log file, it seems to start gpg, and launch pinentry, but
immediately fails, so it might be that the pin entry bit does not work.  I get
no pin entry window like I did earlier.


The enigmail log file contains, when trying to send mail:



2015-02-04 04:08:12.967 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.sendMessageListener
2015-02-04 04:08:12.967 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.modifyCompFields: otherRandomHeaders = 
2015-02-04 04:08:12.967 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.encryptMsg: msgSendType=0, Enigmail.msg.sendMode=1,
Enigmail.msg.statusEncrypted=11
2015-02-04 04:08:12.968 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.encryptMsg: currentId=[nsIMsgIdentity: id1], heikki at suonsivu.net
2015-02-04 04:08:12.968 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.encryptMsg:gMsgCompose=[xpconnect wrapped nsIMsgCompose]
2015-02-04 04:08:12.968 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.encryptMsg: BCC: hsu at bbnetworks.net
2015-02-04 04:08:20.104 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.keySelection()
2015-02-04 04:08:20.104 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.keySelection(): toAddrStr="hsu at iki.fi, hsu at bbnetworks.net"
bccAddrStr=""
2015-02-04 04:08:20.104 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.processRules(): toAddrStr="hsu at iki.fi, hsu at bbnetworks.net"
bccAddrStr="" forceRecipientSettings=false
2015-02-04 04:08:20.104 [DEBUG] enigmailMsgComposeHelper.js:
getRecipientsKeys(): emailAddrs="hsu at iki.fi, hsu at bbnetworks.net"
interactive=true forceRecipientSettings=false
2015-02-04 04:08:20.105 [DEBUG] enigmail.js: getRulesData
2015-02-04 04:08:20.105 [DEBUG] enigmail.js: loadRulesFile
2015-02-04 04:08:20.105 [DEBUG] enigmail.js: getRulesFile
2015-02-04 04:08:20.105 [DEBUG] enigmailMsgComposeHelper.js:
validKeysForAllRecipients(): emailAddrs="hsu at iki.fi, hsu at bbnetworks.net"
refresh="true"
2015-02-04 04:08:20.105 [DEBUG] enigmailMsgComposeHelper.js:
validKeysForAllRecipients(): find keys with minTrustLevel="?"
2015-02-04 04:08:20.105 [DEBUG] enigmailFuncs.jsm: loadKeyList
2015-02-04 04:08:20.105 [DEBUG] enigmailFuncs.jsm: obtainKeyList
2015-02-04 04:08:20.105 enigmail.js: Enigmail.execCmd: subprocess =
'/usr/local/bin/gpg'
2015-02-04 04:08:20.124 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
line/env/input to files /usr/users/hsu/enigcmd.txt/enigenv.txt/eniginp.txt
2015-02-04 04:08:20.125 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 --with-fingerprint
--fixed-list-mode --with-colons --list-keys
2015-02-04 04:08:20.296 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
out/err data to files /usr/users/hsu/enigout.txt/enigerr.txt
2015-02-04 04:08:20.296 [DEBUG] enigmail.js: Enigmail.execCmd: exitCode = 0
2015-02-04 04:08:20.297 [DEBUG] enigmail.js: Enigmail.execCmd: errOutput = gpg:
Warning: using insecure memory!

2015-02-04 04:08:20.297 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!

2015-02-04 04:08:20.297 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00000000
2015-02-04 04:08:20.297 [CONSOLE] gpg: Warning: using insecure memory!
2015-02-04 04:08:20.297 [DEBUG] enigmailCommon.jsm: stillActive: 
2015-02-04 04:08:20.297 [DEBUG] enigmailFuncs.jsm: obtainKeyList
2015-02-04 04:08:20.298 enigmail.js: Enigmail.execCmd: subprocess =
'/usr/local/bin/gpg'
2015-02-04 04:08:20.317 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
line/env/input to files /usr/users/hsu/enigcmd.txt/enigenv.txt/eniginp.txt
2015-02-04 04:08:20.317 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 --with-fingerprint
--fixed-list-mode --with-colons --list-secret-keys
2015-02-04 04:08:20.502 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
out/err data to files /usr/users/hsu/enigout.txt/enigerr.txt
2015-02-04 04:08:20.503 [DEBUG] enigmail.js: Enigmail.execCmd: exitCode = 0
2015-02-04 04:08:20.503 [DEBUG] enigmail.js: Enigmail.execCmd: errOutput = gpg:
Warning: using insecure memory!

2015-02-04 04:08:20.503 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!

2015-02-04 04:08:20.503 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00000000
2015-02-04 04:08:20.503 [CONSOLE] gpg: Warning: using insecure memory!
2015-02-04 04:08:20.503 [DEBUG] enigmailCommon.jsm: stillActive: 
2015-02-04 04:08:20.504 enigmail.js: Enigmail.execCmd: subprocess =
'/usr/local/bin/gpg'
2015-02-04 04:08:20.525 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
line/env/input to files /usr/users/hsu/enigcmd.txt/enigenv.txt/eniginp.txt
2015-02-04 04:08:20.526 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 --fixed-list-mode
--with-colons --list-config
2015-02-04 04:08:20.695 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
out/err data to files /usr/users/hsu/enigout.txt/enigerr.txt
2015-02-04 04:08:20.695 [DEBUG] enigmail.js: Enigmail.execCmd: exitCode = 0
2015-02-04 04:08:20.696 [DEBUG] enigmail.js: Enigmail.execCmd: errOutput = gpg:
Warning: using insecure memory!

2015-02-04 04:08:20.696 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!

2015-02-04 04:08:20.696 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00000000
2015-02-04 04:08:20.696 [CONSOLE] gpg: Warning: using insecure memory!
2015-02-04 04:08:20.696 [DEBUG] enigmailCommon.jsm: stillActive: 
2015-02-04 04:08:20.696 [DEBUG] enigmailMsgComposeHelper.js:
getValidKeyForRecipient(): emailAddr="hsu at iki.fi"
2015-02-04 04:08:20.696 [DEBUG] enigmailMsgComposeHelper.js:
getValidKeyForRecipient(): no key found
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeHelper.js:
validKeysForAllRecipients(): return null (no single valid key found
for="hsu at iki.fi" with minTrustLevel="?")
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.processFinalState()
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false
encryptByRules=1 encFinally=0
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeOverlay.js:   signed=true
signByRules=1 signFinally=1
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeOverlay.js:  
pgpmimeByRules=1 pgpmimeFinally=0
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeHelper.js:
getRecipientsKeys(): emailAddrs="" interactive=true
forceRecipientSettings=false
2015-02-04 04:08:20.697 [DEBUG] enigmail.js: getRulesData
2015-02-04 04:08:20.697 [DEBUG] enigmail.js: loadRulesFile
2015-02-04 04:08:20.697 [DEBUG] enigmail.js: getRulesFile
2015-02-04 04:08:20.697 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.keySelection(): return toAddrStr="hsu at iki.fi, hsu at bbnetworks.net"
bccAddrStr=""
2015-02-04 04:08:20.698 [DEBUG] enigmailMsgComposeOverlay.js: hasAttachments =
false
2015-02-04 04:08:20.701 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.editorGetContentAs
2015-02-04 04:08:20.701 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.replaceEditorText:
2015-02-04 04:08:20.702 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.editorInsertText
2015-02-04 04:08:20.703 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.editorInsertText
2015-02-04 04:08:20.703 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.editorGetContentAs
2015-02-04 04:08:20.704 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.editorGetCharset
2015-02-04 04:08:20.704 [DEBUG] enigmailMsgComposeOverlay.js:
Enigmail.msg.encryptMsg: charset=utf-8
2015-02-04 04:08:20.704 [DEBUG] enigmail.js: Enigmail.encryptMessage: 7 bytes
from 0x1829841E to hsu at iki.fi, hsu at bbnetworks.net (97)
2015-02-04 04:08:20.704 [DEBUG] enigmailCommon.jsm: encryptMessageStart:
uiFlags=1, from 0x1829841E to hsu at iki.fi, hsu at bbnetworks.net,
hashAlgorithm=null (00000061)
2015-02-04 04:08:20.704 [DEBUG] enigmailCommon.jsm: getEncryptCommand:
hashAlgorithm=null
2015-02-04 04:08:20.704 enigmailCommon.jsm: execStart: command =
/usr/local/bin/gpg --charset utf-8 --display-charset utf-8 --batch --no-tty
--status-fd 2 -t --clearsign -u 0x1829841E, needPassphrase=1, domWindow=[object
ChromeWindow], listener=[object Object]
2015-02-04 04:08:20.704 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:08:20.704 [DEBUG] enigmailCommon.jsm: getPassphrase:
2015-02-04 04:08:20.705 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:08:20.705 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 -t --clearsign -u
0x1829841E --use-agent
2015-02-04 04:08:20.806 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:08:20.923 [DEBUG] enigmailCommon.jsm: encryptMessageEnd:
uiFlags=1, sendFlags=00000061, outputLen=56
2015-02-04 04:08:20.923 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!
[GNUPG:] BEGIN_SIGNING H8
[GNUPG:] PINENTRY_LAUNCHED 2280
gpg: allekirjoitus epäonnistui: Operation cancelled
gpg: [stdin]: clearsign failed: Operation cancelled

2015-02-04 04:08:20.924 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00000000
2015-02-04 04:08:20.924 [DEBUG] enigmailCommon.jsm: encryptMessageEnd: command
execution exit code: 2
2015-02-04 04:08:20.924 [DEBUG] enigmail.js: Enigmail.encryptMessage: command
execution exit code: 2



When trying open a draft message, the log file contains:


2015-02-04 04:22:29.679 [DEBUG] enigmailMessengerOverlay.js: messageFrameUnload
2015-02-04 04:22:29.679 [DEBUG] enigmailMessengerOverlay.js: messageCleanup
2015-02-04 04:22:29.679 [DEBUG] enigmailMessengerOverlay.js:
setAttachmentReveal
2015-02-04 04:22:29.679 [DEBUG] enigmailMsgHdrViewOverlay.js:
this.messageUnload
2015-02-04 04:22:29.767 [DEBUG] enigmailMsgHdrViewOverlay.js:
_listener_onStartHeaders
2015-02-04 04:22:29.767 [DEBUG] enigmailMessengerOverlay.js:
setAttachmentReveal
2015-02-04 04:22:29.767 [DEBUG] enigmailCommon.jsm: getFrame: name=messagepane
2015-02-04 04:22:29.768 [DEBUG] enigmailMsgHdrViewOverlay.js: msgFrame=[object
XrayWrapper [object Window]]
2015-02-04 04:22:29.768 [DEBUG] enigmailCommon.jsm: forgetEncryptedUri:
uri=imap-message://heikki@mail.bbnetworks.net/Drafts#15278
2015-02-04 04:22:29.768 [DEBUG] enigmailMsgHdrViewOverlay.js:
enigmailPrepSecurityInfo
2015-02-04 04:22:29.786 [DEBUG] enigmailMsgHdrViewOverlay.js:
_listener_onEndHeaders
2015-02-04 04:22:29.786 [DEBUG] enigmailMessengerOverlay.js:
setAttachmentReveal
2015-02-04 04:22:29.845 [DEBUG] enigmailCommon.jsm: decryptMessageStart:
verifyOnly=false
2015-02-04 04:22:29.845 enigmailCommon.jsm: execStart: command =
/usr/local/bin/gpg --charset utf-8 --display-charset utf-8 --batch --no-tty
--status-fd 2 --max-output 125500 --decrypt, needPassphrase=true,
domWindow=[object ChromeWindow], listener=[object Object]
2015-02-04 04:22:29.845 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:22:29.846 [DEBUG] enigmailCommon.jsm: getPassphrase:
2015-02-04 04:22:29.846 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:22:29.846 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 --max-output 125500
--decrypt --use-agent
2015-02-04 04:22:29.927 [DEBUG] enigmail.js: Setting useAgent to true for gpg2
>= 2.0.16
2015-02-04 04:22:30.110 [DEBUG] enigmailCommon.jsm: decryptMessageEnd:
uiFlags=8, verifyOnly=false, noOutput=false
2015-02-04 04:22:30.111 [DEBUG] enigmailCommon.jsm: decryptMessageEnd:
stderrStr=
gpg: Warning: using insecure memory!
[GNUPG:] ENC_TO 3D08188F6B052631 1 0
[GNUPG:] PINENTRY_LAUNCHED 2324
gpg: encrypted with 4096-bit RSA key, ID 6B052631, created 2014-12-08
      "Heikki Suonsivu <heikki at suonsivu.net>"
gpg: julkisen avaimen avaus epäonnistui: Operation cancelled
[GNUPG:] ERROR pkdecrypt_failed 83886179
[GNUPG:] BEGIN_DECRYPTION
[GNUPG:] DECRYPTION_FAILED
gpg: avaus epäonnistui: No secret key
[GNUPG:] END_DECRYPTION

2015-02-04 04:22:30.111 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!
[GNUPG:] ENC_TO 3D08188F6B052631 1 0
[GNUPG:] PINENTRY_LAUNCHED 2324
gpg: encrypted with 4096-bit RSA key, ID 6B052631, created 2014-12-08
      "Heikki Suonsivu <heikki at suonsivu.net>"
gpg: julkisen avaimen avaus epäonnistui: Operation cancelled
[GNUPG:] ERROR pkdecrypt_failed 83886179
[GNUPG:] BEGIN_DECRYPTION
[GNUPG:] DECRYPTION_FAILED
gpg: avaus epäonnistui: No secret key
[GNUPG:] END_DECRYPTION

2015-02-04 04:22:30.111 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00010002
2015-02-04 04:22:30.112 enigmail.js: Enigmail.execCmd: subprocess =
'/usr/local/bin/gpg'
2015-02-04 04:22:30.131 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
line/env/input to files /usr/users/hsu/enigcmd.txt/enigenv.txt/eniginp.txt
2015-02-04 04:22:30.131 [CONSOLE] enigmail> /usr/local/bin/gpg --charset utf-8
--display-charset utf-8 --batch --no-tty --status-fd 2 --fixed-list-mode
--with-colons --list-keys 0x3D08188F6B052631
2015-02-04 04:22:30.333 [DEBUG] enigmail.js: Enigmail.execCmd: copied command
out/err data to files /usr/users/hsu/enigout.txt/enigerr.txt
2015-02-04 04:22:30.333 [DEBUG] enigmail.js: Enigmail.execCmd: exitCode = 0
2015-02-04 04:22:30.333 [DEBUG] enigmail.js: Enigmail.execCmd: errOutput = gpg:
Warning: using insecure memory!

2015-02-04 04:22:30.333 [DEBUG] enigmailCommon.jsm: parseErrorOutput: status
message: 
gpg: Warning: using insecure memory!

2015-02-04 04:22:30.333 [DEBUG] enigmailCommon.jsm: parseErrorOutput:
statusFlags = 00000000
2015-02-04 04:22:30.333 [CONSOLE] gpg: Warning: using insecure memory!
2015-02-04 04:22:30.333 [DEBUG] enigmailCommon.jsm: stillActive: 
2015-02-04 04:22:30.334 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: command
execution exit code: 2
2015-02-04 04:22:30.334 [DEBUG] enigmailMsgHdrViewOverlay.js:
EnigMimeHeaderSink.updateSecurityStatus:
uriSpec=imap-message://heikki@mail.bbnetworks.net/Drafts#15283
2015-02-04 04:22:30.335 [DEBUG] enigmailMsgHdrViewOverlay.js:
EnigMimeHeaderSink.updateSecurityStatus:
msgUriSpec=imap-message://heikki@mail.bbnetworks.net/Drafts#15283
2015-02-04 04:22:30.335 [DEBUG] enigmailMsgHdrViewOverlay.js:
this.updateHdrIcons: exitCode=2, statusFlags=2162690, keyId=, userId=, gpg:
avaus epäonnistui: No secret key

Note: the message is encrypted with the following User ID's / Keys: 
  0x3D08188F6B052631 (Heikki Suonsivu <heikki at suonsivu.net>)

2015-02-04 04:22:30.336 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateMsgDb
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: messageDecrypt:
[object XrayWrapper [object Event]]
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb:
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb:
mimeMsg is null
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: header
content-type: multipart/encrypted; protocol="application/pgp-encrypted";
boundary="A9gsfoGJGCi7i2SS9m1QCdQ591jNJpRqb"
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: header
content-transfer-encoding: 
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: header
x-enigmail-version: 
2015-02-04 04:22:30.406 [DEBUG] enigmailMessengerOverlay.js: header
x-pgp-encoding-format: 
2015-02-04 04:22:30.407 [DEBUG] enigmailMessengerOverlay.js:
multipart/encrypted

-- 
You are receiving this mail because:
You are the assignee for the bug.

More information about the freebsd-ports-bugs mailing list