Merge branch 'hs/fix-proxy-bh' (Closes 2656)
[users/heiko/exim.git] / test / stderr / 0275
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 dropping to exim gid; retaining priv uid
7 seeking password data for user "CALLER": cache not available
8 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
9 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
10 sender address = CALLER@test.ex
11 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
12 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
13 Testing userx@test.ex
14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
15 Considering userx@test.ex
16 test.ex in "! *.ex"? no (matched "! *.ex")
17 test.ex in "test.ex"? yes (matched "test.ex")
18 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
19 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
20 routing userx@test.ex
21 --------> r00 router <--------
22 local_part=userx domain=test.ex
23 checking domains
24 test.ex in "userx"? no (end of list)
25 test.ex in "+nocache"? no (end of list)
26 r00 router skipped: domains mismatch
27 --------> r01 router <--------
28 local_part=userx domain=test.ex
29 checking domains
30 test.ex in "userx"? no (end of list)
31 test.ex in "+nocache"? no (end of list)
32 r01 router skipped: domains mismatch
33 --------> r02 router <--------
34 local_part=userx domain=test.ex
35 checking domains
36 test.ex in "userx"? no (end of list)
37 test.ex in "+nocache"? no (end of list)
38 test.ex in "+nocache2"? no (end of list)
39 r02 router skipped: domains mismatch
40 --------> r03 router <--------
41 local_part=userx domain=test.ex
42 checking domains
43 test.ex in "userx"? no (end of list)
44 test.ex in "+nocache"? no (end of list)
45 test.ex in "+nocache2"? no (end of list)
46 r03 router skipped: domains mismatch
47 --------> r04 router <--------
48 local_part=userx domain=test.ex
49 checking domains
50 test.ex in "userx"? no (end of list)
51 test.ex in "+forcecache"? no (end of list)
52 r04 router skipped: domains mismatch
53 --------> r05 router <--------
54 local_part=userx domain=test.ex
55 checking domains
56 cached no match for +forcecache
57 cached lookup data = NULL
58 test.ex in "+forcecache"? no (end of list)
59 r05 router skipped: domains mismatch
60 --------> r1 router <--------
61 local_part=userx domain=test.ex
62 checking domains
63 test.ex in "never.ex"? no (end of list)
64 test.ex in "never1.ex"? no (end of list)
65 test.ex in "test.ex"? yes (matched "test.ex")
66 data from lookup saved for cache for +local_domains: key 'test.ex' value 'test.ex'
67 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
68 r1 router skipped: domains mismatch
69 --------> r2 router <--------
70 local_part=userx domain=test.ex
71 checking domains
72 cached no match for +never_domains
73 cached lookup data = NULL
74 cached no match for +n1_domains
75 cached lookup data = NULL
76 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
77 cached yes match for +local_domains
78 cached lookup data = test.ex
79 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
80 r2 router skipped: domains mismatch
81 --------> r3 router <--------
82 local_part=userx domain=test.ex
83 checking domains
84 cached yes match for +local_domains
85 cached lookup data = test.ex
86 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
87 checking local_parts
88 userx in "userx"? yes (matched "userx")
89 calling r3 router
90 r3 router called for userx@test.ex
91   domain = test.ex
92 set transport t1
93 queued for t1 transport: local_part = userx
94 domain = test.ex
95   errors_to=NULL
96   domain_data=test.ex local_part_data=userx
97 routed by r3 router
98   envelope to: userx@test.ex
99   transport: t1
100 search_tidyup called
101 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
102 Exim version x.yz ....
103 changed uid/gid: forcing real = effective
104   uid=uuuu gid=CALLER_GID pid=pppp
105 configuration file is TESTSUITE/test-config
106 admin user
107 changed uid/gid: privilege not needed
108   uid=EXIM_UID gid=EXIM_GID pid=pppp
109 seeking password data for user "CALLER": cache not available
110 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
111 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
112 sender address = CALLER@test.ex
113 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
114 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
115 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
116 Sender: CALLER@test.ex
117 Recipients:
118   userx@test.ex
119 search_tidyup called
120 >>Headers received:
121
122 rewrite_one_header: type=F:
123   From: CALLER_NAME <CALLER@test.ex>
124 search_tidyup called
125 >>Headers after rewriting and local additions:
126 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
127 F From: CALLER_NAME <CALLER@test.ex>
128   Date: Tue, 2 Mar 1999 09:44:33 +0000
129
130 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
131 Data file written for message 10HmaX-0005vi-00
132 >>Generated Received: header line
133 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
134         (envelope-from <CALLER@test.ex>)
135         id 10HmaX-0005vi-00
136         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
137 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
138 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
139 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
140 Size of headers = sss
141 LOG: MAIN
142   <= CALLER@test.ex U=CALLER P=local S=sss
143 created log directory TESTSUITE/spool/log
144 search_tidyup called
145 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
146 Exim version x.yz ....
147 changed uid/gid: forcing real = effective
148   uid=uuuu gid=EXIM_GID pid=pppp
149 configuration file is TESTSUITE/test-config
150 trusted user
151 admin user
152 dropping to exim gid; retaining priv uid
153 seeking password data for user "CALLER": cache not available
154 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
155 set_process_info: pppp delivering specified messages
156 set_process_info: pppp delivering 10HmaX-0005vi-00
157 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
158 reading spool file 10HmaX-0005vi-00-H
159 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
160 sender_local=1 ident=CALLER
161 Non-recipients:
162  Empty Tree
163 ---- End of tree ----
164 recipients_count=1
165 **** SPOOL_IN - No additional fields
166 body_linecount=0 message_linecount=7
167 DSN: set orcpt:   flags: 0x0
168 Delivery address list:
169   userx@test.ex 
170  locking TESTSUITE/spool/db/retry.lockfile
171  locked  TESTSUITE/spool/db/retry.lockfile
172  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
173  returned from EXIM_DBOPEN: (nil)
174  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
175  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
176 no retry data available
177 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
178 Considering: userx@test.ex
179 test.ex in "! *.ex"? no (matched "! *.ex")
180 test.ex in "test.ex"? yes (matched "test.ex")
181 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
182 cached no match for +hold_domains
183 test.ex in hold_domains? no (end of list)
184 unique = userx@test.ex
185 no   domain  retry record
186 no   address retry record
187 userx@test.ex: queued for routing
188 cached yes match for +not_queue_domains
189 test.ex in queue_domains? no (matched "!+not_queue_domains" - cached)
190 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
191 routing userx@test.ex
192 --------> r00 router <--------
193 local_part=userx domain=test.ex
194 checking domains
195 test.ex in "userx"? no (end of list)
196 test.ex in "+nocache"? no (end of list)
197 r00 router skipped: domains mismatch
198 --------> r01 router <--------
199 local_part=userx domain=test.ex
200 checking domains
201 test.ex in "userx"? no (end of list)
202 test.ex in "+nocache"? no (end of list)
203 r01 router skipped: domains mismatch
204 --------> r02 router <--------
205 local_part=userx domain=test.ex
206 checking domains
207 test.ex in "userx"? no (end of list)
208 test.ex in "+nocache"? no (end of list)
209 test.ex in "+nocache2"? no (end of list)
210 r02 router skipped: domains mismatch
211 --------> r03 router <--------
212 local_part=userx domain=test.ex
213 checking domains
214 test.ex in "userx"? no (end of list)
215 test.ex in "+nocache"? no (end of list)
216 test.ex in "+nocache2"? no (end of list)
217 r03 router skipped: domains mismatch
218 --------> r04 router <--------
219 local_part=userx domain=test.ex
220 checking domains
221 test.ex in "userx"? no (end of list)
222 test.ex in "+forcecache"? no (end of list)
223 r04 router skipped: domains mismatch
224 --------> r05 router <--------
225 local_part=userx domain=test.ex
226 checking domains
227 cached no match for +forcecache
228 cached lookup data = NULL
229 test.ex in "+forcecache"? no (end of list)
230 r05 router skipped: domains mismatch
231 --------> r1 router <--------
232 local_part=userx domain=test.ex
233 checking domains
234 test.ex in "never.ex"? no (end of list)
235 test.ex in "never1.ex"? no (end of list)
236 test.ex in "test.ex"? yes (matched "test.ex")
237 data from lookup saved for cache for +local_domains: key 'test.ex' value 'test.ex'
238 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
239 r1 router skipped: domains mismatch
240 --------> r2 router <--------
241 local_part=userx domain=test.ex
242 checking domains
243 cached no match for +never_domains
244 cached lookup data = NULL
245 cached no match for +n1_domains
246 cached lookup data = NULL
247 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
248 cached yes match for +local_domains
249 cached lookup data = test.ex
250 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
251 r2 router skipped: domains mismatch
252 --------> r3 router <--------
253 local_part=userx domain=test.ex
254 checking domains
255 cached yes match for +local_domains
256 cached lookup data = test.ex
257 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
258 checking local_parts
259 userx in "userx"? yes (matched "userx")
260 calling r3 router
261 r3 router called for userx@test.ex
262   domain = test.ex
263 set transport t1
264 queued for t1 transport: local_part = userx
265 domain = test.ex
266   errors_to=NULL
267   domain_data=test.ex local_part_data=userx
268 routed by r3 router
269   envelope to: userx@test.ex
270   transport: t1
271 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
272 After routing:
273   Local deliveries:
274     userx@test.ex
275   Remote deliveries:
276   Failed addresses:
277   Deferred addresses:
278 search_tidyup called
279 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
280 --------> userx@test.ex <--------
281  locking TESTSUITE/spool/db/retry.lockfile
282  locked  TESTSUITE/spool/db/retry.lockfile
283  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
284  returned from EXIM_DBOPEN: (nil)
285  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
286 no retry data available
287 search_tidyup called
288 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
289   uid=CALLER_UID gid=CALLER_GID pid=pppp
290   home=NULL current=/
291 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
292 appendfile transport entered
293 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
294   file=/dev/null format=unix
295   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
296   message_suffix=\n
297   maildir_use_size_file=no
298   locking by lockfile fcntl 
299 search_tidyup called
300 journalling userx@test.ex
301 t1 transport returned OK for userx@test.ex
302 post-process userx@test.ex (0)
303 userx@test.ex delivered
304 LOG: MAIN
305   => userx <userx@test.ex> R=r3 T=t1
306 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
307 changed uid/gid: post-delivery tidying
308   uid=EXIM_UID gid=EXIM_GID pid=pppp
309 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
310 Processing retry items
311 Succeeded addresses:
312  userx@test.ex: no retry items
313 Failed addresses:
314 Deferred addresses:
315 end of retry processing
316 DSN: processing router : r3
317 DSN: processing successful delivery address: userx@test.ex
318 DSN: Sender_address: CALLER@test.ex
319 DSN: orcpt: NULL  flags: 0x0
320 DSN: envid: NULL  ret: 0
321 DSN: Final recipient: userx@test.ex
322 DSN: Remote SMTP server supports DSN: 0
323 DSN: not sending DSN success message
324 LOG: MAIN
325   Completed
326 end delivery of 10HmaX-0005vi-00
327 search_tidyup called
328 search_tidyup called
329 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
330 search_tidyup called
331 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
332 Exim version x.yz ....
333 changed uid/gid: forcing real = effective
334   uid=uuuu gid=CALLER_GID pid=pppp
335 configuration file is TESTSUITE/test-config
336 admin user
337 changed uid/gid: privilege not needed
338   uid=EXIM_UID gid=EXIM_GID pid=pppp
339 seeking password data for user "CALLER": cache not available
340 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
341 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
342 sender address = CALLER@test.ex
343 sender_fullhost = [127.0.0.1]
344 sender_rcvhost = [127.0.0.1]
345 host in hosts_connection_nolog? no (option unset)
346 LOG: smtp_connection MAIN
347   SMTP connection from [127.0.0.1]
348 host in host_lookup? no (option unset)
349 set_process_info: pppp handling incoming connection from [127.0.0.1]
350 host in host_reject_connection? no (option unset)
351 host in sender_unqualified_hosts? no (option unset)
352 host in recipient_unqualified_hosts? no (option unset)
353 host in helo_verify_hosts? no (option unset)
354 host in helo_try_verify_hosts? no (option unset)
355 host in helo_accept_junk_hosts? no (option unset)
356 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
357 smtp_setup_msg entered
358 SMTP<< HELO test
359 test in helo_lookup_domains? no (end of list)
360 sender_fullhost = (test) [127.0.0.1]
361 sender_rcvhost = [127.0.0.1] (helo=test)
362 set_process_info: pppp handling incoming connection from (test) [127.0.0.1]
363 SMTP>> 250 myhost.test.ex Hello test [127.0.0.1]
364 SMTP<< MAIL FROM:<test@test.ex>
365 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
366 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
367 SMTP>> 250 OK
368 SMTP<< RCPT TO:<error@test.ex>
369 test.ex in "! *.ex"? no (matched "! *.ex")
370 test.ex in "test.ex"? yes (matched "test.ex")
371 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
372 processing "accept" (TESTSUITE/test-config 102)
373 check verify = recipient
374 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
375 Verifying error@test.ex
376 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
377 Considering error@test.ex
378 cached no match for +hold_domains
379 cached yes match for +not_queue_domains
380 test.ex in percent_hack_domains? yes (matched "+not_queue_domains" - cached)
381 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
382 routing error@test.ex
383 --------> r0f router <--------
384 local_part=error domain=test.ex
385 checking domains
386 LOG: MAIN PANIC
387   unknown named domain list "+no_such_list"
388 domains check lookup or other defer
389 ----------- end verify ------------
390 accept: condition test deferred in inline ACL
391 SMTP>> 451 Temporary local problem - please try later
392 LOG: MAIN REJECT
393   H=(test) [127.0.0.1] F=<test@test.ex> temporarily rejected RCPT <error@test.ex>: domains check lookup or other defer
394 SMTP<< QUIT
395 SMTP>> 221 myhost.test.ex closing connection
396 LOG: smtp_connection MAIN
397   SMTP connection from (test) [127.0.0.1] closed by QUIT
398 search_tidyup called
399 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>