Debug: indent ACL and expreassion tracing by evaluation depth
[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 >>>>>>>>>>>>>>>>