02bbe1b2a416a9b9e4cf6c025853beadf6a9daea
[exim.git] / test / stderr / 0388
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 configuration file is TESTSUITE/test-config
5 admin user
6 DSN: r0 propagating DSN
7 DSN: r1 propagating DSN
8 set_process_info: pppp delivering specified messages
9 set_process_info: pppp delivering 10HmaX-0005vi-00
10 locking TESTSUITE/spool/db/retry.lockfile
11 locked TESTSUITE/spool/db/retry.lockfile
12 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
13 returned from EXIM_DBOPEN
14 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
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 "CALLER"? no (end of list)
28 r0 router skipped: local_parts mismatch
29 --------> r1 router <--------
30 local_part=x domain=y
31 calling r1 router
32 r1 router called for x@y
33   domain = y
34 route_item = * "127.0.0.1 : V4NET.0.0.0"
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 localpart_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=pppp
75 set_process_info: pppp delivering 10HmaX-0005vi-00 using smtp
76 checking status of 127.0.0.1
77 locking TESTSUITE/spool/db/retry.lockfile
78 locked TESTSUITE/spool/db/retry.lockfile
79 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
80 returned from EXIM_DBOPEN
81 no retry data available
82 127.0.0.1 in serialize_hosts? no (option unset)
83 set_process_info: pppp delivering 10HmaX-0005vi-00 to 127.0.0.1 [127.0.0.1] (x@y)
84 Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
85   SMTP<< 220 Server ready
86 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
87   SMTP>> EHLO myhost.test.ex
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   SMTP<< 250 OK
92   SMTP>> RCPT TO:<x@y>
93   SMTP<< 451 Temporary error
94 LOG: MAIN
95   H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error
96 added retry item for R:x@y: errno=-44 more_errno=dd,A flags=0
97   SMTP>> QUIT
98   SMTP(close)>>
99 set_process_info: pppp delivering 10HmaX-0005vi-00: just tried 127.0.0.1 [127.0.0.1] for x@y: result OK
100 address match test: subject=*@127.0.0.1 pattern=*
101 127.0.0.1 in "*"? yes (matched "*")
102 *@127.0.0.1 in "*"? yes (matched "*")
103 checking status of V4NET.0.0.0
104 locking TESTSUITE/spool/db/retry.lockfile
105 locked TESTSUITE/spool/db/retry.lockfile
106 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
107 returned from EXIM_DBOPEN
108 no retry data available
109 V4NET.0.0.0 in serialize_hosts? no (option unset)
110 set_process_info: pppp delivering 10HmaX-0005vi-00 to V4NET.0.0.0 [V4NET.0.0.0] (x@y)
111 Connecting to V4NET.0.0.0 [V4NET.0.0.0]:1224 ... failed: Network Error
112 LOG: MAIN
113   H=V4NET.0.0.0 [V4NET.0.0.0] Network Error
114 set_process_info: pppp delivering 10HmaX-0005vi-00: just tried V4NET.0.0.0 [V4NET.0.0.0] for x@y: result DEFER
115 added retry item for T:V4NET.0.0.0:V4NET.0.0.0:1224: errno=dd more_errno=dd,A flags=2
116 set_process_info: pppp delivering 10HmaX-0005vi-00 (just run smtp for x@y in subprocess)
117 search_tidyup called
118 set_process_info: pppp delivering 10HmaX-0005vi-00: waiting for a remote delivery subprocess to finish
119 set_process_info: pppp delivering 10HmaX-0005vi-00
120 reading retry information for T:V4NET.0.0.0:V4NET.0.0.0:1224 from subprocess
121   added retry item
122 reading retry information for R:x@y from subprocess
123   added retry item
124 LOG: MAIN
125   == x@y R=r1 T=smtp defer (dd): Network Error H=127.0.0.1 [127.0.0.1]
126 changed uid/gid: post-delivery tidying
127   uid=EXIM_UID gid=EXIM_GID pid=pppp
128 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
129 Processing retry items
130 Succeeded addresses:
131 Failed addresses:
132 Deferred addresses:
133 x@y
134 locking TESTSUITE/spool/db/retry.lockfile
135 locked TESTSUITE/spool/db/retry.lockfile
136 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
137 returned from EXIM_DBOPEN
138 opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
139 address match test: subject=x@y pattern=*
140 y in "*"? yes (matched "*")
141 x@y in "*"? yes (matched "*")
142 retry for R:x@y = * 0 0
143 dbfn_read: key=R:x@y
144 failing_interval=ttt message_age=ttt
145 Writing retry data for R:x@y
146   first failed=dddd last try=dddd next try=+1 expired=1
147   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
148 dbfn_write: key=R:x@y
149 address match test: subject=*@V4NET.0.0.0 pattern=*
150 V4NET.0.0.0 in "*"? yes (matched "*")
151 *@V4NET.0.0.0 in "*"? yes (matched "*")
152 retry for T:V4NET.0.0.0:V4NET.0.0.0:1224 (y) = * 0 0
153 dbfn_read: key=T:V4NET.0.0.0:V4NET.0.0.0:1224
154 failing_interval=ttt message_age=ttt
155 on queue longer than maximum retry
156 Writing retry data for T:V4NET.0.0.0:V4NET.0.0.0:1224
157   first failed=dddd last try=dddd next try=+0 expired=0
158   errno=dd more_errno=dd,A Network Error
159 dbfn_write: key=T:V4NET.0.0.0:V4NET.0.0.0:1224
160 timed out: all retries expired
161 LOG: MAIN
162   ** x@y: retry timeout exceeded
163 end of retry processing
164 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -odi -t -oem -oi -f <> -E10HmaX-0005vi-00
165 Exim version x.yz ....
166 changed uid/gid: forcing real = effective
167   uid=uuuu gid=EXIM_GID pid=pppp
168 configuration file is TESTSUITE/test-config
169 trusted user
170 admin user
171 changed uid/gid: privilege not needed
172   uid=EXIM_UID gid=EXIM_GID pid=pppp
173 DSN: r0 propagating DSN
174 DSN: r1 propagating DSN
175 originator: uid=EXIM_UID gid=EXIM_GID login=EXIMUSER name=
176 sender address = 
177 set_process_info: pppp accepting a local non-SMTP message from <>
178 Sender: 
179 search_tidyup called
180 >>Headers received:
181 X-Failed-Recipients: x@y
182 Auto-Submitted: auto-replied
183 From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
184 To: CALLER@myhost.test.ex
185 Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
186 MIME-Version: 1.0
187 Subject: Mail delivery failed: returning message to sender
188
189 rewrite_one_header: type=F:
190   From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
191 rewrite_one_header: type=T:
192   To: CALLER@myhost.test.ex
193 search_tidyup called
194 >>Headers after rewriting and local additions:
195   X-Failed-Recipients: x@y
196   Auto-Submitted: auto-replied
197 F From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
198 T To: CALLER@myhost.test.ex
199   Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
200   MIME-Version: 1.0
201   Subject: Mail delivery failed: returning message to sender
202 I Message-Id: <E10HmaY-0005vi-00@myhost.test.ex>
203   Date: Tue, 2 Mar 1999 09:44:33 +0000
204
205 Data file name: TESTSUITE/spool/input///10HmaY-0005vi-00-D
206 Data file written for message 10HmaY-0005vi-00
207 >>Generated Received: header line
208 P Received: from EXIMUSER by myhost.test.ex with local (Exim x.yz)
209         id 10HmaY-0005vi-00
210         for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
211 calling local_scan(); timeout=300
212 local_scan() returned 0 NULL
213 Writing spool header file: TESTSUITE/spool/input///hdr.pppp
214 Renaming spool header file: TESTSUITE/spool/input///10HmaY-0005vi-00-H
215 Size of headers = sss
216 LOG: MAIN
217   <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
218 search_tidyup called
219 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xebb95ced -odi -Mc 10HmaY-0005vi-00
220 Exim version x.yz ....
221 changed uid/gid: forcing real = effective
222   uid=uuuu gid=EXIM_GID pid=pppp
223 configuration file is TESTSUITE/test-config
224 trusted user
225 admin user
226 DSN: r0 propagating DSN
227 DSN: r1 propagating DSN
228 set_process_info: pppp delivering specified messages
229 set_process_info: pppp delivering 10HmaY-0005vi-00
230 locking TESTSUITE/spool/db/retry.lockfile
231 locked TESTSUITE/spool/db/retry.lockfile
232 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
233 returned from EXIM_DBOPEN
234 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
235 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
236 Considering: CALLER@myhost.test.ex
237 unique = CALLER@myhost.test.ex
238 dbfn_read: key=R:myhost.test.ex
239 dbfn_read: key=R:CALLER@myhost.test.ex
240 dbfn_read: key=R:CALLER@myhost.test.ex:<>
241 no domain retry record
242 no address retry record
243 CALLER@myhost.test.ex: queued for routing
244 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
245 routing CALLER@myhost.test.ex
246 --------> r0 router <--------
247 local_part=CALLER domain=myhost.test.ex
248 checking local_parts
249 CALLER in "CALLER"? yes (matched "CALLER")
250 checking senders
251 address match test: subject= pattern=
252  in ":"? yes (matched "")
253 calling r0 router
254 rda_interpret (string): :blackhole:
255 expanded: :blackhole:
256 file is not a filter file
257 parse_forward_list: :blackhole:
258 extract item: :blackhole:
259 address :blackhole:d
260 LOG: MAIN
261   => :blackhole: <CALLER@myhost.test.ex> R=r0
262 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
263 After routing:
264   Local deliveries:
265   Remote deliveries:
266   Failed addresses:
267   Deferred addresses:
268 search_tidyup called
269 changed uid/gid: post-delivery tidying
270   uid=EXIM_UID gid=EXIM_GID pid=pppp
271 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
272 Processing retry items
273 Succeeded addresses:
274 CALLER@myhost.test.ex: no retry items
275 Failed addresses:
276 Deferred addresses:
277 end of retry processing
278 LOG: MAIN
279   Completed
280 search_tidyup called
281 search_tidyup called
282 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
283 search_tidyup called
284 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
285 Writing spool header file: TESTSUITE/spool/input///hdr.pppp
286 Renaming spool header file: TESTSUITE/spool/input///10HmaX-0005vi-00-H
287 Size of headers = sss
288 LOG: MAIN
289   Completed
290 search_tidyup called
291 search_tidyup called
292 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>