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