Testsuite: debugging Solaris run ordering issue. Log +received_recipients
[users/jgh/exim.git] / test / stderr / 0432
1 >>> host in hosts_connection_nolog? no (option unset)
2 >>> host in host_lookup? no (option unset)
3 >>> host in host_reject_connection? no (option unset)
4 >>> host in sender_unqualified_hosts? no (option unset)
5 >>> host in recipient_unqualified_hosts? no (option unset)
6 >>> host in helo_verify_hosts? no (option unset)
7 >>> host in helo_try_verify_hosts? no (option unset)
8 >>> host in helo_accept_junk_hosts? no (option unset)
9 >>> using ACL "mail"
10 >>> processing "accept"
11 >>> check verify = sender/callout=1s,maxwait=1s
12 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
13 >>> routing x@y
14 >>> calling r1 router
15 >>> routed by r1 router
16 >>> local host found for non-MX address
17 >>> fully qualified name = 127.0.0.1
18 MUNGED: ::1 will be omitted in what follows
19 >>> get[host|ipnode]byname[2] looked up these IP addresses:
20 >>>   name=127.0.0.1 address=127.0.0.1
21 >>> Attempting full verification using callout
22 >>> ... callout omitted by default when host testing
23 >>> (Use -bhc if you want the callouts to happen.)
24 >>> ----------- end verify ------------
25 >>> accept: condition test succeeded in ACL "mail"
26 >>> end of ACL "mail": ACCEPT
27 Exim version x.yz ....
28 changed uid/gid: forcing real = effective
29   uid=uuuu gid=CALLER_GID pid=pppp
30 configuration file is TESTSUITE/test-config
31 admin user
32 changed uid/gid: privilege not needed
33   uid=EXIM_UID gid=EXIM_GID pid=pppp
34 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
35 sender address = CALLER@myhost.test.ex
36 sender_fullhost = [1.2.3.4]
37 sender_rcvhost = [1.2.3.4]
38 host in hosts_connection_nolog? no (option unset)
39 LOG: smtp_connection MAIN
40   SMTP connection from [1.2.3.4]
41 host in host_lookup? no (option unset)
42 set_process_info: pppp handling incoming connection from [1.2.3.4]
43 host in host_reject_connection? no (option unset)
44 host in sender_unqualified_hosts? no (option unset)
45 host in recipient_unqualified_hosts? no (option unset)
46 host in helo_verify_hosts? no (option unset)
47 host in helo_try_verify_hosts? no (option unset)
48 host in helo_accept_junk_hosts? no (option unset)
49 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
50 smtp_setup_msg entered
51 SMTP<< mail from:<x@y>
52 using ACL "mail"
53 processing "accept"
54 check verify = sender/callout=1s,maxwait=1s
55 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
56 Verifying x@y
57 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
58 Considering x@y
59 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
60 routing x@y
61 --------> r1 router <--------
62 local_part=x domain=y
63 calling r1 router
64 r1 router called for x@y
65   domain = y
66 set transport t1
67 queued for t1 transport: local_part = x
68 domain = y
69   errors_to=NULL
70   domain_data=NULL localpart_data=NULL
71 routed by r1 router
72   envelope to: x@y
73   transport: t1
74 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
75 local host found for non-MX address
76 fully qualified name = 127.0.0.1
77 MUNGED: ::1 will be omitted in what follows
78 get[host|ipnode]byname[2] looked up these IP addresses:
79   name=127.0.0.1 address=127.0.0.1
80 Attempting full verification using callout
81 locking TESTSUITE/spool/db/callout.lockfile
82 locked TESTSUITE/spool/db/callout.lockfile
83 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
84 returned from EXIM_DBOPEN
85 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
86 dbfn_read: key=y
87 callout cache: no domain record found
88 dbfn_read: key=x@y
89 callout cache: no address record found
90 interface=NULL port=1224
91 Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
92   SMTP<< 220 server ready
93 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
94   SMTP>> EHLO myhost.test.ex
95   SMTP<< 250 OK
96 127.0.0.1 in hosts_require_auth? no (option unset)
97   SMTP>> MAIL FROM:<>
98   SMTP<< 250 OK
99   SMTP>> RCPT TO:<x@y>
100   SMTP<< 250 OK
101   SMTP>> QUIT
102 locking TESTSUITE/spool/db/callout.lockfile
103 locked TESTSUITE/spool/db/callout.lockfile
104 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
105 returned from EXIM_DBOPEN
106 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
107 dbfn_write: key=y
108 wrote callout cache domain record:
109   result=1 postmaster=0 random=0
110 dbfn_write: key=x@y
111 wrote positive callout cache address record
112 ----------- end verify ------------
113 sender x@y verified ok
114 accept: condition test succeeded in ACL "mail"
115 end of ACL "mail": ACCEPT
116 SMTP>> 250 OK
117 SMTP<< quit
118 SMTP>> 221 myhost.test.ex closing connection
119 LOG: smtp_connection MAIN
120   SMTP connection from [1.2.3.4] closed by QUIT
121 search_tidyup called
122 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
123 Exim version x.yz ....
124 changed uid/gid: forcing real = effective
125   uid=uuuu gid=CALLER_GID pid=pppp
126 configuration file is TESTSUITE/test-config
127 admin user
128 changed uid/gid: privilege not needed
129   uid=EXIM_UID gid=EXIM_GID pid=pppp
130 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
131 sender address = CALLER@myhost.test.ex
132 sender_fullhost = [1.2.3.4]
133 sender_rcvhost = [1.2.3.4]
134 host in hosts_connection_nolog? no (option unset)
135 LOG: smtp_connection MAIN
136   SMTP connection from [1.2.3.4]
137 host in host_lookup? no (option unset)
138 set_process_info: pppp handling incoming connection from [1.2.3.4]
139 host in host_reject_connection? no (option unset)
140 host in sender_unqualified_hosts? no (option unset)
141 host in recipient_unqualified_hosts? no (option unset)
142 host in helo_verify_hosts? no (option unset)
143 host in helo_try_verify_hosts? no (option unset)
144 host in helo_accept_junk_hosts? no (option unset)
145 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
146 smtp_setup_msg entered
147 SMTP<< mail from:<x@y>
148 using ACL "mail"
149 processing "accept"
150 check verify = sender/callout=1s,maxwait=1s
151 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
152 Verifying x@y
153 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
154 Considering x@y
155 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
156 routing x@y
157 --------> r1 router <--------
158 local_part=x domain=y
159 calling r1 router
160 r1 router called for x@y
161   domain = y
162 set transport t1
163 queued for t1 transport: local_part = x
164 domain = y
165   errors_to=NULL
166   domain_data=NULL localpart_data=NULL
167 routed by r1 router
168   envelope to: x@y
169   transport: t1
170 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
171 local host found for non-MX address
172 fully qualified name = 127.0.0.1
173 MUNGED: ::1 will be omitted in what follows
174 get[host|ipnode]byname[2] looked up these IP addresses:
175   name=127.0.0.1 address=127.0.0.1
176 Attempting full verification using callout
177 locking TESTSUITE/spool/db/callout.lockfile
178 locked TESTSUITE/spool/db/callout.lockfile
179 EXIM_DBOPEN(TESTSUITE/spool/db/callout)
180 returned from EXIM_DBOPEN
181 opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
182 dbfn_read: key=y
183 callout cache: found domain record
184 dbfn_read: key=x@y
185 callout cache: found address record
186 callout cache: address record is positive
187 ----------- end verify ------------
188 sender x@y verified ok
189 accept: condition test succeeded in ACL "mail"
190 end of ACL "mail": ACCEPT
191 SMTP>> 250 OK
192 SMTP<< quit
193 SMTP>> 221 myhost.test.ex closing connection
194 LOG: smtp_connection MAIN
195   SMTP connection from [1.2.3.4] closed by QUIT
196 search_tidyup called
197 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
198 >>> host in hosts_connection_nolog? no (option unset)
199 >>> host in host_lookup? no (option unset)
200 >>> host in host_reject_connection? no (option unset)
201 >>> host in sender_unqualified_hosts? no (option unset)
202 >>> host in recipient_unqualified_hosts? no (option unset)
203 >>> host in helo_verify_hosts? no (option unset)
204 >>> host in helo_try_verify_hosts? no (option unset)
205 >>> host in helo_accept_junk_hosts? no (option unset)
206 >>> using ACL "mail"
207 >>> processing "accept"
208 >>> check verify = sender/callout=1s,maxwait=1s
209 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
210 >>> routing x@y
211 >>> calling r1 router
212 >>> routed by r1 router
213 >>> local host found for non-MX address
214 >>> fully qualified name = 127.0.0.1
215 MUNGED: ::1 will be omitted in what follows
216 >>> get[host|ipnode]byname[2] looked up these IP addresses:
217 >>>   name=127.0.0.1 address=127.0.0.1
218 >>> Attempting full verification using callout
219 >>> callout cache: found domain record
220 >>> callout cache: found address record
221 >>> callout cache: address record is positive
222 >>> ----------- end verify ------------
223 >>> accept: condition test succeeded in ACL "mail"
224 >>> end of ACL "mail": ACCEPT
225 >>> host in hosts_connection_nolog? no (option unset)
226 >>> host in host_lookup? no (option unset)
227 >>> host in host_reject_connection? no (option unset)
228 >>> host in sender_unqualified_hosts? no (option unset)
229 >>> host in recipient_unqualified_hosts? no (option unset)
230 >>> host in helo_verify_hosts? no (option unset)
231 >>> host in helo_try_verify_hosts? no (option unset)
232 >>> host in helo_accept_junk_hosts? no (option unset)
233 >>> using ACL "mail"
234 >>> processing "accept"
235 >>> check verify = sender/callout=1s,maxwait=1s
236 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
237 >>> routing a@b
238 >>> calling r1 router
239 >>> routed by r1 router
240 >>> local host found for non-MX address
241 >>> fully qualified name = 127.0.0.1
242 MUNGED: ::1 will be omitted in what follows
243 >>> get[host|ipnode]byname[2] looked up these IP addresses:
244 >>>   name=127.0.0.1 address=127.0.0.1
245 >>> Attempting full verification using callout
246 >>> callout cache: no domain record found
247 >>> callout cache: no address record found
248 >>> interface=NULL port=1224
249 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
250 >>>   SMTP<< 220 server ready
251 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
252 >>>   SMTP>> EHLO myhost.test.ex
253 >>>   SMTP<< 250 OK
254 >>> 127.0.0.1 in hosts_require_auth? no (option unset)
255 >>>   SMTP>> MAIL FROM:<>
256 >>>   SMTP<< 250 OK
257 >>>   SMTP>> RCPT TO:<a@b>
258 >>>   SMTP<< 250 OK
259 >>>   SMTP>> QUIT
260 >>> wrote callout cache domain record:
261 >>>   result=1 postmaster=0 random=0
262 >>> wrote positive callout cache address record
263 >>> ----------- end verify ------------
264 >>> accept: condition test succeeded in ACL "mail"
265 >>> end of ACL "mail": ACCEPT
266 >>> host in hosts_connection_nolog? no (option unset)
267 >>> host in host_lookup? no (option unset)
268 >>> host in host_reject_connection? no (option unset)
269 >>> host in sender_unqualified_hosts? no (option unset)
270 >>> host in recipient_unqualified_hosts? no (option unset)
271 >>> host in helo_verify_hosts? no (option unset)
272 >>> host in helo_try_verify_hosts? no (option unset)
273 >>> host in helo_accept_junk_hosts? no (option unset)
274 >>> using ACL "mail"
275 >>> processing "accept"
276 >>> check verify = sender/callout=1s,maxwait=1s
277 >>> >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
278 >>> routing p1@q
279 >>> calling r1 router
280 >>> routed by r1 router
281 >>> local host found for non-MX address
282 >>> fully qualified name = 127.0.0.1
283 MUNGED: ::1 will be omitted in what follows
284 >>> get[host|ipnode]byname[2] looked up these IP addresses:
285 >>>   name=127.0.0.1 address=127.0.0.1
286 >>> Attempting full verification using callout
287 >>> callout cache: no domain record found
288 >>> callout cache: no address record found
289 >>> interface=NULL port=1224
290 >>> Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
291 >>>   SMTP<< 220 server ready
292 >>> 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
293 >>>   SMTP>> EHLO myhost.test.ex
294 >>> SMTP timeout
295 >>> ----------- end verify ------------
296 >>> accept: condition test deferred in ACL "mail"
297 LOG: H=[1.2.3.4] sender verify defer for <p1@q>: Could not complete sender verify callout
298 LOG: H=[1.2.3.4] temporarily rejected MAIL <p1@q>: Could not complete sender verify callout