Menu ▾ ▴

Enigmail 2.0.4 attempts to use non-existent key to decrypt; GPG4WIN tools have no problem decrypting

Enigmail Support
James Garrison
2018-05-17
2018-05-17

  • James Garrison

    James Garrison - 2018-05-17

    I had old versions of GPG4WIN and Enigmail installed, so first thing I did was uninstall both and reboot.

    I now have Enigmail 2.0.4 and the latest GPG4WIN with GnuPG 2.2.7

    When I send myself an encrypted message the sending is normal, and the confirmation dialog shows the correct key ID (partly obfuscated):

    Looks like attached-image embedding, as documented in the help, is not working. Please see the attachments for the images.

    enigmail01.png Image of confirmation dialog

    The passphrase dialog looks OK as well

    enigmail02.png Image of passphrase dialog

    However, after receiving the message back, attempting to decrypt/verify shows

    enigmail03.png Image of decryption error message

    It verifies the signature correctly, but then claims that the message is encrypted to a different key ID, which doesn't match the key ID it said it used when encrypting.

    If I save the message text in a file and use any of the tools provided with GPG4WIN (GPA and Kleopatra), both are able to decrypt the message with no complaints.

    Here's the log covering the sending and receipt of the test message that resulted in the dialogs shown above.

    2018-05-16 21:26:56.360 [DEBUG] overlays.jsm: setupUI(chrome://messenger/content/messengercompose/messengercompose.xul)
2018-05-16 21:26:56.360 [DEBUG] overlays.jsm: loadOverlay(0)
2018-05-16 21:26:56.362 [DEBUG] overlays.jsm: insertXul(enigmailMsgComposeOverlay.xul)
2018-05-16 21:26:56.446 [DEBUG] loaded: enigmailMsgComposeOverlay.xul
2018-05-16 21:26:56.448 [DEBUG] overlays.jsm: injectDOM: gonna stuff: enigmailMsgComposeOverlay.xul into: chrome://messenger/content/messengercompose/messengercompose.xul
2018-05-16 21:26:56.450 [DEBUG] overlays.jsm: injectDOM: no target for popupset, not inserting
2018-05-16 21:26:56.450 [DEBUG] overlays.jsm: adding button 'button-enigmail-encrypt to composeToolbar2'
2018-05-16 21:26:56.452 [DEBUG] overlays.jsm: adding button 'button-enigmail-sign to composeToolbar2'
2018-05-16 21:26:56.452 [DEBUG] overlays.jsm: adding button 'button-enigmail-attach to composeToolbar2'
2018-05-16 21:26:56.452 [DEBUG] overlays.jsm: adding button 'enigmail-toolbar-encryptHdr-button to composeToolbar2'
2018-05-16 21:26:56.452 [DEBUG] overlays.jsm: adding button 'enigmail-toolbar-text-button to composeToolbar2'
2018-05-16 21:26:56.452 [DEBUG] overlays.jsm: adding button 'button-enigmail-pepEncrypt to composeToolbar2'
2018-05-16 21:26:56.454 [DEBUG] overlays.jsm: loadCss(chrome://enigmail/skin/enigmail.css)
2018-05-16 21:26:56.458 [DEBUG] overlays.jsm: loadOverlay(1)
2018-05-16 21:26:56.458 [DEBUG] overlays.jsm: loadOverlay: skipping enigmailMsgComposeOverlay-sm.xul
2018-05-16 21:26:56.458 [DEBUG] overlays.jsm: loadOverlay(2)
2018-05-16 21:26:56.458 [DEBUG] overlays.jsm: loadOverlay: completed
2018-05-16 21:26:56.458 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.composeStartup
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay: contentDocument=[object HTMLDocument]
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: addSecurityListener - cannot find element menu_securitySign2
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: addSecurityListener - cannot find element menu_securityEncryptRequire2
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.msgComposeReset
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.setIdentityDefaults
2018-05-16 21:26:56.460 [DEBUG] funcs.jsm: getSignMsg: identity.key=id4
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processAccountSpecificDefaultOptions
2018-05-16 21:26:56.460 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.focusChange: Enigmail.msg.determineSendFlags
2018-05-16 21:26:56.462 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:26:56.462 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:56.462 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:56.462 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:26:56.462 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:26:56.462 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:26:56.518 [DEBUG] enigmailMsgComposeOverlay.js: ECSL.NotifyComposeFieldsReady
2018-05-16 21:26:56.520 [DEBUG] enigmailMsgComposeOverlay.js: EDSL.enigDocStateListener.NotifyDocumentStateChanged
2018-05-16 21:26:56.520 [DEBUG] enigmailMsgComposeOverlay.js: ECSL.ComposeBodyReady
2018-05-16 21:26:56.520 [DEBUG] enigmailMsgComposeOverlay.js: ECSL.ComposeBodyReady: isEmpty=false, isEditable=true
2018-05-16 21:26:56.534 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.decryptQuote: false
2018-05-16 21:26:56.534 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.editorGetContentAs
2018-05-16 21:26:56.542 [DEBUG]   enigmail> DONE
2018-05-16 21:26:56.542 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:26:56.542 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:26:56.542 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:26:56.542 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:26:56.542 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:26:56.542 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:26:56.542 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:26:56.542 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:26:56.542 [CONSOLE] 
2018-05-16 21:26:56.544 [DEBUG] keyRing.jsm: getKeyById: 
2018-05-16 21:26:56.544 [DEBUG] keyRing.jsm: doValidKeysForAllRecipients(): return null (no single valid key found for="" with minTrustLevel="?")
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return null (key missing)
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return 'null'
2018-05-16 21:26:56.544 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 encReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 signReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 signReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 encReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 signReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:56.544 [DEBUG]                                 signReason=
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.composeOpen
2018-05-16 21:26:56.544 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.setupMenuAndToolbar
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 encReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 signReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 signReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 encReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 signReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:56.546 [DEBUG]                                 signReason=
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.initialSendFlags
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.fireSendFlags
2018-05-16 21:26:56.546 [DEBUG] overlays.jsm: loadOverlay: event completed
2018-05-16 21:26:56.546 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.focusChange: Enigmail.msg.determineSendFlags
2018-05-16 21:26:56.548 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:26:56.548 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:56.548 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:56.548 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:26:56.548 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:26:56.548 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:26:56.572 [DEBUG]   enigmail> DONE
2018-05-16 21:26:56.574 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:26:56.574 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:26:56.574 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:26:56.574 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:26:56.574 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:26:56.574 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:26:56.574 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:26:56.574 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:26:56.574 [CONSOLE] 
2018-05-16 21:26:56.574 [DEBUG] keyRing.jsm: getKeyById: 
2018-05-16 21:26:56.574 [DEBUG] keyRing.jsm: doValidKeysForAllRecipients(): return null (no single valid key found for="" with minTrustLevel="?")
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return null (key missing)
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return 'null'
2018-05-16 21:26:56.574 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:56.574 [DEBUG]                                 encReason=
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:56.574 [DEBUG]                                 signReason=
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:56.574 [DEBUG]                                 signReason=
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:56.574 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:58.046 [DEBUG] enigmailMsgComposeOverlay: re-determine send flags
2018-05-16 21:26:58.046 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.focusChange: Enigmail.msg.determineSendFlags
2018-05-16 21:26:58.050 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:26:58.050 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:58.050 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys=''
2018-05-16 21:26:58.050 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:26:58.052 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:26:58.052 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:26:58.090 [DEBUG]   enigmail> DONE
2018-05-16 21:26:58.090 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:26:58.090 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:26:58.090 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:26:58.092 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:26:58.092 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:26:58.092 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:26:58.092 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:26:58.092 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:26:58.092 [CONSOLE] 
2018-05-16 21:26:58.092 [DEBUG] keyRing.jsm: getKeyById: 
2018-05-16 21:26:58.092 [DEBUG] keyRing.jsm: doValidKeysForAllRecipients(): return null (no single valid key found for="" with minTrustLevel="?")
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return null (key missing)
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return 'null'
2018-05-16 21:26:58.092 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 encReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 signReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 signReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 encReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 signReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:26:58.092 [DEBUG]                                 signReason=
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:26:58.092 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:27:01.592 [DEBUG] enigmailMsgComposeOverlay.js: findMissingKeys()
2018-05-16 21:27:01.592 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.focusChange: Enigmail.msg.determineSendFlags
2018-05-16 21:27:01.608 [DEBUG] rules.jsm: mapAddrsToKeys(): emailAddrsStr="jim@jhmg.net" startDialogForMissingKeys=false
2018-05-16 21:27:01.610 [DEBUG] rules.jsm: getRulesData()
2018-05-16 21:27:01.610 [DEBUG] rules.jsm: mapAddrsToKeys(): rules successfully loaded; now process them
2018-05-16 21:27:01.610 [DEBUG]    found keys:
2018-05-16 21:27:01.610 [DEBUG]    addresses without keys:
2018-05-16 21:27:01.610 [DEBUG]      jim@jhmg.net
2018-05-16 21:27:01.610 [DEBUG]    old returned value:
2018-05-16 21:27:01.610 [DEBUG]      
2018-05-16 21:27:01.612 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:27:01.612 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:01.612 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:01.612 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:27:01.614 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:27:01.614 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:27:01.658 [DEBUG]   enigmail> DONE
2018-05-16 21:27:01.658 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:27:01.658 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:27:01.658 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:27:01.658 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:27:01.658 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:27:01.660 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:27:01.660 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:27:01.660 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:27:01.660 [CONSOLE] 
2018-05-16 21:27:01.660 [DEBUG] keyRing.jsm: getValidKeyForRecipient(): emailAddr="jim@jhmg.net"
2018-05-16 21:27:01.662 [DEBUG] keyRing.jsm: getValidKeyForRecipient():  matching subUid in key=296EFC6270738D88 keyTrust="u" found
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return "0x296EFC6270738D88"
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return '0x296EFC6270738D88'
2018-05-16 21:27:01.662 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=0
2018-05-16 21:27:01.662 [DEBUG]                                 encReason=
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:27:01.662 [DEBUG]                                 signReason=
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=0
2018-05-16 21:27:01.662 [DEBUG]                                 signReason=
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:27:01.662 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:27:01.664 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=inactiveNone  encReasonStr=Encryption: OFF
2018-05-16 21:27:01.664 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=inactiveNone  signReasonStr=Sign Message (auto)
2018-05-16 21:27:07.928 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.fireSendFlags
2018-05-16 21:27:12.418 [DEBUG] enigmailMsgComposeOverlay.js: EDSL.enigDocStateListener.NotifyDocumentStateChanged
2018-05-16 21:27:17.836 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.doPgpButton: what=toggle-final-encrypt
2018-05-16 21:27:17.836 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.setFinalSendMode: sendMode=toggle-final-encrypt
2018-05-16 21:27:17.838 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.focusChange: Enigmail.msg.determineSendFlags
2018-05-16 21:27:17.860 [DEBUG] rules.jsm: mapAddrsToKeys(): emailAddrsStr="jim@jhmg.net" startDialogForMissingKeys=false
2018-05-16 21:27:17.862 [DEBUG] rules.jsm: getRulesData()
2018-05-16 21:27:17.862 [DEBUG] rules.jsm: mapAddrsToKeys(): rules successfully loaded; now process them
2018-05-16 21:27:17.862 [DEBUG]    found keys:
2018-05-16 21:27:17.862 [DEBUG]    addresses without keys:
2018-05-16 21:27:17.864 [DEBUG]      jim@jhmg.net
2018-05-16 21:27:17.864 [DEBUG]    old returned value:
2018-05-16 21:27:17.864 [DEBUG]      
2018-05-16 21:27:17.864 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:27:17.864 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:17.864 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:17.864 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:27:17.868 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:27:17.868 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:27:17.910 [DEBUG]   enigmail> DONE
2018-05-16 21:27:17.910 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:27:17.910 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:27:17.910 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:27:17.910 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:27:17.910 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:27:17.910 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:27:17.910 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:27:17.910 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:27:17.910 [CONSOLE] 
2018-05-16 21:27:17.910 [DEBUG] keyRing.jsm: getValidKeyForRecipient(): emailAddr="jim@jhmg.net"
2018-05-16 21:27:17.914 [DEBUG] keyRing.jsm: getValidKeyForRecipient():  matching subUid in key=296EFC6270738D88 keyTrust="u" found
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return "0x296EFC6270738D88"
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return '0x296EFC6270738D88'
2018-05-16 21:27:17.914 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=11
2018-05-16 21:27:17.914 [DEBUG]                                 encReason=manually forced
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:27:17.914 [DEBUG]                                 signReason=
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=1
2018-05-16 21:27:17.914 [DEBUG]                                 signReason=due to encryption mode
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.updateStatusBar()
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   encSymbol=forceYes  encReasonStr=Encryption: ON (manually forced)
2018-05-16 21:27:17.914 [DEBUG] enigmailMsgComposeOverlay.js:   signSymbol=activeNone  signReasonStr=Signing: ON (due to encryption mode)
2018-05-16 21:27:23.992 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.sendMessageListener
2018-05-16 21:27:23.994 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.handleSendMessageEvent
2018-05-16 21:27:23.996 [DEBUG] keyRing.jsm: getKeyById: 0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:23.998 [DEBUG] keyRing.jsm: KeyObject.getMinimalPubKey: 296EFC6270738D88
2018-05-16 21:27:24.000 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: msgSendType=0, Enigmail.msg.sendMode=0, Enigmail.msg.statusEncrypted=11
2018-05-16 21:27:24.004 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: currentId=[nsIMsgIdentity: id4], jhg@jhmg.net
2018-05-16 21:27:24.004 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg:gMsgCompose=[xpconnect wrapped nsIMsgCompose]
2018-05-16 21:27:24.006 [DEBUG] =====> keySelection()
2018-05-16 21:27:24.006 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.keySelection()
2018-05-16 21:27:24.006 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.keySelection(): toAddrStr="jim@jhmg.net" bccAddrStr=""
2018-05-16 21:27:24.006 [DEBUG] =====> processRules()
2018-05-16 21:27:24.006 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processRules(): toAddrStr="jim@jhmg.net" bccAddrStr="" forceRecipientSettings=false
2018-05-16 21:27:24.006 [DEBUG] rules.jsm: mapAddrsToKeys(): emailAddrsStr="jim@jhmg.net" startDialogForMissingKeys=false
2018-05-16 21:27:24.008 [DEBUG] rules.jsm: getRulesData()
2018-05-16 21:27:24.008 [DEBUG] rules.jsm: mapAddrsToKeys(): rules successfully loaded; now process them
2018-05-16 21:27:24.010 [DEBUG]    found keys:
2018-05-16 21:27:24.010 [DEBUG]    addresses without keys:
2018-05-16 21:27:24.010 [DEBUG]      jim@jhmg.net
2018-05-16 21:27:24.010 [DEBUG]    old returned value:
2018-05-16 21:27:24.010 [DEBUG]      
2018-05-16 21:27:24.010 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.processFinalState()
2018-05-16 21:27:24.010 [DEBUG] enigmailMsgComposeOverlay.js:   encrypt=false encryptByRules=1 encFinally=11
2018-05-16 21:27:24.010 [DEBUG]                                 encReason=manually forced
2018-05-16 21:27:24.010 [DEBUG] enigmailMsgComposeOverlay.js:   signed=false signByRules=1 signFinally=0
2018-05-16 21:27:24.010 [DEBUG]                                 signReason=
2018-05-16 21:27:24.010 [DEBUG] enigmailMsgComposeOverlay.js:   derived signFinally=1
2018-05-16 21:27:24.010 [DEBUG]                                 signReason=due to encryption mode
2018-05-16 21:27:24.010 [DEBUG] enigmailMsgComposeOverlay.js:   pgpmimeByRules=1 pgpmimeFinally=1
2018-05-16 21:27:24.010 [DEBUG] rules.jsm: mapAddrsToKeys(): emailAddrsStr="" startDialogForMissingKeys=false
2018-05-16 21:27:24.010 [DEBUG] rules.jsm: getRulesData()
2018-05-16 21:27:24.010 [DEBUG] rules.jsm: mapAddrsToKeys(): rules successfully loaded; now process them
2018-05-16 21:27:24.012 [DEBUG]    found keys:
2018-05-16 21:27:24.012 [DEBUG]    addresses without keys:
2018-05-16 21:27:24.012 [DEBUG]    old returned value:
2018-05-16 21:27:24.012 [DEBUG]      
2018-05-16 21:27:24.012 [DEBUG]   <=== processRules()
2018-05-16 21:27:24.012 [DEBUG] =====> encryptTestMessage()
2018-05-16 21:27:24.012 [DEBUG] =====> validKeysForAllRecipients()
2018-05-16 21:27:24.012 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:24.012 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): emailsOrKeys='jim@jhmg.net'
2018-05-16 21:27:24.012 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): find keys with minTrustLevel="?"
2018-05-16 21:27:24.014 execution.jsm: EnigmailExecution.execCmd: subprocess = 'C:\Program Files (x86)\GnuPG\bin\gpg.exe'
2018-05-16 21:27:24.014 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --fixed-list-mode --with-colons --list-config
2018-05-16 21:27:24.052 [DEBUG]   enigmail> DONE
2018-05-16 21:27:24.052 [DEBUG] execution.jsm: EnigmailExecution.execCmd: exitCode = 0
2018-05-16 21:27:24.052 [DEBUG] execution.jsm: EnigmailExecution.execCmd: errOutput = 
2018-05-16 21:27:24.052 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 

2018-05-16 21:27:24.052 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:27:24.052 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:27:24.054 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00000000
2018-05-16 21:27:24.054 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = 
2018-05-16 21:27:24.054 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags undefined
2018-05-16 21:27:24.054 [CONSOLE] 
2018-05-16 21:27:24.054 [DEBUG] keyRing.jsm: getValidKeyForRecipient(): emailAddr="jim@jhmg.net"
2018-05-16 21:27:24.054 [DEBUG] keyRing.jsm: getValidKeyForRecipient():  matching subUid in key=296EFC6270738D88 keyTrust="u" found
2018-05-16 21:27:24.054 [DEBUG] enigmailMsgComposeHelper.js: doValidKeysForAllRecipients(): return "0x296EFC6270738D88"
2018-05-16 21:27:24.054 [DEBUG] enigmailMsgComposeHelper.js: validKeysForAllRecipients(): return '0x296EFC6270738D88'
2018-05-16 21:27:24.056 [DEBUG]   <=== validKeysForAllRecipients()
2018-05-16 21:27:24.056 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptTestMessage(): call encryptMessage() for fromAddr="0x4D24E9836480879C2A66AF34296EFC6270738D88" toAddrStr="0x296EFC6270738D88" bccAddrStr=""
2018-05-16 21:27:24.056 [DEBUG] enigmail.js: Enigmail.encryptMessage: 12 bytes from 0x4D24E9836480879C2A66AF34296EFC6270738D88 to 0x296EFC6270738D88 (354)
2018-05-16 21:27:24.056 [DEBUG] encryption.jsm: encryptMessageStart: uiFlags=16, from 0x4D24E9836480879C2A66AF34296EFC6270738D88 to 0x296EFC6270738D88, hashAlgorithm=null (00000162)
2018-05-16 21:27:24.056 [DEBUG] encryption.jsm: determineOwnKeyUsability: sendFlags=354, sender=0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:24.056 [DEBUG] keyRing.jsm: getKeyById: 0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:24.056 [DEBUG] encryption.jsm: getEncryptCommand: hashAlgorithm=null
2018-05-16 21:27:24.058 execution.jsm: execStart: command = "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 -a -t --encrypt --trust-model always --encrypt-to 0x4D24E9836480879C2A66AF34296EFC6270738D88 -r 0x296EFC6270738D88 -u 0x4D24E9836480879C2A66AF34296EFC6270738D88, needPassphrase=0, domWindow=[object ChromeWindow], listener=[object Object]
2018-05-16 21:27:24.058 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 -a -t --encrypt --trust-model always --encrypt-to 0x4D24E9836480879C2A66AF34296EFC6270738D88 -r 0x296EFC6270738D88 -u 0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:24.060 [DEBUG]   enigmail> DONE
2018-05-16 21:27:24.144 [DEBUG] encryption.jsm: encryptMessageEnd: uiFlags=16, sendFlags=00000162, outputLen=845
2018-05-16 21:27:24.144 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: 0x296EFC6270738D88: skipped: public key already present
gpg: WARNING: encrypting without integrity protection is dangerous
[GNUPG:] BEGIN_ENCRYPTION 0 3
[GNUPG:] END_ENCRYPTION

2018-05-16 21:27:24.146 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:27:24.146 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:27:24.146 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:27:24.146 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:27:24.146 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 80000000
2018-05-16 21:27:24.146 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = gpg: 0x296EFC6270738D88: skipped: public key already present
gpg: WARNING: encrypting without integrity protection is dangerous
2018-05-16 21:27:24.146 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags -2147483648
2018-05-16 21:27:24.146 [DEBUG] encryption.jsm: encryptMessageEnd: command execution exit code: 0
2018-05-16 21:27:24.146 [DEBUG]   <=== encryptMessage()
2018-05-16 21:27:24.146 [DEBUG]   <=== encryptTestMessage()
2018-05-16 21:27:24.146 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.keySelection(): return toAddrStr="0x296EFC6270738D88" bccAddrStr=""
2018-05-16 21:27:24.146 [DEBUG]   <=== keySelection()
2018-05-16 21:27:24.146 [DEBUG] enigmailMsgComposeOverlay.js: hasAttachments = false
2018-05-16 21:27:24.146 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: Using PGP/MIME, flags=227
2018-05-16 21:27:24.146 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: oldSecurityInfo = [xpconnect wrapped nsIMsgSMIMECompFields]
2018-05-16 21:27:24.148 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: securityInfo = [xpconnect wrapped nsISupports]
2018-05-16 21:27:24.148 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.confirmBeforeSend: sendFlags=227
2018-05-16 21:27:24.160 [DEBUG] enigmailCommon.jsm: dispatchEvent f=resizeDlg
2018-05-16 21:27:40.228 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.encryptMsg: enabled forceMsgEncoding
2018-05-16 21:27:40.254 [DEBUG] mimeEncrypt.js: requiresCryptoEncapsulation
2018-05-16 21:27:40.260 [DEBUG] mimeEncrypt.js: requiresCryptoEncapsulation
2018-05-16 21:27:40.262 [DEBUG] mimeEncrypt.js: beginCryptoEncapsulation
2018-05-16 21:27:40.262 [DEBUG] mimeEncrypt.js: startCryptoHeaders
2018-05-16 21:27:40.262 [DEBUG] mimeEncrypt.js: encryptedHeaders
2018-05-16 21:27:40.266 [DEBUG] mimeEncrypt.js: finishCryptoEncapsulation
2018-05-16 21:27:40.266 [DEBUG] encryption.jsm: encryptMessageStart: uiFlags=9, from 0x4D24E9836480879C2A66AF34296EFC6270738D88 to 0x296EFC6270738D88, hashAlgorithm=null (000000e3)
2018-05-16 21:27:40.266 [DEBUG] encryption.jsm: determineOwnKeyUsability: sendFlags=227, sender=0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:40.266 [DEBUG] keyRing.jsm: getKeyById: 0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:40.268 [DEBUG] encryption.jsm: getEncryptCommand: hashAlgorithm=null
2018-05-16 21:27:40.270 execution.jsm: execStart: command = "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 -a -t --encrypt --sign --trust-model always --encrypt-to 0x4D24E9836480879C2A66AF34296EFC6270738D88 -r 0x296EFC6270738D88 -u 0x4D24E9836480879C2A66AF34296EFC6270738D88, needPassphrase=1, domWindow=[object ChromeWindow], listener=[object Object]
2018-05-16 21:27:40.270 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 -a -t --encrypt --sign --trust-model always --encrypt-to 0x4D24E9836480879C2A66AF34296EFC6270738D88 -r 0x296EFC6270738D88 -u 0x4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:27:40.272 [DEBUG]   enigmail> DONE
2018-05-16 21:28:10.966 [DEBUG] mimeEncrypt.js: done: 0
2018-05-16 21:28:10.966 [DEBUG] encryption.jsm: encryptMessageEnd: uiFlags=9, sendFlags=000000e3, outputLen=1435
2018-05-16 21:28:10.966 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 2
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: 0x296EFC6270738D88: skipped: public key already present
[GNUPG:] BEGIN_SIGNING H2
[GNUPG:] PINENTRY_LAUNCHED 14744 qt 1.1.1-beta5 - - -
[GNUPG:] SIG_CREATED S 17 2 01 1526531260 4D24E9836480879C2A66AF34296EFC6270738D88
gpg: WARNING: encrypting without integrity protection is dangerous
[GNUPG:] BEGIN_ENCRYPTION 0 3
[GNUPG:] END_ENCRYPTION

2018-05-16 21:28:10.968 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:28:10.968 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:28:10.968 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:28:10.968 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:28:10.968 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = c0000000
2018-05-16 21:28:10.968 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = gpg: 0x296EFC6270738D88: skipped: public key already present
gpg: WARNING: encrypting without integrity protection is dangerous
2018-05-16 21:28:10.968 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: 0 statusFlags -1073741824
2018-05-16 21:28:10.968 [DEBUG] encryption.jsm: encryptMessageEnd: command execution exit code: 0
2018-05-16 21:28:10.968 [DEBUG] mimeEncrypt.js: finishCryptoHeaders
2018-05-16 21:28:12.666 [DEBUG] enigmailMsgComposeOverlay.js: EDSL.enigDocStateListener.NotifyDocumentStateChanged
2018-05-16 21:28:12.666 [DEBUG] enigmailMsgComposeOverlay.js: ECSL.ComposeProcessDone: 0
2018-05-16 21:28:12.668 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.msgComposeClose
2018-05-16 21:28:12.668 [DEBUG] enigmailMsgComposeOverlay.js: Enigmail.msg.msgComposeReset
2018-05-16 21:28:12.680 [DEBUG] enigmailMsgComposeOverlay.js: EDSL.enigDocStateListener.NotifyDocumentWillBeDestroyed
2018-05-16 21:28:13.132 [DEBUG] filters.jsm: newMailListener.msgAdded() - got new mail in Inbox
2018-05-16 21:28:13.284 [DEBUG] pgpmimeHandler.js: PgpMimeHandler()
2018-05-16 21:28:13.284 [DEBUG] pgpmimeHandler.js: onStartRequest
2018-05-16 21:28:13.284 [DEBUG] pgpmimeHandler.js: ct= multipart/encrypted;
 protocol="application/pgp-encrypted";
 boundary="divafMnItwcIxZXysAqCTvBVk6y7KfpEm"
2018-05-16 21:28:13.284 [DEBUG] mimeDecrypt.jsm: MimeDecryptHandler()
2018-05-16 21:28:13.286 [DEBUG] mimeDecrypt.jsm: onStartRequest
2018-05-16 21:28:13.286 [DEBUG] mimeDecrypt.jsm: onStartRequest: uri='imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344?header=filter'
2018-05-16 21:28:13.292 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344?header=filter
2018-05-16 21:28:13.292 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:13.292 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:13.292 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:13.292 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:13.294 [DEBUG] enigmailMsgHdrViewOverlay.js: updateSecurityStatus: mimePart=
2018-05-16 21:28:13.294 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: uri.spec=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344?header=filter
2018-05-16 21:28:13.294 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183340
2018-05-16 21:28:13.294 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183340 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:13.294 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344?header=filter
2018-05-16 21:28:13.294 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:13.294 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: url=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183340
2018-05-16 21:28:13.294 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: false
2018-05-16 21:28:24.122 [DEBUG] enigmailMessengerOverlay.js: messageFrameUnload
2018-05-16 21:28:24.122 [DEBUG] enigmailMessengerOverlay.js: messageCleanup
2018-05-16 21:28:24.122 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:24.122 [DEBUG] enigmailMsgHdrViewOverlay.js: this.messageUnload
2018-05-16 21:28:24.128 [DEBUG] enigmailMsgHdrViewOverlay.js: _listener_onStartHeaders
2018-05-16 21:28:24.128 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:24.128 [DEBUG] windows.jsm: getFrame: name=messagepane
2018-05-16 21:28:24.128 [DEBUG] enigmailMsgHdrViewOverlay.js: setWindowCallback
2018-05-16 21:28:24.144 [DEBUG] enigmailMsgHdrViewOverlay.js: _listener_onEndHeaders
2018-05-16 21:28:24.144 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:24.144 [DEBUG] pgpmimeHandler.js: PgpMimeHandler()
2018-05-16 21:28:24.144 [DEBUG] pgpmimeHandler.js: onStartRequest
2018-05-16 21:28:24.144 [DEBUG] pgpmimeHandler.js: ct= multipart/encrypted;
 protocol="application/pgp-encrypted";
 boundary="divafMnItwcIxZXysAqCTvBVk6y7KfpEm"
2018-05-16 21:28:24.144 [DEBUG] mimeDecrypt.jsm: MimeDecryptHandler()
2018-05-16 21:28:24.144 [DEBUG] mimeDecrypt.jsm: onStartRequest
2018-05-16 21:28:24.146 [DEBUG] mimeDecrypt.jsm: onStartRequest: uri='imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344'
2018-05-16 21:28:24.146 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:24.148 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:24.148 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:24.148 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:24.148 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: updateSecurityStatus: mimePart=
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: uri.spec=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:24.148 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:24.148 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:24.148 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:24.148 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: url=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: true
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateHdrIcons: exitCode=-2081, statusFlags=0, keyId=, userId=, Possibly PGP/MIME encrypted or signed message; use 'Decrypt/Verify' function to verify
2018-05-16 21:28:24.148 [DEBUG] enigmailMessengerOverlay.js: createArtificialAutocryptHeader
2018-05-16 21:28:24.148 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateMsgDb
2018-05-16 21:28:24.198 [DEBUG] enigmailMsgHdrViewOverlay.js: this.messageLoad
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: messageAutoDecrypt:
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: messageDecrypt: null
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: movePEPsubject:
2018-05-16 21:28:24.198 [DEBUG] windows.jsm: getFrame: name=messagepane
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb:
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb: mimeMsg is null
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: header content-transfer-encoding: ''
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: header x-enigmail-version: ''
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: header x-pgp-encoding-format: ''
2018-05-16 21:28:24.198 [DEBUG] enigmailMessengerOverlay.js: header autocrypt-setup-message: ''
2018-05-16 21:28:24.200 [DEBUG] autocrypt.jsm: processAutocryptHeader(): from=Jim Garrison <jhg@jhmg.net>
2018-05-16 21:28:24.204 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:24.204 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateHdrIcons: exitCode=-2081, statusFlags=0, keyId=, userId=, Possibly PGP/MIME encrypted or signed message; use 'Decrypt/Verify' function to verify
2018-05-16 21:28:24.204 [DEBUG] enigmailMessengerOverlay.js: createArtificialAutocryptHeader
2018-05-16 21:28:24.204 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateMsgDb
2018-05-16 21:28:24.212 [DEBUG] autocrypt.jsm: checkDatabaseStructure
2018-05-16 21:28:24.212 [DEBUG] autocrypt.jsm: checkDatabaseStructure - success
2018-05-16 21:28:24.212 [DEBUG] autocrypt.jsm: findUserRecord
2018-05-16 21:28:24.214 [DEBUG] autocrypt.jsm: findUserRecord - got row
2018-05-16 21:28:24.214 [DEBUG] autocrypt.jsm: got 1 rows
2018-05-16 21:28:24.214 [DEBUG] autocrypt.jsm: updateUser
2018-05-16 21:28:24.214 [DEBUG] autocrypt.jsm: updateUser: updating latest message
2018-05-16 21:28:24.214 [DEBUG] key.jsm: getKeyListFromKeyBlock
2018-05-16 21:28:24.236 [DEBUG] autocrypt.jsm: OK - closing connection
2018-05-16 21:28:26.564 [DEBUG] enigmailMessengerOverlay.js: updateOptionsDisplay: 
2018-05-16 21:28:26.564 [DEBUG] funcs.jsm: collapseAdvanced:
2018-05-16 21:28:27.922 [DEBUG] enigmailMessengerOverlay.js: messageDecrypt: null
2018-05-16 21:28:27.922 [DEBUG] enigmailMessengerOverlay.js: movePEPsubject:
2018-05-16 21:28:27.922 [DEBUG] windows.jsm: getFrame: name=messagepane
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb:
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb: mimeMsg is null
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: header content-transfer-encoding: ''
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: header x-enigmail-version: ''
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: header x-pgp-encoding-format: ''
2018-05-16 21:28:27.924 [DEBUG] enigmailMessengerOverlay.js: header autocrypt-setup-message: ''
2018-05-16 21:28:27.932 [DEBUG] autocrypt.jsm: processAutocryptHeader(): from=Jim Garrison <jhg@jhmg.net>
2018-05-16 21:28:27.940 [DEBUG] enigmailMessengerOverlay.js: messageReload: false
2018-05-16 21:28:27.970 [DEBUG] autocrypt.jsm: checkDatabaseStructure
2018-05-16 21:28:27.972 [DEBUG] enigmailMessengerOverlay.js: messageFrameUnload
2018-05-16 21:28:27.972 [DEBUG] enigmailMessengerOverlay.js: messageCleanup
2018-05-16 21:28:27.972 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:27.972 [DEBUG] enigmailMsgHdrViewOverlay.js: this.messageUnload
2018-05-16 21:28:27.972 [DEBUG] uris.jsm: forgetEncryptedUri: uri=imap-message://jhg@smtp.jhmg.net/INBOX#183344
2018-05-16 21:28:27.984 [DEBUG] enigmailMsgHdrViewOverlay.js: _listener_onStartHeaders
2018-05-16 21:28:27.986 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:27.986 [DEBUG] windows.jsm: getFrame: name=messagepane
2018-05-16 21:28:27.986 [DEBUG] enigmailMsgHdrViewOverlay.js: setWindowCallback
2018-05-16 21:28:28.004 [DEBUG] enigmailMsgHdrViewOverlay.js: _listener_onEndHeaders
2018-05-16 21:28:28.004 [DEBUG] enigmailMessengerOverlay.js: setAttachmentReveal
2018-05-16 21:28:28.004 [DEBUG] pgpmimeHandler.js: PgpMimeHandler()
2018-05-16 21:28:28.004 [DEBUG] pgpmimeHandler.js: onStartRequest
2018-05-16 21:28:28.004 [DEBUG] pgpmimeHandler.js: ct= multipart/encrypted;
 protocol="application/pgp-encrypted";
 boundary="divafMnItwcIxZXysAqCTvBVk6y7KfpEm"
2018-05-16 21:28:28.004 [DEBUG] mimeDecrypt.jsm: MimeDecryptHandler()
2018-05-16 21:28:28.006 [DEBUG] mimeDecrypt.jsm: onStartRequest
2018-05-16 21:28:28.006 [DEBUG] mimeDecrypt.jsm: onStartRequest: uri='imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344'
2018-05-16 21:28:28.006 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.006 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:28.006 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:28.006 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:28.006 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:28.008 [DEBUG] enigmailCommon.jsm: decryptMessageStart: verifyOnly=false
2018-05-16 21:28:28.008 execution.jsm: execStart: command = "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --sender jhg@jhmg.net --max-output 143700 --decrypt, needPassphrase=true, domWindow=[object ChromeWindow], listener=[object Object]
2018-05-16 21:28:28.008 [CONSOLE] enigmail> "C:\Program Files (x86)\GnuPG\bin\gpg.exe" --charset utf-8 --display-charset utf-8 --no-auto-check-trustdb --allow-weak-digest-algos --batch --no-tty --status-fd 2 --sender jhg@jhmg.net --max-output 143700 --decrypt
2018-05-16 21:28:28.012 [DEBUG]   enigmail> DONE
2018-05-16 21:28:28.012 [DEBUG] mimeDecrypt.jsm: onStopRequest: pipe not yet ready
2018-05-16 21:28:28.012 [DEBUG] autocrypt.jsm: checkDatabaseStructure - success
2018-05-16 21:28:28.012 [DEBUG] autocrypt.jsm: findUserRecord
2018-05-16 21:28:28.024 [DEBUG] autocrypt.jsm: findUserRecord - got row
2018-05-16 21:28:28.024 [DEBUG] autocrypt.jsm: got 1 rows
2018-05-16 21:28:28.024 [DEBUG] autocrypt.jsm: updateUser
2018-05-16 21:28:28.024 [DEBUG] autocrypt.jsm: updateUser: not a new latest message
2018-05-16 21:28:28.024 [DEBUG] autocrypt.jsm: OK - closing connection
2018-05-16 21:28:28.026 [DEBUG] mimeDecrypt.jsm: stdin()
2018-05-16 21:28:28.028 [DEBUG] mimeDecrypt.jsm: stdin: closing pipe
2018-05-16 21:28:28.170 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: uiFlags=8, verifyOnly=false, noOutput=false
2018-05-16 21:28:28.172 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: stderrStr=
[GNUPG:] ENC_TO 68347886F7EA6DF6 16 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: Oops: keyid_from_fingerprint: no pubkey
[GNUPG:] DECRYPTION_KEY FD5C59858C9A3C9481448D6968347886F7EA6DF6 4D24E9836480879C2A66AF34296EFC6270738D88 u
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: encrypted with 2048-bit ELG key, ID 68347886F7EA6DF6, created 1998-06-14
      "James H. Garrison <jhg@acm.org>"
[GNUPG:] BEGIN_DECRYPTION
[GNUPG:] DECRYPTION_INFO 0 3
[GNUPG:] PLAINTEXT 74 1526531260 
[GNUPG:] PLAINTEXT_LENGTH 497
[GNUPG:] NEWSIG
gpg: Signature made 05/16/18 21:27:40 Pacific Daylight Time
gpg:                using DSA key 4D24E9836480879C2A66AF34296EFC6270738D88
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] SIG_ID 7NQPfuJKQx7M3P5nppvSH9Ujo+M 2018-05-17 1526531260
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] GOODSIG 296EFC6270738D88 James H. Garrison <jhg@acm.org>
gpg: Good signature from "James H. Garrison <jhg@acm.org>" [ultimate]
gpg:                 aka "James H. Garrison <jim@jhmg.net>" [ultimate]
gpg:                 aka "James H. Garrison <jhg@jhmg.net>" [ultimate]
[GNUPG:] VALIDSIG 4D24E9836480879C2A66AF34296EFC6270738D88 2018-05-17 1526531260 0 4 0 17 2 01 4D24E9836480879C2A66AF34296EFC6270738D88
[GNUPG:] TRUST_ULTIMATE 0 pgp
[GNUPG:] DECRYPTION_OKAY
gpg: WARNING: message was not integrity protected
[GNUPG:] END_DECRYPTION

2018-05-16 21:28:28.172 [DEBUG] errorHandling.jsm: parseErrorOutputWith: status message: 
[GNUPG:] ENC_TO 68347886F7EA6DF6 16 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: Oops: keyid_from_fingerprint: no pubkey
[GNUPG:] DECRYPTION_KEY FD5C59858C9A3C9481448D6968347886F7EA6DF6 4D24E9836480879C2A66AF34296EFC6270738D88 u
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
gpg: encrypted with 2048-bit ELG key, ID 68347886F7EA6DF6, created 1998-06-14
      "James H. Garrison <jhg@acm.org>"
[GNUPG:] BEGIN_DECRYPTION
[GNUPG:] DECRYPTION_INFO 0 3
[GNUPG:] PLAINTEXT 74 1526531260 
[GNUPG:] PLAINTEXT_LENGTH 497
[GNUPG:] NEWSIG
gpg: Signature made 05/16/18 21:27:40 Pacific Daylight Time
gpg:                using DSA key 4D24E9836480879C2A66AF34296EFC6270738D88
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] SIG_ID 7NQPfuJKQx7M3P5nppvSH9Ujo+M 2018-05-17 1526531260
[GNUPG:] KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
[GNUPG:] GOODSIG 296EFC6270738D88 James H. Garrison <jhg@acm.org>
gpg: Good signature from "James H. Garrison <jhg@acm.org>" [ultimate]
gpg:                 aka "James H. Garrison <jim@jhmg.net>" [ultimate]
gpg:                 aka "James H. Garrison <jhg@jhmg.net>" [ultimate]
[GNUPG:] VALIDSIG 4D24E9836480879C2A66AF34296EFC6270738D88 2018-05-17 1526531260 0 4 0 17 2 01 4D24E9836480879C2A66AF34296EFC6270738D88
[GNUPG:] TRUST_ULTIMATE 0 pgp
[GNUPG:] DECRYPTION_OKAY
gpg: WARNING: message was not integrity protected
[GNUPG:] END_DECRYPTION

2018-05-16 21:28:28.174 [DEBUG] system.jsm: determineSystemCharset
2018-05-16 21:28:28.174 [DEBUG] system.jsm: determineSystemCharset: charset='437'
2018-05-16 21:28:28.174 [DEBUG] errorHandling.jsm: parseErrorOutputWith: statusFlags = 00090001
2018-05-16 21:28:28.174 [DEBUG] errorHandling.jsm: parseErrorOutputWith: return with c.errorMsg = gpg: WARNING: message was not integrity protected
2018-05-16 21:28:28.174 [DEBUG] execution.jsm: EnigmailExecution.fixExitCode: agentType: gpg exitCode: undefined statusFlags 2686977
2018-05-16 21:28:28.174 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: ENC_TO 68347886F7EA6DF6 16 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: DECRYPTION_KEY FD5C59858C9A3C9481448D6968347886F7EA6DF6 4D24E9836480879C2A66AF34296EFC6270738D88 u
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: BEGIN_DECRYPTION
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: DECRYPTION_INFO 0 3
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: PLAINTEXT 74 1526531260 
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: PLAINTEXT_LENGTH 497
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: NEWSIG
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: SIG_ID 7NQPfuJKQx7M3P5nppvSH9Ujo+M 2018-05-17 1526531260
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: KEY_CONSIDERED 4D24E9836480879C2A66AF34296EFC6270738D88 0
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: GOODSIG 296EFC6270738D88 James H. Garrison <jhg@acm.org>
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: VALIDSIG 4D24E9836480879C2A66AF34296EFC6270738D88 2018-05-17 1526531260 0 4 0 17 2 01 4D24E9836480879C2A66AF34296EFC6270738D88
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: TRUST_ULTIMATE 0 pgp
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: DECRYPTION_OKAY
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: process: END_DECRYPTION
2018-05-16 21:28:28.176 [DEBUG] keyRing.jsm: getKeyById: 296EFC6270738D88
2018-05-16 21:28:28.176 [DEBUG] keyRing.jsm: getKeyById: 296EFC6270738D88
2018-05-16 21:28:28.176 [DEBUG] keyRing.jsm: getKeyById: 0x68347886F7EA6DF6
2018-05-16 21:28:28.176 [DEBUG] enigmailCommon.jsm: decryptMessageEnd: command execution exit code: undefined
2018-05-16 21:28:28.176 [DEBUG] mimeDecrypt.jsm: displayStatus
2018-05-16 21:28:28.176 [DEBUG] mimeDecrypt.jsm: displayStatus for uri imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.176 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.processDecryptionResult:
2018-05-16 21:28:28.176 [DEBUG] enigmailMsgHdrViewOverlay.js: actionType= modifyMessageHeaders, mimePart=1
2018-05-16 21:28:28.176 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: uri.spec=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.176 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.178 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:28.178 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.178 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:28.178 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: url=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.178 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: true
2018-05-16 21:28:28.178 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.modifyMessageHeaders:
2018-05-16 21:28:28.188 [DEBUG] enigmailMsgHdrViewOverlay.js: updateSecurityStatus: mimePart=1
2018-05-16 21:28:28.188 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: uri.spec=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.188 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.188 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:28.188 [DEBUG] uris.jsm: msgIdentificationFromUrl: url.pathQueryRef=/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.188 [DEBUG] uris.jsm: msgIdentificationFromUrl: msgNum=183344 / folder=/fetch>UID>/INBOX
2018-05-16 21:28:28.188 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: url=imap://jhg@smtp.jhmg.net:993/fetch%3EUID%3E/INBOX%3E183344
2018-05-16 21:28:28.188 [DEBUG] enigmailMsgHdrViewOverlay.js: EnigMimeHeaderSink.isCurrentMessage: true
2018-05-16 21:28:28.188 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateHdrIcons: exitCode=undefined, statusFlags=2686977, keyId=296EFC6270738D88, userId=James H. Garrison <jhg@acm.org>
James H. Garrison <jim@jhmg.net>
James H. Garrison <jhg@jhmg.net>, Good signature from James H. Garrison <jhg@acm.org>
James H. Garrison <jim@jhmg.net>
James H. Garrison <jhg@jhmg.net>
2018-05-16 21:28:28.190 [DEBUG] enigmailMessengerOverlay.js: createArtificialAutocryptHeader
2018-05-16 21:28:28.190 [DEBUG] enigmailMsgHdrViewOverlay.js: this.updateMsgDb
2018-05-16 21:28:28.190 [DEBUG] mimeDecrypt.jsm: returnData: 0 bytes
2018-05-16 21:28:28.190 [DEBUG] mimeDecrypt.jsm: onStopRequest: process terminated
2018-05-16 21:28:28.202 [DEBUG] enigmailMsgHdrViewOverlay.js: this.messageLoad
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: messageAutoDecrypt:
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: messageDecrypt: null
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: movePEPsubject:
2018-05-16 21:28:28.202 [DEBUG] windows.jsm: getFrame: name=messagepane
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb:
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: messageDecryptCb: mimeMsg is null
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: header content-transfer-encoding: ''
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: header x-enigmail-version: ''
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: header x-pgp-encoding-format: ''
2018-05-16 21:28:28.202 [DEBUG] enigmailMessengerOverlay.js: header autocrypt-setup-message: ''
2018-05-16 21:28:28.204 [DEBUG] autocrypt.jsm: processAutocryptHeader(): from=Jim Garrison <jhg@jhmg.net>
2018-05-16 21:28:28.206 [DEBUG] mimeVerify.jsm: getManualUri
2018-05-16 21:28:28.210 [DEBUG] autocrypt.jsm: checkDatabaseStructure
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: checkDatabaseStructure - success
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: findUserRecord
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: findUserRecord - got row
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: got 1 rows
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: updateUser
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: updateUser: not a new latest message
2018-05-16 21:28:28.212 [DEBUG] autocrypt.jsm: OK - closing connection
2018-05-16 21:28:36.486 [DEBUG] enigmailMessengerOverlay.js: updateOptionsDisplay: 
2018-05-16 21:28:36.486 [DEBUG] funcs.jsm: collapseAdvanced:
2018-05-16 21:28:38.200 [DEBUG] enigmailMessengerOverlay.js: updateOptionsDisplay: 
2018-05-16 21:28:38.202 [DEBUG] funcs.jsm: collapseAdvanced:
2018-05-16 21:28:39.816 [DEBUG] enigmailHelp.js: enigLoadPage
2018-05-16 21:28:39.816 [DEBUG] windows.jsm: getFrame: name=contentFrame
     

    Last edit: James Garrison 2018-05-17
    Thumbnail
    enigmail01.png
    Thumbnail
    enigmail02.png
    Thumbnail
    enigmail03.png

  • Malte Ried

    Malte Ried - 2018-05-17

    Hi!

    Same thing here with all encrypted emails. Even those I got in the past which I could decrypt with old versions of Enigmail. After going back to version 2.0.3 everything works as expected again.

     

  • Patrick Brunschwig

    Patrick Brunschwig - 2018-05-17

    Enigmail doesn't use the wrong key - it is just confused by the output from GnuPG.

    Enigmail refuses to decrypt the messages because they are not MDC-protected. That's exactly the topic of the Efail vulnerability. You can see this because GnuPG prints the message gpg: WARNING: message was not integrity protected.

    The fix is to enable MDC on your key. However, the fact remains - you cannot decrypt messages without MDC in Enigmail anymore. There is currently no workaround other than using the command line for this.

     

    • James Garrison

      James Garrison - 2018-05-17

      Thank you Patrick. I had to do a little digging to find out how to set MDC, so for future readers using Windows, do the following in cmd:

      gpg --edit-key [key-id]
setpref mdc
save

      After that, decrypting works.

      I guess it would be nice if Enigmail would detect this condition and provide a better diagnostic. I have submitted a feature request.

       

      Last edit: James Garrison 2018-05-17

Log in to post a comment.