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