Hintsdb sqlite: use transaction rather than file lock
[exim.git] / test / stderr / 0403
1 Exim version x.yz ....
2 Hints DB:
3 changed uid/gid: forcing real = effective
4   uid=uuuu gid=CALLER_GID pid=p1234
5 configuration file is TESTSUITE/test-config
6 admin user
7 changed uid/gid: privilege not needed
8   uid=EXIM_UID gid=EXIM_GID pid=p1234
9 seeking password data for user "CALLER": cache not available
10 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
11 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
12 sender address = CALLER@test.ex
13 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
14 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
15 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
16 Sender: CALLER@test.ex
17 Recipients:
18   userx@test.ex
19 search_tidyup called
20 >>Headers received:
21
22 qualify & rewrite recipients list
23 rewrite rules on sender address
24 qualify and rewrite headers
25  rewrite_one_header: type=F:
26    From: CALLER_NAME <CALLER@test.ex>
27 search_tidyup called
28 >>Headers after rewriting and local additions:
29  I Message-Id: <E10HmaX-000000005vi-0000@mail.test.ex>
30  F From: CALLER_NAME <CALLER@test.ex>
31    Date: Tue, 2 Mar 1999 09:44:33 +0000
32
33 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
34 Data file written for message 10HmaX-000000005vi-0000
35 >>Generated Received: header line
36 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
37         (envelope-from <CALLER@test.ex>)
38         id 10HmaX-000000005vi-0000
39         for userx@test.ex;
40         Tue, 2 Mar 1999 09:44:33 +0000
41 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
42 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
43 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
44 Size of headers = sss
45 LOG: MAIN
46   <= CALLER@test.ex U=CALLER P=local S=sss
47 created log directory TESTSUITE/spool/log
48 search_tidyup called
49 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaX-000000005vi-0000
50 Exim version x.yz ....
51 Hints DB:
52 changed uid/gid: forcing real = effective
53   uid=uuuu gid=EXIM_GID pid=p1235
54 configuration file is TESTSUITE/test-config
55 trusted user
56 admin user
57 dropping to exim gid; retaining priv uid
58 seeking password data for user "CALLER": cache not available
59 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
60 set_process_info: pppp delivering specified messages
61 set_process_info: pppp delivering 10HmaX-000000005vi-0000
62 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
63 reading spool file 10HmaX-000000005vi-0000-H
64 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
65 sender_local=1 ident=CALLER
66 Non-recipients:
67  Empty Tree
68 ---- End of tree ----
69 recipients_count=1
70 **** SPOOL_IN - No additional fields
71 body_linecount=0 message_linecount=8
72 DSN: set orcpt:   flags: 0x0
73 Delivery address list:
74   userx@test.ex 
75  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
76  returned from EXIM_DBOPEN: (nil)
77  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
78 no retry data available
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 Considering: userx@test.ex
81 unique = userx@test.ex
82 no   domain  retry record
83 no   address retry record
84 userx@test.ex: queued for routing
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing userx@test.ex
87 --------> r1 router <--------
88 local_part=userx domain=test.ex
89 checking domains
90   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
91   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
92     key="userx@test.ex" partial=-1 affix=NULL starflags=0 opts="ret=key"
93   LRU list:
94     0TESTSUITE/aux-fixed/0403.accountfile
95     End
96   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
97     type=lsearch key="userx@test.ex" opts=NULL
98   file lookup required for userx@test.ex
99     in TESTSUITE/aux-fixed/0403.accountfile
100   creating new cache entry
101   lookup failed
102 test.ex in domains?
103 test.ex in domains? no (end of list)
104 r1 router skipped: domains mismatch
105 --------> r2 router <--------
106 local_part=userx domain=test.ex
107 checking domains
108 test.ex in domains?
109  list element: lsearch;TESTSUITE/aux-fixed/0403.data
110  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
111  search_find: file="TESTSUITE/aux-fixed/0403.data"
112    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
113  LRU list:
114    0TESTSUITE/aux-fixed/0403.data
115    0TESTSUITE/aux-fixed/0403.accountfile
116    End
117  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
118    type=lsearch key="test.ex" opts=NULL
119  file lookup required for test.ex
120    in TESTSUITE/aux-fixed/0403.data
121  creating new cache entry
122  lookup yielded: [DOMAINDATA_test.ex]
123  test.ex in domains? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
124 checking local_parts
125 userx in local_parts?
126  list element: lsearch;TESTSUITE/aux-fixed/0403.data
127  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
128    cached open
129  search_find: file="TESTSUITE/aux-fixed/0403.data"
130    key="userx" partial=-1 affix=NULL starflags=0 opts=NULL
131  LRU list:
132    0TESTSUITE/aux-fixed/0403.data
133    0TESTSUITE/aux-fixed/0403.accountfile
134    End
135  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
136    type=lsearch key="userx" opts=NULL
137  file lookup required for userx
138    in TESTSUITE/aux-fixed/0403.data
139  creating new cache entry
140  lookup yielded: [LOCALPARTDATA_userx]
141  userx in local_parts? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
142 +++ROUTER:
143 +++domain_data=[DOMAINDATA_test.ex]
144 +++local_part_data=[LOCALPARTDATA_userx]
145 +++address_data=
146 +++home=/usr
147 processing address_data
148 calling r2 router
149 rda_interpret (string): 'TESTSUITE/test-mail/junk'
150 expanded: 'TESTSUITE/test-mail/junk'
151 file is not a filter file
152 parse_forward_list: TESTSUITE/test-mail/junk
153 extract item: TESTSUITE/test-mail/junk
154 set transport t1
155 r2 router generated TESTSUITE/test-mail/junk
156   pipe, file, or autoreply
157   errors_to=NULL transport=t1
158   uid=unset gid=unset home=/usr
159 routed by r2 router
160   envelope to: userx@test.ex
161   transport: <none>
162  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
163  returned from EXIM_DBOPEN: (nil)
164  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
165 no retry data available
166 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
167 Considering: TESTSUITE/test-mail/junk
168 unique = TESTSUITE/test-mail/junk:userx@test.ex
169 queued for t1 transport
170 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
171 After routing:
172   Local deliveries:
173     TESTSUITE/test-mail/junk
174   Remote deliveries:
175   Failed addresses:
176   Deferred addresses:
177 search_tidyup called
178 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
179 --------> TESTSUITE/test-mail/junk <--------
180  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
181  returned from EXIM_DBOPEN: (nil)
182  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
183 no retry data available
184 search_tidyup called
185 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
186   uid=CALLER_UID gid=CALLER_GID pid=p1236
187   home=/usr current=/usr
188 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
189 +++TRANSPORT:
190 +++domain_data=[DOMAINDATA_test.ex]
191 +++local_part_data=[LOCALPARTDATA_userx]
192 +++address_data=ADDRESSDATA
193 +++home=/usr
194 appendfile transport entered
195 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
196   file=TESTSUITE/test-mail/junk format=unix
197   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
198   message_suffix=\n
199   maildir_use_size_file=no
200   locking by lockfile fcntl 
201 *** delivery by t1 transport bypassed by -N option
202 search_tidyup called
203 journalling TESTSUITE/test-mail/junk:userx@test.ex
204 t1 transport returned OK for TESTSUITE/test-mail/junk
205 post-process TESTSUITE/test-mail/junk (0)
206 TESTSUITE/test-mail/junk delivered
207 userx@test.ex: children all complete
208 LOG: MAIN
209   *> TESTSUITE/test-mail/junk <userx@test.ex> R=r2 T=t1
210 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
211 changed uid/gid: post-delivery tidying
212   uid=EXIM_UID gid=EXIM_GID pid=p1235
213 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
214 DSN: processing router : r2
215 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
216 DSN: Sender_address: CALLER@test.ex
217 DSN: orcpt: NULL  flags: 0x0
218 DSN: envid: NULL  ret: 0
219 DSN: Final recipient: TESTSUITE/test-mail/junk
220 DSN: Remote SMTP server supports DSN: 0
221 DSN: not sending DSN success message
222 DSN: processing router : r2
223 DSN: processing successful delivery address: userx@test.ex
224 DSN: Sender_address: CALLER@test.ex
225 DSN: orcpt: NULL  flags: 0x0
226 DSN: envid: NULL  ret: 0
227 DSN: Final recipient: userx@test.ex
228 DSN: Remote SMTP server supports DSN: 0
229 DSN: not sending DSN success message
230 LOG: MAIN
231   Completed
232 end delivery of 10HmaX-000000005vi-0000
233 search_tidyup called
234 search_tidyup called
235 >>>>>>>>>>>>>>>> Exim pid=p1235 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
236 search_tidyup called
237 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
238 ### _data from a multi-step expansion
239 Exim version x.yz ....
240 Hints DB:
241 changed uid/gid: forcing real = effective
242   uid=uuuu gid=CALLER_GID pid=p1237
243 configuration file is TESTSUITE/test-config
244 admin user
245 changed uid/gid: privilege not needed
246   uid=EXIM_UID gid=EXIM_GID pid=p1237
247 seeking password data for user "CALLER": cache not available
248 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
249 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
250 sender address = CALLER@test.ex
251 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
252 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
253 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
254 Sender: CALLER@test.ex
255 Recipients:
256   charlie@dom1.ain
257 search_tidyup called
258 >>Headers received:
259
260 qualify & rewrite recipients list
261 rewrite rules on sender address
262 qualify and rewrite headers
263  rewrite_one_header: type=F:
264    From: CALLER_NAME <CALLER@test.ex>
265 search_tidyup called
266 >>Headers after rewriting and local additions:
267  I Message-Id: <E10HmaY-000000005vi-0000@mail.test.ex>
268  F From: CALLER_NAME <CALLER@test.ex>
269    Date: Tue, 2 Mar 1999 09:44:33 +0000
270
271 Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
272 Data file written for message 10HmaY-000000005vi-0000
273 >>Generated Received: header line
274 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
275         (envelope-from <CALLER@test.ex>)
276         id 10HmaY-000000005vi-0000
277         for charlie@dom1.ain;
278         Tue, 2 Mar 1999 09:44:33 +0000
279 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000
280 DSN: **** SPOOL_OUT - address: <charlie@dom1.ain> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
281 Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H
282 Size of headers = sss
283 LOG: MAIN
284   <= CALLER@test.ex U=CALLER P=local S=sss
285 search_tidyup called
286 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaY-000000005vi-0000
287 Exim version x.yz ....
288 Hints DB:
289 changed uid/gid: forcing real = effective
290   uid=uuuu gid=EXIM_GID pid=p1238
291 configuration file is TESTSUITE/test-config
292 trusted user
293 admin user
294 dropping to exim gid; retaining priv uid
295 seeking password data for user "CALLER": cache not available
296 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
297 set_process_info: pppp delivering specified messages
298 set_process_info: pppp delivering 10HmaY-000000005vi-0000
299 Trying spool file TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
300 reading spool file 10HmaY-000000005vi-0000-H
301 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
302 sender_local=1 ident=CALLER
303 Non-recipients:
304  Empty Tree
305 ---- End of tree ----
306 recipients_count=1
307 **** SPOOL_IN - No additional fields
308 body_linecount=0 message_linecount=8
309 DSN: set orcpt:   flags: 0x0
310 Delivery address list:
311   charlie@dom1.ain 
312  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
313  returned from EXIM_DBOPEN: (nil)
314  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
315 no retry data available
316 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
317 Considering: charlie@dom1.ain
318 unique = charlie@dom1.ain
319 no   domain  retry record
320 no   address retry record
321 charlie@dom1.ain: queued for routing
322 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
323 routing charlie@dom1.ain
324 --------> r1 router <--------
325 local_part=charlie domain=dom1.ain
326 checking domains
327   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
328   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
329     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
330   LRU list:
331     0TESTSUITE/aux-fixed/0403.accountfile
332     End
333   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
334     type=lsearch key="charlie@dom1.ain" opts=NULL
335   file lookup required for charlie@dom1.ain
336     in TESTSUITE/aux-fixed/0403.accountfile
337   creating new cache entry
338   lookup yielded: 
339   lookup yield replace by key: charlie@dom1.ain
340 dom1.ain in domains?
341  list element: dom1.ain
342  dom1.ain in domains? yes (matched "dom1.ain")
343 checking local_parts
344   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
345     cached open
346   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
347     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
348   LRU list:
349     0TESTSUITE/aux-fixed/0403.accountfile
350     End
351   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
352     type=lsearch key="charlie@dom1.ain" opts=NULL
353   cached data used for lookup of charlie@dom1.ain
354     in TESTSUITE/aux-fixed/0403.accountfile
355   lookup yielded: 
356   lookup yield replace by key: charlie@dom1.ain
357 charlie in local_parts?
358  list element: charlie
359  charlie in local_parts? yes (matched "charlie")
360 +++ROUTER:
361 +++domain_data=dom1.ain
362 +++local_part_data=charlie
363 calling r1 router
364 rda_interpret (string): 'TESTSUITE/test-mail/junk'
365 expanded: 'TESTSUITE/test-mail/junk'
366 file is not a filter file
367 parse_forward_list: TESTSUITE/test-mail/junk
368 extract item: TESTSUITE/test-mail/junk
369 set transport t1
370 r1 router generated TESTSUITE/test-mail/junk
371   pipe, file, or autoreply
372   errors_to=NULL transport=t1
373   uid=unset gid=unset home=NULL
374 routed by r1 router
375   envelope to: charlie@dom1.ain
376   transport: <none>
377  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
378  returned from EXIM_DBOPEN: (nil)
379  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
380 no retry data available
381 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
382 Considering: TESTSUITE/test-mail/junk
383 unique = TESTSUITE/test-mail/junk:charlie@dom1.ain
384 queued for t1 transport
385 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
386 After routing:
387   Local deliveries:
388     TESTSUITE/test-mail/junk
389   Remote deliveries:
390   Failed addresses:
391   Deferred addresses:
392 search_tidyup called
393 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
394 --------> TESTSUITE/test-mail/junk <--------
395  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
396  returned from EXIM_DBOPEN: (nil)
397  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
398 no retry data available
399 search_tidyup called
400 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
401   uid=CALLER_UID gid=CALLER_GID pid=p1239
402   home=NULL current=/
403 set_process_info: pppp delivering 10HmaY-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
404 +++TRANSPORT:
405 +++domain_data=dom1.ain
406 +++local_part_data=charlie
407 +++address_data=
408 +++home=
409 appendfile transport entered
410 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
411   file=TESTSUITE/test-mail/junk format=unix
412   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
413   message_suffix=\n
414   maildir_use_size_file=no
415   locking by lockfile fcntl 
416 *** delivery by t1 transport bypassed by -N option
417 search_tidyup called
418 journalling TESTSUITE/test-mail/junk:charlie@dom1.ain
419 t1 transport returned OK for TESTSUITE/test-mail/junk
420 post-process TESTSUITE/test-mail/junk (0)
421 TESTSUITE/test-mail/junk delivered
422 charlie@dom1.ain: children all complete
423 LOG: MAIN
424   *> TESTSUITE/test-mail/junk <charlie@dom1.ain> R=r1 T=t1
425 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
426 changed uid/gid: post-delivery tidying
427   uid=EXIM_UID gid=EXIM_GID pid=p1238
428 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
429 DSN: processing router : r1
430 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
431 DSN: Sender_address: CALLER@test.ex
432 DSN: orcpt: NULL  flags: 0x0
433 DSN: envid: NULL  ret: 0
434 DSN: Final recipient: TESTSUITE/test-mail/junk
435 DSN: Remote SMTP server supports DSN: 0
436 DSN: not sending DSN success message
437 DSN: processing router : r1
438 DSN: processing successful delivery address: charlie@dom1.ain
439 DSN: Sender_address: CALLER@test.ex
440 DSN: orcpt: NULL  flags: 0x0
441 DSN: envid: NULL  ret: 0
442 DSN: Final recipient: charlie@dom1.ain
443 DSN: Remote SMTP server supports DSN: 0
444 DSN: not sending DSN success message
445 LOG: MAIN
446   Completed
447 end delivery of 10HmaY-000000005vi-0000
448 search_tidyup called
449 search_tidyup called
450 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
451 search_tidyup called
452 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
453
454 ******** SERVER ********
455 ### _data from a multi-step expansion