Avoid doing logging in signal-handlers. Bug 1007
[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 (main) 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 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
113 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
114 Sender: CALLER@test.ex
115 Recipients:
116   userx@test.ex
117 search_tidyup called
118 >>Headers received:
119
120 rewrite_one_header: type=F:
121   From: CALLER_NAME <CALLER@test.ex>
122 search_tidyup called
123 >>Headers after rewriting and local additions:
124 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
125 F From: CALLER_NAME <CALLER@test.ex>
126   Date: Tue, 2 Mar 1999 09:44:33 +0000
127
128 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
129 Data file written for message 10HmaX-0005vi-00
130 >>Generated Received: header line
131 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
132         (envelope-from <CALLER@test.ex>)
133         id 10HmaX-0005vi-00
134         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
135 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
136 DSN: Write SPOOL :-dsn_envid NULL
137 DSN: Write SPOOL :-dsn_ret 0
138 DSN: Flags :0
139 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
140 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
141 Size of headers = sss
142 LOG: MAIN
143   <= CALLER@test.ex U=CALLER P=local S=sss
144 created log directory TESTSUITE/spool/log
145 search_tidyup called
146 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
147 Exim version x.yz ....
148 changed uid/gid: forcing real = effective
149   uid=uuuu gid=EXIM_GID pid=pppp
150 configuration file is TESTSUITE/test-config
151 trusted user
152 admin user
153 seeking password data for user "CALLER": cache not available
154 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
155 set_process_info: pppp delivering specified messages
156 set_process_info: pppp delivering 10HmaX-0005vi-00
157 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
158 reading spool file 10HmaX-0005vi-00-H
159 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
160 sender_local=1 ident=CALLER
161 Non-recipients:
162 Empty Tree
163 ---- End of tree ----
164 recipients_count=1
165 **** SPOOL_IN - No additional fields
166 body_linecount=0 message_linecount=7
167 DSN: set orcpt:   flags: 0
168 Delivery address list:
169   userx@test.ex 
170 locking TESTSUITE/spool/db/retry.lockfile
171 locked  TESTSUITE/spool/db/retry.lockfile
172 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
173 returned from EXIM_DBOPEN: (nil)
174 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
175 failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
176 no retry data available
177 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
178 Considering: userx@test.ex
179 test.ex in "! *.ex"? no (matched "! *.ex")
180 test.ex in "test.ex"? yes (matched "test.ex")
181 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
182 cached no match for +hold_domains
183 test.ex in hold_domains? no (end of list)
184 unique = userx@test.ex
185 no domain retry record
186 no address retry record
187 userx@test.ex: queued for routing
188 cached yes match for +not_queue_domains
189 test.ex in queue_domains? no (matched "!+not_queue_domains" - cached)
190 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
191 routing userx@test.ex
192 --------> r00 router <--------
193 local_part=userx domain=test.ex
194 checking domains
195 test.ex in "userx"? no (end of list)
196 test.ex in "+nocache"? no (end of list)
197 r00 router skipped: domains mismatch
198 --------> r01 router <--------
199 local_part=userx domain=test.ex
200 checking domains
201 test.ex in "userx"? no (end of list)
202 test.ex in "+nocache"? no (end of list)
203 r01 router skipped: domains mismatch
204 --------> r02 router <--------
205 local_part=userx domain=test.ex
206 checking domains
207 test.ex in "userx"? no (end of list)
208 test.ex in "+nocache"? no (end of list)
209 test.ex in "+nocache2"? no (end of list)
210 r02 router skipped: domains mismatch
211 --------> r03 router <--------
212 local_part=userx domain=test.ex
213 checking domains
214 test.ex in "userx"? no (end of list)
215 test.ex in "+nocache"? no (end of list)
216 test.ex in "+nocache2"? no (end of list)
217 r03 router skipped: domains mismatch
218 --------> r04 router <--------
219 local_part=userx domain=test.ex
220 checking domains
221 test.ex in "userx"? no (end of list)
222 test.ex in "+forcecache"? no (end of list)
223 r04 router skipped: domains mismatch
224 --------> r05 router <--------
225 local_part=userx domain=test.ex
226 checking domains
227 cached no match for +forcecache
228 cached lookup data = NULL
229 test.ex in "+forcecache"? no (end of list)
230 r05 router skipped: domains mismatch
231 --------> r1 router <--------
232 local_part=userx domain=test.ex
233 checking domains
234 test.ex in "never.ex"? no (end of list)
235 test.ex in "never1.ex"? no (end of list)
236 test.ex in "test.ex"? yes (matched "test.ex")
237 test.ex in "+never_domains : +n1_domains : ! +local_domains"? no (matched "! +local_domains")
238 r1 router skipped: domains mismatch
239 --------> r2 router <--------
240 local_part=userx domain=test.ex
241 checking domains
242 cached no match for +never_domains
243 cached lookup data = NULL
244 cached no match for +n1_domains
245 cached lookup data = NULL
246 test.ex in "<; never2.ex ; +n1_domains"? no (end of list)
247 cached yes match for +local_domains
248 cached lookup data = NULL
249 test.ex in "+never_domains : +n2_domains : !+local_domains"? no (matched "!+local_domains" - cached)
250 r2 router skipped: domains mismatch
251 --------> r3 router <--------
252 local_part=userx domain=test.ex
253 checking domains
254 cached yes match for +local_domains
255 cached lookup data = NULL
256 test.ex in "+local_domains"? yes (matched "+local_domains" - cached)
257 checking local_parts
258 userx in "userx"? yes (matched "userx")
259 calling r3 router
260 r3 router called for userx@test.ex
261   domain = test.ex
262 set transport t1
263 queued for t1 transport: local_part = userx
264 domain = test.ex
265   errors_to=NULL
266   domain_data=NULL localpart_data=NULL
267 routed by r3 router
268   envelope to: userx@test.ex
269   transport: t1
270 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
271 After routing:
272   Local deliveries:
273     userx@test.ex
274   Remote deliveries:
275   Failed addresses:
276   Deferred addresses:
277 search_tidyup called
278 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
279 --------> userx@test.ex <--------
280 locking TESTSUITE/spool/db/retry.lockfile
281 locked  TESTSUITE/spool/db/retry.lockfile
282 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
283 returned from EXIM_DBOPEN: (nil)
284 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
285 no retry data available
286 search_tidyup called
287 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
288   uid=CALLER_UID gid=CALLER_GID pid=pppp
289   home=NULL current=/
290 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
291 appendfile transport entered
292 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
293   file=/dev/null format=unix
294   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
295   message_suffix=\n
296   maildir_use_size_file=no
297   locking by lockfile fcntl 
298 search_tidyup called
299 journalling userx@test.ex
300 t1 transport returned OK for userx@test.ex
301 post-process userx@test.ex (0)
302 userx@test.ex delivered
303 LOG: MAIN
304   => userx <userx@test.ex> R=r3 T=t1
305 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
306 changed uid/gid: post-delivery tidying
307   uid=EXIM_UID gid=EXIM_GID pid=pppp
308 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
309 Processing retry items
310 Succeeded addresses:
311  userx@test.ex: no retry items
312 Failed addresses:
313 Deferred addresses:
314 end of retry processing
315 DSN: processing router : r3
316 DSN: processing successful delivery address: userx@test.ex
317 DSN: Sender_address: CALLER@test.ex
318 DSN: orcpt: NULL  flags: 0
319 DSN: envid: NULL  ret: 0
320 DSN: Final recipient: userx@test.ex
321 DSN: Remote SMTP server supports DSN: 0
322 DSN: not sending DSN success message
323 LOG: MAIN
324   Completed
325 end delivery of 10HmaX-0005vi-00
326 search_tidyup called
327 search_tidyup called
328 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
329 search_tidyup called
330 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
331 Exim version x.yz ....
332 changed uid/gid: forcing real = effective
333   uid=uuuu gid=CALLER_GID pid=pppp
334 configuration file is TESTSUITE/test-config
335 admin user
336 changed uid/gid: privilege not needed
337   uid=EXIM_UID gid=EXIM_GID pid=pppp
338 seeking password data for user "CALLER": cache not available
339 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
340 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
341 sender address = CALLER@test.ex
342 sender_fullhost = [127.0.0.1]
343 sender_rcvhost = [127.0.0.1]
344 host in hosts_connection_nolog? no (option unset)
345 LOG: smtp_connection MAIN
346   SMTP connection from [127.0.0.1]
347 host in host_lookup? no (option unset)
348 set_process_info: pppp handling incoming connection from [127.0.0.1]
349 host in host_reject_connection? no (option unset)
350 host in sender_unqualified_hosts? no (option unset)
351 host in recipient_unqualified_hosts? no (option unset)
352 host in helo_verify_hosts? no (option unset)
353 host in helo_try_verify_hosts? no (option unset)
354 host in helo_accept_junk_hosts? no (option unset)
355 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
356 smtp_setup_msg entered
357 SMTP<< HELO test
358 test in helo_lookup_domains? no (end of list)
359 sender_fullhost = (test) [127.0.0.1]
360 sender_rcvhost = [127.0.0.1] (helo=test)
361 set_process_info: pppp handling incoming connection from (test) [127.0.0.1]
362 SMTP>> 250 myhost.test.ex Hello test [127.0.0.1]
363 SMTP<< MAIL FROM:<test@test.ex>
364 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
365 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
366 SMTP>> 250 OK
367 SMTP<< RCPT TO:<error@test.ex>
368 test.ex in "! *.ex"? no (matched "! *.ex")
369 test.ex in "test.ex"? yes (matched "test.ex")
370 test.ex in percent_hack_domains? yes (matched "+not_queue_domains")
371 processing "accept"
372 check verify = recipient
373 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
374 Verifying error@test.ex
375 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
376 Considering error@test.ex
377 cached no match for +hold_domains
378 cached yes match for +not_queue_domains
379 test.ex in percent_hack_domains? yes (matched "+not_queue_domains" - cached)
380 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
381 routing error@test.ex
382 --------> r0f router <--------
383 local_part=error domain=test.ex
384 checking domains
385 LOG: MAIN PANIC
386   unknown named domain list "+no_such_list"
387 domains check lookup or other defer
388 ----------- end verify ------------
389 accept: condition test deferred in inline ACL
390 SMTP>> 451 Temporary local problem - please try later
391 LOG: MAIN REJECT
392   H=(test) [127.0.0.1] F=<test@test.ex> temporarily rejected RCPT <error@test.ex>: domains check lookup or other defer
393 SMTP<< QUIT
394 SMTP>> 221 myhost.test.ex closing connection
395 LOG: smtp_connection MAIN
396   SMTP connection from (test) [127.0.0.1] closed by QUIT
397 search_tidyup called
398 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>