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