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