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