Rewrites: avoid a split log line when rewiting hits a malformed address. Bug 3160
[exim.git] / test / stderr / 0044
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 changed uid/gid: privilege not needed
7   uid=EXIM_UID gid=EXIM_GID pid=p1234
8 seeking password data for user "CALLER": cache not available
9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
11 sender address = CALLER@exim.test.ex
12 sender_fullhost = [V4NET.11.12.13]
13 sender_rcvhost = [V4NET.11.12.13]
14 host in hosts_connection_nolog? no (option unset)
15 LOG: smtp_connection MAIN
16   SMTP connection from [V4NET.11.12.13]
17 host in host_lookup? no (option unset)
18 set_process_info: pppp handling incoming connection from [V4NET.11.12.13]
19 host in host_reject_connection? no (option unset)
20 host in sender_unqualified_hosts? no (option unset)
21 host in recipient_unqualified_hosts? no (option unset)
22 host in helo_verify_hosts? no (option unset)
23 host in helo_try_verify_hosts? no (option unset)
24 host in helo_accept_junk_hosts? no (option unset)
25 SMTP>> 220 the.local.host.name ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
26 smtp_setup_msg entered
27 SMTP<< ehlo exim.test.ex
28 exim.test.ex in helo_lookup_domains?
29  list element: @
30  list element: @[]
31 exim.test.ex in helo_lookup_domains? no (end of list)
32 sender_fullhost = (exim.test.ex) [V4NET.11.12.13]
33 sender_rcvhost = [V4NET.11.12.13] (helo=exim.test.ex)
34 set_process_info: pppp handling incoming connection from (exim.test.ex) [V4NET.11.12.13]
35 host in dsn_advertise_hosts? no (option unset)
36 host in pipelining_advertise_hosts?
37  list element: *
38   host in pipelining_advertise_hosts? yes (matched "*")
39 host in chunking_advertise_hosts?
40 host in chunking_advertise_hosts? no (end of list)
41 SMTP>> 250-the.local.host.name Hello exim.test.ex [V4NET.11.12.13]
42        250-SIZE 52428800
43        250-8BITMIME
44        250-PIPELINING
45        250 HELP
46 SMTP<< mail from:postmaster@exim.test.ex
47 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
48 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
49 SMTP>> 250 OK
50 SMTP<< rcpt to:<postmaster@exim.test.ex>
51 using ACL "check_recipient"
52 processing "accept" (TESTSUITE/test-config 19)
53 check hosts = :
54 host in ":"?
55  list element: 
56 host in ":"? no (end of list)
57 accept: condition test failed in ACL "check_recipient"
58 processing "accept" (TESTSUITE/test-config 20)
59 check recipients = postmaster@exim.test.ex
60 postmaster@exim.test.ex in "postmaster@exim.test.ex"?
61  list element: postmaster@exim.test.ex
62  address match test: subject=postmaster@exim.test.ex pattern=postmaster@exim.test.ex
63  exim.test.ex in "exim.test.ex"?
64   list element: exim.test.ex
65   exim.test.ex in "exim.test.ex"? yes (matched "exim.test.ex")
66  postmaster@exim.test.ex in "postmaster@exim.test.ex"? yes (matched "postmaster@exim.test.ex")
67 accept: condition test succeeded in ACL "check_recipient"
68 end of ACL "check_recipient": ACCEPT
69 SMTP>> 250 Accepted
70 SMTP<< rcpt to:list@exim.test.ex
71 using ACL "check_recipient"
72 processing "accept" (TESTSUITE/test-config 19)
73 check hosts = :
74 host in ":"?
75  list element: 
76 host in ":"? no (end of list)
77 accept: condition test failed in ACL "check_recipient"
78 processing "accept" (TESTSUITE/test-config 20)
79 check recipients = postmaster@exim.test.ex
80 list@exim.test.ex in "postmaster@exim.test.ex"?
81  list element: postmaster@exim.test.ex
82  address match test: subject=list@exim.test.ex pattern=postmaster@exim.test.ex
83 list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
84 accept: condition test failed in ACL "check_recipient"
85 processing "accept" (TESTSUITE/test-config 21)
86 check senders = myfriend@*
87 postmaster@exim.test.ex in "myfriend@*"?
88  list element: myfriend@*
89  address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
90 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
91 accept: condition test failed in ACL "check_recipient"
92 processing "deny" (TESTSUITE/test-config 22)
93   message: host is listed in $dnslist_domain
94 check dnslists = rbl.test.ex
95 dnslists check: rbl.test.ex
96 new DNS lookup for 13.12.11.V4NET.rbl.test.ex
97 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens
98 DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded
99 dnslists: wrote cache entry, ttl=3
100 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
101 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens
102 DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded
103 => that means V4NET.11.12.13 is listed at rbl.test.ex
104 deny: condition test succeeded in ACL "check_recipient"
105 end of ACL "check_recipient": DENY
106 SMTP>> 550 host is listed in rbl.test.ex
107 LOG: MAIN REJECT
108   H=(exim.test.ex) [V4NET.11.12.13] F=<postmaster@exim.test.ex> rejected RCPT list@exim.test.ex: host is listed in rbl.test.ex
109 SMTP<< rcpt to:list2@exim.test.ex
110 using ACL "check_recipient"
111 processing "accept" (TESTSUITE/test-config 19)
112 check hosts = :
113 host in ":"?
114  list element: 
115 host in ":"? no (end of list)
116 accept: condition test failed in ACL "check_recipient"
117 processing "accept" (TESTSUITE/test-config 20)
118 check recipients = postmaster@exim.test.ex
119 list2@exim.test.ex in "postmaster@exim.test.ex"?
120  list element: postmaster@exim.test.ex
121  address match test: subject=list2@exim.test.ex pattern=postmaster@exim.test.ex
122 list2@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
123 accept: condition test failed in ACL "check_recipient"
124 processing "accept" (TESTSUITE/test-config 21)
125 check senders = myfriend@*
126 postmaster@exim.test.ex in "myfriend@*"?
127  list element: myfriend@*
128  address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
129 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
130 accept: condition test failed in ACL "check_recipient"
131 processing "deny" (TESTSUITE/test-config 22)
132   message: host is listed in $dnslist_domain
133 check dnslists = rbl.test.ex
134 dnslists check: rbl.test.ex
135 dnslists: using result of previous lookup
136 DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
137 => that means V4NET.11.12.13 is listed at rbl.test.ex
138 deny: condition test succeeded in ACL "check_recipient"
139 end of ACL "check_recipient": DENY
140 SMTP>> 550 host is listed in rbl.test.ex
141 LOG: MAIN REJECT
142   H=(exim.test.ex) [V4NET.11.12.13] F=<postmaster@exim.test.ex> rejected RCPT list2@exim.test.ex: host is listed in rbl.test.ex
143 SMTP<< data
144 SMTP>> 354 Enter message, ending with "." on a line by itself
145 search_tidyup called
146 host in ignore_fromline_hosts? no (option unset)
147 >>Headers received:
148
149 qualify & rewrite recipients list
150 rewrite rules on sender address
151 qualify and rewrite headers
152 search_tidyup called
153 >>Headers after rewriting and local additions:
154
155 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
156 Data file written for message 10HmaX-000000005vi-0000
157 >>Generated Received: header line
158 P Received: from [V4NET.11.12.13] (helo=exim.test.ex)
159         by the.local.host.name with esmtp (Exim x.yz)
160         (envelope-from <postmaster@exim.test.ex>)
161         id 10HmaX-000000005vi-0000
162         for postmaster@exim.test.ex;
163         Tue, 2 Mar 1999 09:44:33 +0000
164 LOG: MAIN
165   <= postmaster@exim.test.ex H=(exim.test.ex) [V4NET.11.12.13] P=esmtp S=sss
166 SMTP>> 250 OK id=10HmaX-000000005vi-0000
167 smtp_setup_msg entered
168 SMTP<< quit
169 SMTP>> 221 the.local.host.name closing connection
170 LOG: smtp_connection MAIN
171   SMTP connection from (exim.test.ex) [V4NET.11.12.13] D=qqs closed by QUIT
172 search_tidyup called
173 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
174 >>> host in hosts_connection_nolog? no (option unset)
175 >>> host in host_lookup? no (option unset)
176 >>> host in host_reject_connection? no (option unset)
177 >>> host in sender_unqualified_hosts? no (option unset)
178 >>> host in recipient_unqualified_hosts? no (option unset)
179 >>> host in helo_verify_hosts? no (option unset)
180 >>> host in helo_try_verify_hosts? no (option unset)
181 >>> host in helo_accept_junk_hosts? no (option unset)
182 >>> exim.test.ex in helo_lookup_domains?
183 >>>  list element: @
184 >>>  list element: @[]
185 >>> exim.test.ex in helo_lookup_domains? no (end of list)
186 >>> host in dsn_advertise_hosts? no (option unset)
187 >>> host in pipelining_advertise_hosts?
188 >>>  list element: *
189 >>>  host in pipelining_advertise_hosts? yes (matched "*")
190 >>> host in chunking_advertise_hosts?
191 >>> host in chunking_advertise_hosts? no (end of list)
192 >>> using ACL "check_recipient"
193 >>> processing "accept" (TESTSUITE/test-config 19)
194 >>> check hosts = :
195 >>> host in ":"?
196 >>>  list element: 
197 >>> host in ":"? no (end of list)
198 >>> accept: condition test failed in ACL "check_recipient"
199 >>> processing "accept" (TESTSUITE/test-config 20)
200 >>> check recipients = postmaster@exim.test.ex
201 >>> list@exim.test.ex in "postmaster@exim.test.ex"?
202 >>>  list element: postmaster@exim.test.ex
203 >>> list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
204 >>> accept: condition test failed in ACL "check_recipient"
205 >>> processing "accept" (TESTSUITE/test-config 21)
206 >>> check senders = myfriend@*
207 >>> myfriend@there.test.ex in "myfriend@*"?
208 >>>  list element: myfriend@*
209 >>>  there.test.ex in "*"?
210 >>>   list element: *
211 >>>   there.test.ex in "*"? yes (matched "*")
212 >>>  myfriend@there.test.ex in "myfriend@*"? yes (matched "myfriend@*")
213 >>> accept: condition test succeeded in ACL "check_recipient"
214 >>> end of ACL "check_recipient": ACCEPT
215 >>> host in ignore_fromline_hosts? no (option unset)
216 LOG: 10HmaY-000000005vi-0000 <= myfriend@there.test.ex H=(exim.test.ex) [V4NET.11.12.13] P=esmtp S=sss
217 Exim version x.yz ....
218 changed uid/gid: forcing real = effective
219   uid=uuuu gid=CALLER_GID pid=p1235
220 configuration file is TESTSUITE/test-config
221 admin user
222 changed uid/gid: privilege not needed
223   uid=EXIM_UID gid=EXIM_GID pid=p1235
224 seeking password data for user "CALLER": cache not available
225 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
226 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
227 sender address = CALLER@exim.test.ex
228 sender_fullhost = [V4NET.99.99.99]
229 sender_rcvhost = [V4NET.99.99.99]
230 host in hosts_connection_nolog? no (option unset)
231 LOG: smtp_connection MAIN
232   SMTP connection from [V4NET.99.99.99]
233 host in host_lookup? no (option unset)
234 set_process_info: pppp handling incoming connection from [V4NET.99.99.99]
235 host in host_reject_connection? no (option unset)
236 host in sender_unqualified_hosts? no (option unset)
237 host in recipient_unqualified_hosts? no (option unset)
238 host in helo_verify_hosts? no (option unset)
239 host in helo_try_verify_hosts? no (option unset)
240 host in helo_accept_junk_hosts? no (option unset)
241 SMTP>> 220 the.local.host.name ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
242 smtp_setup_msg entered
243 SMTP<< ehlo exim.test.ex
244 exim.test.ex in helo_lookup_domains?
245  list element: @
246  list element: @[]
247 exim.test.ex in helo_lookup_domains? no (end of list)
248 sender_fullhost = (exim.test.ex) [V4NET.99.99.99]
249 sender_rcvhost = [V4NET.99.99.99] (helo=exim.test.ex)
250 set_process_info: pppp handling incoming connection from (exim.test.ex) [V4NET.99.99.99]
251 host in dsn_advertise_hosts? no (option unset)
252 host in pipelining_advertise_hosts?
253  list element: *
254   host in pipelining_advertise_hosts? yes (matched "*")
255 host in chunking_advertise_hosts?
256 host in chunking_advertise_hosts? no (end of list)
257 SMTP>> 250-the.local.host.name Hello exim.test.ex [V4NET.99.99.99]
258        250-SIZE 52428800
259        250-8BITMIME
260        250-PIPELINING
261        250 HELP
262 SMTP<< mail from:postmaster@exim.test.ex
263 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
264 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
265 SMTP>> 250 OK
266 SMTP<< rcpt to:list@exim.test.ex
267 using ACL "check_recipient"
268 processing "accept" (TESTSUITE/test-config 19)
269 check hosts = :
270 host in ":"?
271  list element: 
272 host in ":"? no (end of list)
273 accept: condition test failed in ACL "check_recipient"
274 processing "accept" (TESTSUITE/test-config 20)
275 check recipients = postmaster@exim.test.ex
276 list@exim.test.ex in "postmaster@exim.test.ex"?
277  list element: postmaster@exim.test.ex
278  address match test: subject=list@exim.test.ex pattern=postmaster@exim.test.ex
279 list@exim.test.ex in "postmaster@exim.test.ex"? no (end of list)
280 accept: condition test failed in ACL "check_recipient"
281 processing "accept" (TESTSUITE/test-config 21)
282 check senders = myfriend@*
283 postmaster@exim.test.ex in "myfriend@*"?
284  list element: myfriend@*
285  address match test: subject=postmaster@exim.test.ex pattern=myfriend@*
286 postmaster@exim.test.ex in "myfriend@*"? no (end of list)
287 accept: condition test failed in ACL "check_recipient"
288 processing "deny" (TESTSUITE/test-config 22)
289   message: host is listed in $dnslist_domain
290 check dnslists = rbl.test.ex
291 dnslists check: rbl.test.ex
292 new DNS lookup for 99.99.99.V4NET.rbl.test.ex
293 DNS lookup of 99.99.99.V4NET.rbl.test.ex (A) using fakens
294 DNS lookup of 99.99.99.V4NET.rbl.test.ex (A) gave HOST_NOT_FOUND
295 returning DNS_NOMATCH
296 faking res_search(A) response length as 65535
297  writing neg-cache entry for 99.99.99.V4NET.rbl.test.ex-A-xxxx, ttl 3000
298 faking res_search(A) response length as 65535
299 dnslists: wrote cache entry, ttl=3000
300 DNS lookup for 99.99.99.V4NET.rbl.test.ex failed
301 => that means V4NET.99.99.99 is not listed at rbl.test.ex
302 deny: condition test failed in ACL "check_recipient"
303 processing "require" (TESTSUITE/test-config 24)
304 check verify = sender
305 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
306 Verifying postmaster@exim.test.ex
307 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
308 Considering postmaster@exim.test.ex
309 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
310 routing postmaster@exim.test.ex
311 --------> localuser router <--------
312 local_part=postmaster domain=exim.test.ex
313 checking local_parts
314 postmaster in "userx"?
315  list element: userx
316 postmaster in "userx"? no (end of list)
317 localuser router skipped: local_parts mismatch
318 no more routers
319 ----------- end verify ------------
320 require: condition test failed in ACL "check_recipient"
321 end of ACL "check_recipient": not OK
322 LOG: MAIN REJECT
323   H=(exim.test.ex) [V4NET.99.99.99] sender verify fail for <postmaster@exim.test.ex>: Unrouteable address
324 SMTP>> 550-Verification failed for <postmaster@exim.test.ex>
325 SMTP>> 550-Unrouteable address
326 SMTP>> 550 Sender verify failed
327 LOG: MAIN REJECT
328   H=(exim.test.ex) [V4NET.99.99.99] F=<postmaster@exim.test.ex> rejected RCPT list@exim.test.ex: Sender verify failed
329 SMTP>> 421 the.local.host.name lost input connection
330 LOG: lost_incoming_connection MAIN
331   unexpected disconnection while reading SMTP command from (exim.test.ex) [V4NET.99.99.99] D=qqs
332 search_tidyup called
333 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>