unbreak test: s/log_write/logwrite/
[users/jgh/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: a.b.c
82  └─────result: match_address:   no
83 >>>>>>>>>>>>>>>> Exim pid=pppp (main: expansion test) 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 (main: expansion test) 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 (main: expansion test) 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 (main) 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 (main) 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 (main: expansion test) terminating with rc=0 >>>>>>>>>>>>>>>>