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