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