test/stderr/0402

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