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