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