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