test/stderr/0388

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