test/stderr/0375

   1 Exim version x.yz ....
   2 configuration file is TESTSUITE/test-config
   3 admin user
   4 LOG: MAIN
   5   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
   6 created log directory TESTSUITE/spool/log
   7 Exim version x.yz ....
   8 configuration file is TESTSUITE/test-config
   9 trusted user
  10 admin user
  11 dropping to exim gid; retaining priv uid
  12 locking TESTSUITE/spool/db/retry.lockfile
  13 no retry data available
  14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  15 Considering: a1@myhost.test.ex
  16 unique = a1@myhost.test.ex
  17 a1@myhost.test.ex: queued for routing
  18 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  19 Considering: b1@myhost.test.ex
  20 unique = b1@myhost.test.ex
  21 b1@myhost.test.ex: queued for routing
  22 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  23 Considering: c1@myhost.test.ex
  24 unique = c1@myhost.test.ex
  25 c1@myhost.test.ex: queued for routing
  26 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  27 Considering: d1@myhost.test.ex
  28 unique = d1@myhost.test.ex
  29 d1@myhost.test.ex: queued for routing
  30 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  31 Considering: d2@myhost.test.ex
  32 unique = d2@myhost.test.ex
  33 d2@myhost.test.ex: queued for routing
  34 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  35 Considering: d3@myhost.test.ex
  36 unique = d3@myhost.test.ex
  37 d3@myhost.test.ex: queued for routing
  38 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  39 Considering: e1@myhost.test.ex
  40 unique = e1@myhost.test.ex
  41 e1@myhost.test.ex: queued for routing
  42 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  43 Considering: f1@myhost.test.ex
  44 unique = f1@myhost.test.ex
  45 f1@myhost.test.ex: queued for routing
  46 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  47 Considering: f2@myhost.test.ex
  48 unique = f2@myhost.test.ex
  49 f2@myhost.test.ex: queued for routing
  50 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  51 Considering: f3@myhost.test.ex
  52 unique = f3@myhost.test.ex
  53 f3@myhost.test.ex: queued for routing
  54 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  55 Considering: g1@myhost.test.ex
  56 unique = g1@myhost.test.ex
  57 g1@myhost.test.ex: queued for routing
  58 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  59 routing g1@myhost.test.ex
  60 --------> u1 router <--------
  61 local_part=g1 domain=myhost.test.ex
  62 checking local_parts
  63 u1 router skipped: local_parts mismatch
  64 --------> ut2 router <--------
  65 local_part=g1 domain=myhost.test.ex
  66 checking local_parts
  67 ut2 router skipped: local_parts mismatch
  68 --------> ut3 router <--------
  69 local_part=g1 domain=myhost.test.ex
  70 checking local_parts
  71 ut3 router skipped: local_parts mismatch
  72 --------> ut4 router <--------
  73 local_part=g1 domain=myhost.test.ex
  74 checking local_parts
  75 ut4 router skipped: local_parts mismatch
  76 --------> ut5 router <--------
  77 local_part=g1 domain=myhost.test.ex
  78 checking local_parts
  79 ut5 router skipped: local_parts mismatch
  80 --------> ut6 router <--------
  81 local_part=g1 domain=myhost.test.ex
  82 checking local_parts
  83 ut6 router skipped: local_parts mismatch
  84 --------> ut7 router <--------
  85 local_part=g1 domain=myhost.test.ex
  86 checking local_parts
  87 processing address_data
  88 LOG: MAIN
  89   == g1@myhost.test.ex R=ut7 defer (-1): expansion of "$bad expansion" failed in ut7 router: unknown variable name "bad"
  90 log writing disabled
  91 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  92 routing f3@myhost.test.ex
  93 --------> u1 router <--------
  94 local_part=f3 domain=myhost.test.ex
  95 checking local_parts
  96 u1 router skipped: local_parts mismatch
  97 --------> ut2 router <--------
  98 local_part=f3 domain=myhost.test.ex
  99 checking local_parts
 100 ut2 router skipped: local_parts mismatch
 101 --------> ut3 router <--------
 102 local_part=f3 domain=myhost.test.ex
 103 checking local_parts
 104 ut3 router skipped: local_parts mismatch
 105 --------> ut4 router <--------
 106 local_part=f3 domain=myhost.test.ex
 107 checking local_parts
 108 ut4 router skipped: local_parts mismatch
 109 --------> ut5 router <--------
 110 local_part=f3 domain=myhost.test.ex
 111 checking local_parts
 112 ut5 router skipped: local_parts mismatch
 113 --------> ut6 router <--------
 114 local_part=f3 domain=myhost.test.ex
 115 checking local_parts
 116 processing address_data
 117 calling ut6 router
 118 ut6 router called for f3@myhost.test.ex
 119   domain = myhost.test.ex
 120 set transport ut6
 121 queued for ut6 transport: local_part = f3
 122 domain = myhost.test.ex
 123   errors_to=
 124   domain_data=NULL local_part_data=^f\d
 125 routed by ut6 router (unseen)
 126   envelope to: f3@myhost.test.ex
 127   transport: ut6
 128   errors to
 129 "unseen" set: replicated f3@myhost.test.ex
 130 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 131 routing f2@myhost.test.ex
 132 --------> u1 router <--------
 133 local_part=f2 domain=myhost.test.ex
 134 checking local_parts
 135 u1 router skipped: local_parts mismatch
 136 --------> ut2 router <--------
 137 local_part=f2 domain=myhost.test.ex
 138 checking local_parts
 139 ut2 router skipped: local_parts mismatch
 140 --------> ut3 router <--------
 141 local_part=f2 domain=myhost.test.ex
 142 checking local_parts
 143 ut3 router skipped: local_parts mismatch
 144 --------> ut4 router <--------
 145 local_part=f2 domain=myhost.test.ex
 146 checking local_parts
 147 ut4 router skipped: local_parts mismatch
 148 --------> ut5 router <--------
 149 local_part=f2 domain=myhost.test.ex
 150 checking local_parts
 151 ut5 router skipped: local_parts mismatch
 152 --------> ut6 router <--------
 153 local_part=f2 domain=myhost.test.ex
 154 checking local_parts
 155 processing address_data
 156 calling ut6 router
 157 ut6 router called for f2@myhost.test.ex
 158   domain = myhost.test.ex
 159 queued for ut6 transport: local_part = f2
 160 domain = myhost.test.ex
 161   errors_to=
 162   domain_data=NULL local_part_data=^f\d
 163 routed by ut6 router (unseen)
 164   envelope to: f2@myhost.test.ex
 165   transport: ut6
 166   errors to
 167 "unseen" set: replicated f2@myhost.test.ex
 168 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 169 routing f1@myhost.test.ex
 170 --------> u1 router <--------
 171 local_part=f1 domain=myhost.test.ex
 172 checking local_parts
 173 u1 router skipped: local_parts mismatch
 174 --------> ut2 router <--------
 175 local_part=f1 domain=myhost.test.ex
 176 checking local_parts
 177 ut2 router skipped: local_parts mismatch
 178 --------> ut3 router <--------
 179 local_part=f1 domain=myhost.test.ex
 180 checking local_parts
 181 ut3 router skipped: local_parts mismatch
 182 --------> ut4 router <--------
 183 local_part=f1 domain=myhost.test.ex
 184 checking local_parts
 185 ut4 router skipped: local_parts mismatch
 186 --------> ut5 router <--------
 187 local_part=f1 domain=myhost.test.ex
 188 checking local_parts
 189 ut5 router skipped: local_parts mismatch
 190 --------> ut6 router <--------
 191 local_part=f1 domain=myhost.test.ex
 192 checking local_parts
 193 processing address_data
 194 calling ut6 router
 195 ut6 router called for f1@myhost.test.ex
 196   domain = myhost.test.ex
 197 queued for ut6 transport: local_part = f1
 198 domain = myhost.test.ex
 199   errors_to=
 200   domain_data=NULL local_part_data=^f\d
 201 routed by ut6 router (unseen)
 202   envelope to: f1@myhost.test.ex
 203   transport: ut6
 204   errors to
 205 "unseen" set: replicated f1@myhost.test.ex
 206 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 207 routing e1@myhost.test.ex
 208 --------> u1 router <--------
 209 local_part=e1 domain=myhost.test.ex
 210 checking local_parts
 211 u1 router skipped: local_parts mismatch
 212 --------> ut2 router <--------
 213 local_part=e1 domain=myhost.test.ex
 214 checking local_parts
 215 ut2 router skipped: local_parts mismatch
 216 --------> ut3 router <--------
 217 local_part=e1 domain=myhost.test.ex
 218 checking local_parts
 219 ut3 router skipped: local_parts mismatch
 220 --------> ut4 router <--------
 221 local_part=e1 domain=myhost.test.ex
 222 checking local_parts
 223 ut4 router skipped: local_parts mismatch
 224 --------> ut5 router <--------
 225 local_part=e1 domain=myhost.test.ex
 226 checking local_parts
 227 calling ut5 router
 228 ut5 router called for e1@myhost.test.ex
 229   domain = myhost.test.ex
 230 set transport ut5
 231 queued for ut5 transport: local_part = e1
 232 domain = myhost.test.ex
 233   errors_to=NULL
 234   domain_data=NULL local_part_data=^e\d
 235 routed by ut5 router (unseen)
 236   envelope to: e1@myhost.test.ex
 237   transport: ut5
 238 "unseen" set: replicated e1@myhost.test.ex
 239 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 240 routing d3@myhost.test.ex
 241 --------> u1 router <--------
 242 local_part=d3 domain=myhost.test.ex
 243 checking local_parts
 244 u1 router skipped: local_parts mismatch
 245 --------> ut2 router <--------
 246 local_part=d3 domain=myhost.test.ex
 247 checking local_parts
 248 ut2 router skipped: local_parts mismatch
 249 --------> ut3 router <--------
 250 local_part=d3 domain=myhost.test.ex
 251 checking local_parts
 252 ut3 router skipped: local_parts mismatch
 253 --------> ut4 router <--------
 254 local_part=d3 domain=myhost.test.ex
 255 checking local_parts
 256 calling ut4 router
 257 ut4 router called for d3@myhost.test.ex
 258   domain = myhost.test.ex
 259 set transport ut4
 260 queued for ut4 transport: local_part = d3
 261 domain = myhost.test.ex
 262   errors_to=
 263   domain_data=NULL local_part_data=^d\d
 264 routed by ut4 router (unseen)
 265   envelope to: d3@myhost.test.ex
 266   transport: ut4
 267   errors to
 268 "unseen" set: replicated d3@myhost.test.ex
 269 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 270 routing d2@myhost.test.ex
 271 --------> u1 router <--------
 272 local_part=d2 domain=myhost.test.ex
 273 checking local_parts
 274 u1 router skipped: local_parts mismatch
 275 --------> ut2 router <--------
 276 local_part=d2 domain=myhost.test.ex
 277 checking local_parts
 278 ut2 router skipped: local_parts mismatch
 279 --------> ut3 router <--------
 280 local_part=d2 domain=myhost.test.ex
 281 checking local_parts
 282 ut3 router skipped: local_parts mismatch
 283 --------> ut4 router <--------
 284 local_part=d2 domain=myhost.test.ex
 285 checking local_parts
 286 calling ut4 router
 287 ut4 router called for d2@myhost.test.ex
 288   domain = myhost.test.ex
 289 queued for ut4 transport: local_part = d2
 290 domain = myhost.test.ex
 291   errors_to=
 292   domain_data=NULL local_part_data=^d\d
 293 routed by ut4 router (unseen)
 294   envelope to: d2@myhost.test.ex
 295   transport: ut4
 296   errors to
 297 "unseen" set: replicated d2@myhost.test.ex
 298 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 299 routing d1@myhost.test.ex
 300 --------> u1 router <--------
 301 local_part=d1 domain=myhost.test.ex
 302 checking local_parts
 303 u1 router skipped: local_parts mismatch
 304 --------> ut2 router <--------
 305 local_part=d1 domain=myhost.test.ex
 306 checking local_parts
 307 ut2 router skipped: local_parts mismatch
 308 --------> ut3 router <--------
 309 local_part=d1 domain=myhost.test.ex
 310 checking local_parts
 311 ut3 router skipped: local_parts mismatch
 312 --------> ut4 router <--------
 313 local_part=d1 domain=myhost.test.ex
 314 checking local_parts
 315 calling ut4 router
 316 ut4 router called for d1@myhost.test.ex
 317   domain = myhost.test.ex
 318 queued for ut4 transport: local_part = d1
 319 domain = myhost.test.ex
 320   errors_to=
 321   domain_data=NULL local_part_data=^d\d
 322 routed by ut4 router (unseen)
 323   envelope to: d1@myhost.test.ex
 324   transport: ut4
 325   errors to
 326 "unseen" set: replicated d1@myhost.test.ex
 327 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 328 routing c1@myhost.test.ex
 329 --------> u1 router <--------
 330 local_part=c1 domain=myhost.test.ex
 331 checking local_parts
 332 u1 router skipped: local_parts mismatch
 333 --------> ut2 router <--------
 334 local_part=c1 domain=myhost.test.ex
 335 checking local_parts
 336 ut2 router skipped: local_parts mismatch
 337 --------> ut3 router <--------
 338 local_part=c1 domain=myhost.test.ex
 339 checking local_parts
 340 calling ut3 router
 341 ut3 router called for c1@myhost.test.ex
 342   domain = myhost.test.ex
 343 set transport ut3
 344 queued for ut3 transport: local_part = c1
 345 domain = myhost.test.ex
 346   errors_to=
 347   domain_data=NULL local_part_data=^c\d
 348 routed by ut3 router (unseen)
 349   envelope to: c1@myhost.test.ex
 350   transport: ut3
 351   errors to
 352 "unseen" set: replicated c1@myhost.test.ex
 353 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 354 routing b1@myhost.test.ex
 355 --------> u1 router <--------
 356 local_part=b1 domain=myhost.test.ex
 357 checking local_parts
 358 u1 router skipped: local_parts mismatch
 359 --------> ut2 router <--------
 360 local_part=b1 domain=myhost.test.ex
 361 checking local_parts
 362 calling ut2 router
 363 ut2 router called for b1@myhost.test.ex
 364   domain = myhost.test.ex
 365 set transport ut2
 366 queued for ut2 transport: local_part = b1
 367 domain = myhost.test.ex
 368   errors_to=
 369   domain_data=NULL local_part_data=^b\d
 370 routed by ut2 router (unseen)
 371   envelope to: b1@myhost.test.ex
 372   transport: ut2
 373   errors to
 374 "unseen" set: replicated b1@myhost.test.ex
 375 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 376 routing a1@myhost.test.ex
 377 --------> u1 router <--------
 378 local_part=a1 domain=myhost.test.ex
 379 checking local_parts
 380 calling u1 router
 381 u1 router called for a1@myhost.test.ex
 382   domain = myhost.test.ex
 383 set transport ut1
 384 queued for ut1 transport: local_part = a1
 385 domain = myhost.test.ex
 386   errors_to=
 387   domain_data=NULL local_part_data=^a\d
 388 routed by u1 router (unseen)
 389   envelope to: a1@myhost.test.ex
 390   transport: ut1
 391   errors to
 392 "unseen" set: replicated a1@myhost.test.ex
 393 locking TESTSUITE/spool/db/retry.lockfile
 394 no retry data available
 395 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 396 Considering: a1@myhost.test.ex
 397 unique = \0\a1@myhost.test.ex
 398 a1@myhost.test.ex: queued for routing
 399 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 400 Considering: b1@myhost.test.ex
 401 unique = \0\b1@myhost.test.ex
 402 b1@myhost.test.ex: queued for routing
 403 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 404 Considering: c1@myhost.test.ex
 405 unique = \0\c1@myhost.test.ex
 406 c1@myhost.test.ex: queued for routing
 407 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 408 Considering: d1@myhost.test.ex
 409 unique = \0\d1@myhost.test.ex
 410 d1@myhost.test.ex: queued for routing
 411 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 412 Considering: d2@myhost.test.ex
 413 unique = \0\d2@myhost.test.ex
 414 d2@myhost.test.ex: queued for routing
 415 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 416 Considering: d3@myhost.test.ex
 417 unique = \0\d3@myhost.test.ex
 418 d3@myhost.test.ex: queued for routing
 419 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 420 Considering: e1@myhost.test.ex
 421 unique = \0\e1@myhost.test.ex
 422 e1@myhost.test.ex: queued for routing
 423 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 424 Considering: f1@myhost.test.ex
 425 unique = \0\f1@myhost.test.ex
 426 f1@myhost.test.ex: queued for routing
 427 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 428 Considering: f2@myhost.test.ex
 429 unique = \0\f2@myhost.test.ex
 430 f2@myhost.test.ex: queued for routing
 431 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 432 Considering: f3@myhost.test.ex
 433 unique = \0\f3@myhost.test.ex
 434 f3@myhost.test.ex: queued for routing
 435 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 436 routing f3@myhost.test.ex
 437 --------> ut7 router <--------
 438 local_part=f3 domain=myhost.test.ex
 439 checking local_parts
 440 ut7 router skipped: local_parts mismatch
 441 --------> ut8 router <--------
 442 local_part=f3 domain=myhost.test.ex
 443 checking local_parts
 444 ut8 router skipped: local_parts mismatch
 445 --------> real router <--------
 446 local_part=f3 domain=myhost.test.ex
 447 calling real router
 448 real router called for f3@myhost.test.ex
 449   domain = myhost.test.ex
 450 set transport real
 451 queued for real transport: local_part = f3
 452 domain = myhost.test.ex
 453   errors_to=NULL
 454   domain_data=NULL local_part_data=NULL
 455 routed by real router
 456   envelope to: f3@myhost.test.ex
 457   transport: real
 458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 459 routing f2@myhost.test.ex
 460 --------> ut7 router <--------
 461 local_part=f2 domain=myhost.test.ex
 462 checking local_parts
 463 ut7 router skipped: local_parts mismatch
 464 --------> ut8 router <--------
 465 local_part=f2 domain=myhost.test.ex
 466 checking local_parts
 467 ut8 router skipped: local_parts mismatch
 468 --------> real router <--------
 469 local_part=f2 domain=myhost.test.ex
 470 calling real router
 471 real router called for f2@myhost.test.ex
 472   domain = myhost.test.ex
 473 queued for real transport: local_part = f2
 474 domain = myhost.test.ex
 475   errors_to=NULL
 476   domain_data=NULL local_part_data=NULL
 477 routed by real router
 478   envelope to: f2@myhost.test.ex
 479   transport: real
 480 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 481 routing f1@myhost.test.ex
 482 --------> ut7 router <--------
 483 local_part=f1 domain=myhost.test.ex
 484 checking local_parts
 485 ut7 router skipped: local_parts mismatch
 486 --------> ut8 router <--------
 487 local_part=f1 domain=myhost.test.ex
 488 checking local_parts
 489 ut8 router skipped: local_parts mismatch
 490 --------> real router <--------
 491 local_part=f1 domain=myhost.test.ex
 492 calling real router
 493 real router called for f1@myhost.test.ex
 494   domain = myhost.test.ex
 495 queued for real transport: local_part = f1
 496 domain = myhost.test.ex
 497   errors_to=NULL
 498   domain_data=NULL local_part_data=NULL
 499 routed by real router
 500   envelope to: f1@myhost.test.ex
 501   transport: real
 502 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 503 routing e1@myhost.test.ex
 504 --------> ut6 router <--------
 505 local_part=e1 domain=myhost.test.ex
 506 checking local_parts
 507 ut6 router skipped: local_parts mismatch
 508 --------> ut7 router <--------
 509 local_part=e1 domain=myhost.test.ex
 510 checking local_parts
 511 ut7 router skipped: local_parts mismatch
 512 --------> ut8 router <--------
 513 local_part=e1 domain=myhost.test.ex
 514 checking local_parts
 515 ut8 router skipped: local_parts mismatch
 516 --------> real router <--------
 517 local_part=e1 domain=myhost.test.ex
 518 calling real router
 519 real router called for e1@myhost.test.ex
 520   domain = myhost.test.ex
 521 queued for real transport: local_part = e1
 522 domain = myhost.test.ex
 523   errors_to=NULL
 524   domain_data=NULL local_part_data=NULL
 525 routed by real router
 526   envelope to: e1@myhost.test.ex
 527   transport: real
 528 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 529 routing d3@myhost.test.ex
 530 --------> ut5 router <--------
 531 local_part=d3 domain=myhost.test.ex
 532 checking local_parts
 533 ut5 router skipped: local_parts mismatch
 534 --------> ut6 router <--------
 535 local_part=d3 domain=myhost.test.ex
 536 checking local_parts
 537 ut6 router skipped: local_parts mismatch
 538 --------> ut7 router <--------
 539 local_part=d3 domain=myhost.test.ex
 540 checking local_parts
 541 ut7 router skipped: local_parts mismatch
 542 --------> ut8 router <--------
 543 local_part=d3 domain=myhost.test.ex
 544 checking local_parts
 545 ut8 router skipped: local_parts mismatch
 546 --------> real router <--------
 547 local_part=d3 domain=myhost.test.ex
 548 calling real router
 549 real router called for d3@myhost.test.ex
 550   domain = myhost.test.ex
 551 queued for real transport: local_part = d3
 552 domain = myhost.test.ex
 553   errors_to=NULL
 554   domain_data=NULL local_part_data=NULL
 555 routed by real router
 556   envelope to: d3@myhost.test.ex
 557   transport: real
 558 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 559 routing d2@myhost.test.ex
 560 --------> ut5 router <--------
 561 local_part=d2 domain=myhost.test.ex
 562 checking local_parts
 563 ut5 router skipped: local_parts mismatch
 564 --------> ut6 router <--------
 565 local_part=d2 domain=myhost.test.ex
 566 checking local_parts
 567 ut6 router skipped: local_parts mismatch
 568 --------> ut7 router <--------
 569 local_part=d2 domain=myhost.test.ex
 570 checking local_parts
 571 ut7 router skipped: local_parts mismatch
 572 --------> ut8 router <--------
 573 local_part=d2 domain=myhost.test.ex
 574 checking local_parts
 575 ut8 router skipped: local_parts mismatch
 576 --------> real router <--------
 577 local_part=d2 domain=myhost.test.ex
 578 calling real router
 579 real router called for d2@myhost.test.ex
 580   domain = myhost.test.ex
 581 queued for real transport: local_part = d2
 582 domain = myhost.test.ex
 583   errors_to=NULL
 584   domain_data=NULL local_part_data=NULL
 585 routed by real router
 586   envelope to: d2@myhost.test.ex
 587   transport: real
 588 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 589 routing d1@myhost.test.ex
 590 --------> ut5 router <--------
 591 local_part=d1 domain=myhost.test.ex
 592 checking local_parts
 593 ut5 router skipped: local_parts mismatch
 594 --------> ut6 router <--------
 595 local_part=d1 domain=myhost.test.ex
 596 checking local_parts
 597 ut6 router skipped: local_parts mismatch
 598 --------> ut7 router <--------
 599 local_part=d1 domain=myhost.test.ex
 600 checking local_parts
 601 ut7 router skipped: local_parts mismatch
 602 --------> ut8 router <--------
 603 local_part=d1 domain=myhost.test.ex
 604 checking local_parts
 605 ut8 router skipped: local_parts mismatch
 606 --------> real router <--------
 607 local_part=d1 domain=myhost.test.ex
 608 calling real router
 609 real router called for d1@myhost.test.ex
 610   domain = myhost.test.ex
 611 queued for real transport: local_part = d1
 612 domain = myhost.test.ex
 613   errors_to=NULL
 614   domain_data=NULL local_part_data=NULL
 615 routed by real router
 616   envelope to: d1@myhost.test.ex
 617   transport: real
 618 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 619 routing c1@myhost.test.ex
 620 --------> ut4 router <--------
 621 local_part=c1 domain=myhost.test.ex
 622 checking local_parts
 623 ut4 router skipped: local_parts mismatch
 624 --------> ut5 router <--------
 625 local_part=c1 domain=myhost.test.ex
 626 checking local_parts
 627 ut5 router skipped: local_parts mismatch
 628 --------> ut6 router <--------
 629 local_part=c1 domain=myhost.test.ex
 630 checking local_parts
 631 ut6 router skipped: local_parts mismatch
 632 --------> ut7 router <--------
 633 local_part=c1 domain=myhost.test.ex
 634 checking local_parts
 635 ut7 router skipped: local_parts mismatch
 636 --------> ut8 router <--------
 637 local_part=c1 domain=myhost.test.ex
 638 checking local_parts
 639 ut8 router skipped: local_parts mismatch
 640 --------> real router <--------
 641 local_part=c1 domain=myhost.test.ex
 642 calling real router
 643 real router called for c1@myhost.test.ex
 644   domain = myhost.test.ex
 645 queued for real transport: local_part = c1
 646 domain = myhost.test.ex
 647   errors_to=NULL
 648   domain_data=NULL local_part_data=NULL
 649 routed by real router
 650   envelope to: c1@myhost.test.ex
 651   transport: real
 652 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 653 routing b1@myhost.test.ex
 654 --------> ut3 router <--------
 655 local_part=b1 domain=myhost.test.ex
 656 checking local_parts
 657 ut3 router skipped: local_parts mismatch
 658 --------> ut4 router <--------
 659 local_part=b1 domain=myhost.test.ex
 660 checking local_parts
 661 ut4 router skipped: local_parts mismatch
 662 --------> ut5 router <--------
 663 local_part=b1 domain=myhost.test.ex
 664 checking local_parts
 665 ut5 router skipped: local_parts mismatch
 666 --------> ut6 router <--------
 667 local_part=b1 domain=myhost.test.ex
 668 checking local_parts
 669 ut6 router skipped: local_parts mismatch
 670 --------> ut7 router <--------
 671 local_part=b1 domain=myhost.test.ex
 672 checking local_parts
 673 ut7 router skipped: local_parts mismatch
 674 --------> ut8 router <--------
 675 local_part=b1 domain=myhost.test.ex
 676 checking local_parts
 677 ut8 router skipped: local_parts mismatch
 678 --------> real router <--------
 679 local_part=b1 domain=myhost.test.ex
 680 calling real router
 681 real router called for b1@myhost.test.ex
 682   domain = myhost.test.ex
 683 queued for real transport: local_part = b1
 684 domain = myhost.test.ex
 685   errors_to=NULL
 686   domain_data=NULL local_part_data=NULL
 687 routed by real router
 688   envelope to: b1@myhost.test.ex
 689   transport: real
 690 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 691 routing a1@myhost.test.ex
 692 --------> ut2 router <--------
 693 local_part=a1 domain=myhost.test.ex
 694 checking local_parts
 695 ut2 router skipped: local_parts mismatch
 696 --------> ut3 router <--------
 697 local_part=a1 domain=myhost.test.ex
 698 checking local_parts
 699 ut3 router skipped: local_parts mismatch
 700 --------> ut4 router <--------
 701 local_part=a1 domain=myhost.test.ex
 702 checking local_parts
 703 ut4 router skipped: local_parts mismatch
 704 --------> ut5 router <--------
 705 local_part=a1 domain=myhost.test.ex
 706 checking local_parts
 707 ut5 router skipped: local_parts mismatch
 708 --------> ut6 router <--------
 709 local_part=a1 domain=myhost.test.ex
 710 checking local_parts
 711 ut6 router skipped: local_parts mismatch
 712 --------> ut7 router <--------
 713 local_part=a1 domain=myhost.test.ex
 714 checking local_parts
 715 ut7 router skipped: local_parts mismatch
 716 --------> ut8 router <--------
 717 local_part=a1 domain=myhost.test.ex
 718 checking local_parts
 719 ut8 router skipped: local_parts mismatch
 720 --------> real router <--------
 721 local_part=a1 domain=myhost.test.ex
 722 calling real router
 723 real router called for a1@myhost.test.ex
 724   domain = myhost.test.ex
 725 queued for real transport: local_part = a1
 726 domain = myhost.test.ex
 727   errors_to=NULL
 728   domain_data=NULL local_part_data=NULL
 729 routed by real router
 730   envelope to: a1@myhost.test.ex
 731   transport: real
 732 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 733 After routing:
 734   Local deliveries:
 735     a1@myhost.test.ex
 736     b1@myhost.test.ex
 737     c1@myhost.test.ex
 738     d1@myhost.test.ex
 739     d2@myhost.test.ex
 740     d3@myhost.test.ex
 741     e1@myhost.test.ex
 742     f1@myhost.test.ex
 743     f2@myhost.test.ex
 744     f3@myhost.test.ex
 745     a1@myhost.test.ex
 746     b1@myhost.test.ex
 747     c1@myhost.test.ex
 748   Remote deliveries:
 749     d1@myhost.test.ex
 750     d2@myhost.test.ex
 751     d3@myhost.test.ex
 752     e1@myhost.test.ex
 753     f1@myhost.test.ex
 754     f2@myhost.test.ex
 755     f3@myhost.test.ex
 756   Failed addresses:
 757   Deferred addresses:
 758     g1@myhost.test.ex
 759 locking TESTSUITE/spool/db/retry.lockfile
 760 LOG: MAIN
 761   => a1 <a1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 762 locking TESTSUITE/spool/db/retry.lockfile
 763 LOG: MAIN
 764   => b1 <b1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 765 locking TESTSUITE/spool/db/retry.lockfile
 766 LOG: MAIN
 767   => c1 <c1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 768 locking TESTSUITE/spool/db/retry.lockfile
 769 LOG: MAIN
 770   => d1 <d1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 771 locking TESTSUITE/spool/db/retry.lockfile
 772 LOG: MAIN
 773   => d2 <d2@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 774 locking TESTSUITE/spool/db/retry.lockfile
 775 LOG: MAIN
 776   => d3 <d3@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 777 locking TESTSUITE/spool/db/retry.lockfile
 778 LOG: MAIN
 779   => e1 <e1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 780 locking TESTSUITE/spool/db/retry.lockfile
 781 LOG: MAIN
 782   => f1 <f1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 783 locking TESTSUITE/spool/db/retry.lockfile
 784 LOG: MAIN
 785   => f2 <f2@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 786 locking TESTSUITE/spool/db/retry.lockfile
 787 LOG: MAIN
 788   => f3 <f3@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
 789 locking TESTSUITE/spool/db/retry.lockfile
 790 LOG: MAIN
 791   => a1 <a1@myhost.test.ex> P=<> R=u1 T=ut1
 792 log writing disabled
 793 locking TESTSUITE/spool/db/retry.lockfile
 794 transport error EPIPE ignored
 795 LOG: MAIN
 796   ** b1@myhost.test.ex P=<> 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
 797 log writing disabled
 798 locking TESTSUITE/spool/db/retry.lockfile
 799 transport error EPIPE ignored
 800 LOG: MAIN
 801   == 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
 802 log writing disabled
 803 locking TESTSUITE/spool/db/retry.lockfile
 804 locking TESTSUITE/spool/db/wait-ut4.lockfile
 805 cmdlog: '220'
 806 LOG: MAIN
 807   => d1@myhost.test.ex P=<> R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1] C="250 OK"
 808 log writing disabled
 809 locking TESTSUITE/spool/db/retry.lockfile
 810 cmdlog: '220'
 811 LOG: MAIN
 812   == 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
 813 log writing disabled
 814 locking TESTSUITE/spool/db/retry.lockfile
 815 locking TESTSUITE/spool/db/wait-ut4.lockfile
 816 cmdlog: '220'
 817 LOG: MAIN
 818   ** d3@myhost.test.ex P=<> 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
 819 log writing disabled
 820 locking TESTSUITE/spool/db/retry.lockfile
 821 locking TESTSUITE/spool/db/wait-ut5.lockfile
 822 cmdlog: '220'
 823 LOG: MAIN
 824   ** e1@myhost.test.ex P=<> R=ut5 T=ut5 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<e1@myhost.test.ex>: 550 hard error
 825 log writing disabled
 826 locking TESTSUITE/spool/db/retry.lockfile
 827 locking TESTSUITE/spool/db/wait-ut6.lockfile
 828 cmdlog: '220'
 829 LOG: MAIN
 830   => f1@myhost.test.ex P=<CALLER@myhost.test.ex> R=ut6 T=ut6 H=127.0.0.1 [127.0.0.1] C="250 OK"
 831 log writing disabled
 832 locking TESTSUITE/spool/db/retry.lockfile
 833 cmdlog: '220'
 834 LOG: MAIN
 835   == f2@myhost.test.ex R=ut6 T=ut6 defer (-44) H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<f2@myhost.test.ex>: 450 soft error
 836 log writing disabled
 837 locking TESTSUITE/spool/db/retry.lockfile
 838 locking TESTSUITE/spool/db/wait-ut6.lockfile
 839 cmdlog: '220'
 840 LOG: MAIN
 841   ** f3@myhost.test.ex P=<CALLER@myhost.test.ex> R=ut6 T=ut6 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<f3@myhost.test.ex>: 550 hard error
 842 log writing disabled
 843 locking TESTSUITE/spool/db/retry.lockfile
 844 LOG: MAIN
 845   f3@myhost.test.ex <f3@myhost.test.ex>: error ignored
 846 log writing disabled
 847 Exim version x.yz ....
 848 configuration file is TESTSUITE/test-config
 849 trusted user
 850 admin user
 851 LOG: MAIN
 852   <= <> R=10HmaX-000000005vi-0000 U=EXIMUSER P=local S=sss
 853 Exim version x.yz ....
 854 configuration file is TESTSUITE/test-config
 855 trusted user
 856 admin user
 857 dropping to exim gid; retaining priv uid
 858 locking TESTSUITE/spool/db/retry.lockfile
 859 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 860 Considering: CALLER@myhost.test.ex
 861 unique = CALLER@myhost.test.ex
 862 CALLER@myhost.test.ex: queued for routing
 863 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 864 routing CALLER@myhost.test.ex
 865 --------> u1 router <--------
 866 local_part=CALLER domain=myhost.test.ex
 867 checking local_parts
 868 u1 router skipped: local_parts mismatch
 869 --------> ut2 router <--------
 870 local_part=CALLER domain=myhost.test.ex
 871 checking local_parts
 872 ut2 router skipped: local_parts mismatch
 873 --------> ut3 router <--------
 874 local_part=CALLER domain=myhost.test.ex
 875 checking local_parts
 876 ut3 router skipped: local_parts mismatch
 877 --------> ut4 router <--------
 878 local_part=CALLER domain=myhost.test.ex
 879 checking local_parts
 880 ut4 router skipped: local_parts mismatch
 881 --------> ut5 router <--------
 882 local_part=CALLER domain=myhost.test.ex
 883 checking local_parts
 884 ut5 router skipped: local_parts mismatch
 885 --------> ut6 router <--------
 886 local_part=CALLER domain=myhost.test.ex
 887 checking local_parts
 888 ut6 router skipped: local_parts mismatch
 889 --------> ut7 router <--------
 890 local_part=CALLER domain=myhost.test.ex
 891 checking local_parts
 892 ut7 router skipped: local_parts mismatch
 893 --------> ut8 router <--------
 894 local_part=CALLER domain=myhost.test.ex
 895 checking local_parts
 896 ut8 router skipped: local_parts mismatch
 897 --------> real router <--------
 898 local_part=CALLER domain=myhost.test.ex
 899 calling real router
 900 real router called for CALLER@myhost.test.ex
 901   domain = myhost.test.ex
 902 set transport real
 903 queued for real transport: local_part = CALLER
 904 domain = myhost.test.ex
 905   errors_to=NULL
 906   domain_data=NULL local_part_data=NULL
 907 routed by real router
 908   envelope to: CALLER@myhost.test.ex
 909   transport: real
 910 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 911 After routing:
 912   Local deliveries:
 913     CALLER@myhost.test.ex
 914   Remote deliveries:
 915   Failed addresses:
 916   Deferred addresses:
 917 locking TESTSUITE/spool/db/retry.lockfile
 918 LOG: MAIN
 919   => CALLER <CALLER@myhost.test.ex> P=<> R=real T=real
 920 LOG: MAIN
 921   Completed
 922 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 923 >>>>>>>>>>>>>>>> Exim pid=p1237 (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>>
 924 LOG: MAIN
 925   d3@myhost.test.ex <d3@myhost.test.ex>: error ignored
 926 log writing disabled
 927 LOG: MAIN
 928   b1@myhost.test.ex <b1@myhost.test.ex>: error ignored
 929 log writing disabled
 930 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 931 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 932 Exim version x.yz ....
 933 configuration file is TESTSUITE/test-config
 934 admin user
 935 LOG: MAIN
 936   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
 937 Exim version x.yz ....
 938 configuration file is TESTSUITE/test-config
 939 trusted user
 940 admin user
 941 dropping to exim gid; retaining priv uid
 942 locking TESTSUITE/spool/db/retry.lockfile
 943 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 944 Considering: h1@myhost.test.ex
 945 unique = h1@myhost.test.ex
 946 h1@myhost.test.ex: queued for routing
 947 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 948 routing h1@myhost.test.ex
 949 --------> u1 router <--------
 950 local_part=h1 domain=myhost.test.ex
 951 checking local_parts
 952 u1 router skipped: local_parts mismatch
 953 --------> ut2 router <--------
 954 local_part=h1 domain=myhost.test.ex
 955 checking local_parts
 956 ut2 router skipped: local_parts mismatch
 957 --------> ut3 router <--------
 958 local_part=h1 domain=myhost.test.ex
 959 checking local_parts
 960 ut3 router skipped: local_parts mismatch
 961 --------> ut4 router <--------
 962 local_part=h1 domain=myhost.test.ex
 963 checking local_parts
 964 ut4 router skipped: local_parts mismatch
 965 --------> ut5 router <--------
 966 local_part=h1 domain=myhost.test.ex
 967 checking local_parts
 968 ut5 router skipped: local_parts mismatch
 969 --------> ut6 router <--------
 970 local_part=h1 domain=myhost.test.ex
 971 checking local_parts
 972 ut6 router skipped: local_parts mismatch
 973 --------> ut7 router <--------
 974 local_part=h1 domain=myhost.test.ex
 975 checking local_parts
 976 ut7 router skipped: local_parts mismatch
 977 --------> ut8 router <--------
 978 local_part=h1 domain=myhost.test.ex
 979 checking local_parts
 980 calling ut8 router
 981 ut8 router called for h1@myhost.test.ex
 982   domain = myhost.test.ex
 983 forced expansion failure - ignoring errors_to
 984 set transport ut1
 985 queued for ut1 transport: local_part = h1
 986 domain = myhost.test.ex
 987   errors_to=NULL
 988   domain_data=NULL local_part_data=^h\d
 989 routed by ut8 router (unseen)
 990   envelope to: h1@myhost.test.ex
 991   transport: ut1
 992 "unseen" set: replicated h1@myhost.test.ex
 993 locking TESTSUITE/spool/db/retry.lockfile
 994 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 995 Considering: h1@myhost.test.ex
 996 unique = \0\h1@myhost.test.ex
 997 h1@myhost.test.ex: queued for routing
 998 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 999 routing h1@myhost.test.ex
1000 --------> real router <--------
1001 local_part=h1 domain=myhost.test.ex
1002 calling real router
1003 real router called for h1@myhost.test.ex
1004   domain = myhost.test.ex
1005 set transport real
1006 queued for real transport: local_part = h1
1007 domain = myhost.test.ex
1008   errors_to=NULL
1009   domain_data=NULL local_part_data=NULL
1010 routed by real router
1011   envelope to: h1@myhost.test.ex
1012   transport: real
1013 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
1014 After routing:
1015   Local deliveries:
1016     h1@myhost.test.ex
1017     h1@myhost.test.ex
1018   Remote deliveries:
1019   Failed addresses:
1020   Deferred addresses:
1021 locking TESTSUITE/spool/db/retry.lockfile
1022 LOG: MAIN
1023   => h1 <h1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=real T=real
1024 locking TESTSUITE/spool/db/retry.lockfile
1025 LOG: MAIN
1026   => h1 <h1@myhost.test.ex> P=<CALLER@myhost.test.ex> R=ut8 T=ut1
1027 log writing disabled
1028 LOG: MAIN
1029   Completed
1030 >>>>>>>>>>>>>>>> Exim pid=p1240 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
1031 >>>>>>>>>>>>>>>> Exim pid=p1239 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>