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