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