test/stderr/0402

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=pppp
   4 configuration file is TESTSUITE/test-config
   5 admin user
   6 changed uid/gid: privilege not needed
   7   uid=EXIM_UID gid=EXIM_GID pid=pppp
   8 DSN: r1 propagating DSN
   9 DSN: r2 propagating DSN
  10 DSN: r3 propagating DSN
  11 DSN: r4 propagating DSN
  12 DSN: r5 propagating DSN
  13 seeking password data for user "CALLER": cache not available
  14 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  15 seeking password data for user "CALLER": using cached result
  16 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  17 seeking password data for user "CALLER": using cached result
  18 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  19 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  20 sender address = CALLER@test.ex
  21 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
  22 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  23 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  24 Sender: CALLER@test.ex
  25 Recipients:
  26   CALLER@test.ex
  27   usery@test.ex
  28   userz
  29   rd+CALLER
  30   rd+usery
  31 search_tidyup called
  32 >>Headers received:
  33
  34 rewrite_one_header: type=F:
  35   From: CALLER_NAME <CALLER@test.ex>
  36 search_tidyup called
  37 >>Headers after rewriting and local additions:
  38 I Message-Id: <E10HmaX-0005vi-00@mail.test.ex>
  39 F From: CALLER_NAME <CALLER@test.ex>
  40   Date: Tue, 2 Mar 1999 09:44:33 +0000
  41
  42 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
  43 Data file written for message 10HmaX-0005vi-00
  44 considering: ${tod_full}
  45   expanding: ${tod_full}
  46      result: Tue, 2 Mar 1999 09:44:33 +0000
  47 considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
  48         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  49         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  50         }}(Exim $version_number)
  51         ${if def:sender_address {(envelope-from <$sender_address>)
  52         }}id $message_exim_id${if def:received_for {
  53         for $received_for}}
  54   condition: def:sender_rcvhost
  55      result: false
  56    scanning: from $sender_rcvhost
  57         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  58         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  59         }}(Exim $version_number)
  60         ${if def:sender_address {(envelope-from <$sender_address>)
  61         }}id $message_exim_id${if def:received_for {
  62         for $received_for}}
  63   expanding: from $sender_rcvhost
  64
  65      result: from
  66
  67    skipping: result is not used
  68 considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  69         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  70         }}(Exim $version_number)
  71         ${if def:sender_address {(envelope-from <$sender_address>)
  72         }}id $message_exim_id${if def:received_for {
  73         for $received_for}}
  74   condition: def:sender_ident
  75      result: true
  76 considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  77         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  78         }}(Exim $version_number)
  79         ${if def:sender_address {(envelope-from <$sender_address>)
  80         }}id $message_exim_id${if def:received_for {
  81         for $received_for}}
  82 considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  83         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  84         }}(Exim $version_number)
  85         ${if def:sender_address {(envelope-from <$sender_address>)
  86         }}id $message_exim_id${if def:received_for {
  87         for $received_for}}
  88   expanding: $sender_ident
  89      result: CALLER
  90   expanding: from ${quote_local_part:$sender_ident}
  91      result: from CALLER
  92   condition: def:sender_helo_name
  93      result: false
  94    scanning: (helo=$sender_helo_name)
  95         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
  96         }}(Exim $version_number)
  97         ${if def:sender_address {(envelope-from <$sender_address>)
  98         }}id $message_exim_id${if def:received_for {
  99         for $received_for}}
 100   expanding: (helo=$sender_helo_name)
 101
 102      result: (helo=)
 103
 104    skipping: result is not used
 105   expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 106         }}
 107      result: from CALLER
 108   condition: def:received_protocol
 109      result: true
 110 considering: with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
 111         }}(Exim $version_number)
 112         ${if def:sender_address {(envelope-from <$sender_address>)
 113         }}id $message_exim_id${if def:received_for {
 114         for $received_for}}
 115   expanding: with $received_protocol
 116      result: with local
 117   condition: def:tls_cipher
 118      result: false
 119    scanning: ($tls_cipher)
 120         }}(Exim $version_number)
 121         ${if def:sender_address {(envelope-from <$sender_address>)
 122         }}id $message_exim_id${if def:received_for {
 123         for $received_for}}
 124   expanding: ($tls_cipher)
 125
 126      result: ()
 127
 128    skipping: result is not used
 129   condition: def:sender_address
 130      result: true
 131 considering: (envelope-from <$sender_address>)
 132         }}id $message_exim_id${if def:received_for {
 133         for $received_for}}
 134   expanding: (envelope-from <$sender_address>)
 135
 136      result: (envelope-from <CALLER@test.ex>)
 137
 138   condition: def:received_for
 139      result: false
 140    scanning:
 141         for $received_for}}
 142   expanding:
 143         for $received_for
 144      result:
 145         for
 146    skipping: result is not used
 147   expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
 148         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 149         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol}} ${if def:tls_cipher {($tls_cipher)
 150         }}(Exim $version_number)
 151         ${if def:sender_address {(envelope-from <$sender_address>)
 152         }}id $message_exim_id${if def:received_for {
 153         for $received_for}}
 154      result: Received: from CALLER by mail.test.ex with local (Exim x.yz)
 155         (envelope-from <CALLER@test.ex>)
 156         id 10HmaX-0005vi-00
 157 >>Generated Received: header line
 158 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
 159         (envelope-from <CALLER@test.ex>)
 160         id 10HmaX-0005vi-00; Tue, 2 Mar 1999 09:44:33 +0000
 161 calling local_scan(); timeout=300
 162 local_scan() returned 0 NULL
 163 considering: ${tod_full}
 164   expanding: ${tod_full}
 165      result: Tue, 2 Mar 1999 09:44:33 +0000
 166 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
 167 DSN: Write SPOOL :-dsn_envid NULL
 168 DSN: Write SPOOL :-dsn_ret 0
 169 DSN: Flags :0
 170 DSN: **** SPOOL_OUT - address: |CALLER@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 171 DSN: Flags :0
 172 DSN: **** SPOOL_OUT - address: |usery@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 173 DSN: Flags :0
 174 DSN: **** SPOOL_OUT - address: |userz@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 175 DSN: Flags :0
 176 DSN: **** SPOOL_OUT - address: |rd+CALLER@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 177 DSN: Flags :0
 178 DSN: **** SPOOL_OUT - address: |rd+usery@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
 179 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
 180 Size of headers = sss
 181 LOG: MAIN
 182   <= CALLER@test.ex U=CALLER P=local S=sss
 183 created log directory TESTSUITE/spool/log
 184 search_tidyup called
 185 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95dfd -odi -Mc 10HmaX-0005vi-00
 186 Exim version x.yz ....
 187 changed uid/gid: forcing real = effective
 188   uid=uuuu gid=EXIM_GID pid=pppp
 189 configuration file is TESTSUITE/test-config
 190 trusted user
 191 admin user
 192 DSN: r1 propagating DSN
 193 DSN: r2 propagating DSN
 194 DSN: r3 propagating DSN
 195 DSN: r4 propagating DSN
 196 DSN: r5 propagating DSN
 197 seeking password data for user "CALLER": cache not available
 198 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 199 seeking password data for user "CALLER": using cached result
 200 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 201 seeking password data for user "CALLER": using cached result
 202 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 203 set_process_info: pppp delivering specified messages
 204 set_process_info: pppp delivering 10HmaX-0005vi-00
 205 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
 206 reading spool file 10HmaX-0005vi-00-H
 207 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 208 sender_local=1 ident=CALLER
 209 Non-recipients:
 210 Empty Tree
 211 ---- End of tree ----
 212 recipients_count=5
 213 **** SPOOL_IN - No additional fields
 214 **** SPOOL_IN - No additional fields
 215 **** SPOOL_IN - No additional fields
 216 **** SPOOL_IN - No additional fields
 217 **** SPOOL_IN - No additional fields
 218 body_linecount=0 message_linecount=6
 219 DSN: set orcpt: NULL  flags: 0
 220 DSN: set orcpt: NULL  flags: 0
 221 DSN: set orcpt: NULL  flags: 0
 222 DSN: set orcpt: NULL  flags: 0
 223 DSN: set orcpt: NULL  flags: 0
 224 Delivery address list:
 225   CALLER@test.ex
 226   usery@test.ex
 227   userz@test.ex
 228   rd+CALLER@test.ex
 229   rd+usery@test.ex
 230 locking TESTSUITE/spool/db/retry.lockfile
 231 locked  TESTSUITE/spool/db/retry.lockfile
 232 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 233 returned from EXIM_DBOPEN
 234 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
 235 no retry data available
 236 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 237 Considering: CALLER@test.ex
 238 unique = CALLER@test.ex
 239 no domain retry record
 240 no address retry record
 241 CALLER@test.ex: queued for routing
 242 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 243 Considering: usery@test.ex
 244 unique = usery@test.ex
 245 no domain retry record
 246 no address retry record
 247 usery@test.ex: queued for routing
 248 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 249 Considering: userz@test.ex
 250 unique = userz@test.ex
 251 no domain retry record
 252 no address retry record
 253 userz@test.ex: queued for routing
 254 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 255 Considering: rd+CALLER@test.ex
 256 unique = rd+CALLER@test.ex
 257 no domain retry record
 258 no address retry record
 259 rd+CALLER@test.ex: queued for routing
 260 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 261 Considering: rd+usery@test.ex
 262 unique = rd+usery@test.ex
 263 no domain retry record
 264 no address retry record
 265 rd+usery@test.ex: queued for routing
 266 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 267 routing rd+usery@test.ex
 268 --------> r1 router <--------
 269 local_part=rd+usery domain=test.ex
 270 checking local_parts
 271 rd+usery in "CALLER"? no (end of list)
 272 r1 router skipped: local_parts mismatch
 273 --------> r2 router <--------
 274 local_part=rd+usery domain=test.ex
 275 checking local_parts
 276 rd+usery in "usery"? no (end of list)
 277 r2 router skipped: local_parts mismatch
 278 --------> r3 router <--------
 279 local_part=rd+usery domain=test.ex
 280 checking local_parts
 281 rd+usery in "userz"? no (end of list)
 282 r3 router skipped: local_parts mismatch
 283 --------> r4 router <--------
 284 local_part=rd+usery domain=test.ex
 285 stripped prefix rd+
 286 checking local_parts
 287 usery in "CALLER"? no (end of list)
 288 r4 router skipped: local_parts mismatch
 289 --------> r5 router <--------
 290 local_part=rd+usery domain=test.ex
 291 stripped prefix rd+
 292 checking local_parts
 293 usery in "usery"? yes (matched "usery")
 294 considering: /non-exist/$domain
 295   expanding: /non-exist/$domain
 296      result: /non-exist/test.ex
 297 calling r5 router
 298 rda_interpret (string): TESTSUITE/test-mail/junk
 299 expanded: TESTSUITE/test-mail/junk
 300 file is not a filter file
 301 parse_forward_list: TESTSUITE/test-mail/junk
 302 extract item: TESTSUITE/test-mail/junk
 303 set transport ft1
 304 r5 router generated TESTSUITE/test-mail/junk
 305   pipe, file, or autoreply
 306   errors_to=NULL transport=ft1
 307   uid=unset gid=unset home=/non-exist/$local_part
 308 routed by r5 router
 309   envelope to: rd+usery@test.ex
 310   transport: <none>
 311 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 312 routing rd+CALLER@test.ex
 313 --------> r1 router <--------
 314 local_part=rd+CALLER domain=test.ex
 315 checking local_parts
 316 rd+CALLER in "CALLER"? no (end of list)
 317 r1 router skipped: local_parts mismatch
 318 --------> r2 router <--------
 319 local_part=rd+CALLER domain=test.ex
 320 checking local_parts
 321 rd+CALLER in "usery"? no (end of list)
 322 r2 router skipped: local_parts mismatch
 323 --------> r3 router <--------
 324 local_part=rd+CALLER domain=test.ex
 325 checking local_parts
 326 rd+CALLER in "userz"? no (end of list)
 327 r3 router skipped: local_parts mismatch
 328 --------> r4 router <--------
 329 local_part=rd+CALLER domain=test.ex
 330 stripped prefix rd+
 331 checking local_parts
 332 CALLER in "CALLER"? yes (matched "CALLER")
 333 considering: /non-exist/$local_part
 334   expanding: /non-exist/$local_part
 335      result: /non-exist/CALLER
 336 calling r4 router
 337 rda_interpret (string): TESTSUITE/test-mail/junk
 338 expanded: TESTSUITE/test-mail/junk
 339 file is not a filter file
 340 parse_forward_list: TESTSUITE/test-mail/junk
 341 extract item: TESTSUITE/test-mail/junk
 342 set transport ft1
 343 r4 router generated TESTSUITE/test-mail/junk
 344   pipe, file, or autoreply
 345   errors_to=NULL transport=ft1
 346   uid=unset gid=unset home=/non-exist/CALLER
 347 routed by r4 router
 348   envelope to: rd+CALLER@test.ex
 349   transport: <none>
 350 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 351 routing userz@test.ex
 352 --------> r1 router <--------
 353 local_part=userz domain=test.ex
 354 checking local_parts
 355 userz in "CALLER"? no (end of list)
 356 r1 router skipped: local_parts mismatch
 357 --------> r2 router <--------
 358 local_part=userz domain=test.ex
 359 checking local_parts
 360 userz in "usery"? no (end of list)
 361 r2 router skipped: local_parts mismatch
 362 --------> r3 router <--------
 363 local_part=userz domain=test.ex
 364 checking local_parts
 365 userz in "userz"? yes (matched "userz")
 366 considering: /non-exist/$domain
 367   expanding: /non-exist/$domain
 368      result: /non-exist/test.ex
 369 calling r3 router
 370 r3 router called for userz@test.ex
 371   domain = test.ex
 372 set transport t2
 373 queued for t2 transport: local_part = userz
 374 domain = test.ex
 375   errors_to=NULL
 376   domain_data=NULL localpart_data=NULL
 377 routed by r3 router
 378   envelope to: userz@test.ex
 379   transport: t2
 380 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 381 routing usery@test.ex
 382 --------> r1 router <--------
 383 local_part=usery domain=test.ex
 384 checking local_parts
 385 usery in "CALLER"? no (end of list)
 386 r1 router skipped: local_parts mismatch
 387 --------> r2 router <--------
 388 local_part=usery domain=test.ex
 389 checking local_parts
 390 usery in "usery"? yes (matched "usery")
 391 considering: /non-exist/$domain
 392   expanding: /non-exist/$domain
 393      result: /non-exist/test.ex
 394 calling r2 router
 395 r2 router called for usery@test.ex
 396   domain = test.ex
 397 set transport t1
 398 queued for t1 transport: local_part = usery
 399 domain = test.ex
 400   errors_to=NULL
 401   domain_data=NULL localpart_data=NULL
 402 routed by r2 router
 403   envelope to: usery@test.ex
 404   transport: t1
 405 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 406 routing CALLER@test.ex
 407 --------> r1 router <--------
 408 local_part=CALLER domain=test.ex
 409 checking local_parts
 410 CALLER in "CALLER"? yes (matched "CALLER")
 411 considering: /non-exist/$local_part
 412   expanding: /non-exist/$local_part
 413      result: /non-exist/CALLER
 414 calling r1 router
 415 r1 router called for CALLER@test.ex
 416   domain = test.ex
 417 set transport t1
 418 queued for t1 transport: local_part = CALLER
 419 domain = test.ex
 420   errors_to=NULL
 421   domain_data=NULL localpart_data=NULL
 422 routed by r1 router
 423   envelope to: CALLER@test.ex
 424   transport: t1
 425 locking TESTSUITE/spool/db/retry.lockfile
 426 locked  TESTSUITE/spool/db/retry.lockfile
 427 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 428 returned from EXIM_DBOPEN
 429 no retry data available
 430 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 431 Considering: TESTSUITE/test-mail/junk
 432 unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 433 queued for ft1 transport
 434 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 435 Considering: TESTSUITE/test-mail/junk
 436 unique = TESTSUITE/test-mail/junk:rd+usery@test.ex
 437 queued for ft1 transport
 438 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 439 After routing:
 440   Local deliveries:
 441     TESTSUITE/test-mail/junk
 442     TESTSUITE/test-mail/junk
 443     CALLER@test.ex
 444     usery@test.ex
 445     userz@test.ex
 446   Remote deliveries:
 447   Failed addresses:
 448   Deferred addresses:
 449 search_tidyup called
 450 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 451 --------> TESTSUITE/test-mail/junk <--------
 452 locking TESTSUITE/spool/db/retry.lockfile
 453 locked  TESTSUITE/spool/db/retry.lockfile
 454 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 455 returned from EXIM_DBOPEN
 456 no retry data available
 457 considering: /non-exist/$local_part
 458   expanding: /non-exist/$local_part
 459      result: /non-exist/usery
 460 search_tidyup called
 461 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 462   uid=CALLER_UID gid=CALLER_GID pid=pppp
 463   home=/non-exist/usery current=/
 464 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
 465 appendfile transport entered
 466 considering: $address_file
 467   expanding: $address_file
 468      result: TESTSUITE/test-mail/junk
 469 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 470   file=TESTSUITE/test-mail/junk format=unix
 471   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 472   message_suffix=\n
 473   maildir_use_size_file=no
 474   locking by lockfile fcntl
 475 lock name: TESTSUITE/test-mail/junk.lock
 476 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 477 lock file created
 478 mailbox TESTSUITE/test-mail/junk is locked
 479 writing to file TESTSUITE/test-mail/junk
 480 considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 481
 482   condition: def:return_path
 483      result: true
 484 considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 485
 486   expanding: $return_path
 487      result: CALLER@test.ex
 488    scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 489
 490   expanding: MAILER-DAEMON
 491      result: MAILER-DAEMON
 492    skipping: result is not used
 493   expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 494
 495      result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 496
 497 writing data block fd=dddd size=sss timeout=0
 498 writing data block fd=dddd size=sss timeout=0
 499 writing data block fd=dddd size=sss timeout=0
 500 appendfile yields 0 with errno=dd more_errno=dd
 501 search_tidyup called
 502 journalling TESTSUITE/test-mail/junk:rd+usery@test.ex
 503 ft1 transport returned OK for TESTSUITE/test-mail/junk
 504 post-process TESTSUITE/test-mail/junk (0)
 505 TESTSUITE/test-mail/junk delivered
 506 rd+usery@test.ex: children all complete
 507 LOG: MAIN
 508   => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1
 509 --------> TESTSUITE/test-mail/junk <--------
 510 locking TESTSUITE/spool/db/retry.lockfile
 511 locked  TESTSUITE/spool/db/retry.lockfile
 512 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 513 returned from EXIM_DBOPEN
 514 no retry data available
 515 search_tidyup called
 516 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 517   uid=CALLER_UID gid=CALLER_GID pid=pppp
 518   home=/non-exist/CALLER current=/
 519 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
 520 appendfile transport entered
 521 considering: $address_file
 522   expanding: $address_file
 523      result: TESTSUITE/test-mail/junk
 524 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 525   file=TESTSUITE/test-mail/junk format=unix
 526   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 527   message_suffix=\n
 528   maildir_use_size_file=no
 529   locking by lockfile fcntl
 530 lock name: TESTSUITE/test-mail/junk.lock
 531 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 532 lock file created
 533 mailbox TESTSUITE/test-mail/junk is locked
 534 writing to file TESTSUITE/test-mail/junk
 535 considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 536
 537   condition: def:return_path
 538      result: true
 539 considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 540
 541   expanding: $return_path
 542      result: CALLER@test.ex
 543    scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 544
 545   expanding: MAILER-DAEMON
 546      result: MAILER-DAEMON
 547    skipping: result is not used
 548   expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 549
 550      result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 551
 552 writing data block fd=dddd size=sss timeout=0
 553 writing data block fd=dddd size=sss timeout=0
 554 writing data block fd=dddd size=sss timeout=0
 555 appendfile yields 0 with errno=dd more_errno=dd
 556 search_tidyup called
 557 journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 558 ft1 transport returned OK for TESTSUITE/test-mail/junk
 559 post-process TESTSUITE/test-mail/junk (0)
 560 TESTSUITE/test-mail/junk delivered
 561 rd+CALLER@test.ex: children all complete
 562 LOG: MAIN
 563   => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1
 564 --------> CALLER@test.ex <--------
 565 locking TESTSUITE/spool/db/retry.lockfile
 566 locked  TESTSUITE/spool/db/retry.lockfile
 567 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 568 returned from EXIM_DBOPEN
 569 no retry data available
 570 search_tidyup called
 571 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 572   uid=CALLER_UID gid=CALLER_GID pid=pppp
 573   home=/non-exist/CALLER current=/
 574 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
 575 appendfile transport entered
 576 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 577   file=/dev/null format=unix
 578   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 579   message_suffix=\n
 580   maildir_use_size_file=no
 581   locking by lockfile fcntl
 582 search_tidyup called
 583 journalling CALLER@test.ex
 584 t1 transport returned OK for CALLER@test.ex
 585 post-process CALLER@test.ex (0)
 586 CALLER@test.ex delivered
 587 LOG: MAIN
 588   => CALLER <CALLER@test.ex> R=r1 T=t1
 589 --------> usery@test.ex <--------
 590 locking TESTSUITE/spool/db/retry.lockfile
 591 locked  TESTSUITE/spool/db/retry.lockfile
 592 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 593 returned from EXIM_DBOPEN
 594 no retry data available
 595 considering: /non-exist/$local_part
 596   expanding: /non-exist/$local_part
 597      result: /non-exist/usery
 598 search_tidyup called
 599 changed uid/gid: local delivery to usery <usery@test.ex> transport=t1
 600   uid=CALLER_UID gid=CALLER_GID pid=pppp
 601   home=/non-exist/usery current=/
 602 set_process_info: pppp delivering 10HmaX-0005vi-00 to usery using t1
 603 appendfile transport entered
 604 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 605   file=/dev/null format=unix
 606   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 607   message_suffix=\n
 608   maildir_use_size_file=no
 609   locking by lockfile fcntl
 610 search_tidyup called
 611 journalling usery@test.ex
 612 t1 transport returned OK for usery@test.ex
 613 post-process usery@test.ex (0)
 614 usery@test.ex delivered
 615 LOG: MAIN
 616   => usery <usery@test.ex> R=r2 T=t1
 617 --------> userz@test.ex <--------
 618 locking TESTSUITE/spool/db/retry.lockfile
 619 locked  TESTSUITE/spool/db/retry.lockfile
 620 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
 621 returned from EXIM_DBOPEN
 622 no retry data available
 623 considering: /$local_part
 624   expanding: /$local_part
 625      result: /userz
 626 search_tidyup called
 627 changed uid/gid: local delivery to userz <userz@test.ex> transport=t2
 628   uid=CALLER_UID gid=CALLER_GID pid=pppp
 629   home=/userz current=/
 630 set_process_info: pppp delivering 10HmaX-0005vi-00 to userz using t2
 631 appendfile transport entered
 632 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 633   file=/dev/null format=unix
 634   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 635   message_suffix=\n
 636   maildir_use_size_file=no
 637   locking by lockfile fcntl
 638 search_tidyup called
 639 journalling userz@test.ex
 640 t2 transport returned OK for userz@test.ex
 641 post-process userz@test.ex (0)
 642 userz@test.ex delivered
 643 LOG: MAIN
 644   => userz <userz@test.ex> R=r3 T=t2
 645 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
 646 changed uid/gid: post-delivery tidying
 647   uid=EXIM_UID gid=EXIM_GID pid=pppp
 648 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
 649 Processing retry items
 650 Succeeded addresses:
 651  userz@test.ex: no retry items
 652  usery@test.ex: no retry items
 653  CALLER@test.ex: no retry items
 654  TESTSUITE/test-mail/junk: no retry items
 655  rd+CALLER@test.ex: no retry items
 656  TESTSUITE/test-mail/junk: no retry items
 657  rd+usery@test.ex: no retry items
 658  rd+CALLER@test.ex: no retry items
 659  rd+usery@test.ex: no retry items
 660 Failed addresses:
 661 Deferred addresses:
 662 end of retry processing
 663 DSN: processing router : r3
 664 DSN: processing successful delivery address: userz@test.ex
 665 DSN: Sender_address: CALLER@test.ex
 666 DSN: orcpt: NULL  flags: 0
 667 DSN: envid: NULL  ret: 0
 668 DSN: Final recipient: userz@test.ex
 669 DSN: Remote SMTP server supports DSN: 0
 670 DSN: not sending DSN success message
 671 DSN: processing router : r2
 672 DSN: processing successful delivery address: usery@test.ex
 673 DSN: Sender_address: CALLER@test.ex
 674 DSN: orcpt: NULL  flags: 0
 675 DSN: envid: NULL  ret: 0
 676 DSN: Final recipient: usery@test.ex
 677 DSN: Remote SMTP server supports DSN: 0
 678 DSN: not sending DSN success message
 679 DSN: processing router : r1
 680 DSN: processing successful delivery address: CALLER@test.ex
 681 DSN: Sender_address: CALLER@test.ex
 682 DSN: orcpt: NULL  flags: 0
 683 DSN: envid: NULL  ret: 0
 684 DSN: Final recipient: CALLER@test.ex
 685 DSN: Remote SMTP server supports DSN: 0
 686 DSN: not sending DSN success message
 687 DSN: processing router : r4
 688 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
 689 DSN: Sender_address: CALLER@test.ex
 690 DSN: orcpt: NULL  flags: 0
 691 DSN: envid: NULL  ret: 0
 692 DSN: Final recipient: TESTSUITE/test-mail/junk
 693 DSN: Remote SMTP server supports DSN: 0
 694 DSN: not sending DSN success message
 695 DSN: processing router : r5
 696 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
 697 DSN: Sender_address: CALLER@test.ex
 698 DSN: orcpt: NULL  flags: 0
 699 DSN: envid: NULL  ret: 0
 700 DSN: Final recipient: TESTSUITE/test-mail/junk
 701 DSN: Remote SMTP server supports DSN: 0
 702 DSN: not sending DSN success message
 703 DSN: processing router : r4
 704 DSN: processing successful delivery address: rd+CALLER@test.ex
 705 DSN: Sender_address: CALLER@test.ex
 706 DSN: orcpt: NULL  flags: 0
 707 DSN: envid: NULL  ret: 0
 708 DSN: Final recipient: rd+CALLER@test.ex
 709 DSN: Remote SMTP server supports DSN: 0
 710 DSN: not sending DSN success message
 711 DSN: processing router : r5
 712 DSN: processing successful delivery address: rd+usery@test.ex
 713 DSN: Sender_address: CALLER@test.ex
 714 DSN: orcpt: NULL  flags: 0
 715 DSN: envid: NULL  ret: 0
 716 DSN: Final recipient: rd+usery@test.ex
 717 DSN: Remote SMTP server supports DSN: 0
 718 DSN: not sending DSN success message
 719 LOG: MAIN
 720   Completed
 721 end delivery of 10HmaX-0005vi-00
 722 search_tidyup called
 723 search_tidyup called
 724 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
 725 search_tidyup called
 726 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>