test/stderr/0388

   1 Exim version x.yz ....
   2 Hints DB:
   3 changed uid/gid: forcing real = effective
   4   uid=uuuu gid=CALLER_GID pid=p1234
   5 configuration file is TESTSUITE/test-config
   6 admin user
   7 dropping to exim gid; retaining priv uid
   8 set_process_info: pppp delivering specified messages
   9 set_process_info: pppp delivering 10HmaX-000000005vi-0000
  10 no retry data available
  11 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  12 Considering: x@y
  13 unique = x@y
  14 checking router retry status
  15  no   domain  retry record
  16  no   address retry record
  17 x@y: queued for routing
  18 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  19 routing x@y
  20 --------> r0 router <--------
  21 local_part=x domain=y
  22 checking local_parts
  23 x in local_parts?
  24  list element: CALLER
  25 x in local_parts? no (end of list)
  26 r0 router skipped: local_parts mismatch
  27 --------> r1 router <--------
  28 local_part=x domain=y
  29 calling r1 router
  30 r1 router called for x@y
  31   domain = y
  32 route_item = * "127.0.0.1 : V4NET.0.0.0"
  33 y in "*"?
  34  list element: *
  35  y in "*"? yes (matched "*")
  36 original list of hosts = '127.0.0.1 : V4NET.0.0.0' options = ''
  37 expanded list of hosts = '127.0.0.1 : V4NET.0.0.0' options = ''
  38 set transport smtp
  39 finding IP address for 127.0.0.1
  40 calling host_find_byname
  41 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
  42 local host found for non-MX address
  43 fully qualified name = 127.0.0.1
  44 MUNGED: ::1 will be omitted in what follows
  45 get[host|ipnode]byname[2] looked up these IP addresses:
  46   name=127.0.0.1 address=127.0.0.1
  47 remote host address is the local host: y: configured to try delivery anyway
  48 finding IP address for V4NET.0.0.0
  49 calling host_find_byname
  50 using host_fake_gethostbyname for V4NET.0.0.0 (IPv4)
  51 fully qualified name = V4NET.0.0.0
  52 MUNGED: ::1 will be omitted in what follows
  53 get[host|ipnode]byname[2] looked up these IP addresses:
  54   name=V4NET.0.0.0 address=V4NET.0.0.0
  55 queued for smtp transport: local_part = x
  56 domain = y
  57   errors_to=NULL
  58   domain_data=NULL local_part_data=NULL
  59 routed by r1 router
  60   envelope to: x@y
  61   transport: smtp
  62   host 127.0.0.1 [127.0.0.1]
  63   host V4NET.0.0.0 [V4NET.0.0.0]
  64 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
  65 After routing:
  66   Local deliveries:
  67   Remote deliveries:
  68     x@y
  69   Failed addresses:
  70   Deferred addresses:
  71 search_tidyup called
  72 search_tidyup called
  73 changed uid/gid: remote delivery to x@y with transport=smtp
  74   uid=EXIM_UID gid=EXIM_GID pid=p1235
  75 set_process_info: pppp delivering 10HmaX-000000005vi-0000 using smtp
  76 checking retry status of 127.0.0.1
  77  no retry data available
  78 127.0.0.1 in serialize_hosts? no (option unset)
  79 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to 127.0.0.1 [127.0.0.1]:PORT_S (x@y)
  80 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
  81  127.0.0.1 in hosts_try_fastopen?
  82   list element:
  83 connected
  84   SMTP<< 220 Server ready
  85 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
  86   SMTP>> EHLO myhost.test.ex
  87 cmd buf flush ddd bytes
  88   SMTP<< 250 OK
  89 127.0.0.1 in hosts_require_auth? no (option unset)
  90   SMTP>> MAIL FROM:<CALLER@myhost.test.ex>
  91 cmd buf flush ddd bytes
  92   SMTP<< 250 OK
  93   SMTP>> RCPT TO:<x@y>
  94 cmd buf flush ddd bytes
  95   SMTP<< 451 Temporary error
  96 LOG: MAIN
  97   H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error
  98 added retry item for R:x@y: errno=-44 more_errno=dd,A flags=0
  99   SMTP+> QUIT
 100 cmd buf flush ddd bytes (more expected)
 101   SMTP(shutdown)>>
 102   SMTP<< 250 OK
 103   SMTP(close)>>
 104 cmdlog: '220:EHLO:250:MAIL:250:RCPT:451:QUIT+:250'
 105 set_process_info: pppp delivering 10HmaX-000000005vi-0000: just tried 127.0.0.1 [127.0.0.1]:PORT_S for x@y: result OK
 106 *@127.0.0.1 in "*"?
 107  list element: *
 108  address match test: subject=*@127.0.0.1 pattern=*
 109  127.0.0.1 in "*"?
 110   list element: *
 111   127.0.0.1 in "*"? yes (matched "*")
 112  *@127.0.0.1 in "*"? yes (matched "*")
 113 Clearing TFO as not first host for message
 114 checking retry status of V4NET.0.0.0
 115  no retry data available
 116 V4NET.0.0.0 in serialize_hosts? no (option unset)
 117 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to V4NET.0.0.0 [V4NET.0.0.0]:PORT_S (x@y)
 118 Connecting to V4NET.0.0.0 [V4NET.0.0.0]:PORT_S ...
 119  V4NET.0.0.0 in hosts_try_fastopen?
 120  failed: Network Error
 121 cmdlog: (unset)
 122 LOG: MAIN
 123   H=V4NET.0.0.0 [V4NET.0.0.0] Network Error
 124 set_process_info: pppp delivering 10HmaX-000000005vi-0000: just tried V4NET.0.0.0 [V4NET.0.0.0]:PORT_S for x@y: result DEFER
 125 added retry item for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S: errno=dd more_errno=dd,A flags=2
 126 set_process_info: pppp delivering 10HmaX-000000005vi-0000: waiting for a remote delivery subprocess to finish
 127 set_process_info: pppp delivering 10HmaX-000000005vi-0000 (just run smtp for x@y in subprocess)
 128 search_tidyup called
 129 search_tidyup called
 130 >>>>>>>>>>>>>>>> Exim pid=p1235 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
 131 reading retry information for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S from subprocess
 132   added retry item
 133 reading retry information for R:x@y from subprocess
 134   added retry item
 135 set_process_info: pppp delivering 10HmaX-000000005vi-0000
 136 LOG: MAIN
 137   == x@y R=r1 T=smtp defer (dd): Network Error H=127.0.0.1 [127.0.0.1]
 138 changed uid/gid: post-delivery tidying
 139   uid=EXIM_UID gid=EXIM_GID pid=p1234
 140 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
 141 Processing retry items
 142  Succeeded addresses:
 143  Failed addresses:
 144  Deferred addresses:
 145   x@y
 146    x@y in "*"?
 147    ╎list element: *
 148    ╎address match test: subject=x@y pattern=*
 149    ╎y in "*"?
 150    ╎ list element: *
 151    ╎ y in "*"? yes (matched "*")
 152    ╎x@y in "*"? yes (matched "*")
 153    retry for R:x@y = * 0 0
 154    dbfn_read: key=R:x@y
 155    dbfn_read: null return
 156    failing_interval=ttt message_age=ttt
 157    Writing retry data for R:x@y
 158      first failed=dddd last try=dddd next try=+1 expired=1
 159      errno=-44 more_errno=dd,A H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error
 160    dbfn_write: key=R:x@y datalen nn
 161    *@V4NET.0.0.0 in "*"?
 162    ╎list element: *
 163    ╎address match test: subject=*@V4NET.0.0.0 pattern=*
 164    ╎V4NET.0.0.0 in "*"?
 165    ╎ list element: *
 166    ╎ V4NET.0.0.0 in "*"? yes (matched "*")
 167    ╎*@V4NET.0.0.0 in "*"? yes (matched "*")
 168    retry for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S (y) = * 0 0
 169    dbfn_read: key=T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S
 170    dbfn_read: null return
 171    failing_interval=ttt message_age=ttt
 172    on queue longer than maximum retry
 173    Writing retry data for T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S
 174      first failed=dddd last try=dddd next try=+0 expired=0
 175      errno=dd more_errno=dd,A Network Error
 176    dbfn_write: key=T:[V4NET.0.0.0]:V4NET.0.0.0:PORT_S datalen nn
 177   timed out: all retries expired
 178 LOG: MAIN
 179   ** x@y: retry timeout exceeded
 180 end of retry processing
 181 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -MCd bounce-message -odi -odi -t -oem -oi -f <> -E10HmaX-000000005vi-0000
 182 Exim version x.yz ....
 183 Hints DB:
 184 changed uid/gid: forcing real = effective
 185   uid=uuuu gid=EXIM_GID pid=p1236
 186 configuration file is TESTSUITE/test-config
 187 trusted user
 188 admin user
 189 changed uid/gid: privilege not needed
 190   uid=EXIM_UID gid=EXIM_GID pid=p1236
 191 originator: uid=EXIM_UID gid=EXIM_GID login=EXIMUSER name=
 192 sender address =
 193 set_process_info: pppp accepting a local non-SMTP message from <>
 194 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
 195 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
 196 Sender:
 197 search_tidyup called
 198 >>Headers received:
 199  X-Failed-Recipients: x@y
 200  Auto-Submitted: auto-replied
 201  From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
 202  To: CALLER@myhost.test.ex
 203  References: <E10HmaX-000000005vi-0000@myhost.test.ex>
 204  Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
 205  MIME-Version: 1.0
 206  Subject: Mail delivery failed: returning message to sender
 207
 208 qualify & rewrite recipients list
 209 rewrite rules on sender address
 210 qualify and rewrite headers
 211  rewrite_one_header: type=F:
 212    From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
 213  rewrite_one_header: type=T:
 214    To: CALLER@myhost.test.ex
 215 search_tidyup called
 216 >>Headers after rewriting and local additions:
 217    X-Failed-Recipients: x@y
 218    Auto-Submitted: auto-replied
 219  F From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
 220  T To: CALLER@myhost.test.ex
 221    References: <E10HmaX-000000005vi-0000@myhost.test.ex>
 222    Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
 223    MIME-Version: 1.0
 224    Subject: Mail delivery failed: returning message to sender
 225  I Message-Id: <E10HmaY-000000005vi-0000@myhost.test.ex>
 226    Date: Tue, 2 Mar 1999 09:44:33 +0000
 227
 228 Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
 229 Data file written for message 10HmaY-000000005vi-0000
 230 >>Generated Received: header line
 231 P Received: from EXIMUSER by myhost.test.ex with local (Exim x.yz)
 232         id 10HmaY-000000005vi-0000
 233         for CALLER@myhost.test.ex;
 234         Tue, 2 Mar 1999 09:44:33 +0000
 235 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000
 236 Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H
 237 Size of headers = sss
 238 LOG: MAIN
 239   <= <> R=10HmaX-000000005vi-0000 U=EXIMUSER P=local S=sss
 240 search_tidyup called
 241 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -MCd local-accept-delivery -odi -Mc 10HmaY-000000005vi-0000
 242 Exim version x.yz ....
 243 Hints DB:
 244 changed uid/gid: forcing real = effective
 245   uid=uuuu gid=EXIM_GID pid=p1237
 246 configuration file is TESTSUITE/test-config
 247 trusted user
 248 admin user
 249 dropping to exim gid; retaining priv uid
 250 set_process_info: pppp delivering specified messages
 251 set_process_info: pppp delivering 10HmaY-000000005vi-0000
 252 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 253 Considering: CALLER@myhost.test.ex
 254 unique = CALLER@myhost.test.ex
 255 checking router retry status
 256  dbfn_read: key=R:myhost.test.ex
 257  dbfn_read: null return
 258  dbfn_read: key=R:CALLER@myhost.test.ex
 259  dbfn_read: null return
 260  dbfn_read: key=R:CALLER@myhost.test.ex:<>
 261  dbfn_read: null return
 262  no   domain  retry record
 263  no   address retry record
 264 CALLER@myhost.test.ex: queued for routing
 265 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 266 routing CALLER@myhost.test.ex
 267 --------> r0 router <--------
 268 local_part=CALLER domain=myhost.test.ex
 269 checking local_parts
 270 CALLER in local_parts?
 271  list element: CALLER
 272  CALLER in local_parts? yes (matched "CALLER")
 273 checking senders
 274  in senders?
 275  list element:
 276  address match test: subject= pattern=
 277   in senders? yes (matched "")
 278 calling r0 router
 279 rda_interpret (string): ':blackhole:'
 280 expanded: ':blackhole:'
 281 file is not a filter file
 282 parse_forward_list: :blackhole:
 283 extract item: :blackhole:
 284 address :blackhole:d
 285 LOG: MAIN
 286   => :blackhole: <CALLER@myhost.test.ex> R=r0
 287 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
 288 After routing:
 289   Local deliveries:
 290   Remote deliveries:
 291   Failed addresses:
 292   Deferred addresses:
 293 search_tidyup called
 294 changed uid/gid: post-delivery tidying
 295   uid=EXIM_UID gid=EXIM_GID pid=p1237
 296 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
 297 Processing retry items
 298  Succeeded addresses:
 299   CALLER@myhost.test.ex: no retry items
 300  Failed addresses:
 301  Deferred addresses:
 302 end of retry processing
 303 LOG: MAIN
 304   Completed
 305 search_tidyup called
 306 search_tidyup called
 307 >>>>>>>>>>>>>>>> Exim pid=p1237 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
 308 search_tidyup called
 309 >>>>>>>>>>>>>>>> Exim pid=p1236 (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>>
 310 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
 311 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
 312 Size of headers = sss
 313 LOG: MAIN
 314   Completed
 315 search_tidyup called
 316 search_tidyup called
 317 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>