Lookups: cache=no_rd option. Bug 1751
[exim.git] / test / stderr / 2610
1 Exim version x.yz ....
2 configuration file is TESTSUITE/test-config
3 admin user
4 dropping to exim gid; retaining priv uid
5  search_open: mysql "NULL"
6  search_find: file="NULL"
7    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
8  LRU list:
9  internal_search_find: file="NULL"
10    type=mysql key="select name from them where id='ph10';" opts=NULL
11  database lookup required for select name from them where id='ph10';
12  MySQL query: "select name from them where id='ph10';" opts 'NULL'
13  MYSQL new connection: host=127.0.0.1 port=1223 socket=NULL database=test user=root
14  creating new cache entry
15  lookup yielded: Philip Hazel
16  search_open: mysql "NULL"
17    cached open
18  search_find: file="NULL"
19    key="select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
20  LRU list:
21  internal_search_find: file="NULL"
22    type=mysql key="select name from them where id='ph10';" opts=NULL
23  cached data used for lookup of select name from them where id='ph10';
24  lookup yielded: Philip Hazel
25  search_open: mysql "NULL"
26    cached open
27  search_find: file="NULL"
28    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts=NULL
29  LRU list:
30  internal_search_find: file="NULL"
31    type=mysql key="select name from them where id='xxxx';" opts=NULL
32  database lookup required for select name from them where id='xxxx';
33  MySQL query: "select name from them where id='xxxx';" opts 'NULL'
34  MYSQL using cached connection for 127.0.0.1:1223/test/root
35  MYSQL: no data found
36  creating new cache entry
37  lookup failed
38  search_open: mysql "NULL"
39    cached open
40  search_find: file="NULL"
41    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
42  LRU list:
43  internal_search_find: file="NULL"
44    type=mysql key="select name from them where id='nothing';" opts=NULL
45  database lookup required for select name from them where id='nothing';
46  MySQL query: "select name from them where id='nothing';" opts 'NULL'
47  MYSQL using cached connection for 127.0.0.1:1223/test/root
48  creating new cache entry
49  lookup yielded: 
50  search_open: mysql "NULL"
51    cached open
52  search_find: file="NULL"
53    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts=NULL
54  LRU list:
55  internal_search_find: file="NULL"
56    type=mysql key="select id,name from them where id='nothing';" opts=NULL
57  database lookup required for select id,name from them where id='nothing';
58  MySQL query: "select id,name from them where id='nothing';" opts 'NULL'
59  MYSQL using cached connection for 127.0.0.1:1223/test/root
60  creating new cache entry
61  lookup yielded: id=nothing name="" 
62  search_open: mysql "NULL"
63    cached open
64  search_find: file="NULL"
65    key="delete from them where id='nonexist';" partial=-1 affix=NULL starflags=0 opts=NULL
66  LRU list:
67  internal_search_find: file="NULL"
68    type=mysql key="delete from them where id='nonexist';" opts=NULL
69  database lookup required for delete from them where id='nonexist';
70  MySQL query: "delete from them where id='nonexist';" opts 'NULL'
71  MYSQL using cached connection for 127.0.0.1:1223/test/root
72  MYSQL: query was not one that returns data
73  lookup forced cache cleanup
74  lookup yielded: 0
75  search_open: mysql "NULL"
76    cached open
77  search_find: file="NULL"
78    key="select * from them where id='quote';" partial=-1 affix=NULL starflags=0 opts=NULL
79  LRU list:
80  internal_search_find: file="NULL"
81    type=mysql key="select * from them where id='quote';" opts=NULL
82  database lookup required for select * from them where id='quote';
83  MySQL query: "select * from them where id='quote';" opts 'NULL'
84  MYSQL using cached connection for 127.0.0.1:1223/test/root
85  MYSQL: no data found
86  creating new cache entry
87  lookup failed
88  search_open: mysql "NULL"
89    cached open
90  search_find: file="NULL"
91    key="select * from them where id='filter';" partial=-1 affix=NULL starflags=0 opts=NULL
92  LRU list:
93  internal_search_find: file="NULL"
94    type=mysql key="select * from them where id='filter';" opts=NULL
95  database lookup required for select * from them where id='filter';
96  MySQL query: "select * from them where id='filter';" opts 'NULL'
97  MYSQL using cached connection for 127.0.0.1:1223/test/root
98  MYSQL: no data found
99  creating new cache entry
100  lookup failed
101  search_open: mysql "NULL"
102    cached open
103  search_find: file="NULL"
104    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts=NULL
105  LRU list:
106  internal_search_find: file="NULL"
107    type=mysql key="select * from them where id='quote2';" opts=NULL
108  database lookup required for select * from them where id='quote2';
109  MySQL query: "select * from them where id='quote2';" opts 'NULL'
110  MYSQL using cached connection for 127.0.0.1:1223/test/root
111  creating new cache entry
112  lookup yielded: name="\"stquot" id=quote2 
113  search_open: mysql "NULL"
114    cached open
115  search_find: file="NULL"
116    key="select * from them where id='nlonly';" partial=-1 affix=NULL starflags=0 opts=NULL
117  LRU list:
118  internal_search_find: file="NULL"
119    type=mysql key="select * from them where id='nlonly';" opts=NULL
120  database lookup required for select * from them where id='nlonly';
121  MySQL query: "select * from them where id='nlonly';" opts 'NULL'
122  MYSQL using cached connection for 127.0.0.1:1223/test/root
123  MYSQL: no data found
124  creating new cache entry
125  lookup failed
126  search_open: mysql "NULL"
127    cached open
128  search_find: file="NULL"
129    key="servers=x:127.0.0.1::1223; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
130  LRU list:
131  internal_search_find: file="NULL"
132    type=mysql key="servers=x:127.0.0.1::1223; select name from them where id='ph10';" opts=NULL
133  database lookup required for servers=x:127.0.0.1::1223; select name from them where id='ph10';
134  MySQL query: "servers=x:127.0.0.1::1223; select name from them where id='ph10';" opts 'NULL'
135  lookup deferred: MySQL server "x" not found in mysql_servers
136  search_open: mysql "NULL"
137    cached open
138  search_find: file="NULL"
139    key="servers=127.0.0.1::1223:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
140  LRU list:
141  internal_search_find: file="NULL"
142    type=mysql key="servers=127.0.0.1::1223:x; select name from them where id='ph10';" opts=NULL
143  database lookup required for servers=127.0.0.1::1223:x; select name from them where id='ph10';
144  MySQL query: "servers=127.0.0.1::1223:x; select name from them where id='ph10';" opts 'NULL'
145  MYSQL using cached connection for 127.0.0.1:1223/test/root
146  creating new cache entry
147  lookup yielded: Philip Hazel
148  search_open: mysql "NULL"
149    cached open
150  search_find: file="NULL"
151    key="servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
152  LRU list:
153  internal_search_find: file="NULL"
154    type=mysql key="servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';" opts=NULL
155  database lookup required for servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';
156  MySQL query: "servers=127.0.0.1::1223/test/root/:x; select name from them where id='ph10';" opts 'NULL'
157  MYSQL using cached connection for 127.0.0.1:1223/test/root
158  creating new cache entry
159  lookup yielded: Philip Hazel
160  search_open: mysql "NULL"
161    cached open
162  search_find: file="NULL"
163    key="servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
164  LRU list:
165  internal_search_find: file="NULL"
166    type=mysql key="servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';" opts=NULL
167  database lookup required for servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';
168  MySQL query: "servers=ip4.ip4.ip4.ip4::1223/test/root/:127.0.0.1::1223; select name from them where id='ph10';" opts 'NULL'
169  MYSQL new connection: host=ip4.ip4.ip4.ip4 port=1223 socket=NULL database=test user=root
170  creating new cache entry
171  lookup yielded: Philip Hazel
172  search_open: mysql "NULL"
173    cached open
174  search_find: file="NULL"
175    key="servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" partial=-1 affix=NULL starflags=0 opts=NULL
176  LRU list:
177  internal_search_find: file="NULL"
178    type=mysql key="servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" opts=NULL
179  database lookup required for servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';
180  MySQL query: "servers=localhost(TESTSUITE/mysql/sock)/test/root/; select name from them where id='ph10';" opts 'NULL'
181  MYSQL new connection: host=localhost port=0 socket=TESTSUITE/mysql/sock database=test user=root
182  creating new cache entry
183  lookup yielded: Philip Hazel
184  search_open: mysql "NULL"
185    cached open
186  search_find: file="NULL"
187    key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
188  LRU list:
189  internal_search_find: file="NULL"
190    type=mysql key="SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
191  database lookup required for SELECT name FROM them WHERE id IN ('ph10', 'aaaa');
192  MySQL query: "SELECT name FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
193  MYSQL using cached connection for 127.0.0.1:1223/test/root
194  creating new cache entry
195  lookup yielded: Philip Hazel
196  Aristotle
197  search_open: mysql "NULL"
198    cached open
199  search_find: file="NULL"
200    key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" partial=-1 affix=NULL starflags=0 opts=NULL
201  LRU list:
202  internal_search_find: file="NULL"
203    type=mysql key="SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts=NULL
204  database lookup required for SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');
205  MySQL query: "SELECT *    FROM them WHERE id IN ('ph10', 'aaaa');" opts 'NULL'
206  MYSQL using cached connection for 127.0.0.1:1223/test/root
207  creating new cache entry
208  lookup yielded: name="Philip Hazel" id=ph10 
209  name=Aristotle id=aaaa 
210  search_open: mysql "NULL"
211    cached open
212  search_find: file="NULL"
213    key="delete from them where id='aaaa'" partial=-1 affix=NULL starflags=0 opts=NULL
214  LRU list:
215  internal_search_find: file="NULL"
216    type=mysql key="delete from them where id='aaaa'" opts=NULL
217  database lookup required for delete from them where id='aaaa'
218  MySQL query: "delete from them where id='aaaa'" opts 'NULL'
219  MYSQL using cached connection for 127.0.0.1:1223/test/root
220  MYSQL: query was not one that returns data
221  lookup forced cache cleanup
222  lookup yielded: 1
223 search_tidyup called
224 close MYSQL connection: localhost(TESTSUITE/mysql/sock)/test/root
225 close MYSQL connection: ip4.ip4.ip4.ip4:1223/test/root
226 close MYSQL connection: 127.0.0.1:1223/test/root
227 >>>>>>>>>>>>>>>> Exim pid=pppp (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=pppp
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=pppp
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<< mail from:<a@b>
255 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
256 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
257 SMTP>> 250 OK
258 SMTP<< rcpt to:<c@d>
259 using ACL "check_recipient"
260 processing "warn" (TESTSUITE/test-config 25)
261  search_open: mysql "NULL"
262  search_find: file="NULL"
263    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
264  LRU list:
265  internal_search_find: file="NULL"
266    type=mysql key="select name from them where id = 'c'" opts=NULL
267  database lookup required for select name from them where id = 'c'
268  MySQL query: "select name from them where id = 'c'" opts 'NULL'
269  MYSQL new connection: host=127.0.0.1 port=1223 socket=NULL database=test user=root
270  MYSQL: no data found
271  creating new cache entry
272  lookup failed
273 check set acl_m0 = ok:   ${lookup mysql                    {select name from them where id = '$local_part'}}
274                  = ok:   
275  search_open: mysql "NULL"
276    cached open
277  search_find: file="NULL"
278    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223/test/root/"
279  LRU list:
280  internal_search_find: file="NULL"
281    type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::1223/test/root/"
282  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
283  MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::1223/test/root/'
284  MYSQL using cached connection for 127.0.0.1:1223/test/root
285  MYSQL: no data found
286  replacing old cache entry
287  lookup failed
288 check set acl_m0 = ok:   ${lookup mysql,servers=127.0.0.1::1223/test/root/      {select name from them where id = '$local_part'}}
289                  = ok:   
290  search_open: mysql "NULL"
291    cached open
292  search_find: file="NULL"
293    key="select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223"
294  LRU list:
295  internal_search_find: file="NULL"
296    type=mysql key="select name from them where id = 'c'" opts="servers=127.0.0.1::1223"
297  cached data found but wrong opts;  database lookup required for select name from them where id = 'c'
298  MySQL query: "select name from them where id = 'c'" opts 'servers=127.0.0.1::1223'
299  MYSQL using cached connection for 127.0.0.1:1223/test/root
300  MYSQL: no data found
301  replacing old cache entry
302  lookup failed
303 check set acl_m0 = ok:   ${lookup mysql,servers=127.0.0.1::1223    {select name from them where id = '$local_part'}}
304                  = ok:   
305  search_open: mysql "NULL"
306    cached open
307  search_find: file="NULL"
308    key="servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" partial=-1 affix=NULL starflags=0 opts=NULL
309  LRU list:
310  internal_search_find: file="NULL"
311    type=mysql key="servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" opts=NULL
312  database lookup required for servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'
313  MySQL query: "servers=127.0.0.1::1223/test/root/; select name from them where id = 'c'" opts 'NULL'
314  lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
315 warn: condition test deferred in ACL "check_recipient"
316 LOG: MAIN
317   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
318 processing "warn" (TESTSUITE/test-config 36)
319 check set acl_m0 = ok:   hostlist
320 check hosts = net-mysql;select * from them where id='$local_part'
321 search_open: mysql "NULL"
322   cached open
323 search_find: file="NULL"
324   key="select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
325 LRU list:
326 internal_search_find: file="NULL"
327   type=mysql key="select * from them where id='c'" opts=NULL
328 database lookup required for select * from them where id='c'
329 MySQL query: "select * from them where id='c'" opts 'NULL'
330 MYSQL using cached connection for 127.0.0.1:1223/test/root
331 MYSQL: no data found
332 creating new cache entry
333 lookup failed
334 host in "net-mysql;select * from them where id='c'"? no (end of list)
335 warn: condition test failed in ACL "check_recipient"
336 processing "warn" (TESTSUITE/test-config 39)
337 check set acl_m0 = FAIL: hostlist
338 check hosts = <& net-mysql;servers=127.0.0.1::1223/test/root/; select * from them where id='$local_part'
339 search_open: mysql "NULL"
340   cached open
341 search_find: file="NULL"
342   key="servers=127.0.0.1::1223/test/root/; select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts=NULL
343 LRU list:
344 internal_search_find: file="NULL"
345   type=mysql key="servers=127.0.0.1::1223/test/root/; select * from them where id='c'" opts=NULL
346 database lookup required for servers=127.0.0.1::1223/test/root/; select * from them where id='c'
347 MySQL query: "servers=127.0.0.1::1223/test/root/; select * from them where id='c'" opts 'NULL'
348 lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
349 host in "<& net-mysql;servers=127.0.0.1::1223/test/root/; select * from them where id='c'"? list match deferred for net-mysql;servers=127.0.0.1::1223/test/root/; select * from them where id='c'
350 warn: condition test deferred in ACL "check_recipient"
351 LOG: MAIN
352   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
353 processing "warn" (TESTSUITE/test-config 44)
354 check set acl_m0 = FAIL: hostlist
355 check hosts = <& net-mysql,servers=127.0.0.1::1223/test/root/; select * from them where id='$local_part'
356 search_open: mysql "NULL"
357   cached open
358 search_find: file="NULL"
359   key=" select * from them where id='c'" partial=-1 affix=NULL starflags=0 opts="servers=127.0.0.1::1223/test/root/"
360 LRU list:
361 internal_search_find: file="NULL"
362   type=mysql key=" select * from them where id='c'" opts="servers=127.0.0.1::1223/test/root/"
363 database lookup required for  select * from them where id='c'
364 MySQL query: " select * from them where id='c'" opts 'servers=127.0.0.1::1223/test/root/'
365 lookup deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
366 host in "<& net-mysql,servers=127.0.0.1::1223/test/root/; select * from them where id='c'"? list match deferred for net-mysql,servers=127.0.0.1::1223/test/root/; select * from them where id='c'
367 warn: condition test deferred in ACL "check_recipient"
368 LOG: MAIN
369   H=[10.0.0.0] Warning: ACL "warn" statement skipped: condition test deferred: MySQL server "127.0.0.1:1223/test/root/" is tainted
370 processing "accept" (TESTSUITE/test-config 47)
371 check domains = +local_domains
372 d in "@"? no (end of list)
373 d in "+local_domains"? no (end of list)
374 accept: condition test failed in ACL "check_recipient"
375 processing "accept" (TESTSUITE/test-config 48)
376 check hosts = +relay_hosts
377 search_open: mysql "NULL"
378   cached open
379 search_find: file="NULL"
380   key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
381 LRU list:
382 internal_search_find: file="NULL"
383   type=mysql key="select * from them where id='10.0.0.0'" opts=NULL
384 database lookup required for select * from them where id='10.0.0.0'
385 MySQL query: "select * from them where id='10.0.0.0'" opts 'NULL'
386 MYSQL using cached connection for 127.0.0.1:1223/test/root
387 MYSQL: no data found
388 creating new cache entry
389 lookup failed
390 host in "net-mysql;select * from them where id='10.0.0.0'"? no (end of list)
391 host in "+relay_hosts"? no (end of list)
392 accept: condition test failed in ACL "check_recipient"
393 processing "deny" (TESTSUITE/test-config 49)
394   message: relay not permitted
395 deny: condition test succeeded in ACL "check_recipient"
396 end of ACL "check_recipient": DENY
397 SMTP>> 550 relay not permitted
398 LOG: MAIN REJECT
399   H=[10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
400 SMTP<< quit
401 SMTP>> 221 myhost.test.ex closing connection
402 LOG: smtp_connection MAIN
403   SMTP connection from [10.0.0.0] closed by QUIT
404 search_tidyup called
405 close MYSQL connection: 127.0.0.1:1223/test/root
406 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
407 Exim version x.yz ....
408 changed uid/gid: forcing real = effective
409   uid=uuuu gid=CALLER_GID pid=pppp
410 configuration file is TESTSUITE/test-config
411 admin user
412 changed uid/gid: privilege not needed
413   uid=EXIM_UID gid=EXIM_GID pid=pppp
414 seeking password data for user "CALLER": cache not available
415 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
416 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
417 sender address = CALLER@myhost.test.ex
418 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
419 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
420 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
421 Sender: CALLER@myhost.test.ex
422 Recipients:
423   ph10
424 search_tidyup called
425 >>Headers received:
426
427 rewrite_one_header: type=F:
428   From: CALLER_NAME <CALLER@myhost.test.ex>
429 search_tidyup called
430 >>Headers after rewriting and local additions:
431 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
432 F From: CALLER_NAME <CALLER@myhost.test.ex>
433   Date: Tue, 2 Mar 1999 09:44:33 +0000
434
435 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
436 Data file written for message 10HmaX-0005vi-00
437 >>Generated Received: header line
438 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
439         (envelope-from <CALLER@myhost.test.ex>)
440         id 10HmaX-0005vi-00
441         for ph10@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
442 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
443 DSN: **** SPOOL_OUT - address: <ph10@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
444 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
445 Size of headers = sss
446 LOG: MAIN
447   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
448 created log directory TESTSUITE/spool/log
449 search_tidyup called
450 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-0005vi-00
451 Exim version x.yz ....
452 changed uid/gid: forcing real = effective
453   uid=uuuu gid=EXIM_GID pid=pppp
454 configuration file is TESTSUITE/test-config
455 trusted user
456 admin user
457 dropping to exim gid; retaining priv uid
458 seeking password data for user "CALLER": cache not available
459 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
460 set_process_info: pppp delivering specified messages
461 set_process_info: pppp delivering 10HmaX-0005vi-00
462 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
463 reading spool file 10HmaX-0005vi-00-H
464 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
465 sender_local=1 ident=CALLER
466 Non-recipients:
467 Empty Tree
468 ---- End of tree ----
469 recipients_count=1
470 **** SPOOL_IN - No additional fields
471 body_linecount=1 message_linecount=7
472 DSN: set orcpt:   flags: 0x0
473 Delivery address list:
474   ph10@myhost.test.ex 
475  locking TESTSUITE/spool/db/retry.lockfile
476  locked  TESTSUITE/spool/db/retry.lockfile
477  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
478  returned from EXIM_DBOPEN: (nil)
479  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
480  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
481 no retry data available
482 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
483 Considering: ph10@myhost.test.ex
484 unique = ph10@myhost.test.ex
485 no   domain  retry record
486 no   address retry record
487 ph10@myhost.test.ex: queued for routing
488 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
489 routing ph10@myhost.test.ex
490 --------> r1 router <--------
491 local_part=ph10 domain=myhost.test.ex
492 processing address_data
493  search_open: mysql "NULL"
494  search_find: file="NULL"
495    key="select name from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
496  LRU list:
497  internal_search_find: file="NULL"
498    type=mysql key="select name from them where id='ph10'" opts=NULL
499  database lookup required for select name from them where id='ph10'
500  MySQL query: "select name from them where id='ph10'" opts 'NULL'
501  MYSQL new connection: host=127.0.0.1 port=1223 socket=NULL database=test user=root
502  creating new cache entry
503  lookup yielded: Philip Hazel
504 calling r1 router
505 r1 router called for ph10@myhost.test.ex
506   domain = myhost.test.ex
507 set transport t1
508 queued for t1 transport: local_part = ph10
509 domain = myhost.test.ex
510   errors_to=NULL
511   domain_data=NULL localpart_data=NULL
512 routed by r1 router
513   envelope to: ph10@myhost.test.ex
514   transport: t1
515 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
516 After routing:
517   Local deliveries:
518     ph10@myhost.test.ex
519   Remote deliveries:
520   Failed addresses:
521   Deferred addresses:
522 search_tidyup called
523 close MYSQL connection: 127.0.0.1:1223/test/root
524 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
525 --------> ph10@myhost.test.ex <--------
526  locking TESTSUITE/spool/db/retry.lockfile
527  locked  TESTSUITE/spool/db/retry.lockfile
528  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
529  returned from EXIM_DBOPEN: (nil)
530  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
531 no retry data available
532 search_tidyup called
533 changed uid/gid: local delivery to ph10 <ph10@myhost.test.ex> transport=t1
534   uid=CALLER_UID gid=CALLER_GID pid=pppp
535   home=NULL current=/
536 set_process_info: pppp delivering 10HmaX-0005vi-00 to ph10 using t1
537 appendfile transport entered
538  search_open: mysql "NULL"
539  search_find: file="NULL"
540    key="select id from them where id='ph10'" partial=-1 affix=NULL starflags=0 opts=NULL
541  LRU list:
542  internal_search_find: file="NULL"
543    type=mysql key="select id from them where id='ph10'" opts=NULL
544  database lookup required for select id from them where id='ph10'
545  MySQL query: "select id from them where id='ph10'" opts 'NULL'
546  MYSQL new connection: host=127.0.0.1 port=1223 socket=NULL database=test user=root
547  creating new cache entry
548  lookup yielded: ph10
549 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
550   file=TESTSUITE/test-mail/ph10 format=unix
551   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
552   message_suffix=\n
553   maildir_use_size_file=no
554   locking by lockfile fcntl 
555 lock name: TESTSUITE/test-mail/ph10.lock
556 hitch name: TESTSUITE/test-mail/ph10.lock.test.ex.dddddddd.pppppppp
557 lock file created
558 mailbox TESTSUITE/test-mail/ph10 is locked
559 writing to file TESTSUITE/test-mail/ph10
560 writing data block fd=dddd size=sss timeout=0
561 cannot use sendfile for body: spoolfile not wireformat
562 writing data block fd=dddd size=sss timeout=0
563 writing data block fd=dddd size=sss timeout=0
564 appendfile yields 0 with errno=dd more_errno=dd
565 search_tidyup called
566 close MYSQL connection: 127.0.0.1:1223/test/root
567 journalling ph10@myhost.test.ex
568 t1 transport returned OK for ph10@myhost.test.ex
569 post-process ph10@myhost.test.ex (0)
570 ph10@myhost.test.ex delivered
571 LOG: MAIN
572   => ph10 <ph10@myhost.test.ex> R=r1 T=t1
573 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
574 changed uid/gid: post-delivery tidying
575   uid=EXIM_UID gid=EXIM_GID pid=pppp
576 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
577 Processing retry items
578 Succeeded addresses:
579  ph10@myhost.test.ex: no retry items
580 Failed addresses:
581 Deferred addresses:
582 end of retry processing
583 DSN: processing router : r1
584 DSN: processing successful delivery address: ph10@myhost.test.ex
585 DSN: Sender_address: CALLER@myhost.test.ex
586 DSN: orcpt: NULL  flags: 0x0
587 DSN: envid: NULL  ret: 0
588 DSN: Final recipient: ph10@myhost.test.ex
589 DSN: Remote SMTP server supports DSN: 0
590 DSN: not sending DSN success message
591 LOG: MAIN
592   Completed
593 end delivery of 10HmaX-0005vi-00
594 search_tidyup called
595 search_tidyup called
596 >>>>>>>>>>>>>>>> Exim pid=pppp (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
597 search_tidyup called
598 >>>>>>>>>>>>>>>> Exim pid=pppp (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>