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