exit-time debug
[exim.git] / test / stderr / 0374
... / ...
CommitLineData
1Exim version x.yz ....
2configuration file is TESTSUITE/test-config
3admin user
4LOG: MAIN
5 <= CALLER@myhost.test.ex U=CALLER P=local S=sss
6created log directory TESTSUITE/spool/log
7fresh-exec forking for local-accept-delivery
8postfork: local-accept-delivery
9fresh-exec forked for local-accept-delivery: npppp
10Exim version x.yz ....
11configuration file is TESTSUITE/test-config
12trusted user
13admin user
14dropping to exim gid; retaining priv uid
15locking TESTSUITE/spool/db/retry.lockfile
16no retry data available
17>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
18Considering: a1@myhost.test.ex
19unique = a1@myhost.test.ex
20a1@myhost.test.ex: queued for routing
21>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
22Considering: b1@myhost.test.ex
23unique = b1@myhost.test.ex
24b1@myhost.test.ex: queued for routing
25>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
26Considering: c1@myhost.test.ex
27unique = c1@myhost.test.ex
28c1@myhost.test.ex: queued for routing
29>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
30Considering: d1@myhost.test.ex
31unique = d1@myhost.test.ex
32d1@myhost.test.ex: queued for routing
33>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
34Considering: d2@myhost.test.ex
35unique = d2@myhost.test.ex
36d2@myhost.test.ex: queued for routing
37>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
38Considering: d3@myhost.test.ex
39unique = d3@myhost.test.ex
40d3@myhost.test.ex: queued for routing
41>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
42routing d3@myhost.test.ex
43--------> u1 router <--------
44local_part=d3 domain=myhost.test.ex
45checking local_parts
46u1 router skipped: local_parts mismatch
47--------> ut2 router <--------
48local_part=d3 domain=myhost.test.ex
49checking local_parts
50ut2 router skipped: local_parts mismatch
51--------> ut3 router <--------
52local_part=d3 domain=myhost.test.ex
53checking local_parts
54ut3 router skipped: local_parts mismatch
55--------> ut4 router <--------
56local_part=d3 domain=myhost.test.ex
57checking local_parts
58calling ut4 router
59ut4 router called for d3@myhost.test.ex
60 domain = myhost.test.ex
61set transport ut4
62queued for ut4 transport: local_part = d3
63domain = myhost.test.ex
64 errors_to=NULL
65 domain_data=NULL localpart_data=NULL
66routed by ut4 router (unseen)
67 envelope to: d3@myhost.test.ex
68 transport: ut4
69"unseen" set: replicated d3@myhost.test.ex
70>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
71routing d2@myhost.test.ex
72--------> u1 router <--------
73local_part=d2 domain=myhost.test.ex
74checking local_parts
75u1 router skipped: local_parts mismatch
76--------> ut2 router <--------
77local_part=d2 domain=myhost.test.ex
78checking local_parts
79ut2 router skipped: local_parts mismatch
80--------> ut3 router <--------
81local_part=d2 domain=myhost.test.ex
82checking local_parts
83ut3 router skipped: local_parts mismatch
84--------> ut4 router <--------
85local_part=d2 domain=myhost.test.ex
86checking local_parts
87calling ut4 router
88ut4 router called for d2@myhost.test.ex
89 domain = myhost.test.ex
90queued for ut4 transport: local_part = d2
91domain = myhost.test.ex
92 errors_to=NULL
93 domain_data=NULL localpart_data=NULL
94routed by ut4 router (unseen)
95 envelope to: d2@myhost.test.ex
96 transport: ut4
97"unseen" set: replicated d2@myhost.test.ex
98>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
99routing d1@myhost.test.ex
100--------> u1 router <--------
101local_part=d1 domain=myhost.test.ex
102checking local_parts
103u1 router skipped: local_parts mismatch
104--------> ut2 router <--------
105local_part=d1 domain=myhost.test.ex
106checking local_parts
107ut2 router skipped: local_parts mismatch
108--------> ut3 router <--------
109local_part=d1 domain=myhost.test.ex
110checking local_parts
111ut3 router skipped: local_parts mismatch
112--------> ut4 router <--------
113local_part=d1 domain=myhost.test.ex
114checking local_parts
115calling ut4 router
116ut4 router called for d1@myhost.test.ex
117 domain = myhost.test.ex
118queued for ut4 transport: local_part = d1
119domain = myhost.test.ex
120 errors_to=NULL
121 domain_data=NULL localpart_data=NULL
122routed by ut4 router (unseen)
123 envelope to: d1@myhost.test.ex
124 transport: ut4
125"unseen" set: replicated d1@myhost.test.ex
126>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
127routing c1@myhost.test.ex
128--------> u1 router <--------
129local_part=c1 domain=myhost.test.ex
130checking local_parts
131u1 router skipped: local_parts mismatch
132--------> ut2 router <--------
133local_part=c1 domain=myhost.test.ex
134checking local_parts
135ut2 router skipped: local_parts mismatch
136--------> ut3 router <--------
137local_part=c1 domain=myhost.test.ex
138checking local_parts
139calling ut3 router
140ut3 router called for c1@myhost.test.ex
141 domain = myhost.test.ex
142set transport ut3
143queued for ut3 transport: local_part = c1
144domain = myhost.test.ex
145 errors_to=NULL
146 domain_data=NULL localpart_data=NULL
147routed by ut3 router (unseen)
148 envelope to: c1@myhost.test.ex
149 transport: ut3
150"unseen" set: replicated c1@myhost.test.ex
151>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
152routing b1@myhost.test.ex
153--------> u1 router <--------
154local_part=b1 domain=myhost.test.ex
155checking local_parts
156u1 router skipped: local_parts mismatch
157--------> ut2 router <--------
158local_part=b1 domain=myhost.test.ex
159checking local_parts
160calling ut2 router
161ut2 router called for b1@myhost.test.ex
162 domain = myhost.test.ex
163set transport ut2
164queued for ut2 transport: local_part = b1
165domain = myhost.test.ex
166 errors_to=NULL
167 domain_data=NULL localpart_data=NULL
168routed by ut2 router (unseen)
169 envelope to: b1@myhost.test.ex
170 transport: ut2
171"unseen" set: replicated b1@myhost.test.ex
172>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
173routing a1@myhost.test.ex
174--------> u1 router <--------
175local_part=a1 domain=myhost.test.ex
176checking local_parts
177calling u1 router
178u1 router called for a1@myhost.test.ex
179 domain = myhost.test.ex
180set transport ut1
181queued for ut1 transport: local_part = a1
182domain = myhost.test.ex
183 errors_to=NULL
184 domain_data=NULL localpart_data=NULL
185routed by u1 router (unseen)
186 envelope to: a1@myhost.test.ex
187 transport: ut1
188"unseen" set: replicated a1@myhost.test.ex
189locking TESTSUITE/spool/db/retry.lockfile
190no retry data available
191>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
192Considering: a1@myhost.test.ex
193unique = \0\a1@myhost.test.ex
194a1@myhost.test.ex: queued for routing
195>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
196Considering: b1@myhost.test.ex
197unique = \0\b1@myhost.test.ex
198b1@myhost.test.ex: queued for routing
199>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
200Considering: c1@myhost.test.ex
201unique = \0\c1@myhost.test.ex
202c1@myhost.test.ex: queued for routing
203>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
204Considering: d1@myhost.test.ex
205unique = \0\d1@myhost.test.ex
206d1@myhost.test.ex: queued for routing
207>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
208Considering: d2@myhost.test.ex
209unique = \0\d2@myhost.test.ex
210d2@myhost.test.ex: queued for routing
211>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
212Considering: d3@myhost.test.ex
213unique = \0\d3@myhost.test.ex
214d3@myhost.test.ex: queued for routing
215>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
216routing d3@myhost.test.ex
217--------> real router <--------
218local_part=d3 domain=myhost.test.ex
219calling real router
220real router called for d3@myhost.test.ex
221 domain = myhost.test.ex
222set transport real
223queued for real transport: local_part = d3
224domain = myhost.test.ex
225 errors_to=NULL
226 domain_data=NULL localpart_data=NULL
227routed by real router
228 envelope to: d3@myhost.test.ex
229 transport: real
230>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
231routing d2@myhost.test.ex
232--------> real router <--------
233local_part=d2 domain=myhost.test.ex
234calling real router
235real router called for d2@myhost.test.ex
236 domain = myhost.test.ex
237queued for real transport: local_part = d2
238domain = myhost.test.ex
239 errors_to=NULL
240 domain_data=NULL localpart_data=NULL
241routed by real router
242 envelope to: d2@myhost.test.ex
243 transport: real
244>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
245routing d1@myhost.test.ex
246--------> real router <--------
247local_part=d1 domain=myhost.test.ex
248calling real router
249real router called for d1@myhost.test.ex
250 domain = myhost.test.ex
251queued for real transport: local_part = d1
252domain = myhost.test.ex
253 errors_to=NULL
254 domain_data=NULL localpart_data=NULL
255routed by real router
256 envelope to: d1@myhost.test.ex
257 transport: real
258>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
259routing c1@myhost.test.ex
260--------> ut4 router <--------
261local_part=c1 domain=myhost.test.ex
262checking local_parts
263ut4 router skipped: local_parts mismatch
264--------> real router <--------
265local_part=c1 domain=myhost.test.ex
266calling real router
267real router called for c1@myhost.test.ex
268 domain = myhost.test.ex
269queued for real transport: local_part = c1
270domain = myhost.test.ex
271 errors_to=NULL
272 domain_data=NULL localpart_data=NULL
273routed by real router
274 envelope to: c1@myhost.test.ex
275 transport: real
276>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
277routing b1@myhost.test.ex
278--------> ut3 router <--------
279local_part=b1 domain=myhost.test.ex
280checking local_parts
281ut3 router skipped: local_parts mismatch
282--------> ut4 router <--------
283local_part=b1 domain=myhost.test.ex
284checking local_parts
285ut4 router skipped: local_parts mismatch
286--------> real router <--------
287local_part=b1 domain=myhost.test.ex
288calling real router
289real router called for b1@myhost.test.ex
290 domain = myhost.test.ex
291queued for real transport: local_part = b1
292domain = myhost.test.ex
293 errors_to=NULL
294 domain_data=NULL localpart_data=NULL
295routed by real router
296 envelope to: b1@myhost.test.ex
297 transport: real
298>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
299routing a1@myhost.test.ex
300--------> ut2 router <--------
301local_part=a1 domain=myhost.test.ex
302checking local_parts
303ut2 router skipped: local_parts mismatch
304--------> ut3 router <--------
305local_part=a1 domain=myhost.test.ex
306checking local_parts
307ut3 router skipped: local_parts mismatch
308--------> ut4 router <--------
309local_part=a1 domain=myhost.test.ex
310checking local_parts
311ut4 router skipped: local_parts mismatch
312--------> real router <--------
313local_part=a1 domain=myhost.test.ex
314calling real router
315real router called for a1@myhost.test.ex
316 domain = myhost.test.ex
317queued for real transport: local_part = a1
318domain = myhost.test.ex
319 errors_to=NULL
320 domain_data=NULL localpart_data=NULL
321routed by real router
322 envelope to: a1@myhost.test.ex
323 transport: real
324>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
325After routing:
326 Local deliveries:
327 a1@myhost.test.ex
328 b1@myhost.test.ex
329 c1@myhost.test.ex
330 d1@myhost.test.ex
331 d2@myhost.test.ex
332 d3@myhost.test.ex
333 a1@myhost.test.ex
334 b1@myhost.test.ex
335 c1@myhost.test.ex
336 Remote deliveries:
337 d1@myhost.test.ex
338 d2@myhost.test.ex
339 d3@myhost.test.ex
340 Failed addresses:
341 Deferred addresses:
342locking TESTSUITE/spool/db/retry.lockfile
343local-accept-delivery forking for delivery-local
344postfork: delivery-local
345local-accept-delivery forked for delivery-local: npppp
346LOG: MAIN
347 => a1 <a1@myhost.test.ex> R=real T=real
348locking TESTSUITE/spool/db/retry.lockfile
349local-accept-delivery forking for delivery-local
350postfork: delivery-local
351local-accept-delivery forked for delivery-local: npppp
352LOG: MAIN
353 => b1 <b1@myhost.test.ex> R=real T=real
354locking TESTSUITE/spool/db/retry.lockfile
355local-accept-delivery forking for delivery-local
356postfork: delivery-local
357local-accept-delivery forked for delivery-local: npppp
358LOG: MAIN
359 => c1 <c1@myhost.test.ex> R=real T=real
360locking TESTSUITE/spool/db/retry.lockfile
361local-accept-delivery forking for delivery-local
362postfork: delivery-local
363local-accept-delivery forked for delivery-local: npppp
364LOG: MAIN
365 => d1 <d1@myhost.test.ex> R=real T=real
366locking TESTSUITE/spool/db/retry.lockfile
367local-accept-delivery forking for delivery-local
368postfork: delivery-local
369local-accept-delivery forked for delivery-local: npppp
370LOG: MAIN
371 => d2 <d2@myhost.test.ex> R=real T=real
372locking TESTSUITE/spool/db/retry.lockfile
373local-accept-delivery forking for delivery-local
374postfork: delivery-local
375local-accept-delivery forked for delivery-local: npppp
376LOG: MAIN
377 => d3 <d3@myhost.test.ex> R=real T=real
378locking TESTSUITE/spool/db/retry.lockfile
379local-accept-delivery forking for delivery-local
380postfork: delivery-local
381local-accept-delivery forked for delivery-local: npppp
382LOG: MAIN
383 => a1 <a1@myhost.test.ex> R=u1 T=ut1
384locking TESTSUITE/spool/db/retry.lockfile
385local-accept-delivery forking for delivery-local
386postfork: delivery-local
387delivery-local forking for pipe-tpt-cmd
388postfork: pipe-tpt-cmd
389local-accept-delivery forked for delivery-local: npppp
390delivery-local forked for pipe-tpt-cmd: npppp
391delivery-local forking for pipe-tpt-output
392postfork: pipe-tpt-output
393delivery-local forked for pipe-tpt-output: npppp
394transport error EPIPE ignored
395LOG: MAIN
396 ** b1@myhost.test.ex R=ut2 T=ut2: Child process of ut2 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
397locking TESTSUITE/spool/db/retry.lockfile
398local-accept-delivery forking for delivery-local
399postfork: delivery-local
400delivery-local forking for pipe-tpt-cmd
401postfork: pipe-tpt-cmd
402local-accept-delivery forked for delivery-local: npppp
403delivery-local forked for pipe-tpt-cmd: npppp
404delivery-local forking for pipe-tpt-output
405postfork: pipe-tpt-output
406delivery-local forked for pipe-tpt-output: npppp
407transport error EPIPE ignored
408LOG: MAIN
409 == c1@myhost.test.ex R=ut3 T=ut3 defer (0): Child process of ut3 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
410local-accept-delivery forking for transport
411postfork: transport
412locking TESTSUITE/spool/db/retry.lockfile
413locking TESTSUITE/spool/db/wait-ut4.lockfile
414local-accept-delivery forked for transport: npppp
415LOG: MAIN
416 => d1@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1] C="250 OK"
417local-accept-delivery forking for transport
418postfork: transport
419locking TESTSUITE/spool/db/retry.lockfile
420local-accept-delivery forked for transport: npppp
421LOG: MAIN
422 == d2@myhost.test.ex R=ut4 T=ut4 defer (-44) H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<d2@myhost.test.ex>: 450 soft error
423local-accept-delivery forking for transport
424postfork: transport
425locking TESTSUITE/spool/db/retry.lockfile
426locking TESTSUITE/spool/db/wait-ut4.lockfile
427local-accept-delivery forked for transport: npppp
428LOG: MAIN
429 ** d3@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<d3@myhost.test.ex>: 550 hard error
430locking TESTSUITE/spool/db/retry.lockfile
431local-accept-delivery forking for bounce-message
432postfork: bounce-message
433local-accept-delivery forked for bounce-message: npppp
434Exim version x.yz ....
435configuration file is TESTSUITE/test-config
436trusted user
437admin user
438LOG: MAIN
439 <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
440bounce-message forking for local-accept-delivery
441postfork: local-accept-delivery
442bounce-message forked for local-accept-delivery: npppp
443Exim version x.yz ....
444configuration file is TESTSUITE/test-config
445trusted user
446admin user
447dropping to exim gid; retaining priv uid
448locking TESTSUITE/spool/db/retry.lockfile
449>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
450Considering: CALLER@myhost.test.ex
451unique = CALLER@myhost.test.ex
452CALLER@myhost.test.ex: queued for routing
453>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
454routing CALLER@myhost.test.ex
455--------> u1 router <--------
456local_part=CALLER domain=myhost.test.ex
457checking local_parts
458u1 router skipped: local_parts mismatch
459--------> ut2 router <--------
460local_part=CALLER domain=myhost.test.ex
461checking local_parts
462ut2 router skipped: local_parts mismatch
463--------> ut3 router <--------
464local_part=CALLER domain=myhost.test.ex
465checking local_parts
466ut3 router skipped: local_parts mismatch
467--------> ut4 router <--------
468local_part=CALLER domain=myhost.test.ex
469checking local_parts
470ut4 router skipped: local_parts mismatch
471--------> real router <--------
472local_part=CALLER domain=myhost.test.ex
473calling real router
474real router called for CALLER@myhost.test.ex
475 domain = myhost.test.ex
476set transport real
477queued for real transport: local_part = CALLER
478domain = myhost.test.ex
479 errors_to=NULL
480 domain_data=NULL localpart_data=NULL
481routed by real router
482 envelope to: CALLER@myhost.test.ex
483 transport: real
484>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
485After routing:
486 Local deliveries:
487 CALLER@myhost.test.ex
488 Remote deliveries:
489 Failed addresses:
490 Deferred addresses:
491locking TESTSUITE/spool/db/retry.lockfile
492local-accept-delivery forking for delivery-local
493postfork: delivery-local
494local-accept-delivery forked for delivery-local: npppp
495LOG: MAIN
496 => CALLER <CALLER@myhost.test.ex> R=real T=real
497LOG: MAIN
498 Completed
499>>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
500>>>>>>>>>>>>>>>> Exim pid=pppp (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>>
501>>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
502>>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>