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