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