New format for exim_message_id
[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="file=TESTSUITE/aux-fixed/sqlitedb"
8  LRU list:
9  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
10    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
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 found but wrong opts;  file lookup required for select name from them where id='userx';
23  c  in TESTSUITE/aux-fixed/sqlitedb
24  replacing old cache entry
25  lookup yielded: Ayen Other
26  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
27    cached open
28  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
29    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
30  LRU list:
31  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
32    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
33  cached data found but wrong opts;  file lookup required for select name from them where id='userx';
34  c  in TESTSUITE/aux-fixed/sqlitedb
35  replacing old cache entry
36  lookup yielded: Ayen Other
37  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
38    cached open
39  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
40    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
41  LRU list:
42  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
43    type=sqlite key="select name from them where id='userx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
44  cached data used for lookup of select name from them where id='userx';
45    in TESTSUITE/aux-fixed/sqlitedb
46  lookup yielded: Ayen Other
47  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
48    cached open
49  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
50    key="select name from them where id='xxxx';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
51  LRU list:
52  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
53    type=sqlite key="select name from them where id='xxxx';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
54  file lookup required for select name from them where id='xxxx';
55    in TESTSUITE/aux-fixed/sqlitedb
56  lookup forced cache cleanup
57  lookup failed
58  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
59    cached open
60  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
61    key="select name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
62  LRU list:
63  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
64    type=sqlite key="select name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
65  file lookup required for select name from them where id='nothing';
66    in TESTSUITE/aux-fixed/sqlitedb
67  creating new cache entry
68  lookup yielded: 
69  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
70    cached open
71  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
72    key="select id,name from them where id='nothing';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
73  LRU list:
74  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
75    type=sqlite key="select id,name from them where id='nothing';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
76  file lookup required for select id,name from them where id='nothing';
77    in TESTSUITE/aux-fixed/sqlitedb
78  creating new cache entry
79  lookup yielded: id=nothing name="" 
80  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
81    cached open
82  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
83    key="select * from them where id='quote2';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
84  LRU list:
85  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
86    type=sqlite key="select * from them where id='quote2';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
87  file lookup required for select * from them where id='quote2';
88    in TESTSUITE/aux-fixed/sqlitedb
89  creating new cache entry
90  lookup yielded: name="\"stquot" id=quote2 
91  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
92    cached open
93  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
94    key="select * from them where id='newline';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
95  LRU list:
96  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
97    type=sqlite key="select * from them where id='newline';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
98  file lookup required for select * from them where id='newline';
99    in TESTSUITE/aux-fixed/sqlitedb
100  creating new cache entry
101  lookup yielded: name="before
102  after" id=newline 
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 id='tab';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
107  LRU list:
108  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
109    type=sqlite key="select * from them where id='tab';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
110  file lookup required for select * from them where id='tab';
111    in TESTSUITE/aux-fixed/sqlitedb
112  creating new cache entry
113  lookup yielded: name="x        x" id=tab 
114  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
115    cached open
116  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
117    key="select * from them where id='its';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
118  LRU list:
119  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
120    type=sqlite key="select * from them where id='its';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
121  file lookup required for select * from them where id='its';
122    in TESTSUITE/aux-fixed/sqlitedb
123  creating new cache entry
124  lookup yielded: name=it's id=its 
125  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
126    cached open
127  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
128    key="select * from them where name='it''s';" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
129  LRU list:
130  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
131    type=sqlite key="select * from them where name='it''s';" opts="file=TESTSUITE/aux-fixed/sqlitedb"
132  file lookup required for select * from them where name='it''s';
133    in TESTSUITE/aux-fixed/sqlitedb
134  creating new cache entry
135  lookup yielded: name=it's id=its 
136 search_tidyup called
137 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
138 Exim version x.yz ....
139 changed uid/gid: forcing real = effective
140   uid=uuuu gid=CALLER_GID pid=p1235
141 configuration file is TESTSUITE/test-config
142 admin user
143 changed uid/gid: privilege not needed
144   uid=EXIM_UID gid=EXIM_GID pid=p1235
145 seeking password data for user "CALLER": cache not available
146 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
147 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
148 sender address = CALLER@myhost.test.ex
149 sender_fullhost = [10.0.0.0]
150 sender_rcvhost = [10.0.0.0]
151 host in hosts_connection_nolog? no (option unset)
152 LOG: smtp_connection MAIN
153   SMTP connection from [10.0.0.0]
154 host in host_lookup? no (option unset)
155 set_process_info: pppp handling incoming connection from [10.0.0.0]
156 host in host_reject_connection? no (option unset)
157 host in sender_unqualified_hosts? no (option unset)
158 host in recipient_unqualified_hosts? no (option unset)
159 host in helo_verify_hosts? no (option unset)
160 host in helo_try_verify_hosts? no (option unset)
161 host in helo_accept_junk_hosts? no (option unset)
162 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
163 smtp_setup_msg entered
164 SMTP<< helo test
165 test in helo_lookup_domains?
166  list element: @
167  list element: @[]
168 test in helo_lookup_domains? no (end of list)
169 sender_fullhost = (test) [10.0.0.0]
170 sender_rcvhost = [10.0.0.0] (helo=test)
171 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
172 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
173 SMTP<< mail from:<a@b>
174 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
175 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
176 SMTP>> 250 OK
177 SMTP<< rcpt to:<c@d>
178 using ACL "check_recipient"
179 processing "accept" (TESTSUITE/test-config 32)
180 check domains = +local_domains
181 d in "+local_domains"?
182  list element: +local_domains
183   start sublist local_domains
184    d in "@"?
185    ╎list element: @
186    d in "@"? no (end of list)
187   end sublist local_domains
188 d in "+local_domains"? no (end of list)
189 accept: condition test failed in ACL "check_recipient"
190 processing "accept" (TESTSUITE/test-config 33)
191 check hosts = +relay_hosts
192 host in "+relay_hosts"?
193  list element: +relay_hosts
194   start sublist relay_hosts
195   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
196    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
197    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
198    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
199    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
200    ╎LRU list:
201    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
202    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
203    ╎file lookup required for select * from them where id='10.0.0.0'
204    ╎  in TESTSUITE/aux-fixed/sqlitedb
205    ╎lookup forced cache cleanup
206    ╎lookup failed
207   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
208   end sublist relay_hosts
209 host in "+relay_hosts"? no (end of list)
210 accept: condition test failed in ACL "check_recipient"
211 processing "deny" (TESTSUITE/test-config 34)
212   message: relay not permitted
213 deny: condition test succeeded in ACL "check_recipient"
214 end of ACL "check_recipient": DENY
215 SMTP>> 550 relay not permitted
216 LOG: MAIN REJECT
217   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
218 SMTP<< rcpt to:<c@d>
219 using ACL "check_recipient"
220 processing "accept" (TESTSUITE/test-config 32)
221 check domains = +local_domains
222 d in "+local_domains"?
223  list element: +local_domains
224   start sublist local_domains
225    d in "@"?
226    ╎list element: @
227    d in "@"? no (end of list)
228   end sublist local_domains
229 d in "+local_domains"? no (end of list)
230 accept: condition test failed in ACL "check_recipient"
231 processing "accept" (TESTSUITE/test-config 33)
232 check hosts = +relay_hosts
233 host in "+relay_hosts"?
234  list element: +relay_hosts
235   start sublist relay_hosts
236   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"?
237    ╎list element: sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'
238    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
239    ╎  cached open
240    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
241    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts="file=TESTSUITE/aux-fixed/sqlitedb"
242    ╎LRU list:
243    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
244    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts="file=TESTSUITE/aux-fixed/sqlitedb"
245    ╎file lookup required for select * from them where id='10.0.0.0'
246    ╎  in TESTSUITE/aux-fixed/sqlitedb
247    ╎lookup forced cache cleanup
248    ╎lookup failed
249   host in "sqlite,file=TESTSUITE/aux-fixed/sqlitedb; select * from them where id='10.0.0.0'"? no (end of list)
250   end sublist relay_hosts
251 host in "+relay_hosts"? no (end of list)
252 accept: condition test failed in ACL "check_recipient"
253 processing "deny" (TESTSUITE/test-config 34)
254   message: relay not permitted
255 deny: condition test succeeded in ACL "check_recipient"
256 end of ACL "check_recipient": DENY
257 SMTP>> 550 relay not permitted
258 LOG: MAIN REJECT
259   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
260 SMTP<< quit
261 SMTP>> 221 myhost.test.ex closing connection
262 LOG: smtp_connection MAIN
263   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
264 search_tidyup called
265 >>>>>>>>>>>>>>>> Exim pid=p1235 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
266 Exim version x.yz ....
267 changed uid/gid: forcing real = effective
268   uid=uuuu gid=CALLER_GID pid=p1236
269 configuration file is TESTSUITE/test-config
270 admin user
271 changed uid/gid: privilege not needed
272   uid=EXIM_UID gid=EXIM_GID pid=p1236
273 seeking password data for user "CALLER": cache not available
274 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
275 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
276 sender address = CALLER@myhost.test.ex
277 sender_fullhost = [10.0.0.0]
278 sender_rcvhost = [10.0.0.0]
279 host in hosts_connection_nolog? no (option unset)
280 LOG: smtp_connection MAIN
281   SMTP connection from [10.0.0.0]
282 host in host_lookup? no (option unset)
283 set_process_info: pppp handling incoming connection from [10.0.0.0]
284 host in host_reject_connection? no (option unset)
285 host in sender_unqualified_hosts? no (option unset)
286 host in recipient_unqualified_hosts? no (option unset)
287 host in helo_verify_hosts? no (option unset)
288 host in helo_try_verify_hosts? no (option unset)
289 host in helo_accept_junk_hosts? no (option unset)
290 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
291 smtp_setup_msg entered
292 SMTP<< helo test
293 test in helo_lookup_domains?
294  list element: @
295  list element: @[]
296 test in helo_lookup_domains? no (end of list)
297 sender_fullhost = (test) [10.0.0.0]
298 sender_rcvhost = [10.0.0.0] (helo=test)
299 set_process_info: pppp handling incoming connection from (test) [10.0.0.0]
300 SMTP>> 250 myhost.test.ex Hello test [10.0.0.0]
301 SMTP<< mail from:<a@b>
302 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
303 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
304 SMTP>> 250 OK
305 SMTP<< rcpt to:<c@d>
306 using ACL "check_recipient"
307 processing "accept" (TESTSUITE/test-config 32)
308 check domains = +local_domains
309 d in "+local_domains"?
310  list element: +local_domains
311   start sublist local_domains
312    d in "@"?
313    ╎list element: @
314    d in "@"? no (end of list)
315   end sublist local_domains
316 d in "+local_domains"? no (end of list)
317 accept: condition test failed in ACL "check_recipient"
318 processing "accept" (TESTSUITE/test-config 33)
319 check hosts = +relay_hosts
320 host in "+relay_hosts"?
321  list element: +relay_hosts
322   start sublist relay_hosts
323   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
324    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
325    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
326    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
327    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
328    ╎LRU list:
329    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
330    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
331    ╎file lookup required for select * from them where id='10.0.0.0'
332    ╎  in TESTSUITE/aux-fixed/sqlitedb
333    ╎lookup forced cache cleanup
334    ╎lookup failed
335   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
336   end sublist relay_hosts
337 host in "+relay_hosts"? no (end of list)
338 accept: condition test failed in ACL "check_recipient"
339 processing "deny" (TESTSUITE/test-config 34)
340   message: relay not permitted
341 deny: condition test succeeded in ACL "check_recipient"
342 end of ACL "check_recipient": DENY
343 SMTP>> 550 relay not permitted
344 LOG: MAIN REJECT
345   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
346 SMTP<< rcpt to:<c@d>
347 using ACL "check_recipient"
348 processing "accept" (TESTSUITE/test-config 32)
349 check domains = +local_domains
350 d in "+local_domains"?
351  list element: +local_domains
352   start sublist local_domains
353    d in "@"?
354    ╎list element: @
355    d in "@"? no (end of list)
356   end sublist local_domains
357 d in "+local_domains"? no (end of list)
358 accept: condition test failed in ACL "check_recipient"
359 processing "accept" (TESTSUITE/test-config 33)
360 check hosts = +relay_hosts
361 host in "+relay_hosts"?
362  list element: +relay_hosts
363   start sublist relay_hosts
364   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"?
365    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'
366    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
367    ╎  cached open
368    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
369    ╎  key="select * from them where id='10.0.0.0'" partial=-1 affix=NULL starflags=0 opts=NULL
370    ╎LRU list:
371    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
372    ╎  type=sqlite key="select * from them where id='10.0.0.0'" opts=NULL
373    ╎file lookup required for select * from them where id='10.0.0.0'
374    ╎  in TESTSUITE/aux-fixed/sqlitedb
375    ╎lookup forced cache cleanup
376    ╎lookup failed
377   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.0.0.0'"? no (end of list)
378   end sublist relay_hosts
379 host in "+relay_hosts"? no (end of list)
380 accept: condition test failed in ACL "check_recipient"
381 processing "deny" (TESTSUITE/test-config 34)
382   message: relay not permitted
383 deny: condition test succeeded in ACL "check_recipient"
384 end of ACL "check_recipient": DENY
385 SMTP>> 550 relay not permitted
386 LOG: MAIN REJECT
387   H=(test) [10.0.0.0] F=<a@b> rejected RCPT <c@d>: relay not permitted
388 SMTP<< quit
389 SMTP>> 221 myhost.test.ex closing connection
390 LOG: smtp_connection MAIN
391   SMTP connection from (test) [10.0.0.0] D=qqs closed by QUIT
392 search_tidyup called
393 >>>>>>>>>>>>>>>> Exim pid=p1236 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
394 Exim version x.yz ....
395 changed uid/gid: forcing real = effective
396   uid=uuuu gid=CALLER_GID pid=p1237
397 configuration file is TESTSUITE/test-config
398 admin user
399 changed uid/gid: privilege not needed
400   uid=EXIM_UID gid=EXIM_GID pid=p1237
401 seeking password data for user "CALLER": cache not available
402 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
403 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
404 sender address = CALLER@myhost.test.ex
405 sender_fullhost = [10.10.10.10]
406 sender_rcvhost = [10.10.10.10]
407 host in hosts_connection_nolog? no (option unset)
408 LOG: smtp_connection MAIN
409   SMTP connection from [10.10.10.10]
410 host in host_lookup? no (option unset)
411 set_process_info: pppp handling incoming connection from [10.10.10.10]
412 host in host_reject_connection? no (option unset)
413 host in sender_unqualified_hosts? no (option unset)
414 host in recipient_unqualified_hosts? no (option unset)
415 host in helo_verify_hosts? no (option unset)
416 host in helo_try_verify_hosts? no (option unset)
417 host in helo_accept_junk_hosts? no (option unset)
418 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
419 smtp_setup_msg entered
420 SMTP<< helo test
421 test in helo_lookup_domains?
422  list element: @
423  list element: @[]
424 test in helo_lookup_domains? no (end of list)
425 sender_fullhost = (test) [10.10.10.10]
426 sender_rcvhost = [10.10.10.10] (helo=test)
427 set_process_info: pppp handling incoming connection from (test) [10.10.10.10]
428 SMTP>> 250 myhost.test.ex Hello test [10.10.10.10]
429 SMTP<< mail from:<a@b>
430 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
431 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
432 SMTP>> 250 OK
433 SMTP<< rcpt to:<c@d>
434 using ACL "check_recipient"
435 processing "accept" (TESTSUITE/test-config 32)
436 check domains = +local_domains
437 d in "+local_domains"?
438  list element: +local_domains
439   start sublist local_domains
440    d in "@"?
441    ╎list element: @
442    d in "@"? no (end of list)
443   end sublist local_domains
444 d in "+local_domains"? no (end of list)
445 accept: condition test failed in ACL "check_recipient"
446 processing "accept" (TESTSUITE/test-config 33)
447 check hosts = +relay_hosts
448 host in "+relay_hosts"?
449  list element: +relay_hosts
450   start sublist relay_hosts
451   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
452    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
453    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
454    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
455    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
456    ╎LRU list:
457    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
458    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
459    ╎file lookup required for select * from them where id='10.10.10.10'
460    ╎  in TESTSUITE/aux-fixed/sqlitedb
461    ╎creating new cache entry
462    ╎lookup yielded: name=ok id=10.10.10.10 
463    ╎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'")
464   end sublist relay_hosts
465   host in "+relay_hosts"? yes (matched "+relay_hosts")
466 accept: condition test succeeded in ACL "check_recipient"
467 end of ACL "check_recipient": ACCEPT
468 SMTP>> 250 Accepted
469 SMTP<< rcpt to:<c@d>
470 using ACL "check_recipient"
471 processing "accept" (TESTSUITE/test-config 32)
472 check domains = +local_domains
473 d in "+local_domains"?
474  list element: +local_domains
475   start sublist local_domains
476    d in "@"?
477    ╎list element: @
478    d in "@"? no (end of list)
479   end sublist local_domains
480 d in "+local_domains"? no (end of list)
481 accept: condition test failed in ACL "check_recipient"
482 processing "accept" (TESTSUITE/test-config 33)
483 check hosts = +relay_hosts
484 host in "+relay_hosts"?
485  list element: +relay_hosts
486   start sublist relay_hosts
487   host in "sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'"?
488    ╎list element: sqlite;TESTSUITE/aux-fixed/sqlitedb select * from them where id='10.10.10.10'
489    ╎search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
490    ╎  cached open
491    ╎search_find: file="TESTSUITE/aux-fixed/sqlitedb"
492    ╎  key="select * from them where id='10.10.10.10'" partial=-1 affix=NULL starflags=0 opts=NULL
493    ╎LRU list:
494    ╎internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
495    ╎  type=sqlite key="select * from them where id='10.10.10.10'" opts=NULL
496    ╎cached data used for lookup of select * from them where id='10.10.10.10'
497    ╎  in TESTSUITE/aux-fixed/sqlitedb
498    ╎lookup yielded: name=ok id=10.10.10.10 
499    ╎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'")
500   end sublist relay_hosts
501   host in "+relay_hosts"? yes (matched "+relay_hosts")
502 accept: condition test succeeded in ACL "check_recipient"
503 end of ACL "check_recipient": ACCEPT
504 SMTP>> 250 Accepted
505 SMTP<< quit
506 SMTP>> 221 myhost.test.ex closing connection
507 LOG: smtp_connection MAIN
508   SMTP connection from (test) [10.10.10.10] D=qqs closed by QUIT
509 search_tidyup called
510 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
511 Exim version x.yz ....
512 changed uid/gid: forcing real = effective
513   uid=uuuu gid=CALLER_GID pid=p1238
514 configuration file is TESTSUITE/test-config
515 admin user
516 changed uid/gid: privilege not needed
517   uid=EXIM_UID gid=EXIM_GID pid=p1238
518 seeking password data for user "CALLER": cache not available
519 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
520 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
521 sender address = CALLER@myhost.test.ex
522 set_process_info: pppp accepting a local non-SMTP message from <CALLER@myhost.test.ex>
523 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
524 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
525 Sender: CALLER@myhost.test.ex
526 Recipients:
527   userx
528 search_tidyup called
529 >>Headers received:
530
531 qualify & rewrite recipients list
532 global rewrite rules
533 rewrite headers
534  rewrite_one_header: type=F:
535    From: CALLER_NAME <CALLER@myhost.test.ex>
536 search_tidyup called
537 >>Headers after rewriting and local additions:
538 I Message-Id: <E10HmaX-000000005vi-0000@myhost.test.ex>
539 F From: CALLER_NAME <CALLER@myhost.test.ex>
540   Date: Tue, 2 Mar 1999 09:44:33 +0000
541
542 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
543 Data file written for message 10HmaX-000000005vi-0000
544 >>Generated Received: header line
545 P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
546         (envelope-from <CALLER@myhost.test.ex>)
547         id 10HmaX-000000005vi-0000
548         for userx@myhost.test.ex;
549         Tue, 2 Mar 1999 09:44:33 +0000
550 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
551 DSN: **** SPOOL_OUT - address: <userx@myhost.test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
552 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
553 Size of headers = sss
554 LOG: MAIN
555   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
556 created log directory TESTSUITE/spool/log
557 search_tidyup called
558 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -DOPT=y -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -odi -Mc 10HmaX-000000005vi-0000
559 Exim version x.yz ....
560 changed uid/gid: forcing real = effective
561   uid=uuuu gid=EXIM_GID pid=p1239
562 configuration file is TESTSUITE/test-config
563 trusted user
564 admin user
565 dropping to exim gid; retaining priv uid
566 seeking password data for user "CALLER": cache not available
567 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
568 set_process_info: pppp delivering specified messages
569 set_process_info: pppp delivering 10HmaX-000000005vi-0000
570 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
571 reading spool file 10HmaX-000000005vi-0000-H
572 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@myhost.test.ex
573 sender_local=1 ident=CALLER
574 Non-recipients:
575  Empty Tree
576 ---- End of tree ----
577 recipients_count=1
578 **** SPOOL_IN - No additional fields
579 body_linecount=1 message_linecount=8
580 DSN: set orcpt:   flags: 0x0
581 Delivery address list:
582   userx@myhost.test.ex 
583  locking TESTSUITE/spool/db/retry.lockfile
584  locked  TESTSUITE/spool/db/retry.lockfile
585  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
586  returned from EXIM_DBOPEN: (nil)
587  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
588 no retry data available
589 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
590 Considering: userx@myhost.test.ex
591 unique = userx@myhost.test.ex
592 no   domain  retry record
593 no   address retry record
594 userx@myhost.test.ex: queued for routing
595 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
596 routing userx@myhost.test.ex
597 --------> r1 router <--------
598 local_part=userx domain=myhost.test.ex
599 processing address_data
600  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
601  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
602    key="select name from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
603  LRU list:
604  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
605    type=sqlite key="select name from them where id='userx'" opts=NULL
606  file lookup required for select name from them where id='userx'
607    in TESTSUITE/aux-fixed/sqlitedb
608  creating new cache entry
609  lookup yielded: Ayen Other
610 calling r1 router
611 r1 router called for userx@myhost.test.ex
612   domain = myhost.test.ex
613 set transport t1
614 queued for t1 transport: local_part = userx
615 domain = myhost.test.ex
616   errors_to=NULL
617   domain_data=NULL local_part_data=NULL
618 routed by r1 router
619   envelope to: userx@myhost.test.ex
620   transport: t1
621 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
622 After routing:
623   Local deliveries:
624     userx@myhost.test.ex
625   Remote deliveries:
626   Failed addresses:
627   Deferred addresses:
628 search_tidyup called
629 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
630 --------> userx@myhost.test.ex <--------
631  locking TESTSUITE/spool/db/retry.lockfile
632  locked  TESTSUITE/spool/db/retry.lockfile
633  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
634  returned from EXIM_DBOPEN: (nil)
635  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
636 no retry data available
637 search_tidyup called
638 changed uid/gid: local delivery to userx <userx@myhost.test.ex> transport=t1
639   uid=CALLER_UID gid=CALLER_GID pid=p1240
640   home=NULL current=/
641 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to userx using t1
642 appendfile transport entered
643  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
644  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
645    key="select id from them where id='userx'" partial=-1 affix=NULL starflags=0 opts=NULL
646  LRU list:
647  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
648    type=sqlite key="select id from them where id='userx'" opts=NULL
649  file lookup required for select id from them where id='userx'
650    in TESTSUITE/aux-fixed/sqlitedb
651  creating new cache entry
652  lookup yielded: userx
653 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
654   file=TESTSUITE/test-mail/userx format=unix
655   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
656   message_suffix=\n
657   maildir_use_size_file=no
658   locking by lockfile fcntl 
659 lock name: TESTSUITE/test-mail/userx.lock
660 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
661 lock file created
662 mailbox TESTSUITE/test-mail/userx is locked
663 writing to file TESTSUITE/test-mail/userx
664 writing data block fd=dddd size=sss timeout=0
665 cannot use sendfile for body: spoolfile not wireformat
666 writing data block fd=dddd size=sss timeout=0
667 writing data block fd=dddd size=sss timeout=0
668 appendfile yields 0 with errno=dd more_errno=dd
669 search_tidyup called
670 journalling userx@myhost.test.ex
671 t1 transport returned OK for userx@myhost.test.ex
672 post-process userx@myhost.test.ex (0)
673 userx@myhost.test.ex delivered
674 LOG: MAIN
675   => userx <userx@myhost.test.ex> R=r1 T=t1
676 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
677 changed uid/gid: post-delivery tidying
678   uid=EXIM_UID gid=EXIM_GID pid=p1239
679 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
680 Processing retry items
681 Succeeded addresses:
682  userx@myhost.test.ex: no retry items
683 Failed addresses:
684 Deferred addresses:
685 end of retry processing
686 DSN: processing router : r1
687 DSN: processing successful delivery address: userx@myhost.test.ex
688 DSN: Sender_address: CALLER@myhost.test.ex
689 DSN: orcpt: NULL  flags: 0x0
690 DSN: envid: NULL  ret: 0
691 DSN: Final recipient: userx@myhost.test.ex
692 DSN: Remote SMTP server supports DSN: 0
693 DSN: not sending DSN success message
694 LOG: MAIN
695   Completed
696 end delivery of 10HmaX-000000005vi-0000
697 search_tidyup called
698 search_tidyup called
699 >>>>>>>>>>>>>>>> Exim pid=p1239 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
700 search_tidyup called
701 >>>>>>>>>>>>>>>> Exim pid=p1238 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
702 Exim version x.yz ....
703 configuration file is TESTSUITE/test-config
704 admin user
705 dropping to exim gid; retaining priv uid
706  search_open: sqlite "TESTSUITE/aux-fixed/sqlitedb"
707  search_find: file="TESTSUITE/aux-fixed/sqlitedb"
708    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
709  LRU list:
710  internal_search_find: file="TESTSUITE/aux-fixed/sqlitedb"
711    type=sqlite key="select name from them where id='userx';" opts=NULL
712  file lookup required for select name from them where id='userx';
713    in TESTSUITE/aux-fixed/sqlitedb
714  creating new cache entry
715  lookup yielded: Ayen Other
716 search_tidyup called
717 >>>>>>>>>>>>>>>> Exim pid=p1241 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
718 Exim version x.yz ....
719 configuration file is TESTSUITE/test-config
720 admin user
721 dropping to exim gid; retaining priv uid
722  search_open: sqlite "NULL"
723  Using sqlite_dbfile: TESTSUITE/aux-fixed/sqlitedb
724  search_find: file="NULL"
725    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
726  LRU list:
727  internal_search_find: file="NULL"
728    type=sqlite key="select name from them where id='userx';" opts=NULL
729  database lookup required for select name from them where id='userx';
730  creating new cache entry
731  lookup yielded: Ayen Other
732 search_tidyup called
733 >>>>>>>>>>>>>>>> Exim pid=p1242 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
734 Exim version x.yz ....
735 configuration file is TESTSUITE/test-config
736 admin user
737 dropping to exim gid; retaining priv uid
738  search_open: sqlite "NULL"
739  Using sqlite_dbfile: nonabsolute_filename
740 search_tidyup called
741 >>>>>>>>>>>>>>>> Exim pid=p1243 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
742 Exim version x.yz ....
743 configuration file is TESTSUITE/test-config
744 admin user
745 dropping to exim gid; retaining priv uid
746  search_open: sqlite "NULL"
747  Using sqlite_dbfile: TESTSUITE/missingfile
748  search_find: file="NULL"
749    key="select name from them where id='userx';" partial=-1 affix=NULL starflags=0 opts=NULL
750  LRU list:
751  internal_search_find: file="NULL"
752    type=sqlite key="select name from them where id='userx';" opts=NULL
753  database lookup required for select name from them where id='userx';
754  sqlite3_exec failed: no such table: them
755  creating new cache entry
756  lookup failed
757 search_tidyup called
758 >>>>>>>>>>>>>>>> Exim pid=p1244 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>