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