test/stderr/0386

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