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