Overlapped twophase-queue-run and delivery. Experimental.
[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 seeking password data for user "CALLER": using cached result
12 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
13 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
14 sender address = NULL
15 set_process_info: pppp accepting a local SMTP message from <CALLER>
16  in hosts_connection_nolog? no (option unset)
17 LOG: smtp_connection MAIN
18   SMTP connection from CALLER
19 SMTP>> 220 myhost.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
20 smtp_setup_msg entered
21 SMTP<< ehlo x.y
22  in dsn_advertise_hosts? no (option unset)
23  in pipelining_advertise_hosts? yes (matched "*")
24  in chunking_advertise_hosts? no (end of list)
25 SMTP>> 250-myhost.test.ex Hello CALLER at x.y
26 250-SIZE 52428800
27 250-8BITMIME
28 250-PIPELINING
29 250 HELP
30 SMTP<< mail from:<x@y>
31 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
32 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
33 SMTP>> 250 OK
34 SMTP<< rcpt to:<userx@test.ex>
35 processing "accept" (TESTSUITE/test-config 33)
36 accept: condition test succeeded in inline ACL
37 end of inline ACL: ACCEPT
38 SMTP>> 250 Accepted
39 DSN: orcpt: NULL  flags: 0
40 SMTP<< data
41 SMTP>> 354 Enter message, ending with "." on a line by itself
42 search_tidyup called
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 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
63 DSN: Write SPOOL: -dsn_envid NULL
64 DSN: Write SPOOL  :-dsn_ret 0
65 DSN: Flags: 0x0
66 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
67 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
68 Size of headers = sss
69 LOG: MAIN
70   <= x@y U=CALLER P=local-esmtp S=sss
71 created log directory TESTSUITE/spool/log
72 SMTP>> 250 OK id=10HmaX-0005vi-00
73 search_tidyup called
74 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -odi -Mc 10HmaX-0005vi-00
75 Exim version x.yz ....
76 changed uid/gid: forcing real = effective
77   uid=uuuu gid=EXIM_GID pid=pppp
78 seeking password data for user "CALLER": cache not available
79 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
80 configuration file is TESTSUITE/test-config
81 trusted user
82 admin user
83 dropping to exim gid; retaining priv uid
84 seeking password data for user "CALLER": using cached result
85 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
86 set_process_info: pppp delivering specified messages
87 set_process_info: pppp delivering 10HmaX-0005vi-00
88 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
89 reading spool file 10HmaX-0005vi-00-H
90 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=x@y
91 sender_local=0 ident=CALLER
92 Non-recipients:
93 Empty Tree
94 ---- End of tree ----
95 recipients_count=1
96 **** SPOOL_IN - No additional fields
97 body_linecount=1 message_linecount=8
98 DSN: set orcpt:   flags: 0x0
99 Delivery address list:
100   userx@test.ex 
101  locking TESTSUITE/spool/db/retry.lockfile
102  locked  TESTSUITE/spool/db/retry.lockfile
103  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
104  returned from EXIM_DBOPEN: (nil)
105  ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
106  failed to open DB file TESTSUITE/spool/db/retry.lockfile: No such file or directory
107 no retry data available
108 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
109 Considering: userx@test.ex
110 unique = userx@test.ex
111 no   domain  retry record
112 no   address retry record
113 userx@test.ex: queued for routing
114 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
115 routing userx@test.ex
116 --------> r1 router <--------
117 local_part=userx domain=test.ex
118 calling r1 router
119 r1 router called for userx@test.ex
120   domain = test.ex
121 set transport t1
122 queued for t1 transport: local_part = userx
123 domain = test.ex
124   errors_to=NULL
125   domain_data=NULL localpart_data=NULL
126 routed by r1 router
127   envelope to: userx@test.ex
128   transport: t1
129 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
130 After routing:
131   Local deliveries:
132     userx@test.ex
133   Remote deliveries:
134   Failed addresses:
135   Deferred addresses:
136 search_tidyup called
137 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
138 --------> userx@test.ex <--------
139  locking TESTSUITE/spool/db/retry.lockfile
140  locked  TESTSUITE/spool/db/retry.lockfile
141  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
142  returned from EXIM_DBOPEN: (nil)
143  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
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 cannot use sendfile for body: spoolfile not wireformat
166 writing data block fd=dddd size=sss timeout=0
167 writing data block fd=dddd size=sss timeout=0
168 appendfile yields 0 with errno=dd more_errno=dd
169 search_tidyup called
170 journalling userx@test.ex
171 t1 transport returned OK for userx@test.ex
172 post-process userx@test.ex (0)
173 userx@test.ex delivered
174 LOG: MAIN
175   => userx <userx@test.ex> R=r1 T=t1
176 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
177 changed uid/gid: post-delivery tidying
178   uid=EXIM_UID gid=EXIM_GID pid=pppp
179 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
180 Processing retry items
181 Succeeded addresses:
182  userx@test.ex: no retry items
183 Failed addresses:
184 Deferred addresses:
185 end of retry processing
186 DSN: processing router : r1
187 DSN: processing successful delivery address: userx@test.ex
188 DSN: Sender_address: x@y
189 DSN: orcpt: NULL  flags: 0x0
190 DSN: envid: NULL  ret: 0
191 DSN: Final recipient: userx@test.ex
192 DSN: Remote SMTP server supports DSN: 0
193 DSN: not sending DSN success message
194 LOG: MAIN
195   Completed
196 end delivery of 10HmaX-0005vi-00
197 search_tidyup called
198 search_tidyup called
199 >>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
200 smtp_setup_msg entered
201 SMTP<< quit
202 SMTP>> 221 myhost.test.ex closing connection
203 LOG: smtp_connection MAIN
204   SMTP connection from CALLER closed by QUIT
205 search_tidyup called
206 >>>>>>>>>>>>>>>> Exim pid=pppp (msg setup toplevel) terminating with rc=0 >>>>>>>>>>>>>>>>