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