Bug#280647: [exim] Why did this message get frozen?

John Goerzen John Goerzen <jgoerzen@complete.org>, 280647@bugs.debian.org
Thu, 11 Nov 2004 08:23:48 -0600


--UlVJffcvxoiEqYs2
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline

On Thu, Nov 11, 2004 at 10:09:21AM +0000, Philip Hazel wrote:
> On Wed, 10 Nov 2004, John Goerzen wrote:
> 
> > Here is the note Exim sent me:
> 
> Which release of Exim?

This is 4.34, which is unfortunately the latest version currently
available in Debian.  I am CCing this message to the Debian maintainer
and a bug report I submitted there regarding the segfault.

I can also show you this, which may be of interest:

# host -t mx ihst.ru
ihst.ru                 MX      10 luna.ihst.ru

$ telnet luna.ihst.ru smtp
Trying 195.19.12.3...
Connected to luna.ihst.ru.
Escape character is '^]'.
220 Ihst.RU ESMTP CommuniGate Pro 4.1.8
EHLO glockenspiel.complete.org
250-Ihst.RU we trust you glockenspiel.complete.org
250-HELP
250-PIPELINING
250-ETRN
250-DSN
250-TURN
250-ATRN
250-SIZE 10485760
250-STARTTLS
250-AUTH=LOGIN
250-AUTH LOGIN PLAIN CRAM-MD5 DIGEST-MD5 MSN
250-8BITMIME
250 EHLO
MAIL FROM:<jgoerzen@complete.org>
250 jgoerzen@complete.org sender accepted
RCPT TO:<glad@ihst.ru>
550 glad@ihst.ru unknown user account
Connection closed by foreign host.

I've also attached the portion of the debug log relating to the segfault
on the delivery to this recipient.

> > Message 1CRm0w-0002fM-Dr has been frozen.
> 
> The reason for freezing should be in the main Exim log and in the 
> message's own message log.

All I see either place is "Frozen".  I do see the sig11 logged.  I
didn't know that would cause it to freeze.  So that makes sense, I
suppose.

> might give some clue as to why the crash is happening. If you are not
> running the latest release, please upgrade to the latest release and try
> that.

Not the easiest thing on Debian since there isn't a 4.43 packaged, but
if the maintainer doesn't package it, I will try to do that myself.

Anyway, the debug log is attached.

-- John

--UlVJffcvxoiEqYs2
Content-Type: text/plain; charset=us-ascii
Content-Disposition: attachment; filename="debug.txt"

 2686 Remote delivery process started
 2686 changed uid/gid: remote delivery to glad@ihst.ru with transport=remote_smtp
 2686   uid=107 gid=107 pid=2686
 2686   auxiliary group list: <none>
 2686 set_process_info:  2686 delivering 1CRm0w-0002fM-Dr using remote_smtp
 2686 T: remote_smtp for glad@ihst.ru
 2686 remote_smtp transport entered
 2686   glad@ihst.ru
 2686 checking status of luna.ihst.ru
 2686 locking /var/spool/exim4/db/retry.lockfile
 2686 locked /var/spool/exim4/db/retry.lockfile
 2686 opened hints database /var/spool/exim4/db/retry: flags=0
 2686 dbfn_read: key=T:luna.ihst.ru:195.19.12.3
 2686 dbfn_read: key=T:luna.ihst.ru:195.19.12.3:1CRm0w-0002fM-Dr
 2686 no message retry record
 2686 luna.ihst.ru [195.19.12.3] status = usable
 2686 195.19.12.3 in serialize_hosts? no (option unset)
 2686 delivering 1CRm0w-0002fM-Dr to luna.ihst.ru [195.19.12.3] (glad@ihst.ru)
 2686 set_process_info:  2686 delivering 1CRm0w-0002fM-Dr to luna.ihst.ru [195.19.12.3] (glad@ihst.ru)
 2686 Connecting to luna.ihst.ru [195.19.12.3]:25 ... connected
 2686 read response data: size=41
 2686   SMTP<< 220 Ihst.RU ESMTP CommuniGate Pro 4.1.8
 2686 195.19.12.3 in hosts_avoid_esmtp? no (option unset)
 2686   SMTP>> EHLO glockenspiel.complete.org
 2686 read response data: size=236
 2686   SMTP<< 250-Ihst.RU we trust you glockenspiel.complete.org
 2686          250-HELP
 2686          250-PIPELINING
 2686          250-ETRN
 2686          250-DSN
 2686          250-TURN
 2686          250-ATRN
 2686          250-SIZE 10485760
 2686          250-STARTTLS
 2686          250-AUTH=LOGIN
 2686          250-AUTH LOGIN PLAIN CRAM-MD5 DIGEST-MD5 MSN
 2686          250-8BITMIME
 2686          250 EHLO
 2686 195.19.12.3 in hosts_avoid_tls? no (option unset)
 2686   SMTP>> STARTTLS
 2686 read response data: size=35
 2686   SMTP<< 220 please start a TLS connection
 2686 initializing GnuTLS as a client
 2686 read RSA and D-H parameters from file
 2686 initialized RSA and D-H parameters
 2686 certificate file = /etc/ssl/certs/glockenspiel.complete.org.key.pem
 2686 key file = /etc/ssl/certs/glockenspiel.complete.org.key.pem
 2686 initialized certificate stuff
 2686 initialized GnuTLS session
 2686 cipher: TLS-1.0:RSA_ARCFOUR_SHA:16
 2686   SMTP>> EHLO glockenspiel.complete.org
 2686 tls_do_write(bfffcc70, 32)
 2686 gnutls_record_send(SSL, bfffcc70, 32)
 2686 outbytes=32
 2686 Calling gnutls_record_recv(813ce08, bfffd070, 4096)
 2686 read response data: size=222
 2686   SMTP<< 250-Ihst.RU we trust you glockenspiel.complete.org
 2686          250-HELP
 2686          250-PIPELINING
 2686          250-ETRN
 2686          250-DSN
 2686          250-TURN
 2686          250-ATRN
 2686          250-SIZE 10485760
 2686          250-AUTH=LOGIN
 2686          250-AUTH LOGIN PLAIN CRAM-MD5 DIGEST-MD5 MSN
 2686          250-8BITMIME
 2686          250 EHLO
 2686 using PIPELINING
 2686 195.19.12.3 in hosts_require_auth? no (option unset)
 2686 195.19.12.3 in hosts_try_auth? no (option unset)
 2686   SMTP>> MAIL FROM:<[hidden for public display]> SIZE=3598
 2686   SMTP>> RCPT TO:<glad@ihst.ru>
 2686   SMTP>> DATA
 2686 tls_do_write(bfffcc70, 84)
 2686 gnutls_record_send(SSL, bfffcc70, 84)
 2686 outbytes=84
 2686 Calling gnutls_record_recv(813ce08, bfffd070, 4096)
 2686 read response data: size=52
 2686   SMTP<< 250 [hidden for public display] sender accepted
 2686 Calling gnutls_record_recv(813ce08, bfffd070, 4096)
 2686 read response data: size=39
 2686   SMTP<< 550 glad@ihst.ru unknown user account
 2686 Calling gnutls_record_recv(813ce08, bfffd070, 4096)
 2686 Got TLS_EOF
 2686 Remote host luna.ihst.ru [195.19.12.3] closed connection in response to pipelined DATA
 2686 error for DATA ignored: pipelining is in use and there were no good recipients
 2686 ok=1 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address=0
 2686 195.19.12.3 in hosts_nopass_tls? no (option unset)
 2686 transport_check_waiting entered
 2686   sequence=1 local_max=500 global_max=-1
 2686 locking /var/spool/exim4/db/wait-remote_smtp.lockfile
 2686 locked /var/spool/exim4/db/wait-remote_smtp.lockfile
 2686 opened hints database /var/spool/exim4/db/wait-remote_smtp: flags=2
 2686 dbfn_read: key=luna.ihst.ru
 2686 dbfn_write: key=luna.ihst.ru
 2686   SMTP>> RSET
 2686 tls_do_write(bfffcc70, 6)
 2686 gnutls_record_send(SSL, bfffcc70, 6)
 2686 outbytes=6
 2686 Calling gnutls_record_recv(813ce08, bfffd070, 4096)
reading pipe for subprocess 2686 (not ended)
read() yielded 0
remote delivery process 2686 ended: status=000b
set_process_info:  2593 delivering 1CRm0w-0002fM-Dr
post-process glad@ihst.ru (1)
LOG: MAIN
  == glad@ihst.ru R=dnslookup T=remote_smtp defer (-1): smtp transport process returned non-zero status 0x000b: terminated by signal 11
set_process_info:  2593 delivering 1CRm0w-0002fM-Dr: waiting for a remote delivery subprocess to finish
selecting on subprocess pipes
selecting on subprocess pipes
selecting on subprocess pipes

--UlVJffcvxoiEqYs2--