Modify (i.e. fix :-) change 4.61/PH/19: Using message arrival time for
[users/jgh/exim.git] / test / stderr / 0361
1 LOG: MAIN
2   <= CALLER@test.ex U=CALLER P=local S=sss
3 delivering 10HmaX-0005vi-00
4 LOG: MAIN
5   *> miles <miles@thishost.test.ex> R=r4 T=t2
6 LOG: MAIN
7   Completed
8 Exim version x.yz ....
9 changed uid/gid: forcing real = effective
10   uid=uuuu gid=CALLER_GID pid=pppp
11 configuration file is TESTSUITE/test-config
12 admin user
13 changed uid/gid: privilege not needed
14   uid=EXIM_UID gid=EXIM_GID pid=pppp
15 originator: uid=CALLER_UID gid=CALLER_GID login=CALLER name=CALLER_NAME
16 sender address = CALLER@test.ex
17 set_process_info: pppp accepting a local non-SMTP message from <CALLER@test.ex>
18 Sender: CALLER@test.ex
19 Recipients:
20   kilos@recurse.test.ex
21 search_tidyup called
22 >>Headers received:
23
24 rewrite_one_header: type=F:
25   From: CALLER_NAME <CALLER@test.ex>
26 search_tidyup called
27 >>Headers after rewriting and local additions:
28 I Message-Id: <E10HmaY-0005vi-00@the.local.host.name>
29 F From: CALLER_NAME <CALLER@test.ex>
30   Date: Tue, 2 Mar 1999 09:44:33 +0000
31
32 Data file written for message 10HmaY-0005vi-00
33 >>Generated Received: header line
34 P Received: from CALLER by the.local.host.name with local (Exim x.yz)
35         (envelope-from <CALLER@test.ex>)
36         id 10HmaY-0005vi-00
37         for kilos@recurse.test.ex; Tue, 2 Mar 1999 09:44:33 +0000
38 calling local_scan(); timeout=300
39 local_scan() returned 0 NULL
40 Writing spool header file
41 Size of headers = sss
42 LOG: MAIN
43   <= CALLER@test.ex U=CALLER P=local S=sss
44 search_tidyup called
45 exec TESTSUITE/eximdir/exim -DEXIM_PATH=TESTSUITE/eximdir/exim -C TESTSUITE/test-config -d=0xfbb95cfd -N -odi -Mc 10HmaY-0005vi-00
46 Exim version x.yz ....
47 changed uid/gid: forcing real = effective
48   uid=uuuu gid=EXIM_GID pid=pppp
49 configuration file is TESTSUITE/test-config
50 trusted user
51 admin user
52 set_process_info: pppp delivering specified messages
53 set_process_info: pppp delivering 10HmaY-0005vi-00
54 reading spool file 10HmaY-0005vi-00-H
55 user=CALLER uid=CALLER_UID gid=CALLER_GID sender=CALLER@test.ex
56 sender_local=1 ident=CALLER
57 Non-recipients:
58 Empty Tree
59 ---- End of tree ----
60 recipients_count=1
61 body_linecount=0 message_linecount=7
62 Delivery address list:
63   kilos@recurse.test.ex 
64 locking TESTSUITE/spool/db/retry.lockfile
65 locked TESTSUITE/spool/db/retry.lockfile
66 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
67 returned from EXIM_DBOPEN
68 no retry data available
69 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
70 Considering: kilos@recurse.test.ex
71 unique = kilos@recurse.test.ex
72 no domain retry record
73 no address retry record
74 kilos@recurse.test.ex: queued for routing
75 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
76 routing kilos@recurse.test.ex
77 --------> r1 router <--------
78 local_part=kilos domain=recurse.test.ex
79 checking domains
80 recurse.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? yes (end of list)
81 calling r1 router
82 r1 router called for kilos@recurse.test.ex
83   domain = recurse.test.ex
84 DNS lookup of recurse.test.ex (MX) using fakens
85 DNS lookup of recurse.test.ex (MX) gave HOST_NOT_FOUND
86 returning DNS_NOMATCH
87 r1 router widened recurse.test.ex to recurse.test.ex.test.ex
88 DNS lookup of recurse.test.ex.test.ex (MX) using fakens
89 DNS lookup of recurse.test.ex.test.ex (MX) gave NO_DATA
90 returning DNS_NODATA
91 DNS lookup of recurse.test.ex.test.ex (A) using fakens
92 DNS lookup of recurse.test.ex.test.ex (A) succeeded
93 fully qualified name = recurse.test.ex.test.ex
94 recurse.test.ex.test.ex V4NET.99.0.2 mx=-1 sort=xx 
95 domain changed to recurse.test.ex.test.ex
96 rewriting header lines
97 rewrite_one_header: type=F:
98   From: CALLER_NAME <CALLER@test.ex>
99 re-routed to kilos@recurse.test.ex.test.ex
100 locking TESTSUITE/spool/db/retry.lockfile
101 locked TESTSUITE/spool/db/retry.lockfile
102 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
103 returned from EXIM_DBOPEN
104 no retry data available
105 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
106 Considering: kilos@recurse.test.ex.test.ex
107 unique = kilos@recurse.test.ex.test.ex
108 no domain retry record
109 no address retry record
110 kilos@recurse.test.ex.test.ex: queued for routing
111 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
112 routing kilos@recurse.test.ex.test.ex
113 --------> r1 router <--------
114 local_part=kilos domain=recurse.test.ex.test.ex
115 checking domains
116 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
117 r1 router skipped: domains mismatch
118 --------> r2 router <--------
119 local_part=kilos domain=recurse.test.ex.test.ex
120 checking local_parts
121 kilos in "miles"? no (end of list)
122 r2 router skipped: local_parts mismatch
123 --------> r3 router <--------
124 local_part=kilos domain=recurse.test.ex.test.ex
125 checking local_parts
126 kilos in "kilos"? yes (matched "kilos")
127 calling r3 router
128 rda_interpret (string): $local_part@$domain
129 expanded: kilos@recurse.test.ex.test.ex
130 file is not a filter file
131 parse_forward_list: kilos@recurse.test.ex.test.ex
132 extract item: kilos@recurse.test.ex.test.ex
133 r3 router generated kilos@recurse.test.ex.test.ex
134   errors_to=NULL transport=NULL
135   uid=unset gid=unset home=NULL
136 routed by r3 router
137   envelope to: kilos@recurse.test.ex.test.ex
138   transport: <none>
139 locking TESTSUITE/spool/db/retry.lockfile
140 locked TESTSUITE/spool/db/retry.lockfile
141 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
142 returned from EXIM_DBOPEN
143 no retry data available
144 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
145 Considering: kilos@recurse.test.ex.test.ex
146 unique = \0\kilos@recurse.test.ex.test.ex
147 no domain retry record
148 no address retry record
149 kilos@recurse.test.ex.test.ex: queued for routing
150 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
151 routing kilos@recurse.test.ex.test.ex
152 --------> r1 router <--------
153 local_part=kilos domain=recurse.test.ex.test.ex
154 checking domains
155 recurse.test.ex.test.ex in "!thishost.test.ex : !recurse.test.ex.test.ex"? no (matched "!recurse.test.ex.test.ex")
156 r1 router skipped: domains mismatch
157 --------> r2 router <--------
158 local_part=kilos domain=recurse.test.ex.test.ex
159 checking local_parts
160 kilos in "miles"? no (end of list)
161 r2 router skipped: local_parts mismatch
162 --------> r3 router <--------
163 r3 router skipped: previously routed kilos@recurse.test.ex.test.ex
164 --------> r4 router <--------
165 local_part=kilos domain=recurse.test.ex.test.ex
166 calling r4 router
167 r4 router called for kilos@recurse.test.ex.test.ex
168   domain = recurse.test.ex.test.ex
169 set transport t2
170 queued for t2 transport: local_part = kilos
171 domain = recurse.test.ex.test.ex
172   errors_to=NULL
173   domain_data=NULL localpart_data=NULL
174 routed by r4 router
175   envelope to: kilos@recurse.test.ex.test.ex
176   transport: t2
177 >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
178 After routing:
179   Local deliveries:
180     kilos@recurse.test.ex.test.ex
181   Remote deliveries:
182   Failed addresses:
183   Deferred addresses:
184 search_tidyup called
185 >>>>>>>>>>>>>>>> Local deliveries >>>>>>>>>>>>>>>>
186 --------> kilos@recurse.test.ex.test.ex <--------
187 locking TESTSUITE/spool/db/retry.lockfile
188 locked TESTSUITE/spool/db/retry.lockfile
189 EXIM_DBOPEN(TESTSUITE/spool/db/retry)
190 returned from EXIM_DBOPEN
191 no retry data available
192 search_tidyup called
193 changed uid/gid: local delivery to kilos <kilos@recurse.test.ex.test.ex> transport=t2
194   uid=CALLER_UID gid=CALLER_GID pid=pppp
195   home=NULL current=/
196 set_process_info: pppp delivering 10HmaY-0005vi-00 to kilos using t2
197 appendfile transport entered
198 appendfile: mode=600 notify_comsat=0 quota=0 warning=0
199   file=/dev/null format=unix
200   message_prefix=From ${if def:return_path{$return_path}{MAILER-DAEMON}} ${tod_bsdinbox}\n
201   message_suffix=\n
202   maildir_use_size_file=no
203   locking by lockfile fcntl 
204 *** delivery by t2 transport bypassed by -N option
205 search_tidyup called
206 journalling kilos@recurse.test.ex.test.ex/t2
207 t2 transport returned OK for kilos@recurse.test.ex.test.ex
208 post-process kilos@recurse.test.ex.test.ex (0)
209 kilos@recurse.test.ex.test.ex delivered
210 kilos@recurse.test.ex.test.ex: children all complete
211 kilos@recurse.test.ex: children all complete
212 LOG: MAIN
213   *> kilos <kilos@recurse.test.ex> R=r4 T=t2
214 >>>>>>>>>>>>>>>> deliveries are done >>>>>>>>>>>>>>>>
215 changed uid/gid: post-delivery tidying
216   uid=EXIM_UID gid=EXIM_GID pid=pppp
217 set_process_info: pppp tidying up after delivering 10HmaY-0005vi-00
218 LOG: MAIN
219   Completed
220 end delivery of 10HmaY-0005vi-00
221 search_tidyup called
222 search_tidyup called
223 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>
224 search_tidyup called
225 >>>>>>>>>>>>>>>> Exim pid=pppp terminating with rc=0 >>>>>>>>>>>>>>>>