test/stderr/0183

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=pppp
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 dropping to exim gid; retaining priv uid
   7 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
   8 sender address = CALLER@myhost.test.ex
   9 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
  10 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  11 Testing userx@test.again.dns
  12 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  13 Considering userx@test.again.dns
  14 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  15 routing userx@test.again.dns
  16 --------> srv router <--------
  17 local_part=userx domain=test.again.dns
  18 checking local_parts
  19 userx in "^srv"? no (end of list)
  20 srv router skipped: local_parts mismatch
  21 --------> useryz router <--------
  22 local_part=userx domain=test.again.dns
  23 checking local_parts
  24 userx in "usery:userz"? no (end of list)
  25 useryz router skipped: local_parts mismatch
  26 --------> lookuphost router <--------
  27 local_part=userx domain=test.again.dns
  28 checking local_parts
  29 userx in "!userd"? yes (end of list)
  30 calling lookuphost router
  31 lookuphost router called for userx@test.again.dns
  32   domain = test.again.dns
  33 test.again.dns in "*"? yes (matched "*")
  34 DNS lookup of test.again.dns (MX) using fakens
  35 fresh-exec forking for child-open
  36 postfork: child-open
  37 fresh-exec forked for child-open: npppp
  38 DNS lookup of test.again.dns (MX) gave TRY_AGAIN
  39 test.again.dns in dns_again_means_nonexist? no (option unset)
  40 returning DNS_AGAIN
  41  writing neg-cache entry for test.again.dns-MX-xxxx, ttl -1
  42 lookuphost router: defer for userx@test.again.dns
  43   message: host lookup did not complete
  44 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  45 Testing abcd@test.again.dns
  46 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  47 Considering abcd@test.again.dns
  48 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  49 routing abcd@test.again.dns
  50 --------> srv router <--------
  51 local_part=abcd domain=test.again.dns
  52 checking local_parts
  53 abcd in "^srv"? no (end of list)
  54 srv router skipped: local_parts mismatch
  55 --------> useryz router <--------
  56 local_part=abcd domain=test.again.dns
  57 checking local_parts
  58 abcd in "usery:userz"? no (end of list)
  59 useryz router skipped: local_parts mismatch
  60 --------> lookuphost router <--------
  61 local_part=abcd domain=test.again.dns
  62 checking local_parts
  63 abcd in "!userd"? yes (end of list)
  64 calling lookuphost router
  65 lookuphost router called for abcd@test.again.dns
  66   domain = test.again.dns
  67 test.again.dns in "*"? yes (matched "*")
  68 DNS lookup of test.again.dns-MX: using cached value DNS_AGAIN
  69 lookuphost router: defer for abcd@test.again.dns
  70   message: host lookup did not complete
  71 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  72 Testing abcd@ten-1.test.ex
  73 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  74 Considering abcd@ten-1.test.ex
  75 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  76 routing abcd@ten-1.test.ex
  77 --------> srv router <--------
  78 local_part=abcd domain=ten-1.test.ex
  79 checking local_parts
  80 abcd in "^srv"? no (end of list)
  81 srv router skipped: local_parts mismatch
  82 --------> useryz router <--------
  83 local_part=abcd domain=ten-1.test.ex
  84 checking local_parts
  85 abcd in "usery:userz"? no (end of list)
  86 useryz router skipped: local_parts mismatch
  87 --------> lookuphost router <--------
  88 local_part=abcd domain=ten-1.test.ex
  89 checking local_parts
  90 abcd in "!userd"? yes (end of list)
  91 calling lookuphost router
  92 lookuphost router called for abcd@ten-1.test.ex
  93   domain = ten-1.test.ex
  94 ten-1.test.ex in "*"? yes (matched "*")
  95 DNS lookup of ten-1.test.ex (MX) using fakens
  96 fresh-exec forking for child-open
  97 postfork: child-open
  98 fresh-exec forked for child-open: npppp
  99 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
 100 returning DNS_NODATA
 101 faking res_search(MX) response length as 65535
 102  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
 103 ten-1.test.ex (MX resp) DNSSEC
 104 DNS lookup of ten-1.test.ex (A) using fakens
 105 fresh-exec forking for child-open
 106 postfork: child-open
 107 fresh-exec forked for child-open: npppp
 108 DNS lookup of ten-1.test.ex (A) succeeded
 109 fully qualified name = ten-1.test.ex
 110 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 111 set transport smtp
 112 queued for smtp transport: local_part = abcd
 113 domain = ten-1.test.ex
 114   errors_to=NULL
 115   domain_data=NULL localpart_data=NULL
 116 routed by lookuphost router
 117   envelope to: abcd@ten-1.test.ex
 118   transport: smtp
 119   host ten-1.test.ex [V4NET.0.0.1]
 120 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 121 Testing usery@test.again.dns
 122 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 123 Considering usery@test.again.dns
 124 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 125 routing usery@test.again.dns
 126 --------> srv router <--------
 127 local_part=usery domain=test.again.dns
 128 checking local_parts
 129 usery in "^srv"? no (end of list)
 130 srv router skipped: local_parts mismatch
 131 --------> useryz router <--------
 132 local_part=usery domain=test.again.dns
 133 checking local_parts
 134 usery in "usery:userz"? yes (matched "usery")
 135 calling useryz router
 136 useryz router called for usery@test.again.dns
 137   domain = test.again.dns
 138 route_item = * $domain bydns
 139 test.again.dns in "*"? yes (matched "*")
 140 original list of hosts = '$domain' options = 'bydns'
 141 expanded list of hosts = 'test.again.dns' options = 'bydns'
 142 set transport smtp
 143 finding IP address for test.again.dns
 144 doing DNS lookup
 145 test.again.dns in "*"? yes (matched "*")
 146 DNS lookup of test.again.dns (A) using fakens
 147 fresh-exec forking for child-open
 148 postfork: child-open
 149 fresh-exec forked for child-open: npppp
 150 DNS lookup of test.again.dns (A) gave TRY_AGAIN
 151 test.again.dns in dns_again_means_nonexist? no (option unset)
 152 returning DNS_AGAIN
 153  writing neg-cache entry for test.again.dns-A-xxxx, ttl -1
 154 useryz router: defer for usery@test.again.dns
 155   message: host lookup for test.again.dns did not complete (DNS timeout?)
 156 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 157 Testing userz@test.again.dns
 158 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 159 Considering userz@test.again.dns
 160 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 161 routing userz@test.again.dns
 162 --------> srv router <--------
 163 local_part=userz domain=test.again.dns
 164 checking local_parts
 165 userz in "^srv"? no (end of list)
 166 srv router skipped: local_parts mismatch
 167 --------> useryz router <--------
 168 local_part=userz domain=test.again.dns
 169 checking local_parts
 170 userz in "usery:userz"? yes (matched "userz")
 171 calling useryz router
 172 useryz router called for userz@test.again.dns
 173   domain = test.again.dns
 174 route_item = * $domain bydns
 175 test.again.dns in "*"? yes (matched "*")
 176 original list of hosts = '$domain' options = 'bydns'
 177 expanded list of hosts = 'test.again.dns' options = 'bydns'
 178 finding IP address for test.again.dns
 179 doing DNS lookup
 180 test.again.dns in "*"? yes (matched "*")
 181 DNS lookup of test.again.dns-A: using cached value DNS_AGAIN
 182 useryz router: defer for userz@test.again.dns
 183   message: host lookup for test.again.dns did not complete (DNS timeout?)
 184 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 185 Testing xyz@ten-1.test.ex
 186 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 187 Considering xyz@ten-1.test.ex
 188 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 189 routing xyz@ten-1.test.ex
 190 --------> srv router <--------
 191 local_part=xyz domain=ten-1.test.ex
 192 checking local_parts
 193 xyz in "^srv"? no (end of list)
 194 srv router skipped: local_parts mismatch
 195 --------> useryz router <--------
 196 local_part=xyz domain=ten-1.test.ex
 197 checking local_parts
 198 xyz in "usery:userz"? no (end of list)
 199 useryz router skipped: local_parts mismatch
 200 --------> lookuphost router <--------
 201 local_part=xyz domain=ten-1.test.ex
 202 checking local_parts
 203 xyz in "!userd"? yes (end of list)
 204 calling lookuphost router
 205 lookuphost router called for xyz@ten-1.test.ex
 206   domain = ten-1.test.ex
 207 ten-1.test.ex in "*"? yes (matched "*")
 208 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
 209 DNS lookup of ten-1.test.ex (A) using fakens
 210 fresh-exec forking for child-open
 211 postfork: child-open
 212 fresh-exec forked for child-open: npppp
 213 DNS lookup of ten-1.test.ex (A) succeeded
 214 fully qualified name = ten-1.test.ex
 215 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 216 queued for smtp transport: local_part = xyz
 217 domain = ten-1.test.ex
 218   errors_to=NULL
 219   domain_data=NULL localpart_data=NULL
 220 routed by lookuphost router
 221   envelope to: xyz@ten-1.test.ex
 222   transport: smtp
 223   host ten-1.test.ex [V4NET.0.0.1]
 224 search_tidyup called
 225 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
 226 Exim version x.yz ....
 227 changed uid/gid: forcing real = effective
 228   uid=uuuu gid=CALLER_GID pid=pppp
 229 configuration file is TESTSUITE/test-config
 230 admin user
 231 dropping to exim gid; retaining priv uid
 232 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 233 sender address = CALLER@myhost.test.ex
 234 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 235 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 236 Testing userx@test.fail.dns
 237 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 238 Considering userx@test.fail.dns
 239 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 240 routing userx@test.fail.dns
 241 --------> srv router <--------
 242 local_part=userx domain=test.fail.dns
 243 checking local_parts
 244 userx in "^srv"? no (end of list)
 245 srv router skipped: local_parts mismatch
 246 --------> useryz router <--------
 247 local_part=userx domain=test.fail.dns
 248 checking local_parts
 249 userx in "usery:userz"? no (end of list)
 250 useryz router skipped: local_parts mismatch
 251 --------> lookuphost router <--------
 252 local_part=userx domain=test.fail.dns
 253 checking local_parts
 254 userx in "!userd"? yes (end of list)
 255 calling lookuphost router
 256 lookuphost router called for userx@test.fail.dns
 257   domain = test.fail.dns
 258 test.fail.dns in "*"? yes (matched "*")
 259 DNS lookup of test.fail.dns (MX) using fakens
 260 fresh-exec forking for child-open
 261 postfork: child-open
 262 fresh-exec forked for child-open: npppp
 263 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
 264 returning DNS_FAIL
 265  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
 266 lookuphost router: defer for userx@test.fail.dns
 267   message: host lookup did not complete
 268 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 269 Testing abcd@test.fail.dns
 270 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 271 Considering abcd@test.fail.dns
 272 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 273 routing abcd@test.fail.dns
 274 --------> srv router <--------
 275 local_part=abcd domain=test.fail.dns
 276 checking local_parts
 277 abcd in "^srv"? no (end of list)
 278 srv router skipped: local_parts mismatch
 279 --------> useryz router <--------
 280 local_part=abcd domain=test.fail.dns
 281 checking local_parts
 282 abcd in "usery:userz"? no (end of list)
 283 useryz router skipped: local_parts mismatch
 284 --------> lookuphost router <--------
 285 local_part=abcd domain=test.fail.dns
 286 checking local_parts
 287 abcd in "!userd"? yes (end of list)
 288 calling lookuphost router
 289 lookuphost router called for abcd@test.fail.dns
 290   domain = test.fail.dns
 291 test.fail.dns in "*"? yes (matched "*")
 292 DNS lookup of test.fail.dns-MX: using cached value DNS_FAIL
 293 lookuphost router: defer for abcd@test.fail.dns
 294   message: host lookup did not complete
 295 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 296 Testing abcd@ten-1.test.ex
 297 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 298 Considering abcd@ten-1.test.ex
 299 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 300 routing abcd@ten-1.test.ex
 301 --------> srv router <--------
 302 local_part=abcd domain=ten-1.test.ex
 303 checking local_parts
 304 abcd in "^srv"? no (end of list)
 305 srv router skipped: local_parts mismatch
 306 --------> useryz router <--------
 307 local_part=abcd domain=ten-1.test.ex
 308 checking local_parts
 309 abcd in "usery:userz"? no (end of list)
 310 useryz router skipped: local_parts mismatch
 311 --------> lookuphost router <--------
 312 local_part=abcd domain=ten-1.test.ex
 313 checking local_parts
 314 abcd in "!userd"? yes (end of list)
 315 calling lookuphost router
 316 lookuphost router called for abcd@ten-1.test.ex
 317   domain = ten-1.test.ex
 318 ten-1.test.ex in "*"? yes (matched "*")
 319 DNS lookup of ten-1.test.ex (MX) using fakens
 320 fresh-exec forking for child-open
 321 postfork: child-open
 322 fresh-exec forked for child-open: npppp
 323 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
 324 returning DNS_NODATA
 325 faking res_search(MX) response length as 65535
 326  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
 327 ten-1.test.ex (MX resp) DNSSEC
 328 DNS lookup of ten-1.test.ex (A) using fakens
 329 fresh-exec forking for child-open
 330 postfork: child-open
 331 fresh-exec forked for child-open: npppp
 332 DNS lookup of ten-1.test.ex (A) succeeded
 333 fully qualified name = ten-1.test.ex
 334 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 335 set transport smtp
 336 queued for smtp transport: local_part = abcd
 337 domain = ten-1.test.ex
 338   errors_to=NULL
 339   domain_data=NULL localpart_data=NULL
 340 routed by lookuphost router
 341   envelope to: abcd@ten-1.test.ex
 342   transport: smtp
 343   host ten-1.test.ex [V4NET.0.0.1]
 344 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 345 Testing usery@test.fail.dns
 346 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 347 Considering usery@test.fail.dns
 348 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 349 routing usery@test.fail.dns
 350 --------> srv router <--------
 351 local_part=usery domain=test.fail.dns
 352 checking local_parts
 353 usery in "^srv"? no (end of list)
 354 srv router skipped: local_parts mismatch
 355 --------> useryz router <--------
 356 local_part=usery domain=test.fail.dns
 357 checking local_parts
 358 usery in "usery:userz"? yes (matched "usery")
 359 calling useryz router
 360 useryz router called for usery@test.fail.dns
 361   domain = test.fail.dns
 362 route_item = * $domain bydns
 363 test.fail.dns in "*"? yes (matched "*")
 364 original list of hosts = '$domain' options = 'bydns'
 365 expanded list of hosts = 'test.fail.dns' options = 'bydns'
 366 set transport smtp
 367 finding IP address for test.fail.dns
 368 doing DNS lookup
 369 test.fail.dns in "*"? yes (matched "*")
 370 DNS lookup of test.fail.dns (A) using fakens
 371 fresh-exec forking for child-open
 372 postfork: child-open
 373 fresh-exec forked for child-open: npppp
 374 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
 375 returning DNS_FAIL
 376  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
 377 useryz router: defer for usery@test.fail.dns
 378   message: host lookup for test.fail.dns did not complete (DNS timeout?)
 379 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 380 Testing userz@test.fail.dns
 381 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 382 Considering userz@test.fail.dns
 383 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 384 routing userz@test.fail.dns
 385 --------> srv router <--------
 386 local_part=userz domain=test.fail.dns
 387 checking local_parts
 388 userz in "^srv"? no (end of list)
 389 srv router skipped: local_parts mismatch
 390 --------> useryz router <--------
 391 local_part=userz domain=test.fail.dns
 392 checking local_parts
 393 userz in "usery:userz"? yes (matched "userz")
 394 calling useryz router
 395 useryz router called for userz@test.fail.dns
 396   domain = test.fail.dns
 397 route_item = * $domain bydns
 398 test.fail.dns in "*"? yes (matched "*")
 399 original list of hosts = '$domain' options = 'bydns'
 400 expanded list of hosts = 'test.fail.dns' options = 'bydns'
 401 finding IP address for test.fail.dns
 402 doing DNS lookup
 403 test.fail.dns in "*"? yes (matched "*")
 404 DNS lookup of test.fail.dns-A: using cached value DNS_FAIL
 405 useryz router: defer for userz@test.fail.dns
 406   message: host lookup for test.fail.dns did not complete (DNS timeout?)
 407 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 408 Testing xyz@ten-1.test.ex
 409 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 410 Considering xyz@ten-1.test.ex
 411 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 412 routing xyz@ten-1.test.ex
 413 --------> srv router <--------
 414 local_part=xyz domain=ten-1.test.ex
 415 checking local_parts
 416 xyz in "^srv"? no (end of list)
 417 srv router skipped: local_parts mismatch
 418 --------> useryz router <--------
 419 local_part=xyz domain=ten-1.test.ex
 420 checking local_parts
 421 xyz in "usery:userz"? no (end of list)
 422 useryz router skipped: local_parts mismatch
 423 --------> lookuphost router <--------
 424 local_part=xyz domain=ten-1.test.ex
 425 checking local_parts
 426 xyz in "!userd"? yes (end of list)
 427 calling lookuphost router
 428 lookuphost router called for xyz@ten-1.test.ex
 429   domain = ten-1.test.ex
 430 ten-1.test.ex in "*"? yes (matched "*")
 431 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
 432 DNS lookup of ten-1.test.ex (A) using fakens
 433 fresh-exec forking for child-open
 434 postfork: child-open
 435 fresh-exec forked for child-open: npppp
 436 DNS lookup of ten-1.test.ex (A) succeeded
 437 fully qualified name = ten-1.test.ex
 438 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 439 queued for smtp transport: local_part = xyz
 440 domain = ten-1.test.ex
 441   errors_to=NULL
 442   domain_data=NULL localpart_data=NULL
 443 routed by lookuphost router
 444   envelope to: xyz@ten-1.test.ex
 445   transport: smtp
 446   host ten-1.test.ex [V4NET.0.0.1]
 447 search_tidyup called
 448 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
 449 Exim version x.yz ....
 450 changed uid/gid: forcing real = effective
 451   uid=uuuu gid=CALLER_GID pid=pppp
 452 configuration file is TESTSUITE/test-config
 453 admin user
 454 dropping to exim gid; retaining priv uid
 455 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 456 sender address = CALLER@myhost.test.ex
 457 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 459 Testing userx@nonexist.test.ex
 460 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 461 Considering userx@nonexist.test.ex
 462 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 463 routing userx@nonexist.test.ex
 464 --------> srv router <--------
 465 local_part=userx domain=nonexist.test.ex
 466 checking local_parts
 467 userx in "^srv"? no (end of list)
 468 srv router skipped: local_parts mismatch
 469 --------> useryz router <--------
 470 local_part=userx domain=nonexist.test.ex
 471 checking local_parts
 472 userx in "usery:userz"? no (end of list)
 473 useryz router skipped: local_parts mismatch
 474 --------> lookuphost router <--------
 475 local_part=userx domain=nonexist.test.ex
 476 checking local_parts
 477 userx in "!userd"? yes (end of list)
 478 calling lookuphost router
 479 lookuphost router called for userx@nonexist.test.ex
 480   domain = nonexist.test.ex
 481 nonexist.test.ex in "*"? yes (matched "*")
 482 DNS lookup of nonexist.test.ex (MX) using fakens
 483 fresh-exec forking for child-open
 484 postfork: child-open
 485 fresh-exec forked for child-open: npppp
 486 DNS lookup of nonexist.test.ex (MX) gave HOST_NOT_FOUND
 487 returning DNS_NOMATCH
 488 faking res_search(MX) response length as 65535
 489  writing neg-cache entry for nonexist.test.ex-MX-xxxx, ttl 3000
 490 lookuphost router declined for userx@nonexist.test.ex
 491 "more" is false: skipping remaining routers
 492 no more routers
 493 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 494 Testing abcd@nonexist.test.ex
 495 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 496 Considering abcd@nonexist.test.ex
 497 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 498 routing abcd@nonexist.test.ex
 499 --------> srv router <--------
 500 local_part=abcd domain=nonexist.test.ex
 501 checking local_parts
 502 abcd in "^srv"? no (end of list)
 503 srv router skipped: local_parts mismatch
 504 --------> useryz router <--------
 505 local_part=abcd domain=nonexist.test.ex
 506 checking local_parts
 507 abcd in "usery:userz"? no (end of list)
 508 useryz router skipped: local_parts mismatch
 509 --------> lookuphost router <--------
 510 local_part=abcd domain=nonexist.test.ex
 511 checking local_parts
 512 abcd in "!userd"? yes (end of list)
 513 calling lookuphost router
 514 lookuphost router called for abcd@nonexist.test.ex
 515   domain = nonexist.test.ex
 516 nonexist.test.ex in "*"? yes (matched "*")
 517 DNS lookup of nonexist.test.ex-MX: using cached value DNS_NOMATCH
 518 lookuphost router declined for abcd@nonexist.test.ex
 519 "more" is false: skipping remaining routers
 520 no more routers
 521 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 522 Testing abcd@ten-1.test.ex
 523 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 524 Considering abcd@ten-1.test.ex
 525 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 526 routing abcd@ten-1.test.ex
 527 --------> srv router <--------
 528 local_part=abcd domain=ten-1.test.ex
 529 checking local_parts
 530 abcd in "^srv"? no (end of list)
 531 srv router skipped: local_parts mismatch
 532 --------> useryz router <--------
 533 local_part=abcd domain=ten-1.test.ex
 534 checking local_parts
 535 abcd in "usery:userz"? no (end of list)
 536 useryz router skipped: local_parts mismatch
 537 --------> lookuphost router <--------
 538 local_part=abcd domain=ten-1.test.ex
 539 checking local_parts
 540 abcd in "!userd"? yes (end of list)
 541 calling lookuphost router
 542 lookuphost router called for abcd@ten-1.test.ex
 543   domain = ten-1.test.ex
 544 ten-1.test.ex in "*"? yes (matched "*")
 545 DNS lookup of ten-1.test.ex (MX) using fakens
 546 fresh-exec forking for child-open
 547 postfork: child-open
 548 fresh-exec forked for child-open: npppp
 549 DNS lookup of ten-1.test.ex (MX) gave NO_DATA
 550 returning DNS_NODATA
 551 faking res_search(MX) response length as 65535
 552  writing neg-cache entry for ten-1.test.ex-MX-xxxx, ttl 3000
 553 ten-1.test.ex (MX resp) DNSSEC
 554 DNS lookup of ten-1.test.ex (A) using fakens
 555 fresh-exec forking for child-open
 556 postfork: child-open
 557 fresh-exec forked for child-open: npppp
 558 DNS lookup of ten-1.test.ex (A) succeeded
 559 fully qualified name = ten-1.test.ex
 560 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 561 set transport smtp
 562 queued for smtp transport: local_part = abcd
 563 domain = ten-1.test.ex
 564   errors_to=NULL
 565   domain_data=NULL localpart_data=NULL
 566 routed by lookuphost router
 567   envelope to: abcd@ten-1.test.ex
 568   transport: smtp
 569   host ten-1.test.ex [V4NET.0.0.1]
 570 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 571 Testing usery@nonexist.test.ex
 572 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 573 Considering usery@nonexist.test.ex
 574 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 575 routing usery@nonexist.test.ex
 576 --------> srv router <--------
 577 local_part=usery domain=nonexist.test.ex
 578 checking local_parts
 579 usery in "^srv"? no (end of list)
 580 srv router skipped: local_parts mismatch
 581 --------> useryz router <--------
 582 local_part=usery domain=nonexist.test.ex
 583 checking local_parts
 584 usery in "usery:userz"? yes (matched "usery")
 585 calling useryz router
 586 useryz router called for usery@nonexist.test.ex
 587   domain = nonexist.test.ex
 588 route_item = * $domain bydns
 589 nonexist.test.ex in "*"? yes (matched "*")
 590 original list of hosts = '$domain' options = 'bydns'
 591 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
 592 set transport smtp
 593 finding IP address for nonexist.test.ex
 594 doing DNS lookup
 595 nonexist.test.ex in "*"? yes (matched "*")
 596 DNS lookup of nonexist.test.ex (A) using fakens
 597 fresh-exec forking for child-open
 598 postfork: child-open
 599 fresh-exec forked for child-open: npppp
 600 DNS lookup of nonexist.test.ex (A) gave HOST_NOT_FOUND
 601 returning DNS_NOMATCH
 602 faking res_search(A) response length as 65535
 603  writing neg-cache entry for nonexist.test.ex-A-xxxx, ttl 3000
 604 useryz router: defer for usery@nonexist.test.ex
 605   message: lookup of host "nonexist.test.ex" failed in useryz router
 606 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 607 Testing userz@nonexist.test.ex
 608 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 609 Considering userz@nonexist.test.ex
 610 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 611 routing userz@nonexist.test.ex
 612 --------> srv router <--------
 613 local_part=userz domain=nonexist.test.ex
 614 checking local_parts
 615 userz in "^srv"? no (end of list)
 616 srv router skipped: local_parts mismatch
 617 --------> useryz router <--------
 618 local_part=userz domain=nonexist.test.ex
 619 checking local_parts
 620 userz in "usery:userz"? yes (matched "userz")
 621 calling useryz router
 622 useryz router called for userz@nonexist.test.ex
 623   domain = nonexist.test.ex
 624 route_item = * $domain bydns
 625 nonexist.test.ex in "*"? yes (matched "*")
 626 original list of hosts = '$domain' options = 'bydns'
 627 expanded list of hosts = 'nonexist.test.ex' options = 'bydns'
 628 finding IP address for nonexist.test.ex
 629 doing DNS lookup
 630 nonexist.test.ex in "*"? yes (matched "*")
 631 DNS lookup of nonexist.test.ex-A: using cached value DNS_NOMATCH
 632 useryz router: defer for userz@nonexist.test.ex
 633   message: lookup of host "nonexist.test.ex" failed in useryz router
 634 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 635 Testing xyz@ten-1.test.ex
 636 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 637 Considering xyz@ten-1.test.ex
 638 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 639 routing xyz@ten-1.test.ex
 640 --------> srv router <--------
 641 local_part=xyz domain=ten-1.test.ex
 642 checking local_parts
 643 xyz in "^srv"? no (end of list)
 644 srv router skipped: local_parts mismatch
 645 --------> useryz router <--------
 646 local_part=xyz domain=ten-1.test.ex
 647 checking local_parts
 648 xyz in "usery:userz"? no (end of list)
 649 useryz router skipped: local_parts mismatch
 650 --------> lookuphost router <--------
 651 local_part=xyz domain=ten-1.test.ex
 652 checking local_parts
 653 xyz in "!userd"? yes (end of list)
 654 calling lookuphost router
 655 lookuphost router called for xyz@ten-1.test.ex
 656   domain = ten-1.test.ex
 657 ten-1.test.ex in "*"? yes (matched "*")
 658 DNS lookup of ten-1.test.ex-MX: using cached value DNS_NODATA
 659 DNS lookup of ten-1.test.ex (A) using fakens
 660 fresh-exec forking for child-open
 661 postfork: child-open
 662 fresh-exec forked for child-open: npppp
 663 DNS lookup of ten-1.test.ex (A) succeeded
 664 fully qualified name = ten-1.test.ex
 665 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx
 666 queued for smtp transport: local_part = xyz
 667 domain = ten-1.test.ex
 668   errors_to=NULL
 669   domain_data=NULL localpart_data=NULL
 670 routed by lookuphost router
 671   envelope to: xyz@ten-1.test.ex
 672   transport: smtp
 673   host ten-1.test.ex [V4NET.0.0.1]
 674 search_tidyup called
 675 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=2 >>>>>>>>>>>>>>>>
 676 Exim version x.yz ....
 677 changed uid/gid: forcing real = effective
 678   uid=uuuu gid=CALLER_GID pid=pppp
 679 configuration file is TESTSUITE/test-config
 680 admin user
 681 dropping to exim gid; retaining priv uid
 682 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 683 sender address = CALLER@myhost.test.ex
 684 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 685 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 686 Testing srv@test.again.dns
 687 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 688 Considering srv@test.again.dns
 689 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 690 routing srv@test.again.dns
 691 --------> srv router <--------
 692 local_part=srv domain=test.again.dns
 693 checking local_parts
 694 srv in "^srv"? yes (matched "^srv")
 695 calling srv router
 696 srv router called for srv@test.again.dns
 697   domain = test.again.dns
 698 test.again.dns in "*"? yes (matched "*")
 699 DNS lookup of _smtp._tcp.test.again.dns (SRV) using fakens
 700 fresh-exec forking for child-open
 701 postfork: child-open
 702 fresh-exec forked for child-open: npppp
 703 DNS lookup of _smtp._tcp.test.again.dns (SRV) gave TRY_AGAIN
 704 _smtp._tcp.test.again.dns in dns_again_means_nonexist? no (option unset)
 705 returning DNS_AGAIN
 706  writing neg-cache entry for _smtp._tcp.test.again.dns-SRV-xxxx, ttl -1
 707 test.again.dns in "test.fail.dns"? no (end of list)
 708 srv router: defer for srv@test.again.dns
 709   message: host lookup did not complete
 710 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 711 Testing srv@test.fail.dns
 712 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 713 Considering srv@test.fail.dns
 714 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 715 routing srv@test.fail.dns
 716 --------> srv router <--------
 717 local_part=srv domain=test.fail.dns
 718 checking local_parts
 719 srv in "^srv"? yes (matched "^srv")
 720 calling srv router
 721 srv router called for srv@test.fail.dns
 722   domain = test.fail.dns
 723 test.fail.dns in "*"? yes (matched "*")
 724 DNS lookup of _smtp._tcp.test.fail.dns (SRV) using fakens
 725 fresh-exec forking for child-open
 726 postfork: child-open
 727 fresh-exec forked for child-open: npppp
 728 DNS lookup of _smtp._tcp.test.fail.dns (SRV) gave NO_RECOVERY
 729 returning DNS_FAIL
 730  writing neg-cache entry for _smtp._tcp.test.fail.dns-SRV-xxxx, ttl -1
 731 test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
 732 DNS_FAIL treated as DNS_NODATA (domain in srv_fail_domains)
 733 DNS lookup of test.fail.dns (MX) using fakens
 734 fresh-exec forking for child-open
 735 postfork: child-open
 736 fresh-exec forked for child-open: npppp
 737 DNS lookup of test.fail.dns (MX) gave NO_RECOVERY
 738 returning DNS_FAIL
 739  writing neg-cache entry for test.fail.dns-MX-xxxx, ttl -1
 740 test.fail.dns in "test.fail.dns"? yes (matched "test.fail.dns")
 741 DNS_FAIL treated as DNS_NODATA (domain in mx_fail_domains)
 742 DNS lookup of test.fail.dns (A) using fakens
 743 fresh-exec forking for child-open
 744 postfork: child-open
 745 fresh-exec forked for child-open: npppp
 746 DNS lookup of test.fail.dns (A) gave NO_RECOVERY
 747 returning DNS_FAIL
 748  writing neg-cache entry for test.fail.dns-A-xxxx, ttl -1
 749 srv router: defer for srv@test.fail.dns
 750   message: host lookup did not complete
 751 search_tidyup called
 752 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=1 >>>>>>>>>>>>>>>>
 753 Exim version x.yz ....
 754 changed uid/gid: forcing real = effective
 755   uid=uuuu gid=CALLER_GID pid=pppp
 756 configuration file is TESTSUITE/test-config
 757 admin user
 758 dropping to exim gid; retaining priv uid
 759 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 760 sender address = CALLER@myhost.test.ex
 761 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
 762 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 763 Testing userx@nonexist.example.com
 764 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 765 Considering userx@nonexist.example.com
 766 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 767 routing userx@nonexist.example.com
 768 --------> srv router <--------
 769 local_part=userx domain=nonexist.example.com
 770 checking local_parts
 771 userx in "^srv"? no (end of list)
 772 srv router skipped: local_parts mismatch
 773 --------> useryz router <--------
 774 local_part=userx domain=nonexist.example.com
 775 checking local_parts
 776 userx in "usery:userz"? no (end of list)
 777 useryz router skipped: local_parts mismatch
 778 --------> lookuphost router <--------
 779 local_part=userx domain=nonexist.example.com
 780 checking local_parts
 781 userx in "!userd"? yes (end of list)
 782 calling lookuphost router
 783 lookuphost router called for userx@nonexist.example.com
 784   domain = nonexist.example.com
 785 nonexist.example.com in "*"? yes (matched "*")
 786 DNS lookup of nonexist.example.com (MX) using fakens
 787 fresh-exec forking for child-open
 788 postfork: child-open
 789 fresh-exec forked for child-open: npppp
 790 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
 791 returning DNS_NOMATCH
 792 faking res_search(MX) response length as 65535
 793  writing neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
 794 lookuphost router declined for userx@nonexist.example.com
 795 "more" is false: skipping remaining routers
 796 no more routers
 797 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 798 Testing userd@nonexist.example.com
 799 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 800 Considering userd@nonexist.example.com
 801 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 802 routing userd@nonexist.example.com
 803 --------> srv router <--------
 804 local_part=userd domain=nonexist.example.com
 805 checking local_parts
 806 userd in "^srv"? no (end of list)
 807 srv router skipped: local_parts mismatch
 808 --------> useryz router <--------
 809 local_part=userd domain=nonexist.example.com
 810 checking local_parts
 811 userd in "usery:userz"? no (end of list)
 812 useryz router skipped: local_parts mismatch
 813 --------> lookuphost router <--------
 814 local_part=userd domain=nonexist.example.com
 815 checking local_parts
 816 userd in "!userd"? no (matched "!userd")
 817 lookuphost router skipped: local_parts mismatch
 818 --------> delay router <--------
 819 local_part=userd domain=nonexist.example.com
 820 checking "condition" "${acl {delay}}"...
 821   using ACL "delay"
 822   processing "accept" (TESTSUITE/test-config 18)
 823   check delay = 3s
 824   delay modifier requests 3-second delay
 825     message: y
 826   accept: condition test succeeded in ACL "delay"
 827   end of ACL "delay": ACCEPT
 828 calling delay router
 829 delay router called for userd@nonexist.example.com
 830   domain = nonexist.example.com
 831 nonexist.example.com in "*"? yes (matched "*")
 832 DNS lookup of nonexist.example.com-MX: cached value DNS_NOMATCH past valid time
 833 DNS lookup of nonexist.example.com (MX) using fakens
 834 fresh-exec forking for child-open
 835 postfork: child-open
 836 fresh-exec forked for child-open: npppp
 837 DNS lookup of nonexist.example.com (MX) gave HOST_NOT_FOUND
 838 returning DNS_NOMATCH
 839 faking res_search(MX) response length as 65535
 840  update neg-cache entry for nonexist.example.com-MX-xxxx, ttl 2
 841 delay router declined for userd@nonexist.example.com
 842 "more" is false: skipping remaining routers
 843 no more routers
 844 search_tidyup called
 845 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=2 >>>>>>>>>>>>>>>>