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