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
 393  ╭considering: ${tod_full}
 394  ├──expanding: ${tod_full}
 395  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
 396 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
 397 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 398 DSN: **** SPOOL_OUT - address: <usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 399 DSN: **** SPOOL_OUT - address: <userz@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 400 DSN: **** SPOOL_OUT - address: <rd+CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 401 DSN: **** SPOOL_OUT - address: <rd+usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
 402 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
 403 Size of headers = sss
 404 LOG: MAIN
 405   <= CALLER@test.ex U=CALLER P=local S=sss
 406 created log directory TESTSUITE/spool/log
 407 search_tidyup called
 408 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715dfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
 409 Exim version x.yz ....
 410 changed uid/gid: forcing real = effective
 411   uid=uuuu gid=EXIM_GID pid=p1235
 412 environment after trimming:
 413  PATH=<munged>
 414 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
 415 configuration file is TESTSUITE/test-config
 416 trusted user
 417 admin user
 418 dropping to exim gid; retaining priv uid
 419 seeking password data for user "CALLER": cache not available
 420 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 421 seeking password data for user "CALLER": using cached result
 422 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 423 seeking password data for user "CALLER": using cached result
 424 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
 425 set_process_info: pppp delivering specified messages
 426 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 427 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
 428 reading spool file 10HmaX-000000005vi-0000-H
 429 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
 430 sender_local=1 ident=CALLER
 431 Non-recipients:
 432  Empty Tree
 433 ---- End of tree ----
 434 recipients_count=5
 435 **** SPOOL_IN - No additional fields
 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 body_linecount=0 message_linecount=7
 441 DSN: set orcpt:   flags: 0x0
 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 Delivery address list:
 447   CALLER@test.ex
 448   usery@test.ex
 449   userz@test.ex
 450   rd+CALLER@test.ex
 451   rd+usery@test.ex
 452  locking TESTSUITE/spool/db/retry.lockfile
 453  locked  TESTSUITE/spool/db/retry.lockfile
 454  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 455  returned from EXIM_DBOPEN: (nil)
 456  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 457 no retry data available
 458 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 459 Considering: CALLER@test.ex
 460 unique = CALLER@test.ex
 461 no   domain  retry record
 462 no   address retry record
 463 CALLER@test.ex: queued for routing
 464 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 465 Considering: usery@test.ex
 466 unique = usery@test.ex
 467 no   domain  retry record
 468 no   address retry record
 469 usery@test.ex: queued for routing
 470 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 471 Considering: userz@test.ex
 472 unique = userz@test.ex
 473 no   domain  retry record
 474 no   address retry record
 475 userz@test.ex: queued for routing
 476 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 477 Considering: rd+CALLER@test.ex
 478 unique = rd+CALLER@test.ex
 479 no   domain  retry record
 480 no   address retry record
 481 rd+CALLER@test.ex: queued for routing
 482 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 483 Considering: rd+usery@test.ex
 484 unique = rd+usery@test.ex
 485 no   domain  retry record
 486 no   address retry record
 487 rd+usery@test.ex: queued for routing
 488 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 489 routing rd+usery@test.ex
 490 --------> r1 router <--------
 491 local_part=rd+usery domain=test.ex
 492 checking local_parts
 493 rd+usery in "CALLER"?
 494  list element: CALLER
 495 rd+usery in "CALLER"? no (end of list)
 496 r1 router skipped: local_parts mismatch
 497 --------> r2 router <--------
 498 local_part=rd+usery domain=test.ex
 499 checking local_parts
 500 rd+usery in "usery"?
 501  list element: usery
 502 rd+usery in "usery"? no (end of list)
 503 r2 router skipped: local_parts mismatch
 504 --------> r3 router <--------
 505 local_part=rd+usery domain=test.ex
 506 checking local_parts
 507 rd+usery in "userz"?
 508  list element: userz
 509 rd+usery in "userz"? no (end of list)
 510 r3 router skipped: local_parts mismatch
 511 --------> r4 router <--------
 512 local_part=rd+usery domain=test.ex
 513 stripped prefix rd+
 514 checking local_parts
 515 usery in "CALLER"?
 516  list element: CALLER
 517 usery in "CALLER"? no (end of list)
 518 r4 router skipped: local_parts mismatch
 519 --------> r5 router <--------
 520 local_part=rd+usery domain=test.ex
 521 stripped prefix rd+
 522 checking local_parts
 523 usery in "usery"?
 524  list element: usery
 525  usery in "usery"? yes (matched "usery")
 526 try option router_home_directory
 527  ╭considering: /non-exist/$domain
 528  ├───────text: /non-exist/
 529  ├considering: $domain
 530  ├──────value: test.ex
 531             ╰──(tainted)
 532  ├──expanding: /non-exist/$domain
 533  ╰─────result: /non-exist/test.ex
 534             ╰──(tainted)
 535 try option set
 536 calling r5 router
 537 try option qualify_domain
 538 rda_interpret (string): 'TESTSUITE/test-mail/junk'
 539 expanded: 'TESTSUITE/test-mail/junk'
 540 file is not a filter file
 541 parse_forward_list: TESTSUITE/test-mail/junk
 542 extract item: TESTSUITE/test-mail/junk
 543 try option file_transport
 544 try option transport
 545 set transport ft1
 546 r5 router generated TESTSUITE/test-mail/junk
 547   pipe, file, or autoreply
 548   errors_to=NULL transport=ft1
 549   uid=unset gid=unset home=/non-exist/$local_part
 550 try option unseen
 551 try option unseen
 552 routed by r5 router
 553   envelope to: rd+usery@test.ex
 554   transport: <none>
 555 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 556 routing rd+CALLER@test.ex
 557 --------> r1 router <--------
 558 local_part=rd+CALLER domain=test.ex
 559 checking local_parts
 560 rd+CALLER in "CALLER"?
 561  list element: CALLER
 562 rd+CALLER in "CALLER"? no (end of list)
 563 r1 router skipped: local_parts mismatch
 564 --------> r2 router <--------
 565 local_part=rd+CALLER domain=test.ex
 566 checking local_parts
 567 rd+CALLER in "usery"?
 568  list element: usery
 569 rd+CALLER in "usery"? no (end of list)
 570 r2 router skipped: local_parts mismatch
 571 --------> r3 router <--------
 572 local_part=rd+CALLER domain=test.ex
 573 checking local_parts
 574 rd+CALLER in "userz"?
 575  list element: userz
 576 rd+CALLER in "userz"? no (end of list)
 577 r3 router skipped: local_parts mismatch
 578 --------> r4 router <--------
 579 local_part=rd+CALLER domain=test.ex
 580 stripped prefix rd+
 581 checking local_parts
 582 CALLER in "CALLER"?
 583  list element: CALLER
 584  CALLER in "CALLER"? yes (matched "CALLER")
 585 try option router_home_directory
 586  ╭considering: /non-exist/$local_part
 587  ├───────text: /non-exist/
 588  ├considering: $local_part
 589  ├──────value: CALLER
 590             ╰──(tainted)
 591  ├──expanding: /non-exist/$local_part
 592  ╰─────result: /non-exist/CALLER
 593             ╰──(tainted)
 594 try option set
 595 calling r4 router
 596 try option qualify_domain
 597 rda_interpret (string): 'TESTSUITE/test-mail/junk'
 598 expanded: 'TESTSUITE/test-mail/junk'
 599 file is not a filter file
 600 parse_forward_list: TESTSUITE/test-mail/junk
 601 extract item: TESTSUITE/test-mail/junk
 602 try option file_transport
 603 try option transport
 604 set transport ft1
 605 r4 router generated TESTSUITE/test-mail/junk
 606   pipe, file, or autoreply
 607   errors_to=NULL transport=ft1
 608   uid=unset gid=unset home=/non-exist/CALLER
 609 try option unseen
 610 try option unseen
 611 routed by r4 router
 612   envelope to: rd+CALLER@test.ex
 613   transport: <none>
 614 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 615 routing userz@test.ex
 616 --------> r1 router <--------
 617 local_part=userz domain=test.ex
 618 checking local_parts
 619 userz in "CALLER"?
 620  list element: CALLER
 621 userz in "CALLER"? no (end of list)
 622 r1 router skipped: local_parts mismatch
 623 --------> r2 router <--------
 624 local_part=userz domain=test.ex
 625 checking local_parts
 626 userz in "usery"?
 627  list element: usery
 628 userz in "usery"? no (end of list)
 629 r2 router skipped: local_parts mismatch
 630 --------> r3 router <--------
 631 local_part=userz domain=test.ex
 632 checking local_parts
 633 userz in "userz"?
 634  list element: userz
 635  userz in "userz"? yes (matched "userz")
 636 try option router_home_directory
 637  ╭considering: /non-exist/$domain
 638  ├───────text: /non-exist/
 639  ├considering: $domain
 640  ├──────value: test.ex
 641             ╰──(tainted)
 642  ├──expanding: /non-exist/$domain
 643  ╰─────result: /non-exist/test.ex
 644             ╰──(tainted)
 645 try option set
 646 calling r3 router
 647 r3 router called for userz@test.ex
 648   domain = test.ex
 649 try option transport
 650 set transport t2
 651 queued for t2 transport: local_part = userz
 652 domain = test.ex
 653   errors_to=NULL
 654   domain_data=NULL local_part_data=userz
 655 try option unseen
 656 try option unseen
 657 routed by r3 router
 658   envelope to: userz@test.ex
 659   transport: t2
 660 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 661 routing usery@test.ex
 662 --------> r1 router <--------
 663 local_part=usery domain=test.ex
 664 checking local_parts
 665 usery in "CALLER"?
 666  list element: CALLER
 667 usery in "CALLER"? no (end of list)
 668 r1 router skipped: local_parts mismatch
 669 --------> r2 router <--------
 670 local_part=usery domain=test.ex
 671 checking local_parts
 672 usery in "usery"?
 673  list element: usery
 674  usery in "usery"? yes (matched "usery")
 675 try option router_home_directory
 676  ╭considering: /non-exist/$domain
 677  ├───────text: /non-exist/
 678  ├considering: $domain
 679  ├──────value: test.ex
 680             ╰──(tainted)
 681  ├──expanding: /non-exist/$domain
 682  ╰─────result: /non-exist/test.ex
 683             ╰──(tainted)
 684 try option set
 685 calling r2 router
 686 r2 router called for usery@test.ex
 687   domain = test.ex
 688 try option transport
 689 set transport t1
 690 queued for t1 transport: local_part = usery
 691 domain = test.ex
 692   errors_to=NULL
 693   domain_data=NULL local_part_data=usery
 694 try option unseen
 695 try option unseen
 696 routed by r2 router
 697   envelope to: usery@test.ex
 698   transport: t1
 699 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 700 routing CALLER@test.ex
 701 --------> r1 router <--------
 702 local_part=CALLER domain=test.ex
 703 checking local_parts
 704 CALLER in "CALLER"?
 705  list element: CALLER
 706  CALLER in "CALLER"? yes (matched "CALLER")
 707 try option router_home_directory
 708  ╭considering: /non-exist/$local_part
 709  ├───────text: /non-exist/
 710  ├considering: $local_part
 711  ├──────value: CALLER
 712             ╰──(tainted)
 713  ├──expanding: /non-exist/$local_part
 714  ╰─────result: /non-exist/CALLER
 715             ╰──(tainted)
 716 try option set
 717 calling r1 router
 718 r1 router called for CALLER@test.ex
 719   domain = test.ex
 720 try option transport
 721 set transport t1
 722 queued for t1 transport: local_part = CALLER
 723 domain = test.ex
 724   errors_to=NULL
 725   domain_data=NULL local_part_data=CALLER
 726 try option unseen
 727 try option unseen
 728 routed by r1 router
 729   envelope to: CALLER@test.ex
 730   transport: t1
 731  locking TESTSUITE/spool/db/retry.lockfile
 732  locked  TESTSUITE/spool/db/retry.lockfile
 733  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 734  returned from EXIM_DBOPEN: (nil)
 735  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 736 no retry data available
 737 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 738 Considering: TESTSUITE/test-mail/junk
 739 unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 740 queued for ft1 transport
 741 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 742 Considering: TESTSUITE/test-mail/junk
 743 unique = TESTSUITE/test-mail/junk:rd+usery@test.ex
 744 queued for ft1 transport
 745 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 746 After routing:
 747   Local deliveries:
 748     TESTSUITE/test-mail/junk
 749     TESTSUITE/test-mail/junk
 750     CALLER@test.ex
 751     usery@test.ex
 752     userz@test.ex
 753   Remote deliveries:
 754   Failed addresses:
 755   Deferred addresses:
 756 search_tidyup called
 757 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
 758 --------> TESTSUITE/test-mail/junk <--------
 759  locking TESTSUITE/spool/db/retry.lockfile
 760  locked  TESTSUITE/spool/db/retry.lockfile
 761  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 762  returned from EXIM_DBOPEN: (nil)
 763  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 764 no retry data available
 765 try option max_parallel
 766 try option return_path
 767 try option home_directory
 768  ╭considering: /non-exist/$local_part
 769  ├───────text: /non-exist/
 770  ├considering: $local_part
 771  ├──────value: usery
 772             ╰──(tainted)
 773  ├──expanding: /non-exist/$local_part
 774  ╰─────result: /non-exist/usery
 775             ╰──(tainted)
 776 try option current_directory
 777 search_tidyup called
 778 try option quota
 779 try option quota_filecount
 780 try option quota_warn_threshold
 781 try option mailbox_size
 782 try option mailbox_filecount
 783 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 784   uid=CALLER_UID gid=CALLER_GID pid=p1236
 785   home=/non-exist/usery current=/
 786 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using ft1
 787 appendfile transport entered
 788  ╭considering: $address_file
 789  ├──────value: TESTSUITE/test-mail/junk
 790  ├──expanding: $address_file
 791  ╰─────result: TESTSUITE/test-mail/junk
 792 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 793   file=TESTSUITE/test-mail/junk format=unix
 794   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 795   message_suffix=\n
 796   maildir_use_size_file=no
 797   locking by lockfile fcntl
 798 lock name: TESTSUITE/test-mail/junk.lock
 799 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 800 lock file created
 801 mailbox TESTSUITE/test-mail/junk is locked
 802 writing to file TESTSUITE/test-mail/junk
 803 try option message_prefix
 804  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 805
 806  ├───────text: From
 807  ├considering: ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 808
 809  ├──condition: def:return_path
 810  ├─────result: true
 811   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 812
 813   ├──────value: CALLER@test.ex
 814              ╰──(tainted)
 815   ├considering: }{MAILER-DAEMON}} ${tod_bsdinbox}
 816
 817   ├──expanding: $return_path
 818   ╰─────result: CALLER@test.ex
 819              ╰──(tainted)
 820   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 821
 822   ├───────text: MAILER-DAEMON
 823   ├───scanning: }} ${tod_bsdinbox}
 824
 825   ├──expanding: MAILER-DAEMON
 826   ├─────result: MAILER-DAEMON
 827   ╰───skipping: result is not used
 828  ├───item-res: CALLER@test.ex
 829             ╰──(tainted)
 830  ├considering:  ${tod_bsdinbox}
 831
 832  ├───────text:
 833  ├considering: ${tod_bsdinbox}
 834
 835  ├considering:
 836
 837  ├───────text:
 838
 839  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 840
 841  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 842
 843             ╰──(tainted)
 844 writing data block fd=dddd size=sss timeout=0
 845 cannot use sendfile for body: spoolfile not wireformat
 846 writing data block fd=dddd size=sss timeout=0
 847 try option message_suffix
 848 writing data block fd=dddd size=sss timeout=0
 849 appendfile yields 0 with errno=dd more_errno=dd
 850 search_tidyup called
 851 journalling TESTSUITE/test-mail/junk:rd+usery@test.ex
 852 ft1 transport returned OK for TESTSUITE/test-mail/junk
 853 post-process TESTSUITE/test-mail/junk (0)
 854 TESTSUITE/test-mail/junk delivered
 855 rd+usery@test.ex: children all complete
 856 LOG: MAIN
 857   => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1
 858 --------> TESTSUITE/test-mail/junk <--------
 859  locking TESTSUITE/spool/db/retry.lockfile
 860  locked  TESTSUITE/spool/db/retry.lockfile
 861  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 862  returned from EXIM_DBOPEN: (nil)
 863  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 864 no retry data available
 865 try option max_parallel
 866 try option return_path
 867 try option home_directory
 868 try option current_directory
 869 search_tidyup called
 870 try option quota
 871 try option quota_filecount
 872 try option quota_warn_threshold
 873 try option mailbox_size
 874 try option mailbox_filecount
 875 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
 876   uid=CALLER_UID gid=CALLER_GID pid=p1237
 877   home=/non-exist/CALLER current=/
 878 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using ft1
 879 appendfile transport entered
 880  ╭considering: $address_file
 881  ├──────value: TESTSUITE/test-mail/junk
 882  ├──expanding: $address_file
 883  ╰─────result: TESTSUITE/test-mail/junk
 884 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 885   file=TESTSUITE/test-mail/junk format=unix
 886   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 887   message_suffix=\n
 888   maildir_use_size_file=no
 889   locking by lockfile fcntl
 890 lock name: TESTSUITE/test-mail/junk.lock
 891 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
 892 lock file created
 893 mailbox TESTSUITE/test-mail/junk is locked
 894 writing to file TESTSUITE/test-mail/junk
 895 try option message_prefix
 896  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 897
 898  ├───────text: From
 899  ├considering: ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 900
 901  ├──condition: def:return_path
 902  ├─────result: true
 903   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 904
 905   ├──────value: CALLER@test.ex
 906              ╰──(tainted)
 907   ├considering: }{MAILER-DAEMON}} ${tod_bsdinbox}
 908
 909   ├──expanding: $return_path
 910   ╰─────result: CALLER@test.ex
 911              ╰──(tainted)
 912   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
 913
 914   ├───────text: MAILER-DAEMON
 915   ├───scanning: }} ${tod_bsdinbox}
 916
 917   ├──expanding: MAILER-DAEMON
 918   ├─────result: MAILER-DAEMON
 919   ╰───skipping: result is not used
 920  ├───item-res: CALLER@test.ex
 921             ╰──(tainted)
 922  ├considering:  ${tod_bsdinbox}
 923
 924  ├───────text:
 925  ├considering: ${tod_bsdinbox}
 926
 927  ├considering:
 928
 929  ├───────text:
 930
 931  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
 932
 933  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
 934
 935             ╰──(tainted)
 936 writing data block fd=dddd size=sss timeout=0
 937 cannot use sendfile for body: spoolfile not wireformat
 938 writing data block fd=dddd size=sss timeout=0
 939 try option message_suffix
 940 writing data block fd=dddd size=sss timeout=0
 941 appendfile yields 0 with errno=dd more_errno=dd
 942 search_tidyup called
 943 journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex
 944 ft1 transport returned OK for TESTSUITE/test-mail/junk
 945 post-process TESTSUITE/test-mail/junk (0)
 946 TESTSUITE/test-mail/junk delivered
 947 rd+CALLER@test.ex: children all complete
 948 LOG: MAIN
 949   => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1
 950 --------> CALLER@test.ex <--------
 951  locking TESTSUITE/spool/db/retry.lockfile
 952  locked  TESTSUITE/spool/db/retry.lockfile
 953  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 954  returned from EXIM_DBOPEN: (nil)
 955  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 956 no retry data available
 957 try option max_parallel
 958 try option return_path
 959 try option home_directory
 960 try option current_directory
 961 search_tidyup called
 962 try option quota
 963 try option quota_filecount
 964 try option quota_warn_threshold
 965 try option mailbox_size
 966 try option mailbox_filecount
 967 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
 968   uid=CALLER_UID gid=CALLER_GID pid=p1238
 969   home=/non-exist/CALLER current=/
 970 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
 971 appendfile transport entered
 972 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
 973   file=/dev/null format=unix
 974   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
 975   message_suffix=\n
 976   maildir_use_size_file=no
 977   locking by lockfile fcntl
 978 search_tidyup called
 979 journalling CALLER@test.ex
 980 t1 transport returned OK for CALLER@test.ex
 981 post-process CALLER@test.ex (0)
 982 CALLER@test.ex delivered
 983 LOG: MAIN
 984   => CALLER <CALLER@test.ex> R=r1 T=t1
 985 --------> usery@test.ex <--------
 986  locking TESTSUITE/spool/db/retry.lockfile
 987  locked  TESTSUITE/spool/db/retry.lockfile
 988  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
 989  returned from EXIM_DBOPEN: (nil)
 990  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
 991 no retry data available
 992 try option max_parallel
 993 try option return_path
 994 try option home_directory
 995  ╭considering: /non-exist/$local_part
 996  ├───────text: /non-exist/
 997  ├considering: $local_part
 998  ├──────value: usery
 999             ╰──(tainted)
1000  ├──expanding: /non-exist/$local_part
1001  ╰─────result: /non-exist/usery
1002             ╰──(tainted)
1003 try option current_directory
1004 search_tidyup called
1005 try option quota
1006 try option quota_filecount
1007 try option quota_warn_threshold
1008 try option mailbox_size
1009 try option mailbox_filecount
1010 changed uid/gid: local delivery to usery <usery@test.ex> transport=t1
1011   uid=CALLER_UID gid=CALLER_GID pid=p1239
1012   home=/non-exist/usery current=/
1013 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to usery using t1
1014 appendfile transport entered
1015 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
1016   file=/dev/null format=unix
1017   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
1018   message_suffix=\n
1019   maildir_use_size_file=no
1020   locking by lockfile fcntl
1021 search_tidyup called
1022 journalling usery@test.ex
1023 t1 transport returned OK for usery@test.ex
1024 post-process usery@test.ex (0)
1025 usery@test.ex delivered
1026 LOG: MAIN
1027   => usery <usery@test.ex> R=r2 T=t1
1028 --------> userz@test.ex <--------
1029  locking TESTSUITE/spool/db/retry.lockfile
1030  locked  TESTSUITE/spool/db/retry.lockfile
1031  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
1032  returned from EXIM_DBOPEN: (nil)
1033  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
1034 no retry data available
1035 try option max_parallel
1036 try option return_path
1037 try option home_directory
1038  ╭considering: /$local_part
1039  ├───────text: /
1040  ├considering: $local_part
1041  ├──────value: userz
1042             ╰──(tainted)
1043  ├──expanding: /$local_part
1044  ╰─────result: /userz
1045             ╰──(tainted)
1046 try option current_directory
1047 search_tidyup called
1048 try option quota
1049 try option quota_filecount
1050 try option quota_warn_threshold
1051 try option mailbox_size
1052 try option mailbox_filecount
1053 changed uid/gid: local delivery to userz <userz@test.ex> transport=t2
1054   uid=CALLER_UID gid=CALLER_GID pid=p1240
1055   home=/userz current=/
1056 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to userz using t2
1057 appendfile transport entered
1058 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
1059   file=/dev/null format=unix
1060   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
1061   message_suffix=\n
1062   maildir_use_size_file=no
1063   locking by lockfile fcntl
1064 search_tidyup called
1065 journalling userz@test.ex
1066 t2 transport returned OK for userz@test.ex
1067 post-process userz@test.ex (0)
1068 userz@test.ex delivered
1069 LOG: MAIN
1070   => userz <userz@test.ex> R=r3 T=t2
1071 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
1072 changed uid/gid: post-delivery tidying
1073   uid=EXIM_UID gid=EXIM_GID pid=p1235
1074 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
1075 Processing retry items
1076 Succeeded addresses:
1077  userz@test.ex: no retry items
1078  usery@test.ex: no retry items
1079  CALLER@test.ex: no retry items
1080  TESTSUITE/test-mail/junk: no retry items
1081  rd+CALLER@test.ex: no retry items
1082  TESTSUITE/test-mail/junk: no retry items
1083  rd+usery@test.ex: no retry items
1084  rd+CALLER@test.ex: no retry items
1085  rd+usery@test.ex: no retry items
1086 Failed addresses:
1087 Deferred addresses:
1088 end of retry processing
1089 DSN: processing router : r3
1090 DSN: processing successful delivery address: userz@test.ex
1091 DSN: Sender_address: CALLER@test.ex
1092 DSN: orcpt: NULL  flags: 0x0
1093 DSN: envid: NULL  ret: 0
1094 DSN: Final recipient: userz@test.ex
1095 DSN: Remote SMTP server supports DSN: 0
1096 DSN: not sending DSN success message
1097 DSN: processing router : r2
1098 DSN: processing successful delivery address: usery@test.ex
1099 DSN: Sender_address: CALLER@test.ex
1100 DSN: orcpt: NULL  flags: 0x0
1101 DSN: envid: NULL  ret: 0
1102 DSN: Final recipient: usery@test.ex
1103 DSN: Remote SMTP server supports DSN: 0
1104 DSN: not sending DSN success message
1105 DSN: processing router : r1
1106 DSN: processing successful delivery address: CALLER@test.ex
1107 DSN: Sender_address: CALLER@test.ex
1108 DSN: orcpt: NULL  flags: 0x0
1109 DSN: envid: NULL  ret: 0
1110 DSN: Final recipient: CALLER@test.ex
1111 DSN: Remote SMTP server supports DSN: 0
1112 DSN: not sending DSN success message
1113 DSN: processing router : r4
1114 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
1115 DSN: Sender_address: CALLER@test.ex
1116 DSN: orcpt: NULL  flags: 0x0
1117 DSN: envid: NULL  ret: 0
1118 DSN: Final recipient: TESTSUITE/test-mail/junk
1119 DSN: Remote SMTP server supports DSN: 0
1120 DSN: not sending DSN success message
1121 DSN: processing router : r5
1122 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
1123 DSN: Sender_address: CALLER@test.ex
1124 DSN: orcpt: NULL  flags: 0x0
1125 DSN: envid: NULL  ret: 0
1126 DSN: Final recipient: TESTSUITE/test-mail/junk
1127 DSN: Remote SMTP server supports DSN: 0
1128 DSN: not sending DSN success message
1129 DSN: processing router : r4
1130 DSN: processing successful delivery address: rd+CALLER@test.ex
1131 DSN: Sender_address: CALLER@test.ex
1132 DSN: orcpt: NULL  flags: 0x0
1133 DSN: envid: NULL  ret: 0
1134 DSN: Final recipient: rd+CALLER@test.ex
1135 DSN: Remote SMTP server supports DSN: 0
1136 DSN: not sending DSN success message
1137 DSN: processing router : r5
1138 DSN: processing successful delivery address: rd+usery@test.ex
1139 DSN: Sender_address: CALLER@test.ex
1140 DSN: orcpt: NULL  flags: 0x0
1141 DSN: envid: NULL  ret: 0
1142 DSN: Final recipient: rd+usery@test.ex
1143 DSN: Remote SMTP server supports DSN: 0
1144 DSN: not sending DSN success message
1145 LOG: MAIN
1146   Completed
1147 end delivery of 10HmaX-000000005vi-0000
1148 search_tidyup called
1149 search_tidyup called
1150 >>>>>>>>>>>>>>>> Exim pid=p1235 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
1151 search_tidyup called
1152 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>