debug tidying
[exim.git] / test / stderr / 0388
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
4b424e0d 6dropping to exim gid; retaining priv uid
54c5ebb1
PH
7set_process_info: pppp delivering specified messages
8set_process_info: pppp delivering 10HmaX-0005vi-00
966e829c
JH
9 locking TESTSUITE/spool/db/retry.lockfile
10 locked TESTSUITE/spool/db/retry.lockfile
11 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
12 returned from EXIM_DBOPEN: (nil)
13 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
14 failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
54c5ebb1
PH
15no retry data available
16>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
17Considering: x@y
18unique = x@y
de6f74f2
JH
19no domain retry record
20no address retry record
54c5ebb1
PH
21x@y: queued for routing
22>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
23routing x@y
24--------> r0 router <--------
25local_part=x domain=y
26checking local_parts
27x in "CALLER"? no (end of list)
28r0 router skipped: local_parts mismatch
29--------> r1 router <--------
30local_part=x domain=y
31calling r1 router
32r1 router called for x@y
33 domain = y
34route_item = * "127.0.0.1 : V4NET.0.0.0"
35y in "*"? yes (matched "*")
8b455685
JH
36original list of hosts = '127.0.0.1 : V4NET.0.0.0' options = ''
37expanded list of hosts = '127.0.0.1 : V4NET.0.0.0' options = ''
54c5ebb1
PH
38set transport smtp
39finding IP address for 127.0.0.1
40calling host_find_byname
41using host_fake_gethostbyname for 127.0.0.1 (IPv4)
42local host found for non-MX address
43fully qualified name = 127.0.0.1
44MUNGED: ::1 will be omitted in what follows
45get[host|ipnode]byname[2] looked up these IP addresses:
46 name=127.0.0.1 address=127.0.0.1
47remote host address is the local host: y: configured to try delivery anyway
48finding IP address for V4NET.0.0.0
49calling host_find_byname
50using host_fake_gethostbyname for V4NET.0.0.0 (IPv4)
51fully qualified name = V4NET.0.0.0
52MUNGED: ::1 will be omitted in what follows
53get[host|ipnode]byname[2] looked up these IP addresses:
54 name=V4NET.0.0.0 address=V4NET.0.0.0
55queued for smtp transport: local_part = x
56domain = y
57 errors_to=NULL
58 domain_data=NULL localpart_data=NULL
59routed by r1 router
60 envelope to: x@y
61 transport: smtp
62 host 127.0.0.1 [127.0.0.1]
63 host V4NET.0.0.0 [V4NET.0.0.0]
64>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
65After routing:
66 Local deliveries:
67 Remote deliveries:
68 x@y
69 Failed addresses:
70 Deferred addresses:
71search_tidyup called
72search_tidyup called
56809214
JH
73fresh-exec forking for transport
74postfork: transport
54c5ebb1
PH
75changed uid/gid: remote delivery to x@y with transport=smtp
76 uid=EXIM_UID gid=EXIM_GID pid=pppp
77set_process_info: pppp delivering 10HmaX-0005vi-00 using smtp
78checking status of 127.0.0.1
966e829c
JH
79 locking TESTSUITE/spool/db/retry.lockfile
80 locked TESTSUITE/spool/db/retry.lockfile
81 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
82 returned from EXIM_DBOPEN: (nil)
83 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
54c5ebb1
PH
84no retry data available
85127.0.0.1 in serialize_hosts? no (option unset)
67ea939c 86set_process_info: pppp delivering 10HmaX-0005vi-00 to 127.0.0.1 [127.0.0.1]:PORT_S (x@y)
b48cf079 87Connecting to 127.0.0.1 [127.0.0.1]:1224 ... connected
54c5ebb1
PH
88 SMTP<< 220 Server ready
89127.0.0.1 in hosts_avoid_esmtp? no (option unset)
90 SMTP>> EHLO myhost.test.ex
76003495 91cmd buf flush ddd bytes
54c5ebb1
PH
92 SMTP<< 250 OK
93127.0.0.1 in hosts_require_auth? no (option unset)
94 SMTP>> MAIL FROM:<CALLER@myhost.test.ex>
76003495 95cmd buf flush ddd bytes
54c5ebb1
PH
96 SMTP<< 250 OK
97 SMTP>> RCPT TO:<x@y>
76003495 98cmd buf flush ddd bytes
54c5ebb1
PH
99 SMTP<< 451 Temporary error
100LOG: MAIN
c562fd30 101 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error
54c5ebb1
PH
102added retry item for R:x@y: errno=-44 more_errno=dd,A flags=0
103 SMTP>> QUIT
76003495 104cmd buf flush ddd bytes
b9d9c5a2 105 SMTP(close)>>
56809214 106fresh-exec forked for transport: npppp
67ea939c 107set_process_info: pppp delivering 10HmaX-0005vi-00: just tried 127.0.0.1 [127.0.0.1]:PORT_S for x@y: result OK
cd5567b1 108address match test: subject=*@127.0.0.1 pattern=*
54c5ebb1
PH
109127.0.0.1 in "*"? yes (matched "*")
110*@127.0.0.1 in "*"? yes (matched "*")
1813a1e0 111set_process_info: pppp delivering 10HmaX-0005vi-00: waiting for a remote delivery subprocess to finish
54c5ebb1 112checking status of V4NET.0.0.0
966e829c
JH
113 locking TESTSUITE/spool/db/retry.lockfile
114 locked TESTSUITE/spool/db/retry.lockfile
115 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
116 returned from EXIM_DBOPEN: (nil)
117 failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
54c5ebb1
PH
118no retry data available
119V4NET.0.0.0 in serialize_hosts? no (option unset)
67ea939c 120set_process_info: pppp delivering 10HmaX-0005vi-00 to V4NET.0.0.0 [V4NET.0.0.0]:PORT_S (x@y)
adb21834 121Connecting to V4NET.0.0.0 [V4NET.0.0.0]:1224 ... failed: Network Error
54c5ebb1 122LOG: MAIN
c562fd30 123 H=V4NET.0.0.0 [V4NET.0.0.0] Network Error
67ea939c 124set_process_info: pppp delivering 10HmaX-0005vi-00: just tried V4NET.0.0.0 [V4NET.0.0.0]:PORT_S for x@y: result DEFER
7eb59ff9 125added retry item for T:V4NET.0.0.0:V4NET.0.0.0:1224: errno=dd more_errno=dd,A flags=2
54c5ebb1
PH
126set_process_info: pppp delivering 10HmaX-0005vi-00 (just run smtp for x@y in subprocess)
127search_tidyup called
7eb59ff9 128reading retry information for T:V4NET.0.0.0:V4NET.0.0.0:1224 from subprocess
54c5ebb1
PH
129 added retry item
130reading retry information for R:x@y from subprocess
131 added retry item
1813a1e0 132set_process_info: pppp delivering 10HmaX-0005vi-00
54c5ebb1 133LOG: MAIN
c562fd30 134 == x@y R=r1 T=smtp defer (dd): Network Error H=127.0.0.1 [127.0.0.1]
54c5ebb1
PH
135changed uid/gid: post-delivery tidying
136 uid=EXIM_UID gid=EXIM_GID pid=pppp
137set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
138Processing retry items
139Succeeded addresses:
140Failed addresses:
141Deferred addresses:
7b4c8c1f 142 x@y
966e829c
JH
143 locking TESTSUITE/spool/db/retry.lockfile
144 locked TESTSUITE/spool/db/retry.lockfile
145 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDWR
146 returned from EXIM_DBOPEN: 0xAAAAAAAA
147 opened hints database TESTSUITE/spool/db/retry: flags=O_RDWR
cd5567b1 148address match test: subject=x@y pattern=*
54c5ebb1
PH
149y in "*"? yes (matched "*")
150x@y in "*"? yes (matched "*")
ea49d0e1 151retry for R:x@y = * 0 0
966e829c 152 dbfn_read: key=R:x@y
727071f8 153failing_interval=ttt message_age=ttt
54c5ebb1 154Writing retry data for R:x@y
727071f8 155 first failed=dddd last try=dddd next try=+1 expired=1
c562fd30 156 errno=-44 more_errno=dd,A H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<x@y>: 451 Temporary error
966e829c 157 dbfn_write: key=R:x@y
cd5567b1 158address match test: subject=*@V4NET.0.0.0 pattern=*
54c5ebb1
PH
159V4NET.0.0.0 in "*"? yes (matched "*")
160*@V4NET.0.0.0 in "*"? yes (matched "*")
7eb59ff9 161retry for T:V4NET.0.0.0:V4NET.0.0.0:1224 (y) = * 0 0
966e829c 162 dbfn_read: key=T:V4NET.0.0.0:V4NET.0.0.0:1224
727071f8 163failing_interval=ttt message_age=ttt
dd16e114 164on queue longer than maximum retry
7eb59ff9 165Writing retry data for T:V4NET.0.0.0:V4NET.0.0.0:1224
dd16e114 166 first failed=dddd last try=dddd next try=+0 expired=0
54c5ebb1 167 errno=dd more_errno=dd,A Network Error
966e829c 168 dbfn_write: key=T:V4NET.0.0.0:V4NET.0.0.0:1224
54c5ebb1
PH
169timed out: all retries expired
170LOG: MAIN
171 ** x@y: retry timeout exceeded
966e829c
JH
172 EXIM_DBCLOSE(0xAAAAAAAA)
173 closed hints database and lockfile
54c5ebb1 174end of retry processing
4b01271f
JH
175fresh-exec forking for bounce-message
176postfork: bounce-message
177exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -MCd bounce-message -odi -odi -t -oem -oi -f <> -E10HmaX-0005vi-00
178fresh-exec forked for bounce-message: npppp
54c5ebb1
PH
179Exim version x.yz ....
180changed uid/gid: forcing real = effective
181 uid=uuuu gid=EXIM_GID pid=pppp
182configuration file is TESTSUITE/test-config
183trusted user
184admin user
185changed uid/gid: privilege not needed
186 uid=EXIM_UID gid=EXIM_GID pid=pppp
187originator: uid=EXIM_UID gid=EXIM_GID login=EXIMUSER name=
188sender address =
189set_process_info: pppp accepting a local non-SMTP message from <>
ddf1b11a
JH
190spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
191log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
54c5ebb1
PH
192Sender:
193search_tidyup called
194>>Headers received:
195X-Failed-Recipients: x@y
196Auto-Submitted: auto-replied
197From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
198To: CALLER@myhost.test.ex
d6c829b9 199References: <E10HmaX-0005vi-00@myhost.test.ex>
50dc7409
JH
200Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
201MIME-Version: 1.0
54c5ebb1
PH
202Subject: Mail delivery failed: returning message to sender
203
204rewrite_one_header: type=F:
205 From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
206rewrite_one_header: type=T:
207 To: CALLER@myhost.test.ex
208search_tidyup called
209>>Headers after rewriting and local additions:
210 X-Failed-Recipients: x@y
211 Auto-Submitted: auto-replied
212F From: Mail Delivery System <Mailer-Daemon@myhost.test.ex>
213T To: CALLER@myhost.test.ex
d6c829b9 214 References: <E10HmaX-0005vi-00@myhost.test.ex>
50dc7409
JH
215 Content-Type: multipart/report; report-type=delivery-status; boundary=NNNNNNNNNN-eximdsn-MMMMMMMMMM
216 MIME-Version: 1.0
54c5ebb1 217 Subject: Mail delivery failed: returning message to sender
b3f43fa0
JJ
218I Message-Id: <E10HmaY-0005vi-00@myhost.test.ex>
219 Date: Tue, 2 Mar 1999 09:44:33 +0000
54c5ebb1 220
0971ec06 221Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D
54c5ebb1
PH
222Data file written for message 10HmaY-0005vi-00
223>>Generated Received: header line
224P Received: from EXIMUSER by myhost.test.ex with local (Exim x.yz)
225 id 10HmaY-0005vi-00
226 for CALLER@myhost.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
ced52486 227Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00
0971ec06 228Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
54c5ebb1
PH
229Size of headers = sss
230LOG: MAIN
231 <= <> R=10HmaX-0005vi-00 U=EXIMUSER P=local S=sss
232search_tidyup called
4b01271f 233bounce-message forking for local-accept-delivery
32393657
JH
234postfork: local-accept-delivery
235exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xd7715ced -MCd local-accept-delivery -odi -Mc 10HmaY-0005vi-00
4b01271f 236bounce-message forked for local-accept-delivery: npppp
54c5ebb1
PH
237Exim version x.yz ....
238changed uid/gid: forcing real = effective
239 uid=uuuu gid=EXIM_GID pid=pppp
240configuration file is TESTSUITE/test-config
241trusted user
242admin user
4b424e0d 243dropping to exim gid; retaining priv uid
54c5ebb1
PH
244set_process_info: pppp delivering specified messages
245set_process_info: pppp delivering 10HmaY-0005vi-00
966e829c
JH
246 locking TESTSUITE/spool/db/retry.lockfile
247 locked TESTSUITE/spool/db/retry.lockfile
248 EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
249 returned from EXIM_DBOPEN: 0xAAAAAAAA
250 opened hints database TESTSUITE/spool/db/retry: flags=O_RDONLY
54c5ebb1
PH
251>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
252Considering: CALLER@myhost.test.ex
253unique = CALLER@myhost.test.ex
966e829c
JH
254 dbfn_read: key=R:myhost.test.ex
255 dbfn_read: key=R:CALLER@myhost.test.ex
256 dbfn_read: key=R:CALLER@myhost.test.ex:<>
de6f74f2
JH
257no domain retry record
258no address retry record
54c5ebb1 259CALLER@myhost.test.ex: queued for routing
966e829c
JH
260 EXIM_DBCLOSE(0xAAAAAAAA)
261 closed hints database and lockfile
54c5ebb1
PH
262>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
263routing CALLER@myhost.test.ex
264--------> r0 router <--------
265local_part=CALLER domain=myhost.test.ex
266checking local_parts
267CALLER in "CALLER"? yes (matched "CALLER")
268checking senders
cd5567b1 269address match test: subject= pattern=
54c5ebb1
PH
270 in ":"? yes (matched "")
271calling r0 router
f3ebb786
JH
272rda_interpret (string): ':blackhole:'
273expanded: ':blackhole:'
54c5ebb1
PH
274file is not a filter file
275parse_forward_list: :blackhole:
276extract item: :blackhole:
277address :blackhole:d
278LOG: MAIN
279 => :blackhole: <CALLER@myhost.test.ex> R=r0
280>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
281After routing:
282 Local deliveries:
283 Remote deliveries:
284 Failed addresses:
285 Deferred addresses:
286search_tidyup called
287changed uid/gid: post-delivery tidying
288 uid=EXIM_UID gid=EXIM_GID pid=pppp
289set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
290Processing retry items
291Succeeded addresses:
7b4c8c1f 292 CALLER@myhost.test.ex: no retry items
54c5ebb1
PH
293Failed addresses:
294Deferred addresses:
295end of retry processing
296LOG: MAIN
297 Completed
298search_tidyup called
299search_tidyup called
9bfb7e1b 300>>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
54c5ebb1 301search_tidyup called
9bfb7e1b 302>>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
ced52486 303Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
0971ec06 304Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
54c5ebb1
PH
305Size of headers = sss
306LOG: MAIN
307 Completed
308search_tidyup called
309search_tidyup called
9bfb7e1b 310>>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>