ACL: Ensure that acl_smtp_notquit is called for a conndrop between data-go-ahead...
[users/jgh/exim.git] / test / stderr / 0487
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=pppp
4 seeking password data for user "CALLER": cache not available
5 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
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
11 DSN: r1 propagating DSN
12 seeking password data for user "CALLER": using cached result
13 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
14 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
15 sender address = NULL
16 set_process_info: pppp accepting a local SMTP message from <CALLER>
17  in hosts_connection_nolog? no (option unset)
18 LOG: smtp_connection MAIN
19   SMTP connection from CALLER
20 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
21 smtp_setup_msg entered
22 SMTP<< ehlo x.y
23  in dsn_advertise_hosts? no (option unset)
24  in pipelining_advertise_hosts? yes (matched "*")
25  in chunking_advertise_hosts? no (end of list)
26 SMTP>> 250-myhost.test.ex Hello CALLER at x.y
27 250-SIZE 52428800
28 250-8BITMIME
29 250-PIPELINING
30 250 HELP
31 SMTP<< mail from:<x@y>
32 SMTP>> 250 OK
33 SMTP<< rcpt to:<userx@test.ex>
34 processing "accept"
35 accept: condition test succeeded in inline ACL
36 end of inline ACL: ACCEPT
37 SMTP>> 250 Accepted
38 DSN: orcpt: NULL  flags: 0
39 SMTP<< data
40 SMTP>> 354 Enter message, ending with "." on a line by itself
41 search_tidyup called
42 PDKIM >> Body data for hash, canonicalized >>>>>>>>>>>>>>>>>>>>>>
43 >>Headers received:
44 From: unqualified
45
46 rewrite_one_header: type=F:
47   From: unqualified
48 search_tidyup called
49 >>Headers after rewriting and local additions:
50 F From: unqualified
51 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
52   Date: Tue, 2 Mar 1999 09:44:33 +0000
53
54 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
55 Data file written for message 10HmaX-0005vi-00
56 >>Generated Received: header line
57 P Received: from CALLER (helo=x.y)
58         by myhost.test.ex with local-esmtp (Exim x.yz)
59         (envelope-from <x@y>)
60         id 10HmaX-0005vi-00
61         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
62 calling local_scan(); timeout=300
63 local_scan() returned 0 NULL
64 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
65 DSN: Write SPOOL :-dsn_envid NULL
66 DSN: Write SPOOL :-dsn_ret 0
67 DSN: Flags :0
68 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
69 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
70 Size of headers = sss
71 LOG: MAIN
72   <= x@y U=CALLER P=local-esmtp S=sss
73 created log directory TESTSUITE/spool/log
74 SMTP>> 250 OK id=10HmaX-0005vi-00
75 search_tidyup called
76 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -odi -Mc 10HmaX-0005vi-00
77 Exim version x.yz ....
78 changed uid/gid: forcing real = effective
79   uid=uuuu gid=EXIM_GID pid=pppp
80 seeking password data for user "CALLER": cache not available
81 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
82 configuration file is TESTSUITE/test-config
83 trusted user
84 admin user
85 DSN: r1 propagating DSN
86 seeking password data for user "CALLER": using cached result
87 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
88 set_process_info: pppp delivering specified messages
89 set_process_info: pppp delivering 10HmaX-0005vi-00
90 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
91 reading spool file 10HmaX-0005vi-00-H
92 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
93 sender_local=0 ident=CALLER
94 Non-recipients:
95 Empty Tree
96 ---- End of tree ----
97 recipients_count=1
98 **** SPOOL_IN - No additional fields
99 body_linecount=1 message_linecount=8
100 DSN: set orcpt: NULL  flags: 0
101 Delivery address list:
102   userx@test.ex 
103 locking TESTSUITE/spool/db/retry.lockfile
104 locked TESTSUITE/spool/db/retry.lockfile
105 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
106 returned from EXIM_DBOPEN
107 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
108 no retry data available
109 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
110 Considering: userx@test.ex
111 unique = userx@test.ex
112 no domain retry record
113 no address retry record
114 userx@test.ex: queued for routing
115 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
116 routing userx@test.ex
117 --------> r1 router <--------
118 local_part=userx domain=test.ex
119 calling r1 router
120 r1 router called for userx@test.ex
121   domain = test.ex
122 set transport t1
123 queued for t1 transport: local_part = userx
124 domain = test.ex
125   errors_to=NULL
126   domain_data=NULL localpart_data=NULL
127 routed by r1 router
128   envelope to: userx@test.ex
129   transport: t1
130 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
131 After routing:
132   Local deliveries:
133     userx@test.ex
134   Remote deliveries:
135   Failed addresses:
136   Deferred addresses:
137 search_tidyup called
138 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
139 --------> userx@test.ex <--------
140 locking TESTSUITE/spool/db/retry.lockfile
141 locked TESTSUITE/spool/db/retry.lockfile
142 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
143 returned from EXIM_DBOPEN
144 no retry data available
145 search_tidyup called
146 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
147   uid=CALLER_UID gid=CALLER_GID pid=pppp
148   home=NULL current=/
149 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
150 appendfile transport entered
151 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
152   file=TESTSUITE/test-mail/userx format=unix
153   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
154   message_suffix=\n
155   maildir_use_size_file=no
156   locking by lockfile fcntl 
157 lock name: TESTSUITE/test-mail/userx.lock
158 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
159 lock file created
160 mailbox TESTSUITE/test-mail/userx is locked
161 writing to file TESTSUITE/test-mail/userx
162 writing data block fd=dddd size=sss timeout=0
163 rewrite_one_header: type=F:
164   From: unqualified
165 writing data block fd=dddd size=sss timeout=0
166 writing data block fd=dddd size=sss timeout=0
167 appendfile yields 0 with errno=dd more_errno=dd
168 search_tidyup called
169 journalling userx@test.ex
170 t1 transport returned OK for userx@test.ex
171 post-process userx@test.ex (0)
172 userx@test.ex delivered
173 LOG: MAIN
174   => userx <userx@test.ex> R=r1 T=t1
175 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
176 changed uid/gid: post-delivery tidying
177   uid=EXIM_UID gid=EXIM_GID pid=pppp
178 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
179 Processing retry items
180 Succeeded addresses:
181 userx@test.ex: no retry items
182 Failed addresses:
183 Deferred addresses:
184 end of retry processing
185 DSN: processing router : r1
186 DSN: processing successful delivery address: userx@test.ex
187 DSN: Sender_address: x@y
188 DSN: orcpt: NULL  flags: 0
189 DSN: envid: NULL  ret: 0
190 DSN: Final recipient: userx@test.ex
191 DSN: Remote SMTP server supports DSN: 0
192 DSN: not sending DSN success message
193 LOG: MAIN
194   Completed
195 end delivery of 10HmaX-0005vi-00
196 search_tidyup called
197 search_tidyup called
198 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
199 smtp_setup_msg entered
200 SMTP<< quit
201 SMTP>> 221 myhost.test.ex closing connection
202 LOG: smtp_connection MAIN
203   SMTP connection from CALLER closed by QUIT
204 search_tidyup called
205 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>