#exim -bd -d+all
...........
02:39:49 32501 Delivery address list:
02:39:49 32501
real-mailbox@ukr.net
02:39:49 32501 locking /var/spool/exim4/db/retry.lockfile
02:39:49 32501 locked /var/spool/exim4/db/retry.lockfile
02:39:49 32501 EXIM_DBOPEN(/var/spool/exim4/db/retry)
02:39:49 32501 returned from EXIM_DBOPEN
02:39:49 32501 opened hints database /var/spool/exim4/db/retry: flags=O_RDONLY
02:39:49 32501 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
02:39:49 32501 Considering:
real-mailbox@ukr.net
02:39:49 32501 unique =
real-mailbox@ukr.net
02:39:49 32501 dbfn_read: key=R:ukr.net
02:39:49 32501 dbfn_read: key=R:
real-mailbox@ukr.net
02:39:49 32501 no domain retry record
02:39:49 32501
real-mailbox@ukr.net: queued for routing
02:39:49 32501 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
02:39:49 32501 routing
real-mailbox@ukr.net
02:39:49 32501 --------> dnslookup router <--------
02:39:49 32501 local_part=
real-mailbox domain=ukr.net
02:39:49 32501 checking domains
02:39:49 32501 expanding: $domain
02:39:49 32501 result: ukr.net
02:39:49 32501 expanding: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = '${quote_mysql:$domain}'
02:39:49 32501 result: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'ukr.net'
02:39:49 32501 search_open: mysql "NULL"
02:39:49 32501 search_find: file="NULL"
02:39:49 32501 key="SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'ukr.net'" partial=-1 affix=NULL starflags=0
02:39:49 32501 LRU list:
02:39:49 32501 internal_search_find: file="NULL"
02:39:49 32501 type=mysql key="SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'ukr.net'"
02:39:49 32501 database lookup required for SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'ukr.net'
02:39:49 32501 MYSQL query: SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = 'ukr.net'
02:39:49 32501 MYSQL new connection: host=localhost port=0 socket=NULL database=vexim user=vexim
02:39:49 32501 MYSQL: no data found
02:39:49 32501 lookup failed
02:39:49 32501 expanding: $domain
02:39:49 32501 result: ukr.net
02:39:49 32501 expanding: SELECT DISTINCT alias FROM domainalias WHERE alias = '${quote_mysql:$domain}'
02:39:49 32501 result: SELECT DISTINCT alias FROM domainalias WHERE alias = 'ukr.net'
02:39:49 32501 search_open: mysql "NULL"
02:39:49 32501 cached open
02:39:49 32501 search_find: file="NULL"
02:39:49 32501 key="SELECT DISTINCT alias FROM domainalias WHERE alias = 'ukr.net'" partial=-1 affix=NULL starflags=0
02:39:49 32501 LRU list:
02:39:49 32501 internal_search_find: file="NULL"
02:39:49 32501 type=mysql key="SELECT DISTINCT alias FROM domainalias WHERE alias = 'ukr.net'"
02:39:49 32501 database lookup required for SELECT DISTINCT alias FROM domainalias WHERE alias = 'ukr.net'
02:39:49 32501 MYSQL query: SELECT DISTINCT alias FROM domainalias WHERE alias = 'ukr.net'
02:39:49 32501 MYSQL using cached connection for localhost/vexim/vexim
02:39:49 32501 MYSQL: no data found
02:39:49 32501 lookup failed
02:39:49 32501 expanding: @ : ${lookup mysql{SELECT DISTINCT domain FROM domains WHERE type = 'local' AND enabled = '1' AND domain = '${quote_mysql:$domain}'}} : ${lookup mysql{SELECT DISTINCT alias FROM domainalias WHERE alias = '${quote_mysql:$domain}'}}
02:39:49 32501 result: @ : :
02:39:49 32501 ukr.net in "@ : : "? no (end of list)
02:39:49 32501 ukr.net in "! +local_domains"? yes (end of list)
02:39:49 32501 calling dnslookup router
02:39:49 32501 dnslookup router called for
real-mailbox@ukr.net
02:39:49 32501 domain = ukr.net
02:39:49 32501 DNS lookup of ukr.net (MX) succeeded
02:39:49 32501 DNS lookup of mxs.ukr.net (AAAA) gave NO_DATA
02:39:49 32501 returning DNS_NODATA
02:39:49 32501 DNS lookup of mxs.ukr.net (A) succeeded
02:39:49 32501 195.214.192.100 in "0.0.0.0 : 127.0.0.0/8 : 10.0.0.0/8 "? no (end of list)
02:39:49 32501 Actual local interface address is 127.0.0.1 (lo)
02:39:49 32501 Actual local interface address is 127.0.0.1 (venet0)
02:39:49 32501 Actual local interface address is 91.203.XXX.XXX (venet0:0)
02:39:49 32501 Actual local interface address is ::1 (lo)
02:39:49 32501 fully qualified name = ukr.net
02:39:49 32501 host_find_bydns yield = HOST_FOUND (2); returned hosts:
02:39:49 32501 mxs.ukr.net 195.214.192.100 MX=10
02:39:49 32501 set transport remote_smtp
02:39:49 32501 queued for remote_smtp transport: local_part =
real-mailbox
02:39:49 32501 domain = ukr.net
02:39:49 32501 errors_to=NULL
02:39:49 32501 domain_data=NULL localpart_data=NULL
02:39:49 32501 routed by dnslookup router
02:39:49 32501 envelope to:
real-mailbox@ukr.net
02:39:49 32501 transport: remote_smtp
02:39:49 32501 host mxs.ukr.net [195.214.192.100] MX=10
02:39:49 32501 added retry item for R:
real-mailbox@ukr.net: errno=-1 more_errno=0 flags=1
02:39:49 32501 added retry item for R:ukr.net: errno=-1 more_errno=0 flags=1
02:39:49 32501 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
02:39:49 32501 After routing:
02:39:49 32501 Local deliveries:
02:39:49 32501 Remote deliveries:
02:39:49 32501
real-mailbox@ukr.net
02:39:49 32501 Failed addresses:
02:39:49 32501 Deferred addresses:
02:39:49 32501 search_tidyup called
02:39:49 32501 close MYSQL connection: localhost/vexim/vexim
02:39:49 32501 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
02:39:49 32501 -------->
real-mailbox@ukr.net <--------
02:39:49 32501 search_tidyup called
02:39:49 32502 changed uid/gid: remote delivery to
real-mailbox@ukr.net with transport=remote_smtp
02:39:49 32502 uid=102 gid=102 pid=32502
02:39:49 32502 auxiliary group list: <none>
02:39:49 32502 set_process_info: 32502 delivering 1KRbYj-0008SB-Ki using remote_smtp
02:39:49 32502 remote_smtp transport entered
02:39:49 32502
real-mailbox@ukr.net
02:39:49 32502 ukr.net in queue_smtp_domains? no (option unset)
02:39:49 32502 checking status of mxs.ukr.net
02:39:49 32502 locking /var/spool/exim4/db/retry.lockfile
02:39:49 32502 locked /var/spool/exim4/db/retry.lockfile
02:39:49 32502 EXIM_DBOPEN(/var/spool/exim4/db/retry)
02:39:49 32502 returned from EXIM_DBOPEN
02:39:49 32502 opened hints database /var/spool/exim4/db/retry: flags=O_RDONLY
02:39:49 32502 dbfn_read: key=T:mxs.ukr.net:195.214.192.100
02:39:49 32502 dbfn_read: key=T:mxs.ukr.net:195.214.192.100:1KRbYj-0008SB-Ki
02:39:49 32502 no host retry record
02:39:49 32502 no message retry record
02:39:49 32502 mxs.ukr.net [195.214.192.100] status = usable
02:39:49 32502 195.214.192.100 in serialize_hosts? no (option unset)
02:39:49 32502 delivering 1KRbYj-0008SB-Ki to mxs.ukr.net [195.214.192.100] (
real-mailbox@ukr.net)
02:39:49 32502 set_process_info: 32502 delivering 1KRbYj-0008SB-Ki to mxs.ukr.net [195.214.192.100] (
real-mailbox@ukr.net)
02:39:49 32502 expanding: $primary_hostname
02:39:49 32502 result:
domain-ua.net.ua
02:39:49 32501 set_process_info: 32501 delivering 1KRbYj-0008SB-Ki: waiting for a remote delivery subprocess to finish
02:39:49 32501 selecting on subprocess pipes
02:39:49 32502 Connecting to mxs.ukr.net [195.214.192.100]:25 ... connected
02:39:49 32502 waiting for data on socket
02:39:49 32502 read response data: size=51
02:39:49 32502 SMTP<< 220 UKR.NET ESMTP Fri, 08 Aug 2008 23:51:01 +0300
02:39:49 32502 195.214.192.100 in hosts_avoid_esmtp? no (option unset)
02:39:49 32502 SMTP>> EHLO
domain-ua.net.ua
02:39:49 32502 waiting for data on socket
02:39:49 32502 read response data: size=108
02:39:49 32502 SMTP<< 250-mx2.ukr.net Hello
domain-ua.net.ua [91.203.xxx.xxx]
02:39:49 32502 250-SIZE 26214400
02:39:49 32502 250-8BITMIME
02:39:49 32502 250-PIPELINING
02:39:49 32502 250 HELP
02:39:49 32502 195.214.192.100 in hosts_require_tls? no (option unset)
02:39:49 32502 using PIPELINING
02:39:49 32502 195.214.192.100 in hosts_require_auth? no (option unset)
02:39:49 32502 SMTP>> MAIL FROM:<wel@
domain-ua.net.ua> SIZE=4226
02:39:49 32502 SMTP>> RCPT TO:<
real-mailbox@ukr.net>
02:39:49 32502 SMTP>> DATA
02:39:49 32502 waiting for data on socket
02:39:52 32502 read response data: size=198
02:39:52 32502 SMTP<< 250 OK
02:39:52 32502 SMTP<< 451
http://ukr.net/mta/std3.html?91.203.xxx.xxx
02:39:52 32502 added retry item for R:
real-mailbox@ukr.net: errno=-44 more_errno=51,M flags=0
02:39:52 32502 SMTP<< 503-All RCPT commands were rejected with this error:
02:39:52 32502 503-
http://ukr.net/mta/std3.html?91.203.xxx.xxx
02:39:52 32502 503 Valid RCPT command must precede DATA
02:39:52 32502 SMTP error from remote mail server after pipelined DATA: host mxs.ukr.net [195.214.192.100]: 503-All RCPT commands were rejected with this error:\n503-
http://ukr.net/mta/std3.html?91.203.xxx.xxx\n503 Valid RCPT command must precede DATA
02:39:52 32502 error for DATA ignored: pipelining is in use and there were no good recipients
02:39:52 32502 ok=1 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address is NULL
02:39:52 32502 SMTP>> QUIT
02:39:52 32502 set_process_info: 32502 delivering 1KRbYj-0008SB-Ki: just tried mxs.ukr.net [195.214.192.100] for
real-mailbox@ukr.net: result OK
02:39:52 32502 all IP addresses skipped or deferred at least one address
02:39:52 32502 locking /var/spool/exim4/db/wait-remote_smtp.lockfile
02:39:52 32502 locked /var/spool/exim4/db/wait-remote_smtp.lockfile
02:39:52 32502 EXIM_DBOPEN(/var/spool/exim4/db/wait-remote_smtp)
02:39:52 32502 returned from EXIM_DBOPEN
02:39:52 32502 opened hints database /var/spool/exim4/db/wait-remote_smtp: flags=O_RDWR
02:39:52 32502 Leaving remote_smtp transport
02:39:52 32502 set_process_info: 32502 delivering 1KRbYj-0008SB-Ki (just run remote_smtp for
real-mailbox@ukr.net in subprocess)
02:39:52 32502 search_tidyup called
02:39:52 32501 reading pipe for subprocess 32502 (not ended)
02:39:52 32501 read() yielded 408
02:39:52 32501 reading retry information for R:
real-mailbox@ukr.net from subprocess
02:39:52 32501 existing delete item dropped
02:39:52 32501 added retry item
02:39:52 32501 reading retry information for R:ukr.net from subprocess
02:39:52 32501 existing delete item dropped
02:39:52 32501 added delete item
02:39:52 32501 reading retry information for R:
real-mailbox@ukr.net from subprocess
02:39:52 32501 delete item not added: non-delete item exists
02:39:52 32501 Z0 item read
02:39:52 32501 remote delivery process 32502 ended
02:39:52 32501 set_process_info: 32501 delivering 1KRbYj-0008SB-Ki
02:39:52 32501 post-process
real-mailbox@ukr.net (1)
02:39:52 32501 LOG: MAIN
02:39:52 32501 ==
real-mailbox@ukr.net R=dnslookup T=remote_smtp defer (-44): SMTP error from remote mail server after RCPT TO:<
real-mailbox@ukr.net>: host mxs.ukr.net [195.214.192.100]: 451
http://ukr.net/mta/std3.html?91.203.xxx.xxx
02:39:52 32501 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
02:39:52 32501 changed uid/gid: post-delivery tidying
02:39:52 32501 uid=102 gid=102 pid=32501
02:39:52 32501 auxiliary group list: <none>
02:39:52 32501 set_process_info: 32501 tidying up after delivering 1KRbYj-0008SB-Ki
02:39:52 32501 Processing retry items
02:39:52 32501 Succeeded addresses:
02:39:52 32501 Failed addresses:
02:39:52 32501 Deferred addresses:
02:39:52 32501
real-mailbox@ukr.net
02:39:52 32501 locking /var/spool/exim4/db/retry.lockfile
02:39:52 32501 locked /var/spool/exim4/db/retry.lockfile
02:39:52 32501 EXIM_DBOPEN(/var/spool/exim4/db/retry)
02:39:52 32501 returned from EXIM_DBOPEN
02:39:52 32501 opened hints database /var/spool/exim4/db/retry: flags=O_RDWR
02:39:52 32501 deleted retry information for R:ukr.net
02:39:52 32501 address match: subject=
real-mailbox@ukr.net pattern=*
02:39:52 32501 ukr.net in "*"? yes (matched "*")
02:39:52 32501
real-mailbox@ukr.net in "*"? yes (matched "*")
02:39:52 32501 retry for R:
real-mailbox@ukr.net = * 0 0
02:39:52 32501 dbfn_read: key=R:
real-mailbox@ukr.net
02:39:52 32501 failing_interval=13704 message_age=0
02:39:52 32501 Writing retry data for R:
real-mailbox@ukr.net
02:39:52 32501 first failed=1218225088 last try=1218238792 next try=1218242392 expired=0
02:39:52 32501 errno=-44 more_errno=51,M SMTP error from remote mail server after RCPT TO:<
real-mailbox@ukr.net>: host mxs.ukr.net [195.214.192.100]: 451
http://ukr.net/mta/std3.html?91.203.xxx.xxx
02:39:52 32501 dbfn_write: key=R:
real-mailbox@ukr.net
02:39:52 32501 end of retry processing
02:39:52 32501 expanding: $h_list-id:$h_list-post:$h_list-subscribe:
02:39:52 32501 result:
02:39:52 32501 expanding:
02:39:52 32501 result:
02:39:52 32501 expanding: $h_precedence:
02:39:52 32501 result:
02:39:52 32501 expanding: (?i)bulk|list|junk
02:39:52 32501 result: (?i)bulk|list|junk
02:39:52 32501 expanding: $h_auto-submitted:
02:39:52 32501 result:
02:39:52 32501 expanding: (?i)auto-generated|auto-replied
02:39:52 32501 result: (?i)auto-generated|auto-replied
02:39:52 32501 condition: or {{ !eq{$h_list-id:$h_list-post:$h_list-subscribe:}{} }{ match{$h_precedence:}{(?i)bulk|list|junk} }{ match{$h_auto-submitted:}{(?i)auto-generated|auto-replied} }}
02:39:52 32501 result: false
02:39:52 32501 expanding: no
02:39:52 32501 result: no
02:39:52 32501 skipping: result is not used
02:39:52 32501 expanding: yes
02:39:52 32501 result: yes
02:39:52 32501 expanding: ${if or {{ !eq{$h_list-id:$h_list-post:$h_list-subscribe:}{} }{ match{$h_precedence:}{(?i)bulk|list|junk} }{ match{$h_auto-submitted:}{(?i)auto-generated|auto-replied} }} {no}{yes}}
02:39:52 32501 result: yes
02:39:52 32501 time on queue = 3s
02:39:52 32501 warning counts: required 0 done 0
02:39:52 32501 delivery deferred: update_spool=1 header_rewritten=0
02:39:52 32501 Writing spool header file
02:39:52 32501 Size of headers = 658
02:39:52 32501 end delivery of 1KRbYj-0008SB-Ki
02:39:52 32501 search_tidyup called
02:39:52 32501 search_tidyup called
02:39:52 32501 >>>>>>>>>>>>>>>> Exim pid=32501 terminating with rc=0 >>>>>>>>>>>>>>>>