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