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 >>Headers received:
 195
 196 search_tidyup called
 197 >>Headers after rewriting and local additions:
 198
 199 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
 200 Data file written for message 10HmaX-0005vi-00
 201 >>Generated Received: header line
 202 P Received: from [V4NET.11.12.13] (ident=CALLER)
 203         by myhost.test.ex with smtp (Exim x.yz)
 204         (envelope-from <x@y>)
 205         id 10HmaX-0005vi-00
 206         for 2@b; Tue, 2 Mar 1999 09:44:33 +0000
 207 >>Headers added by MAIL or RCPT ACL:
 208   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
 209   X-Warning: This is a test blacklisting message
 210 >>
 211 calling local_scan(); timeout=300
 212 local_scan() returned 0 NULL
 213 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
 214 DSN: Write SPOOL :-dsn_envid NULL
 215 DSN: Write SPOOL :-dsn_ret 0
 216 DSN: Flags :0
 217 DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 218 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 219 Size of headers = sss
 220 LOG: MAIN
 221   <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss
 222 SMTP>> 250 OK id=10HmaX-0005vi-00
 223 search_tidyup called
 224 release cutthrough conn: msg passed for delivery
 225 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
 226 Exim version x.yz ....
 227 changed uid/gid: forcing real = effective
 228   uid=uuuu gid=EXIM_GID pid=pppp
 229 seeking password data for user "CALLER": cache not available
 230 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 231 configuration file is TESTSUITE/test-config
 232 trusted user
 233 admin user
 234 DSN: r1 propagating DSN
 235 seeking password data for user "CALLER": using cached result
 236 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 237 set_process_info: pppp delivering specified messages
 238 set_process_info: pppp delivering 10HmaX-0005vi-00
 239 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 240 reading spool file 10HmaX-0005vi-00-H
 241 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
 242 sender_fullhost = [V4NET.11.12.13]
 243 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
 244 sender_local=0 ident=CALLER
 245 Non-recipients:
 246 Empty Tree
 247 ---- End of tree ----
 248 recipients_count=1
 249 **** SPOOL_IN - No additional fields
 250 body_linecount=1 message_linecount=7
 251 DSN: set orcpt: NULL  flags: 0
 252 Delivery address list:
 253   2@b
 254 locking TESTSUITE/spool/db/retry.lockfile
 255 locked  TESTSUITE/spool/db/retry.lockfile
 256 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 257 returned from EXIM_DBOPEN
 258 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 259 no retry data available
 260 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 261 Considering: 2@b
 262 unique = 2@b
 263 no domain retry record
 264 no address retry record
 265 2@b: queued for routing
 266 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 267 routing 2@b
 268 --------> r1 router <--------
 269 local_part=2 domain=b
 270 calling r1 router
 271 r1 router called for 2@b
 272   domain = b
 273 set transport t1
 274 queued for t1 transport: local_part = 2
 275 domain = b
 276   errors_to=NULL
 277   domain_data=NULL localpart_data=NULL
 278 routed by r1 router
 279   envelope to: 2@b
 280   transport: t1
 281 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 282 After routing:
 283   Local deliveries:
 284     2@b
 285   Remote deliveries:
 286   Failed addresses:
 287   Deferred addresses:
 288 search_tidyup called
 289 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 290 --------> 2@b <--------
 291 locking TESTSUITE/spool/db/retry.lockfile
 292 locked  TESTSUITE/spool/db/retry.lockfile
 293 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 294 returned from EXIM_DBOPEN
 295 no retry data available
 296 search_tidyup called
 297 changed uid/gid: local delivery to 2 <2@b> transport=t1
 298   uid=CALLER_UID gid=CALLER_GID pid=pppp
 299   home=NULL current=/
 300 set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1
 301 appendfile transport entered
 302 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 303   file=TESTSUITE/test-mail/2 format=unix
 304   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 305   message_suffix=\n
 306   maildir_use_size_file=no
 307   locking by lockfile fcntl
 308 lock name: TESTSUITE/test-mail/2.lock
 309 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
 310 lock file created
 311 mailbox TESTSUITE/test-mail/2 is locked
 312 writing to file TESTSUITE/test-mail/2
 313 writing data block fd=dddd size=sss timeout=0
 314 writing data block fd=dddd size=sss timeout=0
 315 writing data block fd=dddd size=sss timeout=0
 316 appendfile yields 0 with errno=dd more_errno=dd
 317 search_tidyup called
 318 journalling 2@b
 319 t1 transport returned OK for 2@b
 320 post-process 2@b (0)
 321 2@b delivered
 322 LOG: MAIN
 323   => 2 <2@b> R=r1 T=t1
 324 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 325 changed uid/gid: post-delivery tidying
 326   uid=EXIM_UID gid=EXIM_GID pid=pppp
 327 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 328 Processing retry items
 329 Succeeded addresses:
 330  2@b: no retry items
 331 Failed addresses:
 332 Deferred addresses:
 333 end of retry processing
 334 DSN: processing router : r1
 335 DSN: processing successful delivery address: 2@b
 336 DSN: Sender_address: x@y
 337 DSN: orcpt: NULL  flags: 0
 338 DSN: envid: NULL  ret: 0
 339 DSN: Final recipient: 2@b
 340 DSN: Remote SMTP server supports DSN: 0
 341 DSN: not sending DSN success message
 342 LOG: MAIN
 343   Completed
 344 end delivery of 10HmaX-0005vi-00
 345 search_tidyup called
 346 search_tidyup called
 347 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 348 smtp_setup_msg entered
 349 SMTP<< rset
 350 SMTP>> 250 Reset OK
 351 SMTP<< mail from:<x@y>
 352 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 353 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 354 SMTP>> 250 OK
 355 SMTP<< rcpt to:<2@b>
 356 using ACL "TESTSUITE/aux-fixed/0386.acl2"
 357 processing "warn"
 358   message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text
 359 l_message: found in $dnslist_domain: $dnslist_text
 360 check dnslists = rbl.test.ex
 361 DNS list check: rbl.test.ex
 362 using result of previous DNS lookup
 363 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
 364 => that means V4NET.11.12.13 is listed at rbl.test.ex
 365 warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 366 LOG: MAIN
 367   H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message
 368 processing "accept"
 369 accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2"
 370 end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT
 371 SMTP>> 250 Accepted
 372 DSN: orcpt: NULL  flags: 0
 373 SMTP<< data
 374 SMTP>> 354 Enter message, ending with "." on a line by itself
 375 search_tidyup called
 376 >>Headers received:
 377
 378 search_tidyup called
 379 >>Headers after rewriting and local additions:
 380
 381 Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
 382 Data file written for message 10HmaY-0005vi-00
 383 >>Generated Received: header line
 384 P Received: from [V4NET.11.12.13] (ident=CALLER)
 385         by myhost.test.ex with smtp (Exim x.yz)
 386         (envelope-from <x@y>)
 387         id 10HmaY-0005vi-00
 388         for 2@b; Tue, 2 Mar 1999 09:44:33 +0000
 389 >>Headers added by MAIL or RCPT ACL:
 390   X-Warning: V4NET.11.12.13 is listed at rbl.test.ex
 391   X-Warning: This is a test blacklisting message
 392 >>
 393 calling local_scan(); timeout=300
 394 local_scan() returned 0 NULL
 395 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
 396 DSN: Write SPOOL :-dsn_envid NULL
 397 DSN: Write SPOOL :-dsn_ret 0
 398 DSN: Flags :0
 399 DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 400 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
 401 Size of headers = sss
 402 LOG: MAIN
 403   <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss
 404 SMTP>> 250 OK id=10HmaY-0005vi-00
 405 search_tidyup called
 406 release cutthrough conn: msg passed for delivery
 407 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaY-0005vi-00
 408 Exim version x.yz ....
 409 changed uid/gid: forcing real = effective
 410   uid=uuuu gid=EXIM_GID pid=pppp
 411 seeking password data for user "CALLER": cache not available
 412 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 413 configuration file is TESTSUITE/test-config
 414 trusted user
 415 admin user
 416 DSN: r1 propagating DSN
 417 seeking password data for user "CALLER": using cached result
 418 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 419 set_process_info: pppp delivering specified messages
 420 set_process_info: pppp delivering 10HmaY-0005vi-00
 421 Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D
 422 reading spool file 10HmaY-0005vi-00-H
 423 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
 424 sender_fullhost = [V4NET.11.12.13]
 425 sender_rcvhost = [V4NET.11.12.13] (ident=CALLER)
 426 sender_local=0 ident=CALLER
 427 Non-recipients:
 428 Empty Tree
 429 ---- End of tree ----
 430 recipients_count=1
 431 **** SPOOL_IN - No additional fields
 432 body_linecount=1 message_linecount=7
 433 DSN: set orcpt: NULL  flags: 0
 434 Delivery address list:
 435   2@b
 436 locking TESTSUITE/spool/db/retry.lockfile
 437 locked  TESTSUITE/spool/db/retry.lockfile
 438 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 439 returned from EXIM_DBOPEN
 440 no retry data available
 441 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 442 Considering: 2@b
 443 unique = 2@b
 444 no domain retry record
 445 no address retry record
 446 2@b: queued for routing
 447 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 448 routing 2@b
 449 --------> r1 router <--------
 450 local_part=2 domain=b
 451 calling r1 router
 452 r1 router called for 2@b
 453   domain = b
 454 set transport t1
 455 queued for t1 transport: local_part = 2
 456 domain = b
 457   errors_to=NULL
 458   domain_data=NULL localpart_data=NULL
 459 routed by r1 router
 460   envelope to: 2@b
 461   transport: t1
 462 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 463 After routing:
 464   Local deliveries:
 465     2@b
 466   Remote deliveries:
 467   Failed addresses:
 468   Deferred addresses:
 469 search_tidyup called
 470 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 471 --------> 2@b <--------
 472 locking TESTSUITE/spool/db/retry.lockfile
 473 locked  TESTSUITE/spool/db/retry.lockfile
 474 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 475 returned from EXIM_DBOPEN
 476 no retry data available
 477 search_tidyup called
 478 changed uid/gid: local delivery to 2 <2@b> transport=t1
 479   uid=CALLER_UID gid=CALLER_GID pid=pppp
 480   home=NULL current=/
 481 set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1
 482 appendfile transport entered
 483 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 484   file=TESTSUITE/test-mail/2 format=unix
 485   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 486   message_suffix=\n
 487   maildir_use_size_file=no
 488   locking by lockfile fcntl
 489 lock name: TESTSUITE/test-mail/2.lock
 490 hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp
 491 lock file created
 492 mailbox TESTSUITE/test-mail/2 is locked
 493 writing to file TESTSUITE/test-mail/2
 494 writing data block fd=dddd size=sss timeout=0
 495 writing data block fd=dddd size=sss timeout=0
 496 writing data block fd=dddd size=sss timeout=0
 497 appendfile yields 0 with errno=dd more_errno=dd
 498 search_tidyup called
 499 journalling 2@b
 500 t1 transport returned OK for 2@b
 501 post-process 2@b (0)
 502 2@b delivered
 503 LOG: MAIN
 504   => 2 <2@b> R=r1 T=t1
 505 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 506 changed uid/gid: post-delivery tidying
 507   uid=EXIM_UID gid=EXIM_GID pid=pppp
 508 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
 509 Processing retry items
 510 Succeeded addresses:
 511  2@b: no retry items
 512 Failed addresses:
 513 Deferred addresses:
 514 end of retry processing
 515 DSN: processing router : r1
 516 DSN: processing successful delivery address: 2@b
 517 DSN: Sender_address: x@y
 518 DSN: orcpt: NULL  flags: 0
 519 DSN: envid: NULL  ret: 0
 520 DSN: Final recipient: 2@b
 521 DSN: Remote SMTP server supports DSN: 0
 522 DSN: not sending DSN success message
 523 LOG: MAIN
 524   Completed
 525 end delivery of 10HmaY-0005vi-00
 526 search_tidyup called
 527 search_tidyup called
 528 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 529 smtp_setup_msg entered
 530 SMTP<< quit
 531 SMTP>> 221 myhost.test.ex closing connection
 532 LOG: smtp_connection MAIN
 533   SMTP connection from CALLER closed by QUIT
 534 search_tidyup called
 535 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>