test/stderr/0402

   1 Exim version x.yz ....
   2 changed uid/gid: forcing real = effective
   3   uid=uuuu gid=CALLER_GID pid=p1234
   4 environment after trimming:
   5  PATH=<munged>
   6 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
   7 configuration file is TESTSUITE/test-config
   8 admin user
   9 changed uid/gid: privilege not needed
  10   uid=EXIM_UID gid=EXIM_GID pid=p1234
  11 seeking password data for user "CALLER": cache not available
  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 seeking password data for user "CALLER": using cached result
  16 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
  17 try option gecos_pattern
  18 try option gecos_name
  19 try option unknown_login
  20 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
  21 sender address = CALLER@test.ex
  22 try option smtp_active_hostname
  23 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
  24 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
  25 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
  26 try option message_size_limit
  27 Sender: CALLER@test.ex
  28 Recipients:
  29   CALLER@test.ex
  30   usery@test.ex
  31   userz
  32   rd+CALLER
  33   rd+usery
  34 try option acl_not_smtp_start
  35 search_tidyup called
  36 >>Headers received:
  37
  38 try option message_id_header_domain
  39 try option message_id_header_text
  40 qualify & rewrite recipients list
  41 rewrite rules on sender address
  42 qualify and rewrite headers
  43  rewrite_one_header: type=F:
  44    From: CALLER_NAME <CALLER@test.ex>
  45 search_tidyup called
  46 >>Headers after rewriting and local additions:
  47  I Message-Id: <E10HmaX-000000005vi-0000@mail.test.ex>
  48  F From: CALLER_NAME <CALLER@test.ex>
  49    Date: Tue, 2 Mar 1999 09:44:33 +0000
  50
  51 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
  52 Data file written for message 10HmaX-000000005vi-0000
  53  ╭considering: ${tod_full}
  54  ├──expanding: ${tod_full}
  55  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
  56 try option received_header_text
  57  ╭considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
  58         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  59         }}}}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
  60         }}(Exim $version_number)
  61         ${if def:sender_address {(envelope-from <$sender_address>)
  62         }}id $message_exim_id${if def:received_for {
  63         for $received_for}}
  64  ├───────text: Received:
  65  ├considering: ${if def:sender_rcvhost {from $sender_rcvhost
  66         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  67         }}}}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
  68         }}(Exim $version_number)
  69         ${if def:sender_address {(envelope-from <$sender_address>)
  70         }}id $message_exim_id${if def:received_for {
  71         for $received_for}}
  72  ├──condition: def:sender_rcvhost
  73  ├─────result: false
  74   ╭───scanning: from $sender_rcvhost
  75         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  76         }}}}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
  77         }}(Exim $version_number)
  78         ${if def:sender_address {(envelope-from <$sender_address>)
  79         }}id $message_exim_id${if def:received_for {
  80         for $received_for}}
  81   ├───────text: from
  82   ├───scanning: $sender_rcvhost
  83         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  84         }}}}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
  85         }}(Exim $version_number)
  86         ${if def:sender_address {(envelope-from <$sender_address>)
  87         }}id $message_exim_id${if def:received_for {
  88         for $received_for}}
  89   ├──────value:
  90   ├───scanning:
  91         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
  92         }}}}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
  93         }}(Exim $version_number)
  94         ${if def:sender_address {(envelope-from <$sender_address>)
  95         }}id $message_exim_id${if def:received_for {
  96         for $received_for}}
  97   ├───────text:
  98
  99   ├───scanning: }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 100         }}}}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
 101         }}(Exim $version_number)
 102         ${if def:sender_address {(envelope-from <$sender_address>)
 103         }}id $message_exim_id${if def:received_for {
 104         for $received_for}}
 105   ├──expanding: from $sender_rcvhost
 106
 107   ├─────result: from
 108
 109   ╰───skipping: result is not used
 110   ╭considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 111         }}}}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
 112         }}(Exim $version_number)
 113         ${if def:sender_address {(envelope-from <$sender_address>)
 114         }}id $message_exim_id${if def:received_for {
 115         for $received_for}}
 116   ├──condition: def:sender_ident
 117   ├─────result: true
 118    ╭considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 119         }}}}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
 120         }}(Exim $version_number)
 121         ${if def:sender_address {(envelope-from <$sender_address>)
 122         }}id $message_exim_id${if def:received_for {
 123         for $received_for}}
 124    ├───────text: from
 125    ├considering: ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 126         }}}}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
 127         }}(Exim $version_number)
 128         ${if def:sender_address {(envelope-from <$sender_address>)
 129         }}id $message_exim_id${if def:received_for {
 130         for $received_for}}
 131    ╎╭considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 132    ╎  }}}}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
 133    ╎  }}(Exim $version_number)
 134    ╎  ${if def:sender_address {(envelope-from <$sender_address>)
 135    ╎  }}id $message_exim_id${if def:received_for {
 136    ╎  for $received_for}}
 137    ╎├──────value: CALLER
 138    ╎├considering: } }}${if def:sender_helo_name {(helo=$sender_helo_name)
 139    ╎  }}}}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
 140    ╎  }}(Exim $version_number)
 141    ╎  ${if def:sender_address {(envelope-from <$sender_address>)
 142    ╎  }}id $message_exim_id${if def:received_for {
 143    ╎  for $received_for}}
 144    ╎├──expanding: $sender_ident
 145    ╎╰─────result: CALLER
 146    ├─────op-res: CALLER
 147    ├considering:  }}${if def:sender_helo_name {(helo=$sender_helo_name)
 148         }}}}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
 149         }}(Exim $version_number)
 150         ${if def:sender_address {(envelope-from <$sender_address>)
 151         }}id $message_exim_id${if def:received_for {
 152         for $received_for}}
 153    ├───────text:
 154    ├considering: }}${if def:sender_helo_name {(helo=$sender_helo_name)
 155         }}}}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
 156         }}(Exim $version_number)
 157         ${if def:sender_address {(envelope-from <$sender_address>)
 158         }}id $message_exim_id${if def:received_for {
 159         for $received_for}}
 160    ├──expanding: from ${quote_local_part:$sender_ident}
 161    ╰─────result: from CALLER
 162   ├───item-res: from CALLER
 163   ├considering: ${if def:sender_helo_name {(helo=$sender_helo_name)
 164         }}}}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
 165         }}(Exim $version_number)
 166         ${if def:sender_address {(envelope-from <$sender_address>)
 167         }}id $message_exim_id${if def:received_for {
 168         for $received_for}}
 169   ├──condition: def:sender_helo_name
 170   ├─────result: false
 171    ╭───scanning: (helo=$sender_helo_name)
 172         }}}}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
 173         }}(Exim $version_number)
 174         ${if def:sender_address {(envelope-from <$sender_address>)
 175         }}id $message_exim_id${if def:received_for {
 176         for $received_for}}
 177    ├───────text: (helo=
 178    ├───scanning: $sender_helo_name)
 179         }}}}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
 180         }}(Exim $version_number)
 181         ${if def:sender_address {(envelope-from <$sender_address>)
 182         }}id $message_exim_id${if def:received_for {
 183         for $received_for}}
 184    ├──────value:
 185    ├───scanning: )
 186         }}}}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
 187         }}(Exim $version_number)
 188         ${if def:sender_address {(envelope-from <$sender_address>)
 189         }}id $message_exim_id${if def:received_for {
 190         for $received_for}}
 191    ├───────text: )
 192
 193    ├───scanning: }}}}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
 194         }}(Exim $version_number)
 195         ${if def:sender_address {(envelope-from <$sender_address>)
 196         }}id $message_exim_id${if def:received_for {
 197         for $received_for}}
 198    ├──expanding: (helo=$sender_helo_name)
 199
 200    ├─────result: (helo=)
 201
 202    ╰───skipping: result is not used
 203   ├───item-res:
 204   ├considering: }}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
 205         }}(Exim $version_number)
 206         ${if def:sender_address {(envelope-from <$sender_address>)
 207         }}id $message_exim_id${if def:received_for {
 208         for $received_for}}
 209   ├──expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 210         }}
 211   ╰─────result: from CALLER
 212  ├───item-res: from CALLER
 213  ├considering: 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
 214         }}(Exim $version_number)
 215         ${if def:sender_address {(envelope-from <$sender_address>)
 216         }}id $message_exim_id${if def:received_for {
 217         for $received_for}}
 218  ├───────text: by
 219  ├considering: $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
 220         }}(Exim $version_number)
 221         ${if def:sender_address {(envelope-from <$sender_address>)
 222         }}id $message_exim_id${if def:received_for {
 223         for $received_for}}
 224  ├──────value: mail.test.ex
 225  ├considering:  ${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
 226         }}(Exim $version_number)
 227         ${if def:sender_address {(envelope-from <$sender_address>)
 228         }}id $message_exim_id${if def:received_for {
 229         for $received_for}}
 230  ├───────text:
 231  ├considering: ${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
 232         }}(Exim $version_number)
 233         ${if def:sender_address {(envelope-from <$sender_address>)
 234         }}id $message_exim_id${if def:received_for {
 235         for $received_for}}
 236  ├──condition: def:received_protocol
 237  ├─────result: true
 238   ╭considering: with $received_protocol }}${if def:tls_in_ver        { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 239         }}(Exim $version_number)
 240         ${if def:sender_address {(envelope-from <$sender_address>)
 241         }}id $message_exim_id${if def:received_for {
 242         for $received_for}}
 243   ├───────text: with
 244   ├considering: $received_protocol }}${if def:tls_in_ver        { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 245         }}(Exim $version_number)
 246         ${if def:sender_address {(envelope-from <$sender_address>)
 247         }}id $message_exim_id${if def:received_for {
 248         for $received_for}}
 249   ├──────value: local
 250   ├considering:  }}${if def:tls_in_ver        { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 251         }}(Exim $version_number)
 252         ${if def:sender_address {(envelope-from <$sender_address>)
 253         }}id $message_exim_id${if def:received_for {
 254         for $received_for}}
 255   ├───────text:
 256   ├considering: }}${if def:tls_in_ver        { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 257         }}(Exim $version_number)
 258         ${if def:sender_address {(envelope-from <$sender_address>)
 259         }}id $message_exim_id${if def:received_for {
 260         for $received_for}}
 261   ├──expanding: with $received_protocol
 262   ╰─────result: with local
 263  ├───item-res: with local
 264  ├considering: ${if def:tls_in_ver        { ($tls_in_ver)}}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 265         }}(Exim $version_number)
 266         ${if def:sender_address {(envelope-from <$sender_address>)
 267         }}id $message_exim_id${if def:received_for {
 268         for $received_for}}
 269         ${if def:sender_address {(envelope-from <$sender_address>)
 270         }}id $message_exim_id${if def:received_for {
 271         for $received_for}}
 272   ├──expanding:  ($tls_in_ver)
 273   ├─────result:  ()
 274   ╰───skipping: result is not used
 275  ├───item-res:
 276  ├considering: ${if def:tls_in_cipher_std { tls $tls_in_cipher_std
 277         }}(Exim $version_number)
 278         ${if def:sender_address {(envelope-from <$sender_address>)
 279         }}id $message_exim_id${if def:received_for {
 280         for $received_for}}
 281  ├──condition: def:tls_in_cipher_std
 282  ├─────result: false
 283   ╭───scanning:  tls $tls_in_cipher_std
 284         }}(Exim $version_number)
 285         ${if def:sender_address {(envelope-from <$sender_address>)
 286         }}id $message_exim_id${if def:received_for {
 287         for $received_for}}
 288   ├───────text:  tls
 289   ├───scanning: $tls_in_cipher_std
 290         }}(Exim $version_number)
 291         ${if def:sender_address {(envelope-from <$sender_address>)
 292         }}id $message_exim_id${if def:received_for {
 293         for $received_for}}
 294   ├──────value:
 295   ├───scanning:
 296         }}(Exim $version_number)
 297         ${if def:sender_address {(envelope-from <$sender_address>)
 298         }}id $message_exim_id${if def:received_for {
 299         for $received_for}}
 300   ├───────text:
 301
 302   ├───scanning: }}(Exim $version_number)
 303         ${if def:sender_address {(envelope-from <$sender_address>)
 304         }}id $message_exim_id${if def:received_for {
 305         for $received_for}}
 306   ├──expanding:  tls $tls_in_cipher_std
 307
 308   ├─────result:  tls
 309
 310   ╰───skipping: result is not used
 311  ├───item-res:
 312  ├considering: (Exim $version_number)
 313         ${if def:sender_address {(envelope-from <$sender_address>)
 314         }}id $message_exim_id${if def:received_for {
 315         for $received_for}}
 316  ├───────text: (Exim
 317  ├considering: $version_number)
 318         ${if def:sender_address {(envelope-from <$sender_address>)
 319         }}id $message_exim_id${if def:received_for {
 320         for $received_for}}
 321  ├──────value: x.yz
 322  ├considering: )
 323         ${if def:sender_address {(envelope-from <$sender_address>)
 324         }}id $message_exim_id${if def:received_for {
 325         for $received_for}}
 326  ├───────text: )
 327
 328  ├considering: ${if def:sender_address {(envelope-from <$sender_address>)
 329         }}id $message_exim_id${if def:received_for {
 330         for $received_for}}
 331  ├──condition: def:sender_address
 332  ├─────result: true
 333   ╭considering: (envelope-from <$sender_address>)
 334         }}id $message_exim_id${if def:received_for {
 335         for $received_for}}
 336   ├───────text: (envelope-from <
 337   ├considering: $sender_address>)
 338         }}id $message_exim_id${if def:received_for {
 339         for $received_for}}
 340   ├──────value: CALLER@test.ex
 341   ├considering: >)
 342         }}id $message_exim_id${if def:received_for {
 343         for $received_for}}
 344   ├───────text: >)
 345
 346   ├considering: }}id $message_exim_id${if def:received_for {
 347         for $received_for}}
 348   ├──expanding: (envelope-from <$sender_address>)
 349
 350   ╰─────result: (envelope-from <CALLER@test.ex>)
 351
 352  ├───item-res: (envelope-from <CALLER@test.ex>)
 353
 354  ├considering: id $message_exim_id${if def:received_for {
 355         for $received_for}}
 356  ├───────text: id
 357  ├considering: $message_exim_id${if def:received_for {
 358         for $received_for}}
 359  ├──────value: 10HmaX-000000005vi-0000
 360  ├considering: ${if def:received_for {
 361         for $received_for}}
 362  ├──condition: def:received_for
 363  ├─────result: false
 364   ╭───scanning:
 365         for $received_for}}
 366   ├───────text:
 367         for
 368   ├───scanning: $received_for}}
 369   ├──────value:
 370   ├───scanning: }}
 371   ├──expanding:
 372         for $received_for
 373   ├─────result:
 374         for
 375   ╰───skipping: result is not used
 376  ├───item-res:
 377  ├──expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
 378         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
 379         }}}}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
 380         }}(Exim $version_number)
 381         ${if def:sender_address {(envelope-from <$sender_address>)
 382         }}id $message_exim_id${if def:received_for {
 383         for $received_for}}
 384  ╰─────result: Received: from CALLER by mail.test.ex with local (Exim x.yz)
 385         (envelope-from <CALLER@test.ex>)
 386         id 10HmaX-000000005vi-0000
 387 >>Generated Received: header line
 388 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
 389         (envelope-from <CALLER@test.ex>)
 390         id 10HmaX-000000005vi-0000;
 391         Tue, 2 Mar 1999 09:44:33 +0000
 392 try option acl_not_smtp_mime
 393 try option acl_not_smtp
 394  ╭considering: ${tod_full}
 395  ├──expanding: ${tod_full}
 396  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
 397 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
 398 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 399 DSN: **** SPOOL_OUT - address: <usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 400 DSN: **** SPOOL_OUT - address: <userz@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 401 DSN: **** SPOOL_OUT - address: <rd+CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 402 DSN: **** SPOOL_OUT - address: <rd+usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 403 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
 404 Size of headers = sss
 405 LOG: MAIN
 406   <= CALLER@test.ex U=CALLER P=local S=sss
 407 created log directory TESTSUITE/spool/log
 408 search_tidyup called
 409 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715dfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
 410 Exim version x.yz ....
 411 changed uid/gid: forcing real = effective
 412   uid=uuuu gid=EXIM_GID pid=p1235
 413 environment after trimming:
 414  PATH=<munged>
 415 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
 416 configuration file is TESTSUITE/test-config
 417 trusted user
 418 admin user
 419 dropping to exim gid; retaining priv uid
 420 seeking password data for user "CALLER": cache not available
 421 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 422 seeking password data for user "CALLER": using cached result
 423 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 424 seeking password data for user "CALLER": using cached result
 425 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 426 set_process_info: pppp delivering specified messages
 427 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 428 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 429 reading spool file 10HmaX-000000005vi-0000-H
 430 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 431 sender_local=1 ident=CALLER
 432 Non-recipients:
 433  Empty Tree
 434 ---- End of tree ----
 435 recipients_count=5
 436 **** SPOOL_IN - No additional fields
 437 **** SPOOL_IN - No additional fields
 438 **** SPOOL_IN - No additional fields
 439 **** SPOOL_IN - No additional fields
 440 **** SPOOL_IN - No additional fields
 441 body_linecount=0 message_linecount=7
 442 DSN: set orcpt:   flags: 0x0
 443 DSN: set orcpt:   flags: 0x0
 444 DSN: set orcpt:   flags: 0x0
 445 DSN: set orcpt:   flags: 0x0
 446 DSN: set orcpt:   flags: 0x0
 447 Delivery address list:
 448   CALLER@test.ex
 449   usery@test.ex
 450   userz@test.ex
 451   rd+CALLER@test.ex
 452   rd+usery@test.ex
 453  locking TESTSUITE/spool/db/retry.lockfile
 454  locked  TESTSUITE/spool/db/retry.lockfile
 455  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 456  returned from EXIM_DBOPEN: (nil)
 457  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 458 no retry data available
 459 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 460 Considering: CALLER@test.ex
 461 unique = CALLER@test.ex
 462 no   domain  retry record
 463 no   address retry record
 464 CALLER@test.ex: queued for routing
 465 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 466 Considering: usery@test.ex
 467 unique = usery@test.ex
 468 no   domain  retry record
 469 no   address retry record
 470 usery@test.ex: queued for routing
 471 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 472 Considering: userz@test.ex
 473 unique = userz@test.ex
 474 no   domain  retry record
 475 no   address retry record
 476 userz@test.ex: queued for routing
 477 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 478 Considering: rd+CALLER@test.ex
 479 unique = rd+CALLER@test.ex
 480 no   domain  retry record
 481 no   address retry record
 482 rd+CALLER@test.ex: queued for routing
 483 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 484 Considering: rd+usery@test.ex
 485 unique = rd+usery@test.ex
 486 no   domain  retry record
 487 no   address retry record
 488 rd+usery@test.ex: queued for routing
 489 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 490 routing rd+usery@test.ex
 491 --------> r1 router <--------
 492 local_part=rd+usery domain=test.ex
 493 checking local_parts
 494 rd+usery in "CALLER"?
 495  list element: CALLER
 496 rd+usery in "CALLER"? no (end of list)
 497 r1 router skipped: local_parts mismatch
 498 --------> r2 router <--------
 499 local_part=rd+usery domain=test.ex
 500 checking local_parts
 501 rd+usery in "usery"?
 502  list element: usery
 503 rd+usery in "usery"? no (end of list)
 504 r2 router skipped: local_parts mismatch
 505 --------> r3 router <--------
 506 local_part=rd+usery domain=test.ex
 507 checking local_parts
 508 rd+usery in "userz"?
 509  list element: userz
 510 rd+usery in "userz"? no (end of list)
 511 r3 router skipped: local_parts mismatch
 512 --------> r4 router <--------
 513 local_part=rd+usery domain=test.ex
 514 stripped prefix rd+
 515 checking local_parts
 516 usery in "CALLER"?
 517  list element: CALLER
 518 usery in "CALLER"? no (end of list)
 519 r4 router skipped: local_parts mismatch
 520 --------> r5 router <--------
 521 local_part=rd+usery domain=test.ex
 522 stripped prefix rd+
 523 checking local_parts
 524 usery in "usery"?
 525  list element: usery
 526  usery in "usery"? yes (matched "usery")
 527 try option router_home_directory
 528  ╭considering: /non-exist/$domain
 529  ├───────text: /non-exist/
 530  ├considering: $domain
 531  ├──────value: test.ex
 532             ╰──(tainted)
 533  ├──expanding: /non-exist/$domain
 534  ╰─────result: /non-exist/test.ex
 535             ╰──(tainted)
 536 try option set
 537 calling r5 router
 538 try option qualify_domain
 539 rda_interpret (string): 'TESTSUITE/test-mail/junk'
 540 expanded: 'TESTSUITE/test-mail/junk'
 541 file is not a filter file
 542 parse_forward_list: TESTSUITE/test-mail/junk
 543 extract item: TESTSUITE/test-mail/junk
 544 try option file_transport
 545 try option transport
 546 set transport ft1
 547 r5 router generated TESTSUITE/test-mail/junk
 548   pipe, file, or autoreply
 549   errors_to=NULL transport=ft1
 550   uid=unset gid=unset home=/non-exist/$local_part
 551 try option unseen
 552 try option unseen
 553 routed by r5 router
 554   envelope to: rd+usery@test.ex
 555   transport: <none>
 556 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 557 routing rd+CALLER@test.ex
 558 --------> r1 router <--------
 559 local_part=rd+CALLER domain=test.ex
 560 checking local_parts
 561 rd+CALLER in "CALLER"?
 562  list element: CALLER
 563 rd+CALLER in "CALLER"? no (end of list)
 564 r1 router skipped: local_parts mismatch
 565 --------> r2 router <--------
 566 local_part=rd+CALLER domain=test.ex
 567 checking local_parts
 568 rd+CALLER in "usery"?
 569  list element: usery
 570 rd+CALLER in "usery"? no (end of list)
 571 r2 router skipped: local_parts mismatch
 572 --------> r3 router <--------
 573 local_part=rd+CALLER domain=test.ex
 574 checking local_parts
 575 rd+CALLER in "userz"?
 576  list element: userz
 577 rd+CALLER in "userz"? no (end of list)
 578 r3 router skipped: local_parts mismatch
 579 --------> r4 router <--------
 580 local_part=rd+CALLER domain=test.ex
 581 stripped prefix rd+
 582 checking local_parts
 583 CALLER in "CALLER"?
 584  list element: CALLER
 585  CALLER in "CALLER"? yes (matched "CALLER")
 586 try option router_home_directory
 587  ╭considering: /non-exist/$local_part
 588  ├───────text: /non-exist/
 589  ├considering: $local_part
 590  ├──────value: CALLER
 591             ╰──(tainted)
 592  ├──expanding: /non-exist/$local_part
 593  ╰─────result: /non-exist/CALLER
 594             ╰──(tainted)
 595 try option set
 596 calling r4 router
 597 try option qualify_domain
 598 rda_interpret (string): 'TESTSUITE/test-mail/junk'
 599 expanded: 'TESTSUITE/test-mail/junk'
 600 file is not a filter file
 601 parse_forward_list: TESTSUITE/test-mail/junk
 602 extract item: TESTSUITE/test-mail/junk
 603 try option file_transport
 604 try option transport
 605 set transport ft1
 606 r4 router generated TESTSUITE/test-mail/junk
 607   pipe, file, or autoreply
 608   errors_to=NULL transport=ft1
 609   uid=unset gid=unset home=/non-exist/CALLER
 610 try option unseen
 611 try option unseen
 612 routed by r4 router
 613   envelope to: rd+CALLER@test.ex
 614   transport: <none>
 615 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 616 routing userz@test.ex
 617 --------> r1 router <--------
 618 local_part=userz domain=test.ex
 619 checking local_parts
 620 userz in "CALLER"?
 621  list element: CALLER
 622 userz in "CALLER"? no (end of list)
 623 r1 router skipped: local_parts mismatch
 624 --------> r2 router <--------
 625 local_part=userz domain=test.ex
 626 checking local_parts
 627 userz in "usery"?
 628  list element: usery
 629 userz in "usery"? no (end of list)
 630 r2 router skipped: local_parts mismatch
 631 --------> r3 router <--------
 632 local_part=userz domain=test.ex
 633 checking local_parts
 634 userz in "userz"?
 635  list element: userz
 636  userz in "userz"? yes (matched "userz")
 637 try option router_home_directory
 638  ╭considering: /non-exist/$domain
 639  ├───────text: /non-exist/
 640  ├considering: $domain
 641  ├──────value: test.ex
 642             ╰──(tainted)
 643  ├──expanding: /non-exist/$domain
 644  ╰─────result: /non-exist/test.ex
 645             ╰──(tainted)
 646 try option set
 647 calling r3 router
 648 r3 router called for userz@test.ex
 649   domain = test.ex
 650 try option transport
 651 set transport t2
 652 queued for t2 transport: local_part = userz
 653 domain = test.ex
 654   errors_to=NULL
 655   domain_data=NULL local_part_data=userz
 656 try option unseen
 657 try option unseen
 658 routed by r3 router
 659   envelope to: userz@test.ex
 660   transport: t2
 661 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 662 routing usery@test.ex
 663 --------> r1 router <--------
 664 local_part=usery domain=test.ex
 665 checking local_parts
 666 usery in "CALLER"?
 667  list element: CALLER
 668 usery in "CALLER"? no (end of list)
 669 r1 router skipped: local_parts mismatch
 670 --------> r2 router <--------
 671 local_part=usery domain=test.ex
 672 checking local_parts
 673 usery in "usery"?
 674  list element: usery
 675  usery in "usery"? yes (matched "usery")
 676 try option router_home_directory
 677  ╭considering: /non-exist/$domain
 678  ├───────text: /non-exist/
 679  ├considering: $domain
 680  ├──────value: test.ex
 681             ╰──(tainted)
 682  ├──expanding: /non-exist/$domain
 683  ╰─────result: /non-exist/test.ex
 684             ╰──(tainted)
 685 try option set
 686 calling r2 router
 687 r2 router called for usery@test.ex
 688   domain = test.ex
 689 try option transport
 690 set transport t1
 691 queued for t1 transport: local_part = usery
 692 domain = test.ex
 693   errors_to=NULL
 694   domain_data=NULL local_part_data=usery
 695 try option unseen
 696 try option unseen
 697 routed by r2 router
 698   envelope to: usery@test.ex
 699   transport: t1
 700 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 701 routing CALLER@test.ex
 702 --------> r1 router <--------
 703 local_part=CALLER domain=test.ex
 704 checking local_parts
 705 CALLER in "CALLER"?
 706  list element: CALLER
 707  CALLER in "CALLER"? yes (matched "CALLER")
 708 try option router_home_directory
 709  ╭considering: /non-exist/$local_part
 710  ├───────text: /non-exist/
 711  ├considering: $local_part
 712  ├──────value: CALLER
 713             ╰──(tainted)
 714  ├──expanding: /non-exist/$local_part
 715  ╰─────result: /non-exist/CALLER
 716             ╰──(tainted)
 717 try option set
 718 calling r1 router
 719 r1 router called for CALLER@test.ex
 720   domain = test.ex
 721 try option transport
 722 set transport t1
 723 queued for t1 transport: local_part = CALLER
 724 domain = test.ex
 725   errors_to=NULL
 726   domain_data=NULL local_part_data=CALLER
 727 try option unseen
 728 try option unseen
 729 routed by r1 router
 730   envelope to: CALLER@test.ex
 731   transport: t1
 732  locking TESTSUITE/spool/db/retry.lockfile
 733  locked  TESTSUITE/spool/db/retry.lockfile
 734  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 735  returned from EXIM_DBOPEN: (nil)
 736  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 737 no retry data available
 738 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 739 Considering: TESTSUITE/test-mail/junk
 740 unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 741 queued for ft1 transport
 742 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 743 Considering: TESTSUITE/test-mail/junk
 744 unique = TESTSUITE/test-mail/junk:rd+usery@test.ex
 745 queued for ft1 transport
 746 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 747 After routing:
 748   Local deliveries:
 749     TESTSUITE/test-mail/junk
 750     TESTSUITE/test-mail/junk
 751     CALLER@test.ex
 752     usery@test.ex
 753     userz@test.ex
 754   Remote deliveries:
 755   Failed addresses:
 756   Deferred addresses:
 757 search_tidyup called
 758 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 759 --------> TESTSUITE/test-mail/junk <--------
 760  locking TESTSUITE/spool/db/retry.lockfile
 761  locked  TESTSUITE/spool/db/retry.lockfile
 762  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 763  returned from EXIM_DBOPEN: (nil)
 764  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 765 no retry data available
 766 try option max_parallel
 767 try option return_path
 768 try option home_directory
 769  ╭considering: /non-exist/$local_part
 770  ├───────text: /non-exist/
 771  ├considering: $local_part
 772  ├──────value: usery
 773             ╰──(tainted)
 774  ├──expanding: /non-exist/$local_part
 775  ╰─────result: /non-exist/usery
 776             ╰──(tainted)
 777 try option current_directory
 778 search_tidyup called
 779 try option quota
 780 try option quota_filecount
 781 try option quota_warn_threshold
 782 try option mailbox_size
 783 try option mailbox_filecount
 784 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 785   uid=CALLER_UID gid=CALLER_GID pid=p1236
 786   home=/non-exist/usery current=/
 787 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using ft1
 788 appendfile transport entered
 789  ╭considering: $address_file
 790  ├──────value: TESTSUITE/test-mail/junk
 791  ├──expanding: $address_file
 792  ╰─────result: TESTSUITE/test-mail/junk
 793 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 794   file=TESTSUITE/test-mail/junk format=unix
 795   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 796   message_suffix=\n
 797   maildir_use_size_file=no
 798   locking by lockfile fcntl
 799 lock name: TESTSUITE/test-mail/junk.lock
 800 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 801 lock file created
 802 mailbox TESTSUITE/test-mail/junk is locked
 803 writing to file TESTSUITE/test-mail/junk
 804 try option message_prefix
 805  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 806
 807  ├───────text: From
 808  ├considering: ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 809
 810  ├──condition: def:return_path
 811  ├─────result: true
 812   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 813
 814   ├──────value: CALLER@test.ex
 815              ╰──(tainted)
 816   ├considering: }{MAILER-DAEMON}} ${tod_bsdinbox}
 817
 818   ├──expanding: $return_path
 819   ╰─────result: CALLER@test.ex
 820              ╰──(tainted)
 821   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 822
 823   ├───────text: MAILER-DAEMON
 824   ├───scanning: }} ${tod_bsdinbox}
 825
 826   ├──expanding: MAILER-DAEMON
 827   ├─────result: MAILER-DAEMON
 828   ╰───skipping: result is not used
 829  ├───item-res: CALLER@test.ex
 830             ╰──(tainted)
 831  ├considering:  ${tod_bsdinbox}
 832
 833  ├───────text:
 834  ├considering: ${tod_bsdinbox}
 835
 836  ├considering:
 837
 838  ├───────text:
 839
 840  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 841
 842  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 843
 844             ╰──(tainted)
 845 writing data block fd=dddd size=sss timeout=0
 846 cannot use sendfile for body: spoolfile not wireformat
 847 writing data block fd=dddd size=sss timeout=0
 848 try option message_suffix
 849 writing data block fd=dddd size=sss timeout=0
 850 appendfile yields 0 with errno=dd more_errno=dd
 851 search_tidyup called
 852 journalling TESTSUITE/test-mail/junk:rd+usery@test.ex
 853 ft1 transport returned OK for TESTSUITE/test-mail/junk
 854 post-process TESTSUITE/test-mail/junk (0)
 855 TESTSUITE/test-mail/junk delivered
 856 rd+usery@test.ex: children all complete
 857 LOG: MAIN
 858   => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1
 859 --------> TESTSUITE/test-mail/junk <--------
 860  locking TESTSUITE/spool/db/retry.lockfile
 861  locked  TESTSUITE/spool/db/retry.lockfile
 862  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 863  returned from EXIM_DBOPEN: (nil)
 864  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 865 no retry data available
 866 try option max_parallel
 867 try option return_path
 868 try option home_directory
 869 try option current_directory
 870 search_tidyup called
 871 try option quota
 872 try option quota_filecount
 873 try option quota_warn_threshold
 874 try option mailbox_size
 875 try option mailbox_filecount
 876 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 877   uid=CALLER_UID gid=CALLER_GID pid=p1237
 878   home=/non-exist/CALLER current=/
 879 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using ft1
 880 appendfile transport entered
 881  ╭considering: $address_file
 882  ├──────value: TESTSUITE/test-mail/junk
 883  ├──expanding: $address_file
 884  ╰─────result: TESTSUITE/test-mail/junk
 885 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 886   file=TESTSUITE/test-mail/junk format=unix
 887   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 888   message_suffix=\n
 889   maildir_use_size_file=no
 890   locking by lockfile fcntl
 891 lock name: TESTSUITE/test-mail/junk.lock
 892 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 893 lock file created
 894 mailbox TESTSUITE/test-mail/junk is locked
 895 writing to file TESTSUITE/test-mail/junk
 896 try option message_prefix
 897  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 898
 899  ├───────text: From
 900  ├considering: ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 901
 902  ├──condition: def:return_path
 903  ├─────result: true
 904   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 905
 906   ├──────value: CALLER@test.ex
 907              ╰──(tainted)
 908   ├considering: }{MAILER-DAEMON}} ${tod_bsdinbox}
 909
 910   ├──expanding: $return_path
 911   ╰─────result: CALLER@test.ex
 912              ╰──(tainted)
 913   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 914
 915   ├───────text: MAILER-DAEMON
 916   ├───scanning: }} ${tod_bsdinbox}
 917
 918   ├──expanding: MAILER-DAEMON
 919   ├─────result: MAILER-DAEMON
 920   ╰───skipping: result is not used
 921  ├───item-res: CALLER@test.ex
 922             ╰──(tainted)
 923  ├considering:  ${tod_bsdinbox}
 924
 925  ├───────text:
 926  ├considering: ${tod_bsdinbox}
 927
 928  ├considering:
 929
 930  ├───────text:
 931
 932  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 933
 934  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 935
 936             ╰──(tainted)
 937 writing data block fd=dddd size=sss timeout=0
 938 cannot use sendfile for body: spoolfile not wireformat
 939 writing data block fd=dddd size=sss timeout=0
 940 try option message_suffix
 941 writing data block fd=dddd size=sss timeout=0
 942 appendfile yields 0 with errno=dd more_errno=dd
 943 search_tidyup called
 944 journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 945 ft1 transport returned OK for TESTSUITE/test-mail/junk
 946 post-process TESTSUITE/test-mail/junk (0)
 947 TESTSUITE/test-mail/junk delivered
 948 rd+CALLER@test.ex: children all complete
 949 LOG: MAIN
 950   => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1
 951 --------> CALLER@test.ex <--------
 952  locking TESTSUITE/spool/db/retry.lockfile
 953  locked  TESTSUITE/spool/db/retry.lockfile
 954  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 955  returned from EXIM_DBOPEN: (nil)
 956  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 957 no retry data available
 958 try option max_parallel
 959 try option return_path
 960 try option home_directory
 961 try option current_directory
 962 search_tidyup called
 963 try option quota
 964 try option quota_filecount
 965 try option quota_warn_threshold
 966 try option mailbox_size
 967 try option mailbox_filecount
 968 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 969   uid=CALLER_UID gid=CALLER_GID pid=p1238
 970   home=/non-exist/CALLER current=/
 971 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
 972 appendfile transport entered
 973 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 974   file=/dev/null format=unix
 975   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 976   message_suffix=\n
 977   maildir_use_size_file=no
 978   locking by lockfile fcntl
 979 search_tidyup called
 980 journalling CALLER@test.ex
 981 t1 transport returned OK for CALLER@test.ex
 982 post-process CALLER@test.ex (0)
 983 CALLER@test.ex delivered
 984 LOG: MAIN
 985   => CALLER <CALLER@test.ex> R=r1 T=t1
 986 --------> usery@test.ex <--------
 987  locking TESTSUITE/spool/db/retry.lockfile
 988  locked  TESTSUITE/spool/db/retry.lockfile
 989  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 990  returned from EXIM_DBOPEN: (nil)
 991  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 992 no retry data available
 993 try option max_parallel
 994 try option return_path
 995 try option home_directory
 996  ╭considering: /non-exist/$local_part
 997  ├───────text: /non-exist/
 998  ├considering: $local_part
 999  ├──────value: usery
1000             ╰──(tainted)
1001  ├──expanding: /non-exist/$local_part
1002  ╰─────result: /non-exist/usery
1003             ╰──(tainted)
1004 try option current_directory
1005 search_tidyup called
1006 try option quota
1007 try option quota_filecount
1008 try option quota_warn_threshold
1009 try option mailbox_size
1010 try option mailbox_filecount
1011 changed uid/gid: local delivery to usery <usery@test.ex> transport=t1
1012   uid=CALLER_UID gid=CALLER_GID pid=p1239
1013   home=/non-exist/usery current=/
1014 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to usery using t1
1015 appendfile transport entered
1016 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
1017   file=/dev/null format=unix
1018   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
1019   message_suffix=\n
1020   maildir_use_size_file=no
1021   locking by lockfile fcntl
1022 search_tidyup called
1023 journalling usery@test.ex
1024 t1 transport returned OK for usery@test.ex
1025 post-process usery@test.ex (0)
1026 usery@test.ex delivered
1027 LOG: MAIN
1028   => usery <usery@test.ex> R=r2 T=t1
1029 --------> userz@test.ex <--------
1030  locking TESTSUITE/spool/db/retry.lockfile
1031  locked  TESTSUITE/spool/db/retry.lockfile
1032  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
1033  returned from EXIM_DBOPEN: (nil)
1034  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
1035 no retry data available
1036 try option max_parallel
1037 try option return_path
1038 try option home_directory
1039  ╭considering: /$local_part
1040  ├───────text: /
1041  ├considering: $local_part
1042  ├──────value: userz
1043             ╰──(tainted)
1044  ├──expanding: /$local_part
1045  ╰─────result: /userz
1046             ╰──(tainted)
1047 try option current_directory
1048 search_tidyup called
1049 try option quota
1050 try option quota_filecount
1051 try option quota_warn_threshold
1052 try option mailbox_size
1053 try option mailbox_filecount
1054 changed uid/gid: local delivery to userz <userz@test.ex> transport=t2
1055   uid=CALLER_UID gid=CALLER_GID pid=p1240
1056   home=/userz current=/
1057 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to userz using t2
1058 appendfile transport entered
1059 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
1060   file=/dev/null format=unix
1061   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
1062   message_suffix=\n
1063   maildir_use_size_file=no
1064   locking by lockfile fcntl
1065 search_tidyup called
1066 journalling userz@test.ex
1067 t2 transport returned OK for userz@test.ex
1068 post-process userz@test.ex (0)
1069 userz@test.ex delivered
1070 LOG: MAIN
1071   => userz <userz@test.ex> R=r3 T=t2
1072 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
1073 changed uid/gid: post-delivery tidying
1074   uid=EXIM_UID gid=EXIM_GID pid=p1235
1075 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
1076 Processing retry items
1077 Succeeded addresses:
1078  userz@test.ex: no retry items
1079  usery@test.ex: no retry items
1080  CALLER@test.ex: no retry items
1081  TESTSUITE/test-mail/junk: no retry items
1082  rd+CALLER@test.ex: no retry items
1083  TESTSUITE/test-mail/junk: no retry items
1084  rd+usery@test.ex: no retry items
1085  rd+CALLER@test.ex: no retry items
1086  rd+usery@test.ex: no retry items
1087 Failed addresses:
1088 Deferred addresses:
1089 end of retry processing
1090 DSN: processing router : r3
1091 DSN: processing successful delivery address: userz@test.ex
1092 DSN: Sender_address: CALLER@test.ex
1093 DSN: orcpt: NULL  flags: 0x0
1094 DSN: envid: NULL  ret: 0
1095 DSN: Final recipient: userz@test.ex
1096 DSN: Remote SMTP server supports DSN: 0
1097 DSN: not sending DSN success message
1098 DSN: processing router : r2
1099 DSN: processing successful delivery address: usery@test.ex
1100 DSN: Sender_address: CALLER@test.ex
1101 DSN: orcpt: NULL  flags: 0x0
1102 DSN: envid: NULL  ret: 0
1103 DSN: Final recipient: usery@test.ex
1104 DSN: Remote SMTP server supports DSN: 0
1105 DSN: not sending DSN success message
1106 DSN: processing router : r1
1107 DSN: processing successful delivery address: CALLER@test.ex
1108 DSN: Sender_address: CALLER@test.ex
1109 DSN: orcpt: NULL  flags: 0x0
1110 DSN: envid: NULL  ret: 0
1111 DSN: Final recipient: CALLER@test.ex
1112 DSN: Remote SMTP server supports DSN: 0
1113 DSN: not sending DSN success message
1114 DSN: processing router : r4
1115 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
1116 DSN: Sender_address: CALLER@test.ex
1117 DSN: orcpt: NULL  flags: 0x0
1118 DSN: envid: NULL  ret: 0
1119 DSN: Final recipient: TESTSUITE/test-mail/junk
1120 DSN: Remote SMTP server supports DSN: 0
1121 DSN: not sending DSN success message
1122 DSN: processing router : r5
1123 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
1124 DSN: Sender_address: CALLER@test.ex
1125 DSN: orcpt: NULL  flags: 0x0
1126 DSN: envid: NULL  ret: 0
1127 DSN: Final recipient: TESTSUITE/test-mail/junk
1128 DSN: Remote SMTP server supports DSN: 0
1129 DSN: not sending DSN success message
1130 DSN: processing router : r4
1131 DSN: processing successful delivery address: rd+CALLER@test.ex
1132 DSN: Sender_address: CALLER@test.ex
1133 DSN: orcpt: NULL  flags: 0x0
1134 DSN: envid: NULL  ret: 0
1135 DSN: Final recipient: rd+CALLER@test.ex
1136 DSN: Remote SMTP server supports DSN: 0
1137 DSN: not sending DSN success message
1138 DSN: processing router : r5
1139 DSN: processing successful delivery address: rd+usery@test.ex
1140 DSN: Sender_address: CALLER@test.ex
1141 DSN: orcpt: NULL  flags: 0x0
1142 DSN: envid: NULL  ret: 0
1143 DSN: Final recipient: rd+usery@test.ex
1144 DSN: Remote SMTP server supports DSN: 0
1145 DSN: not sending DSN success message
1146 LOG: MAIN
1147   Completed
1148 end delivery of 10HmaX-000000005vi-0000
1149 search_tidyup called
1150 search_tidyup called
1151 >>>>>>>>>>>>>>>> Exim pid=p1235 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
1152 search_tidyup called
1153 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>