Commit | Line | Data |
---|---|---|
54c5ebb1 PH |
1 | Exim version x.yz .... |
2 | changed uid/gid: forcing real = effective | |
3 | uid=uuuu gid=CALLER_GID pid=pppp | |
d8fe1c03 PH |
4 | seeking password data for user "CALLER": cache not available |
5 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
6 | configuration file is TESTSUITE/test-config |
7 | trusted user | |
8 | admin user | |
9 | changed uid/gid: privilege not needed | |
10 | uid=EXIM_UID gid=EXIM_GID pid=pppp | |
50dc7409 | 11 | DSN: r1 propagating DSN |
d8fe1c03 PH |
12 | seeking password data for user "CALLER": using cached result |
13 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
14 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
15 | sender address = CALLER@test.ex | |
16 | sender_fullhost = [V4NET.9.8.7] | |
17 | sender_rcvhost = [V4NET.9.8.7] | |
18 | host in hosts_connection_nolog? no (option unset) | |
19 | LOG: smtp_connection MAIN | |
20 | SMTP connection from [V4NET.9.8.7] | |
21 | host in host_lookup? no (option unset) | |
22 | set_process_info: pppp handling incoming connection from [V4NET.9.8.7] | |
23 | host in host_reject_connection? no (option unset) | |
24 | host in sender_unqualified_hosts? no (option unset) | |
25 | host in recipient_unqualified_hosts? no (option unset) | |
26 | host in helo_verify_hosts? no (option unset) | |
27 | host in helo_try_verify_hosts? no (option unset) | |
28 | host in helo_accept_junk_hosts? no (option unset) | |
898d150f | 29 | SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 |
54c5ebb1 PH |
30 | smtp_setup_msg entered |
31 | SMTP<< mail from:<x@y> | |
ddf1b11a | 32 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
ad424056 | 33 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
898d150f | 34 | SMTP>> 250 OK |
54c5ebb1 PH |
35 | SMTP<< rcpt to:<1@b> |
36 | read ACL from file TESTSUITE/aux-fixed/0386.acl1 | |
37 | processing "accept" | |
38 | check hosts = : | |
39 | host in ":"? no (end of list) | |
6968512f | 40 | accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 PH |
41 | processing "deny" |
42 | check local_parts = ^.*[@%!/|] | |
43 | 1 in "^.*[@%!/|]"? no (end of list) | |
6968512f | 44 | deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 | 45 | processing "require" |
187bc588 JH |
46 | l_message: Invalid sender |
47 | message: Couldn't verify the sender | |
54c5ebb1 PH |
48 | check verify = sender/defer_ok |
49 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
50 | Verifying x@y | |
51 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
52 | Considering x@y | |
53 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
54 | routing x@y | |
55 | --------> r1 router <-------- | |
56 | local_part=x domain=y | |
57 | calling r1 router | |
58 | r1 router called for x@y | |
59 | domain = y | |
60 | set transport t1 | |
61 | queued for t1 transport: local_part = x | |
62 | domain = y | |
63 | errors_to=NULL | |
64 | domain_data=NULL localpart_data=NULL | |
65 | routed by r1 router | |
66 | envelope to: x@y | |
67 | transport: t1 | |
68 | ----------- end verify ------------ | |
69 | sender x@y verified ok | |
6968512f | 70 | require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 | 71 | processing "deny" |
187bc588 | 72 | message: No such user here |
6968512f | 73 | deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
a7538db1 | 74 | end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY |
898d150f | 75 | SMTP>> 550 No such user here |
54c5ebb1 PH |
76 | LOG: MAIN REJECT |
77 | H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here | |
78 | SMTP<< rset | |
898d150f | 79 | SMTP>> 250 Reset OK |
54c5ebb1 | 80 | SMTP<< mail from:<x@y> |
ddf1b11a | 81 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
ad424056 | 82 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
898d150f | 83 | SMTP>> 250 OK |
54c5ebb1 PH |
84 | SMTP<< rcpt to:<1@b> |
85 | using ACL "TESTSUITE/aux-fixed/0386.acl1" | |
86 | processing "accept" | |
87 | check hosts = : | |
88 | host in ":"? no (end of list) | |
6968512f | 89 | accept: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 PH |
90 | processing "deny" |
91 | check local_parts = ^.*[@%!/|] | |
92 | 1 in "^.*[@%!/|]"? no (end of list) | |
6968512f | 93 | deny: condition test failed in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 | 94 | processing "require" |
187bc588 JH |
95 | l_message: Invalid sender |
96 | message: Couldn't verify the sender | |
54c5ebb1 PH |
97 | check verify = sender/defer_ok |
98 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
99 | Verifying x@y | |
100 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
101 | Considering x@y | |
102 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
103 | routing x@y | |
104 | --------> r1 router <-------- | |
105 | local_part=x domain=y | |
106 | calling r1 router | |
107 | r1 router called for x@y | |
108 | domain = y | |
109 | queued for t1 transport: local_part = x | |
110 | domain = y | |
111 | errors_to=NULL | |
112 | domain_data=NULL localpart_data=NULL | |
113 | routed by r1 router | |
114 | envelope to: x@y | |
115 | transport: t1 | |
116 | ----------- end verify ------------ | |
117 | sender x@y verified ok | |
6968512f | 118 | require: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
54c5ebb1 | 119 | processing "deny" |
187bc588 | 120 | message: No such user here |
6968512f | 121 | deny: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl1" |
a7538db1 | 122 | end of ACL "TESTSUITE/aux-fixed/0386.acl1": DENY |
898d150f | 123 | SMTP>> 550 No such user here |
54c5ebb1 PH |
124 | LOG: MAIN REJECT |
125 | H=[V4NET.9.8.7] F=<x@y> rejected RCPT <1@b>: No such user here | |
126 | SMTP<< quit | |
898d150f | 127 | SMTP>> 221 myhost.test.ex closing connection |
54c5ebb1 PH |
128 | LOG: smtp_connection MAIN |
129 | SMTP connection from [V4NET.9.8.7] closed by QUIT | |
130 | search_tidyup called | |
131 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> | |
132 | Exim version x.yz .... | |
133 | changed uid/gid: forcing real = effective | |
134 | uid=uuuu gid=CALLER_GID pid=pppp | |
d8fe1c03 PH |
135 | seeking password data for user "CALLER": cache not available |
136 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
137 | configuration file is TESTSUITE/test-config |
138 | trusted user | |
139 | admin user | |
140 | changed uid/gid: privilege not needed | |
141 | uid=EXIM_UID gid=EXIM_GID pid=pppp | |
50dc7409 | 142 | DSN: r1 propagating DSN |
d8fe1c03 PH |
143 | seeking password data for user "CALLER": using cached result |
144 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
145 | originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME |
146 | sender address = NULL | |
147 | sender_fullhost = [V4NET.11.12.13] | |
148 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) | |
149 | set_process_info: pppp handling incoming connection from [V4NET.11.12.13] via -oMa | |
273f34d0 | 150 | set_process_info: pppp accepting a local SMTP message from <CALLER> |
54c5ebb1 PH |
151 | host in hosts_connection_nolog? no (option unset) |
152 | LOG: smtp_connection MAIN | |
153 | SMTP connection from CALLER | |
154 | host in host_lookup? no (option unset) | |
155 | set_process_info: pppp handling incoming connection from [V4NET.11.12.13] U=CALLER | |
156 | host in host_reject_connection? no (option unset) | |
157 | host in sender_unqualified_hosts? no (option unset) | |
158 | host in recipient_unqualified_hosts? no (option unset) | |
159 | host in helo_verify_hosts? no (option unset) | |
160 | host in helo_try_verify_hosts? no (option unset) | |
161 | host in helo_accept_junk_hosts? no (option unset) | |
898d150f | 162 | SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000 |
54c5ebb1 PH |
163 | smtp_setup_msg entered |
164 | SMTP<< mail from:<x@y> | |
ddf1b11a | 165 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
ad424056 | 166 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 |
898d150f | 167 | SMTP>> 250 OK |
54c5ebb1 PH |
168 | SMTP<< rcpt to:<2@b> |
169 | read ACL from file TESTSUITE/aux-fixed/0386.acl2 | |
170 | processing "warn" | |
187bc588 JH |
171 | message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text |
172 | l_message: found in $dnslist_domain: $dnslist_text | |
54c5ebb1 PH |
173 | check dnslists = rbl.test.ex |
174 | DNS list check: rbl.test.ex | |
175 | new DNS lookup for 13.12.11.V4NET.rbl.test.ex | |
176 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) using fakens | |
177 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (A) succeeded | |
178 | DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) | |
179 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) using fakens | |
180 | DNS lookup of 13.12.11.V4NET.rbl.test.ex (TXT) succeeded | |
181 | => that means V4NET.11.12.13 is listed at rbl.test.ex | |
6968512f | 182 | warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
54c5ebb1 PH |
183 | LOG: MAIN |
184 | H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message | |
185 | created log directory TESTSUITE/spool/log | |
186 | processing "accept" | |
6968512f | 187 | accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
a7538db1 | 188 | end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT |
898d150f | 189 | SMTP>> 250 Accepted |
50dc7409 | 190 | DSN: orcpt: NULL flags: 0 |
54c5ebb1 | 191 | SMTP<< data |
898d150f | 192 | SMTP>> 354 Enter message, ending with "." on a line by itself |
54c5ebb1 | 193 | search_tidyup called |
bd8fbe36 JH |
194 | PDKIM >> raw hdr: {CR}{LF} |
195 | PDKIM >> raw hdr: Message{SP}1{CR}{LF} | |
196 | PDKIM >> raw hdr: .{CR}{LF} | |
197 | PDKIM >> raw hdr: rset{CR}{LF} | |
198 | PDKIM >> raw hdr: mail{SP}from:<x@y>{CR}{LF} | |
199 | PDKIM >> raw hdr: rcpt{SP}to:<2@b>{CR}{LF} | |
200 | PDKIM >> raw hdr: data{CR}{LF} | |
54c5ebb1 PH |
201 | >>Headers received: |
202 | ||
203 | search_tidyup called | |
204 | >>Headers after rewriting and local additions: | |
205 | ||
0971ec06 | 206 | Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D |
54c5ebb1 PH |
207 | Data file written for message 10HmaX-0005vi-00 |
208 | >>Generated Received: header line | |
209 | P Received: from [V4NET.11.12.13] (ident=CALLER) | |
210 | by myhost.test.ex with smtp (Exim x.yz) | |
211 | (envelope-from <x@y>) | |
212 | id 10HmaX-0005vi-00 | |
213 | for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 | |
214 | >>Headers added by MAIL or RCPT ACL: | |
215 | X-Warning: V4NET.11.12.13 is listed at rbl.test.ex | |
216 | X-Warning: This is a test blacklisting message | |
217 | >> | |
218 | calling local_scan(); timeout=300 | |
219 | local_scan() returned 0 NULL | |
0971ec06 | 220 | Writing spool header file: TESTSUITE/spool//input//hdr.pppp |
50dc7409 JH |
221 | DSN: Write SPOOL :-dsn_envid NULL |
222 | DSN: Write SPOOL :-dsn_ret 0 | |
223 | DSN: Flags :0 | |
224 | DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0 | |
0971ec06 | 225 | Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H |
54c5ebb1 PH |
226 | Size of headers = sss |
227 | LOG: MAIN | |
228 | <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss | |
898d150f | 229 | SMTP>> 250 OK id=10HmaX-0005vi-00 |
54c5ebb1 PH |
230 | search_tidyup called |
231 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00 | |
232 | Exim version x.yz .... | |
233 | changed uid/gid: forcing real = effective | |
234 | uid=uuuu gid=EXIM_GID pid=pppp | |
d8fe1c03 PH |
235 | seeking password data for user "CALLER": cache not available |
236 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
237 | configuration file is TESTSUITE/test-config |
238 | trusted user | |
239 | admin user | |
50dc7409 | 240 | DSN: r1 propagating DSN |
d8fe1c03 PH |
241 | seeking password data for user "CALLER": using cached result |
242 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
243 | set_process_info: pppp delivering specified messages |
244 | set_process_info: pppp delivering 10HmaX-0005vi-00 | |
0971ec06 | 245 | Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D |
54c5ebb1 PH |
246 | reading spool file 10HmaX-0005vi-00-H |
247 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y | |
248 | sender_fullhost = [V4NET.11.12.13] | |
249 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) | |
250 | sender_local=0 ident=CALLER | |
251 | Non-recipients: | |
252 | Empty Tree | |
253 | ---- End of tree ---- | |
254 | recipients_count=1 | |
50dc7409 | 255 | **** SPOOL_IN - No additional fields |
54c5ebb1 | 256 | body_linecount=1 message_linecount=7 |
50dc7409 | 257 | DSN: set orcpt: NULL flags: 0 |
54c5ebb1 PH |
258 | Delivery address list: |
259 | 2@b | |
260 | locking TESTSUITE/spool/db/retry.lockfile | |
7b4c8c1f | 261 | locked TESTSUITE/spool/db/retry.lockfile |
54c5ebb1 PH |
262 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
263 | returned from EXIM_DBOPEN | |
264 | ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim | |
265 | no retry data available | |
266 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
267 | Considering: 2@b | |
268 | unique = 2@b | |
269 | no domain retry record | |
270 | no address retry record | |
271 | 2@b: queued for routing | |
272 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
273 | routing 2@b | |
274 | --------> r1 router <-------- | |
275 | local_part=2 domain=b | |
276 | calling r1 router | |
277 | r1 router called for 2@b | |
278 | domain = b | |
279 | set transport t1 | |
280 | queued for t1 transport: local_part = 2 | |
281 | domain = b | |
282 | errors_to=NULL | |
283 | domain_data=NULL localpart_data=NULL | |
284 | routed by r1 router | |
285 | envelope to: 2@b | |
286 | transport: t1 | |
287 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
288 | After routing: | |
289 | Local deliveries: | |
290 | 2@b | |
291 | Remote deliveries: | |
292 | Failed addresses: | |
293 | Deferred addresses: | |
294 | search_tidyup called | |
295 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> | |
296 | --------> 2@b <-------- | |
297 | locking TESTSUITE/spool/db/retry.lockfile | |
7b4c8c1f | 298 | locked TESTSUITE/spool/db/retry.lockfile |
54c5ebb1 PH |
299 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
300 | returned from EXIM_DBOPEN | |
301 | no retry data available | |
302 | search_tidyup called | |
303 | changed uid/gid: local delivery to 2 <2@b> transport=t1 | |
304 | uid=CALLER_UID gid=CALLER_GID pid=pppp | |
305 | home=NULL current=/ | |
306 | set_process_info: pppp delivering 10HmaX-0005vi-00 to 2 using t1 | |
307 | appendfile transport entered | |
308 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 | |
309 | file=TESTSUITE/test-mail/2 format=unix | |
310 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n | |
311 | message_suffix=\n | |
312 | maildir_use_size_file=no | |
313 | locking by lockfile fcntl | |
314 | lock name: TESTSUITE/test-mail/2.lock | |
315 | hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp | |
316 | lock file created | |
317 | mailbox TESTSUITE/test-mail/2 is locked | |
318 | writing to file TESTSUITE/test-mail/2 | |
1b781f48 PH |
319 | writing data block fd=dddd size=sss timeout=0 |
320 | writing data block fd=dddd size=sss timeout=0 | |
321 | writing data block fd=dddd size=sss timeout=0 | |
54c5ebb1 PH |
322 | appendfile yields 0 with errno=dd more_errno=dd |
323 | search_tidyup called | |
324 | journalling 2@b | |
325 | t1 transport returned OK for 2@b | |
326 | post-process 2@b (0) | |
327 | 2@b delivered | |
328 | LOG: MAIN | |
329 | => 2 <2@b> R=r1 T=t1 | |
330 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> | |
331 | changed uid/gid: post-delivery tidying | |
332 | uid=EXIM_UID gid=EXIM_GID pid=pppp | |
333 | set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00 | |
334 | Processing retry items | |
335 | Succeeded addresses: | |
7b4c8c1f | 336 | 2@b: no retry items |
54c5ebb1 PH |
337 | Failed addresses: |
338 | Deferred addresses: | |
339 | end of retry processing | |
50dc7409 JH |
340 | DSN: processing router : r1 |
341 | DSN: processing successful delivery address: 2@b | |
342 | DSN: Sender_address: x@y | |
343 | DSN: orcpt: NULL flags: 0 | |
344 | DSN: envid: NULL ret: 0 | |
345 | DSN: Final recipient: 2@b | |
346 | DSN: Remote SMTP server supports DSN: 0 | |
9671784a | 347 | DSN: not sending DSN success message |
54c5ebb1 PH |
348 | LOG: MAIN |
349 | Completed | |
350 | end delivery of 10HmaX-0005vi-00 | |
351 | search_tidyup called | |
352 | search_tidyup called | |
353 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> | |
354 | smtp_setup_msg entered | |
355 | SMTP<< rset | |
898d150f | 356 | SMTP>> 250 Reset OK |
54c5ebb1 | 357 | SMTP<< mail from:<x@y> |
ddf1b11a JH |
358 | spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0 |
359 | log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 | |
898d150f | 360 | SMTP>> 250 OK |
54c5ebb1 PH |
361 | SMTP<< rcpt to:<2@b> |
362 | using ACL "TESTSUITE/aux-fixed/0386.acl2" | |
363 | processing "warn" | |
187bc588 JH |
364 | message: X-Warning: $sender_host_address is listed at $dnslist_domain\nX-Warning: $dnslist_text |
365 | l_message: found in $dnslist_domain: $dnslist_text | |
54c5ebb1 PH |
366 | check dnslists = rbl.test.ex |
367 | DNS list check: rbl.test.ex | |
368 | using result of previous DNS lookup | |
369 | DNS lookup for 13.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2) | |
370 | => that means V4NET.11.12.13 is listed at rbl.test.ex | |
6968512f | 371 | warn: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
54c5ebb1 PH |
372 | LOG: MAIN |
373 | H=[V4NET.11.12.13] U=CALLER Warning: found in rbl.test.ex: This is a test blacklisting message | |
374 | processing "accept" | |
6968512f | 375 | accept: condition test succeeded in ACL "TESTSUITE/aux-fixed/0386.acl2" |
a7538db1 | 376 | end of ACL "TESTSUITE/aux-fixed/0386.acl2": ACCEPT |
898d150f | 377 | SMTP>> 250 Accepted |
50dc7409 | 378 | DSN: orcpt: NULL flags: 0 |
54c5ebb1 | 379 | SMTP<< data |
898d150f | 380 | SMTP>> 354 Enter message, ending with "." on a line by itself |
54c5ebb1 | 381 | search_tidyup called |
bd8fbe36 JH |
382 | PDKIM >> raw hdr: {CR}{LF} |
383 | PDKIM >> raw hdr: Message{SP}2{CR}{LF} | |
384 | PDKIM >> raw hdr: .{CR}{LF} | |
54c5ebb1 PH |
385 | >>Headers received: |
386 | ||
387 | search_tidyup called | |
388 | >>Headers after rewriting and local additions: | |
389 | ||
0971ec06 | 390 | Data file name: TESTSUITE/spool//input//10HmaY-0005vi-00-D |
54c5ebb1 PH |
391 | Data file written for message 10HmaY-0005vi-00 |
392 | >>Generated Received: header line | |
393 | P Received: from [V4NET.11.12.13] (ident=CALLER) | |
394 | by myhost.test.ex with smtp (Exim x.yz) | |
395 | (envelope-from <x@y>) | |
396 | id 10HmaY-0005vi-00 | |
397 | for 2@b; Tue, 2 Mar 1999 09:44:33 +0000 | |
398 | >>Headers added by MAIL or RCPT ACL: | |
399 | X-Warning: V4NET.11.12.13 is listed at rbl.test.ex | |
400 | X-Warning: This is a test blacklisting message | |
401 | >> | |
bd8fbe36 | 402 | PDKIM >> raw hdr: quit{CR}{LF} |
54c5ebb1 PH |
403 | calling local_scan(); timeout=300 |
404 | local_scan() returned 0 NULL | |
0971ec06 | 405 | Writing spool header file: TESTSUITE/spool//input//hdr.pppp |
50dc7409 JH |
406 | DSN: Write SPOOL :-dsn_envid NULL |
407 | DSN: Write SPOOL :-dsn_ret 0 | |
408 | DSN: Flags :0 | |
409 | DSN: **** SPOOL_OUT - address: |2@b| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0 | |
0971ec06 | 410 | Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H |
54c5ebb1 PH |
411 | Size of headers = sss |
412 | LOG: MAIN | |
413 | <= x@y H=[V4NET.11.12.13] U=CALLER P=smtp S=sss | |
898d150f | 414 | SMTP>> 250 OK id=10HmaY-0005vi-00 |
54c5ebb1 PH |
415 | search_tidyup called |
416 | exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaY-0005vi-00 | |
417 | Exim version x.yz .... | |
418 | changed uid/gid: forcing real = effective | |
419 | uid=uuuu gid=EXIM_GID pid=pppp | |
d8fe1c03 PH |
420 | seeking password data for user "CALLER": cache not available |
421 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
422 | configuration file is TESTSUITE/test-config |
423 | trusted user | |
424 | admin user | |
50dc7409 | 425 | DSN: r1 propagating DSN |
d8fe1c03 PH |
426 | seeking password data for user "CALLER": using cached result |
427 | getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID | |
54c5ebb1 PH |
428 | set_process_info: pppp delivering specified messages |
429 | set_process_info: pppp delivering 10HmaY-0005vi-00 | |
0971ec06 | 430 | Trying spool file TESTSUITE/spool//input//10HmaY-0005vi-00-D |
54c5ebb1 PH |
431 | reading spool file 10HmaY-0005vi-00-H |
432 | user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y | |
433 | sender_fullhost = [V4NET.11.12.13] | |
434 | sender_rcvhost = [V4NET.11.12.13] (ident=CALLER) | |
435 | sender_local=0 ident=CALLER | |
436 | Non-recipients: | |
437 | Empty Tree | |
438 | ---- End of tree ---- | |
439 | recipients_count=1 | |
50dc7409 | 440 | **** SPOOL_IN - No additional fields |
54c5ebb1 | 441 | body_linecount=1 message_linecount=7 |
50dc7409 | 442 | DSN: set orcpt: NULL flags: 0 |
54c5ebb1 PH |
443 | Delivery address list: |
444 | 2@b | |
445 | locking TESTSUITE/spool/db/retry.lockfile | |
7b4c8c1f | 446 | locked TESTSUITE/spool/db/retry.lockfile |
54c5ebb1 PH |
447 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
448 | returned from EXIM_DBOPEN | |
449 | no retry data available | |
450 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
451 | Considering: 2@b | |
452 | unique = 2@b | |
453 | no domain retry record | |
454 | no address retry record | |
455 | 2@b: queued for routing | |
456 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
457 | routing 2@b | |
458 | --------> r1 router <-------- | |
459 | local_part=2 domain=b | |
460 | calling r1 router | |
461 | r1 router called for 2@b | |
462 | domain = b | |
463 | set transport t1 | |
464 | queued for t1 transport: local_part = 2 | |
465 | domain = b | |
466 | errors_to=NULL | |
467 | domain_data=NULL localpart_data=NULL | |
468 | routed by r1 router | |
469 | envelope to: 2@b | |
470 | transport: t1 | |
471 | >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> | |
472 | After routing: | |
473 | Local deliveries: | |
474 | 2@b | |
475 | Remote deliveries: | |
476 | Failed addresses: | |
477 | Deferred addresses: | |
478 | search_tidyup called | |
479 | >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>> | |
480 | --------> 2@b <-------- | |
481 | locking TESTSUITE/spool/db/retry.lockfile | |
7b4c8c1f | 482 | locked TESTSUITE/spool/db/retry.lockfile |
54c5ebb1 PH |
483 | EXIM_DBOPEN(TESTSUITE/spool/db/retry) |
484 | returned from EXIM_DBOPEN | |
485 | no retry data available | |
486 | search_tidyup called | |
487 | changed uid/gid: local delivery to 2 <2@b> transport=t1 | |
488 | uid=CALLER_UID gid=CALLER_GID pid=pppp | |
489 | home=NULL current=/ | |
490 | set_process_info: pppp delivering 10HmaY-0005vi-00 to 2 using t1 | |
491 | appendfile transport entered | |
492 | appendfile: mode=600 notify_comsat=0 quota=0 warning=0 | |
493 | file=TESTSUITE/test-mail/2 format=unix | |
494 | message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n | |
495 | message_suffix=\n | |
496 | maildir_use_size_file=no | |
497 | locking by lockfile fcntl | |
498 | lock name: TESTSUITE/test-mail/2.lock | |
499 | hitch name: TESTSUITE/test-mail/2.lock.test.ex.dddddddd.pppppppp | |
500 | lock file created | |
501 | mailbox TESTSUITE/test-mail/2 is locked | |
502 | writing to file TESTSUITE/test-mail/2 | |
1b781f48 PH |
503 | writing data block fd=dddd size=sss timeout=0 |
504 | writing data block fd=dddd size=sss timeout=0 | |
505 | writing data block fd=dddd size=sss timeout=0 | |
54c5ebb1 PH |
506 | appendfile yields 0 with errno=dd more_errno=dd |
507 | search_tidyup called | |
508 | journalling 2@b | |
509 | t1 transport returned OK for 2@b | |
510 | post-process 2@b (0) | |
511 | 2@b delivered | |
512 | LOG: MAIN | |
513 | => 2 <2@b> R=r1 T=t1 | |
514 | >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>> | |
515 | changed uid/gid: post-delivery tidying | |
516 | uid=EXIM_UID gid=EXIM_GID pid=pppp | |
517 | set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00 | |
518 | Processing retry items | |
519 | Succeeded addresses: | |
7b4c8c1f | 520 | 2@b: no retry items |
54c5ebb1 PH |
521 | Failed addresses: |
522 | Deferred addresses: | |
523 | end of retry processing | |
50dc7409 JH |
524 | DSN: processing router : r1 |
525 | DSN: processing successful delivery address: 2@b | |
526 | DSN: Sender_address: x@y | |
527 | DSN: orcpt: NULL flags: 0 | |
528 | DSN: envid: NULL ret: 0 | |
529 | DSN: Final recipient: 2@b | |
530 | DSN: Remote SMTP server supports DSN: 0 | |
9671784a | 531 | DSN: not sending DSN success message |
54c5ebb1 PH |
532 | LOG: MAIN |
533 | Completed | |
534 | end delivery of 10HmaY-0005vi-00 | |
535 | search_tidyup called | |
536 | search_tidyup called | |
537 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> | |
538 | smtp_setup_msg entered | |
539 | SMTP<< quit | |
898d150f | 540 | SMTP>> 221 myhost.test.ex closing connection |
54c5ebb1 PH |
541 | LOG: smtp_connection MAIN |
542 | SMTP connection from CALLER closed by QUIT | |
543 | search_tidyup called | |
544 | >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>> |