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