Hintsbd: fix locking
[exim.git] / test / stderr / 0377
1 Exim version x.yz ....
2 Hints DB:
3 configuration file is TESTSUITE/test-config
4 admin user
5 LOG: MAIN
6   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
7 created log directory TESTSUITE/spool/log
8 Exim version x.yz ....
9 Hints DB:
10 configuration file is TESTSUITE/test-config
11 trusted user
12 admin user
13 dropping to exim gid; retaining priv uid
14 locking TESTSUITE/spool/db/retry.lockfile
15 no retry data available
16 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17 Considering: aaaa@myhost.test.ex
18 unique = aaaa@myhost.test.ex
19 aaaa@myhost.test.ex: queued for routing
20 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
21 Considering: bbbb@myhost.test.ex
22 unique = bbbb@myhost.test.ex
23 bbbb@myhost.test.ex: queued for routing
24 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
25 Considering: cccc@myhost.test.ex
26 unique = cccc@myhost.test.ex
27 cccc@myhost.test.ex: queued for routing
28 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
29 routing cccc@myhost.test.ex
30 --------> defer router <--------
31 local_part=cccc domain=myhost.test.ex
32 checking local_parts
33 cccc in local_parts? no (end of list)
34 defer router skipped: local_parts mismatch
35 --------> unseen_aaaa router <--------
36 local_part=cccc domain=myhost.test.ex
37 checking local_parts
38 cccc in local_parts? no (end of list)
39 unseen_aaaa router skipped: local_parts mismatch
40 --------> seen_aaaa router <--------
41 local_part=cccc domain=myhost.test.ex
42 checking local_parts
43 cccc in local_parts? no (end of list)
44 seen_aaaa router skipped: local_parts mismatch
45 --------> bbbb router <--------
46 local_part=cccc domain=myhost.test.ex
47 checking local_parts
48 cccc in local_parts? no (end of list)
49 bbbb router skipped: local_parts mismatch
50 --------> bbbb_0 router <--------
51 local_part=cccc domain=myhost.test.ex
52 checking local_parts
53 cccc in local_parts? no (end of list)
54 bbbb_0 router skipped: local_parts mismatch
55 --------> cccc_2nd_time router <--------
56 local_part=cccc domain=myhost.test.ex
57 checking local_parts
58 checking "condition" "${if first_delivery{no}{yes}}"...
59 cccc_2nd_time router skipped: condition failure
60 --------> cccc_redirect router <--------
61 local_part=cccc domain=myhost.test.ex
62 checking local_parts
63 calling cccc_redirect router
64 rda_interpret (string): 'cccc@$domain, defer_cccc@$domain'
65 expanded: 'cccc@myhost.test.ex, defer_cccc@myhost.test.ex' (tainted)
66 file is not a filter file
67 parse_forward_list: cccc@myhost.test.ex, defer_cccc@myhost.test.ex
68 extract item: cccc@myhost.test.ex
69 extract item: defer_cccc@myhost.test.ex
70 cccc_redirect router generated defer_cccc@myhost.test.ex
71   errors_to=NULL transport=NULL
72   uid=unset gid=unset home=NULL
73 cccc_redirect router generated cccc@myhost.test.ex
74   errors_to=NULL transport=NULL
75   uid=unset gid=unset home=NULL
76 routed by cccc_redirect router
77   envelope to: cccc@myhost.test.ex
78   transport: <none>
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 routing bbbb@myhost.test.ex
81 --------> defer router <--------
82 local_part=bbbb domain=myhost.test.ex
83 checking local_parts
84 bbbb in local_parts? no (end of list)
85 defer router skipped: local_parts mismatch
86 --------> unseen_aaaa router <--------
87 local_part=bbbb domain=myhost.test.ex
88 checking local_parts
89 bbbb in local_parts? no (end of list)
90 unseen_aaaa router skipped: local_parts mismatch
91 --------> seen_aaaa router <--------
92 local_part=bbbb domain=myhost.test.ex
93 checking local_parts
94 bbbb in local_parts? no (end of list)
95 seen_aaaa router skipped: local_parts mismatch
96 --------> bbbb router <--------
97 local_part=bbbb domain=myhost.test.ex
98 checking local_parts
99 checking "condition" "${if first_delivery{yes}{no}}"...
100 calling bbbb router
101 rda_interpret (string): 'bbbb@$domain, defer_bbbb@$domain'
102 expanded: 'bbbb@myhost.test.ex, defer_bbbb@myhost.test.ex' (tainted)
103 file is not a filter file
104 parse_forward_list: bbbb@myhost.test.ex, defer_bbbb@myhost.test.ex
105 extract item: bbbb@myhost.test.ex
106 extract item: defer_bbbb@myhost.test.ex
107 bbbb router generated defer_bbbb@myhost.test.ex
108   errors_to=NULL transport=NULL
109   uid=unset gid=unset home=NULL
110 bbbb router generated bbbb@myhost.test.ex
111   errors_to=NULL transport=NULL
112   uid=unset gid=unset home=NULL
113 routed by bbbb router
114   envelope to: bbbb@myhost.test.ex
115   transport: <none>
116 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
117 routing aaaa@myhost.test.ex
118 --------> defer router <--------
119 local_part=aaaa domain=myhost.test.ex
120 checking local_parts
121 aaaa in local_parts? no (end of list)
122 defer router skipped: local_parts mismatch
123 --------> unseen_aaaa router <--------
124 local_part=aaaa domain=myhost.test.ex
125 checking local_parts
126 checking "condition" "${if first_delivery{yes}{no}}"...
127 calling unseen_aaaa router
128 rda_interpret (string): 'defer_aaaa@$domain'
129 expanded: 'defer_aaaa@myhost.test.ex' (tainted)
130 file is not a filter file
131 parse_forward_list: defer_aaaa@myhost.test.ex
132 extract item: defer_aaaa@myhost.test.ex
133 unseen_aaaa router generated defer_aaaa@myhost.test.ex
134   errors_to=NULL transport=NULL
135   uid=unset gid=unset home=NULL
136 routed by unseen_aaaa router (unseen)
137   envelope to: aaaa@myhost.test.ex
138   transport: <none>
139 "unseen" set: replicated aaaa@myhost.test.ex
140 locking TESTSUITE/spool/db/retry.lockfile
141 no retry data available
142 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
143 Considering: aaaa@myhost.test.ex
144 unique = \0\aaaa@myhost.test.ex
145 aaaa@myhost.test.ex: queued for routing
146 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
147 Considering: defer_aaaa@myhost.test.ex
148 unique = defer_aaaa@myhost.test.ex
149 defer_aaaa@myhost.test.ex: queued for routing
150 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
151 Considering: bbbb@myhost.test.ex
152 unique = \0\bbbb@myhost.test.ex
153 bbbb@myhost.test.ex: queued for routing
154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
155 Considering: defer_bbbb@myhost.test.ex
156 unique = defer_bbbb@myhost.test.ex
157 defer_bbbb@myhost.test.ex: queued for routing
158 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
159 Considering: cccc@myhost.test.ex
160 unique = \0\cccc@myhost.test.ex
161 cccc@myhost.test.ex: queued for routing
162 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
163 Considering: defer_cccc@myhost.test.ex
164 unique = defer_cccc@myhost.test.ex
165 defer_cccc@myhost.test.ex: queued for routing
166 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
167 routing defer_cccc@myhost.test.ex
168 --------> defer router <--------
169 local_part=defer_cccc domain=myhost.test.ex
170 checking local_parts
171 calling defer router
172 rda_interpret (string): ':defer: forced defer'
173 expanded: ':defer: forced defer'
174 file is not a filter file
175 parse_forward_list: :defer: forced defer
176 extract item: :defer: forced defer
177 defer router: defer for defer_cccc@myhost.test.ex
178   message: forced defer
179 LOG: MAIN
180   == defer_cccc@myhost.test.ex <cccc@myhost.test.ex> R=defer defer (-1): forced defer
181 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
182 routing cccc@myhost.test.ex
183 --------> defer router <--------
184 local_part=cccc domain=myhost.test.ex
185 checking local_parts
186 cccc in local_parts? no (end of list)
187 defer router skipped: local_parts mismatch
188 --------> unseen_aaaa router <--------
189 local_part=cccc domain=myhost.test.ex
190 checking local_parts
191 cccc in local_parts? no (end of list)
192 unseen_aaaa router skipped: local_parts mismatch
193 --------> seen_aaaa router <--------
194 local_part=cccc domain=myhost.test.ex
195 checking local_parts
196 cccc in local_parts? no (end of list)
197 seen_aaaa router skipped: local_parts mismatch
198 --------> bbbb router <--------
199 local_part=cccc domain=myhost.test.ex
200 checking local_parts
201 cccc in local_parts? no (end of list)
202 bbbb router skipped: local_parts mismatch
203 --------> bbbb_0 router <--------
204 local_part=cccc domain=myhost.test.ex
205 checking local_parts
206 cccc in local_parts? no (end of list)
207 bbbb_0 router skipped: local_parts mismatch
208 --------> cccc_2nd_time router <--------
209 local_part=cccc domain=myhost.test.ex
210 checking local_parts
211 checking "condition" "${if first_delivery{no}{yes}}"...
212 cccc_2nd_time router skipped: condition failure
213 --------> cccc_redirect router <--------
214 cccc_redirect router skipped: previously routed cccc@myhost.test.ex
215 --------> cccc_accept router <--------
216 local_part=cccc domain=myhost.test.ex
217 checking local_parts
218 calling cccc_accept router
219 cccc_accept router called for cccc@myhost.test.ex
220   domain = myhost.test.ex
221 set transport t1
222 queued for t1 transport: local_part = cccc
223 domain = myhost.test.ex
224   errors_to=NULL
225   domain_data=NULL local_part_data=cccc
226 routed by cccc_accept router
227   envelope to: cccc@myhost.test.ex
228   transport: t1
229 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
230 routing defer_bbbb@myhost.test.ex
231 --------> defer router <--------
232 local_part=defer_bbbb domain=myhost.test.ex
233 checking local_parts
234 calling defer router
235 rda_interpret (string): ':defer: forced defer'
236 expanded: ':defer: forced defer'
237 file is not a filter file
238 parse_forward_list: :defer: forced defer
239 extract item: :defer: forced defer
240 defer router: defer for defer_bbbb@myhost.test.ex
241   message: forced defer
242 LOG: MAIN
243   == defer_bbbb@myhost.test.ex <bbbb@myhost.test.ex> R=defer defer (-1): forced defer
244 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
245 routing bbbb@myhost.test.ex
246 --------> defer router <--------
247 local_part=bbbb domain=myhost.test.ex
248 checking local_parts
249 bbbb in local_parts? no (end of list)
250 defer router skipped: local_parts mismatch
251 --------> unseen_aaaa router <--------
252 local_part=bbbb domain=myhost.test.ex
253 checking local_parts
254 bbbb in local_parts? no (end of list)
255 unseen_aaaa router skipped: local_parts mismatch
256 --------> seen_aaaa router <--------
257 local_part=bbbb domain=myhost.test.ex
258 checking local_parts
259 bbbb in local_parts? no (end of list)
260 seen_aaaa router skipped: local_parts mismatch
261 --------> bbbb router <--------
262 bbbb router skipped: previously routed bbbb@myhost.test.ex
263 --------> bbbb_0 router <--------
264 local_part=bbbb domain=myhost.test.ex
265 checking local_parts
266 calling bbbb_0 router
267 bbbb_0 router called for bbbb@myhost.test.ex
268   domain = myhost.test.ex
269 set transport t1
270 queued for t1 transport: local_part = bbbb
271 domain = myhost.test.ex
272   errors_to=NULL
273   domain_data=NULL local_part_data=bbbb
274 routed by bbbb_0 router
275   envelope to: bbbb@myhost.test.ex
276   transport: t1
277 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
278 routing defer_aaaa@myhost.test.ex
279 --------> defer router <--------
280 local_part=defer_aaaa domain=myhost.test.ex
281 checking local_parts
282 calling defer router
283 rda_interpret (string): ':defer: forced defer'
284 expanded: ':defer: forced defer'
285 file is not a filter file
286 parse_forward_list: :defer: forced defer
287 extract item: :defer: forced defer
288 defer router: defer for defer_aaaa@myhost.test.ex
289   message: forced defer
290 LOG: MAIN
291   == defer_aaaa@myhost.test.ex <aaaa@myhost.test.ex> R=defer defer (-1): forced defer
292 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
293 routing aaaa@myhost.test.ex
294 --------> seen_aaaa router <--------
295 local_part=aaaa domain=myhost.test.ex
296 checking local_parts
297 calling seen_aaaa router
298 seen_aaaa router called for aaaa@myhost.test.ex
299   domain = myhost.test.ex
300 set transport t1
301 queued for t1 transport: local_part = aaaa
302 domain = myhost.test.ex
303   errors_to=NULL
304   domain_data=NULL local_part_data=aaaa
305 routed by seen_aaaa router
306   envelope to: aaaa@myhost.test.ex
307   transport: t1
308 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
309 After routing:
310   Local deliveries:
311     aaaa@myhost.test.ex
312     bbbb@myhost.test.ex
313     cccc@myhost.test.ex
314   Remote deliveries:
315   Failed addresses:
316   Deferred addresses:
317     defer_aaaa@myhost.test.ex
318     defer_bbbb@myhost.test.ex
319     defer_cccc@myhost.test.ex
320 locking TESTSUITE/spool/db/retry.lockfile
321 LOG: MAIN
322   => aaaa <aaaa@myhost.test.ex> R=seen_aaaa T=t1
323 locking TESTSUITE/spool/db/retry.lockfile
324 LOG: MAIN
325   => bbbb <bbbb@myhost.test.ex> R=bbbb_0 T=t1
326 locking TESTSUITE/spool/db/retry.lockfile
327 LOG: MAIN
328   => cccc <cccc@myhost.test.ex> R=cccc_accept T=t1
329 locking TESTSUITE/spool/db/retry.lockfile
330 >>>>>>>>>>>>>>>> Exim pid=p1237 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
331 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
332 Exim version x.yz ....
333 Hints DB:
334 configuration file is TESTSUITE/test-config
335 admin user
336 dropping to exim gid; retaining priv uid
337 LOG: queue_run MAIN
338   Start queue run: pid=p1234 -qf
339 locking TESTSUITE/spool/db/retry.lockfile
340 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
341 Considering: aaaa@myhost.test.ex
342 unique = aaaa@myhost.test.ex
343 aaaa@myhost.test.ex: queued for routing
344 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
345 Considering: bbbb@myhost.test.ex
346 unique = bbbb@myhost.test.ex
347 bbbb@myhost.test.ex: queued for routing
348 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
349 Considering: cccc@myhost.test.ex
350 unique = cccc@myhost.test.ex
351 cccc@myhost.test.ex: queued for routing
352 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
353 routing cccc@myhost.test.ex
354 --------> defer router <--------
355 local_part=cccc domain=myhost.test.ex
356 checking local_parts
357 cccc in local_parts? no (end of list)
358 defer router skipped: local_parts mismatch
359 --------> unseen_aaaa router <--------
360 local_part=cccc domain=myhost.test.ex
361 checking local_parts
362 cccc in local_parts? no (end of list)
363 unseen_aaaa router skipped: local_parts mismatch
364 --------> seen_aaaa router <--------
365 local_part=cccc domain=myhost.test.ex
366 checking local_parts
367 cccc in local_parts? no (end of list)
368 seen_aaaa router skipped: local_parts mismatch
369 --------> bbbb router <--------
370 local_part=cccc domain=myhost.test.ex
371 checking local_parts
372 cccc in local_parts? no (end of list)
373 bbbb router skipped: local_parts mismatch
374 --------> bbbb_0 router <--------
375 local_part=cccc domain=myhost.test.ex
376 checking local_parts
377 cccc in local_parts? no (end of list)
378 bbbb_0 router skipped: local_parts mismatch
379 --------> cccc_2nd_time router <--------
380 local_part=cccc domain=myhost.test.ex
381 checking local_parts
382 checking "condition" "${if first_delivery{no}{yes}}"...
383 calling cccc_2nd_time router
384 rda_interpret (string): '$local_part@$domain'
385 expanded: 'cccc@myhost.test.ex' (tainted)
386 file is not a filter file
387 parse_forward_list: cccc@myhost.test.ex
388 extract item: cccc@myhost.test.ex
389 cccc_2nd_time router generated cccc@myhost.test.ex
390   errors_to=NULL transport=NULL
391   uid=unset gid=unset home=NULL
392 routed by cccc_2nd_time router
393   envelope to: cccc@myhost.test.ex
394   transport: <none>
395 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
396 routing bbbb@myhost.test.ex
397 --------> defer router <--------
398 local_part=bbbb domain=myhost.test.ex
399 checking local_parts
400 bbbb in local_parts? no (end of list)
401 defer router skipped: local_parts mismatch
402 --------> unseen_aaaa router <--------
403 local_part=bbbb domain=myhost.test.ex
404 checking local_parts
405 bbbb in local_parts? no (end of list)
406 unseen_aaaa router skipped: local_parts mismatch
407 --------> seen_aaaa router <--------
408 local_part=bbbb domain=myhost.test.ex
409 checking local_parts
410 bbbb in local_parts? no (end of list)
411 seen_aaaa router skipped: local_parts mismatch
412 --------> bbbb router <--------
413 local_part=bbbb domain=myhost.test.ex
414 checking local_parts
415 checking "condition" "${if first_delivery{yes}{no}}"...
416 bbbb router skipped: condition failure
417 --------> bbbb_0 router <--------
418 local_part=bbbb domain=myhost.test.ex
419 checking local_parts
420 calling bbbb_0 router
421 bbbb_0 router called for bbbb@myhost.test.ex
422   domain = myhost.test.ex
423 set transport t1
424 queued for t1 transport: local_part = bbbb
425 domain = myhost.test.ex
426   errors_to=NULL
427   domain_data=NULL local_part_data=bbbb
428 routed by bbbb_0 router
429   envelope to: bbbb@myhost.test.ex
430   transport: t1
431 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
432 routing aaaa@myhost.test.ex
433 --------> defer router <--------
434 local_part=aaaa domain=myhost.test.ex
435 checking local_parts
436 aaaa in local_parts? no (end of list)
437 defer router skipped: local_parts mismatch
438 --------> unseen_aaaa router <--------
439 local_part=aaaa domain=myhost.test.ex
440 checking local_parts
441 checking "condition" "${if first_delivery{yes}{no}}"...
442 unseen_aaaa router skipped: condition failure
443 --------> seen_aaaa router <--------
444 local_part=aaaa domain=myhost.test.ex
445 checking local_parts
446 calling seen_aaaa router
447 seen_aaaa router called for aaaa@myhost.test.ex
448   domain = myhost.test.ex
449 set transport t1
450 queued for t1 transport: local_part = aaaa
451 domain = myhost.test.ex
452   errors_to=NULL
453   domain_data=NULL local_part_data=aaaa
454 routed by seen_aaaa router
455   envelope to: aaaa@myhost.test.ex
456   transport: t1
457 locking TESTSUITE/spool/db/retry.lockfile
458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
459 Considering: cccc@myhost.test.ex
460 unique = \0\cccc@myhost.test.ex
461 cccc@myhost.test.ex: queued for routing
462 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
463 routing cccc@myhost.test.ex
464 --------> defer router <--------
465 local_part=cccc domain=myhost.test.ex
466 checking local_parts
467 cccc in local_parts? no (end of list)
468 defer router skipped: local_parts mismatch
469 --------> unseen_aaaa router <--------
470 local_part=cccc domain=myhost.test.ex
471 checking local_parts
472 cccc in local_parts? no (end of list)
473 unseen_aaaa router skipped: local_parts mismatch
474 --------> seen_aaaa router <--------
475 local_part=cccc domain=myhost.test.ex
476 checking local_parts
477 cccc in local_parts? no (end of list)
478 seen_aaaa router skipped: local_parts mismatch
479 --------> bbbb router <--------
480 local_part=cccc domain=myhost.test.ex
481 checking local_parts
482 cccc in local_parts? no (end of list)
483 bbbb router skipped: local_parts mismatch
484 --------> bbbb_0 router <--------
485 local_part=cccc domain=myhost.test.ex
486 checking local_parts
487 cccc in local_parts? no (end of list)
488 bbbb_0 router skipped: local_parts mismatch
489 --------> cccc_2nd_time router <--------
490 cccc_2nd_time router skipped: previously routed cccc@myhost.test.ex
491 --------> cccc_redirect router <--------
492 local_part=cccc domain=myhost.test.ex
493 checking local_parts
494 calling cccc_redirect router
495 rda_interpret (string): 'cccc@$domain, defer_cccc@$domain'
496 expanded: 'cccc@myhost.test.ex, defer_cccc@myhost.test.ex' (tainted)
497 file is not a filter file
498 parse_forward_list: cccc@myhost.test.ex, defer_cccc@myhost.test.ex
499 extract item: cccc@myhost.test.ex
500 extract item: defer_cccc@myhost.test.ex
501 cccc_redirect router generated defer_cccc@myhost.test.ex
502   errors_to=NULL transport=NULL
503   uid=unset gid=unset home=NULL
504 cccc_redirect router generated cccc@myhost.test.ex
505   errors_to=NULL transport=NULL
506   uid=unset gid=unset home=NULL
507 routed by cccc_redirect router
508   envelope to: cccc@myhost.test.ex
509   transport: <none>
510 locking TESTSUITE/spool/db/retry.lockfile
511 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
512 Considering: cccc@myhost.test.ex
513 unique = \1\cccc@myhost.test.ex
514 cccc@myhost.test.ex: queued for routing
515 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
516 Considering: defer_cccc@myhost.test.ex
517 unique = defer_cccc@myhost.test.ex
518 defer_cccc@myhost.test.ex: queued for routing
519 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
520 routing defer_cccc@myhost.test.ex
521 --------> defer router <--------
522 local_part=defer_cccc domain=myhost.test.ex
523 checking local_parts
524 calling defer router
525 rda_interpret (string): ':defer: forced defer'
526 expanded: ':defer: forced defer'
527 file is not a filter file
528 parse_forward_list: :defer: forced defer
529 extract item: :defer: forced defer
530 defer router: defer for defer_cccc@myhost.test.ex
531   message: forced defer
532 LOG: MAIN
533   == defer_cccc@myhost.test.ex <cccc@myhost.test.ex> R=defer defer (-1): forced defer
534 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
535 routing cccc@myhost.test.ex
536 --------> defer router <--------
537 local_part=cccc domain=myhost.test.ex
538 checking local_parts
539 cccc in local_parts? no (end of list)
540 defer router skipped: local_parts mismatch
541 --------> unseen_aaaa router <--------
542 local_part=cccc domain=myhost.test.ex
543 checking local_parts
544 cccc in local_parts? no (end of list)
545 unseen_aaaa router skipped: local_parts mismatch
546 --------> seen_aaaa router <--------
547 local_part=cccc domain=myhost.test.ex
548 checking local_parts
549 cccc in local_parts? no (end of list)
550 seen_aaaa router skipped: local_parts mismatch
551 --------> bbbb router <--------
552 local_part=cccc domain=myhost.test.ex
553 checking local_parts
554 cccc in local_parts? no (end of list)
555 bbbb router skipped: local_parts mismatch
556 --------> bbbb_0 router <--------
557 local_part=cccc domain=myhost.test.ex
558 checking local_parts
559 cccc in local_parts? no (end of list)
560 bbbb_0 router skipped: local_parts mismatch
561 --------> cccc_2nd_time router <--------
562 cccc_2nd_time router skipped: previously routed cccc@myhost.test.ex
563 --------> cccc_redirect router <--------
564 cccc_redirect router skipped: previously routed cccc@myhost.test.ex
565 --------> cccc_accept router <--------
566 local_part=cccc domain=myhost.test.ex
567 checking local_parts
568 calling cccc_accept router
569 cccc_accept router called for cccc@myhost.test.ex
570   domain = myhost.test.ex
571 set transport t1
572 queued for t1 transport: local_part = cccc
573 domain = myhost.test.ex
574   errors_to=NULL
575   domain_data=NULL local_part_data=cccc
576 routed by cccc_accept router
577   envelope to: cccc@myhost.test.ex
578   transport: t1
579 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
580 After routing:
581   Local deliveries:
582     cccc@myhost.test.ex
583     aaaa@myhost.test.ex
584     bbbb@myhost.test.ex
585   Remote deliveries:
586   Failed addresses:
587   Deferred addresses:
588     defer_cccc@myhost.test.ex
589 cccc@myhost.test.ex was previously delivered (t1 transport): discarded
590 aaaa@myhost.test.ex was previously delivered (t1 transport): discarded
591 bbbb@myhost.test.ex was previously delivered (t1 transport): discarded
592 locking TESTSUITE/spool/db/retry.lockfile
593 >>>>>>>>>>>>>>>> Exim pid=p1238 (qrun-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
594 LOG: queue_run MAIN
595   End queue run: pid=p1234 -qf
596 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
597 Exim version x.yz ....
598 Hints DB:
599 configuration file is TESTSUITE/test-config
600 admin user
601 dropping to exim gid; retaining priv uid
602 LOG: queue_run MAIN
603   Start queue run: pid=p1235 -qf
604 locking TESTSUITE/spool/db/retry.lockfile
605 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
606 Considering: aaaa@myhost.test.ex
607 unique = aaaa@myhost.test.ex
608 aaaa@myhost.test.ex: queued for routing
609 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
610 Considering: bbbb@myhost.test.ex
611 unique = bbbb@myhost.test.ex
612 bbbb@myhost.test.ex: queued for routing
613 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
614 Considering: cccc@myhost.test.ex
615 unique = cccc@myhost.test.ex
616 cccc@myhost.test.ex: queued for routing
617 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
618 routing cccc@myhost.test.ex
619 --------> defer router <--------
620 local_part=cccc domain=myhost.test.ex
621 checking local_parts
622 cccc in local_parts? no (end of list)
623 defer router skipped: local_parts mismatch
624 --------> unseen_aaaa router <--------
625 local_part=cccc domain=myhost.test.ex
626 checking local_parts
627 cccc in local_parts? no (end of list)
628 unseen_aaaa router skipped: local_parts mismatch
629 --------> seen_aaaa router <--------
630 local_part=cccc domain=myhost.test.ex
631 checking local_parts
632 cccc in local_parts? no (end of list)
633 seen_aaaa router skipped: local_parts mismatch
634 --------> bbbb router <--------
635 local_part=cccc domain=myhost.test.ex
636 checking local_parts
637 cccc in local_parts? no (end of list)
638 bbbb router skipped: local_parts mismatch
639 --------> bbbb_0 router <--------
640 local_part=cccc domain=myhost.test.ex
641 checking local_parts
642 cccc in local_parts? no (end of list)
643 bbbb_0 router skipped: local_parts mismatch
644 --------> cccc_2nd_time router <--------
645 local_part=cccc domain=myhost.test.ex
646 checking local_parts
647 checking "condition" "${if first_delivery{no}{yes}}"...
648 calling cccc_2nd_time router
649 rda_interpret (string): '$local_part@$domain'
650 expanded: 'cccc@myhost.test.ex' (tainted)
651 file is not a filter file
652 parse_forward_list: cccc@myhost.test.ex
653 extract item: cccc@myhost.test.ex
654 cccc_2nd_time router generated cccc@myhost.test.ex
655   errors_to=NULL transport=NULL
656   uid=unset gid=unset home=NULL
657 routed by cccc_2nd_time router
658   envelope to: cccc@myhost.test.ex
659   transport: <none>
660 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
661 routing bbbb@myhost.test.ex
662 --------> defer router <--------
663 local_part=bbbb domain=myhost.test.ex
664 checking local_parts
665 bbbb in local_parts? no (end of list)
666 defer router skipped: local_parts mismatch
667 --------> unseen_aaaa router <--------
668 local_part=bbbb domain=myhost.test.ex
669 checking local_parts
670 bbbb in local_parts? no (end of list)
671 unseen_aaaa router skipped: local_parts mismatch
672 --------> seen_aaaa router <--------
673 local_part=bbbb domain=myhost.test.ex
674 checking local_parts
675 bbbb in local_parts? no (end of list)
676 seen_aaaa router skipped: local_parts mismatch
677 --------> bbbb router <--------
678 local_part=bbbb domain=myhost.test.ex
679 checking local_parts
680 checking "condition" "${if first_delivery{yes}{no}}"...
681 bbbb router skipped: condition failure
682 --------> bbbb_0 router <--------
683 local_part=bbbb domain=myhost.test.ex
684 checking local_parts
685 calling bbbb_0 router
686 bbbb_0 router called for bbbb@myhost.test.ex
687   domain = myhost.test.ex
688 set transport t1
689 queued for t1 transport: local_part = bbbb
690 domain = myhost.test.ex
691   errors_to=NULL
692   domain_data=NULL local_part_data=bbbb
693 routed by bbbb_0 router
694   envelope to: bbbb@myhost.test.ex
695   transport: t1
696 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
697 routing aaaa@myhost.test.ex
698 --------> defer router <--------
699 local_part=aaaa domain=myhost.test.ex
700 checking local_parts
701 aaaa in local_parts? no (end of list)
702 defer router skipped: local_parts mismatch
703 --------> unseen_aaaa router <--------
704 local_part=aaaa domain=myhost.test.ex
705 checking local_parts
706 checking "condition" "${if first_delivery{yes}{no}}"...
707 unseen_aaaa router skipped: condition failure
708 --------> seen_aaaa router <--------
709 local_part=aaaa domain=myhost.test.ex
710 checking local_parts
711 calling seen_aaaa router
712 seen_aaaa router called for aaaa@myhost.test.ex
713   domain = myhost.test.ex
714 set transport t1
715 queued for t1 transport: local_part = aaaa
716 domain = myhost.test.ex
717   errors_to=NULL
718   domain_data=NULL local_part_data=aaaa
719 routed by seen_aaaa router
720   envelope to: aaaa@myhost.test.ex
721   transport: t1
722 locking TESTSUITE/spool/db/retry.lockfile
723 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
724 Considering: cccc@myhost.test.ex
725 unique = \0\cccc@myhost.test.ex
726 cccc@myhost.test.ex: queued for routing
727 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
728 routing cccc@myhost.test.ex
729 --------> defer router <--------
730 local_part=cccc domain=myhost.test.ex
731 checking local_parts
732 cccc in local_parts? no (end of list)
733 defer router skipped: local_parts mismatch
734 --------> unseen_aaaa router <--------
735 local_part=cccc domain=myhost.test.ex
736 checking local_parts
737 cccc in local_parts? no (end of list)
738 unseen_aaaa router skipped: local_parts mismatch
739 --------> seen_aaaa router <--------
740 local_part=cccc domain=myhost.test.ex
741 checking local_parts
742 cccc in local_parts? no (end of list)
743 seen_aaaa router skipped: local_parts mismatch
744 --------> bbbb router <--------
745 local_part=cccc domain=myhost.test.ex
746 checking local_parts
747 cccc in local_parts? no (end of list)
748 bbbb router skipped: local_parts mismatch
749 --------> bbbb_0 router <--------
750 local_part=cccc domain=myhost.test.ex
751 checking local_parts
752 cccc in local_parts? no (end of list)
753 bbbb_0 router skipped: local_parts mismatch
754 --------> cccc_2nd_time router <--------
755 cccc_2nd_time router skipped: previously routed cccc@myhost.test.ex
756 --------> cccc_redirect router <--------
757 local_part=cccc domain=myhost.test.ex
758 checking local_parts
759 calling cccc_redirect router
760 rda_interpret (string): 'cccc@$domain, defer_cccc@$domain'
761 expanded: 'cccc@myhost.test.ex, defer_cccc@myhost.test.ex' (tainted)
762 file is not a filter file
763 parse_forward_list: cccc@myhost.test.ex, defer_cccc@myhost.test.ex
764 extract item: cccc@myhost.test.ex
765 extract item: defer_cccc@myhost.test.ex
766 cccc_redirect router generated defer_cccc@myhost.test.ex
767   errors_to=NULL transport=NULL
768   uid=unset gid=unset home=NULL
769 cccc_redirect router generated cccc@myhost.test.ex
770   errors_to=NULL transport=NULL
771   uid=unset gid=unset home=NULL
772 routed by cccc_redirect router
773   envelope to: cccc@myhost.test.ex
774   transport: <none>
775 locking TESTSUITE/spool/db/retry.lockfile
776 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
777 Considering: cccc@myhost.test.ex
778 unique = \1\cccc@myhost.test.ex
779 cccc@myhost.test.ex: queued for routing
780 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
781 Considering: defer_cccc@myhost.test.ex
782 unique = defer_cccc@myhost.test.ex
783 defer_cccc@myhost.test.ex: queued for routing
784 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
785 routing defer_cccc@myhost.test.ex
786 --------> defer router <--------
787 local_part=defer_cccc domain=myhost.test.ex
788 checking local_parts
789 calling defer router
790 rda_interpret (string): ':defer: forced defer'
791 expanded: ':defer: forced defer'
792 file is not a filter file
793 parse_forward_list: :defer: forced defer
794 extract item: :defer: forced defer
795 defer router: defer for defer_cccc@myhost.test.ex
796   message: forced defer
797 LOG: MAIN
798   == defer_cccc@myhost.test.ex <cccc@myhost.test.ex> R=defer defer (-1): forced defer
799 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
800 routing cccc@myhost.test.ex
801 --------> defer router <--------
802 local_part=cccc domain=myhost.test.ex
803 checking local_parts
804 cccc in local_parts? no (end of list)
805 defer router skipped: local_parts mismatch
806 --------> unseen_aaaa router <--------
807 local_part=cccc domain=myhost.test.ex
808 checking local_parts
809 cccc in local_parts? no (end of list)
810 unseen_aaaa router skipped: local_parts mismatch
811 --------> seen_aaaa router <--------
812 local_part=cccc domain=myhost.test.ex
813 checking local_parts
814 cccc in local_parts? no (end of list)
815 seen_aaaa router skipped: local_parts mismatch
816 --------> bbbb router <--------
817 local_part=cccc domain=myhost.test.ex
818 checking local_parts
819 cccc in local_parts? no (end of list)
820 bbbb router skipped: local_parts mismatch
821 --------> bbbb_0 router <--------
822 local_part=cccc domain=myhost.test.ex
823 checking local_parts
824 cccc in local_parts? no (end of list)
825 bbbb_0 router skipped: local_parts mismatch
826 --------> cccc_2nd_time router <--------
827 cccc_2nd_time router skipped: previously routed cccc@myhost.test.ex
828 --------> cccc_redirect router <--------
829 cccc_redirect router skipped: previously routed cccc@myhost.test.ex
830 --------> cccc_accept router <--------
831 local_part=cccc domain=myhost.test.ex
832 checking local_parts
833 calling cccc_accept router
834 cccc_accept router called for cccc@myhost.test.ex
835   domain = myhost.test.ex
836 set transport t1
837 queued for t1 transport: local_part = cccc
838 domain = myhost.test.ex
839   errors_to=NULL
840   domain_data=NULL local_part_data=cccc
841 routed by cccc_accept router
842   envelope to: cccc@myhost.test.ex
843   transport: t1
844 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
845 After routing:
846   Local deliveries:
847     cccc@myhost.test.ex
848     aaaa@myhost.test.ex
849     bbbb@myhost.test.ex
850   Remote deliveries:
851   Failed addresses:
852   Deferred addresses:
853     defer_cccc@myhost.test.ex
854 cccc@myhost.test.ex was previously delivered (t1 transport): discarded
855 aaaa@myhost.test.ex was previously delivered (t1 transport): discarded
856 bbbb@myhost.test.ex was previously delivered (t1 transport): discarded
857 locking TESTSUITE/spool/db/retry.lockfile
858 >>>>>>>>>>>>>>>> Exim pid=p1239 (qrun-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
859 LOG: queue_run MAIN
860   End queue run: pid=p1235 -qf
861 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>