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