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