Cutthrough: avoid use of callout result 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 >>> using ACL "mail"
10 >>> processing "accept"
11 >>> check verify = sender/callout=1s,maxwait=1s
12 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
13 >>> routing x@y
14 >>> calling r1 router
15 >>> routed by r1 router
16 >>> local host found for non-MX address
17 MUNGED: ::1 will be omitted in what follows
18 >>> get[host|ipnode]byname[2] looked up these IP addresses:
19 >>>   name=127.0.0.1 address=127.0.0.1
20 >>> Attempting full verification using callout
21 >>> ... callout omitted by default when host testing
22 >>> (Use -bhc if you want the callouts to happen.)
23 >>> ----------- end verify ------------
24 >>> accept: condition test succeeded in ACL "mail"
25 >>> end of ACL "mail": ACCEPT
26 Exim version x.yz ....
27 changed uid/gid: forcing real = effective
28   uid=uuuu gid=CALLER_GID pid=pppp
29 configuration file is TESTSUITE/test-config
30 admin user
31 changed uid/gid: privilege not needed
32   uid=EXIM_UID gid=EXIM_GID pid=pppp
33 DSN: r1 propagating DSN
34 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
35 sender address = CALLER@myhost.test.ex
36 sender_fullhost = [1.2.3.4]
37 sender_rcvhost = [1.2.3.4]
38 host in hosts_connection_nolog? no (option unset)
39 LOG: smtp_connection MAIN
40   SMTP connection from [1.2.3.4]
41 host in host_lookup? no (option unset)
42 set_process_info: pppp handling incoming connection from [1.2.3.4]
43 host in host_reject_connection? no (option unset)
44 host in sender_unqualified_hosts? no (option unset)
45 host in recipient_unqualified_hosts? no (option unset)
46 host in helo_verify_hosts? no (option unset)
47 host in helo_try_verify_hosts? no (option unset)
48 host in helo_accept_junk_hosts? no (option unset)
49 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
50 smtp_setup_msg entered
51 SMTP<< mail from:<x@y>
52 using ACL "mail"
53 processing "accept"
54 check verify = sender/callout=1s,maxwait=1s
55 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
56 Verifying x@y
57 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
58 Considering x@y
59 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
60 routing x@y
61 --------> r1 router <--------
62 local_part=x domain=y
63 calling r1 router
64 r1 router called for x@y
65   domain = y
66 set transport t1
67 queued for t1 transport: local_part = x
68 domain = y
69   errors_to=NULL
70   domain_data=NULL localpart_data=NULL
71 routed by r1 router
72   envelope to: x@y
73   transport: t1
74 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
75 local host found for non-MX address
76 MUNGED: ::1 will be omitted in what follows
77 get[host|ipnode]byname[2] looked up these IP addresses:
78   name=127.0.0.1 address=127.0.0.1
79 Attempting full verification using callout
80 locking TESTSUITE/spool/db/callout.lockfile
81 locked TESTSUITE/spool/db/callout.lockfile
82 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
83 returned from EXIM_DBOPEN
84 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
85 dbfn_read: key=y
86 callout cache: no domain record found for y
87 dbfn_read: key=x@y
88 callout cache: no address record found for x@y
89 interface=NULL port=1224
90 Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
91   SMTP<< 220 server ready
92 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
93   SMTP>> EHLO myhost.test.ex
94   SMTP<< 250 OK
95 127.0.0.1 in hosts_require_auth? no (option unset)
96   SMTP>> MAIL FROM:<>
97   SMTP<< 250 OK
98   SMTP>> RCPT TO:<x@y>
99   SMTP<< 250 OK
100   SMTP>> QUIT
101 locking TESTSUITE/spool/db/callout.lockfile
102 locked TESTSUITE/spool/db/callout.lockfile
103 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
104 returned from EXIM_DBOPEN
105 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
106 dbfn_write: key=y
107 wrote callout cache domain record for y:
108   result=1 postmaster=0 random=0
109 dbfn_write: key=x@y
110 wrote positive callout cache address record for x@y
111 ----------- end verify ------------
112 sender x@y verified ok
113 accept: condition test succeeded in ACL "mail"
114 end of ACL "mail": ACCEPT
115 SMTP>> 250 OK
116 SMTP<< quit
117 SMTP>> 221 myhost.test.ex closing connection
118 LOG: smtp_connection MAIN
119   SMTP connection from [1.2.3.4] closed by QUIT
120 search_tidyup called
121 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
122 Exim version x.yz ....
123 changed uid/gid: forcing real = effective
124   uid=uuuu gid=CALLER_GID pid=pppp
125 configuration file is TESTSUITE/test-config
126 admin user
127 changed uid/gid: privilege not needed
128   uid=EXIM_UID gid=EXIM_GID pid=pppp
129 DSN: r1 propagating DSN
130 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
131 sender address = CALLER@myhost.test.ex
132 sender_fullhost = [1.2.3.4]
133 sender_rcvhost = [1.2.3.4]
134 host in hosts_connection_nolog? no (option unset)
135 LOG: smtp_connection MAIN
136   SMTP connection from [1.2.3.4]
137 host in host_lookup? no (option unset)
138 set_process_info: pppp handling incoming connection from [1.2.3.4]
139 host in host_reject_connection? no (option unset)
140 host in sender_unqualified_hosts? no (option unset)
141 host in recipient_unqualified_hosts? no (option unset)
142 host in helo_verify_hosts? no (option unset)
143 host in helo_try_verify_hosts? no (option unset)
144 host in helo_accept_junk_hosts? no (option unset)
145 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
146 smtp_setup_msg entered
147 SMTP<< mail from:<x@y>
148 using ACL "mail"
149 processing "accept"
150 check verify = sender/callout=1s,maxwait=1s
151 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
152 Verifying x@y
153 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
154 Considering x@y
155 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
156 routing x@y
157 --------> r1 router <--------
158 local_part=x domain=y
159 calling r1 router
160 r1 router called for x@y
161   domain = y
162 set transport t1
163 queued for t1 transport: local_part = x
164 domain = y
165   errors_to=NULL
166   domain_data=NULL localpart_data=NULL
167 routed by r1 router
168   envelope to: x@y
169   transport: t1
170 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
171 local host found for non-MX address
172 MUNGED: ::1 will be omitted in what follows
173 get[host|ipnode]byname[2] looked up these IP addresses:
174   name=127.0.0.1 address=127.0.0.1
175 Attempting full verification using callout
176 locking TESTSUITE/spool/db/callout.lockfile
177 locked TESTSUITE/spool/db/callout.lockfile
178 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
179 returned from EXIM_DBOPEN
180 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
181 dbfn_read: key=y
182 callout cache: found domain record for y
183 dbfn_read: key=x@y
184 callout cache: found address record for x@y
185 callout cache: address record is positive
186 ----------- end verify ------------
187 sender x@y verified ok
188 accept: condition test succeeded in ACL "mail"
189 end of ACL "mail": ACCEPT
190 SMTP>> 250 OK
191 SMTP<< quit
192 SMTP>> 221 myhost.test.ex closing connection
193 LOG: smtp_connection MAIN
194   SMTP connection from [1.2.3.4] closed by QUIT
195 search_tidyup called
196 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
197 >>> host in hosts_connection_nolog? no (option unset)
198 >>> host in host_lookup? no (option unset)
199 >>> host in host_reject_connection? no (option unset)
200 >>> host in sender_unqualified_hosts? no (option unset)
201 >>> host in recipient_unqualified_hosts? no (option unset)
202 >>> host in helo_verify_hosts? no (option unset)
203 >>> host in helo_try_verify_hosts? no (option unset)
204 >>> host in helo_accept_junk_hosts? no (option unset)
205 >>> using ACL "mail"
206 >>> processing "accept"
207 >>> check verify = sender/callout=1s,maxwait=1s
208 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
209 >>> routing x@y
210 >>> calling r1 router
211 >>> routed by r1 router
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 >>> callout cache: found domain record for y
218 >>> callout cache: found address record for x@y
219 >>> callout cache: address record is positive
220 >>> ----------- end verify ------------
221 >>> accept: condition test succeeded in ACL "mail"
222 >>> end of ACL "mail": ACCEPT
223 >>> host in hosts_connection_nolog? no (option unset)
224 >>> host in host_lookup? no (option unset)
225 >>> host in host_reject_connection? no (option unset)
226 >>> host in sender_unqualified_hosts? no (option unset)
227 >>> host in recipient_unqualified_hosts? no (option unset)
228 >>> host in helo_verify_hosts? no (option unset)
229 >>> host in helo_try_verify_hosts? no (option unset)
230 >>> host in helo_accept_junk_hosts? no (option unset)
231 >>> using ACL "mail"
232 >>> processing "accept"
233 >>> check verify = sender/callout=1s,maxwait=1s
234 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
235 >>> routing a@b
236 >>> calling r1 router
237 >>> routed by r1 router
238 >>> local host found for non-MX address
239 MUNGED: ::1 will be omitted in what follows
240 >>> get[host|ipnode]byname[2] looked up these IP addresses:
241 >>>   name=127.0.0.1 address=127.0.0.1
242 >>> Attempting full verification using callout
243 >>> callout cache: no domain record found for b
244 >>> callout cache: no address record found for a@b
245 >>> interface=NULL port=1224
246 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
247 >>>   SMTP<< 220 server ready
248 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
249 >>>   SMTP>> EHLO myhost.test.ex
250 >>>   SMTP<< 250 OK
251 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
252 >>>   SMTP>> MAIL FROM:<>
253 >>>   SMTP<< 250 OK
254 >>>   SMTP>> RCPT TO:<a@b>
255 >>>   SMTP<< 250 OK
256 >>>   SMTP>> QUIT
257 >>> wrote callout cache domain record for b:
258 >>>   result=1 postmaster=0 random=0
259 >>> wrote positive callout cache address record for a@b
260 >>> ----------- end verify ------------
261 >>> accept: condition test succeeded in ACL "mail"
262 >>> end of ACL "mail": ACCEPT
263 >>> host in hosts_connection_nolog? no (option unset)
264 >>> host in host_lookup? no (option unset)
265 >>> host in host_reject_connection? no (option unset)
266 >>> host in sender_unqualified_hosts? no (option unset)
267 >>> host in recipient_unqualified_hosts? no (option unset)
268 >>> host in helo_verify_hosts? no (option unset)
269 >>> host in helo_try_verify_hosts? no (option unset)
270 >>> host in helo_accept_junk_hosts? no (option unset)
271 >>> using ACL "mail"
272 >>> processing "accept"
273 >>> check verify = sender/callout=1s,maxwait=1s
274 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
275 >>> routing p1@q
276 >>> calling r1 router
277 >>> routed by r1 router
278 >>> local host found for non-MX address
279 MUNGED: ::1 will be omitted in what follows
280 >>> get[host|ipnode]byname[2] looked up these IP addresses:
281 >>>   name=127.0.0.1 address=127.0.0.1
282 >>> Attempting full verification using callout
283 >>> callout cache: no domain record found for q
284 >>> callout cache: no address record found for p1@q
285 >>> interface=NULL port=1224
286 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
287 >>>   SMTP<< 220 server ready
288 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
289 >>>   SMTP>> EHLO myhost.test.ex
290 >>> SMTP timeout
291 >>> ----------- end verify ------------
292 >>> accept: condition test deferred in ACL "mail"
293 LOG: H=[1.2.3.4] sender verify defer for <p1@q>: Could not complete sender verify callout
294 LOG: H=[1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout