Logging: TLSA lookups should honor slow_lookup_log
[exim.git] / test / stderr / 0402
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
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=pppp
9 seeking password data for user "CALLER": cache not available
10 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
11 seeking password data for user "CALLER": using cached result
12 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
13 seeking password data for user "CALLER": using cached result
14 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
15 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
16 sender address = CALLER@test.ex
17 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
18 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
19 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
20 Sender: CALLER@test.ex
21 Recipients:
22   CALLER@test.ex
23   usery@test.ex
24   userz
25   rd+CALLER
26   rd+usery
27 search_tidyup called
28 >>Headers received:
29
30 rewrite_one_header: type=F:
31   From: CALLER_NAME <CALLER@test.ex>
32 search_tidyup called
33 >>Headers after rewriting and local additions:
34 I Message-Id: <E10HmaX-0005vi-00@mail.test.ex>
35 F From: CALLER_NAME <CALLER@test.ex>
36   Date: Tue, 2 Mar 1999 09:44:33 +0000
37
38 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
39 Data file written for message 10HmaX-0005vi-00
40  ╭considering: ${tod_full}
41  ├──expanding: ${tod_full}
42  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
43  ╭considering: Received: ${if def:sender_rcvhost {from $sender_rcvhost
44         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
45         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
46         }}(Exim $version_number)
47         ${if def:sender_address {(envelope-from <$sender_address>)
48         }}id $message_exim_id${if def:received_for {
49         for $received_for}}
50  ├──condition: def:sender_rcvhost
51  ├─────result: false
52   ╭───scanning: from $sender_rcvhost
53         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
54         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
55         }}(Exim $version_number)
56         ${if def:sender_address {(envelope-from <$sender_address>)
57         }}id $message_exim_id${if def:received_for {
58         for $received_for}}
59   ├──expanding: from $sender_rcvhost
60         
61   ├─────result: from 
62         
63   ╰───skipping: result is not used
64   ╭considering: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
65         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
66         }}(Exim $version_number)
67         ${if def:sender_address {(envelope-from <$sender_address>)
68         }}id $message_exim_id${if def:received_for {
69         for $received_for}}
70   ├──condition: def:sender_ident
71   ├─────result: true
72    ╭considering: from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
73         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
74         }}(Exim $version_number)
75         ${if def:sender_address {(envelope-from <$sender_address>)
76         }}id $message_exim_id${if def:received_for {
77         for $received_for}}
78    ╎╭considering: $sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
79    ╎  }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
80    ╎  }}(Exim $version_number)
81    ╎  ${if def:sender_address {(envelope-from <$sender_address>)
82    ╎  }}id $message_exim_id${if def:received_for {
83    ╎  for $received_for}}
84    ╎├──expanding: $sender_ident
85    ╎╰─────result: CALLER
86    ├──expanding: from ${quote_local_part:$sender_ident} 
87    ╰─────result: from CALLER 
88   ├──condition: def:sender_helo_name
89   ├─────result: false
90    ╭───scanning: (helo=$sender_helo_name)
91         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
92         }}(Exim $version_number)
93         ${if def:sender_address {(envelope-from <$sender_address>)
94         }}id $message_exim_id${if def:received_for {
95         for $received_for}}
96    ├──expanding: (helo=$sender_helo_name)
97         
98    ├─────result: (helo=)
99         
100    ╰───skipping: result is not used
101   ├──expanding: ${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
102         }}
103   ╰─────result: from CALLER 
104  ├──condition: def:received_protocol
105  ├─────result: true
106   ╭considering: with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
107         }}(Exim $version_number)
108         ${if def:sender_address {(envelope-from <$sender_address>)
109         }}id $message_exim_id${if def:received_for {
110         for $received_for}}
111   ├──expanding: with $received_protocol 
112   ╰─────result: with local 
113  ├──condition: def:sender_address
114  ├─────result: true
115   ╭considering: (envelope-from <$sender_address>)
116         }}id $message_exim_id${if def:received_for {
117         for $received_for}}
118   ├──expanding: (envelope-from <$sender_address>)
119         
120   ╰─────result: (envelope-from <CALLER@test.ex>)
121         
122  ├──condition: def:received_for
123  ├─────result: false
124   ╭───scanning: 
125         for $received_for}}
126   ├──expanding: 
127         for $received_for
128   ├─────result: 
129         for 
130   ╰───skipping: result is not used
131  ├──expanding: Received: ${if def:sender_rcvhost {from $sender_rcvhost
132         }{${if def:sender_ident {from ${quote_local_part:$sender_ident} }}${if def:sender_helo_name {(helo=$sender_helo_name)
133         }}}}by $primary_hostname ${if def:received_protocol {with $received_protocol }}${if def:tls_in_cipher_std { tls $tls_in_cipher_std
134         }}(Exim $version_number)
135         ${if def:sender_address {(envelope-from <$sender_address>)
136         }}id $message_exim_id${if def:received_for {
137         for $received_for}}
138  ╰─────result: Received: from CALLER by mail.test.ex with local (Exim x.yz)
139         (envelope-from <CALLER@test.ex>)
140         id 10HmaX-0005vi-00
141 >>Generated Received: header line
142 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
143         (envelope-from <CALLER@test.ex>)
144         id 10HmaX-0005vi-00; Tue, 2 Mar 1999 09:44:33 +0000
145  ╭considering: ${tod_full}
146  ├──expanding: ${tod_full}
147  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
148 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
149 DSN: Write SPOOL: -dsn_envid NULL
150 DSN: Write SPOOL  :-dsn_ret 0
151 DSN: Flags: 0x0
152 DSN: **** SPOOL_OUT - address: <CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
153 DSN: Flags: 0x0
154 DSN: **** SPOOL_OUT - address: <usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
155 DSN: Flags: 0x0
156 DSN: **** SPOOL_OUT - address: <userz@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
157 DSN: Flags: 0x0
158 DSN: **** SPOOL_OUT - address: <rd+CALLER@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
159 DSN: Flags: 0x0
160 DSN: **** SPOOL_OUT - address: <rd+usery@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
161 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
162 Size of headers = sss
163 LOG: MAIN
164   <= CALLER@test.ex U=CALLER P=local S=sss
165 created log directory TESTSUITE/spool/log
166 search_tidyup called
167 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715dfd -odi -Mc 10HmaX-0005vi-00
168 Exim version x.yz ....
169 changed uid/gid: forcing real = effective
170   uid=uuuu gid=EXIM_GID pid=pppp
171 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
172 configuration file is TESTSUITE/test-config
173 trusted user
174 admin user
175 dropping to exim gid; retaining priv uid
176 seeking password data for user "CALLER": cache not available
177 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
178 seeking password data for user "CALLER": using cached result
179 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
180 seeking password data for user "CALLER": using cached result
181 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
182 set_process_info: pppp delivering specified messages
183 set_process_info: pppp delivering 10HmaX-0005vi-00
184 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
185 reading spool file 10HmaX-0005vi-00-H
186 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
187 sender_local=1 ident=CALLER
188 Non-recipients:
189 Empty Tree
190 ---- End of tree ----
191 recipients_count=5
192 **** SPOOL_IN - No additional fields
193 **** SPOOL_IN - No additional fields
194 **** SPOOL_IN - No additional fields
195 **** SPOOL_IN - No additional fields
196 **** SPOOL_IN - No additional fields
197 body_linecount=0 message_linecount=6
198 DSN: set orcpt:   flags: 0x0
199 DSN: set orcpt:   flags: 0x0
200 DSN: set orcpt:   flags: 0x0
201 DSN: set orcpt:   flags: 0x0
202 DSN: set orcpt:   flags: 0x0
203 Delivery address list:
204   CALLER@test.ex 
205   usery@test.ex 
206   userz@test.ex 
207   rd+CALLER@test.ex 
208   rd+usery@test.ex 
209  locking TESTSUITE/spool/db/retry.lockfile
210  locked  TESTSUITE/spool/db/retry.lockfile
211  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
212  returned from EXIM_DBOPEN: (nil)
213  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
214  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
215 no retry data available
216 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
217 Considering: CALLER@test.ex
218 unique = CALLER@test.ex
219 no   domain  retry record
220 no   address retry record
221 CALLER@test.ex: queued for routing
222 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
223 Considering: usery@test.ex
224 unique = usery@test.ex
225 no   domain  retry record
226 no   address retry record
227 usery@test.ex: queued for routing
228 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
229 Considering: userz@test.ex
230 unique = userz@test.ex
231 no   domain  retry record
232 no   address retry record
233 userz@test.ex: queued for routing
234 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
235 Considering: rd+CALLER@test.ex
236 unique = rd+CALLER@test.ex
237 no   domain  retry record
238 no   address retry record
239 rd+CALLER@test.ex: queued for routing
240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
241 Considering: rd+usery@test.ex
242 unique = rd+usery@test.ex
243 no   domain  retry record
244 no   address retry record
245 rd+usery@test.ex: queued for routing
246 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
247 routing rd+usery@test.ex
248 --------> r1 router <--------
249 local_part=rd+usery domain=test.ex
250 checking local_parts
251 rd+usery in "CALLER"? no (end of list)
252 r1 router skipped: local_parts mismatch
253 --------> r2 router <--------
254 local_part=rd+usery domain=test.ex
255 checking local_parts
256 rd+usery in "usery"? no (end of list)
257 r2 router skipped: local_parts mismatch
258 --------> r3 router <--------
259 local_part=rd+usery domain=test.ex
260 checking local_parts
261 rd+usery in "userz"? no (end of list)
262 r3 router skipped: local_parts mismatch
263 --------> r4 router <--------
264 local_part=rd+usery domain=test.ex
265 stripped prefix rd+
266 checking local_parts
267 usery in "CALLER"? no (end of list)
268 r4 router skipped: local_parts mismatch
269 --------> r5 router <--------
270 local_part=rd+usery domain=test.ex
271 stripped prefix rd+
272 checking local_parts
273 usery in "usery"? yes (matched "usery")
274  ╭considering: /non-exist/$domain
275  ├──expanding: /non-exist/$domain
276  ╰─────result: /non-exist/test.ex
277             ╰──(tainted)
278 calling r5 router
279 rda_interpret (string): 'TESTSUITE/test-mail/junk'
280 expanded: 'TESTSUITE/test-mail/junk'
281 file is not a filter file
282 parse_forward_list: TESTSUITE/test-mail/junk
283 extract item: TESTSUITE/test-mail/junk
284 set transport ft1
285 r5 router generated TESTSUITE/test-mail/junk
286   pipe, file, or autoreply
287   errors_to=NULL transport=ft1
288   uid=unset gid=unset home=/non-exist/$local_part
289 routed by r5 router
290   envelope to: rd+usery@test.ex
291   transport: <none>
292 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
293 routing rd+CALLER@test.ex
294 --------> r1 router <--------
295 local_part=rd+CALLER domain=test.ex
296 checking local_parts
297 rd+CALLER in "CALLER"? no (end of list)
298 r1 router skipped: local_parts mismatch
299 --------> r2 router <--------
300 local_part=rd+CALLER domain=test.ex
301 checking local_parts
302 rd+CALLER in "usery"? no (end of list)
303 r2 router skipped: local_parts mismatch
304 --------> r3 router <--------
305 local_part=rd+CALLER domain=test.ex
306 checking local_parts
307 rd+CALLER in "userz"? no (end of list)
308 r3 router skipped: local_parts mismatch
309 --------> r4 router <--------
310 local_part=rd+CALLER domain=test.ex
311 stripped prefix rd+
312 checking local_parts
313 CALLER in "CALLER"? yes (matched "CALLER")
314  ╭considering: /non-exist/$local_part
315  ├──expanding: /non-exist/$local_part
316  ╰─────result: /non-exist/CALLER
317             ╰──(tainted)
318 calling r4 router
319 rda_interpret (string): 'TESTSUITE/test-mail/junk'
320 expanded: 'TESTSUITE/test-mail/junk'
321 file is not a filter file
322 parse_forward_list: TESTSUITE/test-mail/junk
323 extract item: TESTSUITE/test-mail/junk
324 set transport ft1
325 r4 router generated TESTSUITE/test-mail/junk
326   pipe, file, or autoreply
327   errors_to=NULL transport=ft1
328   uid=unset gid=unset home=/non-exist/CALLER
329 routed by r4 router
330   envelope to: rd+CALLER@test.ex
331   transport: <none>
332 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
333 routing userz@test.ex
334 --------> r1 router <--------
335 local_part=userz domain=test.ex
336 checking local_parts
337 userz in "CALLER"? no (end of list)
338 r1 router skipped: local_parts mismatch
339 --------> r2 router <--------
340 local_part=userz domain=test.ex
341 checking local_parts
342 userz in "usery"? no (end of list)
343 r2 router skipped: local_parts mismatch
344 --------> r3 router <--------
345 local_part=userz domain=test.ex
346 checking local_parts
347 userz in "userz"? yes (matched "userz")
348  ╭considering: /non-exist/$domain
349  ├──expanding: /non-exist/$domain
350  ╰─────result: /non-exist/test.ex
351             ╰──(tainted)
352 calling r3 router
353 r3 router called for userz@test.ex
354   domain = test.ex
355 set transport t2
356 queued for t2 transport: local_part = userz
357 domain = test.ex
358   errors_to=NULL
359   domain_data=NULL localpart_data=NULL
360 routed by r3 router
361   envelope to: userz@test.ex
362   transport: t2
363 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
364 routing usery@test.ex
365 --------> r1 router <--------
366 local_part=usery domain=test.ex
367 checking local_parts
368 usery in "CALLER"? no (end of list)
369 r1 router skipped: local_parts mismatch
370 --------> r2 router <--------
371 local_part=usery domain=test.ex
372 checking local_parts
373 usery in "usery"? yes (matched "usery")
374  ╭considering: /non-exist/$domain
375  ├──expanding: /non-exist/$domain
376  ╰─────result: /non-exist/test.ex
377             ╰──(tainted)
378 calling r2 router
379 r2 router called for usery@test.ex
380   domain = test.ex
381 set transport t1
382 queued for t1 transport: local_part = usery
383 domain = test.ex
384   errors_to=NULL
385   domain_data=NULL localpart_data=NULL
386 routed by r2 router
387   envelope to: usery@test.ex
388   transport: t1
389 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
390 routing CALLER@test.ex
391 --------> r1 router <--------
392 local_part=CALLER domain=test.ex
393 checking local_parts
394 CALLER in "CALLER"? yes (matched "CALLER")
395  ╭considering: /non-exist/$local_part
396  ├──expanding: /non-exist/$local_part
397  ╰─────result: /non-exist/CALLER
398             ╰──(tainted)
399 calling r1 router
400 r1 router called for CALLER@test.ex
401   domain = test.ex
402 set transport t1
403 queued for t1 transport: local_part = CALLER
404 domain = test.ex
405   errors_to=NULL
406   domain_data=NULL localpart_data=NULL
407 routed by r1 router
408   envelope to: CALLER@test.ex
409   transport: t1
410  locking TESTSUITE/spool/db/retry.lockfile
411  locked  TESTSUITE/spool/db/retry.lockfile
412  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
413  returned from EXIM_DBOPEN: (nil)
414  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
415 no retry data available
416 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
417 Considering: TESTSUITE/test-mail/junk
418 unique = TESTSUITE/test-mail/junk:rd+CALLER@test.ex
419 queued for ft1 transport
420 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
421 Considering: TESTSUITE/test-mail/junk
422 unique = TESTSUITE/test-mail/junk:rd+usery@test.ex
423 queued for ft1 transport
424 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
425 After routing:
426   Local deliveries:
427     TESTSUITE/test-mail/junk
428     TESTSUITE/test-mail/junk
429     CALLER@test.ex
430     usery@test.ex
431     userz@test.ex
432   Remote deliveries:
433   Failed addresses:
434   Deferred addresses:
435 search_tidyup called
436 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
437 --------> TESTSUITE/test-mail/junk <--------
438  locking TESTSUITE/spool/db/retry.lockfile
439  locked  TESTSUITE/spool/db/retry.lockfile
440  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
441  returned from EXIM_DBOPEN: (nil)
442  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
443 no retry data available
444  ╭considering: /non-exist/$local_part
445  ├──expanding: /non-exist/$local_part
446  ╰─────result: /non-exist/usery
447             ╰──(tainted)
448 search_tidyup called
449 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
450   uid=CALLER_UID gid=CALLER_GID pid=pppp
451   home=/non-exist/usery current=/
452 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
453 appendfile transport entered
454  ╭considering: $address_file
455  ├──expanding: $address_file
456  ╰─────result: TESTSUITE/test-mail/junk
457 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
458   file=TESTSUITE/test-mail/junk format=unix
459   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
460   message_suffix=\n
461   maildir_use_size_file=no
462   locking by lockfile fcntl 
463 lock name: TESTSUITE/test-mail/junk.lock
464 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
465 lock file created
466 mailbox TESTSUITE/test-mail/junk is locked
467 writing to file TESTSUITE/test-mail/junk
468  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
469  
470  ├──condition: def:return_path
471  ├─────result: true
472   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
473   
474   ├──expanding: $return_path
475   ╰─────result: CALLER@test.ex
476              ╰──(tainted)
477   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
478   
479   ├──expanding: MAILER-DAEMON
480   ├─────result: MAILER-DAEMON
481   ╰───skipping: result is not used
482  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
483  
484  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
485  
486             ╰──(tainted)
487 writing data block fd=dddd size=sss timeout=0
488 cannot use sendfile for body: spoolfile not wireformat
489 writing data block fd=dddd size=sss timeout=0
490 writing data block fd=dddd size=sss timeout=0
491 appendfile yields 0 with errno=dd more_errno=dd
492 search_tidyup called
493 journalling TESTSUITE/test-mail/junk:rd+usery@test.ex
494 ft1 transport returned OK for TESTSUITE/test-mail/junk
495 post-process TESTSUITE/test-mail/junk (0)
496 TESTSUITE/test-mail/junk delivered
497 rd+usery@test.ex: children all complete
498 LOG: MAIN
499   => TESTSUITE/test-mail/junk <rd+usery@test.ex> R=r5 T=ft1
500 --------> TESTSUITE/test-mail/junk <--------
501  locking TESTSUITE/spool/db/retry.lockfile
502  locked  TESTSUITE/spool/db/retry.lockfile
503  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
504  returned from EXIM_DBOPEN: (nil)
505  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
506 no retry data available
507 search_tidyup called
508 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=ft1
509   uid=CALLER_UID gid=CALLER_GID pid=pppp
510   home=/non-exist/CALLER current=/
511 set_process_info: pppp delivering 10HmaX-0005vi-00 to TESTSUITE/test-mail/junk using ft1
512 appendfile transport entered
513  ╭considering: $address_file
514  ├──expanding: $address_file
515  ╰─────result: TESTSUITE/test-mail/junk
516 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
517   file=TESTSUITE/test-mail/junk format=unix
518   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
519   message_suffix=\n
520   maildir_use_size_file=no
521   locking by lockfile fcntl 
522 lock name: TESTSUITE/test-mail/junk.lock
523 hitch name: TESTSUITE/test-mail/junk.lock.test.ex.dddddddd.pppppppp
524 lock file created
525 mailbox TESTSUITE/test-mail/junk is locked
526 writing to file TESTSUITE/test-mail/junk
527  ╭considering: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
528  
529  ├──condition: def:return_path
530  ├─────result: true
531   ╭considering: $return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
532   
533   ├──expanding: $return_path
534   ╰─────result: CALLER@test.ex
535              ╰──(tainted)
536   ╭───scanning: MAILER-DAEMON}} ${tod_bsdinbox}
537   
538   ├──expanding: MAILER-DAEMON
539   ├─────result: MAILER-DAEMON
540   ╰───skipping: result is not used
541  ├──expanding: From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}
542  
543  ╰─────result: From CALLER@test.ex Tue Mar 02 09:44:33 1999
544  
545             ╰──(tainted)
546 writing data block fd=dddd size=sss timeout=0
547 cannot use sendfile for body: spoolfile not wireformat
548 writing data block fd=dddd size=sss timeout=0
549 writing data block fd=dddd size=sss timeout=0
550 appendfile yields 0 with errno=dd more_errno=dd
551 search_tidyup called
552 journalling TESTSUITE/test-mail/junk:rd+CALLER@test.ex
553 ft1 transport returned OK for TESTSUITE/test-mail/junk
554 post-process TESTSUITE/test-mail/junk (0)
555 TESTSUITE/test-mail/junk delivered
556 rd+CALLER@test.ex: children all complete
557 LOG: MAIN
558   => TESTSUITE/test-mail/junk <rd+CALLER@test.ex> R=r4 T=ft1
559 --------> CALLER@test.ex <--------
560  locking TESTSUITE/spool/db/retry.lockfile
561  locked  TESTSUITE/spool/db/retry.lockfile
562  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
563  returned from EXIM_DBOPEN: (nil)
564  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
565 no retry data available
566 search_tidyup called
567 changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
568   uid=CALLER_UID gid=CALLER_GID pid=pppp
569   home=/non-exist/CALLER current=/
570 set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
571 appendfile transport entered
572 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
573   file=/dev/null format=unix
574   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
575   message_suffix=\n
576   maildir_use_size_file=no
577   locking by lockfile fcntl 
578 search_tidyup called
579 journalling CALLER@test.ex
580 t1 transport returned OK for CALLER@test.ex
581 post-process CALLER@test.ex (0)
582 CALLER@test.ex delivered
583 LOG: MAIN
584   => CALLER <CALLER@test.ex> R=r1 T=t1
585 --------> usery@test.ex <--------
586  locking TESTSUITE/spool/db/retry.lockfile
587  locked  TESTSUITE/spool/db/retry.lockfile
588  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
589  returned from EXIM_DBOPEN: (nil)
590  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
591 no retry data available
592  ╭considering: /non-exist/$local_part
593  ├──expanding: /non-exist/$local_part
594  ╰─────result: /non-exist/usery
595             ╰──(tainted)
596 search_tidyup called
597 changed uid/gid: local delivery to usery <usery@test.ex> transport=t1
598   uid=CALLER_UID gid=CALLER_GID pid=pppp
599   home=/non-exist/usery current=/
600 set_process_info: pppp delivering 10HmaX-0005vi-00 to usery using t1
601 appendfile transport entered
602 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
603   file=/dev/null format=unix
604   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
605   message_suffix=\n
606   maildir_use_size_file=no
607   locking by lockfile fcntl 
608 search_tidyup called
609 journalling usery@test.ex
610 t1 transport returned OK for usery@test.ex
611 post-process usery@test.ex (0)
612 usery@test.ex delivered
613 LOG: MAIN
614   => usery <usery@test.ex> R=r2 T=t1
615 --------> userz@test.ex <--------
616  locking TESTSUITE/spool/db/retry.lockfile
617  locked  TESTSUITE/spool/db/retry.lockfile
618  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
619  returned from EXIM_DBOPEN: (nil)
620  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
621 no retry data available
622  ╭considering: /$local_part
623  ├──expanding: /$local_part
624  ╰─────result: /userz
625             ╰──(tainted)
626 search_tidyup called
627 changed uid/gid: local delivery to userz <userz@test.ex> transport=t2
628   uid=CALLER_UID gid=CALLER_GID pid=pppp
629   home=/userz current=/
630 set_process_info: pppp delivering 10HmaX-0005vi-00 to userz using t2
631 appendfile transport entered
632 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
633   file=/dev/null format=unix
634   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
635   message_suffix=\n
636   maildir_use_size_file=no
637   locking by lockfile fcntl 
638 search_tidyup called
639 journalling userz@test.ex
640 t2 transport returned OK for userz@test.ex
641 post-process userz@test.ex (0)
642 userz@test.ex delivered
643 LOG: MAIN
644   => userz <userz@test.ex> R=r3 T=t2
645 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
646 changed uid/gid: post-delivery tidying
647   uid=EXIM_UID gid=EXIM_GID pid=pppp
648 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
649 Processing retry items
650 Succeeded addresses:
651  userz@test.ex: no retry items
652  usery@test.ex: no retry items
653  CALLER@test.ex: no retry items
654  TESTSUITE/test-mail/junk: no retry items
655  rd+CALLER@test.ex: no retry items
656  TESTSUITE/test-mail/junk: no retry items
657  rd+usery@test.ex: no retry items
658  rd+CALLER@test.ex: no retry items
659  rd+usery@test.ex: no retry items
660 Failed addresses:
661 Deferred addresses:
662 end of retry processing
663 DSN: processing router : r3
664 DSN: processing successful delivery address: userz@test.ex
665 DSN: Sender_address: CALLER@test.ex
666 DSN: orcpt: NULL  flags: 0x0
667 DSN: envid: NULL  ret: 0
668 DSN: Final recipient: userz@test.ex
669 DSN: Remote SMTP server supports DSN: 0
670 DSN: not sending DSN success message
671 DSN: processing router : r2
672 DSN: processing successful delivery address: usery@test.ex
673 DSN: Sender_address: CALLER@test.ex
674 DSN: orcpt: NULL  flags: 0x0
675 DSN: envid: NULL  ret: 0
676 DSN: Final recipient: usery@test.ex
677 DSN: Remote SMTP server supports DSN: 0
678 DSN: not sending DSN success message
679 DSN: processing router : r1
680 DSN: processing successful delivery address: CALLER@test.ex
681 DSN: Sender_address: CALLER@test.ex
682 DSN: orcpt: NULL  flags: 0x0
683 DSN: envid: NULL  ret: 0
684 DSN: Final recipient: CALLER@test.ex
685 DSN: Remote SMTP server supports DSN: 0
686 DSN: not sending DSN success message
687 DSN: processing router : r4
688 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
689 DSN: Sender_address: CALLER@test.ex
690 DSN: orcpt: NULL  flags: 0x0
691 DSN: envid: NULL  ret: 0
692 DSN: Final recipient: TESTSUITE/test-mail/junk
693 DSN: Remote SMTP server supports DSN: 0
694 DSN: not sending DSN success message
695 DSN: processing router : r5
696 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
697 DSN: Sender_address: CALLER@test.ex
698 DSN: orcpt: NULL  flags: 0x0
699 DSN: envid: NULL  ret: 0
700 DSN: Final recipient: TESTSUITE/test-mail/junk
701 DSN: Remote SMTP server supports DSN: 0
702 DSN: not sending DSN success message
703 DSN: processing router : r4
704 DSN: processing successful delivery address: rd+CALLER@test.ex
705 DSN: Sender_address: CALLER@test.ex
706 DSN: orcpt: NULL  flags: 0x0
707 DSN: envid: NULL  ret: 0
708 DSN: Final recipient: rd+CALLER@test.ex
709 DSN: Remote SMTP server supports DSN: 0
710 DSN: not sending DSN success message
711 DSN: processing router : r5
712 DSN: processing successful delivery address: rd+usery@test.ex
713 DSN: Sender_address: CALLER@test.ex
714 DSN: orcpt: NULL  flags: 0x0
715 DSN: envid: NULL  ret: 0
716 DSN: Final recipient: rd+usery@test.ex
717 DSN: Remote SMTP server supports DSN: 0
718 DSN: not sending DSN success message
719 LOG: MAIN
720   Completed
721 end delivery of 10HmaX-0005vi-00
722 search_tidyup called
723 search_tidyup called
724 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
725 search_tidyup called
726 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>