Log local IP on delivery log line, under the incoming_interface log selector. Bug 507
[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 seeking password data for user "CALLER": cache not available
7 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
8 DSN: r00 propagating DSN
9 DSN: r01 propagating DSN
10 DSN: r02 propagating DSN
11 DSN: r03 propagating DSN
12 DSN: r04 propagating DSN
13 DSN: r05 propagating DSN
14 DSN: r1 propagating DSN
15 DSN: r2 propagating DSN
16 DSN: r3 propagating DSN
17 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
18 sender address = CALLER@test.ex
19 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
20 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
21 Testing userx@test.ex
22 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
23 Considering userx@test.ex
24 test.ex in "! *.ex"? no (matched "! *.ex")
25 test.ex in "test.ex"? yes (matched "test.ex")
26 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
27 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
28 routing userx@test.ex
29 --------> r00 router <--------
30 local_part=userx domain=test.ex
31 checking domains
32 test.ex in "userx"? no (end of list)
33 test.ex in "+nocache"? no (end of list)
34 r00 router skipped: domains mismatch
35 --------> r01 router <--------
36 local_part=userx domain=test.ex
37 checking domains
38 test.ex in "userx"? no (end of list)
39 test.ex in "+nocache"? no (end of list)
40 r01 router skipped: domains mismatch
41 --------> r02 router <--------
42 local_part=userx domain=test.ex
43 checking domains
44 test.ex in "userx"? no (end of list)
45 test.ex in "+nocache"? no (end of list)
46 test.ex in "+nocache2"? no (end of list)
47 r02 router skipped: domains mismatch
48 --------> r03 router <--------
49 local_part=userx domain=test.ex
50 checking domains
51 test.ex in "userx"? no (end of list)
52 test.ex in "+nocache"? no (end of list)
53 test.ex in "+nocache2"? no (end of list)
54 r03 router skipped: domains mismatch
55 --------> r04 router <--------
56 local_part=userx domain=test.ex
57 checking domains
58 test.ex in "userx"? no (end of list)
59 test.ex in "+forcecache"? no (end of list)
60 r04 router skipped: domains mismatch
61 --------> r05 router <--------
62 local_part=userx domain=test.ex
63 checking domains
64 cached no match for +forcecache
65 cached lookup data = NULL
66 test.ex in "+forcecache"? no (end of list)
67 r05 router skipped: domains mismatch
68 --------> r1 router <--------
69 local_part=userx domain=test.ex
70 checking domains
71 test.ex in "never.ex"? no (end of list)
72 test.ex in "never1.ex"? no (end of list)
73 test.ex in "test.ex"? yes (matched "test.ex")
74 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
75 r1 router skipped: domains mismatch
76 --------> r2 router <--------
77 local_part=userx domain=test.ex
78 checking domains
79 cached no match for +never_domains
80 cached lookup data = NULL
81 cached no match for +n1_domains
82 cached lookup data = NULL
83 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
84 cached yes match for +local_domains
85 cached lookup data = NULL
86 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
87 r2 router skipped: domains mismatch
88 --------> r3 router <--------
89 local_part=userx domain=test.ex
90 checking domains
91 cached yes match for +local_domains
92 cached lookup data = NULL
93 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
94 checking local_parts
95 userx in "userx"? yes (matched "userx")
96 calling r3 router
97 r3 router called for userx@test.ex
98   domain = test.ex
99 set transport t1
100 queued for t1 transport: local_part = userx
101 domain = test.ex
102   errors_to=NULL
103   domain_data=NULL localpart_data=NULL
104 routed by r3 router
105   envelope to: userx@test.ex
106   transport: t1
107 search_tidyup called
108 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
109 Exim version x.yz ....
110 changed uid/gid: forcing real = effective
111   uid=uuuu gid=CALLER_GID pid=pppp
112 configuration file is TESTSUITE/test-config
113 admin user
114 changed uid/gid: privilege not needed
115   uid=EXIM_UID gid=EXIM_GID pid=pppp
116 seeking password data for user "CALLER": cache not available
117 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
118 DSN: r00 propagating DSN
119 DSN: r01 propagating DSN
120 DSN: r02 propagating DSN
121 DSN: r03 propagating DSN
122 DSN: r04 propagating DSN
123 DSN: r05 propagating DSN
124 DSN: r1 propagating DSN
125 DSN: r2 propagating DSN
126 DSN: r3 propagating DSN
127 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
128 sender address = CALLER@test.ex
129 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
130 Sender: CALLER@test.ex
131 Recipients:
132   userx@test.ex
133 search_tidyup called
134 >>Headers received:
135
136 rewrite_one_header: type=F:
137   From: CALLER_NAME <CALLER@test.ex>
138 search_tidyup called
139 >>Headers after rewriting and local additions:
140 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
141 F From: CALLER_NAME <CALLER@test.ex>
142   Date: Tue, 2 Mar 1999 09:44:33 +0000
143
144 Data file written for message 10HmaX-0005vi-00
145 >>Generated Received: header line
146 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
147         (envelope-from <CALLER@test.ex>)
148         id 10HmaX-0005vi-00
149         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
150 calling local_scan(); timeout=300
151 local_scan() returned 0 NULL
152 Writing spool header file
153 DSN: Write SPOOL :-dsn_envid NULL
154 DSN: Write SPOOL :-dsn_ret 0
155 DSN: Flags :0
156 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
157 Size of headers = sss
158 LOG: MAIN
159   <= CALLER@test.ex U=CALLER P=local S=sss
160 created log directory TESTSUITE/spool/log
161 search_tidyup called
162 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
163 Exim version x.yz ....
164 changed uid/gid: forcing real = effective
165   uid=uuuu gid=EXIM_GID pid=pppp
166 configuration file is TESTSUITE/test-config
167 trusted user
168 admin user
169 seeking password data for user "CALLER": cache not available
170 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
171 DSN: r00 propagating DSN
172 DSN: r01 propagating DSN
173 DSN: r02 propagating DSN
174 DSN: r03 propagating DSN
175 DSN: r04 propagating DSN
176 DSN: r05 propagating DSN
177 DSN: r1 propagating DSN
178 DSN: r2 propagating DSN
179 DSN: r3 propagating DSN
180 set_process_info: pppp delivering specified messages
181 set_process_info: pppp delivering 10HmaX-0005vi-00
182 reading spool file 10HmaX-0005vi-00-H
183 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
184 sender_local=1 ident=CALLER
185 Non-recipients:
186 Empty Tree
187 ---- End of tree ----
188 recipients_count=1
189 **** SPOOL_IN - No additional fields
190 body_linecount=0 message_linecount=7
191 DSN: set orcpt: NULL  flags: 0
192 Delivery address list:
193   userx@test.ex 
194 locking TESTSUITE/spool/db/retry.lockfile
195 locked TESTSUITE/spool/db/retry.lockfile
196 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
197 returned from EXIM_DBOPEN
198 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
199 no retry data available
200 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
201 Considering: userx@test.ex
202 test.ex in "! *.ex"? no (matched "! *.ex")
203 test.ex in "test.ex"? yes (matched "test.ex")
204 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
205 cached no match for +hold_domains
206 test.ex in hold_domains? no (end of list)
207 unique = userx@test.ex
208 no domain retry record
209 no address retry record
210 userx@test.ex: queued for routing
211 cached yes match for +not_queue_domains
212 test.ex in queue_domains? no (matched "!+not_queue_domains" - cached)
213 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
214 routing userx@test.ex
215 --------> r00 router <--------
216 local_part=userx domain=test.ex
217 checking domains
218 test.ex in "userx"? no (end of list)
219 test.ex in "+nocache"? no (end of list)
220 r00 router skipped: domains mismatch
221 --------> r01 router <--------
222 local_part=userx domain=test.ex
223 checking domains
224 test.ex in "userx"? no (end of list)
225 test.ex in "+nocache"? no (end of list)
226 r01 router skipped: domains mismatch
227 --------> r02 router <--------
228 local_part=userx domain=test.ex
229 checking domains
230 test.ex in "userx"? no (end of list)
231 test.ex in "+nocache"? no (end of list)
232 test.ex in "+nocache2"? no (end of list)
233 r02 router skipped: domains mismatch
234 --------> r03 router <--------
235 local_part=userx domain=test.ex
236 checking domains
237 test.ex in "userx"? no (end of list)
238 test.ex in "+nocache"? no (end of list)
239 test.ex in "+nocache2"? no (end of list)
240 r03 router skipped: domains mismatch
241 --------> r04 router <--------
242 local_part=userx domain=test.ex
243 checking domains
244 test.ex in "userx"? no (end of list)
245 test.ex in "+forcecache"? no (end of list)
246 r04 router skipped: domains mismatch
247 --------> r05 router <--------
248 local_part=userx domain=test.ex
249 checking domains
250 cached no match for +forcecache
251 cached lookup data = NULL
252 test.ex in "+forcecache"? no (end of list)
253 r05 router skipped: domains mismatch
254 --------> r1 router <--------
255 local_part=userx domain=test.ex
256 checking domains
257 test.ex in "never.ex"? no (end of list)
258 test.ex in "never1.ex"? no (end of list)
259 test.ex in "test.ex"? yes (matched "test.ex")
260 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
261 r1 router skipped: domains mismatch
262 --------> r2 router <--------
263 local_part=userx domain=test.ex
264 checking domains
265 cached no match for +never_domains
266 cached lookup data = NULL
267 cached no match for +n1_domains
268 cached lookup data = NULL
269 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
270 cached yes match for +local_domains
271 cached lookup data = NULL
272 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
273 r2 router skipped: domains mismatch
274 --------> r3 router <--------
275 local_part=userx domain=test.ex
276 checking domains
277 cached yes match for +local_domains
278 cached lookup data = NULL
279 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
280 checking local_parts
281 userx in "userx"? yes (matched "userx")
282 calling r3 router
283 r3 router called for userx@test.ex
284   domain = test.ex
285 set transport t1
286 queued for t1 transport: local_part = userx
287 domain = test.ex
288   errors_to=NULL
289   domain_data=NULL localpart_data=NULL
290 routed by r3 router
291   envelope to: userx@test.ex
292   transport: t1
293 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
294 After routing:
295   Local deliveries:
296     userx@test.ex
297   Remote deliveries:
298   Failed addresses:
299   Deferred addresses:
300 search_tidyup called
301 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
302 --------> userx@test.ex <--------
303 locking TESTSUITE/spool/db/retry.lockfile
304 locked TESTSUITE/spool/db/retry.lockfile
305 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
306 returned from EXIM_DBOPEN
307 no retry data available
308 search_tidyup called
309 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
310   uid=CALLER_UID gid=CALLER_GID pid=pppp
311   home=NULL current=/
312 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
313 appendfile transport entered
314 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
315   file=/dev/null format=unix
316   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
317   message_suffix=\n
318   maildir_use_size_file=no
319   locking by lockfile fcntl 
320 search_tidyup called
321 journalling userx@test.ex
322 t1 transport returned OK for userx@test.ex
323 post-process userx@test.ex (0)
324 userx@test.ex delivered
325 LOG: MAIN
326   => userx <userx@test.ex> R=r3 T=t1
327 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
328 changed uid/gid: post-delivery tidying
329   uid=EXIM_UID gid=EXIM_GID pid=pppp
330 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
331 Processing retry items
332 Succeeded addresses:
333 userx@test.ex: no retry items
334 Failed addresses:
335 Deferred addresses:
336 end of retry processing
337 DSN: processing router : r3
338 DSN: processing successful delivery address: userx@test.ex
339 DSN: Sender_address: CALLER@test.ex
340 DSN: orcpt: NULL  flags: 0
341 DSN: envid: NULL  ret: 0
342 DSN: Final recipient: userx@test.ex
343 DSN: Remote SMTP server supports DSN: 0
344 DSN: *** NOT SENDING DSN SUCCESS Message ***
345 LOG: MAIN
346   Completed
347 end delivery of 10HmaX-0005vi-00
348 search_tidyup called
349 search_tidyup called
350 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
351 search_tidyup called
352 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>