test/stderr/0386

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