Logging: TLSA lookups should honor slow_lookup_log
[users/jgh/exim.git] / test / stderr / 2201
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 seeking password data for user "CALLER": cache not available
5 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
6 configuration file is TESTSUITE/test-config
7 trusted user
8 admin user
9 dropping to exim gid; retaining priv uid
10 seeking password data for user "CALLER": using cached result
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@myhost.test.ex
14 Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
15 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16 Testing test.ex@test.ex
17 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
18 Considering test.ex@test.ex
19 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
20 routing test.ex@test.ex
21 --------> r0 router <--------
22 local_part=test.ex domain=test.ex
23 checking senders
24 address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
25 CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
26 r0 router skipped: senders mismatch
27 --------> r1 router <--------
28 local_part=test.ex domain=test.ex
29 checking domains
30 search_open: dnsdb "NULL"
31 search_find: file="NULL"
32   key="test.ex" partial=-1 affix=NULL starflags=0
33 LRU list:
34 internal_search_find: file="NULL"
35   type=dnsdb key="test.ex"
36 database lookup required for test.ex
37 dnsdb key: test.ex
38 DNS lookup of test.ex (TXT) using fakens
39 DNS lookup of test.ex (TXT) succeeded
40 lookup yielded: A TXT record for test.ex.
41 test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
42 checking local_parts
43 search_open: dnsdb "NULL"
44   cached open
45 search_find: file="NULL"
46   key="test.ex" partial=-1 affix=NULL starflags=0
47 LRU list:
48 internal_search_find: file="NULL"
49   type=dnsdb key="test.ex"
50 cached data used for lookup of test.ex
51 lookup yielded: A TXT record for test.ex.
52 test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
53 calling r1 router
54 r1 router called for test.ex@test.ex
55   domain = test.ex
56 set transport local_delivery
57 queued for local_delivery transport: local_part = test.ex
58 domain = test.ex
59   errors_to=NULL
60   domain_data=A TXT record for test.ex. localpart_data=A TXT record for test.ex.
61 routed by r1 router
62   envelope to: test.ex@test.ex
63   transport: local_delivery
64 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
65 Testing unknown@test.ex
66 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
67 Considering unknown@test.ex
68 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
69 routing unknown@test.ex
70 --------> r0 router <--------
71 local_part=unknown domain=test.ex
72 checking senders
73 address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
74 CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
75 r0 router skipped: senders mismatch
76 --------> r1 router <--------
77 local_part=unknown domain=test.ex
78 checking domains
79 search_open: dnsdb "NULL"
80   cached open
81 search_find: file="NULL"
82   key="test.ex" partial=-1 affix=NULL starflags=0
83 LRU list:
84 internal_search_find: file="NULL"
85   type=dnsdb key="test.ex"
86 cached data used for lookup of test.ex
87 lookup yielded: A TXT record for test.ex.
88 test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
89 checking local_parts
90 search_open: dnsdb "NULL"
91   cached open
92 search_find: file="NULL"
93   key="unknown" partial=-1 affix=NULL starflags=0
94 LRU list:
95 internal_search_find: file="NULL"
96   type=dnsdb key="unknown"
97 database lookup required for unknown
98 dnsdb key: unknown
99 DNS lookup of unknown (TXT) using fakens
100 DNS lookup of unknown (TXT) gave HOST_NOT_FOUND
101 returning DNS_NOMATCH
102 faking res_search(TXT) response length as 65535
103  writing neg-cache entry for unknown-TXT-xxxx, ttl 3000
104 lookup failed
105 unknown in "dnsdb;unknown"? no (end of list)
106 r1 router skipped: local_parts mismatch
107 --------> r2 router <--------
108 local_part=unknown domain=test.ex
109 checking domains
110 search_open: dnsdb "NULL"
111   cached open
112 search_find: file="NULL"
113   key="test.ex" partial=-1 affix=NULL starflags=0
114 LRU list:
115 internal_search_find: file="NULL"
116   type=dnsdb key="test.ex"
117 cached data used for lookup of test.ex
118 lookup yielded: A TXT record for test.ex.
119 test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
120 checking senders
121 address match test: subject=CALLER@myhost.test.ex pattern=dnsdb;A=myhost.test.ex
122 search_open: dnsdb "NULL"
123   cached open
124 search_find: file="NULL"
125   key="A=myhost.test.ex" partial=-1 affix=NULL starflags=0
126 LRU list:
127 internal_search_find: file="NULL"
128   type=dnsdb key="A=myhost.test.ex"
129 database lookup required for A=myhost.test.ex
130 dnsdb key: myhost.test.ex
131 DNS lookup of myhost.test.ex (A) using fakens
132 DNS lookup of myhost.test.ex (A) succeeded
133 lookup yielded: V4NET.10.10.10
134 CALLER@myhost.test.ex in "dnsdb;A=myhost.test.ex"? yes (matched "dnsdb;A=myhost.test.ex")
135 calling r2 router
136 r2 router called for unknown@test.ex
137   domain = test.ex
138 set transport local_delivery
139 queued for local_delivery transport: local_part = unknown
140 domain = test.ex
141   errors_to=NULL
142   domain_data=A TXT record for test.ex. localpart_data=NULL
143 routed by r2 router
144   envelope to: unknown@test.ex
145   transport: local_delivery
146 search_tidyup called
147 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
148 Exim version x.yz ....
149 configuration file is TESTSUITE/test-config
150 trusted user
151 admin user
152 search_tidyup called
153 search_tidyup called
154 search_open: dnsdb "NULL"
155 search_find: file="NULL"
156   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
157 LRU list:
158 internal_search_find: file="NULL"
159   type=dnsdb key="a=shorthost.test.ex"
160 database lookup required for a=shorthost.test.ex
161 dnsdb key: shorthost.test.ex
162 lookup yielded: 127.0.0.1
163 search_open: dnsdb "NULL"
164   cached open
165 search_find: file="NULL"
166   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
167 LRU list:
168 internal_search_find: file="NULL"
169   type=dnsdb key="a=shorthost.test.ex"
170 cached data used for lookup of a=shorthost.test.ex
171 lookup yielded: 127.0.0.1
172 search_open: dnsdb "NULL"
173   cached open
174 search_find: file="NULL"
175   key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
176 LRU list:
177 internal_search_find: file="NULL"
178   type=dnsdb key="a=shorthost.test.ex"
179 cached data found but past valid time; database lookup required for a=shorthost.test.ex
180 dnsdb key: shorthost.test.ex
181 lookup yielded: 127.0.0.1
182 LOG: MAIN
183   <= a@shorthost.test.ex U=CALLER P=local S=sss
184 created log directory TESTSUITE/spool/log
185 search_tidyup called
186 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
187
188 ******** SERVER ********
189 Exim version x.yz ....
190 configuration file is TESTSUITE/test-config
191 trusted user
192 admin user
193 dropping to exim gid; retaining priv uid
194 ppppp daemon_smtp_port overridden by -oX:
195 ppppp   <: 1225
196 ppppp listening on all interfaces (IPv4) port 1225
197 ppppp pid written to TESTSUITE/spool/exim-daemon.pid
198 ppppp LOG: MAIN
199 ppppp   exim x.yz daemon started: pid=pppp, no queue runs, listening for SMTP on port 1225
200 ppppp daemon running with uid=EXIM_UID gid=EXIM_GID euid=EXIM_UID egid=EXIM_GID
201 ppppp Listening...
202 ppppp Connection request from 127.0.0.1 port sssss
203 ppppp 1 SMTP accept process running
204 ppppp Listening...
205 ppppp Process ppppp is handling incoming connection from [127.0.0.1]
206 ppppp Process ppppp is ready for new message
207 ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
208 ppppp new DNS lookup for 14.12.11.V4NET.rbl.test.ex
209 ppppp dnslists: wrote cache entry, ttl=2
210 ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
211 ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
212 ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
213 ppppp dnslists: using result of previous lookup
214 ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
215 ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
216 ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
217 ppppp cached data found but past valid time; new DNS lookup for 14.12.11.V4NET.rbl.test.ex
218 ppppp dnslists: wrote cache entry, ttl=2
219 ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
220 ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
221 ppppp LOG: MAIN
222 ppppp   <= a@shorthost.test.ex H=localhost (myhost.test.ex) [127.0.0.1] P=esmtp S=sss id=E10HmaX-0005vi-00@myhost.test.ex
223 ppppp Process ppppp is ready for new message
224 ppppp LOG: smtp_connection MAIN
225 ppppp   SMTP connection from localhost (myhost.test.ex) [127.0.0.1] closed by QUIT
226 ppppp child ppppp ended: status=0x0
227 ppppp   normal exit, 0
228 ppppp 0 SMTP accept processes now running
229 ppppp Listening...