Debug: list searching
[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 SHELL in keep_environment?
142  list element: PATH
143  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
144 EXIM_TEST_NUMBER in keep_environment?
145  list element: PATH
146  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
147 PWD in keep_environment?
148  list element: PATH
149  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
150 TERM in keep_environment?
151  list element: PATH
152  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
153 USER in keep_environment?
154  list element: PATH
155  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
156 SHLVL in keep_environment?
157  list element: PATH
158  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
159 PATH in keep_environment?
160  list element: PATH
161 _ in keep_environment?
162  list element: PATH
163  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
164 PATH in keep_environment?
165  list element: PATH
166 configuration file is TESTSUITE/test-config
167 admin user
168 changed uid/gid: privilege not needed
169   uid=EXIM_UID gid=EXIM_GID pid=p1235
170 seeking password data for user "CALLER": cache not available
171 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
172 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
173 sender address = CALLER@myhost.test.ex
174 sender_fullhost = [10.0.0.0]
175 sender_rcvhost = [10.0.0.0]
176 host in hosts_connection_nolog? no (option unset)
177 LOG: smtp_connection MAIN
178   SMTP connection from [10.0.0.0]
179 host in host_lookup? no (option unset)
180 set_process_info: pppp handling incoming connection from [10.0.0.0]
181 host in host_reject_connection? no (option unset)
182 host in sender_unqualified_hosts? no (option unset)
183 host in recipient_unqualified_hosts? no (option unset)
184 host in helo_verify_hosts? no (option unset)
185 host in helo_try_verify_hosts? no (option unset)
186 host in helo_accept_junk_hosts? no (option unset)
187 host in pipelining_connect_advertise_hosts?
188  list element: 
189 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
190 smtp_setup_msg entered
191 SMTP<< helo test
192 test in helo_lookup_domains?
193  list element: @
194  list element: @[]
195 test in helo_lookup_domains? no (end of list)
196 sender_fullhost = (test) [10.0.0.0]
197 sender_rcvhost = [10.0.0.0] (helo=test)
198 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
199 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
200 SMTP<< mail from:<a@b>
201 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
202 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
203 SMTP>> 250 OK
204 SMTP<< rcpt to:<c@d>
205 using ACL "check_recipient"
206 processing "accept" (TESTSUITE/test-config 32)
207 check domains = +local_domains
208 d in "+local_domains"?
209  list element: +local_domains
210   start sublist local_domains
211    d in "@"?
212    ╎list element: @
213    d in "@"? no (end of list)
214   end sublist local_domains
215 d in "+local_domains"? no (end of list)
216 accept: condition test failed in ACL "check_recipient"
217 processing "accept" (TESTSUITE/test-config 33)
218 check hosts = +relay_hosts
219 host in "+relay_hosts"?
220  list element: +relay_hosts
221   start sublist relay_hosts
222   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
223    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
224    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
225    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
226    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
227    ╎LRU list:
228    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
229    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
230    ╎file lookup required for select * from them where id='10.0.0.0'
231    ╎  in TESTSUITE/aux-fixed/sqlitedb
232    ╎lookup forced cache cleanup
233    ╎lookup failed
234   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
235   end sublist relay_hosts
236 host in "+relay_hosts"? no (end of list)
237 accept: condition test failed in ACL "check_recipient"
238 processing "deny" (TESTSUITE/test-config 34)
239   message: relay not permitted
240 deny: condition test succeeded in ACL "check_recipient"
241 end of ACL "check_recipient": DENY
242 SMTP>> 550 relay not permitted
243 LOG: MAIN REJECT
244   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
245 SMTP<< rcpt to:<c@d>
246 using ACL "check_recipient"
247 processing "accept" (TESTSUITE/test-config 32)
248 check domains = +local_domains
249 d in "+local_domains"?
250  list element: +local_domains
251   start sublist local_domains
252    d in "@"?
253    ╎list element: @
254    d in "@"? no (end of list)
255   end sublist local_domains
256 d in "+local_domains"? no (end of list)
257 accept: condition test failed in ACL "check_recipient"
258 processing "accept" (TESTSUITE/test-config 33)
259 check hosts = +relay_hosts
260 host in "+relay_hosts"?
261  list element: +relay_hosts
262   start sublist relay_hosts
263   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
264    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
265    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
266    ╎  cached open
267    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
268    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
269    ╎LRU list:
270    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
271    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
272    ╎file lookup required for select * from them where id='10.0.0.0'
273    ╎  in TESTSUITE/aux-fixed/sqlitedb
274    ╎lookup forced cache cleanup
275    ╎lookup failed
276   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
277   end sublist relay_hosts
278 host in "+relay_hosts"? no (end of list)
279 accept: condition test failed in ACL "check_recipient"
280 processing "deny" (TESTSUITE/test-config 34)
281   message: relay not permitted
282 deny: condition test succeeded in ACL "check_recipient"
283 end of ACL "check_recipient": DENY
284 SMTP>> 550 relay not permitted
285 LOG: MAIN REJECT
286   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
287 SMTP<< quit
288 SMTP>> 221 myhost.test.ex closing connection
289 LOG: smtp_connection MAIN
290   SMTP connection from (test) [10.0.0.0] closed by QUIT
291 search_tidyup called
292 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
293 Exim version x.yz ....
294 changed uid/gid: forcing real = effective
295   uid=uuuu gid=CALLER_GID pid=p1236
296 SHELL in keep_environment?
297  list element: PATH
298  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
299 EXIM_TEST_NUMBER in keep_environment?
300  list element: PATH
301  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
302 PWD in keep_environment?
303  list element: PATH
304  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
305 TERM in keep_environment?
306  list element: PATH
307  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
308 USER in keep_environment?
309  list element: PATH
310  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
311 SHLVL in keep_environment?
312  list element: PATH
313  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
314 PATH in keep_environment?
315  list element: PATH
316 _ in keep_environment?
317  list element: PATH
318  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
319 PATH in keep_environment?
320  list element: PATH
321 configuration file is TESTSUITE/test-config
322 admin user
323 changed uid/gid: privilege not needed
324   uid=EXIM_UID gid=EXIM_GID pid=p1236
325 seeking password data for user "CALLER": cache not available
326 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
327 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
328 sender address = CALLER@myhost.test.ex
329 sender_fullhost = [10.0.0.0]
330 sender_rcvhost = [10.0.0.0]
331 host in hosts_connection_nolog? no (option unset)
332 LOG: smtp_connection MAIN
333   SMTP connection from [10.0.0.0]
334 host in host_lookup? no (option unset)
335 set_process_info: pppp handling incoming connection from [10.0.0.0]
336 host in host_reject_connection? no (option unset)
337 host in sender_unqualified_hosts? no (option unset)
338 host in recipient_unqualified_hosts? no (option unset)
339 host in helo_verify_hosts? no (option unset)
340 host in helo_try_verify_hosts? no (option unset)
341 host in helo_accept_junk_hosts? no (option unset)
342 host in pipelining_connect_advertise_hosts?
343  list element: 
344 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
345 smtp_setup_msg entered
346 SMTP<< helo test
347 test in helo_lookup_domains?
348  list element: @
349  list element: @[]
350 test in helo_lookup_domains? no (end of list)
351 sender_fullhost = (test) [10.0.0.0]
352 sender_rcvhost = [10.0.0.0] (helo=test)
353 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
354 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
355 SMTP<< mail from:<a@b>
356 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
357 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
358 SMTP>> 250 OK
359 SMTP<< rcpt to:<c@d>
360 using ACL "check_recipient"
361 processing "accept" (TESTSUITE/test-config 32)
362 check domains = +local_domains
363 d in "+local_domains"?
364  list element: +local_domains
365   start sublist local_domains
366    d in "@"?
367    ╎list element: @
368    d in "@"? no (end of list)
369   end sublist local_domains
370 d in "+local_domains"? no (end of list)
371 accept: condition test failed in ACL "check_recipient"
372 processing "accept" (TESTSUITE/test-config 33)
373 check hosts = +relay_hosts
374 host in "+relay_hosts"?
375  list element: +relay_hosts
376   start sublist relay_hosts
377   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
378    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
379    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
380    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
381    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
382    ╎LRU list:
383    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
384    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
385    ╎file lookup required for select * from them where id='10.0.0.0'
386    ╎  in TESTSUITE/aux-fixed/sqlitedb
387    ╎lookup forced cache cleanup
388    ╎lookup failed
389   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
390   end sublist relay_hosts
391 host in "+relay_hosts"? no (end of list)
392 accept: condition test failed in ACL "check_recipient"
393 processing "deny" (TESTSUITE/test-config 34)
394   message: relay not permitted
395 deny: condition test succeeded in ACL "check_recipient"
396 end of ACL "check_recipient": DENY
397 SMTP>> 550 relay not permitted
398 LOG: MAIN REJECT
399   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
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 "+local_domains"?
405  list element: +local_domains
406   start sublist local_domains
407    d in "@"?
408    ╎list element: @
409    d in "@"? no (end of list)
410   end sublist local_domains
411 d in "+local_domains"? no (end of list)
412 accept: condition test failed in ACL "check_recipient"
413 processing "accept" (TESTSUITE/test-config 33)
414 check hosts = +relay_hosts
415 host in "+relay_hosts"?
416  list element: +relay_hosts
417   start sublist relay_hosts
418   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
419    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
420    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
421    ╎  cached open
422    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
423    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
424    ╎LRU list:
425    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
426    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
427    ╎file lookup required for select * from them where id='10.0.0.0'
428    ╎  in TESTSUITE/aux-fixed/sqlitedb
429    ╎lookup forced cache cleanup
430    ╎lookup failed
431   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
432   end sublist relay_hosts
433 host in "+relay_hosts"? no (end of list)
434 accept: condition test failed in ACL "check_recipient"
435 processing "deny" (TESTSUITE/test-config 34)
436   message: relay not permitted
437 deny: condition test succeeded in ACL "check_recipient"
438 end of ACL "check_recipient": DENY
439 SMTP>> 550 relay not permitted
440 LOG: MAIN REJECT
441   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
442 SMTP<< quit
443 SMTP>> 221 myhost.test.ex closing connection
444 LOG: smtp_connection MAIN
445   SMTP connection from (test) [10.0.0.0] closed by QUIT
446 search_tidyup called
447 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
448 Exim version x.yz ....
449 changed uid/gid: forcing real = effective
450   uid=uuuu gid=CALLER_GID pid=p1237
451 SHELL in keep_environment?
452  list element: PATH
453  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
454 EXIM_TEST_NUMBER in keep_environment?
455  list element: PATH
456  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
457 PWD in keep_environment?
458  list element: PATH
459  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
460 TERM in keep_environment?
461  list element: PATH
462  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
463 USER in keep_environment?
464  list element: PATH
465  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
466 SHLVL in keep_environment?
467  list element: PATH
468  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
469 PATH in keep_environment?
470  list element: PATH
471 _ in keep_environment?
472  list element: PATH
473  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
474 PATH in keep_environment?
475  list element: PATH
476 configuration file is TESTSUITE/test-config
477 admin user
478 changed uid/gid: privilege not needed
479   uid=EXIM_UID gid=EXIM_GID pid=p1237
480 seeking password data for user "CALLER": cache not available
481 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
482 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
483 sender address = CALLER@myhost.test.ex
484 sender_fullhost = [10.10.10.10]
485 sender_rcvhost = [10.10.10.10]
486 host in hosts_connection_nolog? no (option unset)
487 LOG: smtp_connection MAIN
488   SMTP connection from [10.10.10.10]
489 host in host_lookup? no (option unset)
490 set_process_info: pppp handling incoming connection from [10.10.10.10]
491 host in host_reject_connection? no (option unset)
492 host in sender_unqualified_hosts? no (option unset)
493 host in recipient_unqualified_hosts? no (option unset)
494 host in helo_verify_hosts? no (option unset)
495 host in helo_try_verify_hosts? no (option unset)
496 host in helo_accept_junk_hosts? no (option unset)
497 host in pipelining_connect_advertise_hosts?
498  list element: 
499 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
500 smtp_setup_msg entered
501 SMTP<< helo test
502 test in helo_lookup_domains?
503  list element: @
504  list element: @[]
505 test in helo_lookup_domains? no (end of list)
506 sender_fullhost = (test) [10.10.10.10]
507 sender_rcvhost = [10.10.10.10] (helo=test)
508 set_process_info: pppp handling incoming connection from (test) [10.10.10.10]
509 SMTP>> 250 myhost.test.ex Hello test [10.10.10.10]
510 SMTP<< mail from:<a@b>
511 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
512 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
513 SMTP>> 250 OK
514 SMTP<< rcpt to:<c@d>
515 using ACL "check_recipient"
516 processing "accept" (TESTSUITE/test-config 32)
517 check domains = +local_domains
518 d in "+local_domains"?
519  list element: +local_domains
520   start sublist local_domains
521    d in "@"?
522    ╎list element: @
523    d in "@"? no (end of list)
524   end sublist local_domains
525 d in "+local_domains"? no (end of list)
526 accept: condition test failed in ACL "check_recipient"
527 processing "accept" (TESTSUITE/test-config 33)
528 check hosts = +relay_hosts
529 host in "+relay_hosts"?
530  list element: +relay_hosts
531   start sublist relay_hosts
532   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
533    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
534    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
535    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
536    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
537    ╎LRU list:
538    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
539    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
540    ╎file lookup required for select * from them where id='10.10.10.10'
541    ╎  in TESTSUITE/aux-fixed/sqlitedb
542    ╎creating new cache entry
543    ╎lookup yielded: name=ok id=10.10.10.10 
544    ╎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'")
545   end sublist relay_hosts
546   host in "+relay_hosts"? yes (matched "+relay_hosts")
547 accept: condition test succeeded in ACL "check_recipient"
548 end of ACL "check_recipient": ACCEPT
549 SMTP>> 250 Accepted
550 SMTP<< rcpt to:<c@d>
551 using ACL "check_recipient"
552 processing "accept" (TESTSUITE/test-config 32)
553 check domains = +local_domains
554 d in "+local_domains"?
555  list element: +local_domains
556   start sublist local_domains
557    d in "@"?
558    ╎list element: @
559    d in "@"? no (end of list)
560   end sublist local_domains
561 d in "+local_domains"? no (end of list)
562 accept: condition test failed in ACL "check_recipient"
563 processing "accept" (TESTSUITE/test-config 33)
564 check hosts = +relay_hosts
565 host in "+relay_hosts"?
566  list element: +relay_hosts
567   start sublist relay_hosts
568   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
569    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
570    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
571    ╎  cached open
572    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
573    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
574    ╎LRU list:
575    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
576    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
577    ╎cached data used for lookup of select * from them where id='10.10.10.10'
578    ╎  in TESTSUITE/aux-fixed/sqlitedb
579    ╎lookup yielded: name=ok id=10.10.10.10 
580    ╎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'")
581   end sublist relay_hosts
582   host in "+relay_hosts"? yes (matched "+relay_hosts")
583 accept: condition test succeeded in ACL "check_recipient"
584 end of ACL "check_recipient": ACCEPT
585 SMTP>> 250 Accepted
586 SMTP<< quit
587 SMTP>> 221 myhost.test.ex closing connection
588 LOG: smtp_connection MAIN
589   SMTP connection from (test) [10.10.10.10] closed by QUIT
590 search_tidyup called
591 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
592 Exim version x.yz ....
593 changed uid/gid: forcing real = effective
594   uid=uuuu gid=CALLER_GID pid=p1238
595 SHELL in keep_environment?
596  list element: PATH
597  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
598 EXIM_TEST_NUMBER in keep_environment?
599  list element: PATH
600  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
601 PWD in keep_environment?
602  list element: PATH
603  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
604 TERM in keep_environment?
605  list element: PATH
606  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
607 USER in keep_environment?
608  list element: PATH
609  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
610 SHLVL in keep_environment?
611  list element: PATH
612  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
613 PATH in keep_environment?
614  list element: PATH
615 _ in keep_environment?
616  list element: PATH
617  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
618 PATH in keep_environment?
619  list element: PATH
620 configuration file is TESTSUITE/test-config
621 admin user
622 changed uid/gid: privilege not needed
623   uid=EXIM_UID gid=EXIM_GID pid=p1238
624 seeking password data for user "CALLER": cache not available
625 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
626 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
627 sender address = CALLER@myhost.test.ex
628 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
629 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
630 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
631 Sender: CALLER@myhost.test.ex
632 Recipients:
633   userx
634 search_tidyup called
635 >>Headers received:
636
637 qualify & rewrite recipients list
638 global rewrite rules
639 rewrite headers
640  rewrite_one_header: type=F:
641    From: CALLER_NAME <CALLER@myhost.test.ex>
642 search_tidyup called
643 >>Headers after rewriting and local additions:
644 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
645 F From: CALLER_NAME <CALLER@myhost.test.ex>
646   Date: Tue, 2 Mar 1999 09:44:33 +0000
647
648 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
649 Data file written for message 10HmaX-0005vi-00
650 >>Generated Received: header line
651 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
652         (envelope-from <CALLER@myhost.test.ex>)
653         id 10HmaX-0005vi-00
654         for userx@myhost.test.ex;
655         Tue, 2 Mar 1999 09:44:33 +0000
656 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
657 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
658 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
659 Size of headers = sss
660 LOG: MAIN
661   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
662 created log directory TESTSUITE/spool/log
663 search_tidyup called
664 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
665 Exim version x.yz ....
666 changed uid/gid: forcing real = effective
667   uid=uuuu gid=EXIM_GID pid=p1239
668 PATH in keep_environment?
669  list element: PATH
670 SSLKEYLOGFILE in keep_environment?
671  list element: PATH
672  list element: EXIM_TESTHARNESS_DISABLE_OCSPVALIDITYCHECK
673 PATH in keep_environment?
674  list element: PATH
675 configuration file is TESTSUITE/test-config
676 trusted user
677 admin user
678 dropping to exim gid; retaining priv uid
679 seeking password data for user "CALLER": cache not available
680 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
681 set_process_info: pppp delivering specified messages
682 set_process_info: pppp delivering 10HmaX-0005vi-00
683 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
684 reading spool file 10HmaX-0005vi-00-H
685 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
686 sender_local=1 ident=CALLER
687 Non-recipients:
688  Empty Tree
689 ---- End of tree ----
690 recipients_count=1
691 **** SPOOL_IN - No additional fields
692 body_linecount=1 message_linecount=8
693 DSN: set orcpt:   flags: 0x0
694 Delivery address list:
695   userx@myhost.test.ex 
696  locking TESTSUITE/spool/db/retry.lockfile
697  locked  TESTSUITE/spool/db/retry.lockfile
698  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
699  returned from EXIM_DBOPEN: (nil)
700  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
701 no retry data available
702 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
703 Considering: userx@myhost.test.ex
704 unique = userx@myhost.test.ex
705 no   domain  retry record
706 no   address retry record
707 userx@myhost.test.ex: queued for routing
708 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
709 routing userx@myhost.test.ex
710 --------> r1 router <--------
711 local_part=userx domain=myhost.test.ex
712 processing address_data
713  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
714  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
715    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
716  LRU list:
717  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
718    type=sqlite key="select name from them where id='userx'" opts=NULL
719  file lookup required for select name from them where id='userx'
720    in TESTSUITE/aux-fixed/sqlitedb
721  creating new cache entry
722  lookup yielded: Ayen Other
723 calling r1 router
724 r1 router called for userx@myhost.test.ex
725   domain = myhost.test.ex
726 set transport t1
727 queued for t1 transport: local_part = userx
728 domain = myhost.test.ex
729   errors_to=NULL
730   domain_data=NULL local_part_data=NULL
731 routed by r1 router
732   envelope to: userx@myhost.test.ex
733   transport: t1
734 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
735 After routing:
736   Local deliveries:
737     userx@myhost.test.ex
738   Remote deliveries:
739   Failed addresses:
740   Deferred addresses:
741 search_tidyup called
742 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
743 --------> userx@myhost.test.ex <--------
744  locking TESTSUITE/spool/db/retry.lockfile
745  locked  TESTSUITE/spool/db/retry.lockfile
746  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
747  returned from EXIM_DBOPEN: (nil)
748  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
749 no retry data available
750 search_tidyup called
751 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
752   uid=CALLER_UID gid=CALLER_GID pid=p1240
753   home=NULL current=/
754 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
755 appendfile transport entered
756  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
757  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
758    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
759  LRU list:
760  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
761    type=sqlite key="select id from them where id='userx'" opts=NULL
762  file lookup required for select id from them where id='userx'
763    in TESTSUITE/aux-fixed/sqlitedb
764  creating new cache entry
765  lookup yielded: userx
766 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
767   file=TESTSUITE/test-mail/userx format=unix
768   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
769   message_suffix=\n
770   maildir_use_size_file=no
771   locking by lockfile fcntl 
772 lock name: TESTSUITE/test-mail/userx.lock
773 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
774 lock file created
775 mailbox TESTSUITE/test-mail/userx is locked
776 writing to file TESTSUITE/test-mail/userx
777 writing data block fd=dddd size=sss timeout=0
778 cannot use sendfile for body: spoolfile not wireformat
779 writing data block fd=dddd size=sss timeout=0
780 writing data block fd=dddd size=sss timeout=0
781 appendfile yields 0 with errno=dd more_errno=dd
782 search_tidyup called
783 journalling userx@myhost.test.ex
784 t1 transport returned OK for userx@myhost.test.ex
785 post-process userx@myhost.test.ex (0)
786 userx@myhost.test.ex delivered
787 LOG: MAIN
788   => userx <userx@myhost.test.ex> R=r1 T=t1
789 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
790 changed uid/gid: post-delivery tidying
791   uid=EXIM_UID gid=EXIM_GID pid=p1239
792 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
793 Processing retry items
794 Succeeded addresses:
795  userx@myhost.test.ex: no retry items
796 Failed addresses:
797 Deferred addresses:
798 end of retry processing
799 DSN: processing router : r1
800 DSN: processing successful delivery address: userx@myhost.test.ex
801 DSN: Sender_address: CALLER@myhost.test.ex
802 DSN: orcpt: NULL  flags: 0x0
803 DSN: envid: NULL  ret: 0
804 DSN: Final recipient: userx@myhost.test.ex
805 DSN: Remote SMTP server supports DSN: 0
806 DSN: not sending DSN success message
807 LOG: MAIN
808   Completed
809 end delivery of 10HmaX-0005vi-00
810 search_tidyup called
811 search_tidyup called
812 >>>>>>>>>>>>>>>> Exim pid=p1239 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
813 search_tidyup called
814 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
815 Exim version x.yz ....
816 configuration file is TESTSUITE/test-config
817 admin user
818 dropping to exim gid; retaining priv uid
819  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
820  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
821    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
822  LRU list:
823  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
824    type=sqlite key="select name from them where id='userx';" opts=NULL
825  file lookup required for select name from them where id='userx';
826    in TESTSUITE/aux-fixed/sqlitedb
827  creating new cache entry
828  lookup yielded: Ayen Other
829 search_tidyup called
830 >>>>>>>>>>>>>>>> Exim pid=p1241 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
831 Exim version x.yz ....
832 configuration file is TESTSUITE/test-config
833 admin user
834 dropping to exim gid; retaining priv uid
835  search_open: sqlite "NULL"
836  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
837  search_find: file="NULL"
838    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
839  LRU list:
840  internal_search_find: file="NULL"
841    type=sqlite key="select name from them where id='userx';" opts=NULL
842  database lookup required for select name from them where id='userx';
843  creating new cache entry
844  lookup yielded: Ayen Other
845 search_tidyup called
846 >>>>>>>>>>>>>>>> Exim pid=p1242 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
847 Exim version x.yz ....
848 configuration file is TESTSUITE/test-config
849 admin user
850 dropping to exim gid; retaining priv uid
851  search_open: sqlite "NULL"
852  Using sqlite_dbfile: nonabsolute_filename
853 search_tidyup called
854 >>>>>>>>>>>>>>>> Exim pid=p1243 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
855 Exim version x.yz ....
856 configuration file is TESTSUITE/test-config
857 admin user
858 dropping to exim gid; retaining priv uid
859  search_open: sqlite "NULL"
860  Using sqlite_dbfile: TESTSUITE/missingfile
861  search_find: file="NULL"
862    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
863  LRU list:
864  internal_search_find: file="NULL"
865    type=sqlite key="select name from them where id='userx';" opts=NULL
866  database lookup required for select name from them where id='userx';
867  sqlite3_exec failed: no such table: them
868  creating new cache entry
869  lookup failed
870 search_tidyup called
871 >>>>>>>>>>>>>>>> Exim pid=p1244 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>