test/stderr/0402

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