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