unbreak test: s/log_write/logwrite/
[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 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 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
52 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
53 using ACL "mail"
54 processing "accept"
55 check verify = sender/callout=1s,maxwait=1s
56 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
57 Verifying x@y
58 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
59 Considering x@y
60 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
61 routing x@y
62 --------> r1 router <--------
63 local_part=x domain=y
64 calling r1 router
65 r1 router called for x@y
66   domain = y
67 set transport t1
68 queued for t1 transport: local_part = x
69 domain = y
70   errors_to=NULL
71   domain_data=NULL localpart_data=NULL
72 routed by r1 router
73   envelope to: x@y
74   transport: t1
75 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
76 local host found for non-MX address
77 MUNGED: ::1 will be omitted in what follows
78 get[host|ipnode]byname[2] looked up these IP addresses:
79   name=127.0.0.1 address=127.0.0.1
80 Attempting full verification using callout
81 locking TESTSUITE/spool/db/callout.lockfile
82 locked  TESTSUITE/spool/db/callout.lockfile
83 EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
84 returned from EXIM_DBOPEN: 0xAAAAAAAA
85 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
86 dbfn_read: key=y
87 callout cache: no domain record found for y
88 dbfn_read: key=x@y
89 callout cache: no address record found for x@y
90 EXIM_DBCLOSE(0xAAAAAAAA)
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: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR|O_CREAT
115 returned from EXIM_DBOPEN: 0xAAAAAAAA
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 EXIM_DBCLOSE(0xAAAAAAAA)
123 closed hints database and lockfile
124 ----------- end verify ------------
125 sender x@y verified ok
126 accept: condition test succeeded in ACL "mail"
127 end of ACL "mail": ACCEPT
128 SMTP>> 250 OK
129 SMTP<< quit
130 SMTP>> 221 myhost.test.ex closing connection
131 LOG: smtp_connection MAIN
132   SMTP connection from [1.2.3.4] closed by QUIT
133 search_tidyup called
134 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
135 Exim version x.yz ....
136 changed uid/gid: forcing real = effective
137   uid=uuuu gid=CALLER_GID pid=pppp
138 configuration file is TESTSUITE/test-config
139 admin user
140 changed uid/gid: privilege not needed
141   uid=EXIM_UID gid=EXIM_GID pid=pppp
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: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
193 returned from EXIM_DBOPEN: 0xAAAAAAAA
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 EXIM_DBCLOSE(0xAAAAAAAA)
201 closed hints database and lockfile
202 ----------- end verify ------------
203 sender x@y verified ok
204 accept: condition test succeeded in ACL "mail"
205 end of ACL "mail": ACCEPT
206 SMTP>> 250 OK
207 SMTP<< quit
208 SMTP>> 221 myhost.test.ex closing connection
209 LOG: smtp_connection MAIN
210   SMTP connection from [1.2.3.4] closed by QUIT
211 search_tidyup called
212 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
213 >>> host in hosts_connection_nolog? no (option unset)
214 >>> host in host_lookup? no (option unset)
215 >>> host in host_reject_connection? no (option unset)
216 >>> host in sender_unqualified_hosts? no (option unset)
217 >>> host in recipient_unqualified_hosts? no (option unset)
218 >>> host in helo_verify_hosts? no (option unset)
219 >>> host in helo_try_verify_hosts? no (option unset)
220 >>> host in helo_accept_junk_hosts? no (option unset)
221 >>> using ACL "mail"
222 >>> processing "accept"
223 >>> check verify = sender/callout=1s,maxwait=1s
224 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
225 >>> routing x@y
226 >>> calling r1 router
227 >>> routed by r1 router
228 >>> local host found for non-MX address
229 MUNGED: ::1 will be omitted in what follows
230 >>> get[host|ipnode]byname[2] looked up these IP addresses:
231 >>>   name=127.0.0.1 address=127.0.0.1
232 >>> Attempting full verification using callout
233 >>> callout cache: found domain record for y
234 >>> callout cache: found address record for x@y
235 >>> callout cache: address record is positive
236 >>> ----------- end verify ------------
237 >>> accept: condition test succeeded in ACL "mail"
238 >>> end of ACL "mail": ACCEPT
239 >>> host in hosts_connection_nolog? no (option unset)
240 >>> host in host_lookup? no (option unset)
241 >>> host in host_reject_connection? no (option unset)
242 >>> host in sender_unqualified_hosts? no (option unset)
243 >>> host in recipient_unqualified_hosts? no (option unset)
244 >>> host in helo_verify_hosts? no (option unset)
245 >>> host in helo_try_verify_hosts? no (option unset)
246 >>> host in helo_accept_junk_hosts? no (option unset)
247 >>> using ACL "mail"
248 >>> processing "accept"
249 >>> check verify = sender/callout=1s,maxwait=1s
250 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
251 >>> routing a@b
252 >>> calling r1 router
253 >>> routed by r1 router
254 >>> local host found for non-MX address
255 MUNGED: ::1 will be omitted in what follows
256 >>> get[host|ipnode]byname[2] looked up these IP addresses:
257 >>>   name=127.0.0.1 address=127.0.0.1
258 >>> Attempting full verification using callout
259 >>> callout cache: no domain record found for b
260 >>> callout cache: no address record found for a@b
261 >>> interface=NULL port=1224
262 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
263 >>>   SMTP<< 220 server ready
264 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
265 >>>   SMTP>> EHLO myhost.test.ex
266 >>> cmd buf flush 21 bytes
267 >>>   SMTP<< 250 OK
268 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
269 >>>   SMTP>> MAIL FROM:<>
270 >>> cmd buf flush 14 bytes
271 >>>   SMTP<< 250 OK
272 >>>   SMTP>> RCPT TO:<a@b>
273 >>> cmd buf flush 15 bytes
274 >>>   SMTP<< 250 OK
275 >>>   SMTP>> QUIT
276 >>> cmd buf flush 6 bytes
277 >>>   SMTP<< 220 OK
278 >>>   SMTP(close)>>
279 >>> wrote callout cache domain record for b:
280 >>>   result=1 postmaster=0 random=0
281 >>> wrote positive callout cache address record for a@b
282 >>> ----------- end verify ------------
283 >>> accept: condition test succeeded in ACL "mail"
284 >>> end of ACL "mail": ACCEPT
285 >>> host in hosts_connection_nolog? no (option unset)
286 >>> host in host_lookup? no (option unset)
287 >>> host in host_reject_connection? no (option unset)
288 >>> host in sender_unqualified_hosts? no (option unset)
289 >>> host in recipient_unqualified_hosts? no (option unset)
290 >>> host in helo_verify_hosts? no (option unset)
291 >>> host in helo_try_verify_hosts? no (option unset)
292 >>> host in helo_accept_junk_hosts? no (option unset)
293 >>> using ACL "mail"
294 >>> processing "accept"
295 >>> check verify = sender/callout=1s,maxwait=1s
296 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
297 >>> routing p1@q
298 >>> calling r1 router
299 >>> routed by r1 router
300 >>> local host found for non-MX address
301 MUNGED: ::1 will be omitted in what follows
302 >>> get[host|ipnode]byname[2] looked up these IP addresses:
303 >>>   name=127.0.0.1 address=127.0.0.1
304 >>> Attempting full verification using callout
305 >>> callout cache: no domain record found for q
306 >>> callout cache: no address record found for p1@q
307 >>> interface=NULL port=1224
308 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
309 >>>   SMTP<< 220 server ready
310 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
311 >>>   SMTP>> EHLO myhost.test.ex
312 >>> cmd buf flush 21 bytes
313 >>>   SMTP(close)>>
314 >>> SMTP timeout
315 >>> ----------- end verify ------------
316 >>> accept: condition test deferred in ACL "mail"
317 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
318 LOG: H=[1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout