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