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