Debug: more info during server-side authentication
[users/heiko/exim.git] / test / stderr / 0398
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
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=pppp
8 DSN: r1 propagating DSN
9 DSN: r2 propagating DSN
10 seeking password data for user "CALLER": cache not available
11 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
12 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
13 sender address = CALLER@test.ex
14 set_process_info: pppp accepting a local SMTP message from <CALLER@test.ex>
15  in hosts_connection_nolog? no (option unset)
16 LOG: smtp_connection MAIN
17   SMTP connection from CALLER
18 SMTP>> 220 mail.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
19 smtp_setup_msg entered
20 SMTP<< mail from:<qq@remote>
21 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
22 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
23 SMTP>> 250 OK
24 SMTP<< rcpt to:<abc@local>
25 using ACL "rcpt"
26 processing "deny"
27 check senders = qq@remote
28 address match test: subject=qq@remote pattern=qq@remote
29 remote in "remote"? yes (matched "remote")
30 qq@remote in "qq@remote"? yes (matched "qq@remote")
31 check !verify = sender
32 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
33 Verifying qq@remote
34 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
35 Considering qq@remote
36 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
37 routing qq@remote
38 --------> r1 router <--------
39 local_part=qq domain=remote
40 checking domains
41 remote in "local"? no (end of list)
42 r1 router skipped: domains mismatch
43 --------> r2 router <--------
44 local_part=qq domain=remote
45 checking domains
46 remote in "remote"? yes (matched "remote")
47 calling r2 router
48 r2 router called for qq@remote
49   domain = remote
50 route_item = * 127.0.0.1
51 remote in "*"? yes (matched "*")
52 original list of hosts = "127.0.0.1" options = 
53 expanded list of hosts = "127.0.0.1" options = 
54 set transport t2
55 finding IP address for 127.0.0.1
56 calling host_find_byname
57 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
58 local host found for non-MX address
59 fully qualified name = 127.0.0.1
60 MUNGED: ::1 will be omitted in what follows
61 get[host|ipnode]byname[2] looked up these IP addresses:
62   name=127.0.0.1 address=127.0.0.1
63 remote host address is the local host: remote: configured to try delivery anyway
64 queued for t2 transport: local_part = qq
65 domain = remote
66   errors_to=NULL
67   domain_data=NULL localpart_data=NULL
68 routed by r2 router
69   envelope to: qq@remote
70   transport: t2
71   host 127.0.0.1 [127.0.0.1]
72 ----------- end verify ------------
73 sender qq@remote verified ok
74 deny: condition test failed in ACL "rcpt"
75 processing "warn"
76 check senders = qq@remote
77 address match test: subject=qq@remote pattern=qq@remote
78 remote in "remote"? yes (matched "remote")
79 qq@remote in "qq@remote"? yes (matched "qq@remote")
80 check !verify = sender/callout
81 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
82 Verifying qq@remote
83 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
84 Considering qq@remote
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing qq@remote
87 --------> r1 router <--------
88 local_part=qq domain=remote
89 checking domains
90 remote in "local"? no (end of list)
91 r1 router skipped: domains mismatch
92 --------> r2 router <--------
93 local_part=qq domain=remote
94 checking domains
95 remote in "remote"? yes (matched "remote")
96 calling r2 router
97 r2 router called for qq@remote
98   domain = remote
99 route_item = * 127.0.0.1
100 remote in "*"? yes (matched "*")
101 original list of hosts = "127.0.0.1" options = 
102 expanded list of hosts = "127.0.0.1" options = 
103 finding IP address for 127.0.0.1
104 calling host_find_byname
105 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
106 local host found for non-MX address
107 fully qualified name = 127.0.0.1
108 MUNGED: ::1 will be omitted in what follows
109 get[host|ipnode]byname[2] looked up these IP addresses:
110   name=127.0.0.1 address=127.0.0.1
111 remote host address is the local host: remote: configured to try delivery anyway
112 queued for t2 transport: local_part = qq
113 domain = remote
114   errors_to=NULL
115   domain_data=NULL localpart_data=NULL
116 routed by r2 router
117   envelope to: qq@remote
118   transport: t2
119   host 127.0.0.1 [127.0.0.1]
120 Attempting full verification using callout
121 locking TESTSUITE/spool/db/callout.lockfile
122 locked  TESTSUITE/spool/db/callout.lockfile
123 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
124 returned from EXIM_DBOPEN
125 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
126 dbfn_read: key=remote
127 callout cache: found domain record for remote
128 dbfn_read: key=qq@remote
129 callout cache: no address record found for qq@remote
130 closed hints database and lockfile
131 interface=NULL port=1224
132 Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
133   SMTP<< 220 Server ready
134 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
135   SMTP>> EHLO mail.test.ex
136 cmd buf flush ddd bytes
137   SMTP<< 250 OK
138 not using PIPELINING
139 not using DSN
140 127.0.0.1 in hosts_require_auth? no (option unset)
141   SMTP>> MAIL FROM:<>
142 cmd buf flush ddd bytes
143   SMTP<< 250 OK
144   SMTP>> RCPT TO:<qq@remote>
145 cmd buf flush ddd bytes
146   SMTP<< 550 Unknown
147   SMTP>> QUIT
148 cmd buf flush ddd bytes
149   SMTP<< 250 OK
150   SMTP(close)>>
151 locking TESTSUITE/spool/db/callout.lockfile
152 locked  TESTSUITE/spool/db/callout.lockfile
153 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
154 returned from EXIM_DBOPEN
155 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
156 dbfn_write: key=remote
157 wrote callout cache domain record for remote:
158   result=1 postmaster=0 random=0
159 dbfn_write: key=qq@remote
160 wrote negative callout cache address record for qq@remote
161 closed hints database and lockfile
162 ----------- end verify ------------
163 l_message: $acl_verify_message
164 warn: condition test succeeded in ACL "rcpt"
165 LOG: MAIN
166   U=CALLER Warning: Sender verify failed: 127.0.0.1 [127.0.0.1] : SMTP error from remote mail server after RCPT TO:<qq@remote>: 550 Unknown
167 processing "accept"
168 check senders = qq@remote
169 address match test: subject=qq@remote pattern=qq@remote
170 remote in "remote"? yes (matched "remote")
171 qq@remote in "qq@remote"? yes (matched "qq@remote")
172 accept: condition test succeeded in ACL "rcpt"
173 end of ACL "rcpt": ACCEPT
174 SMTP>> 250 Accepted
175 DSN: orcpt: NULL  flags: 0
176 SMTP<< rcpt to:<xyz@local>
177 using ACL "rcpt"
178 processing "deny"
179 check senders = qq@remote
180 address match test: subject=qq@remote pattern=qq@remote
181 remote in "remote"? yes (matched "remote")
182 qq@remote in "qq@remote"? yes (matched "qq@remote")
183 check !verify = sender
184 using cached sender verify result
185 deny: condition test failed in ACL "rcpt"
186 processing "warn"
187 check senders = qq@remote
188 address match test: subject=qq@remote pattern=qq@remote
189 remote in "remote"? yes (matched "remote")
190 qq@remote in "qq@remote"? yes (matched "qq@remote")
191 check !verify = sender/callout
192 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
193 Verifying qq@remote
194 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
195 Considering qq@remote
196 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
197 routing qq@remote
198 --------> r1 router <--------
199 local_part=qq domain=remote
200 checking domains
201 remote in "local"? no (end of list)
202 r1 router skipped: domains mismatch
203 --------> r2 router <--------
204 local_part=qq domain=remote
205 checking domains
206 remote in "remote"? yes (matched "remote")
207 calling r2 router
208 r2 router called for qq@remote
209   domain = remote
210 route_item = * 127.0.0.1
211 remote in "*"? yes (matched "*")
212 original list of hosts = "127.0.0.1" options = 
213 expanded list of hosts = "127.0.0.1" options = 
214 finding IP address for 127.0.0.1
215 calling host_find_byname
216 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
217 local host found for non-MX address
218 fully qualified name = 127.0.0.1
219 MUNGED: ::1 will be omitted in what follows
220 get[host|ipnode]byname[2] looked up these IP addresses:
221   name=127.0.0.1 address=127.0.0.1
222 remote host address is the local host: remote: configured to try delivery anyway
223 queued for t2 transport: local_part = qq
224 domain = remote
225   errors_to=NULL
226   domain_data=NULL localpart_data=NULL
227 routed by r2 router
228   envelope to: qq@remote
229   transport: t2
230   host 127.0.0.1 [127.0.0.1]
231 Attempting full verification using callout
232 locking TESTSUITE/spool/db/callout.lockfile
233 locked  TESTSUITE/spool/db/callout.lockfile
234 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
235 returned from EXIM_DBOPEN
236 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
237 dbfn_read: key=remote
238 callout cache: found domain record for remote
239 dbfn_read: key=qq@remote
240 callout cache: found address record for qq@remote
241 callout cache: address record is negative
242 closed hints database and lockfile
243 ----------- end verify ------------
244 l_message: $acl_verify_message
245 warn: condition test succeeded in ACL "rcpt"
246 LOG: MAIN
247   U=CALLER Warning: Sender verify failed
248 processing "accept"
249 check senders = qq@remote
250 address match test: subject=qq@remote pattern=qq@remote
251 remote in "remote"? yes (matched "remote")
252 qq@remote in "qq@remote"? yes (matched "qq@remote")
253 accept: condition test succeeded in ACL "rcpt"
254 end of ACL "rcpt": ACCEPT
255 SMTP>> 250 Accepted
256 DSN: orcpt: NULL  flags: 0
257 SMTP<< quit
258 SMTP>> 221 mail.test.ex closing connection
259 LOG: smtp_connection MAIN
260   SMTP connection from CALLER closed by QUIT
261 search_tidyup called
262 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>