Log: more info in transport "line too long" error message. Bug 2871
[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 11 of -be stdin:
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 12 of -be stdin:
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 13 of -be stdin:
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 14 of -be stdin:
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 ACL check_recipient "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 No quoter name for addr
297 LOG: MAIN PANIC
298   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
299  required_quoter_id (pgsql) quoting -1 (NULL)
300  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
301  PGSQL using cached connection for localhost:PORT_N/test/CALLER
302  PGSQL: no data found
303  replacing old cache entry
304  lookup failed
305 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
306                  = FAIL: 
307 warn: condition test succeeded in ACL check_recipient
308 processing ACL check_recipient "warn" (TESTSUITE/test-config 32)
309  search_open: pgsql "NULL"
310    cached open
311  search_find: file="NULL"
312    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
313  LRU list:
314  internal_search_find: file="NULL"
315    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
316  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
317                               (tainted, quoted:pgsql)
318  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
319  PGSQL using cached connection for localhost:PORT_N/test/CALLER
320  PGSQL: no data found
321  replacing old cache entry
322  lookup failed
323 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
324                  = ok:   
325  search_open: pgsql "NULL"
326    cached open
327  search_find: file="NULL"
328    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
329  LRU list:
330  internal_search_find: file="NULL"
331    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
332  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
333                               (tainted, quoted:pgsql)
334  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
335  PGSQL using cached connection for localhost:PORT_N/test/CALLER
336  PGSQL: no data found
337  replacing old cache entry
338  lookup failed
339 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
340                  = ok:   
341  search_open: pgsql "NULL"
342    cached open
343  search_find: file="NULL"
344    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
345  LRU list:
346  internal_search_find: file="NULL"
347    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
348  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
349                               (tainted, quoted:pgsql)
350  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
351 LOG: MAIN
352   Exim configuration error in ACL verb at line 32 of TESTSUITE/test-config:
353   WARNING: obsolete syntax used for lookup
354  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
355 warn: condition test deferred in ACL check_recipient
356 LOG: MAIN
357   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
358 processing ACL check_recipient "warn" (TESTSUITE/test-config 41)
359 check set acl_m0 = ok:   hostlist
360 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
361 host in "net-pgsql;select * from them where id='c'"?
362  list element: net-pgsql;select░*░from░them░where░id='c'
363  search_open: pgsql "NULL"
364    cached open
365  search_find: file="NULL"
366    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
367  LRU list:
368  internal_search_find: file="NULL"
369    type=pgsql key="select * from them where id='c'" opts=NULL
370  database lookup required for select * from them where id='c'
371                               (tainted, quoted:pgsql)
372  PostgreSQL query: "select * from them where id='c'" opts 'NULL'
373  PGSQL using cached connection for localhost:PORT_N/test/CALLER
374  PGSQL: no data found
375  creating new cache entry
376  lookup failed
377 host in "net-pgsql;select * from them where id='c'"? no (end of list)
378 warn: condition test failed in ACL check_recipient
379 processing ACL check_recipient "warn" (TESTSUITE/test-config 44)
380 check set acl_m0 = FAIL: hostlist
381 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
382 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
383  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
384  search_open: pgsql "NULL"
385    cached open
386  search_find: file="NULL"
387    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
388  LRU list:
389  internal_search_find: file="NULL"
390    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
391  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
392                               (tainted, quoted:pgsql)
393  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
394 LOG: MAIN
395   Exim configuration error in ACL verb at line 44 of TESTSUITE/test-config:
396   WARNING: obsolete syntax used for lookup
397  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
398 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'
399 warn: condition test deferred in ACL check_recipient
400 LOG: MAIN
401   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
402 processing ACL check_recipient "warn" (TESTSUITE/test-config 49)
403 check set acl_m0 = FAIL: hostlist
404 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
405 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
406  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
407  search_open: pgsql "NULL"
408    cached open
409  search_find: file="NULL"
410    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
411  LRU list:
412  internal_search_find: file="NULL"
413    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
414  database lookup required for  select * from them where id='c'
415                               (tainted, quoted:pgsql)
416  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
417  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
418 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'
419 warn: condition test deferred in ACL check_recipient
420 LOG: MAIN
421   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
422 processing ACL check_recipient "accept" (TESTSUITE/test-config 52)
423 check domains = +local_domains
424 d in "+local_domains"?
425  list element: +local_domains
426   start sublist local_domains
427    d in "@"?
428    ╎list element: @
429    d in "@"? no (end of list)
430   end sublist local_domains
431 d in "+local_domains"? no (end of list)
432 accept: condition test failed in ACL check_recipient
433 processing ACL check_recipient "accept" (TESTSUITE/test-config 53)
434 check hosts = +relay_hosts
435 host in "+relay_hosts"?
436  list element: +relay_hosts
437   start sublist relay_hosts
438   host in "net-pgsql;select * from them where id='10.0.0.0'"?
439    ╎list element: net-pgsql;select░*░from░them░where░id='10.0.0.0'
440    ╎search_open: pgsql "NULL"
441    ╎  cached open
442    ╎search_find: file="NULL"
443    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
444    ╎LRU list:
445    ╎internal_search_find: file="NULL"
446    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
447    ╎database lookup required for select * from them where id='10.0.0.0'
448    ╎PostgreSQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
449    ╎PGSQL using cached connection for localhost:PORT_N/test/CALLER
450    ╎PGSQL: no data found
451    ╎creating new cache entry
452    ╎lookup failed
453   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
454   end sublist relay_hosts
455 host in "+relay_hosts"? no (end of list)
456 accept: condition test failed in ACL check_recipient
457 processing ACL check_recipient "deny" (TESTSUITE/test-config 54)
458   message: relay not permitted
459 deny: condition test succeeded in ACL check_recipient
460 end of ACL check_recipient: DENY
461 SMTP>> 550 relay not permitted
462 LOG: MAIN REJECT
463   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
464 SMTP<< rcpt to:<c@d>
465 using ACL "check_recipient"
466 processing ACL check_recipient "warn" (TESTSUITE/test-config 27)
467  search_open: pgsql "NULL"
468    cached open
469  search_find: file="NULL"
470    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
471  LRU list:
472  internal_search_find: file="NULL"
473    type=pgsql key="select name from them where id = 'c'" opts=NULL
474  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
475                               (tainted, quoted:pgsql)
476  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
477  PGSQL using cached connection for localhost:PORT_N/test/CALLER
478  PGSQL: no data found
479  replacing old cache entry
480  lookup failed
481 check set acl_m0 = ok:   ${lookup pgsql                    {select name from them where id = '${quote_pgsql:$local_part}'}}
482                  = ok:   
483  search_open: pgsql "NULL"
484    cached open
485  search_find: file="NULL"
486    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="cache=no_rd"
487  LRU list:
488  internal_search_find: file="NULL"
489    type=pgsql key="select name from them where id = 'c'" opts=NULL
490  cached data found but no_rd option set;  database lookup required for select name from them where id = 'c'
491                               (tainted)
492 No quoter name for addr
493 LOG: MAIN PANIC
494   tainted search query is not properly quoted (ACL warn, TESTSUITE/test-config 27): select name from them where id = 'c'
495  required_quoter_id (pgsql) quoting -1 (NULL)
496  PostgreSQL query: "select name from them where id = 'c'" opts 'NULL'
497  PGSQL using cached connection for localhost:PORT_N/test/CALLER
498  PGSQL: no data found
499  replacing old cache entry
500  lookup failed
501 check set acl_m0 = FAIL: ${lookup pgsql,cache=no_rd        {select name from them where id = '$local_part'}}
502                  = FAIL: 
503 warn: condition test succeeded in ACL check_recipient
504 processing ACL check_recipient "warn" (TESTSUITE/test-config 32)
505  search_open: pgsql "NULL"
506    cached open
507  search_find: file="NULL"
508    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
509  LRU list:
510  internal_search_find: file="NULL"
511    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N/test/CALLER/"
512  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
513                               (tainted, quoted:pgsql)
514  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N/test/CALLER/'
515  PGSQL using cached connection for localhost:PORT_N/test/CALLER
516  PGSQL: no data found
517  replacing old cache entry
518  lookup failed
519 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N/test/CALLER/      {select name from them where id = '${quote_pgsql:$local_part}'}}
520                  = ok:   
521  search_open: pgsql "NULL"
522    cached open
523  search_find: file="NULL"
524    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N"
525  LRU list:
526  internal_search_find: file="NULL"
527    type=pgsql key="select name from them where id = 'c'" opts="servers=localhost::PORT_N"
528  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
529                               (tainted, quoted:pgsql)
530  PostgreSQL query: "select name from them where id = 'c'" opts 'servers=localhost::PORT_N'
531  PGSQL using cached connection for localhost:PORT_N/test/CALLER
532  PGSQL: no data found
533  replacing old cache entry
534  lookup failed
535 check set acl_m0 = ok:   ${lookup pgsql,servers=localhost::PORT_N    {select name from them where id = '${quote_pgsql:$local_part}'}}
536                  = ok:   
537  search_open: pgsql "NULL"
538    cached open
539  search_find: file="NULL"
540    key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
541  LRU list:
542  internal_search_find: file="NULL"
543    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts=NULL
544  database lookup required for servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'
545                               (tainted, quoted:pgsql)
546  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select name from them where id = 'c'" opts 'NULL'
547 LOG: MAIN
548   Exim configuration error in ACL verb at line 32 of TESTSUITE/test-config:
549   WARNING: obsolete syntax used for lookup
550  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
551 warn: condition test deferred in ACL check_recipient
552 LOG: MAIN
553   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
554 processing ACL check_recipient "warn" (TESTSUITE/test-config 41)
555 check set acl_m0 = ok:   hostlist
556 check hosts = net-pgsql;select * from them where id='${quote_pgsql:$local_part}'
557 host in "net-pgsql;select * from them where id='c'"?
558  list element: net-pgsql;select░*░from░them░where░id='c'
559  search_open: pgsql "NULL"
560    cached open
561  search_find: file="NULL"
562    key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
563  LRU list:
564  internal_search_find: file="NULL"
565    type=pgsql key="select * from them where id='c'" opts=NULL
566  cached data used for lookup of select * from them where id='c'
567  lookup failed
568 host in "net-pgsql;select * from them where id='c'"? no (end of list)
569 warn: condition test failed in ACL check_recipient
570 processing ACL check_recipient "warn" (TESTSUITE/test-config 44)
571 check set acl_m0 = FAIL: hostlist
572 check hosts = <& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
573 host in "<& net-pgsql;servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
574  list element: net-pgsql;servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
575  search_open: pgsql "NULL"
576    cached open
577  search_find: file="NULL"
578    key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
579  LRU list:
580  internal_search_find: file="NULL"
581    type=pgsql key="servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts=NULL
582  database lookup required for servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'
583                               (tainted, quoted:pgsql)
584  PostgreSQL query: "servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'" opts 'NULL'
585 LOG: MAIN
586   Exim configuration error in ACL verb at line 44 of TESTSUITE/test-config:
587   WARNING: obsolete syntax used for lookup
588  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
589 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'
590 warn: condition test deferred in ACL check_recipient
591 LOG: MAIN
592   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
593 processing ACL check_recipient "warn" (TESTSUITE/test-config 49)
594 check set acl_m0 = FAIL: hostlist
595 check hosts = <& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='${quote_pgsql:$local_part}'
596 host in "<& net-pgsql,servers=localhost::PORT_N/test/CALLER/; select * from them where id='c'"?
597  list element: net-pgsql,servers=localhost::PORT_N/test/CALLER/;░select░*░from░them░where░id='c'
598  search_open: pgsql "NULL"
599    cached open
600  search_find: file="NULL"
601    key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=localhost::PORT_N/test/CALLER/"
602  LRU list:
603  internal_search_find: file="NULL"
604    type=pgsql key=" select * from them where id='c'" opts="servers=localhost::PORT_N/test/CALLER/"
605  database lookup required for  select * from them where id='c'
606                               (tainted, quoted:pgsql)
607  PostgreSQL query: " select * from them where id='c'" opts 'servers=localhost::PORT_N/test/CALLER/'
608  lookup deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
609 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'
610 warn: condition test deferred in ACL check_recipient
611 LOG: MAIN
612   H=(test) [10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: PostgreSQL server "localhost:PORT_N/test" is tainted
613 processing ACL check_recipient "accept" (TESTSUITE/test-config 52)
614 check domains = +local_domains
615 d in "+local_domains"?
616  list element: +local_domains
617   start sublist local_domains
618    d in "@"?
619    ╎list element: @
620    d in "@"? no (end of list)
621   end sublist local_domains
622 d in "+local_domains"? no (end of list)
623 accept: condition test failed in ACL check_recipient
624 processing ACL check_recipient "accept" (TESTSUITE/test-config 53)
625 check hosts = +relay_hosts
626 host in "+relay_hosts"?
627  list element: +relay_hosts
628   start sublist relay_hosts
629   host in "net-pgsql;select * from them where id='10.0.0.0'"?
630    ╎list element: net-pgsql;select░*░from░them░where░id='10.0.0.0'
631    ╎search_open: pgsql "NULL"
632    ╎  cached open
633    ╎search_find: file="NULL"
634    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
635    ╎LRU list:
636    ╎internal_search_find: file="NULL"
637    ╎  type=pgsql key="select * from them where id='10.0.0.0'" opts=NULL
638    ╎cached data used for lookup of select * from them where id='10.0.0.0'
639    ╎lookup failed
640   host in "net-pgsql;select * from them where id='10.0.0.0'"? no (end of list)
641   end sublist relay_hosts
642 host in "+relay_hosts"? no (end of list)
643 accept: condition test failed in ACL check_recipient
644 processing ACL check_recipient "deny" (TESTSUITE/test-config 54)
645   message: relay not permitted
646 deny: condition test succeeded in ACL check_recipient
647 end of ACL check_recipient: DENY
648 SMTP>> 550 relay not permitted
649 LOG: MAIN REJECT
650   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
651 SMTP<< quit
652 SMTP>> 221 myhost.test.ex closing connection
653 LOG: smtp_connection MAIN
654   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
655 search_tidyup called
656 close PGSQL connection: localhost:PORT_N/test/CALLER
657 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
658 Exim version x.yz ....
659 Hints DB:
660 changed uid/gid: forcing real = effective
661   uid=uuuu gid=CALLER_GID pid=p1237
662 configuration file is TESTSUITE/test-config
663 admin user
664 changed uid/gid: privilege not needed
665   uid=EXIM_UID gid=EXIM_GID pid=p1237
666 seeking password data for user "CALLER": cache not available
667 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
668 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
669 sender address = CALLER@myhost.test.ex
670 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
671 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
672 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
673 Sender: CALLER@myhost.test.ex
674 Recipients:
675   CALLER
676 search_tidyup called
677 >>Headers received:
678
679 qualify & rewrite recipients list
680 rewrite rules on sender address
681 qualify and rewrite headers
682  rewrite_one_header: type=F:
683    From: CALLER_NAME <CALLER@myhost.test.ex>
684 search_tidyup called
685 >>Headers after rewriting and local additions:
686  I Message-Id: <E10HmaX-000000005vi-0000@myhost.test.ex>
687  F From: CALLER_NAME <CALLER@myhost.test.ex>
688    Date: Tue, 2 Mar 1999 09:44:33 +0000
689
690 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
691 Data file written for message 10HmaX-000000005vi-0000
692 >>Generated Received: header line
693 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
694         (envelope-from <CALLER@myhost.test.ex>)
695         id 10HmaX-000000005vi-0000
696         for CALLER@myhost.test.ex;
697         Tue, 2 Mar 1999 09:44:33 +0000
698 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
699 DSN: **** SPOOL_OUT - address: <CALLER@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
700 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
701 Size of headers = sss
702 LOG: MAIN
703   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
704 created log directory TESTSUITE/spool/log
705 search_tidyup called
706 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
707 Exim version x.yz ....
708 Hints DB:
709 changed uid/gid: forcing real = effective
710   uid=uuuu gid=EXIM_GID pid=p1238
711 configuration file is TESTSUITE/test-config
712 trusted user
713 admin user
714 dropping to exim gid; retaining priv uid
715 seeking password data for user "CALLER": cache not available
716 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
717 set_process_info: pppp delivering specified messages
718 set_process_info: pppp delivering 10HmaX-000000005vi-0000
719 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
720 reading spool file 10HmaX-000000005vi-0000-H
721 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
722 sender_local=1 ident=CALLER
723 Non-recipients:
724  Empty Tree
725 ---- End of tree ----
726 recipients_count=1
727 **** SPOOL_IN - No additional fields
728 body_linecount=1 message_linecount=8
729 DSN: set orcpt:   flags: 0x0
730 Delivery address list:
731   CALLER@myhost.test.ex 
732 no retry data available
733 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
734 Considering: CALLER@myhost.test.ex
735 unique = CALLER@myhost.test.ex
736 checking router retry status
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 no retry data available
779 search_tidyup called
780 changed uid/gid: local delivery to CALLER <CALLER@myhost.test.ex> transport=t1
781   uid=CALLER_UID gid=CALLER_GID pid=p1239
782   home=NULL current=/
783 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to CALLER using t1
784 appendfile transport entered
785  search_open: pgsql "NULL"
786  search_find: file="NULL"
787    key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
788  LRU list:
789  internal_search_find: file="NULL"
790    type=pgsql key="select id from them where id='ph10'" opts=NULL
791  database lookup required for select id from them where id='ph10'
792  PostgreSQL query: "select id from them where id='ph10'" opts 'NULL'
793  PGSQL new connection: host=localhost port=PORT_N database=test user=CALLER
794  creating new cache entry
795  lookup yielded: ph10
796 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
797   file=TESTSUITE/test-mail/ph10 format=unix
798   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
799   message_suffix=\n
800   maildir_use_size_file=no
801   locking by lockfile fcntl 
802 lock name: TESTSUITE/test-mail/ph10.lock
803 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
804 lock file created
805 mailbox TESTSUITE/test-mail/ph10 is locked
806 writing to file TESTSUITE/test-mail/ph10
807 writing data block fd=dddd size=sss timeout=0
808 cannot use sendfile for body: spoolfile not wireformat
809 writing data block fd=dddd size=sss timeout=0
810 writing data block fd=dddd size=sss timeout=0
811 appendfile yields 0 with errno=dd more_errno=dd
812 search_tidyup called
813 close PGSQL connection: localhost:PORT_N/test/CALLER
814 >>>>>>>>>>>>>>>> Exim pid=p1239 (delivery-local) terminating with rc=0 >>>>>>>>>>>>>>>>
815 journalling CALLER@myhost.test.ex
816 t1 transport returned OK for CALLER@myhost.test.ex
817 post-process CALLER@myhost.test.ex (0)
818 CALLER@myhost.test.ex delivered
819 LOG: MAIN
820   => CALLER <CALLER@myhost.test.ex> R=r1 T=t1
821 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
822 changed uid/gid: post-delivery tidying
823   uid=EXIM_UID gid=EXIM_GID pid=p1238
824 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
825 Processing retry items
826  Succeeded addresses:
827   CALLER@myhost.test.ex: no retry items
828  Failed addresses:
829  Deferred addresses:
830 end of retry processing
831 DSN: processing router : r1
832 DSN: processing successful delivery address: CALLER@myhost.test.ex
833 DSN: Sender_address: CALLER@myhost.test.ex
834 DSN: orcpt: NULL  flags: 0x0
835 DSN: envid: NULL  ret: 0
836 DSN: Final recipient: CALLER@myhost.test.ex
837 DSN: Remote SMTP server supports DSN: 0
838 DSN: not sending DSN success message
839 LOG: MAIN
840   Completed
841 end delivery of 10HmaX-000000005vi-0000
842 search_tidyup called
843 search_tidyup called
844 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
845 search_tidyup called
846 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
847 Exim version x.yz ....
848 Hints DB:
849 configuration file is TESTSUITE/test-config
850 admin user
851 dropping to exim gid; retaining priv uid
852  search_open: pgsql "NULL"
853  search_find: file="NULL"
854    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
855  LRU list:
856  internal_search_find: file="NULL"
857    type=pgsql key="select name from them where id='ph10';" opts=NULL
858  database lookup required for select name from them where id='ph10';
859  PostgreSQL query: "select name from them where id='ph10';" opts 'NULL'
860  PGSQL new connection: socket=TESTSUITE/pgsql/.s.PGSQL.1223 database=test user=CALLER
861  creating new cache entry
862  lookup yielded: Philip░Hazel
863 search_tidyup called
864 close PGSQL connection: (TESTSUITE/pgsql/.s.PGSQL.1223)/test/CALLER
865 >>>>>>>>>>>>>>>> Exim pid=p1240 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
866
867 ******** SERVER ********
868 2017-07-30 18:51:05.712 [pppp] LOG:  redirecting log output to logging collector process
869 2017-07-30 18:51:05.712 [pppp] HINT:  Future log output will appear in directory "log".