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