Testsuite: cutthrough vs. callout cache
[exim.git] / test / stderr / 0038
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 >>> test.ex in helo_lookup_domains? no (end of list)
10 >>> host in dsn_advertise_hosts? no (option unset)
11 >>> host in pipelining_advertise_hosts? yes (matched "*")
12 >>> using ACL "check_rcpt"
13 >>> processing "warn"
14 >>> check ratelimit = 0/1h/strict
15 >>> ratelimit condition count=1 0.0/1h/per_mail/V4NET.9.8.7
16 >>> ratelimit creating new rate data block
17 >>> ratelimit initializing new key's rate data
18 >>> ratelimit db updated
19 >>> ratelimit computed rate 1.0
20 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
21 >>> warn: condition test succeeded in ACL "check_rcpt"
22 LOG: H=(test.ex) [V4NET.9.8.7] Warning: RCPT: sender_rate=1.0 sender_rate_limit=0 sender_rate_period=1h
23 >>> processing "accept"
24 >>> accept: condition test succeeded in ACL "check_rcpt"
25 >>> end of ACL "check_rcpt": ACCEPT
26 >>> host in ignore_fromline_hosts? no (option unset)
27 >>> using ACL "check_data"
28 >>> processing "warn"
29 >>> check ratelimit = 0/1h/per_byte/strict
30 >>> ratelimit condition count=19 0.0/1h/per_mail/V4NET.9.8.7
31 >>> ratelimit found pre-computed rate 1.0
32 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
33 >>> warn: condition test succeeded in ACL "check_data"
34 LOG: 10HmaX-0005vi-00 H=(test.ex) [V4NET.9.8.7] Warning: DATA: sender_rate=1.0 sender_rate_limit=0 sender_rate_period=1h
35 >>> processing "deny"
36 >>> deny: condition test succeeded in ACL "check_data"
37 >>> end of ACL "check_data": DENY
38 LOG: 10HmaX-0005vi-00 H=(test.ex) [V4NET.9.8.7] F=<> rejected after DATA
39 >>> host in hosts_connection_nolog? no (option unset)
40 >>> host in host_lookup? no (option unset)
41 >>> host in host_reject_connection? no (option unset)
42 >>> host in sender_unqualified_hosts? no (option unset)
43 >>> host in recipient_unqualified_hosts? no (option unset)
44 >>> host in helo_verify_hosts? no (option unset)
45 >>> host in helo_try_verify_hosts? no (option unset)
46 >>> host in helo_accept_junk_hosts? no (option unset)
47 >>> test.ex in helo_lookup_domains? no (end of list)
48 >>> host in dsn_advertise_hosts? no (option unset)
49 >>> host in pipelining_advertise_hosts? yes (matched "*")
50 >>> using ACL "check_rcpt"
51 >>> processing "warn"
52 >>> check ratelimit = 0/1h/strict
53 >>> ratelimit condition count=1 0.0/1h/per_mail/V4NET.9.8.7
54 >>> ratelimit found key in database
55 >>> ratelimit db updated
56 >>> ratelimit computed rate 2.0
57 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
58 >>> warn: condition test succeeded in ACL "check_rcpt"
59 LOG: H=(test.ex) [V4NET.9.8.7] Warning: RCPT: sender_rate=2.0 sender_rate_limit=0 sender_rate_period=1h
60 >>> processing "accept"
61 >>> accept: condition test succeeded in ACL "check_rcpt"
62 >>> end of ACL "check_rcpt": ACCEPT
63 >>> host in ignore_fromline_hosts? no (option unset)
64 >>> using ACL "check_data"
65 >>> processing "warn"
66 >>> check ratelimit = 0/1h/per_byte/strict
67 >>> ratelimit condition count=19 0.0/1h/per_mail/V4NET.9.8.7
68 >>> ratelimit found pre-computed rate 2.0
69 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
70 >>> warn: condition test succeeded in ACL "check_data"
71 LOG: 10HmaY-0005vi-00 H=(test.ex) [V4NET.9.8.7] Warning: DATA: sender_rate=2.0 sender_rate_limit=0 sender_rate_period=1h
72 >>> processing "deny"
73 >>> deny: condition test succeeded in ACL "check_data"
74 >>> end of ACL "check_data": DENY
75 LOG: 10HmaY-0005vi-00 H=(test.ex) [V4NET.9.8.7] F=<> rejected after DATA
76 >>> host in hosts_connection_nolog? no (option unset)
77 >>> host in host_lookup? no (option unset)
78 >>> host in host_reject_connection? no (option unset)
79 >>> host in sender_unqualified_hosts? no (option unset)
80 >>> host in recipient_unqualified_hosts? no (option unset)
81 >>> host in helo_verify_hosts? no (option unset)
82 >>> host in helo_try_verify_hosts? no (option unset)
83 >>> host in helo_accept_junk_hosts? no (option unset)
84 >>> test.ex in helo_lookup_domains? no (end of list)
85 >>> host in dsn_advertise_hosts? no (option unset)
86 >>> host in pipelining_advertise_hosts? yes (matched "*")
87 >>> using ACL "check_rcpt"
88 >>> processing "warn"
89 >>> check ratelimit = 0/1h/per_conn/strict
90 >>> ratelimit condition count=1 0.0/1h/per_conn/V4NET.9.8.7
91 >>> ratelimit creating new rate data block
92 >>> ratelimit initializing new key's rate data
93 >>> ratelimit db updated
94 >>> ratelimit computed rate 1.0
95 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
96 >>> warn: condition test succeeded in ACL "check_rcpt"
97 LOG: H=(test.ex) [V4NET.9.8.7] Warning: RCPT: sender_rate=1.0 sender_rate_limit=0 sender_rate_period=1h
98 >>> processing "accept"
99 >>> accept: condition test succeeded in ACL "check_rcpt"
100 >>> end of ACL "check_rcpt": ACCEPT
101 >>> host in ignore_fromline_hosts? no (option unset)
102 >>> using ACL "check_data"
103 >>> processing "warn"
104 >>> check ratelimit = 0/1h/per_conn/strict
105 >>> ratelimit condition count=1 0.0/1h/per_conn/V4NET.9.8.7
106 >>> ratelimit found pre-computed rate 1.0
107 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
108 >>> warn: condition test succeeded in ACL "check_data"
109 LOG: 10HmaZ-0005vi-00 H=(test.ex) [V4NET.9.8.7] Warning: DATA: sender_rate=1.0 sender_rate_limit=0 sender_rate_period=1h
110 >>> processing "deny"
111 >>> deny: condition test succeeded in ACL "check_data"
112 >>> end of ACL "check_data": DENY
113 LOG: 10HmaZ-0005vi-00 H=(test.ex) [V4NET.9.8.7] F=<> rejected after DATA
114 >>> host in hosts_connection_nolog? no (option unset)
115 >>> host in host_lookup? no (option unset)
116 >>> host in host_reject_connection? no (option unset)
117 >>> host in sender_unqualified_hosts? no (option unset)
118 >>> host in recipient_unqualified_hosts? no (option unset)
119 >>> host in helo_verify_hosts? no (option unset)
120 >>> host in helo_try_verify_hosts? no (option unset)
121 >>> host in helo_accept_junk_hosts? no (option unset)
122 >>> test.ex in helo_lookup_domains? no (end of list)
123 >>> host in dsn_advertise_hosts? no (option unset)
124 >>> host in pipelining_advertise_hosts? yes (matched "*")
125 >>> using ACL "check_rcpt"
126 >>> processing "warn"
127 >>> check ratelimit = 0/1h/per_conn/strict
128 >>> ratelimit condition count=1 0.0/1h/per_conn/V4NET.9.8.7
129 >>> ratelimit found key in database
130 >>> ratelimit db updated
131 >>> ratelimit computed rate 2.0
132 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
133 >>> warn: condition test succeeded in ACL "check_rcpt"
134 LOG: H=(test.ex) [V4NET.9.8.7] Warning: RCPT: sender_rate=2.0 sender_rate_limit=0 sender_rate_period=1h
135 >>> processing "accept"
136 >>> accept: condition test succeeded in ACL "check_rcpt"
137 >>> end of ACL "check_rcpt": ACCEPT
138 >>> host in ignore_fromline_hosts? no (option unset)
139 >>> using ACL "check_data"
140 >>> processing "warn"
141 >>> check ratelimit = 0/1h/per_conn/strict
142 >>> ratelimit condition count=1 0.0/1h/per_conn/V4NET.9.8.7
143 >>> ratelimit found pre-computed rate 2.0
144 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
145 >>> warn: condition test succeeded in ACL "check_data"
146 LOG: 10HmbA-0005vi-00 H=(test.ex) [V4NET.9.8.7] Warning: DATA: sender_rate=2.0 sender_rate_limit=0 sender_rate_period=1h
147 >>> processing "deny"
148 >>> deny: condition test succeeded in ACL "check_data"
149 >>> end of ACL "check_data": DENY
150 LOG: 10HmbA-0005vi-00 H=(test.ex) [V4NET.9.8.7] F=<> rejected after DATA
151 >>> host in hosts_connection_nolog? no (option unset)
152 >>> host in host_lookup? no (option unset)
153 >>> host in host_reject_connection? no (option unset)
154 >>> host in sender_unqualified_hosts? no (option unset)
155 >>> host in recipient_unqualified_hosts? no (option unset)
156 >>> host in helo_verify_hosts? no (option unset)
157 >>> host in helo_try_verify_hosts? no (option unset)
158 >>> host in helo_accept_junk_hosts? no (option unset)
159 >>> test.ex in helo_lookup_domains? no (end of list)
160 >>> host in dsn_advertise_hosts? no (option unset)
161 >>> host in pipelining_advertise_hosts? yes (matched "*")
162 >>> using ACL "check_rcpt"
163 >>> processing "warn"
164 >>> check ratelimit = 0/1h/per_rcpt
165 >>> ratelimit condition count=1 0.0/1h/per_rcpt/V4NET.9.8.7
166 >>> ratelimit creating new rate data block
167 >>> ratelimit initializing new key's rate data
168 >>> ratelimit db not updated: over the limit, but leaky
169 >>> ratelimit computed rate 1.0
170 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
171 >>> warn: condition test succeeded in ACL "check_rcpt"
172 LOG: H=(test.ex) [V4NET.9.8.7] Warning: RCPT: sender_rate=1.0 sender_rate_limit=0 sender_rate_period=1h
173 >>> processing "accept"
174 >>> accept: condition test succeeded in ACL "check_rcpt"
175 >>> end of ACL "check_rcpt": ACCEPT
176 >>> using ACL "check_rcpt"
177 >>> processing "warn"
178 >>> check ratelimit = 0/1h/per_rcpt
179 >>> ratelimit condition count=1 0.0/1h/per_rcpt/V4NET.9.8.7
180 >>> ratelimit creating new rate data block
181 >>> ratelimit initializing new key's rate data
182 >>> ratelimit db not updated: over the limit, but leaky
183 >>> ratelimit computed rate 1.0
184 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
185 >>> warn: condition test succeeded in ACL "check_rcpt"
186 >>> processing "accept"
187 >>> accept: condition test succeeded in ACL "check_rcpt"
188 >>> end of ACL "check_rcpt": ACCEPT
189 >>> using ACL "check_rcpt"
190 >>> processing "warn"
191 >>> check ratelimit = 0/1h/per_rcpt
192 >>> ratelimit condition count=1 0.0/1h/per_rcpt/V4NET.9.8.7
193 >>> ratelimit creating new rate data block
194 >>> ratelimit initializing new key's rate data
195 >>> ratelimit db not updated: over the limit, but leaky
196 >>> ratelimit computed rate 1.0
197 >>> l_message: RCPT: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
198 >>> warn: condition test succeeded in ACL "check_rcpt"
199 >>> processing "accept"
200 >>> accept: condition test succeeded in ACL "check_rcpt"
201 >>> end of ACL "check_rcpt": ACCEPT
202 >>> host in ignore_fromline_hosts? no (option unset)
203 >>> using ACL "check_data"
204 >>> processing "warn"
205 >>> check ratelimit = 0/1h/per_conn
206 >>> ratelimit condition count=1 0.0/1h/per_conn/V4NET.9.8.7
207 >>> ratelimit found key in database
208 >>> ratelimit db not updated: over the limit, but leaky
209 >>> ratelimit computed rate 3.0
210 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
211 >>> warn: condition test succeeded in ACL "check_data"
212 LOG: 10HmbB-0005vi-00 H=(test.ex) [V4NET.9.8.7] Warning: DATA: sender_rate=3.0 sender_rate_limit=0 sender_rate_period=1h
213 >>> processing "deny"
214 >>> deny: condition test succeeded in ACL "check_data"
215 >>> end of ACL "check_data": DENY
216 LOG: 10HmbB-0005vi-00 H=(test.ex) [V4NET.9.8.7] F=<> rejected after DATA
217 >>> host in hosts_connection_nolog? no (option unset)
218 >>> host in host_lookup? no (option unset)
219 >>> host in host_reject_connection? no (option unset)
220 >>> host in sender_unqualified_hosts? no (option unset)
221 >>> host in recipient_unqualified_hosts? no (option unset)
222 >>> host in helo_verify_hosts? no (option unset)
223 >>> host in helo_try_verify_hosts? no (option unset)
224 >>> host in helo_accept_junk_hosts? no (option unset)
225 >>> test.ex in helo_lookup_domains? no (end of list)
226 >>> host in dsn_advertise_hosts? no (option unset)
227 >>> host in pipelining_advertise_hosts? yes (matched "*")
228 >>> using ACL "check_rcpt2"
229 >>> processing "warn"
230 >>> check ratelimit = 1/1m/per_rcpt/noupdate
231 >>> ratelimit condition count=0 1.0/1m/per_rcpt/V4NET.9.8.6
232 >>> ratelimit creating new rate data block
233 >>> ratelimit initializing new key's rate data
234 >>> ratelimit db not updated: readonly mode
235 >>> ratelimit computed rate 0.0
236 >>> warn: condition test failed in ACL "check_rcpt2"
237 >>> processing "warn"
238 >>> check ratelimit = 1/1m/per_rcpt
239 >>> ratelimit condition count=1 1.0/1m/per_rcpt/V4NET.9.8.6
240 >>> ratelimit found pre-computed rate 0.0
241 >>> warn: condition test failed in ACL "check_rcpt2"
242 >>> processing "accept"
243 >>> accept: condition test succeeded in ACL "check_rcpt2"
244 >>> end of ACL "check_rcpt2": ACCEPT
245 >>> using ACL "check_rcpt2"
246 >>> processing "warn"
247 >>> check ratelimit = 1/1m/per_rcpt/noupdate
248 >>> ratelimit condition count=0 1.0/1m/per_rcpt/V4NET.9.8.6
249 >>> ratelimit creating new rate data block
250 >>> ratelimit initializing new key's rate data
251 >>> ratelimit db not updated: readonly mode
252 >>> ratelimit computed rate 0.0
253 >>> warn: condition test failed in ACL "check_rcpt2"
254 >>> processing "warn"
255 >>> check ratelimit = 1/1m/per_rcpt
256 >>> ratelimit condition count=1 1.0/1m/per_rcpt/V4NET.9.8.6
257 >>> ratelimit found pre-computed rate 0.0
258 >>> warn: condition test failed in ACL "check_rcpt2"
259 >>> processing "accept"
260 >>> accept: condition test succeeded in ACL "check_rcpt2"
261 >>> end of ACL "check_rcpt2": ACCEPT
262 >>> host in ignore_fromline_hosts? no (option unset)
263 >>> using ACL "check_data"
264 >>> processing "warn"
265 >>> check ratelimit = 0/1h/per_byte/strict
266 >>> ratelimit condition count=19 0.0/1h/per_mail/V4NET.9.8.6
267 >>> ratelimit creating new rate data block
268 >>> ratelimit initializing new key's rate data
269 >>> ratelimit db updated
270 >>> ratelimit computed rate 19.0
271 >>> l_message: DATA: sender_rate=$sender_rate sender_rate_limit=$sender_rate_limit sender_rate_period=$sender_rate_period
272 >>> warn: condition test succeeded in ACL "check_data"
273 LOG: 10HmbC-0005vi-00 H=(test.ex) [V4NET.9.8.6] Warning: DATA: sender_rate=19.0 sender_rate_limit=0 sender_rate_period=1h
274 >>> processing "deny"
275 >>> deny: condition test succeeded in ACL "check_data"
276 >>> end of ACL "check_data": DENY
277 LOG: 10HmbC-0005vi-00 H=(test.ex) [V4NET.9.8.6] F=<> rejected after DATA