Log expansion failures
[users/jgh/exim.git] / test / stderr / 0361
1 LOG: MAIN
2   <= CALLER@test.ex U=CALLER P=local S=sss
3 delivering 10HmaX-0005vi-00
4 LOG: MAIN
5   *> miles <miles@thishost.test.ex> R=r4 T=t2
6 LOG: MAIN
7   Completed
8 Exim version x.yz ....
9 changed uid/gid: forcing real = effective
10   uid=uuuu gid=CALLER_GID pid=pppp
11 configuration file is TESTSUITE/test-config
12 admin user
13 changed uid/gid: privilege not needed
14   uid=EXIM_UID gid=EXIM_GID pid=pppp
15 seeking password data for user "CALLER": cache not available
16 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
17 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
18 sender address = CALLER@test.ex
19 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
20 Sender: CALLER@test.ex
21 Recipients:
22   kilos@recurse.test.ex
23 search_tidyup called
24 >>Headers received:
25
26 rewrite_one_header: type=F:
27   From: CALLER_NAME <CALLER@test.ex>
28 search_tidyup called
29 >>Headers after rewriting and local additions:
30 I Message-Id: <E10HmaY-0005vi-00@the.local.host.name>
31 F From: CALLER_NAME <CALLER@test.ex>
32   Date: Tue, 2 Mar 1999 09:44:33 +0000
33
34 Data file written for message 10HmaY-0005vi-00
35 >>Generated Received: header line
36 P Received: from CALLER by the.local.host.name with local (Exim x.yz)
37         (envelope-from <CALLER@test.ex>)
38         id 10HmaY-0005vi-00
39         for kilos@recurse.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
40 calling local_scan(); timeout=300
41 local_scan() returned 0 NULL
42 Writing spool header file
43 Size of headers = sss
44 LOG: MAIN
45   <= CALLER@test.ex U=CALLER P=local S=sss
46 search_tidyup called
47 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -N -odi -Mc 10HmaY-0005vi-00
48 Exim version x.yz ....
49 changed uid/gid: forcing real = effective
50   uid=uuuu gid=EXIM_GID pid=pppp
51 configuration file is TESTSUITE/test-config
52 trusted user
53 admin user
54 seeking password data for user "CALLER": cache not available
55 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
56 set_process_info: pppp delivering specified messages
57 set_process_info: pppp delivering 10HmaY-0005vi-00
58 reading spool file 10HmaY-0005vi-00-H
59 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
60 sender_local=1 ident=CALLER
61 Non-recipients:
62 Empty Tree
63 ---- End of tree ----
64 recipients_count=1
65 body_linecount=0 message_linecount=7
66 Delivery address list:
67   kilos@recurse.test.ex 
68 locking TESTSUITE/spool/db/retry.lockfile
69 locked TESTSUITE/spool/db/retry.lockfile
70 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
71 returned from EXIM_DBOPEN
72 no retry data available
73 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
74 Considering: kilos@recurse.test.ex
75 unique = kilos@recurse.test.ex
76 no domain retry record
77 no address retry record
78 kilos@recurse.test.ex: queued for routing
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 routing kilos@recurse.test.ex
81 --------> r1 router <--------
82 local_part=kilos domain=recurse.test.ex
83 checking domains
84 recurse.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? yes (end of list)
85 calling r1 router
86 r1 router called for kilos@recurse.test.ex
87   domain = recurse.test.ex
88 DNS lookup of recurse.test.ex (MX) using fakens
89 DNS lookup of recurse.test.ex (MX) gave HOST_NOT_FOUND
90 returning DNS_NOMATCH
91 r1 router widened recurse.test.ex to recurse.test.ex.test.ex
92 DNS lookup of recurse.test.ex.test.ex (MX) using fakens
93 DNS lookup of recurse.test.ex.test.ex (MX) gave NO_DATA
94 returning DNS_NODATA
95 DNS lookup of recurse.test.ex.test.ex (A) using fakens
96 DNS lookup of recurse.test.ex.test.ex (A) succeeded
97 fully qualified name = recurse.test.ex.test.ex
98 recurse.test.ex.test.ex V4NET.99.0.2 mx=-1 sort=xx 
99 domain changed to recurse.test.ex.test.ex
100 rewriting header lines
101 rewrite_one_header: type=F:
102   From: CALLER_NAME <CALLER@test.ex>
103 re-routed to kilos@recurse.test.ex.test.ex
104 locking TESTSUITE/spool/db/retry.lockfile
105 locked TESTSUITE/spool/db/retry.lockfile
106 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
107 returned from EXIM_DBOPEN
108 no retry data available
109 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
110 Considering: kilos@recurse.test.ex.test.ex
111 unique = kilos@recurse.test.ex.test.ex
112 no domain retry record
113 no address retry record
114 kilos@recurse.test.ex.test.ex: queued for routing
115 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
116 routing kilos@recurse.test.ex.test.ex
117 --------> r1 router <--------
118 local_part=kilos domain=recurse.test.ex.test.ex
119 checking domains
120 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
121 r1 router skipped: domains mismatch
122 --------> r2 router <--------
123 local_part=kilos domain=recurse.test.ex.test.ex
124 checking local_parts
125 kilos in "miles"? no (end of list)
126 r2 router skipped: local_parts mismatch
127 --------> r3 router <--------
128 local_part=kilos domain=recurse.test.ex.test.ex
129 checking local_parts
130 kilos in "kilos"? yes (matched "kilos")
131 calling r3 router
132 rda_interpret (string): $local_part@$domain
133 expanded: kilos@recurse.test.ex.test.ex
134 file is not a filter file
135 parse_forward_list: kilos@recurse.test.ex.test.ex
136 extract item: kilos@recurse.test.ex.test.ex
137 r3 router generated kilos@recurse.test.ex.test.ex
138   errors_to=NULL transport=NULL
139   uid=unset gid=unset home=NULL
140 routed by r3 router
141   envelope to: kilos@recurse.test.ex.test.ex
142   transport: <none>
143 locking TESTSUITE/spool/db/retry.lockfile
144 locked TESTSUITE/spool/db/retry.lockfile
145 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
146 returned from EXIM_DBOPEN
147 no retry data available
148 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
149 Considering: kilos@recurse.test.ex.test.ex
150 unique = \0\kilos@recurse.test.ex.test.ex
151 no domain retry record
152 no address retry record
153 kilos@recurse.test.ex.test.ex: queued for routing
154 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
155 routing kilos@recurse.test.ex.test.ex
156 --------> r1 router <--------
157 local_part=kilos domain=recurse.test.ex.test.ex
158 checking domains
159 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
160 r1 router skipped: domains mismatch
161 --------> r2 router <--------
162 local_part=kilos domain=recurse.test.ex.test.ex
163 checking local_parts
164 kilos in "miles"? no (end of list)
165 r2 router skipped: local_parts mismatch
166 --------> r3 router <--------
167 r3 router skipped: previously routed kilos@recurse.test.ex.test.ex
168 --------> r4 router <--------
169 local_part=kilos domain=recurse.test.ex.test.ex
170 calling r4 router
171 r4 router called for kilos@recurse.test.ex.test.ex
172   domain = recurse.test.ex.test.ex
173 set transport t2
174 queued for t2 transport: local_part = kilos
175 domain = recurse.test.ex.test.ex
176   errors_to=NULL
177   domain_data=NULL localpart_data=NULL
178 routed by r4 router
179   envelope to: kilos@recurse.test.ex.test.ex
180   transport: t2
181 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
182 After routing:
183   Local deliveries:
184     kilos@recurse.test.ex.test.ex
185   Remote deliveries:
186   Failed addresses:
187   Deferred addresses:
188 search_tidyup called
189 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
190 --------> kilos@recurse.test.ex.test.ex <--------
191 locking TESTSUITE/spool/db/retry.lockfile
192 locked TESTSUITE/spool/db/retry.lockfile
193 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
194 returned from EXIM_DBOPEN
195 no retry data available
196 search_tidyup called
197 changed uid/gid: local delivery to kilos <kilos@recurse.test.ex.test.ex> transport=t2
198   uid=CALLER_UID gid=CALLER_GID pid=pppp
199   home=NULL current=/
200 set_process_info: pppp delivering 10HmaY-0005vi-00 to kilos using t2
201 appendfile transport entered
202 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
203   file=/dev/null format=unix
204   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
205   message_suffix=\n
206   maildir_use_size_file=no
207   locking by lockfile fcntl 
208 *** delivery by t2 transport bypassed by -N option
209 search_tidyup called
210 journalling kilos@recurse.test.ex.test.ex/t2
211 t2 transport returned OK for kilos@recurse.test.ex.test.ex
212 post-process kilos@recurse.test.ex.test.ex (0)
213 kilos@recurse.test.ex.test.ex delivered
214 kilos@recurse.test.ex.test.ex: children all complete
215 kilos@recurse.test.ex: children all complete
216 LOG: MAIN
217   *> kilos <kilos@recurse.test.ex> R=r4 T=t2
218 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
219 changed uid/gid: post-delivery tidying
220   uid=EXIM_UID gid=EXIM_GID pid=pppp
221 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
222 LOG: MAIN
223   Completed
224 end delivery of 10HmaY-0005vi-00
225 search_tidyup called
226 search_tidyup called
227 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
228 search_tidyup called
229 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>