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