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