Fix logging of drop-after-EHLO-reject.
[exim.git] / test / stderr / 0002
1 Exim version x.yz ....
2 configuration file is TESTSUITE/test-config
3 admin user
4 considering: primary_hostname: $primary_hostname
5   expanding: primary_hostname: $primary_hostname
6      result: primary_hostname: myhost.test.ex
7 considering: match:  ${if match{abcd}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
8 considering: abcd}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
9   expanding: abcd
10      result: abcd
11 considering: \N^([ab]+)(\w+)$\N}{$2$1}fail}
12   expanding: \N^([ab]+)(\w+)$\N
13      result: ^([ab]+)(\w+)$
14   condition: match{abcd}{\N^([ab]+)(\w+)$\N}
15      result: true
16 considering: $2$1}fail}
17   expanding: $2$1
18      result: cdab
19   expanding: match:  ${if match{abcd}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
20      result: match:  cdab
21 considering: match:  ${if match{wxyz}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
22 considering: wxyz}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
23   expanding: wxyz
24      result: wxyz
25 considering: \N^([ab]+)(\w+)$\N}{$2$1}fail}
26   expanding: \N^([ab]+)(\w+)$\N
27      result: ^([ab]+)(\w+)$
28   condition: match{wxyz}{\N^([ab]+)(\w+)$\N}
29      result: false
30    scanning: $2$1}fail}
31   expanding: $2$1
32      result: 
33    skipping: result is not used
34 failed to expand: match:  ${if match{wxyz}{\N^([ab]+)(\w+)$\N}{$2$1}fail}
35    error message: "if" failed and "fail" requested
36 failure was forced
37 considering: ${if eq {1}{1}{yes}{${lookup{xx}lsearch{/non/exist}}}}
38 considering: 1}{1}{yes}{${lookup{xx}lsearch{/non/exist}}}}
39   expanding: 1
40      result: 1
41 considering: 1}{yes}{${lookup{xx}lsearch{/non/exist}}}}
42   expanding: 1
43      result: 1
44   condition: eq {1}{1}
45      result: true
46 considering: yes}{${lookup{xx}lsearch{/non/exist}}}}
47   expanding: yes
48      result: yes
49    scanning: ${lookup{xx}lsearch{/non/exist}}}}
50    scanning: xx}lsearch{/non/exist}}}}
51   expanding: xx
52      result: xx
53    skipping: result is not used
54    scanning: /non/exist}}}}
55   expanding: /non/exist
56      result: /non/exist
57    skipping: result is not used
58   expanding: ${lookup{xx}lsearch{/non/exist}}
59      result: 
60    skipping: result is not used
61   expanding: ${if eq {1}{1}{yes}{${lookup{xx}lsearch{/non/exist}}}}
62      result: yes
63 considering: match_address:   ${if match_address{a.b.c}{a.b.c}{yes}{no}}
64 considering: a.b.c}{a.b.c}{yes}{no}}
65   expanding: a.b.c
66      result: a.b.c
67 considering: a.b.c}{yes}{no}}
68   expanding: a.b.c
69      result: a.b.c
70 LOG: MAIN PANIC
71   no @ found in the subject of an address list match: subject="a.b.c" pattern="a.b.c"
72   condition: match_address{a.b.c}{a.b.c}
73      result: false
74    scanning: yes}{no}}
75   expanding: yes
76      result: yes
77    skipping: result is not used
78 considering: no}}
79   expanding: no
80      result: no
81   expanding: match_address:   ${if match_address{a.b.c}{a.b.c}{yes}{no}}
82      result: match_address:   no
83 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
84 Exim version x.yz ....
85 configuration file is TESTSUITE/test-config
86 admin user
87 considering: -oMa  sender_host_address = $sender_host_address
88   expanding: -oMa  sender_host_address = $sender_host_address
89      result: -oMa  sender_host_address = V4NET.0.0.1
90 considering:       sender_host_port = $sender_host_port
91   expanding:       sender_host_port = $sender_host_port
92      result:       sender_host_port = 1234
93 considering: -oMaa sender_host_authenticated = $sender_host_authenticated
94   expanding: -oMaa sender_host_authenticated = $sender_host_authenticated
95      result: -oMaa sender_host_authenticated = AAA
96 considering: -oMai authenticated_id = $authenticated_id
97   expanding: -oMai authenticated_id = $authenticated_id
98      result: -oMai authenticated_id = philip
99 considering: -oMas authenticated_sender = $authenticated_sender
100   expanding: -oMas authenticated_sender = $authenticated_sender
101      result: -oMas authenticated_sender = xx@yy.zz
102 considering: -oMi  interface_address = $interface_address
103   expanding: -oMi  interface_address = $interface_address
104      result: -oMi  interface_address = 1.1.1.1
105 considering:       interface_port = $interface_port
106   expanding:       interface_port = $interface_port
107      result:       interface_port = 99
108 considering: -oMr  received_protocol = $received_protocol
109   expanding: -oMr  received_protocol = $received_protocol
110      result: -oMr  received_protocol = special
111 considering: -oMt  sender_ident = $sender_ident
112   expanding: -oMt  sender_ident = $sender_ident
113      result: -oMt  sender_ident = me
114 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
115 1999-03-02 09:44:33 no host name found for IP address V4NET.11.12.13
116 Exim version x.yz ....
117 configuration file is TESTSUITE/test-config
118 admin user
119 considering: -oMa  sender_host_address = $sender_host_address
120   expanding: -oMa  sender_host_address = $sender_host_address
121      result: -oMa  sender_host_address = V4NET.0.0.1
122 considering:       sender_host_port = $sender_host_port
123   expanding:       sender_host_port = $sender_host_port
124      result:       sender_host_port = 1234
125 considering: -oMaa sender_host_authenticated = $sender_host_authenticated
126   expanding: -oMaa sender_host_authenticated = $sender_host_authenticated
127      result: -oMaa sender_host_authenticated = AAA
128 considering: -oMai authenticated_id = $authenticated_id
129   expanding: -oMai authenticated_id = $authenticated_id
130      result: -oMai authenticated_id = philip
131 considering: -oMas authenticated_sender = $authenticated_sender
132   expanding: -oMas authenticated_sender = $authenticated_sender
133      result: -oMas authenticated_sender = xx@yy.zz
134 considering: -oMi  interface_address = $interface_address
135   expanding: -oMi  interface_address = $interface_address
136      result: -oMi  interface_address = 1.1.1.1
137 considering:       interface_port = $interface_port
138   expanding:       interface_port = $interface_port
139      result:       interface_port = 99
140 considering: -oMr  received_protocol = $received_protocol
141   expanding: -oMr  received_protocol = $received_protocol
142      result: -oMr  received_protocol = special
143 considering: ----> No lookup yet: ${if eq{black}{white}{$sender_host_name}{No}}
144 considering: black}{white}{$sender_host_name}{No}}
145   expanding: black
146      result: black
147 considering: white}{$sender_host_name}{No}}
148   expanding: white
149      result: white
150   condition: eq{black}{white}
151      result: false
152    scanning: $sender_host_name}{No}}
153   expanding: $sender_host_name
154      result: 
155    skipping: result is not used
156 considering: No}}
157   expanding: No
158      result: No
159   expanding: ----> No lookup yet: ${if eq{black}{white}{$sender_host_name}{No}}
160      result: ----> No lookup yet: No
161 considering: -oMs  sender_host_name = $sender_host_name
162 looking up host name for V4NET.0.0.1
163 IP address lookup yielded "ten-1.test.ex"
164 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
165 checking addresses for ten-1.test.ex
166   V4NET.0.0.1 OK
167 sender_fullhost = ten-1.test.ex [V4NET.0.0.1]
168 sender_rcvhost = ten-1.test.ex ([V4NET.0.0.1] ident=me)
169   expanding: -oMs  sender_host_name = $sender_host_name
170      result: -oMs  sender_host_name = ten-1.test.ex
171 considering: -oMt  sender_ident = $sender_ident
172   expanding: -oMt  sender_ident = $sender_ident
173      result: -oMt  sender_ident = me
174 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
175 Exim version x.yz ....
176 changed uid/gid: forcing real = effective
177   uid=uuuu gid=CALLER_GID pid=pppp
178 configuration file is TESTSUITE/test-config
179 admin user
180 changed uid/gid: privilege not needed
181   uid=EXIM_UID gid=EXIM_GID pid=pppp
182 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
183 sender address = CALLER@myhost.test.ex
184 sender_fullhost = [V4NET.0.0.1]
185 sender_rcvhost = [V4NET.0.0.1]
186 host in hosts_connection_nolog? no (option unset)
187 LOG: smtp_connection MAIN
188   SMTP connection from [V4NET.0.0.1]
189 host in host_lookup? no (option unset)
190 set_process_info: pppp handling incoming connection from [V4NET.0.0.1]
191 host in host_reject_connection? no (option unset)
192 host in sender_unqualified_hosts? no (option unset)
193 host in recipient_unqualified_hosts? no (option unset)
194 host in helo_verify_hosts? no (option unset)
195 host in helo_try_verify_hosts? no (option unset)
196 host in helo_accept_junk_hosts? no (option unset)
197 using ACL "connect1"
198 processing "deny"
199 check hosts = <\n partial-lsearch;TESTSUITE/aux-fixed/0002.lsearch \n 1.2.3.4
200 sender host name required, to match against partial-lsearch;TESTSUITE/aux-fixed/0002.lsearch
201 looking up host name for V4NET.0.0.1
202 DNS lookup of 1.0.0.V4NET.in-addr.arpa (PTR) using fakens
203 DNS lookup of 1.0.0.V4NET.in-addr.arpa (PTR) succeeded
204 IP address lookup yielded "ten-1.test.ex"
205 DNS lookup of ten-1.test.ex (A) using fakens
206 DNS lookup of ten-1.test.ex (A) succeeded
207 ten-1.test.ex V4NET.0.0.1 mx=-1 sort=xx 
208 checking addresses for ten-1.test.ex
209 Forward DNS security status: unverified
210   V4NET.0.0.1 OK
211 sender_fullhost = ten-1.test.ex [V4NET.0.0.1]
212 sender_rcvhost = ten-1.test.ex ([V4NET.0.0.1])
213 search_open: lsearch "TESTSUITE/aux-fixed/0002.lsearch"
214 search_find: file="TESTSUITE/aux-fixed/0002.lsearch"
215   key="ten-1.test.ex" partial=2 affix=*. starflags=0
216 LRU list:
217   0TESTSUITE/aux-fixed/0002.lsearch
218   End
219 internal_search_find: file="TESTSUITE/aux-fixed/0002.lsearch"
220   type=lsearch key="ten-1.test.ex"
221 file lookup required for ten-1.test.ex
222   in TESTSUITE/aux-fixed/0002.lsearch
223 lookup yielded: 
224 host in "<
225  partial-lsearch;TESTSUITE/aux-fixed/0002.lsearch 
226  1.2.3.4"? yes (matched "partial-lsearch;TESTSUITE/aux-fixed/0002.lsearch")
227 deny: condition test succeeded in ACL "connect1"
228 end of ACL "connect1": DENY
229 SMTP>> 550 Administrative prohibition
230 LOG: connection_reject MAIN REJECT
231   H=ten-1.test.ex [V4NET.0.0.1] rejected connection in "connect" ACL
232 search_tidyup called
233 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
234 Exim version x.yz ....
235 changed uid/gid: forcing real = effective
236   uid=uuuu gid=CALLER_GID pid=pppp
237 configuration file is TESTSUITE/test-config
238 admin user
239 changed uid/gid: privilege not needed
240   uid=EXIM_UID gid=EXIM_GID pid=pppp
241 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
242 sender address = CALLER@myhost.test.ex
243 sender_fullhost = [V4NET.0.0.2]
244 sender_rcvhost = [V4NET.0.0.2]
245 host in hosts_connection_nolog? no (option unset)
246 LOG: smtp_connection MAIN
247   SMTP connection from [V4NET.0.0.2]
248 host in host_lookup? no (option unset)
249 set_process_info: pppp handling incoming connection from [V4NET.0.0.2]
250 host in host_reject_connection? no (option unset)
251 host in sender_unqualified_hosts? no (option unset)
252 host in recipient_unqualified_hosts? no (option unset)
253 host in helo_verify_hosts? no (option unset)
254 host in helo_try_verify_hosts? no (option unset)
255 host in helo_accept_junk_hosts? no (option unset)
256 using ACL "connect2"
257 processing "deny"
258 check hosts = net-lsearch;TESTSUITE/aux-fixed/0002.lsearch
259 search_open: lsearch "TESTSUITE/aux-fixed/0002.lsearch"
260 search_find: file="TESTSUITE/aux-fixed/0002.lsearch"
261   key="V4NET.0.0.2" partial=-1 affix=NULL starflags=0
262 LRU list:
263   0TESTSUITE/aux-fixed/0002.lsearch
264   End
265 internal_search_find: file="TESTSUITE/aux-fixed/0002.lsearch"
266   type=lsearch key="V4NET.0.0.2"
267 file lookup required for V4NET.0.0.2
268   in TESTSUITE/aux-fixed/0002.lsearch
269 lookup failed
270 host in "net-lsearch;TESTSUITE/aux-fixed/0002.lsearch"? no (end of list)
271 deny: condition test failed in ACL "connect2"
272 end of ACL "connect2": implicit DENY
273 SMTP>> 550 Administrative prohibition
274 LOG: connection_reject MAIN REJECT
275   H=[V4NET.0.0.2] rejected connection in "connect" ACL
276 search_tidyup called
277 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
278 >>> host in hosts_connection_nolog? no (option unset)
279 >>> host in host_lookup? no (option unset)
280 >>> host in host_reject_connection? no (option unset)
281 >>> host in sender_unqualified_hosts? no (option unset)
282 >>> host in recipient_unqualified_hosts? no (option unset)
283 >>> host in helo_verify_hosts? no (option unset)
284 >>> host in helo_try_verify_hosts? no (option unset)
285 >>> host in helo_accept_junk_hosts? no (option unset)
286 >>> using ACL "connect0"
287 >>> processing "accept"
288 >>> accept: condition test succeeded in ACL "connect0"
289 >>> end of ACL "connect0": ACCEPT
290 >>> processing "accept"
291 >>> accept: condition test succeeded in inline ACL
292 >>> end of inline ACL: ACCEPT
293 >>> using ACL "check_data"
294 >>> processing "warn"
295 >>> check logwrite = Subject is: "$h_subject:"
296 >>>                = Subject is: ""
297 LOG: 10HmaX-0005vi-00 Subject is: ""
298 >>> warn: condition test succeeded in ACL "check_data"
299 >>> processing "deny"
300 >>>   message: reply_address=<$reply_address>
301 >>> deny: condition test succeeded in ACL "check_data"
302 >>> end of ACL "check_data": DENY
303 LOG: 10HmaX-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<>
304 >>> processing "accept"
305 >>> accept: condition test succeeded in inline ACL
306 >>> end of inline ACL: ACCEPT
307 >>> host in ignore_fromline_hosts? no (option unset)
308 >>> using ACL "check_data"
309 >>> processing "warn"
310 >>> check logwrite = Subject is: "$h_subject:"
311 >>>                = Subject is: ""
312 LOG: 10HmaY-0005vi-00 Subject is: ""
313 >>> warn: condition test succeeded in ACL "check_data"
314 >>> processing "deny"
315 >>>   message: reply_address=<$reply_address>
316 >>> deny: condition test succeeded in ACL "check_data"
317 >>> end of ACL "check_data": DENY
318 LOG: 10HmaY-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<a@b>
319 >>> processing "accept"
320 >>> accept: condition test succeeded in inline ACL
321 >>> end of inline ACL: ACCEPT
322 >>> host in ignore_fromline_hosts? no (option unset)
323 >>> using ACL "check_data"
324 >>> processing "warn"
325 >>> check logwrite = Subject is: "$h_subject:"
326 >>>                = Subject is: ""
327 LOG: 10HmaZ-0005vi-00 Subject is: ""
328 >>> warn: condition test succeeded in ACL "check_data"
329 >>> processing "deny"
330 >>>   message: reply_address=<$reply_address>
331 >>> deny: condition test succeeded in ACL "check_data"
332 >>> end of ACL "check_data": DENY
333 LOG: 10HmaZ-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<c@d>
334 >>> processing "accept"
335 >>> accept: condition test succeeded in inline ACL
336 >>> end of inline ACL: ACCEPT
337 >>> host in ignore_fromline_hosts? no (option unset)
338 >>> using ACL "check_data"
339 >>> processing "warn"
340 >>> check logwrite = Subject is: "$h_subject:"
341 >>>                = Subject is: ""
342 LOG: 10HmbA-0005vi-00 Subject is: ""
343 >>> warn: condition test succeeded in ACL "check_data"
344 >>> processing "deny"
345 >>>   message: reply_address=<$reply_address>
346 >>> deny: condition test succeeded in ACL "check_data"
347 >>> end of ACL "check_data": DENY
348 LOG: 10HmbA-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<>
349 >>> processing "accept"
350 >>> accept: condition test succeeded in inline ACL
351 >>> end of inline ACL: ACCEPT
352 >>> host in ignore_fromline_hosts? no (option unset)
353 >>> using ACL "check_data"
354 >>> processing "warn"
355 >>> check logwrite = Subject is: "$h_subject:"
356 >>>                = Subject is: ""
357 LOG: 10HmbB-0005vi-00 Subject is: ""
358 >>> warn: condition test succeeded in ACL "check_data"
359 >>> processing "deny"
360 >>>   message: reply_address=<$reply_address>
361 >>> deny: condition test succeeded in ACL "check_data"
362 >>> end of ACL "check_data": DENY
363 LOG: 10HmbB-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<x@y>
364 >>> host in hosts_connection_nolog? no (option unset)
365 >>> host in host_lookup? no (option unset)
366 >>> host in host_reject_connection? no (option unset)
367 >>> host in sender_unqualified_hosts? no (option unset)
368 >>> host in recipient_unqualified_hosts? no (option unset)
369 >>> host in helo_verify_hosts? no (option unset)
370 >>> host in helo_try_verify_hosts? no (option unset)
371 >>> host in helo_accept_junk_hosts? no (option unset)
372 >>> using ACL "connect0"
373 >>> processing "accept"
374 >>> accept: condition test succeeded in ACL "connect0"
375 >>> end of ACL "connect0": ACCEPT
376 >>> processing "accept"
377 >>> accept: condition test succeeded in inline ACL
378 >>> end of inline ACL: ACCEPT
379 >>> host in ignore_fromline_hosts? no (option unset)
380 >>> using ACL "check_data"
381 >>> processing "warn"
382 >>> check logwrite = Subject is: "$h_subject:"
383 >>>                = Subject is: "=?iso-8859-8?Q?_here_we_go=3A_a_string_that_is_going_to_be_encoded=3A_it_will_go_over_the_75-char_limit_by_a_long_way=3B_in_fact_this_one_will_go_over_the_150_character_limit?="
384 LOG: 10HmbC-0005vi-00 Subject is: "=?iso-8859-8?Q?_here_we_go=3A_a_string_that_is_going_to_be_encoded=3A_it_will_go_over_the_75-char_limit_by_a_long_way=3B_in_fact_this_one_will_go_over_the_150_character_limit?="
385 >>> warn: condition test succeeded in ACL "check_data"
386 >>> processing "deny"
387 >>>   message: reply_address=<$reply_address>
388 >>> deny: condition test succeeded in ACL "check_data"
389 >>> end of ACL "check_data": DENY
390 LOG: 10HmbC-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<>
391 >>> host in hosts_connection_nolog? no (option unset)
392 >>> host in host_lookup? no (option unset)
393 >>> host in host_reject_connection? no (option unset)
394 >>> host in sender_unqualified_hosts? no (option unset)
395 >>> host in recipient_unqualified_hosts? no (option unset)
396 >>> host in helo_verify_hosts? no (option unset)
397 >>> host in helo_try_verify_hosts? no (option unset)
398 >>> host in helo_accept_junk_hosts? no (option unset)
399 >>> using ACL "connect0"
400 >>> processing "accept"
401 >>> accept: condition test succeeded in ACL "connect0"
402 >>> end of ACL "connect0": ACCEPT
403 >>> processing "accept"
404 >>> accept: condition test succeeded in inline ACL
405 >>> end of inline ACL: ACCEPT
406 >>> host in ignore_fromline_hosts? no (option unset)
407 >>> using ACL "check_data"
408 >>> processing "warn"
409 >>> check logwrite = Subject is: "$h_subject:"
410 >>>                = Subject is: " here we go: a string that is going to be encoded: it will go over the 75-char limit by a long way; in fact this one will go over the 150 character limit"
411 LOG: 10HmbD-0005vi-00 Subject is: " here we go: a string that is going to be encoded: it will go over the 75-char limit by a long way; in fact this one will go over the 150 character limit"
412 >>> warn: condition test succeeded in ACL "check_data"
413 >>> processing "deny"
414 >>>   message: reply_address=<$reply_address>
415 >>> deny: condition test succeeded in ACL "check_data"
416 >>> end of ACL "check_data": DENY
417 LOG: 10HmbD-0005vi-00 H=[V4NET.0.0.0] F=<> rejected after DATA: reply_address=<>
418 Exim version x.yz ....
419 changed uid/gid: -C, -D, -be or -bf forces real uid
420   uid=CALLER_UID gid=CALLER_GID pid=pppp
421 configuration file is TESTSUITE/test-config
422 admin user
423 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
424 sender address = CALLER@myhost.test.ex
425 1.2.3.4 in "1.2.3"? no (malformed IPv4 address or address mask)
426 1.2.3.4 in "1.2.3.4/abc"? no (malformed IPv4 address or address mask)
427 search_tidyup called
428 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>