Merge branch 'master' into 4.next
[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 Hints DB:
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=PORT_N 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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/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:PORT_N/test/CALLER
101  creating new cache entry
102  lookup yielded: name="before␍↩
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:PORT_N/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:PORT_N/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 LOG: MAIN
138   Exim configuration error in line 78 of TESTSUITE/test-config:
139   WARNING: obsolete syntax used for lookup
140  lookup deferred: PostgreSQL server "x" not found in pgsql_servers
141  search_open: pgsql "NULL"
142    cached open
143  search_find: file="NULL"
144    key="servers=localhost::PORT_N:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
145  LRU list:
146  internal_search_find: file="NULL"
147    type=pgsql key="servers=localhost::PORT_N:x; select name from them where id='ph10';" opts=NULL
148  database lookup required for servers=localhost::PORT_N:x; select name from them where id='ph10';
149  PostgreSQL query: "servers=localhost::PORT_N:x; select name from them where id='ph10';" opts 'NULL'
150 LOG: MAIN
151   Exim configuration error in line 78 of TESTSUITE/test-config:
152   WARNING: obsolete syntax used for lookup
153  PGSQL using cached connection for localhost:PORT_N/test/CALLER
154  creating new cache entry
155  lookup yielded: Philip░Hazel
156  search_open: pgsql "NULL"
157    cached open
158  search_find: file="NULL"
159    key="servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
160  LRU list:
161  internal_search_find: file="NULL"
162    type=pgsql key="servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';" opts=NULL
163  database lookup required for servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';
164  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/:x; select name from them where id='ph10';" opts 'NULL'
165 LOG: MAIN
166   Exim configuration error in line 78 of TESTSUITE/test-config:
167   WARNING: obsolete syntax used for lookup
168  PGSQL using cached connection for localhost:PORT_N/test/CALLER
169  creating new cache entry
170  lookup yielded: Philip░Hazel
171  search_open: pgsql "NULL"
172    cached open
173  search_find: file="NULL"
174    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
175  LRU list:
176  internal_search_find: file="NULL"
177    type=pgsql key="servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" opts=NULL
178  database lookup required for servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';
179  PostgreSQL query: "servers=(TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER/:x; select name from them where id='ph10';" opts 'NULL'
180 LOG: MAIN
181   Exim configuration error in line 78 of TESTSUITE/test-config:
182   WARNING: obsolete syntax used for lookup
183  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
184  creating new cache entry
185  lookup yielded: Philip░Hazel
186  search_open: pgsql "NULL"
187    cached open
188  search_find: file="NULL"
189    key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
190  LRU list:
191  internal_search_find: file="NULL"
192    type=pgsql key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
193  database lookup required for SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
194  PostgreSQL query: "SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
195  PGSQL using cached connection for localhost:PORT_N/test/CALLER
196  creating new cache entry
197  lookup yielded: Philip░Hazel↩
198  Aristotle
199  search_open: pgsql "NULL"
200    cached open
201  search_find: file="NULL"
202    key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
203  LRU list:
204  internal_search_find: file="NULL"
205    type=pgsql key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
206  database lookup required for SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');
207  PostgreSQL query: "SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
208  PGSQL using cached connection for localhost:PORT_N/test/CALLER
209  creating new cache entry
210  lookup yielded: name="Philip░Hazel"░id=ph10░↩
211  name=Aristotle░id=aaaa░
212  search_open: pgsql "NULL"
213    cached open
214  search_find: file="NULL"
215    key="delete from them where id='aaaa'" partial=-1 affix=NULL starflags=0 opts=NULL
216  LRU list:
217  internal_search_find: file="NULL"
218    type=pgsql key="delete from them where id='aaaa'" opts=NULL
219  database lookup required for delete from them where id='aaaa'
220  PostgreSQL query: "delete from them where id='aaaa'" opts 'NULL'
221  PGSQL using cached connection for localhost:PORT_N/test/CALLER
222  PGSQL: command does not return any data but was successful. Rows affected: 1
223  lookup forced cache cleanup
224  lookup yielded: 1
225 search_tidyup called
226 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
227 close PGSQL connection: localhost:PORT_N/test/CALLER
228 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
229 Exim version x.yz ....
230 Hints DB:
231 changed uid/gid: forcing real = effective
232   uid=uuuu gid=CALLER_GID pid=p1236
233 configuration file is TESTSUITE/test-config
234 admin user
235 changed uid/gid: privilege not needed
236   uid=EXIM_UID gid=EXIM_GID pid=p1236
237 seeking password data for user "CALLER": cache not available
238 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
239 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
240 sender address = CALLER@myhost.test.ex
241 sender_fullhost = [10.0.0.0]
242 sender_rcvhost = [10.0.0.0]
243 host in hosts_connection_nolog? no (option unset)
244 LOG: smtp_connection MAIN
245   SMTP connection from [10.0.0.0]
246 host in host_lookup? no (option unset)
247 set_process_info: pppp handling incoming connection from [10.0.0.0]
248 host in host_reject_connection? no (option unset)
249 host in sender_unqualified_hosts? no (option unset)
250 host in recipient_unqualified_hosts? no (option unset)
251 host in helo_verify_hosts? no (option unset)
252 host in helo_try_verify_hosts? no (option unset)
253 host in helo_accept_junk_hosts? no (option unset)
254 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
255 smtp_setup_msg entered
256 SMTP<< helo test
257 test in helo_lookup_domains?
258  list element: @
259  list element: @[]
260 test in helo_lookup_domains? no (end of list)
261 sender_fullhost = (test) [10.0.0.0]
262 sender_rcvhost = [10.0.0.0] (helo=test)
263 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
264 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
265 SMTP<< mail from:<a@b>
266 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
267 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
268 SMTP>> 250 OK
269 SMTP<< rcpt to:<c@d>
270 using ACL "check_recipient"
271 processing "warn" (TESTSUITE/test-config 27)
272  search_open: pgsql "NULL"
273  search_find: file="NULL"
274    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
275  LRU list:
276  internal_search_find: file="NULL"
277    type=pgsql key="select name from them where id = 'c'" opts=NULL
278  database lookup required for select name from them where id = 'c'
279                               (tainted, quoted:pgsql)
280  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
281  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
282  PGSQL: no data found
283  creating new cache entry
284  lookup failed
285 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '${quote_pgsql:$local_part}'}}
286                  = ok:   
287  search_open: pgsql "NULL"
288    cached open
289  search_find: file="NULL"
290    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="cache=no_rd"
291  LRU list:
292  internal_search_find: file="NULL"
293    type=pgsql key="select name from them where id = 'c'" opts=NULL
294  cached data found but no_rd option set;  database lookup required for select name from them where id = 'c'
295                               (tainted)
296 LOG: MAIN PANIC
297   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
298  search_type NN (pgsql) quoting -1 (none)
299  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
300  PGSQL using cached connection for localhost:PORT_N/test/CALLER
301  PGSQL: no data found
302  replacing old cache entry
303  lookup failed
304 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
305                  = FAIL: 
306 warn: condition test succeeded in ACL "check_recipient"
307 processing "warn" (TESTSUITE/test-config 32)
308  search_open: pgsql "NULL"
309    cached open
310  search_find: file="NULL"
311    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
312  LRU list:
313  internal_search_find: file="NULL"
314    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
315  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
316                               (tainted, quoted:pgsql)
317  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
318  PGSQL using cached connection for localhost:PORT_N/test/CALLER
319  PGSQL: no data found
320  replacing old cache entry
321  lookup failed
322 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
323                  = ok:   
324  search_open: pgsql "NULL"
325    cached open
326  search_find: file="NULL"
327    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
328  LRU list:
329  internal_search_find: file="NULL"
330    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
331  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
332                               (tainted, quoted:pgsql)
333  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
334  PGSQL using cached connection for localhost:PORT_N/test/CALLER
335  PGSQL: no data found
336  replacing old cache entry
337  lookup failed
338 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
339                  = ok:   
340  search_open: pgsql "NULL"
341    cached open
342  search_find: file="NULL"
343    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
344  LRU list:
345  internal_search_find: file="NULL"
346    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
347  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
348                               (tainted, quoted:pgsql)
349  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
350 LOG: MAIN
351   Exim configuration error in line 78 of TESTSUITE/test-config:
352   WARNING: obsolete syntax used for lookup
353  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
354 warn: condition test deferred in ACL "check_recipient"
355 LOG: MAIN
356   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
357 processing "warn" (TESTSUITE/test-config 41)
358 check set acl_m0 = ok:   hostlist
359 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
360 host in "net-pgsql;select * from them where id='c'"?
361  list element: net-pgsql;select░*░from░them░where░id='c'
362  search_open: pgsql "NULL"
363    cached open
364  search_find: file="NULL"
365    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
366  LRU list:
367  internal_search_find: file="NULL"
368    type=pgsql key="select * from them where id='c'" opts=NULL
369  database lookup required for select * from them where id='c'
370                               (tainted, quoted:pgsql)
371  PostgreSQL query: "select * from them where id='c'" opts 'NULL'
372  PGSQL using cached connection for localhost:PORT_N/test/CALLER
373  PGSQL: no data found
374  creating new cache entry
375  lookup failed
376 host in "net-pgsql;select * from them where id='c'"? no (end of list)
377 warn: condition test failed in ACL "check_recipient"
378 processing "warn" (TESTSUITE/test-config 44)
379 check set acl_m0 = FAIL: hostlist
380 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
381 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
382  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
383  search_open: pgsql "NULL"
384    cached open
385  search_find: file="NULL"
386    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
387  LRU list:
388  internal_search_find: file="NULL"
389    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
390  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
391                               (tainted, quoted:pgsql)
392  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
393 LOG: MAIN
394   Exim configuration error in line 78 of TESTSUITE/test-config:
395   WARNING: obsolete syntax used for lookup
396  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
397 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'
398 warn: condition test deferred in ACL "check_recipient"
399 LOG: MAIN
400   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
401 processing "warn" (TESTSUITE/test-config 49)
402 check set acl_m0 = FAIL: hostlist
403 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
404 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
405  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
406  search_open: pgsql "NULL"
407    cached open
408  search_find: file="NULL"
409    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
410  LRU list:
411  internal_search_find: file="NULL"
412    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
413  database lookup required for  select * from them where id='c'
414                               (tainted, quoted:pgsql)
415  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
416  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
417 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'
418 warn: condition test deferred in ACL "check_recipient"
419 LOG: MAIN
420   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
421 processing "accept" (TESTSUITE/test-config 52)
422 check domains = +local_domains
423 d in "+local_domains"?
424  list element: +local_domains
425   start sublist local_domains
426    d in "@"?
427    ╎list element: @
428    d in "@"? no (end of list)
429   end sublist local_domains
430 d in "+local_domains"? no (end of list)
431 accept: condition test failed in ACL "check_recipient"
432 processing "accept" (TESTSUITE/test-config 53)
433 check hosts = +relay_hosts
434 host in "+relay_hosts"?
435  list element: +relay_hosts
436   start sublist relay_hosts
437   host in "net-pgsql;select * from them where id='10.0.0.0'"?
438    ╎list element: net-pgsql;select░*░from░them░where░id='10.0.0.0'
439    ╎search_open: pgsql "NULL"
440    ╎  cached open
441    ╎search_find: file="NULL"
442    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
443    ╎LRU list:
444    ╎internal_search_find: file="NULL"
445    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
446    ╎database lookup required for select * from them where id='10.0.0.0'
447    ╎PostgreSQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
448    ╎PGSQL using cached connection for localhost:PORT_N/test/CALLER
449    ╎PGSQL: no data found
450    ╎creating new cache entry
451    ╎lookup failed
452   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
453   end sublist relay_hosts
454 host in "+relay_hosts"? no (end of list)
455 accept: condition test failed in ACL "check_recipient"
456 processing "deny" (TESTSUITE/test-config 54)
457   message: relay not permitted
458 deny: condition test succeeded in ACL "check_recipient"
459 end of ACL "check_recipient": DENY
460 SMTP>> 550 relay not permitted
461 LOG: MAIN REJECT
462   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
463 SMTP<< rcpt to:<c@d>
464 using ACL "check_recipient"
465 processing "warn" (TESTSUITE/test-config 27)
466  search_open: pgsql "NULL"
467    cached open
468  search_find: file="NULL"
469    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
470  LRU list:
471  internal_search_find: file="NULL"
472    type=pgsql key="select name from them where id = 'c'" opts=NULL
473  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
474                               (tainted, quoted:pgsql)
475  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
476  PGSQL using cached connection for localhost:PORT_N/test/CALLER
477  PGSQL: no data found
478  replacing old cache entry
479  lookup failed
480 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '${quote_pgsql:$local_part}'}}
481                  = ok:   
482  search_open: pgsql "NULL"
483    cached open
484  search_find: file="NULL"
485    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="cache=no_rd"
486  LRU list:
487  internal_search_find: file="NULL"
488    type=pgsql key="select name from them where id = 'c'" opts=NULL
489  cached data found but no_rd option set;  database lookup required for select name from them where id = 'c'
490                               (tainted)
491 LOG: MAIN PANIC
492   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
493  search_type NN (pgsql) quoting -1 (none)
494  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
495  PGSQL using cached connection for localhost:PORT_N/test/CALLER
496  PGSQL: no data found
497  replacing old cache entry
498  lookup failed
499 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
500                  = FAIL: 
501 warn: condition test succeeded in ACL "check_recipient"
502 processing "warn" (TESTSUITE/test-config 32)
503  search_open: pgsql "NULL"
504    cached open
505  search_find: file="NULL"
506    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
507  LRU list:
508  internal_search_find: file="NULL"
509    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
510  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
511                               (tainted, quoted:pgsql)
512  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
513  PGSQL using cached connection for localhost:PORT_N/test/CALLER
514  PGSQL: no data found
515  replacing old cache entry
516  lookup failed
517 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
518                  = ok:   
519  search_open: pgsql "NULL"
520    cached open
521  search_find: file="NULL"
522    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
523  LRU list:
524  internal_search_find: file="NULL"
525    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
526  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
527                               (tainted, quoted:pgsql)
528  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
529  PGSQL using cached connection for localhost:PORT_N/test/CALLER
530  PGSQL: no data found
531  replacing old cache entry
532  lookup failed
533 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
534                  = ok:   
535  search_open: pgsql "NULL"
536    cached open
537  search_find: file="NULL"
538    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
539  LRU list:
540  internal_search_find: file="NULL"
541    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
542  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
543                               (tainted, quoted:pgsql)
544  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
545 LOG: MAIN
546   Exim configuration error in line 78 of TESTSUITE/test-config:
547   WARNING: obsolete syntax used for lookup
548  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
549 warn: condition test deferred in ACL "check_recipient"
550 LOG: MAIN
551   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
552 processing "warn" (TESTSUITE/test-config 41)
553 check set acl_m0 = ok:   hostlist
554 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
555 host in "net-pgsql;select * from them where id='c'"?
556  list element: net-pgsql;select░*░from░them░where░id='c'
557  search_open: pgsql "NULL"
558    cached open
559  search_find: file="NULL"
560    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
561  LRU list:
562  internal_search_find: file="NULL"
563    type=pgsql key="select * from them where id='c'" opts=NULL
564  cached data used for lookup of select * from them where id='c'
565  lookup failed
566 host in "net-pgsql;select * from them where id='c'"? no (end of list)
567 warn: condition test failed in ACL "check_recipient"
568 processing "warn" (TESTSUITE/test-config 44)
569 check set acl_m0 = FAIL: hostlist
570 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
571 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
572  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
573  search_open: pgsql "NULL"
574    cached open
575  search_find: file="NULL"
576    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
577  LRU list:
578  internal_search_find: file="NULL"
579    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
580  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
581                               (tainted, quoted:pgsql)
582  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
583 LOG: MAIN
584   Exim configuration error in line 78 of TESTSUITE/test-config:
585   WARNING: obsolete syntax used for lookup
586  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
587 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'
588 warn: condition test deferred in ACL "check_recipient"
589 LOG: MAIN
590   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
591 processing "warn" (TESTSUITE/test-config 49)
592 check set acl_m0 = FAIL: hostlist
593 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
594 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
595  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
596  search_open: pgsql "NULL"
597    cached open
598  search_find: file="NULL"
599    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
600  LRU list:
601  internal_search_find: file="NULL"
602    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
603  database lookup required for  select * from them where id='c'
604                               (tainted, quoted:pgsql)
605  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
606  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
607 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'
608 warn: condition test deferred in ACL "check_recipient"
609 LOG: MAIN
610   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
611 processing "accept" (TESTSUITE/test-config 52)
612 check domains = +local_domains
613 d in "+local_domains"?
614  list element: +local_domains
615   start sublist local_domains
616    d in "@"?
617    ╎list element: @
618    d in "@"? no (end of list)
619   end sublist local_domains
620 d in "+local_domains"? no (end of list)
621 accept: condition test failed in ACL "check_recipient"
622 processing "accept" (TESTSUITE/test-config 53)
623 check hosts = +relay_hosts
624 host in "+relay_hosts"?
625  list element: +relay_hosts
626   start sublist relay_hosts
627   host in "net-pgsql;select * from them where id='10.0.0.0'"?
628    ╎list element: net-pgsql;select░*░from░them░where░id='10.0.0.0'
629    ╎search_open: pgsql "NULL"
630    ╎  cached open
631    ╎search_find: file="NULL"
632    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
633    ╎LRU list:
634    ╎internal_search_find: file="NULL"
635    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
636    ╎cached data used for lookup of select * from them where id='10.0.0.0'
637    ╎lookup failed
638   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
639   end sublist relay_hosts
640 host in "+relay_hosts"? no (end of list)
641 accept: condition test failed in ACL "check_recipient"
642 processing "deny" (TESTSUITE/test-config 54)
643   message: relay not permitted
644 deny: condition test succeeded in ACL "check_recipient"
645 end of ACL "check_recipient": DENY
646 SMTP>> 550 relay not permitted
647 LOG: MAIN REJECT
648   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
649 SMTP<< quit
650 SMTP>> 221 myhost.test.ex closing connection
651 LOG: smtp_connection MAIN
652   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
653 search_tidyup called
654 close PGSQL connection: localhost:PORT_N/test/CALLER
655 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
656 Exim version x.yz ....
657 Hints DB:
658 changed uid/gid: forcing real = effective
659   uid=uuuu gid=CALLER_GID pid=p1237
660 configuration file is TESTSUITE/test-config
661 admin user
662 changed uid/gid: privilege not needed
663   uid=EXIM_UID gid=EXIM_GID pid=p1237
664 seeking password data for user "CALLER": cache not available
665 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
666 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
667 sender address = CALLER@myhost.test.ex
668 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
669 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
670 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
671 Sender: CALLER@myhost.test.ex
672 Recipients:
673   CALLER
674 search_tidyup called
675 >>Headers received:
676
677 qualify & rewrite recipients list
678 rewrite rules on sender address
679 qualify and rewrite headers
680  rewrite_one_header: type=F:
681    From: CALLER_NAME <CALLER@myhost.test.ex>
682 search_tidyup called
683 >>Headers after rewriting and local additions:
684  I Message-Id: <E10HmaX-000000005vi-0000@myhost.test.ex>
685  F From: CALLER_NAME <CALLER@myhost.test.ex>
686    Date: Tue, 2 Mar 1999 09:44:33 +0000
687
688 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
689 Data file written for message 10HmaX-000000005vi-0000
690 >>Generated Received: header line
691 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
692         (envelope-from <CALLER@myhost.test.ex>)
693         id 10HmaX-000000005vi-0000
694         for CALLER@myhost.test.ex;
695         Tue, 2 Mar 1999 09:44:33 +0000
696 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
697 DSN: **** SPOOL_OUT - address: <CALLER@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
698 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
699 Size of headers = sss
700 LOG: MAIN
701   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
702 created log directory TESTSUITE/spool/log
703 search_tidyup called
704 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
705 Exim version x.yz ....
706 Hints DB:
707 changed uid/gid: forcing real = effective
708   uid=uuuu gid=EXIM_GID pid=p1238
709 configuration file is TESTSUITE/test-config
710 trusted user
711 admin user
712 dropping to exim gid; retaining priv uid
713 seeking password data for user "CALLER": cache not available
714 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
715 set_process_info: pppp delivering specified messages
716 set_process_info: pppp delivering 10HmaX-000000005vi-0000
717 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
718 reading spool file 10HmaX-000000005vi-0000-H
719 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
720 sender_local=1 ident=CALLER
721 Non-recipients:
722  Empty Tree
723 ---- End of tree ----
724 recipients_count=1
725 **** SPOOL_IN - No additional fields
726 body_linecount=1 message_linecount=8
727 DSN: set orcpt:   flags: 0x0
728 Delivery address list:
729   CALLER@myhost.test.ex 
730  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
731  returned from EXIM_DBOPEN: (nil)
732  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
733 no retry data available
734 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
735 Considering: CALLER@myhost.test.ex
736 unique = CALLER@myhost.test.ex
737 no   domain  retry record
738 no   address retry record
739 CALLER@myhost.test.ex: queued for routing
740 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
741 routing CALLER@myhost.test.ex
742 --------> r1 router <--------
743 local_part=CALLER domain=myhost.test.ex
744 processing address_data
745  search_open: pgsql "NULL"
746  search_find: file="NULL"
747    key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
748  LRU list:
749  internal_search_find: file="NULL"
750    type=pgsql key="select name from them where id='ph10'" opts=NULL
751  database lookup required for select name from them where id='ph10'
752  PostgreSQL query: "select name from them where id='ph10'" opts 'NULL'
753  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
754  creating new cache entry
755  lookup yielded: Philip░Hazel
756 calling r1 router
757 r1 router called for CALLER@myhost.test.ex
758   domain = myhost.test.ex
759 set transport t1
760 queued for t1 transport: local_part = CALLER
761 domain = myhost.test.ex
762   errors_to=NULL
763   domain_data=NULL local_part_data=NULL
764 routed by r1 router
765   envelope to: CALLER@myhost.test.ex
766   transport: t1
767 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
768 After routing:
769   Local deliveries:
770     CALLER@myhost.test.ex
771   Remote deliveries:
772   Failed addresses:
773   Deferred addresses:
774 search_tidyup called
775 close PGSQL connection: localhost:PORT_N/test/CALLER
776 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
777 --------> CALLER@myhost.test.ex <--------
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 Hints DB:
851 configuration file is TESTSUITE/test-config
852 admin user
853 dropping to exim gid; retaining priv uid
854  search_open: pgsql "NULL"
855  search_find: file="NULL"
856    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
857  LRU list:
858  internal_search_find: file="NULL"
859    type=pgsql key="select name from them where id='ph10';" opts=NULL
860  database lookup required for select name from them where id='ph10';
861  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
862  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
863  creating new cache entry
864  lookup yielded: Philip░Hazel
865 search_tidyup called
866 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
867 >>>>>>>>>>>>>>>> Exim pid=p1240 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
868
869 ******** SERVER ********
870 2017-07-30 18:51:05.712 [pppp] LOG:  redirecting log output to logging collector process
871 2017-07-30 18:51:05.712 [pppp] HINT:  Future log output will appear in directory "log".