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