redis lookup returns false for things that should be true
[users/jgh/exim.git] / test / stderr / 0374
1 Exim version x.yz ....
2 configuration file is TESTSUITE/test-config
3 admin user
4 LOG: MAIN
5   <= CALLER@myhost.test.ex U=CALLER P=local S=sss
6 created log directory TESTSUITE/spool/log
7 Exim version x.yz ....
8 configuration file is TESTSUITE/test-config
9 trusted user
10 admin user
11 locking TESTSUITE/spool/db/retry.lockfile
12 no retry data available
13 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
14 Considering: a1@myhost.test.ex
15 unique = a1@myhost.test.ex
16 a1@myhost.test.ex: queued for routing
17 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
18 Considering: b1@myhost.test.ex
19 unique = b1@myhost.test.ex
20 b1@myhost.test.ex: queued for routing
21 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
22 Considering: c1@myhost.test.ex
23 unique = c1@myhost.test.ex
24 c1@myhost.test.ex: queued for routing
25 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
26 Considering: d1@myhost.test.ex
27 unique = d1@myhost.test.ex
28 d1@myhost.test.ex: queued for routing
29 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
30 Considering: d2@myhost.test.ex
31 unique = d2@myhost.test.ex
32 d2@myhost.test.ex: queued for routing
33 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
34 Considering: d3@myhost.test.ex
35 unique = d3@myhost.test.ex
36 d3@myhost.test.ex: queued for routing
37 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
38 routing d3@myhost.test.ex
39 --------> u1 router <--------
40 local_part=d3 domain=myhost.test.ex
41 checking local_parts
42 u1 router skipped: local_parts mismatch
43 --------> ut2 router <--------
44 local_part=d3 domain=myhost.test.ex
45 checking local_parts
46 ut2 router skipped: local_parts mismatch
47 --------> ut3 router <--------
48 local_part=d3 domain=myhost.test.ex
49 checking local_parts
50 ut3 router skipped: local_parts mismatch
51 --------> ut4 router <--------
52 local_part=d3 domain=myhost.test.ex
53 checking local_parts
54 calling ut4 router
55 ut4 router called for d3@myhost.test.ex
56   domain = myhost.test.ex
57 set transport ut4
58 queued for ut4 transport: local_part = d3
59 domain = myhost.test.ex
60   errors_to=NULL
61   domain_data=NULL localpart_data=NULL
62 routed by ut4 router (unseen)
63   envelope to: d3@myhost.test.ex
64   transport: ut4
65 "unseen" set: replicated d3@myhost.test.ex
66 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
67 routing d2@myhost.test.ex
68 --------> u1 router <--------
69 local_part=d2 domain=myhost.test.ex
70 checking local_parts
71 u1 router skipped: local_parts mismatch
72 --------> ut2 router <--------
73 local_part=d2 domain=myhost.test.ex
74 checking local_parts
75 ut2 router skipped: local_parts mismatch
76 --------> ut3 router <--------
77 local_part=d2 domain=myhost.test.ex
78 checking local_parts
79 ut3 router skipped: local_parts mismatch
80 --------> ut4 router <--------
81 local_part=d2 domain=myhost.test.ex
82 checking local_parts
83 calling ut4 router
84 ut4 router called for d2@myhost.test.ex
85   domain = myhost.test.ex
86 queued for ut4 transport: local_part = d2
87 domain = myhost.test.ex
88   errors_to=NULL
89   domain_data=NULL localpart_data=NULL
90 routed by ut4 router (unseen)
91   envelope to: d2@myhost.test.ex
92   transport: ut4
93 "unseen" set: replicated d2@myhost.test.ex
94 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
95 routing d1@myhost.test.ex
96 --------> u1 router <--------
97 local_part=d1 domain=myhost.test.ex
98 checking local_parts
99 u1 router skipped: local_parts mismatch
100 --------> ut2 router <--------
101 local_part=d1 domain=myhost.test.ex
102 checking local_parts
103 ut2 router skipped: local_parts mismatch
104 --------> ut3 router <--------
105 local_part=d1 domain=myhost.test.ex
106 checking local_parts
107 ut3 router skipped: local_parts mismatch
108 --------> ut4 router <--------
109 local_part=d1 domain=myhost.test.ex
110 checking local_parts
111 calling ut4 router
112 ut4 router called for d1@myhost.test.ex
113   domain = myhost.test.ex
114 queued for ut4 transport: local_part = d1
115 domain = myhost.test.ex
116   errors_to=NULL
117   domain_data=NULL localpart_data=NULL
118 routed by ut4 router (unseen)
119   envelope to: d1@myhost.test.ex
120   transport: ut4
121 "unseen" set: replicated d1@myhost.test.ex
122 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
123 routing c1@myhost.test.ex
124 --------> u1 router <--------
125 local_part=c1 domain=myhost.test.ex
126 checking local_parts
127 u1 router skipped: local_parts mismatch
128 --------> ut2 router <--------
129 local_part=c1 domain=myhost.test.ex
130 checking local_parts
131 ut2 router skipped: local_parts mismatch
132 --------> ut3 router <--------
133 local_part=c1 domain=myhost.test.ex
134 checking local_parts
135 calling ut3 router
136 ut3 router called for c1@myhost.test.ex
137   domain = myhost.test.ex
138 set transport ut3
139 queued for ut3 transport: local_part = c1
140 domain = myhost.test.ex
141   errors_to=NULL
142   domain_data=NULL localpart_data=NULL
143 routed by ut3 router (unseen)
144   envelope to: c1@myhost.test.ex
145   transport: ut3
146 "unseen" set: replicated c1@myhost.test.ex
147 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
148 routing b1@myhost.test.ex
149 --------> u1 router <--------
150 local_part=b1 domain=myhost.test.ex
151 checking local_parts
152 u1 router skipped: local_parts mismatch
153 --------> ut2 router <--------
154 local_part=b1 domain=myhost.test.ex
155 checking local_parts
156 calling ut2 router
157 ut2 router called for b1@myhost.test.ex
158   domain = myhost.test.ex
159 set transport ut2
160 queued for ut2 transport: local_part = b1
161 domain = myhost.test.ex
162   errors_to=NULL
163   domain_data=NULL localpart_data=NULL
164 routed by ut2 router (unseen)
165   envelope to: b1@myhost.test.ex
166   transport: ut2
167 "unseen" set: replicated b1@myhost.test.ex
168 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
169 routing a1@myhost.test.ex
170 --------> u1 router <--------
171 local_part=a1 domain=myhost.test.ex
172 checking local_parts
173 calling u1 router
174 u1 router called for a1@myhost.test.ex
175   domain = myhost.test.ex
176 set transport ut1
177 queued for ut1 transport: local_part = a1
178 domain = myhost.test.ex
179   errors_to=NULL
180   domain_data=NULL localpart_data=NULL
181 routed by u1 router (unseen)
182   envelope to: a1@myhost.test.ex
183   transport: ut1
184 "unseen" set: replicated a1@myhost.test.ex
185 locking TESTSUITE/spool/db/retry.lockfile
186 no retry data available
187 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
188 Considering: a1@myhost.test.ex
189 unique = \0\a1@myhost.test.ex
190 a1@myhost.test.ex: queued for routing
191 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
192 Considering: b1@myhost.test.ex
193 unique = \0\b1@myhost.test.ex
194 b1@myhost.test.ex: queued for routing
195 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
196 Considering: c1@myhost.test.ex
197 unique = \0\c1@myhost.test.ex
198 c1@myhost.test.ex: queued for routing
199 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
200 Considering: d1@myhost.test.ex
201 unique = \0\d1@myhost.test.ex
202 d1@myhost.test.ex: queued for routing
203 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
204 Considering: d2@myhost.test.ex
205 unique = \0\d2@myhost.test.ex
206 d2@myhost.test.ex: queued for routing
207 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
208 Considering: d3@myhost.test.ex
209 unique = \0\d3@myhost.test.ex
210 d3@myhost.test.ex: queued for routing
211 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
212 routing d3@myhost.test.ex
213 --------> real router <--------
214 local_part=d3 domain=myhost.test.ex
215 calling real router
216 real router called for d3@myhost.test.ex
217   domain = myhost.test.ex
218 set transport real
219 queued for real transport: local_part = d3
220 domain = myhost.test.ex
221   errors_to=NULL
222   domain_data=NULL localpart_data=NULL
223 routed by real router
224   envelope to: d3@myhost.test.ex
225   transport: real
226 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
227 routing d2@myhost.test.ex
228 --------> real router <--------
229 local_part=d2 domain=myhost.test.ex
230 calling real router
231 real router called for d2@myhost.test.ex
232   domain = myhost.test.ex
233 queued for real transport: local_part = d2
234 domain = myhost.test.ex
235   errors_to=NULL
236   domain_data=NULL localpart_data=NULL
237 routed by real router
238   envelope to: d2@myhost.test.ex
239   transport: real
240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
241 routing d1@myhost.test.ex
242 --------> real router <--------
243 local_part=d1 domain=myhost.test.ex
244 calling real router
245 real router called for d1@myhost.test.ex
246   domain = myhost.test.ex
247 queued for real transport: local_part = d1
248 domain = myhost.test.ex
249   errors_to=NULL
250   domain_data=NULL localpart_data=NULL
251 routed by real router
252   envelope to: d1@myhost.test.ex
253   transport: real
254 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
255 routing c1@myhost.test.ex
256 --------> ut4 router <--------
257 local_part=c1 domain=myhost.test.ex
258 checking local_parts
259 ut4 router skipped: local_parts mismatch
260 --------> real router <--------
261 local_part=c1 domain=myhost.test.ex
262 calling real router
263 real router called for c1@myhost.test.ex
264   domain = myhost.test.ex
265 queued for real transport: local_part = c1
266 domain = myhost.test.ex
267   errors_to=NULL
268   domain_data=NULL localpart_data=NULL
269 routed by real router
270   envelope to: c1@myhost.test.ex
271   transport: real
272 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
273 routing b1@myhost.test.ex
274 --------> ut3 router <--------
275 local_part=b1 domain=myhost.test.ex
276 checking local_parts
277 ut3 router skipped: local_parts mismatch
278 --------> ut4 router <--------
279 local_part=b1 domain=myhost.test.ex
280 checking local_parts
281 ut4 router skipped: local_parts mismatch
282 --------> real router <--------
283 local_part=b1 domain=myhost.test.ex
284 calling real router
285 real router called for b1@myhost.test.ex
286   domain = myhost.test.ex
287 queued for real transport: local_part = b1
288 domain = myhost.test.ex
289   errors_to=NULL
290   domain_data=NULL localpart_data=NULL
291 routed by real router
292   envelope to: b1@myhost.test.ex
293   transport: real
294 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
295 routing a1@myhost.test.ex
296 --------> ut2 router <--------
297 local_part=a1 domain=myhost.test.ex
298 checking local_parts
299 ut2 router skipped: local_parts mismatch
300 --------> ut3 router <--------
301 local_part=a1 domain=myhost.test.ex
302 checking local_parts
303 ut3 router skipped: local_parts mismatch
304 --------> ut4 router <--------
305 local_part=a1 domain=myhost.test.ex
306 checking local_parts
307 ut4 router skipped: local_parts mismatch
308 --------> real router <--------
309 local_part=a1 domain=myhost.test.ex
310 calling real router
311 real router called for a1@myhost.test.ex
312   domain = myhost.test.ex
313 queued for real transport: local_part = a1
314 domain = myhost.test.ex
315   errors_to=NULL
316   domain_data=NULL localpart_data=NULL
317 routed by real router
318   envelope to: a1@myhost.test.ex
319   transport: real
320 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
321 After routing:
322   Local deliveries:
323     a1@myhost.test.ex
324     b1@myhost.test.ex
325     c1@myhost.test.ex
326     d1@myhost.test.ex
327     d2@myhost.test.ex
328     d3@myhost.test.ex
329     a1@myhost.test.ex
330     b1@myhost.test.ex
331     c1@myhost.test.ex
332   Remote deliveries:
333     d1@myhost.test.ex
334     d2@myhost.test.ex
335     d3@myhost.test.ex
336   Failed addresses:
337   Deferred addresses:
338 locking TESTSUITE/spool/db/retry.lockfile
339 LOG: MAIN
340   => a1 <a1@myhost.test.ex> R=real T=real
341 locking TESTSUITE/spool/db/retry.lockfile
342 LOG: MAIN
343   => b1 <b1@myhost.test.ex> R=real T=real
344 locking TESTSUITE/spool/db/retry.lockfile
345 LOG: MAIN
346   => c1 <c1@myhost.test.ex> R=real T=real
347 locking TESTSUITE/spool/db/retry.lockfile
348 LOG: MAIN
349   => d1 <d1@myhost.test.ex> R=real T=real
350 locking TESTSUITE/spool/db/retry.lockfile
351 LOG: MAIN
352   => d2 <d2@myhost.test.ex> R=real T=real
353 locking TESTSUITE/spool/db/retry.lockfile
354 LOG: MAIN
355   => d3 <d3@myhost.test.ex> R=real T=real
356 locking TESTSUITE/spool/db/retry.lockfile
357 LOG: MAIN
358   => a1 <a1@myhost.test.ex> R=u1 T=ut1
359 locking TESTSUITE/spool/db/retry.lockfile
360 transport error EPIPE ignored
361 LOG: MAIN
362   ** b1@myhost.test.ex R=ut2 T=ut2: Child process of ut2 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
363 locking TESTSUITE/spool/db/retry.lockfile
364 transport error EPIPE ignored
365 LOG: MAIN
366   == c1@myhost.test.ex R=ut3 T=ut3 defer (0): Child process of ut3 transport returned 127 (could mean unable to exec or command does not exist) from command: /non/existent/file
367 locking TESTSUITE/spool/db/retry.lockfile
368 locking TESTSUITE/spool/db/wait-ut4.lockfile
369 LOG: MAIN
370   => d1@myhost.test.ex R=ut4 T=ut4 H=127.0.0.1 [127.0.0.1] C="250 OK"
371 locking TESTSUITE/spool/db/retry.lockfile
372 LOG: MAIN
373   == d2@myhost.test.ex R=ut4 T=ut4 defer (-44): SMTP error from remote mail server after RCPT TO:<d2@myhost.test.ex>: host 127.0.0.1 [127.0.0.1]: 450 soft error
374 locking TESTSUITE/spool/db/retry.lockfile
375 locking TESTSUITE/spool/db/wait-ut4.lockfile
376 LOG: MAIN
377   ** d3@myhost.test.ex R=ut4 T=ut4: SMTP error from remote mail server after RCPT TO:<d3@myhost.test.ex>: host 127.0.0.1 [127.0.0.1]: 550 hard error
378 locking TESTSUITE/spool/db/retry.lockfile
379 Exim version x.yz ....
380 configuration file is TESTSUITE/test-config
381 trusted user
382 admin user
383 LOG: MAIN
384   <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
385 Exim version x.yz ....
386 configuration file is TESTSUITE/test-config
387 trusted user
388 admin user
389 locking TESTSUITE/spool/db/retry.lockfile
390 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
391 Considering: CALLER@myhost.test.ex
392 unique = CALLER@myhost.test.ex
393 CALLER@myhost.test.ex: queued for routing
394 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
395 routing CALLER@myhost.test.ex
396 --------> u1 router <--------
397 local_part=CALLER domain=myhost.test.ex
398 checking local_parts
399 u1 router skipped: local_parts mismatch
400 --------> ut2 router <--------
401 local_part=CALLER domain=myhost.test.ex
402 checking local_parts
403 ut2 router skipped: local_parts mismatch
404 --------> ut3 router <--------
405 local_part=CALLER domain=myhost.test.ex
406 checking local_parts
407 ut3 router skipped: local_parts mismatch
408 --------> ut4 router <--------
409 local_part=CALLER domain=myhost.test.ex
410 checking local_parts
411 ut4 router skipped: local_parts mismatch
412 --------> real router <--------
413 local_part=CALLER domain=myhost.test.ex
414 calling real router
415 real router called for CALLER@myhost.test.ex
416   domain = myhost.test.ex
417 set transport real
418 queued for real transport: local_part = CALLER
419 domain = myhost.test.ex
420   errors_to=NULL
421   domain_data=NULL localpart_data=NULL
422 routed by real router
423   envelope to: CALLER@myhost.test.ex
424   transport: real
425 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
426 After routing:
427   Local deliveries:
428     CALLER@myhost.test.ex
429   Remote deliveries:
430   Failed addresses:
431   Deferred addresses:
432 locking TESTSUITE/spool/db/retry.lockfile
433 LOG: MAIN
434   => CALLER <CALLER@myhost.test.ex> R=real T=real
435 LOG: MAIN
436   Completed
437 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
438 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
439 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
440 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>