test/stderr/0402

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