Debug: process exits
[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 >>> test in helo_lookup_domains?
10 >>>  list element: @
11 >>>  list element: @[]
12 >>> test in helo_lookup_domains? no (end of list)
13 >>> using ACL "mail"
14 >>> processing "accept" (TESTSUITE/test-config 20)
15 >>> check verify = sender/callout=1s,maxwait=1s
16 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17 >>> routing x@y
18 >>> calling r1 router
19 >>> routed by r1 router
20 >>> local host found for non-MX address
21 MUNGED: ::1 will be omitted in what follows
22 >>> get[host|ipnode]byname[2] looked up these IP addresses:
23 >>>   name=127.0.0.1 address=127.0.0.1
24 >>> Attempting full verification using callout
25 >>> ... callout omitted by default when host testing
26 >>> (Use -bhc if you want the callouts to happen.)
27 >>> ----------- end verify ------------
28 >>> accept: condition test succeeded in ACL "mail"
29 >>> end of ACL "mail": ACCEPT
30 Exim version x.yz ....
31 Hints DB:
32 changed uid/gid: forcing real = effective
33   uid=uuuu gid=CALLER_GID pid=p1234
34 configuration file is TESTSUITE/test-config
35 admin user
36 changed uid/gid: privilege not needed
37   uid=EXIM_UID gid=EXIM_GID pid=p1234
38 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
39 sender address = CALLER@myhost.test.ex
40 sender_fullhost = [1.2.3.4]
41 sender_rcvhost = [1.2.3.4]
42 host in hosts_connection_nolog? no (option unset)
43 LOG: smtp_connection MAIN
44   SMTP connection from [1.2.3.4]
45 host in host_lookup? no (option unset)
46 set_process_info: pppp handling incoming connection from [1.2.3.4]
47 host in host_reject_connection? no (option unset)
48 host in sender_unqualified_hosts? no (option unset)
49 host in recipient_unqualified_hosts? no (option unset)
50 host in helo_verify_hosts? no (option unset)
51 host in helo_try_verify_hosts? no (option unset)
52 host in helo_accept_junk_hosts? no (option unset)
53 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
54 smtp_setup_msg entered
55 SMTP<< helo test
56 test in helo_lookup_domains?
57  list element: @
58  list element: @[]
59 test in helo_lookup_domains? no (end of list)
60 sender_fullhost = (test) [1.2.3.4]
61 sender_rcvhost = [1.2.3.4] (helo=test)
62 set_process_info: pppp handling incoming connection from (test) [1.2.3.4]
63 SMTP>> 250 myhost.test.ex Hello test [1.2.3.4]
64 SMTP<< mail from:<x@y>
65 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
66 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
67 using ACL "mail"
68 processing "accept" (TESTSUITE/test-config 20)
69 check verify = sender/callout=1s,maxwait=1s
70 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
71 Verifying x@y
72 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
73 Considering x@y
74 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
75 routing x@y
76 --------> r1 router <--------
77 local_part=x domain=y
78 calling r1 router
79 r1 router called for x@y
80   domain = y
81 set transport t1
82 queued for t1 transport: local_part = x
83 domain = y
84   errors_to=NULL
85   domain_data=NULL local_part_data=NULL
86 routed by r1 router
87   envelope to: x@y
88   transport: t1
89 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
90 local host found for non-MX address
91 MUNGED: ::1 will be omitted in what follows
92 get[host|ipnode]byname[2] looked up these IP addresses:
93   name=127.0.0.1 address=127.0.0.1
94 Attempting full verification using callout
95  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
96  returned from EXIM_DBOPEN: 0xAAAAAAAA
97  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
98  dbfn_read: key=y
99  dbfn_read: null return
100  callout cache: no domain record found for y
101  dbfn_read: key=x@y
102  dbfn_read: null return
103  callout cache: no address record found for x@y
104  EXIM_DBCLOSE(0xAAAAAAAA)
105  closed hints database
106 interface=NULL port=PORT_S
107 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
108  127.0.0.1 in hosts_try_fastopen?
109   list element: 
110 connected
111   SMTP<< 220 server ready
112 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
113   SMTP>> EHLO myhost.test.ex
114 cmd buf flush ddd bytes
115   SMTP<< 250 OK
116 not using PIPELINING
117 not using DSN
118 127.0.0.1 in hosts_require_auth? no (option unset)
119   SMTP>> MAIL FROM:<>
120 cmd buf flush ddd bytes
121   SMTP<< 250 OK
122   SMTP>> RCPT TO:<x@y>
123 cmd buf flush ddd bytes
124 sync_responses expect rcpt for x@y
125   SMTP<< 250 OK
126   SMTP>> QUIT
127 cmd buf flush ddd bytes
128   SMTP<< 220 OK
129   SMTP(close)>>
130 cmdlog: '220:EHLO:250:MAIL:250:RCPT:250:QUIT:220'
131  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
132  returned from EXIM_DBOPEN: 0xAAAAAAAA
133  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
134  dbfn_write: key=y datalen 40
135  wrote callout cache domain record for y:
136    result=1 postmaster=0 random=0
137  dbfn_write: key=x@y datalen 16
138  wrote positive callout cache address record for x@y
139  EXIM_DBCLOSE(0xAAAAAAAA)
140  closed hints database
141 ----------- end verify ------------
142 sender x@y verified ok
143 accept: condition test succeeded in ACL "mail"
144 end of ACL "mail": ACCEPT
145 SMTP>> 250 OK
146 SMTP<< quit
147 SMTP>> 221 myhost.test.ex closing connection
148 LOG: smtp_connection MAIN
149   SMTP connection from (test) [1.2.3.4] D=qqs closed by QUIT
150 search_tidyup called
151 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
152 Exim version x.yz ....
153 Hints DB:
154 changed uid/gid: forcing real = effective
155   uid=uuuu gid=CALLER_GID pid=p1235
156 configuration file is TESTSUITE/test-config
157 admin user
158 changed uid/gid: privilege not needed
159   uid=EXIM_UID gid=EXIM_GID pid=p1235
160 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
161 sender address = CALLER@myhost.test.ex
162 sender_fullhost = [1.2.3.4]
163 sender_rcvhost = [1.2.3.4]
164 host in hosts_connection_nolog? no (option unset)
165 LOG: smtp_connection MAIN
166   SMTP connection from [1.2.3.4]
167 host in host_lookup? no (option unset)
168 set_process_info: pppp handling incoming connection from [1.2.3.4]
169 host in host_reject_connection? no (option unset)
170 host in sender_unqualified_hosts? no (option unset)
171 host in recipient_unqualified_hosts? no (option unset)
172 host in helo_verify_hosts? no (option unset)
173 host in helo_try_verify_hosts? no (option unset)
174 host in helo_accept_junk_hosts? no (option unset)
175 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
176 smtp_setup_msg entered
177 SMTP<< helo test
178 test in helo_lookup_domains?
179  list element: @
180  list element: @[]
181 test in helo_lookup_domains? no (end of list)
182 sender_fullhost = (test) [1.2.3.4]
183 sender_rcvhost = [1.2.3.4] (helo=test)
184 set_process_info: pppp handling incoming connection from (test) [1.2.3.4]
185 SMTP>> 250 myhost.test.ex Hello test [1.2.3.4]
186 SMTP<< mail from:<x@y>
187 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
188 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
189 using ACL "mail"
190 processing "accept" (TESTSUITE/test-config 20)
191 check verify = sender/callout=1s,maxwait=1s
192 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
193 Verifying x@y
194 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
195 Considering x@y
196 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
197 routing x@y
198 --------> r1 router <--------
199 local_part=x domain=y
200 calling r1 router
201 r1 router called for x@y
202   domain = y
203 set transport t1
204 queued for t1 transport: local_part = x
205 domain = y
206   errors_to=NULL
207   domain_data=NULL local_part_data=NULL
208 routed by r1 router
209   envelope to: x@y
210   transport: t1
211 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
212 local host found for non-MX address
213 MUNGED: ::1 will be omitted in what follows
214 get[host|ipnode]byname[2] looked up these IP addresses:
215   name=127.0.0.1 address=127.0.0.1
216 Attempting full verification using callout
217  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
218  returned from EXIM_DBOPEN: 0xAAAAAAAA
219  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
220  dbfn_read: key=y
221  dbfn_read: size 40 return
222  callout cache: found domain record for y
223  dbfn_read: key=x@y
224  dbfn_read: size 16 return
225  callout cache: found address record for x@y
226  callout cache: address record is positive
227  EXIM_DBCLOSE(0xAAAAAAAA)
228  closed hints database
229 ----------- end verify ------------
230 sender x@y verified ok
231 accept: condition test succeeded in ACL "mail"
232 end of ACL "mail": ACCEPT
233 SMTP>> 250 OK
234 SMTP<< quit
235 SMTP>> 221 myhost.test.ex closing connection
236 LOG: smtp_connection MAIN
237   SMTP connection from (test) [1.2.3.4] D=qqs closed by QUIT
238 search_tidyup called
239 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
240 >>> host in hosts_connection_nolog? no (option unset)
241 >>> host in host_lookup? no (option unset)
242 >>> host in host_reject_connection? no (option unset)
243 >>> host in sender_unqualified_hosts? no (option unset)
244 >>> host in recipient_unqualified_hosts? no (option unset)
245 >>> host in helo_verify_hosts? no (option unset)
246 >>> host in helo_try_verify_hosts? no (option unset)
247 >>> host in helo_accept_junk_hosts? no (option unset)
248 >>> test in helo_lookup_domains?
249 >>>  list element: @
250 >>>  list element: @[]
251 >>> test in helo_lookup_domains? no (end of list)
252 >>> using ACL "mail"
253 >>> processing "accept" (TESTSUITE/test-config 20)
254 >>> check verify = sender/callout=1s,maxwait=1s
255 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
256 >>> routing x@y
257 >>> calling r1 router
258 >>> routed by r1 router
259 >>> local host found for non-MX address
260 MUNGED: ::1 will be omitted in what follows
261 >>> get[host|ipnode]byname[2] looked up these IP addresses:
262 >>>   name=127.0.0.1 address=127.0.0.1
263 >>> Attempting full verification using callout
264 >>> callout cache: found domain record for y
265 >>> callout cache: found address record for x@y
266 >>> callout cache: address record is positive
267 >>> ----------- end verify ------------
268 >>> accept: condition test succeeded in ACL "mail"
269 >>> end of ACL "mail": ACCEPT
270 >>> host in hosts_connection_nolog? no (option unset)
271 >>> host in host_lookup? no (option unset)
272 >>> host in host_reject_connection? no (option unset)
273 >>> host in sender_unqualified_hosts? no (option unset)
274 >>> host in recipient_unqualified_hosts? no (option unset)
275 >>> host in helo_verify_hosts? no (option unset)
276 >>> host in helo_try_verify_hosts? no (option unset)
277 >>> host in helo_accept_junk_hosts? no (option unset)
278 >>> test in helo_lookup_domains?
279 >>>  list element: @
280 >>>  list element: @[]
281 >>> test in helo_lookup_domains? no (end of list)
282 >>> using ACL "mail"
283 >>> processing "accept" (TESTSUITE/test-config 20)
284 >>> check verify = sender/callout=1s,maxwait=1s
285 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
286 >>> routing a@b
287 >>> calling r1 router
288 >>> routed by r1 router
289 >>> local host found for non-MX address
290 MUNGED: ::1 will be omitted in what follows
291 >>> get[host|ipnode]byname[2] looked up these IP addresses:
292 >>>   name=127.0.0.1 address=127.0.0.1
293 >>> Attempting full verification using callout
294 >>> callout cache: no domain record found for b
295 >>> callout cache: no address record found for a@b
296 >>> interface=NULL port=PORT_S
297 >>> Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
298 >>>  127.0.0.1 in hosts_try_fastopen?
299 >>>   list element: 
300 >>> connected
301 >>>   SMTP<< 220 server ready
302 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
303 >>>   SMTP>> EHLO myhost.test.ex
304 >>> cmd buf flush 21 bytes
305 >>>   SMTP<< 250 OK
306 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
307 >>>   SMTP>> MAIL FROM:<>
308 >>> cmd buf flush 14 bytes
309 >>>   SMTP<< 250 OK
310 >>>   SMTP>> RCPT TO:<a@b>
311 >>> cmd buf flush 15 bytes
312 >>>   SMTP<< 250 OK
313 >>>   SMTP>> QUIT
314 >>> cmd buf flush 6 bytes
315 >>>   SMTP<< 220 OK
316 >>>   SMTP(close)>>
317 >>> cmdlog: '220:EHLO:250:MAIL:250:RCPT:250:QUIT:220'
318 >>> wrote callout cache domain record for b:
319 >>>   result=1 postmaster=0 random=0
320 >>> wrote positive callout cache address record for a@b
321 >>> ----------- end verify ------------
322 >>> accept: condition test succeeded in ACL "mail"
323 >>> end of ACL "mail": ACCEPT
324 >>> host in hosts_connection_nolog? no (option unset)
325 >>> host in host_lookup? no (option unset)
326 >>> host in host_reject_connection? no (option unset)
327 >>> host in sender_unqualified_hosts? no (option unset)
328 >>> host in recipient_unqualified_hosts? no (option unset)
329 >>> host in helo_verify_hosts? no (option unset)
330 >>> host in helo_try_verify_hosts? no (option unset)
331 >>> host in helo_accept_junk_hosts? no (option unset)
332 >>> test in helo_lookup_domains?
333 >>>  list element: @
334 >>>  list element: @[]
335 >>> test in helo_lookup_domains? no (end of list)
336 >>> using ACL "mail"
337 >>> processing "accept" (TESTSUITE/test-config 20)
338 >>> check verify = sender/callout=1s,maxwait=1s
339 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
340 >>> routing p1@q
341 >>> calling r1 router
342 >>> routed by r1 router
343 >>> local host found for non-MX address
344 MUNGED: ::1 will be omitted in what follows
345 >>> get[host|ipnode]byname[2] looked up these IP addresses:
346 >>>   name=127.0.0.1 address=127.0.0.1
347 >>> Attempting full verification using callout
348 >>> callout cache: no domain record found for q
349 >>> callout cache: no address record found for p1@q
350 >>> interface=NULL port=PORT_S
351 >>> Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
352 >>>  127.0.0.1 in hosts_try_fastopen?
353 >>>   list element: 
354 >>> connected
355 >>>   SMTP<< 220 server ready
356 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
357 >>>   SMTP>> EHLO myhost.test.ex
358 >>> cmd buf flush 21 bytes
359 >>>   SMTP(close)>>
360 >>> cmdlog: '220:EHLO'
361 >>> SMTP timeout
362 >>> ----------- end verify ------------
363 >>> accept: condition test deferred in ACL "mail"
364 LOG: H=(test) [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
365 LOG: H=(test) [1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout