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 >>>>>>>>>>>>>>>>