TLS: fix resumption for TLS-on-connect
[exim.git] / test / stderr / 0403
1 Exim version x.yz ....
2 changed uid/gid: forcing real = effective
3   uid=uuuu gid=CALLER_GID pid=p1234
4 configuration file is TESTSUITE/test-config
5 admin user
6 changed uid/gid: privilege not needed
7   uid=EXIM_UID gid=EXIM_GID pid=p1234
8 seeking password data for user "CALLER": cache not available
9 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
10 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
11 sender address = CALLER@test.ex
12 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
13 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
14 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
15 Sender: CALLER@test.ex
16 Recipients:
17   userx@test.ex
18 search_tidyup called
19 >>Headers received:
20
21 qualify & rewrite recipients list
22 global rewrite rules
23 rewrite headers
24  rewrite_one_header: type=F:
25    From: CALLER_NAME <CALLER@test.ex>
26 search_tidyup called
27 >>Headers after rewriting and local additions:
28 I Message-Id: <E10HmaX-000000005vi-0000@mail.test.ex>
29 F From: CALLER_NAME <CALLER@test.ex>
30   Date: Tue, 2 Mar 1999 09:44:33 +0000
31
32 Data file name: TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
33 Data file written for message 10HmaX-000000005vi-0000
34 >>Generated Received: header line
35 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
36         (envelope-from <CALLER@test.ex>)
37         id 10HmaX-000000005vi-0000
38         for userx@test.ex;
39         Tue, 2 Mar 1999 09:44:33 +0000
40 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-000000005vi-0000
41 DSN: **** SPOOL_OUT - address: <userx@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
42 Renaming spool header file: TESTSUITE/spool//input//10HmaX-000000005vi-0000-H
43 Size of headers = sss
44 LOG: MAIN
45   <= CALLER@test.ex U=CALLER P=local S=sss
46 created log directory TESTSUITE/spool/log
47 search_tidyup called
48 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaX-000000005vi-0000
49 Exim version x.yz ....
50 changed uid/gid: forcing real = effective
51   uid=uuuu gid=EXIM_GID pid=p1235
52 configuration file is TESTSUITE/test-config
53 trusted user
54 admin user
55 dropping to exim gid; retaining priv uid
56 seeking password data for user "CALLER": cache not available
57 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
58 set_process_info: pppp delivering specified messages
59 set_process_info: pppp delivering 10HmaX-000000005vi-0000
60 Trying spool file TESTSUITE/spool//input//10HmaX-000000005vi-0000-D
61 reading spool file 10HmaX-000000005vi-0000-H
62 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
63 sender_local=1 ident=CALLER
64 Non-recipients:
65  Empty Tree
66 ---- End of tree ----
67 recipients_count=1
68 **** SPOOL_IN - No additional fields
69 body_linecount=0 message_linecount=8
70 DSN: set orcpt:   flags: 0x0
71 Delivery address list:
72   userx@test.ex 
73  locking TESTSUITE/spool/db/retry.lockfile
74  locked  TESTSUITE/spool/db/retry.lockfile
75  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
76  returned from EXIM_DBOPEN: (nil)
77  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
78 no retry data available
79 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
80 Considering: userx@test.ex
81 unique = userx@test.ex
82 no   domain  retry record
83 no   address retry record
84 userx@test.ex: queued for routing
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing userx@test.ex
87 --------> r1 router <--------
88 local_part=userx domain=test.ex
89 checking domains
90   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
91   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
92     key="userx@test.ex" partial=-1 affix=NULL starflags=0 opts="ret=key"
93   LRU list:
94     0TESTSUITE/aux-fixed/0403.accountfile
95     End
96   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
97     type=lsearch key="userx@test.ex" opts=NULL
98   file lookup required for userx@test.ex
99     in TESTSUITE/aux-fixed/0403.accountfile
100   creating new cache entry
101   lookup failed
102 test.ex in ""?
103 test.ex in ""? no (end of list)
104 r1 router skipped: domains mismatch
105 --------> r2 router <--------
106 local_part=userx domain=test.ex
107 checking domains
108 test.ex in "lsearch;TESTSUITE/aux-fixed/0403.data"?
109  list element: lsearch;TESTSUITE/aux-fixed/0403.data
110  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
111  search_find: file="TESTSUITE/aux-fixed/0403.data"
112    key="test.ex" partial=-1 affix=NULL starflags=0 opts=NULL
113  LRU list:
114    0TESTSUITE/aux-fixed/0403.data
115    0TESTSUITE/aux-fixed/0403.accountfile
116    End
117  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
118    type=lsearch key="test.ex" opts=NULL
119  file lookup required for test.ex
120    in TESTSUITE/aux-fixed/0403.data
121  creating new cache entry
122  lookup yielded: [DOMAINDATA_test.ex]
123  test.ex in "lsearch;TESTSUITE/aux-fixed/0403.data"? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
124 checking local_parts
125 userx in "lsearch;TESTSUITE/aux-fixed/0403.data"?
126  list element: lsearch;TESTSUITE/aux-fixed/0403.data
127  search_open: lsearch "TESTSUITE/aux-fixed/0403.data"
128    cached open
129  search_find: file="TESTSUITE/aux-fixed/0403.data"
130    key="userx" partial=-1 affix=NULL starflags=0 opts=NULL
131  LRU list:
132    0TESTSUITE/aux-fixed/0403.data
133    0TESTSUITE/aux-fixed/0403.accountfile
134    End
135  internal_search_find: file="TESTSUITE/aux-fixed/0403.data"
136    type=lsearch key="userx" opts=NULL
137  file lookup required for userx
138    in TESTSUITE/aux-fixed/0403.data
139  creating new cache entry
140  lookup yielded: [LOCALPARTDATA_userx]
141  userx in "lsearch;TESTSUITE/aux-fixed/0403.data"? yes (matched "lsearch;TESTSUITE/aux-fixed/0403.data")
142 +++ROUTER:
143 +++domain_data=[DOMAINDATA_test.ex]
144 +++local_part_data=[LOCALPARTDATA_userx]
145 +++address_data=
146 +++home=/usr
147 processing address_data
148 calling r2 router
149 rda_interpret (string): 'TESTSUITE/test-mail/junk'
150 expanded: 'TESTSUITE/test-mail/junk'
151 file is not a filter file
152 parse_forward_list: TESTSUITE/test-mail/junk
153 extract item: TESTSUITE/test-mail/junk
154 set transport t1
155 r2 router generated TESTSUITE/test-mail/junk
156   pipe, file, or autoreply
157   errors_to=NULL transport=t1
158   uid=unset gid=unset home=/usr
159 routed by r2 router
160   envelope to: userx@test.ex
161   transport: <none>
162  locking TESTSUITE/spool/db/retry.lockfile
163  locked  TESTSUITE/spool/db/retry.lockfile
164  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
165  returned from EXIM_DBOPEN: (nil)
166  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
167 no retry data available
168 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
169 Considering: TESTSUITE/test-mail/junk
170 unique = TESTSUITE/test-mail/junk:userx@test.ex
171 queued for t1 transport
172 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
173 After routing:
174   Local deliveries:
175     TESTSUITE/test-mail/junk
176   Remote deliveries:
177   Failed addresses:
178   Deferred addresses:
179 search_tidyup called
180 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
181 --------> TESTSUITE/test-mail/junk <--------
182  locking TESTSUITE/spool/db/retry.lockfile
183  locked  TESTSUITE/spool/db/retry.lockfile
184  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
185  returned from EXIM_DBOPEN: (nil)
186  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
187 no retry data available
188 search_tidyup called
189 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
190   uid=CALLER_UID gid=CALLER_GID pid=p1236
191   home=/usr current=/usr
192 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
193 +++TRANSPORT:
194 +++domain_data=[DOMAINDATA_test.ex]
195 +++local_part_data=[LOCALPARTDATA_userx]
196 +++address_data=ADDRESSDATA
197 +++home=/usr
198 appendfile transport entered
199 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
200   file=TESTSUITE/test-mail/junk format=unix
201   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
202   message_suffix=\n
203   maildir_use_size_file=no
204   locking by lockfile fcntl 
205 *** delivery by t1 transport bypassed by -N option
206 search_tidyup called
207 journalling TESTSUITE/test-mail/junk:userx@test.ex
208 t1 transport returned OK for TESTSUITE/test-mail/junk
209 post-process TESTSUITE/test-mail/junk (0)
210 TESTSUITE/test-mail/junk delivered
211 userx@test.ex: children all complete
212 LOG: MAIN
213   *> TESTSUITE/test-mail/junk <userx@test.ex> R=r2 T=t1
214 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
215 changed uid/gid: post-delivery tidying
216   uid=EXIM_UID gid=EXIM_GID pid=p1235
217 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
218 DSN: processing router : r2
219 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
220 DSN: Sender_address: CALLER@test.ex
221 DSN: orcpt: NULL  flags: 0x0
222 DSN: envid: NULL  ret: 0
223 DSN: Final recipient: TESTSUITE/test-mail/junk
224 DSN: Remote SMTP server supports DSN: 0
225 DSN: not sending DSN success message
226 DSN: processing router : r2
227 DSN: processing successful delivery address: userx@test.ex
228 DSN: Sender_address: CALLER@test.ex
229 DSN: orcpt: NULL  flags: 0x0
230 DSN: envid: NULL  ret: 0
231 DSN: Final recipient: userx@test.ex
232 DSN: Remote SMTP server supports DSN: 0
233 DSN: not sending DSN success message
234 LOG: MAIN
235   Completed
236 end delivery of 10HmaX-000000005vi-0000
237 search_tidyup called
238 search_tidyup called
239 >>>>>>>>>>>>>>>> Exim pid=p1235 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
240 search_tidyup called
241 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
242 ### _data from a multi-step expansion
243 Exim version x.yz ....
244 changed uid/gid: forcing real = effective
245   uid=uuuu gid=CALLER_GID pid=p1237
246 configuration file is TESTSUITE/test-config
247 admin user
248 changed uid/gid: privilege not needed
249   uid=EXIM_UID gid=EXIM_GID pid=p1237
250 seeking password data for user "CALLER": cache not available
251 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
252 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
253 sender address = CALLER@test.ex
254 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
255 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
256 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
257 Sender: CALLER@test.ex
258 Recipients:
259   charlie@dom1.ain
260 search_tidyup called
261 >>Headers received:
262
263 qualify & rewrite recipients list
264 global rewrite rules
265 rewrite headers
266  rewrite_one_header: type=F:
267    From: CALLER_NAME <CALLER@test.ex>
268 search_tidyup called
269 >>Headers after rewriting and local additions:
270 I Message-Id: <E10HmaY-000000005vi-0000@mail.test.ex>
271 F From: CALLER_NAME <CALLER@test.ex>
272   Date: Tue, 2 Mar 1999 09:44:33 +0000
273
274 Data file name: TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
275 Data file written for message 10HmaY-000000005vi-0000
276 >>Generated Received: header line
277 P Received: from CALLER by mail.test.ex with local (Exim x.yz)
278         (envelope-from <CALLER@test.ex>)
279         id 10HmaY-000000005vi-0000
280         for charlie@dom1.ain;
281         Tue, 2 Mar 1999 09:44:33 +0000
282 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-000000005vi-0000
283 DSN: **** SPOOL_OUT - address: <charlie@dom1.ain> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
284 Renaming spool header file: TESTSUITE/spool//input//10HmaY-000000005vi-0000-H
285 Size of headers = sss
286 LOG: MAIN
287   <= CALLER@test.ex U=CALLER P=local S=sss
288 search_tidyup called
289 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xf7715cfd -MCd local-accept-delivery -N -odi -Mc 10HmaY-000000005vi-0000
290 Exim version x.yz ....
291 changed uid/gid: forcing real = effective
292   uid=uuuu gid=EXIM_GID pid=p1238
293 configuration file is TESTSUITE/test-config
294 trusted user
295 admin user
296 dropping to exim gid; retaining priv uid
297 seeking password data for user "CALLER": cache not available
298 getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
299 set_process_info: pppp delivering specified messages
300 set_process_info: pppp delivering 10HmaY-000000005vi-0000
301 Trying spool file TESTSUITE/spool//input//10HmaY-000000005vi-0000-D
302 reading spool file 10HmaY-000000005vi-0000-H
303 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
304 sender_local=1 ident=CALLER
305 Non-recipients:
306  Empty Tree
307 ---- End of tree ----
308 recipients_count=1
309 **** SPOOL_IN - No additional fields
310 body_linecount=0 message_linecount=8
311 DSN: set orcpt:   flags: 0x0
312 Delivery address list:
313   charlie@dom1.ain 
314  locking TESTSUITE/spool/db/retry.lockfile
315  locked  TESTSUITE/spool/db/retry.lockfile
316  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
317  returned from EXIM_DBOPEN: (nil)
318  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
319 no retry data available
320 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
321 Considering: charlie@dom1.ain
322 unique = charlie@dom1.ain
323 no   domain  retry record
324 no   address retry record
325 charlie@dom1.ain: queued for routing
326 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
327 routing charlie@dom1.ain
328 --------> r1 router <--------
329 local_part=charlie domain=dom1.ain
330 checking domains
331   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
332   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
333     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
334   LRU list:
335     0TESTSUITE/aux-fixed/0403.accountfile
336     End
337   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
338     type=lsearch key="charlie@dom1.ain" opts=NULL
339   file lookup required for charlie@dom1.ain
340     in TESTSUITE/aux-fixed/0403.accountfile
341   creating new cache entry
342   lookup yielded: 
343 dom1.ain in "dom1.ain"?
344  list element: dom1.ain
345  dom1.ain in "dom1.ain"? yes (matched "dom1.ain")
346 checking local_parts
347   search_open: lsearch "TESTSUITE/aux-fixed/0403.accountfile"
348     cached open
349   search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
350     key="charlie@dom1.ain" partial=-1 affix=NULL starflags=0 opts="ret=key"
351   LRU list:
352     0TESTSUITE/aux-fixed/0403.accountfile
353     End
354   internal_search_find: file="TESTSUITE/aux-fixed/0403.accountfile"
355     type=lsearch key="charlie@dom1.ain" opts=NULL
356   cached data used for lookup of charlie@dom1.ain
357     in TESTSUITE/aux-fixed/0403.accountfile
358   lookup yielded: 
359 charlie in "charlie"?
360  list element: charlie
361  charlie in "charlie"? yes (matched "charlie")
362 +++ROUTER:
363 +++domain_data=dom1.ain
364 +++local_part_data=charlie
365 calling r1 router
366 rda_interpret (string): 'TESTSUITE/test-mail/junk'
367 expanded: 'TESTSUITE/test-mail/junk'
368 file is not a filter file
369 parse_forward_list: TESTSUITE/test-mail/junk
370 extract item: TESTSUITE/test-mail/junk
371 set transport t1
372 r1 router generated TESTSUITE/test-mail/junk
373   pipe, file, or autoreply
374   errors_to=NULL transport=t1
375   uid=unset gid=unset home=NULL
376 routed by r1 router
377   envelope to: charlie@dom1.ain
378   transport: <none>
379  locking TESTSUITE/spool/db/retry.lockfile
380  locked  TESTSUITE/spool/db/retry.lockfile
381  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
382  returned from EXIM_DBOPEN: (nil)
383  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
384 no retry data available
385 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
386 Considering: TESTSUITE/test-mail/junk
387 unique = TESTSUITE/test-mail/junk:charlie@dom1.ain
388 queued for t1 transport
389 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
390 After routing:
391   Local deliveries:
392     TESTSUITE/test-mail/junk
393   Remote deliveries:
394   Failed addresses:
395   Deferred addresses:
396 search_tidyup called
397 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
398 --------> TESTSUITE/test-mail/junk <--------
399  locking TESTSUITE/spool/db/retry.lockfile
400  locked  TESTSUITE/spool/db/retry.lockfile
401  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
402  returned from EXIM_DBOPEN: (nil)
403  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
404 no retry data available
405 search_tidyup called
406 changed uid/gid: local delivery to TESTSUITE/test-mail/junk <TESTSUITE/test-mail/junk> transport=t1
407   uid=CALLER_UID gid=CALLER_GID pid=p1239
408   home=NULL current=/
409 set_process_info: pppp delivering 10HmaY-000000005vi-0000 to TESTSUITE/test-mail/junk using t1
410 +++TRANSPORT:
411 +++domain_data=dom1.ain
412 +++local_part_data=charlie
413 +++address_data=
414 +++home=
415 appendfile transport entered
416 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
417   file=TESTSUITE/test-mail/junk format=unix
418   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
419   message_suffix=\n
420   maildir_use_size_file=no
421   locking by lockfile fcntl 
422 *** delivery by t1 transport bypassed by -N option
423 search_tidyup called
424 journalling TESTSUITE/test-mail/junk:charlie@dom1.ain
425 t1 transport returned OK for TESTSUITE/test-mail/junk
426 post-process TESTSUITE/test-mail/junk (0)
427 TESTSUITE/test-mail/junk delivered
428 charlie@dom1.ain: children all complete
429 LOG: MAIN
430   *> TESTSUITE/test-mail/junk <charlie@dom1.ain> R=r1 T=t1
431 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
432 changed uid/gid: post-delivery tidying
433   uid=EXIM_UID gid=EXIM_GID pid=p1238
434 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
435 DSN: processing router : r1
436 DSN: processing successful delivery address: TESTSUITE/test-mail/junk
437 DSN: Sender_address: CALLER@test.ex
438 DSN: orcpt: NULL  flags: 0x0
439 DSN: envid: NULL  ret: 0
440 DSN: Final recipient: TESTSUITE/test-mail/junk
441 DSN: Remote SMTP server supports DSN: 0
442 DSN: not sending DSN success message
443 DSN: processing router : r1
444 DSN: processing successful delivery address: charlie@dom1.ain
445 DSN: Sender_address: CALLER@test.ex
446 DSN: orcpt: NULL  flags: 0x0
447 DSN: envid: NULL  ret: 0
448 DSN: Final recipient: charlie@dom1.ain
449 DSN: Remote SMTP server supports DSN: 0
450 DSN: not sending DSN success message
451 LOG: MAIN
452   Completed
453 end delivery of 10HmaY-000000005vi-0000
454 search_tidyup called
455 search_tidyup called
456 >>>>>>>>>>>>>>>> Exim pid=p1238 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
457 search_tidyup called
458 >>>>>>>>>>>>>>>> Exim pid=p1237 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>
459
460 ******** SERVER ********
461 ### _data from a multi-step expansion