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