Flush SMTP output buffer before "delay" in an ACL; add control =
[exim.git] / test / stderr / 0022
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@test.ex
12 sender_fullhost = [V4NET.9.8.7]
13 sender_rcvhost = [V4NET.9.8.7]
14 host in hosts_connection_nolog? no (end of list)
15 LOG: smtp_connection MAIN
16   SMTP connection from [V4NET.9.8.7]
17 host in host_lookup? no (option unset)
18 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
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 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
26 smtp_setup_msg entered
27 SMTP<< mail from:<x@y>
28 SMTP>> 250 OK
29 SMTP<< rcpt to:<warn_empty@test.ex>
30 using ACL "warn_empty"
31 processing "warn"
32 warn: condition test succeeded
33 processing "accept"
34 accept: condition test succeeded
35 SMTP>> 250 Accepted
36 SMTP<< data
37 SMTP>> 354 Enter message, ending with "." on a line by itself
38 search_tidyup called
39 host in ignore_fromline_hosts? no (option unset)
40 >>Headers received:
41
42 search_tidyup called
43 >>Headers after rewriting and local additions:
44
45 Data file written for message 10HmbF-0005vi-00
46 >>Generated Received: header line
47 P Received: from [V4NET.9.8.7]
48         by myhost.test.ex with smtp (Exim x.yz)
49         (envelope-from <x@y>)
50         id 10HmbF-0005vi-00
51         for warn_empty@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
52 calling local_scan(); timeout=300
53 local_scan() returned 0 NULL
54 LOG: MAIN
55   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
56 SMTP>> 250 OK id=10HmbF-0005vi-00
57 smtp_setup_msg entered
58 SMTP<< quit
59 SMTP>> 221 myhost.test.ex closing connection
60 LOG: smtp_connection MAIN
61   SMTP connection from [V4NET.9.8.7] closed by QUIT
62 search_tidyup called
63 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
64 Exim version x.yz ....
65 changed uid/gid: forcing real = effective
66   uid=uuuu gid=CALLER_GID pid=pppp
67 configuration file is TESTSUITE/test-config
68 admin user
69 changed uid/gid: privilege not needed
70   uid=EXIM_UID gid=EXIM_GID pid=pppp
71 seeking password data for user "CALLER": cache not available
72 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
73 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
74 sender address = CALLER@test.ex
75 sender_fullhost = [V4NET.9.8.7]
76 sender_rcvhost = [V4NET.9.8.7]
77 host in hosts_connection_nolog? no (end of list)
78 LOG: smtp_connection MAIN
79   SMTP connection from [V4NET.9.8.7]
80 host in host_lookup? no (option unset)
81 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
82 host in host_reject_connection? no (option unset)
83 host in sender_unqualified_hosts? no (option unset)
84 host in recipient_unqualified_hosts? no (option unset)
85 host in helo_verify_hosts? no (option unset)
86 host in helo_try_verify_hosts? no (option unset)
87 host in helo_accept_junk_hosts? no (option unset)
88 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
89 smtp_setup_msg entered
90 SMTP<< mail from:<x@y>
91 SMTP>> 250 OK
92 SMTP<< rcpt to:<warn_log@test.ex>
93 using ACL "warn_log"
94 processing "warn"
95 warn: condition test succeeded
96 LOG: MAIN
97   H=[V4NET.9.8.7] Warning: warn log message
98 processing "accept"
99 accept: condition test succeeded
100 SMTP>> 250 Accepted
101 SMTP<< data
102 SMTP>> 354 Enter message, ending with "." on a line by itself
103 search_tidyup called
104 host in ignore_fromline_hosts? no (option unset)
105 >>Headers received:
106
107 search_tidyup called
108 >>Headers after rewriting and local additions:
109
110 Data file written for message 10HmbG-0005vi-00
111 >>Generated Received: header line
112 P Received: from [V4NET.9.8.7]
113         by myhost.test.ex with smtp (Exim x.yz)
114         (envelope-from <x@y>)
115         id 10HmbG-0005vi-00
116         for warn_log@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
117 calling local_scan(); timeout=300
118 local_scan() returned 0 NULL
119 LOG: MAIN
120   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
121 SMTP>> 250 OK id=10HmbG-0005vi-00
122 smtp_setup_msg entered
123 SMTP<< quit
124 SMTP>> 221 myhost.test.ex closing connection
125 LOG: smtp_connection MAIN
126   SMTP connection from [V4NET.9.8.7] closed by QUIT
127 search_tidyup called
128 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
129 Exim version x.yz ....
130 changed uid/gid: forcing real = effective
131   uid=uuuu gid=CALLER_GID pid=pppp
132 configuration file is TESTSUITE/test-config
133 admin user
134 changed uid/gid: privilege not needed
135   uid=EXIM_UID gid=EXIM_GID pid=pppp
136 seeking password data for user "CALLER": cache not available
137 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
138 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
139 sender address = CALLER@test.ex
140 sender_fullhost = [V4NET.9.8.7]
141 sender_rcvhost = [V4NET.9.8.7]
142 host in hosts_connection_nolog? no (end of list)
143 LOG: smtp_connection MAIN
144   SMTP connection from [V4NET.9.8.7]
145 host in host_lookup? no (option unset)
146 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
147 host in host_reject_connection? no (option unset)
148 host in sender_unqualified_hosts? no (option unset)
149 host in recipient_unqualified_hosts? no (option unset)
150 host in helo_verify_hosts? no (option unset)
151 host in helo_try_verify_hosts? no (option unset)
152 host in helo_accept_junk_hosts? no (option unset)
153 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
154 smtp_setup_msg entered
155 SMTP<< mail from:<x@y>
156 SMTP>> 250 OK
157 SMTP<< rcpt to:<warn_user@test.ex>
158 using ACL "warn_user"
159 processing "warn"
160 warn: condition test succeeded
161 processing "accept"
162 accept: condition test succeeded
163 SMTP>> 250 Accepted
164 SMTP<< data
165 SMTP>> 354 Enter message, ending with "." on a line by itself
166 search_tidyup called
167 host in ignore_fromline_hosts? no (option unset)
168 >>Headers received:
169
170 search_tidyup called
171 >>Headers after rewriting and local additions:
172
173 Data file written for message 10HmbH-0005vi-00
174 >>Generated Received: header line
175 P Received: from [V4NET.9.8.7]
176         by myhost.test.ex with smtp (Exim x.yz)
177         (envelope-from <x@y>)
178         id 10HmbH-0005vi-00
179         for warn_user@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
180 >>Headers added by MAIL or RCPT ACL:
181   X-ACL-Warn: warn user message
182 >>
183 calling local_scan(); timeout=300
184 local_scan() returned 0 NULL
185 LOG: MAIN
186   <= x@y H=[V4NET.9.8.7] P=smtp S=sss
187 SMTP>> 250 OK id=10HmbH-0005vi-00
188 smtp_setup_msg entered
189 SMTP<< quit
190 SMTP>> 221 myhost.test.ex closing connection
191 LOG: smtp_connection MAIN
192   SMTP connection from [V4NET.9.8.7] closed by QUIT
193 search_tidyup called
194 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
195 >>> host in hosts_connection_nolog? no (end of list)
196 LOG: SMTP connection from [V4NET.9.8.7]
197 >>> host in host_lookup? no (option unset)
198 >>> host in host_reject_connection? no (option unset)
199 >>> host in sender_unqualified_hosts? no (option unset)
200 >>> host in recipient_unqualified_hosts? no (option unset)
201 >>> host in helo_verify_hosts? no (option unset)
202 >>> host in helo_try_verify_hosts? no (option unset)
203 >>> host in helo_accept_junk_hosts? no (option unset)
204 >>> using ACL "defer"
205 >>> processing "defer"
206 >>> defer: condition test succeeded
207 LOG: H=[V4NET.9.8.7] F=<x@y> temporarily rejected RCPT <defer@y>: forcibly deferred
208 >>> using ACL "accept"
209 >>> processing "accept"
210 >>> accept: condition test succeeded
211 >>> using ACL "drop"
212 >>> processing "drop"
213 >>> drop: condition test succeeded
214 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <drop@y>: forcibly dropped
215 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
216 >>> host in hosts_connection_nolog? no (end of list)
217 LOG: SMTP connection from [V4NET.9.8.7]
218 >>> host in host_lookup? no (option unset)
219 >>> host in host_reject_connection? no (option unset)
220 >>> host in sender_unqualified_hosts? no (option unset)
221 >>> host in recipient_unqualified_hosts? no (option unset)
222 >>> host in helo_verify_hosts? no (option unset)
223 >>> host in helo_try_verify_hosts? no (option unset)
224 >>> host in helo_accept_junk_hosts? no (option unset)
225 >>> using ACL "defer_senders"
226 >>> processing "defer"
227 >>> check senders = :
228 >>>  in ":"? yes (matched "")
229 >>> defer: condition test succeeded
230 LOG: H=[V4NET.9.8.7] F=<> temporarily rejected RCPT <defer_senders@y>
231 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
232 >>> host in hosts_connection_nolog? no (end of list)
233 LOG: SMTP connection from [V4NET.9.8.7]
234 >>> host in host_lookup? no (option unset)
235 >>> host in host_reject_connection? no (option unset)
236 >>> host in sender_unqualified_hosts? no (option unset)
237 >>> host in recipient_unqualified_hosts? no (option unset)
238 >>> host in helo_verify_hosts? no (option unset)
239 >>> host in helo_try_verify_hosts? no (option unset)
240 >>> host in helo_accept_junk_hosts? no (option unset)
241 >>> using ACL "delay_accept"
242 >>> processing "accept"
243 >>> check delay = 1s
244 >>> delay modifier requests 1-second delay
245 >>> delay skipped in -bh checking mode
246 >>> accept: condition test succeeded
247 >>> using ACL "delay_warn"
248 >>> processing "warn"
249 >>> check delay = 1s
250 >>> delay modifier requests 1-second delay
251 >>> delay skipped in -bh checking mode
252 >>> warn: condition test succeeded
253 >>> processing "accept"
254 >>> accept: condition test succeeded
255 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
256 >>> host in hosts_connection_nolog? no (end of list)
257 LOG: SMTP connection from [V4NET.9.8.7]
258 >>> host in host_lookup? no (option unset)
259 >>> host in host_reject_connection? no (option unset)
260 >>> host in sender_unqualified_hosts? no (option unset)
261 >>> host in recipient_unqualified_hosts? no (option unset)
262 >>> host in helo_verify_hosts? no (option unset)
263 >>> host in helo_try_verify_hosts? no (option unset)
264 >>> host in helo_accept_junk_hosts? no (option unset)
265 >>> using ACL "host_check"
266 >>> processing "deny"
267 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
268 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
269 >>> deny: condition test succeeded
270 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
271 >>> using ACL "host_check"
272 >>> processing "deny"
273 >>> check hosts = net-lsearch;TESTSUITE/aux-var/0022.hosts
274 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
275 >>> deny: condition test succeeded
276 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check@y>: host data >A host-specific message<
277 >>> using ACL "host_check2"
278 >>> processing "deny"
279 >>> check hosts = +some_hosts
280 >>> host in "net-lsearch;TESTSUITE/aux-var/0022.hosts"? yes (matched "net-lsearch;TESTSUITE/aux-var/0022.hosts")
281 >>> host in "+some_hosts"? yes (matched "+some_hosts")
282 >>> deny: condition test succeeded
283 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
284 >>> using ACL "host_check2"
285 >>> processing "deny"
286 >>> check hosts = +some_hosts
287 >>> host in "+some_hosts"? yes (matched "+some_hosts" - cached)
288 >>> deny: condition test succeeded
289 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <host_check2@y>: host data >A host-specific message<
290 LOG: SMTP connection from [V4NET.9.8.7] closed by QUIT
291 LOG: smtp_connection MAIN
292   SMTP connection from CALLER
293 LOG: MAIN
294   Connections=0
295 LOG: MAIN
296   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
297 LOG: MAIN
298   frozen by ACL
299 LOG: MAIN
300   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
301 delivering 10HmaY-0005vi-00
302 LOG: MAIN
303   *> accept <accept@y> R=accept T=appendfile
304 LOG: MAIN
305   Completed
306 LOG: MAIN
307   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
308 LOG: delay_delivery MAIN
309   no immediate delivery: queued by ACL
310 LOG: MAIN
311   <= CALLER@test.ex U=CALLER P=local-smtp S=sss
312 delivering 10HmbA-0005vi-00
313 LOG: MAIN
314   *> accept <accept@y> R=accept T=appendfile
315 LOG: MAIN
316   Completed
317 LOG: smtp_connection MAIN
318   SMTP connection from CALLER closed by QUIT
319 >>> host in hosts_connection_nolog? no (end of list)
320 LOG: SMTP connection from [V4NET.9.8.7]
321 >>> host in host_lookup? no (option unset)
322 >>> host in host_reject_connection? no (option unset)
323 >>> host in sender_unqualified_hosts? no (option unset)
324 >>> host in recipient_unqualified_hosts? no (option unset)
325 >>> host in helo_verify_hosts? no (option unset)
326 >>> host in helo_try_verify_hosts? no (option unset)
327 >>> host in helo_accept_junk_hosts? no (option unset)
328 >>> using ACL "nested_drop"
329 >>> processing "accept"
330 >>> check acl = drop
331 >>> using ACL "drop"
332 >>> processing "drop"
333 >>> drop: condition test succeeded
334 >>> accept: condition test yielded "drop"
335 >>> accept: endpass encountered - denying access
336 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop@y>: forcibly dropped
337 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
338 >>> host in hosts_connection_nolog? no (end of list)
339 LOG: SMTP connection from [V4NET.9.8.7]
340 >>> host in host_lookup? no (option unset)
341 >>> host in host_reject_connection? no (option unset)
342 >>> host in sender_unqualified_hosts? no (option unset)
343 >>> host in recipient_unqualified_hosts? no (option unset)
344 >>> host in helo_verify_hosts? no (option unset)
345 >>> host in helo_try_verify_hosts? no (option unset)
346 >>> host in helo_accept_junk_hosts? no (option unset)
347 >>> using ACL "nested_drop_require"
348 >>> processing "require"
349 >>> check acl = drop
350 >>> using ACL "drop"
351 >>> processing "drop"
352 >>> drop: condition test succeeded
353 >>> require: condition test yielded "drop"
354 LOG: H=[V4NET.9.8.7] F=<x@y> rejected RCPT <nested_drop_require@y>: forcibly dropped
355 LOG: SMTP connection from [V4NET.9.8.7] closed by DROP in ACL
356
357 ******** SERVER ********