Debug: pass ACL-initiated debug through spool residency
[exim.git] / test / stderr / 0628
1 01:01:01 1234 warn: condition test succeeded in ACL "chk_data"
2 01:01:01 1234 processing "accept" (TESTSUITE/test-config 28)
3 01:01:01 1234 accept: condition test succeeded in ACL "chk_data"
4 01:01:01 1234 end of ACL "chk_data": ACCEPT
5 01:01:01 1234  ╭considering: ${tod_full}
6 01:01:01 1234  ├──expanding: ${tod_full}
7 01:01:01 1234  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
8 01:01:01 1234 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaX-0005vi-00
9 01:01:01 1234 DSN: **** SPOOL_OUT - address: <dest@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
10 01:01:01 1234 Renaming spool header file: TESTSUITE/spool//input//10HmaX-0005vi-00-H
11 01:01:01 1234 Size of headers = sss
12 01:01:01 1234 LOG: MAIN
13 01:01:01 1234   <= tester@test.ex H=(test.ex) [127.0.0.1] P=esmtp S=sss
14 01:01:01 1234 SMTP>> 250 OK id=10HmaX-0005vi-00
15 01:01:01 1234 search_tidyup called
16 01:01:01 1234 Sender: tester@test.ex
17 01:01:01 1234 Recipients:
18 01:01:01 1234   dest@test.ex
19 01:01:01 1234 forked delivery process1235 
20 01:01:01 1234 Process1236  is ready for new message
21 01:01:01 1234 smtp_setup_msg entered
22 01:01:01 1234 SMTP<< QUIT
23 01:01:01 1234 SMTP>> 221 myhost.test.ex closing connection
24 01:01:01 1234 LOG: smtp_connection MAIN
25 01:01:01 1234   SMTP connection from (test.ex) [127.0.0.1] closed by QUIT
26 01:01:01 1237 Exim version x.yz uid=EXIM_UID gid=EXIM_GID pid=pppp D=fff9ffff
27 01:01:01 1237 macros_trusted overridden to true by whitelisting
28 01:01:01 1237 changed uid/gid: forcing real = effective
29 01:01:01 1237   uid=uuuu gid=EXIM_GID pid=pppp
30 01:01:01 1237 adding SSLKEYLOGFILE=TESTSUITE/spool/sslkeys
31 01:01:01 1237 configuration file is TESTSUITE/test-config
32 01:01:01 1237 trusted user
33 01:01:01 1237 admin user
34 01:01:01 1237 dropping to exim gid; retaining priv uid
35 01:01:01 1237 set_process_info: pppp delivering specified messages
36 01:01:01 1237 set_process_info: pppp delivering 10HmaX-0005vi-00
37 01:01:01 1237 Trying spool file TESTSUITE/spool//input//10HmaX-0005vi-00-D
38 01:01:01 1237 reading spool file 10HmaX-0005vi-00-H
39 01:01:01 1237 user=EXIMUSER uid=EXIM_UID gid=EXIM_GID sender=tester@test.ex
40 01:01:01 1237 sender_fullhost = (test.ex) [127.0.0.1]
41 01:01:01 1237 sender_rcvhost = [127.0.0.1] (helo=test.ex)
42 01:01:01 1237 sender_local=0 ident=unset
43 01:01:01 1237 Non-recipients:
44 01:01:01 1237  Empty Tree
45 01:01:01 1237 ---- End of tree ----
46 01:01:01 1237 recipients_count=1
47 01:01:01 1237 **** SPOOL_IN - No additional fields
48 01:01:01 1237 body_linecount=1 message_linecount=7
49 01:01:01 1237 DSN: set orcpt:   flags: 0x0
50 01:01:01 1237 Delivery address list:
51 01:01:01 1237   dest@test.ex 
52 01:01:01 1237  locking TESTSUITE/spool/db/retry.lockfile
53 01:01:01 1237  locked  TESTSUITE/spool/db/retry.lockfile
54 01:01:01 1237  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
55 01:01:01 1237  returned from EXIM_DBOPEN: (nil)
56 01:01:01 1237  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
57 01:01:01 1237 no retry data available
58 01:01:01 1237 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
59 01:01:01 1237 Considering: dest@test.ex
60 01:01:01 1237 unique = dest@test.ex
61 01:01:01 1237 no   domain  retry record
62 01:01:01 1237 no   address retry record
63 01:01:01 1237 dest@test.ex: queued for routing
64 01:01:01 1237 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
65 01:01:01 1237 routing dest@test.ex
66 01:01:01 1237 --------> r1 router <--------
67 01:01:01 1237 local_part=dest domain=test.ex
68 01:01:01 1237 calling r1 router
69 01:01:01 1237 rda_interpret (string): ':blackhole:'
70 01:01:01 1237 expanded: ':blackhole:'
71 01:01:01 1237 file is not a filter file
72 01:01:01 1237 parse_forward_list: :blackhole:
73 01:01:01 1237 extract item: :blackhole:
74 01:01:01 1237 address :blackhole:d
75 01:01:01 1237 LOG: MAIN
76 01:01:01 1237   => :blackhole: <dest@test.ex> R=r1
77 01:01:01 1237 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
78 01:01:01 1237 After routing:
79 01:01:01 1237   Local deliveries:
80 01:01:01 1237   Remote deliveries:
81 01:01:01 1237   Failed addresses:
82 01:01:01 1237   Deferred addresses:
83 01:01:01 1237 search_tidyup called
84 01:01:01 1237 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
85 01:01:01 1237 changed uid/gid: post-delivery tidying
86 01:01:01 1237   uid=EXIM_UID gid=EXIM_GID pid=pppp
87 01:01:01 1237 set_process_info: pppp tidying up after delivering 10HmaX-0005vi-00
88 01:01:01 1237 Processing retry items
89 01:01:01 1237 Succeeded addresses:
90 01:01:01 1237  dest@test.ex: no retry items
91 01:01:01 1237 Failed addresses:
92 01:01:01 1237 Deferred addresses:
93 01:01:01 1237 end of retry processing
94 01:01:01 1237 DSN: processing router : r1
95 01:01:01 1237 DSN: processing successful delivery address: dest@test.ex
96 01:01:01 1237 DSN: Sender_address: tester@test.ex
97 01:01:01 1237 DSN: orcpt: NULL  flags: 0x0
98 01:01:01 1237 DSN: envid: NULL  ret: 0
99 01:01:01 1237 DSN: Final recipient: dest@test.ex
100 01:01:01 1237 DSN: Remote SMTP server supports DSN: 0
101 01:01:01 1237 DSN: not sending DSN success message
102 01:01:01 1237 LOG: MAIN
103 01:01:01 1237   Completed
104 01:01:01 1237 end delivery of 10HmaX-0005vi-00
105 01:01:01 1237 search_tidyup called
106 01:01:01 1237 search_tidyup called
107 01:01:01 1237 >>>>>>>>>>>>>>>> Exim pid=pppp (daemon-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
108 01:01:01 1234 search_tidyup called
109 01:01:01 1234 SMTP>>(close on process exit)
110 01:01:01 1234 >>>>>>>>>>>>>>>> Exim pid=pppp (daemon-accept) terminating with rc=0 >>>>>>>>>>>>>>>>
111 01:01:01 1238 check control = queue_only
112 01:01:01 1238 warn: condition test succeeded in ACL "chk_data"
113 01:01:01 1238 processing "accept" (TESTSUITE/test-config 28)
114 01:01:01 1238 accept: condition test succeeded in ACL "chk_data"
115 01:01:01 1238 end of ACL "chk_data": ACCEPT
116 01:01:01 1238  ╭considering: ${tod_full}
117 01:01:01 1238  ├──expanding: ${tod_full}
118 01:01:01 1238  ╰─────result: Tue, 2 Mar 1999 09:44:33 +0000
119 01:01:01 1238 Writing spool header file: TESTSUITE/spool//input//hdr.10HmaY-0005vi-00
120 01:01:01 1238 DSN: **** SPOOL_OUT - address: <dest2@test.ex> errorsto: <NULL> orcpt: <NULL> dsn_flags: 0x0
121 01:01:01 1238 Renaming spool header file: TESTSUITE/spool//input//10HmaY-0005vi-00-H
122 01:01:01 1238 Size of headers = sss
123 01:01:01 1238 LOG: MAIN
124 01:01:01 1238   <= tester2@test.ex H=(test.ex) [127.0.0.1] P=esmtp S=sss
125 01:01:01 1238 LOG: delay_delivery MAIN
126 01:01:01 1238   no immediate delivery: queued by ACL
127 01:01:01 1238 SMTP>> 250 OK id=10HmaY-0005vi-00
128 01:01:01 1238 search_tidyup called
129 01:01:01 1238 Sender: tester2@test.ex
130 01:01:01 1238 Recipients:
131 01:01:01 1238   dest2@test.ex
132 01:01:01 1238 Process1239  is ready for new message
133 01:01:01 1238 smtp_setup_msg entered
134 01:01:01 1238 SMTP<< QUIT
135 01:01:01 1238 SMTP>> 221 myhost.test.ex closing connection
136 01:01:01 1238 LOG: smtp_connection MAIN
137 01:01:01 1238   SMTP connection from (test.ex) [127.0.0.1] closed by QUIT
138 01:01:01 1238 search_tidyup called
139 01:01:01 1238 SMTP>>(close on process exit)
140 01:01:01 1238 >>>>>>>>>>>>>>>> Exim pid=pppp (daemon-accept) terminating with rc=0 >>>>>>>>>>>>>>>>
141 01:01:01 1240 debug enabled by spoolfile
142 01:01:01 1240 sender_fullhost = (test.ex) [127.0.0.1]
143 01:01:01 1240 sender_rcvhost = [127.0.0.1] (helo=test.ex)
144 01:01:01 1240 sender_local=0 ident=unset
145 01:01:01 1240 Non-recipients:
146 01:01:01 1240  Empty Tree
147 01:01:01 1240 ---- End of tree ----
148 01:01:01 1240 recipients_count=1
149 01:01:01 1240 **** SPOOL_IN - No additional fields
150 01:01:01 1240 body_linecount=1 message_linecount=7
151 01:01:01 1240 DSN: set orcpt:   flags: 0x0
152 01:01:01 1240 Delivery address list:
153 01:01:01 1240   dest2@test.ex 
154 01:01:01 1240  locking TESTSUITE/spool/db/retry.lockfile
155 01:01:01 1240  locked  TESTSUITE/spool/db/retry.lockfile
156 01:01:01 1240  EXIM_DBOPEN: file <TESTSUITE/spool/db/retry> dir <TESTSUITE/spool/db> flags=O_RDONLY
157 01:01:01 1240  returned from EXIM_DBOPEN: (nil)
158 01:01:01 1240  failed to open DB file TESTSUITE/spool/db/retry: No such file or directory
159 01:01:01 1240 no retry data available
160 01:01:01 1240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
161 01:01:01 1240 Considering: dest2@test.ex
162 01:01:01 1240 unique = dest2@test.ex
163 01:01:01 1240 no   domain  retry record
164 01:01:01 1240 no   address retry record
165 01:01:01 1240 dest2@test.ex: queued for routing
166 01:01:01 1240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
167 01:01:01 1240 routing dest2@test.ex
168 01:01:01 1240 --------> r1 router <--------
169 01:01:01 1240 local_part=dest2 domain=test.ex
170 01:01:01 1240 calling r1 router
171 01:01:01 1240 rda_interpret (string): ':blackhole:'
172 01:01:01 1240 expanded: ':blackhole:'
173 01:01:01 1240 file is not a filter file
174 01:01:01 1240 parse_forward_list: :blackhole:
175 01:01:01 1240 extract item: :blackhole:
176 01:01:01 1240 address :blackhole:d
177 01:01:01 1240 LOG: MAIN
178 01:01:01 1240   => :blackhole: <dest2@test.ex> R=r1
179 01:01:01 1240 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
180 01:01:01 1240 After routing:
181 01:01:01 1240   Local deliveries:
182 01:01:01 1240   Remote deliveries:
183 01:01:01 1240   Failed addresses:
184 01:01:01 1240   Deferred addresses:
185 01:01:01 1240 search_tidyup called
186 01:01:01 1240 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
187 01:01:01 1240 changed uid/gid: post-delivery tidying
188 01:01:01 1240   uid=EXIM_UID gid=EXIM_GID pid=pppp
189 01:01:01 1240 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
190 01:01:01 1240 Processing retry items
191 01:01:01 1240 Succeeded addresses:
192 01:01:01 1240  dest2@test.ex: no retry items
193 01:01:01 1240 Failed addresses:
194 01:01:01 1240 Deferred addresses:
195 01:01:01 1240 end of retry processing
196 01:01:01 1240 DSN: processing router : r1
197 01:01:01 1240 DSN: processing successful delivery address: dest2@test.ex
198 01:01:01 1240 DSN: Sender_address: tester2@test.ex
199 01:01:01 1240 DSN: orcpt: NULL  flags: 0x0
200 01:01:01 1240 DSN: envid: NULL  ret: 0
201 01:01:01 1240 DSN: Final recipient: dest2@test.ex
202 01:01:01 1240 DSN: Remote SMTP server supports DSN: 0
203 01:01:01 1240 DSN: not sending DSN success message
204 01:01:01 1240 LOG: MAIN
205 01:01:01 1240   Completed
206 01:01:01 1240 end delivery of 10HmaY-0005vi-00
207 01:01:01 1240 search_tidyup called
208 01:01:01 1240 >>>>>>>>>>>>>>>> Exim pid=pppp (qrun-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
209
210 ******** SERVER ********