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