debug: prettify expansion condition trace output
[exim.git] / test / stderr / 0278
CommitLineData
54c5ebb1
PH
1Exim version x.yz ....
2changed uid/gid: forcing real = effective
3 uid=uuuu gid=CALLER_GID pid=pppp
4configuration file is TESTSUITE/test-config
5admin user
d8fe1c03
PH
6seeking password data for user "CALLER": cache not available
7getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
50dc7409
JH
8DSN: r1 propagating DSN
9DSN: r2 propagating DSN
10DSN: r3 propagating DSN
11DSN: r4 propagating DSN
12DSN: r5 propagating DSN
54c5ebb1
PH
13originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
14sender address = CALLER@test.ex
15Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
16>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17Testing CALLER@test.ex
18>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
19Considering CALLER@test.ex
20>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
21routing CALLER@test.ex
22--------> r1 router <--------
23local_part=CALLER domain=test.ex
24checking local_parts
25CALLER in "never"? no (end of list)
26CALLER in "never1"? no (end of list)
27CALLER in "CALLER"? yes (matched "CALLER")
28CALLER in "+never_localparts : +n1_localparts : ! +local_localparts"? no (matched "! +local_localparts")
29r1 router skipped: local_parts mismatch
30--------> r2 router <--------
31local_part=CALLER domain=test.ex
32checking local_parts
33cached no match for +never_localparts
34cached lookup data = NULL
35cached no match for +n1_localparts
36cached lookup data = NULL
37CALLER in "<; never2 ; +n1_localparts"? no (end of list)
38cached yes match for +local_localparts
39cached lookup data = NULL
40CALLER in "+never_localparts : +n2_localparts : !+local_localparts"? no (matched "!+local_localparts" - cached)
41r2 router skipped: local_parts mismatch
42--------> r3 router <--------
43local_part=CALLER domain=test.ex
44checking local_parts
45cached yes match for +local_localparts
46cached lookup data = NULL
47CALLER in "+local_localparts"? yes (matched "+local_localparts" - cached)
48checking for local user
d8fe1c03
PH
49seeking password data for user "CALLER": using cached result
50getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
54c5ebb1
PH
51calling r3 router
52r3 router called for CALLER@test.ex
53 domain = test.ex
54set transport t1
55queued for t1 transport: local_part = CALLER
56domain = test.ex
57 errors_to=NULL
58 domain_data=NULL localpart_data=NULL
59routed by r3 router
60 envelope to: CALLER@test.ex
61 transport: t1
62search_tidyup called
63>>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
64Exim version x.yz ....
65changed uid/gid: forcing real = effective
66 uid=uuuu gid=CALLER_GID pid=pppp
67configuration file is TESTSUITE/test-config
68admin user
69changed uid/gid: privilege not needed
70 uid=EXIM_UID gid=EXIM_GID pid=pppp
d8fe1c03
PH
71seeking password data for user "CALLER": cache not available
72getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
50dc7409
JH
73DSN: r1 propagating DSN
74DSN: r2 propagating DSN
75DSN: r3 propagating DSN
76DSN: r4 propagating DSN
77DSN: r5 propagating DSN
54c5ebb1
PH
78originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
79sender address = CALLER@test.ex
80set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
81Sender: CALLER@test.ex
82Recipients:
83 CALLER@test.ex
84search_tidyup called
85>>Headers received:
86
87rewrite_one_header: type=F:
88 From: CALLER_NAME <CALLER@test.ex>
89search_tidyup called
90>>Headers after rewriting and local additions:
91I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
92F From: CALLER_NAME <CALLER@test.ex>
b3f43fa0 93 Date: Tue, 2 Mar 1999 09:44:33 +0000
54c5ebb1 94
0971ec06 95Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
54c5ebb1
PH
96Data file written for message 10HmaX-0005vi-00
97>>Generated Received: header line
98P Received: from CALLER by myhost.test.ex with local (Exim x.yz)
99 (envelope-from <CALLER@test.ex>)
100 id 10HmaX-0005vi-00
101 for CALLER@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
102calling local_scan(); timeout=300
103local_scan() returned 0 NULL
0971ec06 104Writing spool header file: TESTSUITE/spool//input//hdr.pppp
50dc7409
JH
105DSN: Write SPOOL :-dsn_envid NULL
106DSN: Write SPOOL :-dsn_ret 0
107DSN: Flags :0
108DSN: **** SPOOL_OUT - address: |CALLER@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
0971ec06 109Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
54c5ebb1
PH
110Size of headers = sss
111LOG: MAIN
112 <= CALLER@test.ex U=CALLER P=local S=sss
113created log directory TESTSUITE/spool/log
114search_tidyup called
115exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
116Exim version x.yz ....
117changed uid/gid: forcing real = effective
118 uid=uuuu gid=EXIM_GID pid=pppp
119configuration file is TESTSUITE/test-config
120trusted user
121admin user
d8fe1c03
PH
122seeking password data for user "CALLER": cache not available
123getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
50dc7409
JH
124DSN: r1 propagating DSN
125DSN: r2 propagating DSN
126DSN: r3 propagating DSN
127DSN: r4 propagating DSN
128DSN: r5 propagating DSN
54c5ebb1
PH
129set_process_info: pppp delivering specified messages
130set_process_info: pppp delivering 10HmaX-0005vi-00
0971ec06 131Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
54c5ebb1
PH
132reading spool file 10HmaX-0005vi-00-H
133user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
134sender_local=1 ident=CALLER
135Non-recipients:
136Empty Tree
137---- End of tree ----
138recipients_count=1
50dc7409 139**** SPOOL_IN - No additional fields
54c5ebb1 140body_linecount=0 message_linecount=7
50dc7409 141DSN: set orcpt: NULL flags: 0
54c5ebb1
PH
142Delivery address list:
143 CALLER@test.ex
144locking TESTSUITE/spool/db/retry.lockfile
145locked TESTSUITE/spool/db/retry.lockfile
146EXIM_DBOPEN(TESTSUITE/spool/db/retry)
147returned from EXIM_DBOPEN
148ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
149no retry data available
150>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
151Considering: CALLER@test.ex
152unique = CALLER@test.ex
153no domain retry record
154no address retry record
155CALLER@test.ex: queued for routing
156>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
157routing CALLER@test.ex
158--------> r1 router <--------
159local_part=CALLER domain=test.ex
160checking local_parts
161CALLER in "never"? no (end of list)
162CALLER in "never1"? no (end of list)
163CALLER in "CALLER"? yes (matched "CALLER")
164CALLER in "+never_localparts : +n1_localparts : ! +local_localparts"? no (matched "! +local_localparts")
165r1 router skipped: local_parts mismatch
166--------> r2 router <--------
167local_part=CALLER domain=test.ex
168checking local_parts
169cached no match for +never_localparts
170cached lookup data = NULL
171cached no match for +n1_localparts
172cached lookup data = NULL
173CALLER in "<; never2 ; +n1_localparts"? no (end of list)
174cached yes match for +local_localparts
175cached lookup data = NULL
176CALLER in "+never_localparts : +n2_localparts : !+local_localparts"? no (matched "!+local_localparts" - cached)
177r2 router skipped: local_parts mismatch
178--------> r3 router <--------
179local_part=CALLER domain=test.ex
180checking local_parts
181cached yes match for +local_localparts
182cached lookup data = NULL
183CALLER in "+local_localparts"? yes (matched "+local_localparts" - cached)
184checking for local user
d8fe1c03
PH
185seeking password data for user "CALLER": using cached result
186getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
54c5ebb1
PH
187calling r3 router
188r3 router called for CALLER@test.ex
189 domain = test.ex
190set transport t1
191queued for t1 transport: local_part = CALLER
192domain = test.ex
193 errors_to=NULL
194 domain_data=NULL localpart_data=NULL
195routed by r3 router
196 envelope to: CALLER@test.ex
197 transport: t1
198>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
199After routing:
200 Local deliveries:
201 CALLER@test.ex
202 Remote deliveries:
203 Failed addresses:
204 Deferred addresses:
205search_tidyup called
206>>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
207--------> CALLER@test.ex <--------
208locking TESTSUITE/spool/db/retry.lockfile
209locked TESTSUITE/spool/db/retry.lockfile
210EXIM_DBOPEN(TESTSUITE/spool/db/retry)
211returned from EXIM_DBOPEN
212no retry data available
213search_tidyup called
214changed uid/gid: local delivery to CALLER <CALLER@test.ex> transport=t1
215 uid=CALLER_UID gid=CALLER_GID pid=pppp
216 home=CALLER_HOME current=CALLER_HOME
217set_process_info: pppp delivering 10HmaX-0005vi-00 to CALLER using t1
218appendfile transport entered
219appendfile: mode=600 notify_comsat=0 quota=0 warning=0
220 file=/dev/null format=unix
221 message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
222 message_suffix=\n
223 maildir_use_size_file=no
224 locking by lockfile fcntl
225search_tidyup called
226journalling CALLER@test.ex
227t1 transport returned OK for CALLER@test.ex
228post-process CALLER@test.ex (0)
229CALLER@test.ex delivered
230LOG: MAIN
231 => CALLER <CALLER@test.ex> R=r3 T=t1
232>>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
233changed uid/gid: post-delivery tidying
234 uid=EXIM_UID gid=EXIM_GID pid=pppp
235set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
236Processing retry items
237Succeeded addresses:
238CALLER@test.ex: no retry items
239Failed addresses:
240Deferred addresses:
241end of retry processing
50dc7409
JH
242DSN: processing router : r3
243DSN: processing successful delivery address: CALLER@test.ex
244DSN: Sender_address: CALLER@test.ex
245DSN: orcpt: NULL flags: 0
246DSN: envid: NULL ret: 0
247DSN: Final recipient: CALLER@test.ex
248DSN: Remote SMTP server supports DSN: 0
9671784a 249DSN: not sending DSN success message
54c5ebb1
PH
250LOG: MAIN
251 Completed
252end delivery of 10HmaX-0005vi-00
253search_tidyup called
254search_tidyup called
255>>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
256search_tidyup called
257>>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
258Exim version x.yz ....
259changed uid/gid: forcing real = effective
260 uid=uuuu gid=CALLER_GID pid=pppp
261configuration file is TESTSUITE/test-config
262admin user
d8fe1c03
PH
263seeking password data for user "CALLER": cache not available
264getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
50dc7409
JH
265DSN: r1 propagating DSN
266DSN: r2 propagating DSN
267DSN: r3 propagating DSN
268DSN: r4 propagating DSN
269DSN: r5 propagating DSN
54c5ebb1
PH
270originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
271sender address = CALLER@test.ex
272Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
273>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
274Testing unknown@test.ex
275>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
276Considering unknown@test.ex
277>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
278routing unknown@test.ex
279--------> r1 router <--------
280local_part=unknown domain=test.ex
281checking local_parts
282unknown in "never"? no (end of list)
283unknown in "never1"? no (end of list)
284unknown in "CALLER"? no (end of list)
285unknown in "+never_localparts : +n1_localparts : ! +local_localparts"? yes (end of list)
286calling r1 router
287r1 router called for unknown@test.ex
288 domain = test.ex
289route_item = never
290test.ex in "never"? no (end of list)
291r1 router declined for unknown@test.ex
292--------> r2 router <--------
293local_part=unknown domain=test.ex
294checking local_parts
295cached no match for +never_localparts
296cached lookup data = NULL
297cached no match for +n1_localparts
298cached lookup data = NULL
299unknown in "<; never2 ; +n1_localparts"? no (end of list)
300cached no match for +local_localparts
301cached lookup data = NULL
302unknown in "+never_localparts : +n2_localparts : !+local_localparts"? yes (end of list)
303calling r2 router
304r2 router called for unknown@test.ex
305 domain = test.ex
306DNS lookup of test.ex (MX) using fakens
307DNS lookup of test.ex (MX) gave NO_DATA
308returning DNS_NODATA
309DNS lookup of test.ex (A) using fakens
310DNS lookup of test.ex (A) gave NO_DATA
311returning DNS_NODATA
312r2 router declined for unknown@test.ex
313--------> r3 router <--------
314local_part=unknown domain=test.ex
315checking local_parts
316cached no match for +local_localparts
317cached lookup data = NULL
318unknown in "+local_localparts"? no (end of list)
319r3 router skipped: local_parts mismatch
320--------> r4 router <--------
321local_part=unknown domain=test.ex
322checking local_parts
323cached no match for +local_localparts
324cached lookup data = NULL
325unknown in "test.ex"? no (end of list)
326unknown in "unexpanded"? no (end of list)
327unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
328r4 router skipped: local_parts mismatch
329--------> r5 router <--------
330local_part=unknown domain=test.ex
331checking local_parts
332cached no match for +local_localparts
333cached lookup data = NULL
334unknown in "test.ex"? no (end of list)
335cached no match for +unexpanded
336cached lookup data = NULL
337unknown in "+local_localparts : +expanded : +unexpanded"? no (end of list)
338r5 router skipped: local_parts mismatch
339no more routers
340search_tidyup called
341>>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=2 >>>>>>>>>>>>>>>>