Tweak debug output
[exim.git] / test / stderr / 0398
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 SMTP message from <CALLER@test.ex>
13  in hosts_connection_nolog? no (option unset)
14 LOG: smtp_connection MAIN
15   SMTP connection from CALLER
16 SMTP>> 220 mail.test.ex ESMTP Exim x.yz Tue, 2 Mar 1999 09:44:33 +0000
17 smtp_setup_msg entered
18 SMTP<< helo test
19 SMTP>> 250 mail.test.ex Hello CALLER at test
20 SMTP<< mail from:<qq@remote>
21 spool directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100 msg_size = 0
22 log directory space = nnnnnK inodes = nnnnn check_space = 10240K inodes = 100
23 SMTP>> 250 OK
24 SMTP<< rcpt to:<abc@local>
25 using ACL "rcpt"
26 processing "deny" (TESTSUITE/test-config 19)
27 check senders = qq@remote
28 address match test: subject=qq@remote pattern=qq@remote
29 remote in "remote"? yes (matched "remote")
30 qq@remote in "qq@remote"? yes (matched "qq@remote")
31 check !verify = sender
32 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
33 Verifying qq@remote
34 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
35 Considering qq@remote
36 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
37 routing qq@remote
38 --------> r1 router <--------
39 local_part=qq domain=remote
40 checking domains
41 remote in "local"? no (end of list)
42 r1 router skipped: domains mismatch
43 --------> r2 router <--------
44 local_part=qq domain=remote
45 checking domains
46 remote in "remote"? yes (matched "remote")
47 calling r2 router
48 r2 router called for qq@remote
49   domain = remote
50 route_item = * 127.0.0.1
51 remote in "*"? yes (matched "*")
52 original list of hosts = '127.0.0.1' options = ''
53 expanded list of hosts = '127.0.0.1' options = ''
54 set transport t2
55 finding IP address for 127.0.0.1
56 calling host_find_byname
57 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
58 local host found for non-MX address
59 fully qualified name = 127.0.0.1
60 MUNGED: ::1 will be omitted in what follows
61 get[host|ipnode]byname[2] looked up these IP addresses:
62   name=127.0.0.1 address=127.0.0.1
63 remote host address is the local host: remote: configured to try delivery anyway
64 queued for t2 transport: local_part = qq
65 domain = remote
66   errors_to=NULL
67   domain_data=remote local_part_data=NULL
68 routed by r2 router
69   envelope to: qq@remote
70   transport: t2
71   host 127.0.0.1 [127.0.0.1]
72 ----------- end verify ------------
73 sender qq@remote verified ok
74 deny: condition test failed in ACL "rcpt"
75 processing "warn" (TESTSUITE/test-config 21)
76 check senders = qq@remote
77 address match test: subject=qq@remote pattern=qq@remote
78 remote in "remote"? yes (matched "remote")
79 qq@remote in "qq@remote"? yes (matched "qq@remote")
80 check !verify = sender/callout
81 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
82 Verifying qq@remote
83 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
84 Considering qq@remote
85 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
86 routing qq@remote
87 --------> r1 router <--------
88 local_part=qq domain=remote
89 checking domains
90 remote in "local"? no (end of list)
91 r1 router skipped: domains mismatch
92 --------> r2 router <--------
93 local_part=qq domain=remote
94 checking domains
95 remote in "remote"? yes (matched "remote")
96 calling r2 router
97 r2 router called for qq@remote
98   domain = remote
99 route_item = * 127.0.0.1
100 remote in "*"? yes (matched "*")
101 original list of hosts = '127.0.0.1' options = ''
102 expanded list of hosts = '127.0.0.1' options = ''
103 finding IP address for 127.0.0.1
104 calling host_find_byname
105 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
106 local host found for non-MX address
107 fully qualified name = 127.0.0.1
108 MUNGED: ::1 will be omitted in what follows
109 get[host|ipnode]byname[2] looked up these IP addresses:
110   name=127.0.0.1 address=127.0.0.1
111 remote host address is the local host: remote: configured to try delivery anyway
112 queued for t2 transport: local_part = qq
113 domain = remote
114   errors_to=NULL
115   domain_data=remote local_part_data=NULL
116 routed by r2 router
117   envelope to: qq@remote
118   transport: t2
119   host 127.0.0.1 [127.0.0.1]
120 Attempting full verification using callout
121  locking TESTSUITE/spool/db/callout.lockfile
122  locked  TESTSUITE/spool/db/callout.lockfile
123  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
124  returned from EXIM_DBOPEN: 0xAAAAAAAA
125  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
126  dbfn_read: key=remote
127  callout cache: found domain record for remote
128  dbfn_read: key=qq@remote
129  callout cache: no address record found for qq@remote
130  EXIM_DBCLOSE(0xAAAAAAAA)
131  closed hints database and lockfile
132 interface=NULL port=PORT_S
133 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...  connected
134   SMTP<< 220 Server ready
135 127.0.0.1 in hosts_avoid_esmtp? no (option unset)
136   SMTP>> EHLO mail.test.ex
137 cmd buf flush ddd bytes
138   SMTP<< 250 OK
139  compiled RE '.outlook.com$' not found in local cache
140  compiling RE '.outlook.com$'
141  compiled RE '.outlook.com$' saved in local cache
142 not using PIPELINING
143 not using DSN
144 127.0.0.1 in hosts_require_auth? no (option unset)
145   SMTP>> MAIL FROM:<>
146 cmd buf flush ddd bytes
147   SMTP<< 250 OK
148   SMTP>> RCPT TO:<qq@remote>
149 cmd buf flush ddd bytes
150 sync_responses expect rcpt for qq@remote
151   SMTP<< 550 Unknown
152   SMTP>> QUIT
153 cmd buf flush ddd bytes
154   SMTP<< 250 OK
155   SMTP(close)>>
156 cmdlog: '220:EHLO:250:MAIL:250:RCPT:550:QUIT:250'
157  locking TESTSUITE/spool/db/callout.lockfile
158  locked  TESTSUITE/spool/db/callout.lockfile
159  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR|O_CREAT
160  returned from EXIM_DBOPEN: 0xAAAAAAAA
161  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR|O_CREAT
162  dbfn_write: key=remote
163  wrote callout cache domain record for remote:
164    result=1 postmaster=0 random=0
165  dbfn_write: key=qq@remote
166  wrote negative callout cache address record for qq@remote
167  EXIM_DBCLOSE(0xAAAAAAAA)
168  closed hints database and lockfile
169 ----------- end verify ------------
170 l_message: $acl_verify_message
171 warn: condition test succeeded in ACL "rcpt"
172 LOG: MAIN
173   U=CALLER Warning: Sender verify failed: 127.0.0.1 [127.0.0.1] : SMTP error from remote mail server after RCPT TO:<qq@remote>: 550 Unknown
174 processing "accept" (TESTSUITE/test-config 24)
175 check senders = qq@remote
176 address match test: subject=qq@remote pattern=qq@remote
177 remote in "remote"? yes (matched "remote")
178 qq@remote in "qq@remote"? yes (matched "qq@remote")
179 accept: condition test succeeded in ACL "rcpt"
180 end of ACL "rcpt": ACCEPT
181 SMTP>> 250 Accepted
182 SMTP<< rcpt to:<xyz@local>
183 using ACL "rcpt"
184 processing "deny" (TESTSUITE/test-config 19)
185 check senders = qq@remote
186 address match test: subject=qq@remote pattern=qq@remote
187 remote in "remote"? yes (matched "remote")
188 qq@remote in "qq@remote"? yes (matched "qq@remote")
189 check !verify = sender
190 using cached sender verify result
191 deny: condition test failed in ACL "rcpt"
192 processing "warn" (TESTSUITE/test-config 21)
193 check senders = qq@remote
194 address match test: subject=qq@remote pattern=qq@remote
195 remote in "remote"? yes (matched "remote")
196 qq@remote in "qq@remote"? yes (matched "qq@remote")
197 check !verify = sender/callout
198 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
199 Verifying qq@remote
200 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
201 Considering qq@remote
202 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
203 routing qq@remote
204 --------> r1 router <--------
205 local_part=qq domain=remote
206 checking domains
207 remote in "local"? no (end of list)
208 r1 router skipped: domains mismatch
209 --------> r2 router <--------
210 local_part=qq domain=remote
211 checking domains
212 remote in "remote"? yes (matched "remote")
213 calling r2 router
214 r2 router called for qq@remote
215   domain = remote
216 route_item = * 127.0.0.1
217 remote in "*"? yes (matched "*")
218 original list of hosts = '127.0.0.1' options = ''
219 expanded list of hosts = '127.0.0.1' options = ''
220 finding IP address for 127.0.0.1
221 calling host_find_byname
222 using host_fake_gethostbyname for 127.0.0.1 (IPv4)
223 local host found for non-MX address
224 fully qualified name = 127.0.0.1
225 MUNGED: ::1 will be omitted in what follows
226 get[host|ipnode]byname[2] looked up these IP addresses:
227   name=127.0.0.1 address=127.0.0.1
228 remote host address is the local host: remote: configured to try delivery anyway
229 queued for t2 transport: local_part = qq
230 domain = remote
231   errors_to=NULL
232   domain_data=remote local_part_data=NULL
233 routed by r2 router
234   envelope to: qq@remote
235   transport: t2
236   host 127.0.0.1 [127.0.0.1]
237 Attempting full verification using callout
238  locking TESTSUITE/spool/db/callout.lockfile
239  locked  TESTSUITE/spool/db/callout.lockfile
240  EXIM_DBOPEN: file <TESTSUITE/spool/db/callout> dir <TESTSUITE/spool/db> flags=O_RDWR
241  returned from EXIM_DBOPEN: 0xAAAAAAAA
242  opened hints database TESTSUITE/spool/db/callout: flags=O_RDWR
243  dbfn_read: key=remote
244  callout cache: found domain record for remote
245  dbfn_read: key=qq@remote
246  callout cache: found address record for qq@remote
247  callout cache: address record is negative
248  EXIM_DBCLOSE(0xAAAAAAAA)
249  closed hints database and lockfile
250 ----------- end verify ------------
251 l_message: $acl_verify_message
252 warn: condition test succeeded in ACL "rcpt"
253 LOG: MAIN
254   U=CALLER Warning: Sender verify failed
255 processing "accept" (TESTSUITE/test-config 24)
256 check senders = qq@remote
257 address match test: subject=qq@remote pattern=qq@remote
258 remote in "remote"? yes (matched "remote")
259 qq@remote in "qq@remote"? yes (matched "qq@remote")
260 accept: condition test succeeded in ACL "rcpt"
261 end of ACL "rcpt": ACCEPT
262 SMTP>> 250 Accepted
263 SMTP<< quit
264 SMTP>> 221 mail.test.ex closing connection
265 LOG: smtp_connection MAIN
266   SMTP connection from CALLER closed by QUIT
267 search_tidyup called
268 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>