[Exim] Signal 11 in delivery process

Góra strony
Delete this message
Reply to this message
Autor: Dmitry N. Hramtsov
Data:  
Dla: exim-users
Temat: [Exim] Signal 11 in delivery process
Hello,

I encounter the following problem with exim.

Upon attempting to deliver mail to paula@??? address. Their SMTP
server immediately closes the connetction after RCPT TO with incorrect
envelope receiver address. As a result, exim process crashed with signal 11.

Here is relevant logfile entry:

2004-06-13 17:41:09 1BX6kh-0006Kp-FI == paula@??? R=dnslookup T=remote_smtp defer (-1): smtp transport process returned non-zero status 0x000b: terminated by signal 11

For debug purposes I issued the following command:

mx:~# exim4 -d -M 1BX6kh-0006Kp-FI
Exim version 4.32 uid=0 gid=0 pid=1011 D=fbb95cfd
Berkeley DB: Sleepycat Software: Berkeley DB 3.2.9: (June 16, 2003)
Support for: iconv() IPv6 PAM Perl GnuTLS
Lookups: lsearch wildlsearch nwildlsearch cdb dbm dbmnz dnsdb dsearch ldap
ldapdn ldapm mysql nis nis0 passwd pgsql
Authenticators: cram_md5 plaintext spa
Routers: accept dnslookup ipliteral iplookup manualroute queryprogram
redirect
Transports: appendfile/maildir/mailstore/mbx autoreply lmtp pipe smtp
Fixed never_users: 0
changed uid/gid: forcing real = effective
uid=0 gid=0 pid=1011
auxiliary group list: <none>
configuration file is /var/lib/exim4/config.autogenerated
log selectors = 00000ffc 00004200
trusted user
admin user
skipping ACL configuration - not needed
set_process_info: 1011 delivering specified messages
set_process_info: 1011 delivering 1BX6kh-0006Kp-FI
reading spool file 1BX6kh-0006Kp-FI-H
user=Debian-exim uid=101 gid=101 sender=
sender_fullhost = iclub.nsu.ru [193.124.215.97]
sender_rcvhost = iclub.nsu.ru ([193.124.215.97] ident=postfix)
sender_local=0 ident=postfix
Non-recipients:
Empty Tree
---- End of tree ----
recipients_count=1
body_linecount=209 message_linecount=15
Writing spool header file
Size of headers = 637
LOG: MAIN
Unfrozen by errmsg timer
Delivery address list:
paula@???
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

Considering: paula@???
unique = paula@???
dbfn_read: key=R:baticom.com
dbfn_read: key=R:paula@???
no domain retry record
no address retry record
paula@???: queued for routing
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

routing paula@???
--------> through_firewall router <--------
local_part=paula domain=baticom.com
calling through_firewall router
through_firewall router called for paula@???
domain = baticom.com
search_open: lsearch "/etc/exim4/routes"
search_find: file="/etc/exim4/routes"
key="baticom.com" partial=-1 affix=NULL starflags=0
LRU list:
9/etc/exim4/routes
End
internal_search_find: file="/etc/exim4/routes"
type=lsearch key="baticom.com"
file lookup required for baticom.com
in /etc/exim4/routes
lookup failed
through_firewall router declined for paula@???
--------> dnslookup_relay_to_domains router <--------
local_part=paula domain=baticom.com
checking domains
baticom.com in "@:mx.nsu.ru:localhost"? no (end of list)
baticom.com in "! +local_domains : +relay_to_domains"? no (end of list)
dnslookup_relay_to_domains router skipped: domains mismatch
--------> dnslookup router <--------
local_part=paula domain=baticom.com
checking domains
cached no match for +local_domains
cached lookup data = NULL
baticom.com in "! +local_domains"? yes (end of list)
calling dnslookup router
dnslookup router called for paula@???
domain = baticom.com
DNS lookup of baticom.com (MX) succeeded
213.186.33.73 in "0.0.0.0 : 127.0.0.0/8 : 192.168.0.0/16 :172.16.0.0/12 :
169.254.0.0/16"? no (end of list)
213.186.33.74 in "0.0.0.0 : 127.0.0.0/8 : 192.168.0.0/16 :172.16.0.0/12 :
169.254.0.0/16"? no (end of list)
fully qualified name = baticom.com
host_find_bydns yield = HOST_FOUND (2); returned hosts:
mx3.ovh.net 213.186.33.73 MX=1
mx4.ovh.net 213.186.33.74 MX=5
set transport remote_smtp
queued for remote_smtp transport: local_part = paula
domain = baticom.com
errors_to=NULL
domain_data=NULL localpart_data=NULL
routed by dnslookup router
envelope to: paula@???
transport: remote_smtp
host mx3.ovh.net [213.186.33.73] MX=1
host mx4.ovh.net [213.186.33.74] MX=5
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

After routing:
  Local deliveries:
  Remote deliveries:
    paula@???
  Failed addresses:
  Deferred addresses:
search_tidyup called

>>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>

--------> paula@??? <--------
search_tidyup called
set_process_info: 1011 delivering 1BX6kh-0006Kp-FI: waiting for a remote
delivery subprocess to finish
selecting on subprocess pipes
changed uid/gid: remote delivery to paula@??? with
transport=remote_smtp
uid=101 gid=101 pid=1013
auxiliary group list: <none>
set_process_info: 1013 delivering 1BX6kh-0006Kp-FI using remote_smtp
T: remote_smtp for paula@???
remote_smtp transport entered
paula@???
checking status of mx3.ovh.net
locking /var/spool/exim4/db/retry.lockfile
locked /var/spool/exim4/db/retry.lockfile
opened hints database /var/spool/exim4/db/retry: flags=0
dbfn_read: key=T:mx3.ovh.net:213.186.33.73
dbfn_read: key=T:mx3.ovh.net:213.186.33.73:1BX6kh-0006Kp-FI
no host retry record
no message retry record
mx3.ovh.net [213.186.33.73] status = usable
213.186.33.73 in serialize_hosts? no (option unset)
delivering 1BX6kh-0006Kp-FI to mx3.ovh.net [213.186.33.73]
(paula@???)
set_process_info: 1013 delivering 1BX6kh-0006Kp-FI to mx3.ovh.net
[213.186.33.73] (paula@???)
Connecting to mx3.ovh.net [213.186.33.73]:25 ... connected
read response data: size=95
SMTP<< 220 Welcome ! You are on server 31 called mx3.ovh.net, spam
protected: www.spam-rbl.com ESMTP
213.186.33.73 in hosts_avoid_esmtp? no (option unset)
SMTP>> EHLO mx.nsu.ru

read response data: size=214
  SMTP<< 250-Welcome ! You are on server 31 called mx3.ovh.net, spam
protected: www.spam-rbl.com
         250-AUTH LOGIN CRAM-MD5 PLAIN
         250-AUTH=LOGIN CRAM-MD5 PLAIN
         250-PIPELINING
         250-STARTTLS
         250-8BITMIME
         250 SIZE 10000000
213.186.33.73 in hosts_avoid_tls? no (option unset)

SMTP>> STARTTLS

read response data: size=19
SMTP<< 220 ready for tls
initializing GnuTLS as a client
read RSA and D-H parameters from file
initialized RSA and D-H parameters
no TLS client certificate is specified
initialized certificate stuff
initialized GnuTLS session
cipher: TLS-1.0:RSA_ARCFOUR_SHA:16
SMTP>> EHLO mx.nsu.ru

tls_do_write(bfffc9f0, 16)
gnutls_record_send(SSL, bfffc9f0, 16)
outbytes=16
Calling gnutls_record_recv(8135aa8, bfffcdf0, 4096)
read response data: size=200
  SMTP<< 250-Welcome ! You are on server 31 called mx3.ovh.net, spam
protected: www.spam-rbl.com
         250-AUTH LOGIN CRAM-MD5 PLAIN
         250-AUTH=LOGIN CRAM-MD5 PLAIN
         250-PIPELINING
         250-8BITMIME
         250 SIZE 10000000
using PIPELINING
213.186.33.73 in hosts_require_auth? no (option unset)
213.186.33.73 in hosts_try_auth? no (option unset)

SMTP>> MAIL FROM:<> SIZE=9847
SMTP>> RCPT TO:<paula@???>
SMTP>> DATA

tls_do_write(bfffc9f0, 59)
gnutls_record_send(SSL, bfffc9f0, 59)
outbytes=59
Calling gnutls_record_recv(8135aa8, bfffcdf0, 4096)
read response data: size=58
SMTP<< 250 ok
SMTP<< 550 sorry, no mailbox here by that name (#5.1.1)
Calling gnutls_record_recv(8135aa8, bfffcdf0, 4096)
reading pipe for subprocess 1013 (not ended)
read() yielded 0
remote delivery process 1013 ended: status=000b
set_process_info: 1011 delivering 1BX6kh-0006Kp-FI
post-process paula@??? (1)
LOG: MAIN
== paula@??? R=dnslookup T=remote_smtp defer (-1): smtp
transport process returned non-zero status 0x000b: terminated by signal 11
>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>

changed uid/gid: post-delivery tidying
uid=101 gid=101 pid=1011
auxiliary group list: <none>
set_process_info: 1011 tidying up after delivering 1BX6kh-0006Kp-FI
Processing retry items
Succeeded addresses:
Failed addresses:
Deferred addresses:
paula@???: no retry items
end of retry processing
LOG: MAIN
Frozen
delivery deferred: update_spool=1 header_rewritten=0
Writing spool header file
Size of headers = 637
end delivery of 1BX6kh-0006Kp-FI
search_tidyup called
search_tidyup called
>>>>>>>>>>>>>>>> Exim pid=1011 terminating with rc=0 >>>>>>>>>>>>>>>>



So I believe this is a bug in exim mail server. :)
Any help or comments will be appreciated.

Best regards,
Dmitry N. Hramtsov