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