consistent fork-time debug
[exim.git] / test / stderr / 2201
CommitLineData
54c5ebb1
PH
1Exim version x.yz ....
2changed uid/gid: forcing real = effective
3 uid=uuuu gid=CALLER_GID pid=pppp
d8fe1c03
PH
4seeking password data for user "CALLER": cache not available
5getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
54c5ebb1
PH
6configuration file is TESTSUITE/test-config
7trusted user
8admin user
4b424e0d 9dropping to exim gid; retaining priv uid
d8fe1c03
PH
10seeking password data for user "CALLER": using cached result
11getpwnam() succeeded uid=CALLER_UID gid=CALLER_GID
54c5ebb1
PH
12originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
13sender address = CALLER@myhost.test.ex
14Address testing: uid=uuuu gid=EXIM_GID euid=uuuu egid=EXIM_GID
15>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
16Testing test.ex@test.ex
17>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
18Considering test.ex@test.ex
19>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
20routing test.ex@test.ex
14b3c5bc
JH
21--------> r0 router <--------
22local_part=test.ex domain=test.ex
23checking senders
24address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
25CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
26r0 router skipped: senders mismatch
54c5ebb1
PH
27--------> r1 router <--------
28local_part=test.ex domain=test.ex
29checking domains
30search_open: dnsdb "NULL"
31search_find: file="NULL"
32 key="test.ex" partial=-1 affix=NULL starflags=0
33LRU list:
34internal_search_find: file="NULL"
35 type=dnsdb key="test.ex"
36database lookup required for test.ex
37dnsdb key: test.ex
38DNS lookup of test.ex (TXT) using fakens
56809214
JH
39fresh-exec forking for child-open
40postfork: child-open
41fresh-exec forked for child-open: npppp
54c5ebb1
PH
42DNS lookup of test.ex (TXT) succeeded
43lookup yielded: A TXT record for test.ex.
44test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
45checking local_parts
46search_open: dnsdb "NULL"
47 cached open
48search_find: file="NULL"
49 key="test.ex" partial=-1 affix=NULL starflags=0
50LRU list:
51internal_search_find: file="NULL"
52 type=dnsdb key="test.ex"
53cached data used for lookup of test.ex
54lookup yielded: A TXT record for test.ex.
55test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
56calling r1 router
57r1 router called for test.ex@test.ex
58 domain = test.ex
59set transport local_delivery
60queued for local_delivery transport: local_part = test.ex
61domain = test.ex
62 errors_to=NULL
63 domain_data=A TXT record for test.ex. localpart_data=A TXT record for test.ex.
64routed by r1 router
65 envelope to: test.ex@test.ex
66 transport: local_delivery
67>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
68Testing unknown@test.ex
69>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
70Considering unknown@test.ex
71>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
72routing unknown@test.ex
14b3c5bc
JH
73--------> r0 router <--------
74local_part=unknown domain=test.ex
75checking senders
76address match test: subject=CALLER@myhost.test.ex pattern=a@shorthost.test.ex
77CALLER@myhost.test.ex in "a@shorthost.test.ex"? no (end of list)
78r0 router skipped: senders mismatch
54c5ebb1
PH
79--------> r1 router <--------
80local_part=unknown domain=test.ex
81checking domains
82search_open: dnsdb "NULL"
83 cached open
84search_find: file="NULL"
85 key="test.ex" partial=-1 affix=NULL starflags=0
86LRU list:
87internal_search_find: file="NULL"
88 type=dnsdb key="test.ex"
89cached data used for lookup of test.ex
90lookup yielded: A TXT record for test.ex.
91test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
92checking local_parts
93search_open: dnsdb "NULL"
94 cached open
95search_find: file="NULL"
96 key="unknown" partial=-1 affix=NULL starflags=0
97LRU list:
98internal_search_find: file="NULL"
99 type=dnsdb key="unknown"
100database lookup required for unknown
101dnsdb key: unknown
102DNS lookup of unknown (TXT) using fakens
56809214
JH
103fresh-exec forking for child-open
104postfork: child-open
105fresh-exec forked for child-open: npppp
54c5ebb1
PH
106DNS lookup of unknown (TXT) gave HOST_NOT_FOUND
107returning DNS_NOMATCH
a713f766 108faking res_search(TXT) response length as 65535
da0b4ba7 109 writing neg-cache entry for unknown-TXT-xxxx, ttl 3000
54c5ebb1
PH
110lookup failed
111unknown in "dnsdb;unknown"? no (end of list)
112r1 router skipped: local_parts mismatch
113--------> r2 router <--------
114local_part=unknown domain=test.ex
115checking domains
116search_open: dnsdb "NULL"
117 cached open
118search_find: file="NULL"
119 key="test.ex" partial=-1 affix=NULL starflags=0
120LRU list:
121internal_search_find: file="NULL"
122 type=dnsdb key="test.ex"
123cached data used for lookup of test.ex
124lookup yielded: A TXT record for test.ex.
125test.ex in "dnsdb;test.ex"? yes (matched "dnsdb;test.ex")
126checking senders
cd5567b1 127address match test: subject=CALLER@myhost.test.ex pattern=dnsdb;A=myhost.test.ex
54c5ebb1
PH
128search_open: dnsdb "NULL"
129 cached open
130search_find: file="NULL"
131 key="A=myhost.test.ex" partial=-1 affix=NULL starflags=0
132LRU list:
133internal_search_find: file="NULL"
134 type=dnsdb key="A=myhost.test.ex"
135database lookup required for A=myhost.test.ex
136dnsdb key: myhost.test.ex
137DNS lookup of myhost.test.ex (A) using fakens
56809214
JH
138fresh-exec forking for child-open
139postfork: child-open
140fresh-exec forked for child-open: npppp
54c5ebb1
PH
141DNS lookup of myhost.test.ex (A) succeeded
142lookup yielded: V4NET.10.10.10
143CALLER@myhost.test.ex in "dnsdb;A=myhost.test.ex"? yes (matched "dnsdb;A=myhost.test.ex")
144calling r2 router
145r2 router called for unknown@test.ex
146 domain = test.ex
147set transport local_delivery
148queued for local_delivery transport: local_part = unknown
149domain = test.ex
150 errors_to=NULL
151 domain_data=A TXT record for test.ex. localpart_data=NULL
152routed by r2 router
153 envelope to: unknown@test.ex
154 transport: local_delivery
155search_tidyup called
9bfb7e1b 156>>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
14b3c5bc
JH
157Exim version x.yz ....
158configuration file is TESTSUITE/test-config
159trusted user
160admin user
161search_tidyup called
162search_tidyup called
163search_open: dnsdb "NULL"
164search_find: file="NULL"
165 key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
166LRU list:
167internal_search_find: file="NULL"
168 type=dnsdb key="a=shorthost.test.ex"
169database lookup required for a=shorthost.test.ex
170dnsdb key: shorthost.test.ex
56809214
JH
171fresh-exec forking for child-open
172postfork: child-open
173fresh-exec forked for child-open: npppp
14b3c5bc
JH
174lookup yielded: 127.0.0.1
175search_open: dnsdb "NULL"
176 cached open
177search_find: file="NULL"
178 key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
179LRU list:
180internal_search_find: file="NULL"
181 type=dnsdb key="a=shorthost.test.ex"
182cached data used for lookup of a=shorthost.test.ex
183lookup yielded: 127.0.0.1
184search_open: dnsdb "NULL"
185 cached open
186search_find: file="NULL"
187 key="a=shorthost.test.ex" partial=-1 affix=NULL starflags=0
188LRU list:
189internal_search_find: file="NULL"
190 type=dnsdb key="a=shorthost.test.ex"
191cached data found but past valid time; database lookup required for a=shorthost.test.ex
192dnsdb key: shorthost.test.ex
56809214
JH
193fresh-exec forking for child-open
194postfork: child-open
195fresh-exec forked for child-open: npppp
14b3c5bc
JH
196lookup yielded: 127.0.0.1
197LOG: MAIN
198 <= a@shorthost.test.ex U=CALLER P=local S=sss
199created log directory TESTSUITE/spool/log
200search_tidyup called
9bfb7e1b 201>>>>>>>>>>>>>>>> Exim pid=pppp (main) terminating with rc=0 >>>>>>>>>>>>>>>>
14b3c5bc
JH
202
203******** SERVER ********
204Exim version x.yz ....
205configuration file is TESTSUITE/test-config
206trusted user
207admin user
4b424e0d 208dropping to exim gid; retaining priv uid
14b3c5bc
JH
209ppppp daemon_smtp_port overridden by -oX:
210ppppp <: 1225
04403ab0 211ppppp creating notifier socket
691ca88c 212ppppp TESTSUITE/spool/exim_daemon_notify
14b3c5bc
JH
213ppppp listening on all interfaces (IPv4) port 1225
214ppppp pid written to TESTSUITE/spool/exim-daemon.pid
215ppppp LOG: MAIN
216ppppp exim x.yz daemon started: pid=pppp, no queue runs, listening for SMTP on port 1225
217ppppp daemon running with uid=EXIM_UID gid=EXIM_GID euid=EXIM_UID egid=EXIM_GID
218ppppp Listening...
219ppppp Connection request from 127.0.0.1 port sssss
56809214
JH
220ppppp fresh-exec forking for daemon accept
221ppppp postfork: daemon accept
14b3c5bc
JH
222ppppp Process ppppp is handling incoming connection from [127.0.0.1]
223ppppp Process ppppp is ready for new message
56809214
JH
224ppppp daemon accept forking for child-open
225ppppp postfork: child-open
226ppppp fresh-exec forked for daemon accept: npppp
227ppppp 1 SMTP accept process running
228ppppp Listening...
229ppppp daemon accept forked for child-open: npppp
230ppppp daemon accept forking for child-open
231ppppp postfork: child-open
232ppppp daemon accept forked for child-open: npppp
3462da30 233ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
14b3c5bc 234ppppp new DNS lookup for 14.12.11.V4NET.rbl.test.ex
56809214
JH
235ppppp daemon accept forking for child-open
236ppppp postfork: child-open
237ppppp daemon accept forked for child-open: npppp
3462da30 238ppppp dnslists: wrote cache entry, ttl=2
14b3c5bc 239ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
56809214
JH
240ppppp daemon accept forking for child-open
241ppppp postfork: child-open
242ppppp daemon accept forked for child-open: npppp
14b3c5bc 243ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
3462da30
JH
244ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
245ppppp dnslists: using result of previous lookup
14b3c5bc
JH
246ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
247ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
3462da30 248ppppp dnslists check: rbl.test.ex/V4NET.11.12.14
14b3c5bc 249ppppp cached data found but past valid time; new DNS lookup for 14.12.11.V4NET.rbl.test.ex
56809214
JH
250ppppp daemon accept forking for child-open
251ppppp postfork: child-open
252ppppp daemon accept forked for child-open: npppp
3462da30 253ppppp dnslists: wrote cache entry, ttl=2
14b3c5bc 254ppppp DNS lookup for 14.12.11.V4NET.rbl.test.ex succeeded (yielding 127.0.0.2)
56809214
JH
255ppppp daemon accept forking for child-open
256ppppp postfork: child-open
257ppppp daemon accept forked for child-open: npppp
14b3c5bc 258ppppp => that means V4NET.11.12.14 is listed at rbl.test.ex
14b3c5bc
JH
259ppppp LOG: MAIN
260ppppp <= a@shorthost.test.ex H=localhost (myhost.test.ex) [127.0.0.1] P=esmtp S=sss id=E10HmaX-0005vi-00@myhost.test.ex
261ppppp Process ppppp is ready for new message
262ppppp LOG: smtp_connection MAIN
263ppppp SMTP connection from localhost (myhost.test.ex) [127.0.0.1] closed by QUIT
afd5e75f 264ppppp >>>>>>>>>>>>>>>> Exim pid=pppp (conn-setup) terminating with rc=0 >>>>>>>>>>>>>>>>
14b3c5bc
JH
265ppppp child ppppp ended: status=0x0
266ppppp normal exit, 0
267ppppp 0 SMTP accept processes now running
268ppppp Listening...
56809214
JH
269ppppp fresh-exec forking for daemon del pidfile
270ppppp postfork: daemon del pidfile
271ppppp fresh-exec forked for daemon del pidfile: npppp
2f2dd3a5 272ppppp >>>>>>>>>>>>>>>> Exim pid=pppp (daemon) terminating with rc=0 >>>>>>>>>>>>>>>>