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