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