Logging: Fix logging of errors under PIPELINING
[users/jgh/exim.git] / test / stderr / 0361
1 LOG: MAIN
2   <= CALLER@test.ex U=CALLER P=local S=sss
3 delivering 10HmaX-0005vi-00
4 LOG: MAIN
5   *> miles <miles@thishost.test.ex> R=r4 T=t2
6 LOG: MAIN
7   Completed
8 Exim version x.yz ....
9 changed uid/gid: forcing real = effective
10   uid=uuuu gid=CALLER_GID pid=pppp
11 configuration file is TESTSUITE/test-config
12 admin user
13 changed uid/gid: privilege not needed
14   uid=EXIM_UID gid=EXIM_GID pid=pppp
15 DSN: r1 propagating DSN
16 DSN: r2 propagating DSN
17 DSN: r3 propagating DSN
18 DSN: r4 propagating DSN
19 seeking password data for user "CALLER": cache not available
20 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
21 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
22 sender address = CALLER@test.ex
23 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
24 Sender: CALLER@test.ex
25 Recipients:
26   kilos@recurse.test.ex
27 search_tidyup called
28 >>Headers received:
29
30 rewrite_one_header: type=F:
31   From: CALLER_NAME <CALLER@test.ex>
32 search_tidyup called
33 >>Headers after rewriting and local additions:
34 I Message-Id: <E10HmaY-0005vi-00@the.local.host.name>
35 F From: CALLER_NAME <CALLER@test.ex>
36   Date: Tue, 2 Mar 1999 09:44:33 +0000
37
38 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
39 Data file written for message 10HmaY-0005vi-00
40 >>Generated Received: header line
41 P Received: from CALLER by the.local.host.name with local (Exim x.yz)
42         (envelope-from <CALLER@test.ex>)
43         id 10HmaY-0005vi-00
44         for kilos@recurse.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
45 calling local_scan(); timeout=300
46 local_scan() returned 0 NULL
47 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
48 DSN: Write SPOOL :-dsn_envid NULL
49 DSN: Write SPOOL :-dsn_ret 0
50 DSN: Flags :0
51 DSN: **** SPOOL_OUT - address: |kilos@recurse.test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
52 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
53 Size of headers = sss
54 LOG: MAIN
55   <= CALLER@test.ex U=CALLER P=local S=sss
56 search_tidyup called
57 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -N -odi -Mc 10HmaY-0005vi-00
58 Exim version x.yz ....
59 changed uid/gid: forcing real = effective
60   uid=uuuu gid=EXIM_GID pid=pppp
61 configuration file is TESTSUITE/test-config
62 trusted user
63 admin user
64 DSN: r1 propagating DSN
65 DSN: r2 propagating DSN
66 DSN: r3 propagating DSN
67 DSN: r4 propagating DSN
68 seeking password data for user "CALLER": cache not available
69 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
70 set_process_info: pppp delivering specified messages
71 set_process_info: pppp delivering 10HmaY-0005vi-00
72 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
73 reading spool file 10HmaY-0005vi-00-H
74 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
75 sender_local=1 ident=CALLER
76 Non-recipients:
77 Empty Tree
78 ---- End of tree ----
79 recipients_count=1
80 **** SPOOL_IN - No additional fields
81 body_linecount=0 message_linecount=7
82 DSN: set orcpt: NULL  flags: 0
83 Delivery address list:
84   kilos@recurse.test.ex 
85 locking TESTSUITE/spool/db/retry.lockfile
86 locked TESTSUITE/spool/db/retry.lockfile
87 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
88 returned from EXIM_DBOPEN
89 no retry data available
90 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
91 Considering: kilos@recurse.test.ex
92 unique = kilos@recurse.test.ex
93 no domain retry record
94 no address retry record
95 kilos@recurse.test.ex: queued for routing
96 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
97 routing kilos@recurse.test.ex
98 --------> r1 router <--------
99 local_part=kilos domain=recurse.test.ex
100 checking domains
101 recurse.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? yes (end of list)
102 calling r1 router
103 r1 router called for kilos@recurse.test.ex
104   domain = recurse.test.ex
105 DNS lookup of recurse.test.ex (MX) using fakens
106 DNS lookup of recurse.test.ex (MX) gave HOST_NOT_FOUND
107 returning DNS_NOMATCH
108 r1 router widened recurse.test.ex to recurse.test.ex.test.ex
109 DNS lookup of recurse.test.ex.test.ex (MX) using fakens
110 DNS lookup of recurse.test.ex.test.ex (MX) gave NO_DATA
111 returning DNS_NODATA
112 DNS lookup of recurse.test.ex.test.ex (A) using fakens
113 DNS lookup of recurse.test.ex.test.ex (A) succeeded
114 fully qualified name = recurse.test.ex.test.ex
115 recurse.test.ex.test.ex V4NET.99.0.2 mx=-1 sort=xx 
116 domain changed to recurse.test.ex.test.ex
117 rewriting header lines
118 rewrite_one_header: type=F:
119   From: CALLER_NAME <CALLER@test.ex>
120 re-routed to kilos@recurse.test.ex.test.ex
121 locking TESTSUITE/spool/db/retry.lockfile
122 locked TESTSUITE/spool/db/retry.lockfile
123 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
124 returned from EXIM_DBOPEN
125 no retry data available
126 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
127 Considering: kilos@recurse.test.ex.test.ex
128 unique = kilos@recurse.test.ex.test.ex
129 no domain retry record
130 no address retry record
131 kilos@recurse.test.ex.test.ex: queued for routing
132 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
133 routing kilos@recurse.test.ex.test.ex
134 --------> r1 router <--------
135 local_part=kilos domain=recurse.test.ex.test.ex
136 checking domains
137 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
138 r1 router skipped: domains mismatch
139 --------> r2 router <--------
140 local_part=kilos domain=recurse.test.ex.test.ex
141 checking local_parts
142 kilos in "miles"? no (end of list)
143 r2 router skipped: local_parts mismatch
144 --------> r3 router <--------
145 local_part=kilos domain=recurse.test.ex.test.ex
146 checking local_parts
147 kilos in "kilos"? yes (matched "kilos")
148 calling r3 router
149 rda_interpret (string): $local_part@$domain
150 expanded: kilos@recurse.test.ex.test.ex
151 file is not a filter file
152 parse_forward_list: kilos@recurse.test.ex.test.ex
153 extract item: kilos@recurse.test.ex.test.ex
154 r3 router generated kilos@recurse.test.ex.test.ex
155   errors_to=NULL transport=NULL
156   uid=unset gid=unset home=NULL
157 routed by r3 router
158   envelope to: kilos@recurse.test.ex.test.ex
159   transport: <none>
160 locking TESTSUITE/spool/db/retry.lockfile
161 locked TESTSUITE/spool/db/retry.lockfile
162 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
163 returned from EXIM_DBOPEN
164 no retry data available
165 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
166 Considering: kilos@recurse.test.ex.test.ex
167 unique = \0\kilos@recurse.test.ex.test.ex
168 no domain retry record
169 no address retry record
170 kilos@recurse.test.ex.test.ex: queued for routing
171 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
172 routing kilos@recurse.test.ex.test.ex
173 --------> r1 router <--------
174 local_part=kilos domain=recurse.test.ex.test.ex
175 checking domains
176 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
177 r1 router skipped: domains mismatch
178 --------> r2 router <--------
179 local_part=kilos domain=recurse.test.ex.test.ex
180 checking local_parts
181 kilos in "miles"? no (end of list)
182 r2 router skipped: local_parts mismatch
183 --------> r3 router <--------
184 r3 router skipped: previously routed kilos@recurse.test.ex.test.ex
185 --------> r4 router <--------
186 local_part=kilos domain=recurse.test.ex.test.ex
187 calling r4 router
188 r4 router called for kilos@recurse.test.ex.test.ex
189   domain = recurse.test.ex.test.ex
190 set transport t2
191 queued for t2 transport: local_part = kilos
192 domain = recurse.test.ex.test.ex
193   errors_to=NULL
194   domain_data=NULL localpart_data=NULL
195 routed by r4 router
196   envelope to: kilos@recurse.test.ex.test.ex
197   transport: t2
198 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
199 After routing:
200   Local deliveries:
201     kilos@recurse.test.ex.test.ex
202   Remote deliveries:
203   Failed addresses:
204   Deferred addresses:
205 search_tidyup called
206 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
207 --------> kilos@recurse.test.ex.test.ex <--------
208 locking TESTSUITE/spool/db/retry.lockfile
209 locked TESTSUITE/spool/db/retry.lockfile
210 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
211 returned from EXIM_DBOPEN
212 no retry data available
213 search_tidyup called
214 changed uid/gid: local delivery to kilos <kilos@recurse.test.ex.test.ex> transport=t2
215   uid=CALLER_UID gid=CALLER_GID pid=pppp
216   home=NULL current=/
217 set_process_info: pppp delivering 10HmaY-0005vi-00 to kilos using t2
218 appendfile transport entered
219 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
220   file=/dev/null format=unix
221   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
222   message_suffix=\n
223   maildir_use_size_file=no
224   locking by lockfile fcntl 
225 *** delivery by t2 transport bypassed by -N option
226 search_tidyup called
227 journalling kilos@recurse.test.ex.test.ex/t2
228 t2 transport returned OK for kilos@recurse.test.ex.test.ex
229 post-process kilos@recurse.test.ex.test.ex (0)
230 kilos@recurse.test.ex.test.ex delivered
231 kilos@recurse.test.ex.test.ex: children all complete
232 kilos@recurse.test.ex: children all complete
233 LOG: MAIN
234   *> kilos <kilos@recurse.test.ex> R=r4 T=t2
235 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
236 changed uid/gid: post-delivery tidying
237   uid=EXIM_UID gid=EXIM_GID pid=pppp
238 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
239 DSN: processing router : r4
240 DSN: processing successful delivery address: kilos@recurse.test.ex.test.ex
241 DSN: Sender_address: CALLER@test.ex
242 DSN: orcpt: NULL  flags: 0
243 DSN: envid: NULL  ret: 0
244 DSN: Final recipient: kilos@recurse.test.ex.test.ex
245 DSN: Remote SMTP server supports DSN: 0
246 DSN: not sending DSN success message
247 DSN: processing router : r3
248 DSN: processing successful delivery address: kilos@recurse.test.ex.test.ex
249 DSN: Sender_address: CALLER@test.ex
250 DSN: orcpt: NULL  flags: 0
251 DSN: envid: NULL  ret: 0
252 DSN: Final recipient: kilos@recurse.test.ex.test.ex
253 DSN: Remote SMTP server supports DSN: 0
254 DSN: not sending DSN success message
255 LOG: MAIN
256   Completed
257 end delivery of 10HmaY-0005vi-00
258 search_tidyup called
259 search_tidyup called
260 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
261 search_tidyup called
262 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>