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