DKIM: verify using separate pool-pair, reset per message
[users/heiko/exim.git] / test / stderr / 2600
1 Exim version x.yz ....
2 configuration file is TESTSUITE/test-config
3 admin user
4 dropping to exim gid; retaining priv uid
5  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
6  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
7    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
8  LRU list:
9  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
10    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
11  file lookup required for select name from them where id='userx';
12    in TESTSUITE/aux-fixed/sqlitedb
13  creating new cache entry
14  lookup yielded: Ayen Other
15  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
16    cached open
17  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
18    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
19  LRU list:
20  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
21    type=sqlite key="select name from them where id='userx';" opts=NULL
22  cached data found but wrong opts;  file lookup required for select name from them where id='userx';
23  c  in TESTSUITE/aux-fixed/sqlitedb
24  replacing old cache entry
25  lookup yielded: Ayen Other
26  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
27    cached open
28  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
29    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
30  LRU list:
31  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
32    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
33  cached data found but wrong opts;  file lookup required for select name from them where id='userx';
34  c  in TESTSUITE/aux-fixed/sqlitedb
35  replacing old cache entry
36  lookup yielded: Ayen Other
37  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
38    cached open
39  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
40    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
41  LRU list:
42  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
43    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
44  cached data used for lookup of select name from them where id='userx';
45    in TESTSUITE/aux-fixed/sqlitedb
46  lookup yielded: Ayen Other
47  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
48    cached open
49  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
50    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
51  LRU list:
52  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
53    type=sqlite key="select name from them where id='xxxx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
54  file lookup required for select name from them where id='xxxx';
55    in TESTSUITE/aux-fixed/sqlitedb
56  lookup forced cache cleanup
57  lookup failed
58  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
59    cached open
60  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
61    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
62  LRU list:
63  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
64    type=sqlite key="select name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
65  file lookup required for select name from them where id='nothing';
66    in TESTSUITE/aux-fixed/sqlitedb
67  creating new cache entry
68  lookup yielded: 
69  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
70    cached open
71  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
72    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
73  LRU list:
74  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
75    type=sqlite key="select id,name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
76  file lookup required for select id,name from them where id='nothing';
77    in TESTSUITE/aux-fixed/sqlitedb
78  creating new cache entry
79  lookup yielded: id=nothing name="" 
80  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
81    cached open
82  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
83    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
84  LRU list:
85  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
86    type=sqlite key="select * from them where id='quote2';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
87  file lookup required for select * from them where id='quote2';
88    in TESTSUITE/aux-fixed/sqlitedb
89  creating new cache entry
90  lookup yielded: name="\"stquot" id=quote2 
91  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
92    cached open
93  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
94    key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
95  LRU list:
96  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
97    type=sqlite key="select * from them where id='newline';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
98  file lookup required for select * from them where id='newline';
99    in TESTSUITE/aux-fixed/sqlitedb
100  creating new cache entry
101  lookup yielded: name="before
102  after" id=newline 
103  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
104    cached open
105  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
106    key="select * from them where id='tab';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
107  LRU list:
108  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
109    type=sqlite key="select * from them where id='tab';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
110  file lookup required for select * from them where id='tab';
111    in TESTSUITE/aux-fixed/sqlitedb
112  creating new cache entry
113  lookup yielded: name="x        x" id=tab 
114  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
115    cached open
116  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
117    key="select * from them where id='its';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
118  LRU list:
119  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
120    type=sqlite key="select * from them where id='its';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
121  file lookup required for select * from them where id='its';
122    in TESTSUITE/aux-fixed/sqlitedb
123  creating new cache entry
124  lookup yielded: name=it's id=its 
125  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
126    cached open
127  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
128    key="select * from them where name='it''s';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
129  LRU list:
130  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
131    type=sqlite key="select * from them where name='it''s';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
132  file lookup required for select * from them where name='it''s';
133    in TESTSUITE/aux-fixed/sqlitedb
134  creating new cache entry
135  lookup yielded: name=it's id=its 
136 search_tidyup called
137 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
138 Exim version x.yz ....
139 changed uid/gid: forcing real = effective
140   uid=uuuu gid=CALLER_GID pid=pppp
141 configuration file is TESTSUITE/test-config
142 admin user
143 changed uid/gid: privilege not needed
144   uid=EXIM_UID gid=EXIM_GID pid=pppp
145 seeking password data for user "CALLER": cache not available
146 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
147 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
148 sender address = CALLER@myhost.test.ex
149 sender_fullhost = [10.0.0.0]
150 sender_rcvhost = [10.0.0.0]
151 host in hosts_connection_nolog? no (option unset)
152 LOG: smtp_connection MAIN
153   SMTP connection from [10.0.0.0]
154 host in host_lookup? no (option unset)
155 set_process_info: pppp handling incoming connection from [10.0.0.0]
156 host in host_reject_connection? no (option unset)
157 host in sender_unqualified_hosts? no (option unset)
158 host in recipient_unqualified_hosts? no (option unset)
159 host in helo_verify_hosts? no (option unset)
160 host in helo_try_verify_hosts? no (option unset)
161 host in helo_accept_junk_hosts? no (option unset)
162 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
163 smtp_setup_msg entered
164 SMTP<< mail from:<a@b>
165 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
166 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
167 SMTP>> 250 OK
168 SMTP<< rcpt to:<c@d>
169 using ACL "check_recipient"
170 processing "accept" (TESTSUITE/test-config 32)
171 check domains = +local_domains
172 d in "@"? no (end of list)
173 d in "+local_domains"? no (end of list)
174 accept: condition test failed in ACL "check_recipient"
175 processing "accept" (TESTSUITE/test-config 33)
176 check hosts = +relay_hosts
177 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
178 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
179   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
180 LRU list:
181 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
182   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
183 file lookup required for select * from them where id='10.0.0.0'
184   in TESTSUITE/aux-fixed/sqlitedb
185 lookup forced cache cleanup
186 lookup failed
187 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
188 host in "+relay_hosts"? no (end of list)
189 accept: condition test failed in ACL "check_recipient"
190 processing "deny" (TESTSUITE/test-config 34)
191   message: relay not permitted
192 deny: condition test succeeded in ACL "check_recipient"
193 end of ACL "check_recipient": DENY
194 SMTP>> 550 relay not permitted
195 LOG: MAIN REJECT
196   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
197 SMTP<< rcpt to:<c@d>
198 using ACL "check_recipient"
199 processing "accept" (TESTSUITE/test-config 32)
200 check domains = +local_domains
201 d in "@"? no (end of list)
202 d in "+local_domains"? no (end of list)
203 accept: condition test failed in ACL "check_recipient"
204 processing "accept" (TESTSUITE/test-config 33)
205 check hosts = +relay_hosts
206 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
207   cached open
208 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
209   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
210 LRU list:
211 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
212   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
213 file lookup required for select * from them where id='10.0.0.0'
214   in TESTSUITE/aux-fixed/sqlitedb
215 lookup forced cache cleanup
216 lookup failed
217 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
218 host in "+relay_hosts"? no (end of list)
219 accept: condition test failed in ACL "check_recipient"
220 processing "deny" (TESTSUITE/test-config 34)
221   message: relay not permitted
222 deny: condition test succeeded in ACL "check_recipient"
223 end of ACL "check_recipient": DENY
224 SMTP>> 550 relay not permitted
225 LOG: MAIN REJECT
226   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
227 SMTP<< quit
228 SMTP>> 221 myhost.test.ex closing connection
229 LOG: smtp_connection MAIN
230   SMTP connection from [10.0.0.0] closed by QUIT
231 search_tidyup called
232 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
233 Exim version x.yz ....
234 changed uid/gid: forcing real = effective
235   uid=uuuu gid=CALLER_GID pid=pppp
236 configuration file is TESTSUITE/test-config
237 admin user
238 changed uid/gid: privilege not needed
239   uid=EXIM_UID gid=EXIM_GID pid=pppp
240 seeking password data for user "CALLER": cache not available
241 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
242 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
243 sender address = CALLER@myhost.test.ex
244 sender_fullhost = [10.0.0.0]
245 sender_rcvhost = [10.0.0.0]
246 host in hosts_connection_nolog? no (option unset)
247 LOG: smtp_connection MAIN
248   SMTP connection from [10.0.0.0]
249 host in host_lookup? no (option unset)
250 set_process_info: pppp handling incoming connection from [10.0.0.0]
251 host in host_reject_connection? no (option unset)
252 host in sender_unqualified_hosts? no (option unset)
253 host in recipient_unqualified_hosts? no (option unset)
254 host in helo_verify_hosts? no (option unset)
255 host in helo_try_verify_hosts? no (option unset)
256 host in helo_accept_junk_hosts? no (option unset)
257 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
258 smtp_setup_msg entered
259 SMTP<< mail from:<a@b>
260 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
261 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
262 SMTP>> 250 OK
263 SMTP<< rcpt to:<c@d>
264 using ACL "check_recipient"
265 processing "accept" (TESTSUITE/test-config 32)
266 check domains = +local_domains
267 d in "@"? no (end of list)
268 d in "+local_domains"? no (end of list)
269 accept: condition test failed in ACL "check_recipient"
270 processing "accept" (TESTSUITE/test-config 33)
271 check hosts = +relay_hosts
272 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
273 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
274   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
275 LRU list:
276 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
277   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
278 file lookup required for select * from them where id='10.0.0.0'
279   in TESTSUITE/aux-fixed/sqlitedb
280 lookup forced cache cleanup
281 lookup failed
282 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
283 host in "+relay_hosts"? no (end of list)
284 accept: condition test failed in ACL "check_recipient"
285 processing "deny" (TESTSUITE/test-config 34)
286   message: relay not permitted
287 deny: condition test succeeded in ACL "check_recipient"
288 end of ACL "check_recipient": DENY
289 SMTP>> 550 relay not permitted
290 LOG: MAIN REJECT
291   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
292 SMTP<< rcpt to:<c@d>
293 using ACL "check_recipient"
294 processing "accept" (TESTSUITE/test-config 32)
295 check domains = +local_domains
296 d in "@"? no (end of list)
297 d in "+local_domains"? no (end of list)
298 accept: condition test failed in ACL "check_recipient"
299 processing "accept" (TESTSUITE/test-config 33)
300 check hosts = +relay_hosts
301 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
302   cached open
303 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
304   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
305 LRU list:
306 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
307   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
308 file lookup required for select * from them where id='10.0.0.0'
309   in TESTSUITE/aux-fixed/sqlitedb
310 lookup forced cache cleanup
311 lookup failed
312 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
313 host in "+relay_hosts"? no (end of list)
314 accept: condition test failed in ACL "check_recipient"
315 processing "deny" (TESTSUITE/test-config 34)
316   message: relay not permitted
317 deny: condition test succeeded in ACL "check_recipient"
318 end of ACL "check_recipient": DENY
319 SMTP>> 550 relay not permitted
320 LOG: MAIN REJECT
321   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
322 SMTP<< quit
323 SMTP>> 221 myhost.test.ex closing connection
324 LOG: smtp_connection MAIN
325   SMTP connection from [10.0.0.0] closed by QUIT
326 search_tidyup called
327 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
328 Exim version x.yz ....
329 changed uid/gid: forcing real = effective
330   uid=uuuu gid=CALLER_GID pid=pppp
331 configuration file is TESTSUITE/test-config
332 admin user
333 changed uid/gid: privilege not needed
334   uid=EXIM_UID gid=EXIM_GID pid=pppp
335 seeking password data for user "CALLER": cache not available
336 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
337 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
338 sender address = CALLER@myhost.test.ex
339 sender_fullhost = [10.10.10.10]
340 sender_rcvhost = [10.10.10.10]
341 host in hosts_connection_nolog? no (option unset)
342 LOG: smtp_connection MAIN
343   SMTP connection from [10.10.10.10]
344 host in host_lookup? no (option unset)
345 set_process_info: pppp handling incoming connection from [10.10.10.10]
346 host in host_reject_connection? no (option unset)
347 host in sender_unqualified_hosts? no (option unset)
348 host in recipient_unqualified_hosts? no (option unset)
349 host in helo_verify_hosts? no (option unset)
350 host in helo_try_verify_hosts? no (option unset)
351 host in helo_accept_junk_hosts? no (option unset)
352 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
353 smtp_setup_msg entered
354 SMTP<< mail from:<a@b>
355 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
356 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
357 SMTP>> 250 OK
358 SMTP<< rcpt to:<c@d>
359 using ACL "check_recipient"
360 processing "accept" (TESTSUITE/test-config 32)
361 check domains = +local_domains
362 d in "@"? no (end of list)
363 d in "+local_domains"? no (end of list)
364 accept: condition test failed in ACL "check_recipient"
365 processing "accept" (TESTSUITE/test-config 33)
366 check hosts = +relay_hosts
367 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
368 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
369   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
370 LRU list:
371 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
372   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
373 file lookup required for select * from them where id='10.10.10.10'
374   in TESTSUITE/aux-fixed/sqlitedb
375 creating new cache entry
376 lookup yielded: name=ok id=10.10.10.10 
377 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"? yes (matched "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'")
378 host in "+relay_hosts"? yes (matched "+relay_hosts")
379 accept: condition test succeeded in ACL "check_recipient"
380 end of ACL "check_recipient": ACCEPT
381 SMTP>> 250 Accepted
382 SMTP<< rcpt to:<c@d>
383 using ACL "check_recipient"
384 processing "accept" (TESTSUITE/test-config 32)
385 check domains = +local_domains
386 d in "@"? no (end of list)
387 d in "+local_domains"? no (end of list)
388 accept: condition test failed in ACL "check_recipient"
389 processing "accept" (TESTSUITE/test-config 33)
390 check hosts = +relay_hosts
391 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
392   cached open
393 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
394   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
395 LRU list:
396 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
397   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
398 cached data used for lookup of select * from them where id='10.10.10.10'
399   in TESTSUITE/aux-fixed/sqlitedb
400 lookup yielded: name=ok id=10.10.10.10 
401 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"? yes (matched "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'")
402 host in "+relay_hosts"? yes (matched "+relay_hosts")
403 accept: condition test succeeded in ACL "check_recipient"
404 end of ACL "check_recipient": ACCEPT
405 SMTP>> 250 Accepted
406 SMTP<< quit
407 SMTP>> 221 myhost.test.ex closing connection
408 LOG: smtp_connection MAIN
409   SMTP connection from [10.10.10.10] closed by QUIT
410 search_tidyup called
411 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
412 Exim version x.yz ....
413 changed uid/gid: forcing real = effective
414   uid=uuuu gid=CALLER_GID pid=pppp
415 configuration file is TESTSUITE/test-config
416 admin user
417 changed uid/gid: privilege not needed
418   uid=EXIM_UID gid=EXIM_GID pid=pppp
419 seeking password data for user "CALLER": cache not available
420 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
421 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
422 sender address = CALLER@myhost.test.ex
423 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
424 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
425 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
426 Sender: CALLER@myhost.test.ex
427 Recipients:
428   userx
429 search_tidyup called
430 >>Headers received:
431
432 rewrite_one_header: type=F:
433   From: CALLER_NAME <CALLER@myhost.test.ex>
434 search_tidyup called
435 >>Headers after rewriting and local additions:
436 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
437 F From: CALLER_NAME <CALLER@myhost.test.ex>
438   Date: Tue, 2 Mar 1999 09:44:33 +0000
439
440 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
441 Data file written for message 10HmaX-0005vi-00
442 >>Generated Received: header line
443 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
444         (envelope-from <CALLER@myhost.test.ex>)
445         id 10HmaX-0005vi-00
446         for userx@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
447 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
448 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
449 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
450 Size of headers = sss
451 LOG: MAIN
452   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
453 created log directory TESTSUITE/spool/log
454 search_tidyup called
455 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DOPT=y -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
456 Exim version x.yz ....
457 changed uid/gid: forcing real = effective
458   uid=uuuu gid=EXIM_GID pid=pppp
459 configuration file is TESTSUITE/test-config
460 trusted user
461 admin user
462 dropping to exim gid; retaining priv uid
463 seeking password data for user "CALLER": cache not available
464 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
465 set_process_info: pppp delivering specified messages
466 set_process_info: pppp delivering 10HmaX-0005vi-00
467 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
468 reading spool file 10HmaX-0005vi-00-H
469 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
470 sender_local=1 ident=CALLER
471 Non-recipients:
472  Empty Tree
473 ---- End of tree ----
474 recipients_count=1
475 **** SPOOL_IN - No additional fields
476 body_linecount=1 message_linecount=7
477 DSN: set orcpt:   flags: 0x0
478 Delivery address list:
479   userx@myhost.test.ex 
480  locking TESTSUITE/spool/db/retry.lockfile
481  locked  TESTSUITE/spool/db/retry.lockfile
482  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
483  returned from EXIM_DBOPEN: (nil)
484  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
485  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
486 no retry data available
487 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
488 Considering: userx@myhost.test.ex
489 unique = userx@myhost.test.ex
490 no   domain  retry record
491 no   address retry record
492 userx@myhost.test.ex: queued for routing
493 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
494 routing userx@myhost.test.ex
495 --------> r1 router <--------
496 local_part=userx domain=myhost.test.ex
497 processing address_data
498  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
499  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
500    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
501  LRU list:
502  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
503    type=sqlite key="select name from them where id='userx'" opts=NULL
504  file lookup required for select name from them where id='userx'
505    in TESTSUITE/aux-fixed/sqlitedb
506  creating new cache entry
507  lookup yielded: Ayen Other
508 calling r1 router
509 r1 router called for userx@myhost.test.ex
510   domain = myhost.test.ex
511 set transport t1
512 queued for t1 transport: local_part = userx
513 domain = myhost.test.ex
514   errors_to=NULL
515   domain_data=NULL local_part_data=NULL
516 routed by r1 router
517   envelope to: userx@myhost.test.ex
518   transport: t1
519 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
520 After routing:
521   Local deliveries:
522     userx@myhost.test.ex
523   Remote deliveries:
524   Failed addresses:
525   Deferred addresses:
526 search_tidyup called
527 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
528 --------> userx@myhost.test.ex <--------
529  locking TESTSUITE/spool/db/retry.lockfile
530  locked  TESTSUITE/spool/db/retry.lockfile
531  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
532  returned from EXIM_DBOPEN: (nil)
533  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
534 no retry data available
535 search_tidyup called
536 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
537   uid=CALLER_UID gid=CALLER_GID pid=pppp
538   home=NULL current=/
539 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
540 appendfile transport entered
541  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
542  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
543    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
544  LRU list:
545  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
546    type=sqlite key="select id from them where id='userx'" opts=NULL
547  file lookup required for select id from them where id='userx'
548    in TESTSUITE/aux-fixed/sqlitedb
549  creating new cache entry
550  lookup yielded: userx
551 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
552   file=TESTSUITE/test-mail/userx format=unix
553   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
554   message_suffix=\n
555   maildir_use_size_file=no
556   locking by lockfile fcntl 
557 lock name: TESTSUITE/test-mail/userx.lock
558 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
559 lock file created
560 mailbox TESTSUITE/test-mail/userx is locked
561 writing to file TESTSUITE/test-mail/userx
562 writing data block fd=dddd size=sss timeout=0
563 cannot use sendfile for body: spoolfile not wireformat
564 writing data block fd=dddd size=sss timeout=0
565 writing data block fd=dddd size=sss timeout=0
566 appendfile yields 0 with errno=dd more_errno=dd
567 search_tidyup called
568 journalling userx@myhost.test.ex
569 t1 transport returned OK for userx@myhost.test.ex
570 post-process userx@myhost.test.ex (0)
571 userx@myhost.test.ex delivered
572 LOG: MAIN
573   => userx <userx@myhost.test.ex> R=r1 T=t1
574 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
575 changed uid/gid: post-delivery tidying
576   uid=EXIM_UID gid=EXIM_GID pid=pppp
577 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
578 Processing retry items
579 Succeeded addresses:
580  userx@myhost.test.ex: no retry items
581 Failed addresses:
582 Deferred addresses:
583 end of retry processing
584 DSN: processing router : r1
585 DSN: processing successful delivery address: userx@myhost.test.ex
586 DSN: Sender_address: CALLER@myhost.test.ex
587 DSN: orcpt: NULL  flags: 0x0
588 DSN: envid: NULL  ret: 0
589 DSN: Final recipient: userx@myhost.test.ex
590 DSN: Remote SMTP server supports DSN: 0
591 DSN: not sending DSN success message
592 LOG: MAIN
593   Completed
594 end delivery of 10HmaX-0005vi-00
595 search_tidyup called
596 search_tidyup called
597 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
598 search_tidyup called
599 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
600 Exim version x.yz ....
601 configuration file is TESTSUITE/test-config
602 admin user
603 dropping to exim gid; retaining priv uid
604  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
605  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
606    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
607  LRU list:
608  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
609    type=sqlite key="select name from them where id='userx';" opts=NULL
610  file lookup required for select name from them where id='userx';
611    in TESTSUITE/aux-fixed/sqlitedb
612  creating new cache entry
613  lookup yielded: Ayen Other
614 search_tidyup called
615 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
616 Exim version x.yz ....
617 configuration file is TESTSUITE/test-config
618 admin user
619 dropping to exim gid; retaining priv uid
620  search_open: sqlite "NULL"
621  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
622  search_find: file="NULL"
623    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
624  LRU list:
625  internal_search_find: file="NULL"
626    type=sqlite key="select name from them where id='userx';" opts=NULL
627  database lookup required for select name from them where id='userx';
628  creating new cache entry
629  lookup yielded: Ayen Other
630 search_tidyup called
631 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
632 Exim version x.yz ....
633 configuration file is TESTSUITE/test-config
634 admin user
635 dropping to exim gid; retaining priv uid
636  search_open: sqlite "NULL"
637  Using sqlite_dbfile: nonabsolute_filename
638 search_tidyup called
639 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
640 Exim version x.yz ....
641 configuration file is TESTSUITE/test-config
642 admin user
643 dropping to exim gid; retaining priv uid
644  search_open: sqlite "NULL"
645  Using sqlite_dbfile: TESTSUITE/missingfile
646  search_find: file="NULL"
647    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
648  LRU list:
649  internal_search_find: file="NULL"
650    type=sqlite key="select name from them where id='userx';" opts=NULL
651  database lookup required for select name from them where id='userx';
652  sqlite3_exec failed: no such table: them
653  creating new cache entry
654  lookup failed
655 search_tidyup called
656 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>