Merge branch 'callout_smtp_tpt_merge'
[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 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
53 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
54 using ACL "mail"
55 processing "accept"
56 check verify = sender/callout=1s,maxwait=1s
57 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
58 Verifying x@y
59 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
60 Considering x@y
61 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
62 routing x@y
63 --------> r1 router <--------
64 local_part=x domain=y
65 calling r1 router
66 r1 router called for x@y
67   domain = y
68 set transport t1
69 queued for t1 transport: local_part = x
70 domain = y
71   errors_to=NULL
72   domain_data=NULL localpart_data=NULL
73 routed by r1 router
74   envelope to: x@y
75   transport: t1
76 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
77 local host found for non-MX address
78 MUNGED: ::1 will be omitted in what follows
79 get[host|ipnode]byname[2] looked up these IP addresses:
80   name=127.0.0.1 address=127.0.0.1
81 Attempting full verification using callout
82 locking TESTSUITE/spool/db/callout.lockfile
83 locked  TESTSUITE/spool/db/callout.lockfile
84 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
85 returned from EXIM_DBOPEN
86 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
87 dbfn_read: key=y
88 callout cache: no domain record found for y
89 dbfn_read: key=x@y
90 callout cache: no address record found for x@y
91 closed hints database and lockfile
92 interface=NULL port=1224
93 Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
94   SMTP<< 220 server ready
95 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
96   SMTP>> EHLO myhost.test.ex
97 cmd buf flush ddd bytes
98   SMTP<< 250 OK
99 not using PIPELINING
100 not using DSN
101 127.0.0.1 in hosts_require_auth? no (option unset)
102   SMTP>> MAIL FROM:<>
103 cmd buf flush ddd bytes
104   SMTP<< 250 OK
105   SMTP>> RCPT TO:<x@y>
106 cmd buf flush ddd bytes
107   SMTP<< 250 OK
108   SMTP>> QUIT
109 cmd buf flush ddd bytes
110   SMTP<< 220 OK
111   SMTP(close)>>
112 locking TESTSUITE/spool/db/callout.lockfile
113 locked  TESTSUITE/spool/db/callout.lockfile
114 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
115 returned from EXIM_DBOPEN
116 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
117 dbfn_write: key=y
118 wrote callout cache domain record for y:
119   result=1 postmaster=0 random=0
120 dbfn_write: key=x@y
121 wrote positive callout cache address record for x@y
122 closed hints database and lockfile
123 ----------- end verify ------------
124 sender x@y verified ok
125 accept: condition test succeeded in ACL "mail"
126 end of ACL "mail": ACCEPT
127 SMTP>> 250 OK
128 SMTP<< quit
129 SMTP>> 221 myhost.test.ex closing connection
130 LOG: smtp_connection MAIN
131   SMTP connection from [1.2.3.4] closed by QUIT
132 search_tidyup called
133 >>>>>>>>>>>>>>>> Exim pid=pppp 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 DSN: r1 propagating DSN
142 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
143 sender address = CALLER@myhost.test.ex
144 sender_fullhost = [1.2.3.4]
145 sender_rcvhost = [1.2.3.4]
146 host in hosts_connection_nolog? no (option unset)
147 LOG: smtp_connection MAIN
148   SMTP connection from [1.2.3.4]
149 host in host_lookup? no (option unset)
150 set_process_info: pppp handling incoming connection from [1.2.3.4]
151 host in host_reject_connection? no (option unset)
152 host in sender_unqualified_hosts? no (option unset)
153 host in recipient_unqualified_hosts? no (option unset)
154 host in helo_verify_hosts? no (option unset)
155 host in helo_try_verify_hosts? no (option unset)
156 host in helo_accept_junk_hosts? no (option unset)
157 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
158 smtp_setup_msg entered
159 SMTP<< mail from:<x@y>
160 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
161 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
162 using ACL "mail"
163 processing "accept"
164 check verify = sender/callout=1s,maxwait=1s
165 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
166 Verifying x@y
167 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
168 Considering x@y
169 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
170 routing x@y
171 --------> r1 router <--------
172 local_part=x domain=y
173 calling r1 router
174 r1 router called for x@y
175   domain = y
176 set transport t1
177 queued for t1 transport: local_part = x
178 domain = y
179   errors_to=NULL
180   domain_data=NULL localpart_data=NULL
181 routed by r1 router
182   envelope to: x@y
183   transport: t1
184 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
185 local host found for non-MX address
186 MUNGED: ::1 will be omitted in what follows
187 get[host|ipnode]byname[2] looked up these IP addresses:
188   name=127.0.0.1 address=127.0.0.1
189 Attempting full verification using callout
190 locking TESTSUITE/spool/db/callout.lockfile
191 locked  TESTSUITE/spool/db/callout.lockfile
192 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
193 returned from EXIM_DBOPEN
194 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
195 dbfn_read: key=y
196 callout cache: found domain record for y
197 dbfn_read: key=x@y
198 callout cache: found address record for x@y
199 callout cache: address record is positive
200 closed hints database and lockfile
201 ----------- end verify ------------
202 sender x@y verified ok
203 accept: condition test succeeded in ACL "mail"
204 end of ACL "mail": ACCEPT
205 SMTP>> 250 OK
206 SMTP<< quit
207 SMTP>> 221 myhost.test.ex closing connection
208 LOG: smtp_connection MAIN
209   SMTP connection from [1.2.3.4] closed by QUIT
210 search_tidyup called
211 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
212 >>> host in hosts_connection_nolog? no (option unset)
213 >>> host in host_lookup? no (option unset)
214 >>> host in host_reject_connection? no (option unset)
215 >>> host in sender_unqualified_hosts? no (option unset)
216 >>> host in recipient_unqualified_hosts? no (option unset)
217 >>> host in helo_verify_hosts? no (option unset)
218 >>> host in helo_try_verify_hosts? no (option unset)
219 >>> host in helo_accept_junk_hosts? no (option unset)
220 >>> using ACL "mail"
221 >>> processing "accept"
222 >>> check verify = sender/callout=1s,maxwait=1s
223 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
224 >>> routing x@y
225 >>> calling r1 router
226 >>> routed by r1 router
227 >>> local host found for non-MX address
228 MUNGED: ::1 will be omitted in what follows
229 >>> get[host|ipnode]byname[2] looked up these IP addresses:
230 >>>   name=127.0.0.1 address=127.0.0.1
231 >>> Attempting full verification using callout
232 >>> callout cache: found domain record for y
233 >>> callout cache: found address record for x@y
234 >>> callout cache: address record is positive
235 >>> ----------- end verify ------------
236 >>> accept: condition test succeeded in ACL "mail"
237 >>> end of ACL "mail": ACCEPT
238 >>> host in hosts_connection_nolog? no (option unset)
239 >>> host in host_lookup? no (option unset)
240 >>> host in host_reject_connection? no (option unset)
241 >>> host in sender_unqualified_hosts? no (option unset)
242 >>> host in recipient_unqualified_hosts? no (option unset)
243 >>> host in helo_verify_hosts? no (option unset)
244 >>> host in helo_try_verify_hosts? no (option unset)
245 >>> host in helo_accept_junk_hosts? no (option unset)
246 >>> using ACL "mail"
247 >>> processing "accept"
248 >>> check verify = sender/callout=1s,maxwait=1s
249 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
250 >>> routing a@b
251 >>> calling r1 router
252 >>> routed by r1 router
253 >>> local host found for non-MX address
254 MUNGED: ::1 will be omitted in what follows
255 >>> get[host|ipnode]byname[2] looked up these IP addresses:
256 >>>   name=127.0.0.1 address=127.0.0.1
257 >>> Attempting full verification using callout
258 >>> callout cache: no domain record found for b
259 >>> callout cache: no address record found for a@b
260 >>> interface=NULL port=1224
261 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
262 >>>   SMTP<< 220 server ready
263 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
264 >>>   SMTP>> EHLO myhost.test.ex
265 >>> cmd buf flush 21 bytes
266 >>>   SMTP<< 250 OK
267 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
268 >>>   SMTP>> MAIL FROM:<>
269 >>> cmd buf flush 14 bytes
270 >>>   SMTP<< 250 OK
271 >>>   SMTP>> RCPT TO:<a@b>
272 >>> cmd buf flush 15 bytes
273 >>>   SMTP<< 250 OK
274 >>>   SMTP>> QUIT
275 >>> cmd buf flush 6 bytes
276 >>>   SMTP<< 220 OK
277 >>>   SMTP(close)>>
278 >>> wrote callout cache domain record for b:
279 >>>   result=1 postmaster=0 random=0
280 >>> wrote positive callout cache address record for a@b
281 >>> ----------- end verify ------------
282 >>> accept: condition test succeeded in ACL "mail"
283 >>> end of ACL "mail": ACCEPT
284 >>> host in hosts_connection_nolog? no (option unset)
285 >>> host in host_lookup? no (option unset)
286 >>> host in host_reject_connection? no (option unset)
287 >>> host in sender_unqualified_hosts? no (option unset)
288 >>> host in recipient_unqualified_hosts? no (option unset)
289 >>> host in helo_verify_hosts? no (option unset)
290 >>> host in helo_try_verify_hosts? no (option unset)
291 >>> host in helo_accept_junk_hosts? no (option unset)
292 >>> using ACL "mail"
293 >>> processing "accept"
294 >>> check verify = sender/callout=1s,maxwait=1s
295 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
296 >>> routing p1@q
297 >>> calling r1 router
298 >>> routed by r1 router
299 >>> local host found for non-MX address
300 MUNGED: ::1 will be omitted in what follows
301 >>> get[host|ipnode]byname[2] looked up these IP addresses:
302 >>>   name=127.0.0.1 address=127.0.0.1
303 >>> Attempting full verification using callout
304 >>> callout cache: no domain record found for q
305 >>> callout cache: no address record found for p1@q
306 >>> interface=NULL port=1224
307 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
308 >>>   SMTP<< 220 server ready
309 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
310 >>>   SMTP>> EHLO myhost.test.ex
311 >>> cmd buf flush 21 bytes
312 >>>   SMTP(close)>>
313 >>> SMTP timeout
314 >>> ----------- end verify ------------
315 >>> accept: condition test deferred in ACL "mail"
316 LOG: H=[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
317 LOG: H=[1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout