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