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