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