Tweak debug output
[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=p1234 (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=p1235
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=p1235
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<< helo test
165 test in helo_lookup_domains? no (end of list)
166 sender_fullhost = (test) [10.0.0.0]
167 sender_rcvhost = [10.0.0.0] (helo=test)
168 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
169 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
170 SMTP<< mail from:<a@b>
171 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
172 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
173 SMTP>> 250 OK
174 SMTP<< rcpt to:<c@d>
175 using ACL "check_recipient"
176 processing "accept" (TESTSUITE/test-config 32)
177 check domains = +local_domains
178 d in "@"? no (end of list)
179 d in "+local_domains"? no (end of list)
180 accept: condition test failed in ACL "check_recipient"
181 processing "accept" (TESTSUITE/test-config 33)
182 check hosts = +relay_hosts
183 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
184 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
185   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
186 LRU list:
187 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
188   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
189 file lookup required for select * from them where id='10.0.0.0'
190   in TESTSUITE/aux-fixed/sqlitedb
191 lookup forced cache cleanup
192 lookup failed
193 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
194 host in "+relay_hosts"? no (end of list)
195 accept: condition test failed in ACL "check_recipient"
196 processing "deny" (TESTSUITE/test-config 34)
197   message: relay not permitted
198 deny: condition test succeeded in ACL "check_recipient"
199 end of ACL "check_recipient": DENY
200 SMTP>> 550 relay not permitted
201 LOG: MAIN REJECT
202   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
203 SMTP<< rcpt to:<c@d>
204 using ACL "check_recipient"
205 processing "accept" (TESTSUITE/test-config 32)
206 check domains = +local_domains
207 d in "@"? no (end of list)
208 d in "+local_domains"? no (end of list)
209 accept: condition test failed in ACL "check_recipient"
210 processing "accept" (TESTSUITE/test-config 33)
211 check hosts = +relay_hosts
212 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
213   cached open
214 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
215   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
216 LRU list:
217 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
218   type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
219 file lookup required for select * from them where id='10.0.0.0'
220   in TESTSUITE/aux-fixed/sqlitedb
221 lookup forced cache cleanup
222 lookup failed
223 host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
224 host in "+relay_hosts"? no (end of list)
225 accept: condition test failed in ACL "check_recipient"
226 processing "deny" (TESTSUITE/test-config 34)
227   message: relay not permitted
228 deny: condition test succeeded in ACL "check_recipient"
229 end of ACL "check_recipient": DENY
230 SMTP>> 550 relay not permitted
231 LOG: MAIN REJECT
232   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
233 SMTP<< quit
234 SMTP>> 221 myhost.test.ex closing connection
235 LOG: smtp_connection MAIN
236   SMTP connection from (test) [10.0.0.0] closed by QUIT
237 search_tidyup called
238 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
239 Exim version x.yz ....
240 changed uid/gid: forcing real = effective
241   uid=uuuu gid=CALLER_GID pid=p1236
242 configuration file is TESTSUITE/test-config
243 admin user
244 changed uid/gid: privilege not needed
245   uid=EXIM_UID gid=EXIM_GID pid=p1236
246 seeking password data for user "CALLER": cache not available
247 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
248 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
249 sender address = CALLER@myhost.test.ex
250 sender_fullhost = [10.0.0.0]
251 sender_rcvhost = [10.0.0.0]
252 host in hosts_connection_nolog? no (option unset)
253 LOG: smtp_connection MAIN
254   SMTP connection from [10.0.0.0]
255 host in host_lookup? no (option unset)
256 set_process_info: pppp handling incoming connection from [10.0.0.0]
257 host in host_reject_connection? no (option unset)
258 host in sender_unqualified_hosts? no (option unset)
259 host in recipient_unqualified_hosts? no (option unset)
260 host in helo_verify_hosts? no (option unset)
261 host in helo_try_verify_hosts? no (option unset)
262 host in helo_accept_junk_hosts? no (option unset)
263 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
264 smtp_setup_msg entered
265 SMTP<< helo test
266 test in helo_lookup_domains? no (end of list)
267 sender_fullhost = (test) [10.0.0.0]
268 sender_rcvhost = [10.0.0.0] (helo=test)
269 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
270 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
271 SMTP<< mail from:<a@b>
272 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
273 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
274 SMTP>> 250 OK
275 SMTP<< rcpt to:<c@d>
276 using ACL "check_recipient"
277 processing "accept" (TESTSUITE/test-config 32)
278 check domains = +local_domains
279 d in "@"? no (end of list)
280 d in "+local_domains"? no (end of list)
281 accept: condition test failed in ACL "check_recipient"
282 processing "accept" (TESTSUITE/test-config 33)
283 check hosts = +relay_hosts
284 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
285 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
286   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
287 LRU list:
288 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
289   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
290 file lookup required for select * from them where id='10.0.0.0'
291   in TESTSUITE/aux-fixed/sqlitedb
292 lookup forced cache cleanup
293 lookup failed
294 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
295 host in "+relay_hosts"? no (end of list)
296 accept: condition test failed in ACL "check_recipient"
297 processing "deny" (TESTSUITE/test-config 34)
298   message: relay not permitted
299 deny: condition test succeeded in ACL "check_recipient"
300 end of ACL "check_recipient": DENY
301 SMTP>> 550 relay not permitted
302 LOG: MAIN REJECT
303   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
304 SMTP<< rcpt to:<c@d>
305 using ACL "check_recipient"
306 processing "accept" (TESTSUITE/test-config 32)
307 check domains = +local_domains
308 d in "@"? no (end of list)
309 d in "+local_domains"? no (end of list)
310 accept: condition test failed in ACL "check_recipient"
311 processing "accept" (TESTSUITE/test-config 33)
312 check hosts = +relay_hosts
313 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
314   cached open
315 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
316   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
317 LRU list:
318 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
319   type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
320 file lookup required for select * from them where id='10.0.0.0'
321   in TESTSUITE/aux-fixed/sqlitedb
322 lookup forced cache cleanup
323 lookup failed
324 host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
325 host in "+relay_hosts"? no (end of list)
326 accept: condition test failed in ACL "check_recipient"
327 processing "deny" (TESTSUITE/test-config 34)
328   message: relay not permitted
329 deny: condition test succeeded in ACL "check_recipient"
330 end of ACL "check_recipient": DENY
331 SMTP>> 550 relay not permitted
332 LOG: MAIN REJECT
333   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
334 SMTP<< quit
335 SMTP>> 221 myhost.test.ex closing connection
336 LOG: smtp_connection MAIN
337   SMTP connection from (test) [10.0.0.0] closed by QUIT
338 search_tidyup called
339 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
340 Exim version x.yz ....
341 changed uid/gid: forcing real = effective
342   uid=uuuu gid=CALLER_GID pid=p1237
343 configuration file is TESTSUITE/test-config
344 admin user
345 changed uid/gid: privilege not needed
346   uid=EXIM_UID gid=EXIM_GID pid=p1237
347 seeking password data for user "CALLER": cache not available
348 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
349 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
350 sender address = CALLER@myhost.test.ex
351 sender_fullhost = [10.10.10.10]
352 sender_rcvhost = [10.10.10.10]
353 host in hosts_connection_nolog? no (option unset)
354 LOG: smtp_connection MAIN
355   SMTP connection from [10.10.10.10]
356 host in host_lookup? no (option unset)
357 set_process_info: pppp handling incoming connection from [10.10.10.10]
358 host in host_reject_connection? no (option unset)
359 host in sender_unqualified_hosts? no (option unset)
360 host in recipient_unqualified_hosts? no (option unset)
361 host in helo_verify_hosts? no (option unset)
362 host in helo_try_verify_hosts? no (option unset)
363 host in helo_accept_junk_hosts? no (option unset)
364 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
365 smtp_setup_msg entered
366 SMTP<< helo test
367 test in helo_lookup_domains? no (end of list)
368 sender_fullhost = (test) [10.10.10.10]
369 sender_rcvhost = [10.10.10.10] (helo=test)
370 set_process_info: pppp handling incoming connection from (test) [10.10.10.10]
371 SMTP>> 250 myhost.test.ex Hello test [10.10.10.10]
372 SMTP<< mail from:<a@b>
373 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
374 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
375 SMTP>> 250 OK
376 SMTP<< rcpt to:<c@d>
377 using ACL "check_recipient"
378 processing "accept" (TESTSUITE/test-config 32)
379 check domains = +local_domains
380 d in "@"? no (end of list)
381 d in "+local_domains"? no (end of list)
382 accept: condition test failed in ACL "check_recipient"
383 processing "accept" (TESTSUITE/test-config 33)
384 check hosts = +relay_hosts
385 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
386 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
387   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
388 LRU list:
389 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
390   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
391 file lookup required for select * from them where id='10.10.10.10'
392   in TESTSUITE/aux-fixed/sqlitedb
393 creating new cache entry
394 lookup yielded: name=ok id=10.10.10.10 
395 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'")
396 host in "+relay_hosts"? yes (matched "+relay_hosts")
397 accept: condition test succeeded in ACL "check_recipient"
398 end of ACL "check_recipient": ACCEPT
399 SMTP>> 250 Accepted
400 SMTP<< rcpt to:<c@d>
401 using ACL "check_recipient"
402 processing "accept" (TESTSUITE/test-config 32)
403 check domains = +local_domains
404 d in "@"? no (end of list)
405 d in "+local_domains"? no (end of list)
406 accept: condition test failed in ACL "check_recipient"
407 processing "accept" (TESTSUITE/test-config 33)
408 check hosts = +relay_hosts
409 search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
410   cached open
411 search_find: file="TESTSUITE/aux-fixed/sqlitedb"
412   key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
413 LRU list:
414 internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
415   type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
416 cached data used for lookup of select * from them where id='10.10.10.10'
417   in TESTSUITE/aux-fixed/sqlitedb
418 lookup yielded: name=ok id=10.10.10.10 
419 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'")
420 host in "+relay_hosts"? yes (matched "+relay_hosts")
421 accept: condition test succeeded in ACL "check_recipient"
422 end of ACL "check_recipient": ACCEPT
423 SMTP>> 250 Accepted
424 SMTP<< quit
425 SMTP>> 221 myhost.test.ex closing connection
426 LOG: smtp_connection MAIN
427   SMTP connection from (test) [10.10.10.10] closed by QUIT
428 search_tidyup called
429 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
430 Exim version x.yz ....
431 changed uid/gid: forcing real = effective
432   uid=uuuu gid=CALLER_GID pid=p1238
433 configuration file is TESTSUITE/test-config
434 admin user
435 changed uid/gid: privilege not needed
436   uid=EXIM_UID gid=EXIM_GID pid=p1238
437 seeking password data for user "CALLER": cache not available
438 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
439 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
440 sender address = CALLER@myhost.test.ex
441 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
442 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
443 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
444 Sender: CALLER@myhost.test.ex
445 Recipients:
446   userx
447 search_tidyup called
448 >>Headers received:
449
450 qualify & rewrite recipients list
451 global rewrite rules
452 rewrite headers
453  rewrite_one_header: type=F:
454    From: CALLER_NAME <CALLER@myhost.test.ex>
455 search_tidyup called
456 >>Headers after rewriting and local additions:
457 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
458 F From: CALLER_NAME <CALLER@myhost.test.ex>
459   Date: Tue, 2 Mar 1999 09:44:33 +0000
460
461 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
462 Data file written for message 10HmaX-0005vi-00
463 >>Generated Received: header line
464 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
465         (envelope-from <CALLER@myhost.test.ex>)
466         id 10HmaX-0005vi-00
467         for userx@myhost.test.ex;
468         Tue, 2 Mar 1999 09:44:33 +0000
469 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
470 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
471 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
472 Size of headers = sss
473 LOG: MAIN
474   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
475 created log directory TESTSUITE/spool/log
476 search_tidyup called
477 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
478 Exim version x.yz ....
479 changed uid/gid: forcing real = effective
480   uid=uuuu gid=EXIM_GID pid=p1239
481 configuration file is TESTSUITE/test-config
482 trusted user
483 admin user
484 dropping to exim gid; retaining priv uid
485 seeking password data for user "CALLER": cache not available
486 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
487 set_process_info: pppp delivering specified messages
488 set_process_info: pppp delivering 10HmaX-0005vi-00
489 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
490 reading spool file 10HmaX-0005vi-00-H
491 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
492 sender_local=1 ident=CALLER
493 Non-recipients:
494  Empty Tree
495 ---- End of tree ----
496 recipients_count=1
497 **** SPOOL_IN - No additional fields
498 body_linecount=1 message_linecount=8
499 DSN: set orcpt:   flags: 0x0
500 Delivery address list:
501   userx@myhost.test.ex 
502  locking TESTSUITE/spool/db/retry.lockfile
503  locked  TESTSUITE/spool/db/retry.lockfile
504  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
505  returned from EXIM_DBOPEN: (nil)
506  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
507 no retry data available
508 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
509 Considering: userx@myhost.test.ex
510 unique = userx@myhost.test.ex
511 no   domain  retry record
512 no   address retry record
513 userx@myhost.test.ex: queued for routing
514 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
515 routing userx@myhost.test.ex
516 --------> r1 router <--------
517 local_part=userx domain=myhost.test.ex
518 processing address_data
519  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
520  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
521    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
522  LRU list:
523  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
524    type=sqlite key="select name from them where id='userx'" opts=NULL
525  file lookup required for select name from them where id='userx'
526    in TESTSUITE/aux-fixed/sqlitedb
527  creating new cache entry
528  lookup yielded: Ayen Other
529 calling r1 router
530 r1 router called for userx@myhost.test.ex
531   domain = myhost.test.ex
532 set transport t1
533 queued for t1 transport: local_part = userx
534 domain = myhost.test.ex
535   errors_to=NULL
536   domain_data=NULL local_part_data=NULL
537 routed by r1 router
538   envelope to: userx@myhost.test.ex
539   transport: t1
540 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
541 After routing:
542   Local deliveries:
543     userx@myhost.test.ex
544   Remote deliveries:
545   Failed addresses:
546   Deferred addresses:
547 search_tidyup called
548 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
549 --------> userx@myhost.test.ex <--------
550  locking TESTSUITE/spool/db/retry.lockfile
551  locked  TESTSUITE/spool/db/retry.lockfile
552  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
553  returned from EXIM_DBOPEN: (nil)
554  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
555 no retry data available
556 search_tidyup called
557 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
558   uid=CALLER_UID gid=CALLER_GID pid=p1240
559   home=NULL current=/
560 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
561 appendfile transport entered
562  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
563  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
564    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
565  LRU list:
566  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
567    type=sqlite key="select id from them where id='userx'" opts=NULL
568  file lookup required for select id from them where id='userx'
569    in TESTSUITE/aux-fixed/sqlitedb
570  creating new cache entry
571  lookup yielded: userx
572 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
573   file=TESTSUITE/test-mail/userx format=unix
574   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
575   message_suffix=\n
576   maildir_use_size_file=no
577   locking by lockfile fcntl 
578 lock name: TESTSUITE/test-mail/userx.lock
579 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
580 lock file created
581 mailbox TESTSUITE/test-mail/userx is locked
582 writing to file TESTSUITE/test-mail/userx
583 writing data block fd=dddd size=sss timeout=0
584 cannot use sendfile for body: spoolfile not wireformat
585 writing data block fd=dddd size=sss timeout=0
586 writing data block fd=dddd size=sss timeout=0
587 appendfile yields 0 with errno=dd more_errno=dd
588 search_tidyup called
589 journalling userx@myhost.test.ex
590 t1 transport returned OK for userx@myhost.test.ex
591 post-process userx@myhost.test.ex (0)
592 userx@myhost.test.ex delivered
593 LOG: MAIN
594   => userx <userx@myhost.test.ex> R=r1 T=t1
595 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
596 changed uid/gid: post-delivery tidying
597   uid=EXIM_UID gid=EXIM_GID pid=p1239
598 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
599 Processing retry items
600 Succeeded addresses:
601  userx@myhost.test.ex: no retry items
602 Failed addresses:
603 Deferred addresses:
604 end of retry processing
605 DSN: processing router : r1
606 DSN: processing successful delivery address: userx@myhost.test.ex
607 DSN: Sender_address: CALLER@myhost.test.ex
608 DSN: orcpt: NULL  flags: 0x0
609 DSN: envid: NULL  ret: 0
610 DSN: Final recipient: userx@myhost.test.ex
611 DSN: Remote SMTP server supports DSN: 0
612 DSN: not sending DSN success message
613 LOG: MAIN
614   Completed
615 end delivery of 10HmaX-0005vi-00
616 search_tidyup called
617 search_tidyup called
618 >>>>>>>>>>>>>>>> Exim pid=p1239 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
619 search_tidyup called
620 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
621 Exim version x.yz ....
622 configuration file is TESTSUITE/test-config
623 admin user
624 dropping to exim gid; retaining priv uid
625  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
626  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
627    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
628  LRU list:
629  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
630    type=sqlite key="select name from them where id='userx';" opts=NULL
631  file lookup required for select name from them where id='userx';
632    in TESTSUITE/aux-fixed/sqlitedb
633  creating new cache entry
634  lookup yielded: Ayen Other
635 search_tidyup called
636 >>>>>>>>>>>>>>>> Exim pid=p1241 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
637 Exim version x.yz ....
638 configuration file is TESTSUITE/test-config
639 admin user
640 dropping to exim gid; retaining priv uid
641  search_open: sqlite "NULL"
642  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
643  search_find: file="NULL"
644    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
645  LRU list:
646  internal_search_find: file="NULL"
647    type=sqlite key="select name from them where id='userx';" opts=NULL
648  database lookup required for select name from them where id='userx';
649  creating new cache entry
650  lookup yielded: Ayen Other
651 search_tidyup called
652 >>>>>>>>>>>>>>>> Exim pid=p1242 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
653 Exim version x.yz ....
654 configuration file is TESTSUITE/test-config
655 admin user
656 dropping to exim gid; retaining priv uid
657  search_open: sqlite "NULL"
658  Using sqlite_dbfile: nonabsolute_filename
659 search_tidyup called
660 >>>>>>>>>>>>>>>> Exim pid=p1243 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
661 Exim version x.yz ....
662 configuration file is TESTSUITE/test-config
663 admin user
664 dropping to exim gid; retaining priv uid
665  search_open: sqlite "NULL"
666  Using sqlite_dbfile: TESTSUITE/missingfile
667  search_find: file="NULL"
668    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
669  LRU list:
670  internal_search_find: file="NULL"
671    type=sqlite key="select name from them where id='userx';" opts=NULL
672  database lookup required for select name from them where id='userx';
673  sqlite3_exec failed: no such table: them
674  creating new cache entry
675  lookup failed
676 search_tidyup called
677 >>>>>>>>>>>>>>>> Exim pid=p1244 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>