[mew-int 2618] mew hangs when sending second e-mail

Alan Bram alan.bram at example.com
Wed Oct 1 11:32:30 JST 2008


Hi,

I've been using Mew on Linux for a long time, and everything is fine
there.  However, recently I started using FreeBSD, and I wanted to use
Mew here also; but I'm having a problem.

The first time I send out a mail message, it works fine.  But if I try
to send out a second message, Mew seems to hang, and my message never
goes out.  (It doesn't lock up Emacs altogether, but the sending of
the message never completes.)

I found a kind of a workaround: if I kill all Mew-related processes
that were started within Emacs, it's as if something somehow gets
reset, and I'm able to send the next message successfully again.  But
of course if I then try a third time, it hangs again.  As long as I
keep killing processes between each message, it seems to work OK.

This is better than nothing.  And I wrote a little Elisp function to
do the killing for me.  But of course it's a bit inconvenient to have
to remember to do that every time.

Can anyone help me figure out what might be the problem?  I attach my
~/.mew.el configuration, as well as a Mew debug transcript (slightly
scrubbed).  In this example, I send the first message starting at time
18:42:45.  It works fine.  Then, starting at time 18:43:05 I try to
send the second message, but it never seems to finish.

As I say, I'm using the same configuration on Linux (except for the
location of the certs directory) and it seems to work fine there.

I'm using Mew version 6.1 on FreeBSD 7.0-RELEASE.


Cheers,
 - arb


-------------- next part --------------
(setq mew-search-method 'est)           ; Hyper Estraier

;(setq mew-ssl-cert-directory "/etc/ssl/certs")
(setq mew-name "Alan Bram")
(setq mew-user "alan.bram"
      mew-mail-domain "oracle.com"

      mew-smtp-server "xxxxxxxxx.oracle.com"
      mew-smtp-auth-list '("PLAIN")
      mew-smtp-user "alan.bram at example.com"
      
      mew-smtp-ssl t
      mew-smtp-ssl-port "465"
      
      mew-proto "%"
      mew-fcc "+backup,%Sent"
      mew-imap-user "alan.bram at example.com"
      mew-imap-server "xxxxxxxxx.oracle.com"
      mew-imap-trash-folder "%Deleted Messages"
      mew-imap-ssl t
      mew-imap-auth nil
      mew-imap-ssl-port "993")
      

(setq mew-cite-fields nil)
(setq mew-cite-format "")

(setq mew-prog-text/html-ext "firefox")

(define-key mew-message-mode-map [mouse-2] 'browse-url-at-mouse)
(setq browse-url-netscape-program "firefox")

(setq mew-use-cached-passwd t)
(setq mew-passwd-timer-unit 60)
(setq mew-passwd-lifetime 24)           ;save cached password for 24 hours

(setq mew-use-full-window t)
(setq mew-summary-mark-direction 'down)

(setq mew-field-delete-for-forwarding 
      (append '("Received:" "Return-Path:" "Delivered-To:" "X-Spam-Checker-Version:"
                "X-Spam-Status:" "X-Spam-Level:" "X-Qmail-Scrubber-Version:")
              mew-field-delete-for-forwarding))

(setq mew-nntp-header-only t)
(setq mew-nntp-server "news.speakeasy.net")
(setq mew-nntp-newsgroup "comp.databases.berkeley-db")
-------------- next part --------------

<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287296]: RAND_status claims sufficient entropy for the PRNG


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287296]: PRNG seeded successfully


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287296]: Verify directory set to /home/alan/.certs


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287296]: Added /home/alan/.certs revocation lookup directory
2008.09.30 18:42:45 LOG7[791:675287296]: SSL context initialized for service 9425
2008.09.30 18:42:45 LOG5[791:675287296]: stunnel 4.21 on i386-unknown-freebsd7.0 with OpenSSL 0.9.8g 19 Oct 2007
2008.09.30 18:42:45 LOG5[791:675287296]: Threading:PTHREAD SSL:ENGINE Sockets:POLL,IPv6 Auth:LIBWRAP
2008.09.30 18:42:45 LOG6[791:675287296]: file ulimit = 11095 (can be changed with 'ulimit -n')
2008.09.30 18:42:45 LOG6[791:675287296]: poll() used - no FD_SETSIZE limit for file descriptors
2008.09.30 18:42:45 LOG5[791:675287296]: 5417 clients allowed
2008.09.30 18:42:45 LOG7[791:675287296]: FD 8 in non-blocking mode
2008.09.30 18:42:45 LOG7[791:675287296]: FD 9 in non-blocking mode
2008.09.30 18:42:45 LOG7[791:675287296]: FD 10 in non-blocking mode
2008.09.30 18:42:45 LOG7[791:675287296]: SO_REUSEADDR option set on accept socket
2008.09.30 18:42:45 LOG7[791:675287296]: 9425 bound to 127.0.0.1:9425
2008.09.30 18:42:45 LOG7[791:675287296]: No pid file being created


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287296]: 9425 accepted FD=11 from 127.0.0.1:54314
2008.09.30 18:42:45 LOG7[791:675287808]: 9425 started
2008.09.30 18:42:45 LOG7[791:675287808]: FD 11 in non-blocking mode
2008.09.30 18:42:45 LOG7[791:675287808]: Waiting for a libwrap process
2008.09.30 18:42:45 LOG7[791:675287808]: Acquired libwrap process #0


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287808]: Releasing libwrap process #0


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287808]: Released libwrap process #0
2008.09.30 18:42:45 LOG7[791:675287808]: 9425 permitted by libwrap from 127.0.0.1:54314
2008.09.30 18:42:45 LOG5[791:675287808]: 9425 accepted connection from 127.0.0.1:54314
2008.09.30 18:42:45 LOG7[791:675287808]: FD 12 in non-blocking mode
2008.09.30 18:42:45 LOG7[791:675287808]: 9425 connecting 141.146.46.39:465
2008.09.30 18:42:45 LOG7[791:675287808]: connect_wait: waiting 10 seconds


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287808]: connect_wait: connected
2008.09.30 18:42:45 LOG5[791:675287808]: 9425 connected remote server from 192.168.1.6:54763
2008.09.30 18:42:45 LOG7[791:675287808]: Remote FD=12 initialized
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): before/connect initialization
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 write client hello A


<SSL/TLS: >
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 read server hello A


<SSL/TLS: >
2008.09.30 18:42:45 LOG5[791:675287808]: VERIFY OK: depth=2, /C=US/O=VeriSign, Inc./OU=Class 3 Public Primary Certification Authority


<SSL/TLS: >
2008.09.30 18:42:45 LOG5[791:675287808]: VERIFY OK: depth=1, /C=US/O=VeriSign, Inc./OU=VeriSign Trust Network/OU=Terms of use at https://www.verisign.com/rpa (c)05/CN=VeriSign Class 3 Secure Server CA
2008.09.30 18:42:45 LOG5[791:675287808]: VERIFY OK: depth=0, /C=US/ST=California/L=Redwood Shores/O=Oracle Corporation/OU=GIT/OU=Terms of use at www.verisign.com/rpa (c)00/CN=xxxxxxx.oracle.com
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 read server certificate A
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 read server done A
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 write client key exchange A
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 write change cipher spec A
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 write finished A
2008.09.30 18:42:45 LOG7[791:675287808]: SSL state (connect): SSLv3 flush data


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675287808]: SSL state (connect): SSLv3 read finished A


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675287808]:    1 items in the session cache
2008.09.30 18:42:46 LOG7[791:675287808]:    1 client connects (SSL_connect())
2008.09.30 18:42:46 LOG7[791:675287808]:    1 client connects that finished
2008.09.30 18:42:46 LOG7[791:675287808]:    0 client renegotiations requested
2008.09.30 18:42:46 LOG7[791:675287808]:    0 server connects (SSL_accept())
2008.09.30 18:42:46 LOG7[791:675287808]:    0 server connects that finished
2008.09.30 18:42:46 LOG7[791:675287808]:    0 server renegotiations requested
2008.09.30 18:42:46 LOG7[791:675287808]:    0 session cache hits
2008.09.30 18:42:46 LOG7[791:675287808]:    0 session cache misses
2008.09.30 18:42:46 LOG7[791:675287808]:    0 session cache timeouts
2008.09.30 18:42:46 LOG6[791:675287808]: SSL connected: new session negotiated
2008.09.30 18:42:46 LOG6[791:675287808]: Negotiated ciphers: RC4-MD5 SSLv3 Kx=RSA Au=RSA Enc=RC4(128) Mac=MD5 


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675287808]: Socket closed on read
2008.09.30 18:42:46 LOG7[791:675287808]: SSL write shutdown
2008.09.30 18:42:46 LOG7[791:675287808]: SSL alert (write): warning: close notify
2008.09.30 18:42:46 LOG6[791:675287808]: SSL socket closed on SSL_shutdown
2008.09.30 18:42:46 LOG7[791:675287808]: Socket write shutdown
2008.09.30 18:42:46 LOG5[791:675287808]: Connection closed: 0 bytes sent to SSL, 0 bytes sent to socket
2008.09.30 18:42:46 LOG7[791:675287808]: 9425 finished (0 left)
2008.09.30 18:42:46 LOG7[791:675287296]: 9425 accepted FD=11 from 127.0.0.1:54406
2008.09.30 18:42:46 LOG7[791:675288064]: 9425 started
2008.09.30 18:42:46 LOG7[791:675288064]: FD 11 in non-blocking mode
2008.09.30 18:42:46 LOG7[791:675288064]: Waiting for a libwrap process
2008.09.30 18:42:46 LOG7[791:675288064]: Acquired libwrap process #0
2008.09.30 18:42:46 LOG7[791:675288064]: Releasing libwrap process #0
2008.09.30 18:42:46 LOG7[791:675288064]: Released libwrap process #0
2008.09.30 18:42:46 LOG7[791:675288064]: 9425 permitted by libwrap from 127.0.0.1:54406
2008.09.30 18:42:46 LOG5[791:675288064]: 9425 accepted connection from 127.0.0.1:54406
2008.09.30 18:42:46 LOG7[791:675288064]: FD 12 in non-blocking mode
2008.09.30 18:42:46 LOG7[791:675288064]: 9425 connecting 141.146.46.39:465

<SSL/TLS: >

2008.09.30 18:42:46 LOG7[791:675288064]: connect_wait: waiting 10 seconds


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675288064]: connect_wait: connected


<SSL/TLS: >
2008.09.30 18:42:46 LOG5[791:675288064]: 9425 connected remote server from 192.168.1.6:63516
2008.09.30 18:42:46 LOG7[791:675288064]: Remote FD=12 initialized
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): before/connect initialization
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 write client hello A


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 read server hello A


<SSL/TLS: >
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 read finished A
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 write change cipher spec A
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 write finished A
2008.09.30 18:42:46 LOG7[791:675288064]: SSL state (connect): SSLv3 flush data
2008.09.30 18:42:46 LOG7[791:675288064]:    1 items in the session cache
2008.09.30 18:42:46 LOG7[791:675288064]:    2 client connects (SSL_connect())
2008.09.30 18:42:46 LOG7[791:675288064]:    2 client connects that finished
2008.09.30 18:42:46 LOG7[791:675288064]:    0 client renegotiations requested
2008.09.30 18:42:46 LOG7[791:675288064]:    0 server connects (SSL_accept())
2008.09.30 18:42:46 LOG7[791:675288064]:    0 server connects that finished
2008.09.30 18:42:46 LOG7[791:675288064]:    0 server renegotiations requested
2008.09.30 18:42:46 LOG7[791:675288064]:    1 session cache hits
2008.09.30 18:42:46 LOG7[791:675288064]:    0 session cache misses
2008.09.30 18:42:46 LOG7[791:675288064]:    0 session cache timeouts
2008.09.30 18:42:46 LOG6[791:675288064]: SSL connected: previous session reused


<GREETING>
220 default ESMTP Xxxxxx Xxxxxxx Gateway; Tue, 30 Sep 2008 18:42:52 -0700


<=SEND=>
EHLO localhost

<EHLO>
250-default Hello localhost [/xx.xx.xx.xxx] , Pleased to meet you
250-DSN
250-HELP
250 AUTH LOGIN PLAIN


<=SEND=>
AUTH PLAIN XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX

<AUTH-PLAIN>
235 Authentication successful


<=SEND=>
MAIL FROM:<alan.bram at example.com>

<MAIL-FROM>
250 Requested mail action okay, completed


<=SEND=>
RCPT TO:<alan.bram at example.com>

<RCPT-TO>
250 Requested mail action okay, completed


<=SEND=>
DATA

<DATA>
354 Start mail input; end with <CRLF>.<CRLF>


<=SEND=>
.

<CONTENT>
250 Requested mail action okay, completed


<=SEND=>
QUIT

<QUIT>
221 Service closing transmission channel


<SSL/TLS: >
2008.09.30 18:42:49 LOG7[791:675288064]: SSL socket closed on SSL_read


<SSL/TLS: >
2008.09.30 18:42:49 LOG7[791:675288064]: Socket write shutdown
2008.09.30 18:42:49 LOG5[791:675288064]: Connection closed: 519 bytes sent to SSL, 431 bytes sent to socket
2008.09.30 18:42:49 LOG7[791:675288064]: 9425 finished (0 left)


<SMTP SENTINEL>
connection broken by remote peer


<SSL/TLS: >
2008.09.30 18:43:05 LOG7[791:675287296]: 9425 accepted FD=11 from 127.0.0.1:49539
2008.09.30 18:43:05 LOG7[791:675287296]: 9425 accepted FD=12 from 127.0.0.1:60300
2008.09.30 18:43:05 LOG7[791:675288320]: 9425 started
2008.09.30 18:43:05 LOG7[791:675288320]: FD 11 in non-blocking mode
2008.09.30 18:43:05 LOG7[791:675288320]: Waiting for a libwrap process
2008.09.30 18:43:05 LOG7[791:675288320]: Acquired libwrap process #0
2008.09.30 18:43:05 LOG7[791:675288576]: 9425 started
2008.09.30 18:43:05 LOG7[791:675288576]: FD 12 in non-blocking mode
2008.09.30 18:43:05 LOG7[791:675288576]: Waiting for a libwrap process
2008.09.30 18:43:05 LOG7[791:675288576]: Acquired libwrap process #1
2008.09.30 18:43:05 LOG7[791:675288320]: Releasing libwrap process #1
2008.09.30 18:43:05 LOG7[791:675288320]: Released libwrap process #1
2008.09.30 18:43:05 LOG7[791:675288320]: 9425 permitted by libwrap from 127.0.0.1:49539
2008.09.30 18:43:05 LOG5[791:675288320]: 9425 accepted connection from 127.0.0.1:49539
2008.09.30 18:43:05 LOG7[791:675288320]: FD 13 in non-blocking mode
2008.09.30 18:43:05 LOG7[791:675288320]: 9425 connecting 141.146.46.39:465
2008.09.30 18:43:05 LOG7[791:675288320]: connect_wait: waiting 10 seconds


<SSL/TLS: >
2008.09.30 18:43:05 LOG7[791:675288320]: connect_wait: connected


<SSL/TLS: >
2008.09.30 18:43:05 LOG5[791:675288320]: 9425 connected remote server from 192.168.1.6:55251
2008.09.30 18:43:05 LOG7[791:675288320]: Remote FD=13 initialized
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): before/connect initialization
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 write client hello A


<SSL/TLS: >
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 read server hello A


<SSL/TLS: >
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 read finished A
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 write change cipher spec A
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 write finished A
2008.09.30 18:43:05 LOG7[791:675288320]: SSL state (connect): SSLv3 flush data
2008.09.30 18:43:05 LOG7[791:675288320]:    1 items in the session cache
2008.09.30 18:43:05 LOG7[791:675288320]:    3 client connects (SSL_connect())
2008.09.30 18:43:05 LOG7[791:675288320]:    3 client connects that finished
2008.09.30 18:43:05 LOG7[791:675288320]:    0 client renegotiations requested
2008.09.30 18:43:05 LOG7[791:675288320]:    0 server connects (SSL_accept())
2008.09.30 18:43:05 LOG7[791:675288320]:    0 server connects that finished
2008.09.30 18:43:05 LOG7[791:675288320]:    0 server renegotiations requested
2008.09.30 18:43:05 LOG7[791:675288320]:    2 session cache hits
2008.09.30 18:43:05 LOG7[791:675288320]:    0 session cache misses
2008.09.30 18:43:05 LOG7[791:675288320]:    0 session cache timeouts
2008.09.30 18:43:05 LOG6[791:675288320]: SSL connected: previous session reused
2008.09.30 18:43:05 LOG7[791:675288320]: Socket closed on read
2008.09.30 18:43:05 LOG7[791:675288320]: SSL write shutdown
2008.09.30 18:43:05 LOG7[791:675288320]: SSL alert (write): warning: close notify

<SSL/TLS: >

2008.09.30 18:43:05 LOG6[791:675288320]: SSL socket closed on SSL_shutdown
2008.09.30 18:43:05 LOG7[791:675288320]: Socket write shutdown
2008.09.30 18:43:05 LOG5[791:675288320]: Connection closed: 0 bytes sent to SSL, 0 bytes sent to socket
2008.09.30 18:43:05 LOG7[791:675288320]: 9425 finished (1 left)



More information about the Mew-int mailing list