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