Testsuite: munge for dns neg-cache
[exim.git] / test / stderr / 0432
1 >>> host in hosts_connection_nolog? no (option unset)
2 >>> host in host_lookup? no (option unset)
3 >>> host in host_reject_connection? no (option unset)
4 >>> host in sender_unqualified_hosts? no (option unset)
5 >>> host in recipient_unqualified_hosts? no (option unset)
6 >>> host in helo_verify_hosts? no (option unset)
7 >>> host in helo_try_verify_hosts? no (option unset)
8 >>> host in helo_accept_junk_hosts? no (option unset)
9 >>> test in helo_lookup_domains?
10 >>>  list element: @
11 >>>  list element: @[]
12 >>> test in helo_lookup_domains? no (end of list)
13 >>> using ACL "mail"
14 >>> processing "accept" (TESTSUITE/test-config 20)
15 >>> check verify = sender/callout=1s,maxwait=1s
16 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17 >>> routing x@y
18 >>> calling r1 router
19 >>> routed by r1 router
20 >>> local host found for non-MX address
21 MUNGED: ::1 will be omitted in what follows
22 >>> get[host|ipnode]byname[2] looked up these IP addresses:
23 >>>   name=127.0.0.1 address=127.0.0.1
24 >>> Attempting full verification using callout
25 >>> ... callout omitted by default when host testing
26 >>> (Use -bhc if you want the callouts to happen.)
27 >>> ----------- end verify ------------
28 >>> accept: condition test succeeded in ACL "mail"
29 >>> end of ACL "mail": ACCEPT
30 Exim version x.yz ....
31 changed uid/gid: forcing real = effective
32   uid=uuuu gid=CALLER_GID pid=p1234
33 configuration file is TESTSUITE/test-config
34 admin user
35 changed uid/gid: privilege not needed
36   uid=EXIM_UID gid=EXIM_GID pid=p1234
37 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
38 sender address = CALLER@myhost.test.ex
39 sender_fullhost = [1.2.3.4]
40 sender_rcvhost = [1.2.3.4]
41 host in hosts_connection_nolog? no (option unset)
42 LOG: smtp_connection MAIN
43   SMTP connection from [1.2.3.4]
44 host in host_lookup? no (option unset)
45 set_process_info: pppp handling incoming connection from [1.2.3.4]
46 host in host_reject_connection? no (option unset)
47 host in sender_unqualified_hosts? no (option unset)
48 host in recipient_unqualified_hosts? no (option unset)
49 host in helo_verify_hosts? no (option unset)
50 host in helo_try_verify_hosts? no (option unset)
51 host in helo_accept_junk_hosts? no (option unset)
52 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
53 smtp_setup_msg entered
54 SMTP<< helo test
55 test in helo_lookup_domains?
56  list element: @
57  list element: @[]
58 test in helo_lookup_domains? no (end of list)
59 sender_fullhost = (test) [1.2.3.4]
60 sender_rcvhost = [1.2.3.4] (helo=test)
61 set_process_info: pppp handling incoming connection from (test) [1.2.3.4]
62 SMTP>> 250 myhost.test.ex Hello test [1.2.3.4]
63 SMTP<< mail from:<x@y>
64 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
65 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
66 using ACL "mail"
67 processing "accept" (TESTSUITE/test-config 20)
68 check verify = sender/callout=1s,maxwait=1s
69 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
70 Verifying x@y
71 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
72 Considering x@y
73 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
74 routing x@y
75 --------> r1 router <--------
76 local_part=x domain=y
77 calling r1 router
78 r1 router called for x@y
79   domain = y
80 set transport t1
81 queued for t1 transport: local_part = x
82 domain = y
83   errors_to=NULL
84   domain_data=NULL local_part_data=NULL
85 routed by r1 router
86   envelope to: x@y
87   transport: t1
88 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
89 local host found for non-MX address
90 MUNGED: ::1 will be omitted in what follows
91 get[host|ipnode]byname[2] looked up these IP addresses:
92   name=127.0.0.1 address=127.0.0.1
93 Attempting full verification using callout
94  locking TESTSUITE/spool/db/callout.lockfile
95  locked  TESTSUITE/spool/db/callout.lockfile
96  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
97  returned from EXIM_DBOPEN: 0xAAAAAAAA
98  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
99  dbfn_read: key=y
100  callout cache: no domain record found for y
101  dbfn_read: key=x@y
102  callout cache: no address record found for x@y
103  EXIM_DBCLOSE(0xAAAAAAAA)
104  closed hints database and lockfile
105 interface=NULL port=PORT_S
106 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
107  127.0.0.1 in hosts_try_fastopen?
108   list element: 
109 connected
110   SMTP<< 220 server ready
111 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
112   SMTP>> EHLO myhost.test.ex
113 cmd buf flush ddd bytes
114   SMTP<< 250 OK
115 not using PIPELINING
116 not using DSN
117 127.0.0.1 in hosts_require_auth? no (option unset)
118   SMTP>> MAIL FROM:<>
119 cmd buf flush ddd bytes
120   SMTP<< 250 OK
121   SMTP>> RCPT TO:<x@y>
122 cmd buf flush ddd bytes
123 sync_responses expect rcpt for x@y
124   SMTP<< 250 OK
125   SMTP>> QUIT
126 cmd buf flush ddd bytes
127   SMTP<< 220 OK
128   SMTP(close)>>
129 cmdlog: '220:EHLO:250:MAIL:250:RCPT:250:QUIT:220'
130  locking TESTSUITE/spool/db/callout.lockfile
131  locked  TESTSUITE/spool/db/callout.lockfile
132  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR|O_CREAT
133  returned from EXIM_DBOPEN: 0xAAAAAAAA
134  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
135  dbfn_write: key=y
136  wrote callout cache domain record for y:
137    result=1 postmaster=0 random=0
138  dbfn_write: key=x@y
139  wrote positive callout cache address record for x@y
140  EXIM_DBCLOSE(0xAAAAAAAA)
141  closed hints database and lockfile
142 ----------- end verify ------------
143 sender x@y verified ok
144 accept: condition test succeeded in ACL "mail"
145 end of ACL "mail": ACCEPT
146 SMTP>> 250 OK
147 SMTP<< quit
148 SMTP>> 221 myhost.test.ex closing connection
149 LOG: smtp_connection MAIN
150   SMTP connection from (test) [1.2.3.4] D=qqs closed by QUIT
151 search_tidyup called
152 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
153 Exim version x.yz ....
154 changed uid/gid: forcing real = effective
155   uid=uuuu gid=CALLER_GID pid=p1235
156 configuration file is TESTSUITE/test-config
157 admin user
158 changed uid/gid: privilege not needed
159   uid=EXIM_UID gid=EXIM_GID pid=p1235
160 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
161 sender address = CALLER@myhost.test.ex
162 sender_fullhost = [1.2.3.4]
163 sender_rcvhost = [1.2.3.4]
164 host in hosts_connection_nolog? no (option unset)
165 LOG: smtp_connection MAIN
166   SMTP connection from [1.2.3.4]
167 host in host_lookup? no (option unset)
168 set_process_info: pppp handling incoming connection from [1.2.3.4]
169 host in host_reject_connection? no (option unset)
170 host in sender_unqualified_hosts? no (option unset)
171 host in recipient_unqualified_hosts? no (option unset)
172 host in helo_verify_hosts? no (option unset)
173 host in helo_try_verify_hosts? no (option unset)
174 host in helo_accept_junk_hosts? no (option unset)
175 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
176 smtp_setup_msg entered
177 SMTP<< helo test
178 test in helo_lookup_domains?
179  list element: @
180  list element: @[]
181 test in helo_lookup_domains? no (end of list)
182 sender_fullhost = (test) [1.2.3.4]
183 sender_rcvhost = [1.2.3.4] (helo=test)
184 set_process_info: pppp handling incoming connection from (test) [1.2.3.4]
185 SMTP>> 250 myhost.test.ex Hello test [1.2.3.4]
186 SMTP<< mail from:<x@y>
187 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
188 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
189 using ACL "mail"
190 processing "accept" (TESTSUITE/test-config 20)
191 check verify = sender/callout=1s,maxwait=1s
192 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
193 Verifying x@y
194 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
195 Considering x@y
196 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
197 routing x@y
198 --------> r1 router <--------
199 local_part=x domain=y
200 calling r1 router
201 r1 router called for x@y
202   domain = y
203 set transport t1
204 queued for t1 transport: local_part = x
205 domain = y
206   errors_to=NULL
207   domain_data=NULL local_part_data=NULL
208 routed by r1 router
209   envelope to: x@y
210   transport: t1
211 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
212 local host found for non-MX address
213 MUNGED: ::1 will be omitted in what follows
214 get[host|ipnode]byname[2] looked up these IP addresses:
215   name=127.0.0.1 address=127.0.0.1
216 Attempting full verification using callout
217  locking TESTSUITE/spool/db/callout.lockfile
218  locked  TESTSUITE/spool/db/callout.lockfile
219  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
220  returned from EXIM_DBOPEN: 0xAAAAAAAA
221  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
222  dbfn_read: key=y
223  callout cache: found domain record for y
224  dbfn_read: key=x@y
225  callout cache: found address record for x@y
226  callout cache: address record is positive
227  EXIM_DBCLOSE(0xAAAAAAAA)
228  closed hints database and lockfile
229 ----------- end verify ------------
230 sender x@y verified ok
231 accept: condition test succeeded in ACL "mail"
232 end of ACL "mail": ACCEPT
233 SMTP>> 250 OK
234 SMTP<< quit
235 SMTP>> 221 myhost.test.ex closing connection
236 LOG: smtp_connection MAIN
237   SMTP connection from (test) [1.2.3.4] D=qqs closed by QUIT
238 search_tidyup called
239 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
240 >>> host in hosts_connection_nolog? no (option unset)
241 >>> host in host_lookup? no (option unset)
242 >>> host in host_reject_connection? no (option unset)
243 >>> host in sender_unqualified_hosts? no (option unset)
244 >>> host in recipient_unqualified_hosts? no (option unset)
245 >>> host in helo_verify_hosts? no (option unset)
246 >>> host in helo_try_verify_hosts? no (option unset)
247 >>> host in helo_accept_junk_hosts? no (option unset)
248 >>> test in helo_lookup_domains?
249 >>>  list element: @
250 >>>  list element: @[]
251 >>> test in helo_lookup_domains? no (end of list)
252 >>> using ACL "mail"
253 >>> processing "accept" (TESTSUITE/test-config 20)
254 >>> check verify = sender/callout=1s,maxwait=1s
255 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
256 >>> routing x@y
257 >>> calling r1 router
258 >>> routed by r1 router
259 >>> local host found for non-MX address
260 MUNGED: ::1 will be omitted in what follows
261 >>> get[host|ipnode]byname[2] looked up these IP addresses:
262 >>>   name=127.0.0.1 address=127.0.0.1
263 >>> Attempting full verification using callout
264 >>> callout cache: found domain record for y
265 >>> callout cache: found address record for x@y
266 >>> callout cache: address record is positive
267 >>> ----------- end verify ------------
268 >>> accept: condition test succeeded in ACL "mail"
269 >>> end of ACL "mail": ACCEPT
270 >>> host in hosts_connection_nolog? no (option unset)
271 >>> host in host_lookup? no (option unset)
272 >>> host in host_reject_connection? no (option unset)
273 >>> host in sender_unqualified_hosts? no (option unset)
274 >>> host in recipient_unqualified_hosts? no (option unset)
275 >>> host in helo_verify_hosts? no (option unset)
276 >>> host in helo_try_verify_hosts? no (option unset)
277 >>> host in helo_accept_junk_hosts? no (option unset)
278 >>> test in helo_lookup_domains?
279 >>>  list element: @
280 >>>  list element: @[]
281 >>> test in helo_lookup_domains? no (end of list)
282 >>> using ACL "mail"
283 >>> processing "accept" (TESTSUITE/test-config 20)
284 >>> check verify = sender/callout=1s,maxwait=1s
285 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
286 >>> routing a@b
287 >>> calling r1 router
288 >>> routed by r1 router
289 >>> local host found for non-MX address
290 MUNGED: ::1 will be omitted in what follows
291 >>> get[host|ipnode]byname[2] looked up these IP addresses:
292 >>>   name=127.0.0.1 address=127.0.0.1
293 >>> Attempting full verification using callout
294 >>> callout cache: no domain record found for b
295 >>> callout cache: no address record found for a@b
296 >>> interface=NULL port=PORT_S
297 >>> Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
298 >>>  127.0.0.1 in hosts_try_fastopen?
299 >>>   list element: 
300 >>> connected
301 >>>   SMTP<< 220 server ready
302 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
303 >>>   SMTP>> EHLO myhost.test.ex
304 >>> cmd buf flush 21 bytes
305 >>>   SMTP<< 250 OK
306 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
307 >>>   SMTP>> MAIL FROM:<>
308 >>> cmd buf flush 14 bytes
309 >>>   SMTP<< 250 OK
310 >>>   SMTP>> RCPT TO:<a@b>
311 >>> cmd buf flush 15 bytes
312 >>>   SMTP<< 250 OK
313 >>>   SMTP>> QUIT
314 >>> cmd buf flush 6 bytes
315 >>>   SMTP<< 220 OK
316 >>>   SMTP(close)>>
317 >>> cmdlog: '220:EHLO:250:MAIL:250:RCPT:250:QUIT:220'
318 >>> wrote callout cache domain record for b:
319 >>>   result=1 postmaster=0 random=0
320 >>> wrote positive callout cache address record for a@b
321 >>> ----------- end verify ------------
322 >>> accept: condition test succeeded in ACL "mail"
323 >>> end of ACL "mail": ACCEPT
324 >>> host in hosts_connection_nolog? no (option unset)
325 >>> host in host_lookup? no (option unset)
326 >>> host in host_reject_connection? no (option unset)
327 >>> host in sender_unqualified_hosts? no (option unset)
328 >>> host in recipient_unqualified_hosts? no (option unset)
329 >>> host in helo_verify_hosts? no (option unset)
330 >>> host in helo_try_verify_hosts? no (option unset)
331 >>> host in helo_accept_junk_hosts? no (option unset)
332 >>> test in helo_lookup_domains?
333 >>>  list element: @
334 >>>  list element: @[]
335 >>> test in helo_lookup_domains? no (end of list)
336 >>> using ACL "mail"
337 >>> processing "accept" (TESTSUITE/test-config 20)
338 >>> check verify = sender/callout=1s,maxwait=1s
339 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
340 >>> routing p1@q
341 >>> calling r1 router
342 >>> routed by r1 router
343 >>> local host found for non-MX address
344 MUNGED: ::1 will be omitted in what follows
345 >>> get[host|ipnode]byname[2] looked up these IP addresses:
346 >>>   name=127.0.0.1 address=127.0.0.1
347 >>> Attempting full verification using callout
348 >>> callout cache: no domain record found for q
349 >>> callout cache: no address record found for p1@q
350 >>> interface=NULL port=PORT_S
351 >>> Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
352 >>>  127.0.0.1 in hosts_try_fastopen?
353 >>>   list element: 
354 >>> connected
355 >>>   SMTP<< 220 server ready
356 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
357 >>>   SMTP>> EHLO myhost.test.ex
358 >>> cmd buf flush 21 bytes
359 >>>   SMTP(close)>>
360 >>> cmdlog: '220:EHLO'
361 >>> SMTP timeout
362 >>> ----------- end verify ------------
363 >>> accept: condition test deferred in ACL "mail"
364 LOG: H=(test) [1.2.3.4] sender verify defer for <p1@q>: Could not complete sender verify callout: 127.0.0.1 [127.0.0.1] : SMTP timeout after EHLO myhost.test.ex
365 LOG: H=(test) [1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout