exit-time debug
[exim.git] / test / stderr / 0044
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3 uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 changed uid/gid: privilege not needed
7 uid=EXIM_UID gid=EXIM_GID pid=pppp
8 seeking password data for user "CALLER": cache not available
9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
11 sender address = CALLER@exim.test.ex
12 sender_fullhost = [V4NET.11.12.13]
13 sender_rcvhost = [V4NET.11.12.13]
14 host in hosts_connection_nolog? no (option unset)
15 LOG: smtp_connection MAIN
16 SMTP connection from [V4NET.11.12.13]
17 host in host_lookup? no (option unset)
18 set_process_info: pppp handling incoming connection from [V4NET.11.12.13]
19 host in host_reject_connection? no (option unset)
20 host in sender_unqualified_hosts? no (option unset)
21 host in recipient_unqualified_hosts? no (option unset)
22 host in helo_verify_hosts? no (option unset)
23 host in helo_try_verify_hosts? no (option unset)
24 host in helo_accept_junk_hosts? no (option unset)
25 SMTP>> 220 the.local.host.name ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
26 smtp_setup_msg entered
27 SMTP<< ehlo exim.test.ex
28 exim.test.ex in helo_lookup_domains? no (end of list)
29 sender_fullhost = (exim.test.ex) [V4NET.11.12.13]
30 sender_rcvhost = [V4NET.11.12.13] (helo=exim.test.ex)
31 set_process_info: pppp handling incoming connection from (exim.test.ex) [V4NET.11.12.13]
32 host in dsn_advertise_hosts? no (option unset)
33 host in pipelining_advertise_hosts? yes (matched "*")
34 host in chunking_advertise_hosts? no (end of list)
35 SMTP>> 250-the.local.host.name Hello exim.test.ex [V4NET.11.12.13]
36 250-SIZE 52428800
37 250-8BITMIME
38 250-PIPELINING
39 250 HELP
40 SMTP<< mail from:postmaster@exim.test.ex
41 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
42 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
43 SMTP>> 250 OK
44 SMTP<< rcpt to:<postmaster@exim.test.ex>
45 using ACL "check_recipient"
46 processing "accept" (TESTSUITE/test-config 19)
47 check hosts = :
48 host in ":"? no (end of list)
49 accept: condition test failed in ACL "check_recipient"
50 processing "accept" (TESTSUITE/test-config 20)
51 check recipients = postmaster@exim.test.ex
52 address match test: subject=postmaster@exim.test.ex pattern=postmaster@exim.test.ex
53 exim.test.ex in "exim.test.ex"? yes (matched "exim.test.ex")
54 postmaster@exim.test.ex in "postmaster@exim.test.ex"? yes (matched "postmaster@exim.test.ex")
55 accept: condition test succeeded in ACL "check_recipient"
56 end of ACL "check_recipient": ACCEPT
57 SMTP>> 250 Accepted
58 DSN: orcpt: NULL flags: 0
59 SMTP<< rcpt to:list@exim.test.ex
60 using ACL "check_recipient"
61 processing "accept" (TESTSUITE/test-config 19)
62 check hosts = :
63 host in ":"? no (end of list)
64 accept: condition test failed in ACL "check_recipient"
65 processing "accept" (TESTSUITE/test-config 20)
66 check recipients = postmaster@exim.test.ex
67 address match test: subject=list@exim.test.ex pattern=postmaster@exim.test.ex
68 list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
69 accept: condition test failed in ACL "check_recipient"
70 processing "accept" (TESTSUITE/test-config 21)
71 check senders = myfriend@*
72 address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
73 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
74 accept: condition test failed in ACL "check_recipient"
75 processing "deny" (TESTSUITE/test-config 22)
76 message: host is listed in $dnslist_domain
77 check dnslists = rbl.test.ex
78 dnslists check: rbl.test.ex
79 new DNS lookup for 13.12.11.V4NET.rbl.test.ex
80 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens
81 fresh-exec forking for fakens-search
82 postfork: fakens-search
83 fresh-exec forked for fakens-search: npppp
84 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded
85 dnslists: wrote cache entry, ttl=3
86 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
87 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens
88 fresh-exec forking for fakens-search
89 postfork: fakens-search
90 fresh-exec forked for fakens-search: npppp
91 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded
92 => that means V4NET.11.12.13 is listed at rbl.test.ex
93 deny: condition test succeeded in ACL "check_recipient"
94 end of ACL "check_recipient": DENY
95 SMTP>> 550 host is listed in rbl.test.ex
96 LOG: MAIN REJECT
97 H=(exim.test.ex) [V4NET.11.12.13] F=<postmaster@exim.test.ex> rejected RCPT list@exim.test.ex: host is listed in rbl.test.ex
98 SMTP<< rcpt to:list2@exim.test.ex
99 using ACL "check_recipient"
100 processing "accept" (TESTSUITE/test-config 19)
101 check hosts = :
102 host in ":"? no (end of list)
103 accept: condition test failed in ACL "check_recipient"
104 processing "accept" (TESTSUITE/test-config 20)
105 check recipients = postmaster@exim.test.ex
106 address match test: subject=list2@exim.test.ex pattern=postmaster@exim.test.ex
107 list2@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
108 accept: condition test failed in ACL "check_recipient"
109 processing "accept" (TESTSUITE/test-config 21)
110 check senders = myfriend@*
111 address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
112 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
113 accept: condition test failed in ACL "check_recipient"
114 processing "deny" (TESTSUITE/test-config 22)
115 message: host is listed in $dnslist_domain
116 check dnslists = rbl.test.ex
117 dnslists check: rbl.test.ex
118 dnslists: using result of previous lookup
119 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
120 => that means V4NET.11.12.13 is listed at rbl.test.ex
121 deny: condition test succeeded in ACL "check_recipient"
122 end of ACL "check_recipient": DENY
123 SMTP>> 550 host is listed in rbl.test.ex
124 LOG: MAIN REJECT
125 H=(exim.test.ex) [V4NET.11.12.13] F=<postmaster@exim.test.ex> rejected RCPT list2@exim.test.ex: host is listed in rbl.test.ex
126 SMTP<< data
127 SMTP>> 354 Enter message, ending with "." on a line by itself
128 search_tidyup called
129 host in ignore_fromline_hosts? no (option unset)
130 >>Headers received:
131
132 search_tidyup called
133 >>Headers after rewriting and local additions:
134
135 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
136 Data file written for message 10HmaX-0005vi-00
137 >>Generated Received: header line
138 P Received: from [V4NET.11.12.13] (helo=exim.test.ex)
139 by the.local.host.name with esmtp (Exim x.yz)
140 (envelope-from <postmaster@exim.test.ex>)
141 id 10HmaX-0005vi-00
142 for postmaster@exim.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
143 LOG: MAIN
144 <= postmaster@exim.test.ex H=(exim.test.ex) [V4NET.11.12.13] P=esmtp S=sss
145 SMTP>> 250 OK id=10HmaX-0005vi-00
146 smtp_setup_msg entered
147 SMTP<< quit
148 SMTP>> 221 the.local.host.name closing connection
149 LOG: smtp_connection MAIN
150 SMTP connection from (exim.test.ex) [V4NET.11.12.13] closed by QUIT
151 search_tidyup called
152 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
153 >>> host in hosts_connection_nolog? no (option unset)
154 >>> host in host_lookup? no (option unset)
155 >>> host in host_reject_connection? no (option unset)
156 >>> host in sender_unqualified_hosts? no (option unset)
157 >>> host in recipient_unqualified_hosts? no (option unset)
158 >>> host in helo_verify_hosts? no (option unset)
159 >>> host in helo_try_verify_hosts? no (option unset)
160 >>> host in helo_accept_junk_hosts? no (option unset)
161 >>> exim.test.ex in helo_lookup_domains? no (end of list)
162 >>> host in dsn_advertise_hosts? no (option unset)
163 >>> host in pipelining_advertise_hosts? yes (matched "*")
164 >>> host in chunking_advertise_hosts? no (end of list)
165 >>> using ACL "check_recipient"
166 >>> processing "accept" (TESTSUITE/test-config 19)
167 >>> check hosts = :
168 >>> host in ":"? no (end of list)
169 >>> accept: condition test failed in ACL "check_recipient"
170 >>> processing "accept" (TESTSUITE/test-config 20)
171 >>> check recipients = postmaster@exim.test.ex
172 >>> list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
173 >>> accept: condition test failed in ACL "check_recipient"
174 >>> processing "accept" (TESTSUITE/test-config 21)
175 >>> check senders = myfriend@*
176 >>> there.test.ex in "*"? yes (matched "*")
177 >>> myfriend@there.test.ex in "myfriend@*"? yes (matched "myfriend@*")
178 >>> accept: condition test succeeded in ACL "check_recipient"
179 >>> end of ACL "check_recipient": ACCEPT
180 >>> host in ignore_fromline_hosts? no (option unset)
181 LOG: 10HmaY-0005vi-00 <= myfriend@there.test.ex H=(exim.test.ex) [V4NET.11.12.13] P=esmtp S=sss
182 Exim version x.yz ....
183 changed uid/gid: forcing real = effective
184 uid=uuuu gid=CALLER_GID pid=pppp
185 configuration file is TESTSUITE/test-config
186 admin user
187 changed uid/gid: privilege not needed
188 uid=EXIM_UID gid=EXIM_GID pid=pppp
189 seeking password data for user "CALLER": cache not available
190 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
191 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
192 sender address = CALLER@exim.test.ex
193 sender_fullhost = [V4NET.99.99.99]
194 sender_rcvhost = [V4NET.99.99.99]
195 host in hosts_connection_nolog? no (option unset)
196 LOG: smtp_connection MAIN
197 SMTP connection from [V4NET.99.99.99]
198 host in host_lookup? no (option unset)
199 set_process_info: pppp handling incoming connection from [V4NET.99.99.99]
200 host in host_reject_connection? no (option unset)
201 host in sender_unqualified_hosts? no (option unset)
202 host in recipient_unqualified_hosts? no (option unset)
203 host in helo_verify_hosts? no (option unset)
204 host in helo_try_verify_hosts? no (option unset)
205 host in helo_accept_junk_hosts? no (option unset)
206 SMTP>> 220 the.local.host.name ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
207 smtp_setup_msg entered
208 SMTP<< ehlo exim.test.ex
209 exim.test.ex in helo_lookup_domains? no (end of list)
210 sender_fullhost = (exim.test.ex) [V4NET.99.99.99]
211 sender_rcvhost = [V4NET.99.99.99] (helo=exim.test.ex)
212 set_process_info: pppp handling incoming connection from (exim.test.ex) [V4NET.99.99.99]
213 host in dsn_advertise_hosts? no (option unset)
214 host in pipelining_advertise_hosts? yes (matched "*")
215 host in chunking_advertise_hosts? no (end of list)
216 SMTP>> 250-the.local.host.name Hello exim.test.ex [V4NET.99.99.99]
217 250-SIZE 52428800
218 250-8BITMIME
219 250-PIPELINING
220 250 HELP
221 SMTP<< mail from:postmaster@exim.test.ex
222 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
223 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
224 SMTP>> 250 OK
225 SMTP<< rcpt to:list@exim.test.ex
226 using ACL "check_recipient"
227 processing "accept" (TESTSUITE/test-config 19)
228 check hosts = :
229 host in ":"? no (end of list)
230 accept: condition test failed in ACL "check_recipient"
231 processing "accept" (TESTSUITE/test-config 20)
232 check recipients = postmaster@exim.test.ex
233 address match test: subject=list@exim.test.ex pattern=postmaster@exim.test.ex
234 list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
235 accept: condition test failed in ACL "check_recipient"
236 processing "accept" (TESTSUITE/test-config 21)
237 check senders = myfriend@*
238 address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
239 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
240 accept: condition test failed in ACL "check_recipient"
241 processing "deny" (TESTSUITE/test-config 22)
242 message: host is listed in $dnslist_domain
243 check dnslists = rbl.test.ex
244 dnslists check: rbl.test.ex
245 new DNS lookup for 99.99.99.V4NET.rbl.test.ex
246 DNS lookup of 99.99.99.V4NET.rbl.test.ex (A) using fakens
247 fresh-exec forking for fakens-search
248 postfork: fakens-search
249 fresh-exec forked for fakens-search: npppp
250 DNS lookup of 99.99.99.V4NET.rbl.test.ex (A) gave HOST_NOT_FOUND
251 returning DNS_NOMATCH
252 faking res_search(A) response length as 65535
253 writing neg-cache entry for 99.99.99.V4NET.rbl.test.ex-A-xxxx, ttl 3000
254 faking res_search(A) response length as 65535
255 dnslists: wrote cache entry, ttl=3000
256 DNS lookup for 99.99.99.V4NET.rbl.test.ex failed
257 => that means V4NET.99.99.99 is not listed at rbl.test.ex
258 deny: condition test failed in ACL "check_recipient"
259 processing "require" (TESTSUITE/test-config 24)
260 check verify = sender
261 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
262 Verifying postmaster@exim.test.ex
263 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
264 Considering postmaster@exim.test.ex
265 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
266 routing postmaster@exim.test.ex
267 --------> localuser router <--------
268 local_part=postmaster domain=exim.test.ex
269 checking local_parts
270 postmaster in "userx"? no (end of list)
271 localuser router skipped: local_parts mismatch
272 no more routers
273 ----------- end verify ------------
274 require: condition test failed in ACL "check_recipient"
275 end of ACL "check_recipient": not OK
276 LOG: MAIN REJECT
277 H=(exim.test.ex) [V4NET.99.99.99] sender verify fail for <postmaster@exim.test.ex>: Unrouteable address
278 SMTP>> 550-Verification failed for <postmaster@exim.test.ex>
279 SMTP>> 550-Unrouteable address
280 SMTP>> 550 Sender verify failed
281 LOG: MAIN REJECT
282 H=(exim.test.ex) [V4NET.99.99.99] F=<postmaster@exim.test.ex> rejected RCPT list@exim.test.ex: Sender verify failed
283 SMTP>> 421 the.local.host.name lost input connection
284 LOG: lost_incoming_connection MAIN
285 unexpected disconnection while reading SMTP command from (exim.test.ex) [V4NET.99.99.99] D=qqs
286 search_tidyup called
287 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>