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