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