Hintsbd: fix locking
[exim.git] / test / stderr / 0398
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 SMTP message from <CALLER@test.ex>
14  in hosts_connection_nolog? no (option unset)
15 LOG: smtp_connection MAIN
16   SMTP connection from CALLER
17 SMTP>> 220 mail.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
18 smtp_setup_msg entered
19 SMTP<< helo test
20 SMTP>> 250 mail.test.ex Hello CALLER at test
21 SMTP<< mail from:<qq@remote>
22 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
23 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
24 SMTP>> 250 OK
25 SMTP<< rcpt to:<abc@local>
26 using ACL "rcpt"
27 processing "deny" (TESTSUITE/test-config 19)
28 check senders = qq@remote
29 qq@remote in "qq@remote"?
30  list element: qq@remote
31  address match test: subject=qq@remote pattern=qq@remote
32  remote in "remote"?
33   list element: remote
34   remote in "remote"? yes (matched "remote")
35  qq@remote in "qq@remote"? yes (matched "qq@remote")
36 check !verify = sender
37 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
38 Verifying qq@remote
39 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
40 Considering qq@remote
41 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
42 routing qq@remote
43 --------> r1 router <--------
44 local_part=qq domain=remote
45 checking domains
46 remote in domains?
47  list element: local
48 remote in domains? no (end of list)
49 r1 router skipped: domains mismatch
50 --------> r2 router <--------
51 local_part=qq domain=remote
52 checking domains
53 remote in domains?
54  list element: remote
55  remote in domains? yes (matched "remote")
56 calling r2 router
57 r2 router called for qq@remote
58   domain = remote
59 route_item = * 127.0.0.1
60 remote in "*"?
61  list element: *
62  remote in "*"? yes (matched "*")
63 original list of hosts = '127.0.0.1' options = ''
64 expanded list of hosts = '127.0.0.1' options = ''
65 set transport t2
66 finding IP address for 127.0.0.1
67 calling host_find_byname
68 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
69 local host found for non-MX address
70 fully qualified name = 127.0.0.1
71 MUNGED: ::1 will be omitted in what follows
72 get[host|ipnode]byname[2] looked up these IP addresses:
73   name=127.0.0.1 address=127.0.0.1
74 remote host address is the local host: remote: configured to try delivery anyway
75 queued for t2 transport: local_part = qq
76 domain = remote
77   errors_to=NULL
78   domain_data=remote local_part_data=NULL
79 routed by r2 router
80   envelope to: qq@remote
81   transport: t2
82   host 127.0.0.1 [127.0.0.1]
83 ----------- end verify ------------
84 sender qq@remote verified ok
85 deny: condition test failed in ACL "rcpt"
86 processing "warn" (TESTSUITE/test-config 21)
87 check senders = qq@remote
88 qq@remote in "qq@remote"?
89  list element: qq@remote
90  address match test: subject=qq@remote pattern=qq@remote
91  remote in "remote"?
92   list element: remote
93   remote in "remote"? yes (matched "remote")
94  qq@remote in "qq@remote"? yes (matched "qq@remote")
95 check !verify = sender/callout
96 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
97 Verifying qq@remote
98 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
99 Considering qq@remote
100 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
101 routing qq@remote
102 --------> r1 router <--------
103 local_part=qq domain=remote
104 checking domains
105 remote in domains?
106  list element: local
107 remote in domains? no (end of list)
108 r1 router skipped: domains mismatch
109 --------> r2 router <--------
110 local_part=qq domain=remote
111 checking domains
112 remote in domains?
113  list element: remote
114  remote in domains? yes (matched "remote")
115 calling r2 router
116 r2 router called for qq@remote
117   domain = remote
118 route_item = * 127.0.0.1
119 remote in "*"?
120  list element: *
121  remote in "*"? yes (matched "*")
122 original list of hosts = '127.0.0.1' options = ''
123 expanded list of hosts = '127.0.0.1' options = ''
124 finding IP address for 127.0.0.1
125 calling host_find_byname
126 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
127 local host found for non-MX address
128 fully qualified name = 127.0.0.1
129 MUNGED: ::1 will be omitted in what follows
130 get[host|ipnode]byname[2] looked up these IP addresses:
131   name=127.0.0.1 address=127.0.0.1
132 remote host address is the local host: remote: configured to try delivery anyway
133 queued for t2 transport: local_part = qq
134 domain = remote
135   errors_to=NULL
136   domain_data=remote local_part_data=NULL
137 routed by r2 router
138   envelope to: qq@remote
139   transport: t2
140   host 127.0.0.1 [127.0.0.1]
141 Attempting full verification using callout
142  locking TESTSUITE/spool/db/callout.lockfile
143  locked  TESTSUITE/spool/db/callout.lockfile
144  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
145  returned from EXIM_DBOPEN: 0xAAAAAAAA
146  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
147  dbfn_read: key=remote
148  dbfn_read: size 40 return
149  callout cache: found domain record for remote
150  dbfn_read: key=qq@remote
151  dbfn_read: null return
152  callout cache: no address record found for qq@remote
153  EXIM_DBCLOSE(0xAAAAAAAA)
154  closed hints database and lockfile
155 interface=NULL port=PORT_S
156 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
157  127.0.0.1 in hosts_try_fastopen?
158   list element: 
159 connected
160   SMTP<< 220 Server ready
161 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
162   SMTP>> EHLO mail.test.ex
163 cmd buf flush ddd bytes
164   SMTP<< 250 OK
165 not using PIPELINING
166 not using DSN
167 127.0.0.1 in hosts_require_auth? no (option unset)
168   SMTP>> MAIL FROM:<>
169 cmd buf flush ddd bytes
170   SMTP<< 250 OK
171   SMTP>> RCPT TO:<qq@remote>
172 cmd buf flush ddd bytes
173 sync_responses expect rcpt for qq@remote
174   SMTP<< 550 Unknown
175   SMTP>> QUIT
176 cmd buf flush ddd bytes
177   SMTP<< 250 OK
178   SMTP(close)>>
179 cmdlog: '220:EHLO:250:MAIL:250:RCPT:550:QUIT:250'
180  locking TESTSUITE/spool/db/callout.lockfile
181  locked  TESTSUITE/spool/db/callout.lockfile
182  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
183  returned from EXIM_DBOPEN: 0xAAAAAAAA
184  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
185  dbfn_write: key=remote datalen NNN
186  wrote callout cache domain record for remote:
187    result=1 postmaster=0 random=0
188  dbfn_write: key=qq@remote datalen NNN
189  wrote negative callout cache address record for qq@remote
190  EXIM_DBCLOSE(0xAAAAAAAA)
191  closed hints database and lockfile
192 ----------- end verify ------------
193 l_message: $acl_verify_message
194 warn: condition test succeeded in ACL "rcpt"
195 LOG: MAIN
196   U=CALLER Warning: Sender verify failed: 127.0.0.1 [127.0.0.1] : SMTP error from remote mail server after RCPT TO:<qq@remote>: 550 Unknown
197 processing "accept" (TESTSUITE/test-config 24)
198 check senders = qq@remote
199 qq@remote in "qq@remote"?
200  list element: qq@remote
201  address match test: subject=qq@remote pattern=qq@remote
202  remote in "remote"?
203   list element: remote
204   remote in "remote"? yes (matched "remote")
205  qq@remote in "qq@remote"? yes (matched "qq@remote")
206 accept: condition test succeeded in ACL "rcpt"
207 end of ACL "rcpt": ACCEPT
208 SMTP>> 250 Accepted
209 SMTP<< rcpt to:<xyz@local>
210 using ACL "rcpt"
211 processing "deny" (TESTSUITE/test-config 19)
212 check senders = qq@remote
213 qq@remote in "qq@remote"?
214  list element: qq@remote
215  address match test: subject=qq@remote pattern=qq@remote
216  remote in "remote"?
217   list element: remote
218   remote in "remote"? yes (matched "remote")
219  qq@remote in "qq@remote"? yes (matched "qq@remote")
220 check !verify = sender
221 using cached sender verify result
222 deny: condition test failed in ACL "rcpt"
223 processing "warn" (TESTSUITE/test-config 21)
224 check senders = qq@remote
225 qq@remote in "qq@remote"?
226  list element: qq@remote
227  address match test: subject=qq@remote pattern=qq@remote
228  remote in "remote"?
229   list element: remote
230   remote in "remote"? yes (matched "remote")
231  qq@remote in "qq@remote"? yes (matched "qq@remote")
232 check !verify = sender/callout
233 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
234 Verifying qq@remote
235 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
236 Considering qq@remote
237 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
238 routing qq@remote
239 --------> r1 router <--------
240 local_part=qq domain=remote
241 checking domains
242 remote in domains?
243  list element: local
244 remote in domains? no (end of list)
245 r1 router skipped: domains mismatch
246 --------> r2 router <--------
247 local_part=qq domain=remote
248 checking domains
249 remote in domains?
250  list element: remote
251  remote in domains? yes (matched "remote")
252 calling r2 router
253 r2 router called for qq@remote
254   domain = remote
255 route_item = * 127.0.0.1
256 remote in "*"?
257  list element: *
258  remote in "*"? yes (matched "*")
259 original list of hosts = '127.0.0.1' options = ''
260 expanded list of hosts = '127.0.0.1' options = ''
261 finding IP address for 127.0.0.1
262 calling host_find_byname
263 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
264 local host found for non-MX address
265 fully qualified name = 127.0.0.1
266 MUNGED: ::1 will be omitted in what follows
267 get[host|ipnode]byname[2] looked up these IP addresses:
268   name=127.0.0.1 address=127.0.0.1
269 remote host address is the local host: remote: configured to try delivery anyway
270 queued for t2 transport: local_part = qq
271 domain = remote
272   errors_to=NULL
273   domain_data=remote local_part_data=NULL
274 routed by r2 router
275   envelope to: qq@remote
276   transport: t2
277   host 127.0.0.1 [127.0.0.1]
278 Attempting full verification using callout
279  locking TESTSUITE/spool/db/callout.lockfile
280  locked  TESTSUITE/spool/db/callout.lockfile
281  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
282  returned from EXIM_DBOPEN: 0xAAAAAAAA
283  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
284  dbfn_read: key=remote
285  dbfn_read: size 40 return
286  callout cache: found domain record for remote
287  dbfn_read: key=qq@remote
288  dbfn_read: size 16 return
289  callout cache: found address record for qq@remote
290  callout cache: address record is negative
291  EXIM_DBCLOSE(0xAAAAAAAA)
292  closed hints database and lockfile
293 ----------- end verify ------------
294 l_message: $acl_verify_message
295 warn: condition test succeeded in ACL "rcpt"
296 LOG: MAIN
297   U=CALLER Warning: Sender verify failed
298 processing "accept" (TESTSUITE/test-config 24)
299 check senders = qq@remote
300 qq@remote in "qq@remote"?
301  list element: qq@remote
302  address match test: subject=qq@remote pattern=qq@remote
303  remote in "remote"?
304   list element: remote
305   remote in "remote"? yes (matched "remote")
306  qq@remote in "qq@remote"? yes (matched "qq@remote")
307 accept: condition test succeeded in ACL "rcpt"
308 end of ACL "rcpt": ACCEPT
309 SMTP>> 250 Accepted
310 SMTP<< quit
311 SMTP>> 221 mail.test.ex closing connection
312 LOG: smtp_connection MAIN
313   SMTP connection from CALLER D=qqs closed by QUIT
314 search_tidyup called
315 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>