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