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