test/stderr/0386

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=p1234
   4 seeking password data for user "CALLER": cache not available
   5 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
   6 list element: PATH
   7 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
   8 list element: PATH
   9 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  10 list element: PATH
  11 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  12 list element: PATH
  13 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  14 list element: PATH
  15 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  16 list element: PATH
  17 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  18 list element: PATH
  19 list element: PATH
  20 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
  21 list element: PATH
  22 configuration file is TESTSUITE/test-config
  23 trusted user
  24 admin user
  25 changed uid/gid: privilege not needed
  26   uid=EXIM_UID gid=EXIM_GID pid=p1234
  27 seeking password data for user "CALLER": using cached result
  28 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  29 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  30 sender address = CALLER@test.ex
  31 sender_fullhost = [V4NET.9.8.7]
  32 sender_rcvhost = [V4NET.9.8.7]
  33 host in hosts_connection_nolog? no (option unset)
  34 LOG: smtp_connection MAIN
  35   SMTP connection from [V4NET.9.8.7]
  36 host in host_lookup? no (option unset)
  37 set_process_info: pppp handling incoming connection from [V4NET.9.8.7]
  38 host in host_reject_connection? no (option unset)
  39 host in sender_unqualified_hosts? no (option unset)
  40 host in recipient_unqualified_hosts? no (option unset)
  41 host in helo_verify_hosts? no (option unset)
  42 host in helo_try_verify_hosts? no (option unset)
  43 host in helo_accept_junk_hosts? no (option unset)
  44 list element:
  45 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
  46 smtp_setup_msg entered
  47 SMTP<< helo test
  48 list element: @
  49 list element: @[]
  50 test in helo_lookup_domains? no (end of list)
  51 sender_fullhost = (test) [V4NET.9.8.7]
  52 sender_rcvhost = [V4NET.9.8.7] (helo=test)
  53 set_process_info: pppp handling incoming connection from (test) [V4NET.9.8.7]
  54 SMTP>> 250 myhost.test.ex Hello test [V4NET.9.8.7]
  55 SMTP<< mail from:<x@y>
  56 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  57 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  58 SMTP>> 250 OK
  59 SMTP<< rcpt to:<1@b>
  60 using ACL "chk_rcpt"
  61 processing "accept" (TESTSUITE/test-config 18)
  62 check local_parts = 1
  63 list element: 1
  64 1 in "1"? yes (matched "1")
  65 check acl = TESTSUITE/aux-fixed/0386.acl1
  66  read ACL from file TESTSUITE/aux-fixed/0386.acl1
  67  processing "accept" (TESTSUITE/test-config 44)
  68  check hosts = :
  69  list element:
  70   host in ":"? no (end of list)
  71  accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
  72  processing "deny" (TESTSUITE/test-config 44)
  73  check local_parts = ^.*[@%!/|]
  74  list element: ^.*[@%!/|]
  75  compiled caseless RE '^.*[@%!/|]' not found in local cache
  76  compiled RE '^.*[@%!/|]' saved in local cache
  77  1 in "^.*[@%!/|]"? no (end of list)
  78  deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
  79  processing "require" (TESTSUITE/test-config 44)
  80  l_message: Invalid sender
  81    message: Couldn't verify the sender
  82  check verify = sender/defer_ok
  83 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  84 Verifying x@y
  85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  86 Considering x@y
  87 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  88 routing x@y
  89 --------> r1 router <--------
  90 local_part=x domain=y
  91 calling r1 router
  92 r1 router called for x@y
  93   domain = y
  94 set transport t1
  95 queued for t1 transport: local_part = x
  96 domain = y
  97   errors_to=NULL
  98   domain_data=NULL local_part_data=NULL
  99 routed by r1 router
 100   envelope to: x@y
 101   transport: t1
 102  ----------- end verify ------------
 103  sender x@y verified ok
 104  require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
 105  processing "deny" (TESTSUITE/test-config 44)
 106    message: No such user here
 107  deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
 108  end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY
 109 accept: condition test failed in ACL "chk_rcpt"
 110 accept: endpass encountered - denying access
 111 SMTP>> 550 No such user here
 112 LOG: MAIN REJECT
 113   H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here
 114 SMTP<< rset
 115 SMTP>> 250 Reset OK
 116 SMTP<< mail from:<x@y>
 117 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 118 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 119 SMTP>> 250 OK
 120 SMTP<< rcpt to:<1@b>
 121 using ACL "chk_rcpt"
 122 processing "accept" (TESTSUITE/test-config 18)
 123 check local_parts = 1
 124 list element: 1
 125 1 in "1"? yes (matched "1")
 126 check acl = TESTSUITE/aux-fixed/0386.acl1
 127  using ACL "TESTSUITE/aux-fixed/0386.acl1"
 128  processing "accept" (TESTSUITE/test-config 44)
 129  check hosts = :
 130  list element:
 131   host in ":"? no (end of list)
 132  accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
 133  processing "deny" (TESTSUITE/test-config 44)
 134  check local_parts = ^.*[@%!/|]
 135  list element: ^.*[@%!/|]
 136  compiled caseless RE '^.*[@%!/|]' found in local cache
 137  1 in "^.*[@%!/|]"? no (end of list)
 138  deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1"
 139  processing "require" (TESTSUITE/test-config 44)
 140  l_message: Invalid sender
 141    message: Couldn't verify the sender
 142  check verify = sender/defer_ok
 143 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 144 Verifying x@y
 145 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 146 Considering x@y
 147 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 148 routing x@y
 149 --------> r1 router <--------
 150 local_part=x domain=y
 151 calling r1 router
 152 r1 router called for x@y
 153   domain = y
 154 queued for t1 transport: local_part = x
 155 domain = y
 156   errors_to=NULL
 157   domain_data=NULL local_part_data=NULL
 158 routed by r1 router
 159   envelope to: x@y
 160   transport: t1
 161  ----------- end verify ------------
 162  sender x@y verified ok
 163  require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
 164  processing "deny" (TESTSUITE/test-config 44)
 165    message: No such user here
 166  deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1"
 167  end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY
 168 accept: condition test failed in ACL "chk_rcpt"
 169 accept: endpass encountered - denying access
 170 SMTP>> 550 No such user here
 171 LOG: MAIN REJECT
 172   H=(test) [V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here
 173 SMTP<< quit
 174 SMTP>> 221 myhost.test.ex closing connection
 175 LOG: smtp_connection MAIN
 176   SMTP connection from (test) [V4NET.9.8.7] closed by QUIT
 177 search_tidyup called
 178 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
 179 Exim version x.yz ....
 180 changed uid/gid: forcing real = effective
 181   uid=uuuu gid=CALLER_GID pid=p1235
 182 seeking password data for user "CALLER": cache not available
 183 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 184 list element: PATH
 185 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 186 list element: PATH
 187 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 188 list element: PATH
 189 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 190 list element: PATH
 191 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 192 list element: PATH
 193 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 194 list element: PATH
 195 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 196 list element: PATH
 197 list element: PATH
 198 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 199 list element: PATH
 200 configuration file is TESTSUITE/test-config
 201 trusted user
 202 admin user
 203 changed uid/gid: privilege not needed
 204   uid=EXIM_UID gid=EXIM_GID pid=p1235
 205 seeking password data for user "CALLER": using cached result
 206 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 207 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
 208 sender address = NULL
 209 sender_fullhost = [V4NET.11.12.13]
 210 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
 211 set_process_info: pppp handling incoming connection from [V4NET.11.12.13] via -oMa
 212 set_process_info: pppp accepting a local SMTP message from <CALLER>
 213 host in hosts_connection_nolog? no (option unset)
 214 LOG: smtp_connection MAIN
 215   SMTP connection from CALLER
 216 host in host_lookup? no (option unset)
 217 set_process_info: pppp handling incoming connection from [V4NET.11.12.13] U=CALLER
 218 host in host_reject_connection? no (option unset)
 219 host in sender_unqualified_hosts? no (option unset)
 220 host in recipient_unqualified_hosts? no (option unset)
 221 host in helo_verify_hosts? no (option unset)
 222 host in helo_try_verify_hosts? no (option unset)
 223 host in helo_accept_junk_hosts? no (option unset)
 224 list element:
 225 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
 226 smtp_setup_msg entered
 227 SMTP<< helo test
 228 list element: @
 229 list element: @[]
 230 test in helo_lookup_domains? no (end of list)
 231 sender_fullhost = (test) [V4NET.11.12.13]
 232 sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER)
 233 set_process_info: pppp handling incoming connection from (test) [V4NET.11.12.13] U=CALLER
 234 SMTP>> 250 myhost.test.ex Hello CALLER at test [V4NET.11.12.13]
 235 SMTP<< mail from:<x@y>
 236 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 237 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 238 SMTP>> 250 OK
 239 SMTP<< rcpt to:<2@b>
 240 using ACL "chk_rcpt"
 241 processing "accept" (TESTSUITE/test-config 18)
 242 check local_parts = 1
 243 list element: 1
 244 2 in "1"? no (end of list)
 245 accept: condition test failed in ACL "chk_rcpt"
 246 processing "accept" (TESTSUITE/test-config 21)
 247 check local_parts = 2
 248 list element: 2
 249 2 in "2"? yes (matched "2")
 250 check acl = TESTSUITE/aux-fixed/0386.acl2
 251  read ACL from file TESTSUITE/aux-fixed/0386.acl2
 252  processing "warn" (TESTSUITE/test-config 44)
 253    message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
 254  l_message: found in $dnslist_domain: $dnslist_text
 255  check dnslists = rbl.test.ex
 256 dnslists check: rbl.test.ex
 257 new DNS lookup for 13.12.11.V4NET.rbl.test.ex
 258 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens
 259 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded
 260 dnslists: wrote cache entry, ttl=3
 261 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 262 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens
 263 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded
 264 => that means V4NET.11.12.13 is listed at rbl.test.ex
 265  warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 266 LOG: MAIN
 267   H=(test) [V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
 268 created log directory TESTSUITE/spool/log
 269  processing "accept" (TESTSUITE/test-config 44)
 270  accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 271  end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
 272 accept: condition test succeeded in ACL "chk_rcpt"
 273 end of ACL "chk_rcpt": ACCEPT
 274 SMTP>> 250 Accepted
 275 SMTP<< data
 276 SMTP>> 354 Enter message, ending with "." on a line by itself
 277 search_tidyup called
 278 >>Headers received:
 279
 280 qualify & rewrite recipients list
 281 global rewrite rules
 282 rewrite headers
 283 search_tidyup called
 284 >>Headers after rewriting and local additions:
 285
 286 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 287 Data file written for message 10HmaX-0005vi-00
 288 >>Generated Received: header line
 289 P Received: from [V4NET.11.12.13] (helo=test ident=CALLER)
 290         by myhost.test.ex with smtp (Exim x.yz)
 291         (envelope-from <x@y>)
 292         id 10HmaX-0005vi-00
 293         for 2@b;
 294         Tue, 2 Mar 1999 09:44:33 +0000
 295 >>Headers added by MAIL or RCPT ACL:
 296   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
 297   X-Warning: This is a test blacklisting message
 298 >>
 299 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
 300 DSN: **** SPOOL_OUT - address: <2@b> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 301 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 302 Size of headers = sss
 303 LOG: MAIN
 304   <= x@y H=(test) [V4NET.11.12.13] U=CALLER P=smtp S=sss
 305 SMTP>> 250 OK id=10HmaX-0005vi-00
 306 search_tidyup called
 307 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
 308 Exim version x.yz ....
 309 changed uid/gid: forcing real = effective
 310   uid=uuuu gid=EXIM_GID pid=p1236
 311 seeking password data for user "CALLER": cache not available
 312 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 313 list element: PATH
 314 list element: PATH
 315 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 316 list element: PATH
 317 configuration file is TESTSUITE/test-config
 318 trusted user
 319 admin user
 320 dropping to exim gid; retaining priv uid
 321 seeking password data for user "CALLER": using cached result
 322 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 323 set_process_info: pppp delivering specified messages
 324 set_process_info: pppp delivering 10HmaX-0005vi-00
 325 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 326 reading spool file 10HmaX-0005vi-00-H
 327 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
 328 sender_fullhost = (test) [V4NET.11.12.13]
 329 sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER)
 330 sender_local=0 ident=CALLER
 331 Non-recipients:
 332  Empty Tree
 333 ---- End of tree ----
 334 recipients_count=1
 335 **** SPOOL_IN - No additional fields
 336 body_linecount=1 message_linecount=8
 337 DSN: set orcpt:   flags: 0x0
 338 Delivery address list:
 339   2@b
 340  locking TESTSUITE/spool/db/retry.lockfile
 341  locked  TESTSUITE/spool/db/retry.lockfile
 342  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 343  returned from EXIM_DBOPEN: (nil)
 344  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 345 no retry data available
 346 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 347 Considering: 2@b
 348 unique = 2@b
 349 no   domain  retry record
 350 no   address retry record
 351 2@b: queued for routing
 352 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 353 routing 2@b
 354 --------> r1 router <--------
 355 local_part=2 domain=b
 356 calling r1 router
 357 r1 router called for 2@b
 358   domain = b
 359 set transport t1
 360 queued for t1 transport: local_part = 2
 361 domain = b
 362   errors_to=NULL
 363   domain_data=NULL local_part_data=NULL
 364 routed by r1 router
 365   envelope to: 2@b
 366   transport: t1
 367 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 368 After routing:
 369   Local deliveries:
 370     2@b
 371   Remote deliveries:
 372   Failed addresses:
 373   Deferred addresses:
 374 search_tidyup called
 375 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 376 --------> 2@b <--------
 377  locking TESTSUITE/spool/db/retry.lockfile
 378  locked  TESTSUITE/spool/db/retry.lockfile
 379  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 380  returned from EXIM_DBOPEN: (nil)
 381  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 382 no retry data available
 383 search_tidyup called
 384 changed uid/gid: local delivery to 2 <2@b> transport=t1
 385   uid=CALLER_UID gid=CALLER_GID pid=p1237
 386   home=NULL current=/
 387 set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1
 388 appendfile transport entered
 389 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 390   file=TESTSUITE/test-mail/2 format=unix
 391   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 392   message_suffix=\n
 393   maildir_use_size_file=no
 394   locking by lockfile fcntl
 395 de-tainting path 'TESTSUITE/test-mail/2'
 396 lock name: TESTSUITE/test-mail/2.lock
 397 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
 398 lock file created
 399 mailbox TESTSUITE/test-mail/2 is locked
 400 writing to file TESTSUITE/test-mail/2
 401 writing data block fd=dddd size=sss timeout=0
 402 cannot use sendfile for body: spoolfile not wireformat
 403 writing data block fd=dddd size=sss timeout=0
 404 writing data block fd=dddd size=sss timeout=0
 405 appendfile yields 0 with errno=dd more_errno=dd
 406 search_tidyup called
 407 journalling 2@b
 408 t1 transport returned OK for 2@b
 409 post-process 2@b (0)
 410 2@b delivered
 411 LOG: MAIN
 412   => 2 <2@b> R=r1 T=t1
 413 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 414 changed uid/gid: post-delivery tidying
 415   uid=EXIM_UID gid=EXIM_GID pid=p1236
 416 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 417 Processing retry items
 418 Succeeded addresses:
 419  2@b: no retry items
 420 Failed addresses:
 421 Deferred addresses:
 422 end of retry processing
 423 DSN: processing router : r1
 424 DSN: processing successful delivery address: 2@b
 425 DSN: Sender_address: x@y
 426 DSN: orcpt: NULL  flags: 0x0
 427 DSN: envid: NULL  ret: 0
 428 DSN: Final recipient: 2@b
 429 DSN: Remote SMTP server supports DSN: 0
 430 DSN: not sending DSN success message
 431 LOG: MAIN
 432   Completed
 433 end delivery of 10HmaX-0005vi-00
 434 search_tidyup called
 435 search_tidyup called
 436 >>>>>>>>>>>>>>>> Exim pid=p1236 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 437 smtp_setup_msg entered
 438 SMTP<< rset
 439 SMTP>> 250 Reset OK
 440 SMTP<< mail from:<x@y>
 441 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 442 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 443 SMTP>> 250 OK
 444 SMTP<< rcpt to:<2@b>
 445 using ACL "chk_rcpt"
 446 processing "accept" (TESTSUITE/test-config 18)
 447 check local_parts = 1
 448 list element: 1
 449 2 in "1"? no (end of list)
 450 accept: condition test failed in ACL "chk_rcpt"
 451 processing "accept" (TESTSUITE/test-config 21)
 452 check local_parts = 2
 453 list element: 2
 454 2 in "2"? yes (matched "2")
 455 check acl = TESTSUITE/aux-fixed/0386.acl2
 456  using ACL "TESTSUITE/aux-fixed/0386.acl2"
 457  processing "warn" (TESTSUITE/test-config 44)
 458    message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
 459  l_message: found in $dnslist_domain: $dnslist_text
 460  check dnslists = rbl.test.ex
 461 dnslists check: rbl.test.ex
 462 dnslists: using result of previous lookup
 463 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 464 => that means V4NET.11.12.13 is listed at rbl.test.ex
 465  warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 466 LOG: MAIN
 467   H=(test) [V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
 468  processing "accept" (TESTSUITE/test-config 44)
 469  accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 470  end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
 471 accept: condition test succeeded in ACL "chk_rcpt"
 472 end of ACL "chk_rcpt": ACCEPT
 473 SMTP>> 250 Accepted
 474 SMTP<< data
 475 SMTP>> 354 Enter message, ending with "." on a line by itself
 476 search_tidyup called
 477 >>Headers received:
 478
 479 qualify & rewrite recipients list
 480 global rewrite rules
 481 rewrite headers
 482 search_tidyup called
 483 >>Headers after rewriting and local additions:
 484
 485 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
 486 Data file written for message 10HmaY-0005vi-00
 487 >>Generated Received: header line
 488 P Received: from [V4NET.11.12.13] (helo=test ident=CALLER)
 489         by myhost.test.ex with smtp (Exim x.yz)
 490         (envelope-from <x@y>)
 491         id 10HmaY-0005vi-00
 492         for 2@b;
 493         Tue, 2 Mar 1999 09:44:33 +0000
 494 >>Headers added by MAIL or RCPT ACL:
 495   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
 496   X-Warning: This is a test blacklisting message
 497 >>
 498 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00
 499 DSN: **** SPOOL_OUT - address: <2@b> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 500 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
 501 Size of headers = sss
 502 LOG: MAIN
 503   <= x@y H=(test) [V4NET.11.12.13] U=CALLER P=smtp S=sss
 504 SMTP>> 250 OK id=10HmaY-0005vi-00
 505 search_tidyup called
 506 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaY-0005vi-00
 507 Exim version x.yz ....
 508 changed uid/gid: forcing real = effective
 509   uid=uuuu gid=EXIM_GID pid=p1238
 510 seeking password data for user "CALLER": cache not available
 511 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 512 list element: PATH
 513 list element: PATH
 514 list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
 515 list element: PATH
 516 configuration file is TESTSUITE/test-config
 517 trusted user
 518 admin user
 519 dropping to exim gid; retaining priv uid
 520 seeking password data for user "CALLER": using cached result
 521 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 522 set_process_info: pppp delivering specified messages
 523 set_process_info: pppp delivering 10HmaY-0005vi-00
 524 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
 525 reading spool file 10HmaY-0005vi-00-H
 526 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
 527 sender_fullhost = (test) [V4NET.11.12.13]
 528 sender_rcvhost = [V4NET.11.12.13] (helo=test ident=CALLER)
 529 sender_local=0 ident=CALLER
 530 Non-recipients:
 531  Empty Tree
 532 ---- End of tree ----
 533 recipients_count=1
 534 **** SPOOL_IN - No additional fields
 535 body_linecount=1 message_linecount=8
 536 DSN: set orcpt:   flags: 0x0
 537 Delivery address list:
 538   2@b
 539  locking TESTSUITE/spool/db/retry.lockfile
 540  locked  TESTSUITE/spool/db/retry.lockfile
 541  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 542  returned from EXIM_DBOPEN: (nil)
 543  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 544 no retry data available
 545 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 546 Considering: 2@b
 547 unique = 2@b
 548 no   domain  retry record
 549 no   address retry record
 550 2@b: queued for routing
 551 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 552 routing 2@b
 553 --------> r1 router <--------
 554 local_part=2 domain=b
 555 calling r1 router
 556 r1 router called for 2@b
 557   domain = b
 558 set transport t1
 559 queued for t1 transport: local_part = 2
 560 domain = b
 561   errors_to=NULL
 562   domain_data=NULL local_part_data=NULL
 563 routed by r1 router
 564   envelope to: 2@b
 565   transport: t1
 566 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 567 After routing:
 568   Local deliveries:
 569     2@b
 570   Remote deliveries:
 571   Failed addresses:
 572   Deferred addresses:
 573 search_tidyup called
 574 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 575 --------> 2@b <--------
 576  locking TESTSUITE/spool/db/retry.lockfile
 577  locked  TESTSUITE/spool/db/retry.lockfile
 578  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 579  returned from EXIM_DBOPEN: (nil)
 580  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 581 no retry data available
 582 search_tidyup called
 583 changed uid/gid: local delivery to 2 <2@b> transport=t1
 584   uid=CALLER_UID gid=CALLER_GID pid=p1239
 585   home=NULL current=/
 586 set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1
 587 appendfile transport entered
 588 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 589   file=TESTSUITE/test-mail/2 format=unix
 590   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 591   message_suffix=\n
 592   maildir_use_size_file=no
 593   locking by lockfile fcntl
 594 de-tainting path 'TESTSUITE/test-mail/2'
 595 lock name: TESTSUITE/test-mail/2.lock
 596 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
 597 lock file created
 598 mailbox TESTSUITE/test-mail/2 is locked
 599 writing to file TESTSUITE/test-mail/2
 600 writing data block fd=dddd size=sss timeout=0
 601 cannot use sendfile for body: spoolfile not wireformat
 602 writing data block fd=dddd size=sss timeout=0
 603 writing data block fd=dddd size=sss timeout=0
 604 appendfile yields 0 with errno=dd more_errno=dd
 605 search_tidyup called
 606 journalling 2@b
 607 t1 transport returned OK for 2@b
 608 post-process 2@b (0)
 609 2@b delivered
 610 LOG: MAIN
 611   => 2 <2@b> R=r1 T=t1
 612 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 613 changed uid/gid: post-delivery tidying
 614   uid=EXIM_UID gid=EXIM_GID pid=p1238
 615 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
 616 Processing retry items
 617 Succeeded addresses:
 618  2@b: no retry items
 619 Failed addresses:
 620 Deferred addresses:
 621 end of retry processing
 622 DSN: processing router : r1
 623 DSN: processing successful delivery address: 2@b
 624 DSN: Sender_address: x@y
 625 DSN: orcpt: NULL  flags: 0x0
 626 DSN: envid: NULL  ret: 0
 627 DSN: Final recipient: 2@b
 628 DSN: Remote SMTP server supports DSN: 0
 629 DSN: not sending DSN success message
 630 LOG: MAIN
 631   Completed
 632 end delivery of 10HmaY-0005vi-00
 633 search_tidyup called
 634 search_tidyup called
 635 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 636 smtp_setup_msg entered
 637 SMTP<< quit
 638 SMTP>> 221 myhost.test.ex closing connection
 639 LOG: smtp_connection MAIN
 640   SMTP connection from CALLER closed by QUIT
 641 search_tidyup called
 642 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>