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