Testsuite: testcase for DKIM bug 2029
[users/heiko/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 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
33 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
34 SMTP>> 250 OK
35 SMTP<< rcpt to:<userx@test.ex>
36 processing "accept"
37 accept: condition test succeeded in inline ACL
38 end of inline ACL: ACCEPT
39 SMTP>> 250 Accepted
40 DSN: orcpt: NULL  flags: 0
41 SMTP<< data
42 SMTP>> 354 Enter message, ending with "." on a line by itself
43 search_tidyup called
44 PDKIM >> raw hdr: From:{SP}unqualified{CR}{LF}
45 >>Headers received:
46 From: unqualified
47
48 rewrite_one_header: type=F:
49   From: unqualified
50 search_tidyup called
51 >>Headers after rewriting and local additions:
52 F From: unqualified
53 I Message-Id: <E10HmaX-0005vi-00@myhost.test.ex>
54   Date: Tue, 2 Mar 1999 09:44:33 +0000
55
56 Data file name: TESTSUITE/spool//input//10HmaX-0005vi-00-D
57 Data file written for message 10HmaX-0005vi-00
58 >>Generated Received: header line
59 P Received: from CALLER (helo=x.y)
60         by myhost.test.ex with local-esmtp (Exim x.yz)
61         (envelope-from <x@y>)
62         id 10HmaX-0005vi-00
63         for userx@test.ex; Tue, 2 Mar 1999 09:44:33 +0000
64 calling local_scan(); timeout=300
65 local_scan() returned 0 NULL
66 Writing spool header file: TESTSUITE/spool//input//hdr.pppp
67 DSN: Write SPOOL :-dsn_envid NULL
68 DSN: Write SPOOL :-dsn_ret 0
69 DSN: Flags :0
70 DSN: **** SPOOL_OUT - address: |userx@test.ex| errorsto: |NULL| orcpt: |NULL| dsn_flags: 0
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=0xfbb95cfd -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 DSN: r1 propagating DSN
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: NULL  flags: 0
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(TESTSUITE/spool/db/retry)
108 returned from EXIM_DBOPEN
109 ensuring TESTSUITE/spool/db/retry.lockfile is owned by exim
110 no retry data available
111 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
112 Considering: userx@test.ex
113 unique = userx@test.ex
114 no domain retry record
115 no address retry record
116 userx@test.ex: queued for routing
117 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
118 routing userx@test.ex
119 --------> r1 router <--------
120 local_part=userx domain=test.ex
121 calling r1 router
122 r1 router called for userx@test.ex
123   domain = test.ex
124 set transport t1
125 queued for t1 transport: local_part = userx
126 domain = test.ex
127   errors_to=NULL
128   domain_data=NULL localpart_data=NULL
129 routed by r1 router
130   envelope to: userx@test.ex
131   transport: t1
132 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
133 After routing:
134   Local deliveries:
135     userx@test.ex
136   Remote deliveries:
137   Failed addresses:
138   Deferred addresses:
139 search_tidyup called
140 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
141 --------> userx@test.ex <--------
142 locking TESTSUITE/spool/db/retry.lockfile
143 locked  TESTSUITE/spool/db/retry.lockfile
144 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
145 returned from EXIM_DBOPEN
146 no retry data available
147 search_tidyup called
148 changed uid/gid: local delivery to userx <userx@test.ex> transport=t1
149   uid=CALLER_UID gid=CALLER_GID pid=pppp
150   home=NULL current=/
151 set_process_info: pppp delivering 10HmaX-0005vi-00 to userx using t1
152 appendfile transport entered
153 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
154   file=TESTSUITE/test-mail/userx format=unix
155   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
156   message_suffix=\n
157   maildir_use_size_file=no
158   locking by lockfile fcntl 
159 lock name: TESTSUITE/test-mail/userx.lock
160 hitch name: TESTSUITE/test-mail/userx.lock.test.ex.dddddddd.pppppppp
161 lock file created
162 mailbox TESTSUITE/test-mail/userx is locked
163 writing to file TESTSUITE/test-mail/userx
164 writing data block fd=dddd size=sss timeout=0
165 rewrite_one_header: type=F:
166   From: unqualified
167 writing data block fd=dddd size=sss timeout=0
168 writing data block fd=dddd size=sss timeout=0
169 appendfile yields 0 with errno=dd more_errno=dd
170 search_tidyup called
171 journalling userx@test.ex
172 t1 transport returned OK for userx@test.ex
173 post-process userx@test.ex (0)
174 userx@test.ex delivered
175 LOG: MAIN
176   => userx <userx@test.ex> R=r1 T=t1
177 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
178 changed uid/gid: post-delivery tidying
179   uid=EXIM_UID gid=EXIM_GID pid=pppp
180 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
181 Processing retry items
182 Succeeded addresses:
183  userx@test.ex: no retry items
184 Failed addresses:
185 Deferred addresses:
186 end of retry processing
187 DSN: processing router : r1
188 DSN: processing successful delivery address: userx@test.ex
189 DSN: Sender_address: x@y
190 DSN: orcpt: NULL  flags: 0
191 DSN: envid: NULL  ret: 0
192 DSN: Final recipient: userx@test.ex
193 DSN: Remote SMTP server supports DSN: 0
194 DSN: not sending DSN success message
195 LOG: MAIN
196   Completed
197 end delivery of 10HmaX-0005vi-00
198 search_tidyup called
199 search_tidyup called
200 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
201 smtp_setup_msg entered
202 SMTP<< quit
203 SMTP>> 221 myhost.test.ex closing connection
204 LOG: smtp_connection MAIN
205   SMTP connection from CALLER closed by QUIT
206 search_tidyup called
207 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>