Fix 2-phase, in-order queue run delivery order
[exim.git] / test / stderr / 0476
1 Exim version x.yz ....
2 Hints DB:
3 configuration file is TESTSUITE/test-config
4 admin user
5 dropping to exim gid; retaining priv uid
6 set_process_info: pppp running the queue (single queue run)
7 LOG: queue_run MAIN
8   Start queue run: pid=p1234
9 set_process_info: pppp running queue: 10HmaX-000000005vi-0000
10 set_process_info: pppp delivering 10HmaX-000000005vi-0000 (queue run pid p1234)
11 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
12 --------> userx@test.ex <--------
13 set_process_info: pppp delivering 10HmaX-000000005vi-0000 using t1
14 t1 transport entered
15   userx@test.ex
16 hostlist:
17   '127.0.0.1' IP 127.0.0.1 port -1
18 checking retry status of 127.0.0.1
19 127.0.0.1 [127.0.0.1]:1111 retry-status = usable
20 delivering 10HmaX-000000005vi-0000 to 127.0.0.1 [127.0.0.1] (userx@test.ex)
21 set_process_info: pppp delivering 10HmaX-000000005vi-0000 to 127.0.0.1 [127.0.0.1]:PORT_S (userx@test.ex)
22 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
23 connected
24   SMTP<< 220 Server ready
25   SMTP>> EHLO the.local.host.name
26 cmd buf flush ddd bytes
27   SMTP<< 250-server id
28          250-PIPELINING
29          250 OK
30 using PIPELINING
31 not using DSN
32   SMTP|> MAIL FROM:<CALLER@the.local.host.name>
33   SMTP|> RCPT TO:<userx@test.ex>
34   SMTP>> DATA
35 cmd buf flush ddd bytes
36 sync_responses expect mail
37   SMTP<< 250 OK
38 sync_responses expect rcpt for userx@test.ex
39   SMTP<< 550 NO
40 sync_responses expect data
41   SMTP(closed)<<
42 Remote host closed connection in response to pipelined DATA
43 error for DATA ignored: pipelining is in use and there were no good recipients
44 ok=1 send_quit=1 send_rset=1 continue_more=0 yield=0 first_address is NULL
45 transport_check_waiting entered
46   sequence=1 local_max=500 global_max=-1
47 transport_check_waiting: TRUE (found 10HmaY-000000005vi-0000)
48   SMTP>> RSET
49 cmd buf flush ddd bytes
50   SMTP(closed)<<
51 H=127.0.0.1 [127.0.0.1] Remote host closed connection in response to RSET
52   SMTP(close)>>
53 cmdlog: '220:EHLO:250-:MAIL|:RCPT|:DATA:250:550:RSET'
54 set_process_info: pppp delivering 10HmaX-000000005vi-0000: just tried 127.0.0.1 [127.0.0.1]:PORT_S for userx@test.ex: result OK
55 Leaving t1 transport
56 set_process_info: pppp delivering 10HmaX-000000005vi-0000 (just run t1 for userx@test.ex in subprocess)
57 >>>>>>>>>>>>>>>> Exim pid=p1235 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
58 set_process_info: pppp running queue: waiting for 10HmaX-000000005vi-0000 (pppp)
59 set_process_info: pppp delivering 10HmaX-000000005vi-0000: waiting for a remote delivery subprocess to finish
60 set_process_info: pppp delivering 10HmaX-000000005vi-0000
61 LOG: MAIN
62   ** userx@test.ex R=r1 T=t1 H=127.0.0.1 [127.0.0.1]: SMTP error from remote mail server after RCPT TO:<userx@test.ex>: 550 NO
63 set_process_info: pppp tidying up after delivering 10HmaX-000000005vi-0000
64 Exim version x.yz ....
65 Hints DB:
66 configuration file is TESTSUITE/test-config
67 trusted user
68 admin user
69 set_process_info: pppp accepting a local non-SMTP message from <>
70 cannot use sendfile for body: spoolfile not wireformat
71 writing data block fd=dddd size=sss timeout=0
72 LOG: MAIN
73   <= <> R=10HmaX-000000005vi-0000 U=EXIMUSER P=local S=sss
74 Exim version x.yz ....
75 Hints DB:
76 configuration file is TESTSUITE/test-config
77 trusted user
78 admin user
79 dropping to exim gid; retaining priv uid
80 set_process_info: pppp delivering specified messages
81 set_process_info: pppp delivering 10HmaZ-000000005vi-0000
82 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
83 --------> CALLER@the.local.host.name <--------
84 set_process_info: pppp delivering 10HmaZ-000000005vi-0000 using t1
85 t1 transport entered
86   CALLER@the.local.host.name
87 hostlist:
88   '127.0.0.1' IP 127.0.0.1 port -1
89 checking retry status of 127.0.0.1
90 127.0.0.1 [127.0.0.1]:1111 retry-status = usable
91 delivering 10HmaZ-000000005vi-0000 to 127.0.0.1 [127.0.0.1] (CALLER@the.local.host.name)
92 set_process_info: pppp delivering 10HmaZ-000000005vi-0000 to 127.0.0.1 [127.0.0.1]:PORT_S (CALLER@the.local.host.name)
93 Connecting to 127.0.0.1 [127.0.0.1]:PORT_S ...
94  failed: Connection refused
95 LOG: MAIN
96   H=127.0.0.1 [127.0.0.1] Connection refused
97 set_process_info: pppp delivering 10HmaZ-000000005vi-0000: just tried 127.0.0.1 [127.0.0.1]:PORT_S for CALLER@the.local.host.name: result DEFER
98 added retry item for T:[127.0.0.1]:127.0.0.1:PORT_S: errno=dd more_errno=dd,A flags=2
99 all IP addresses skipped or deferred at least one address
100 updating wait-t1 database
101 added 10HmaZ-000000005vi-0000 to queue for 127.0.0.1
102 Leaving t1 transport
103 set_process_info: pppp delivering 10HmaZ-000000005vi-0000 (just run t1 for CALLER@the.local.host.name in subprocess)
104 >>>>>>>>>>>>>>>> Exim pid=p1238 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
105 set_process_info: pppp delivering 10HmaZ-000000005vi-0000: waiting for a remote delivery subprocess to finish
106 set_process_info: pppp delivering 10HmaZ-000000005vi-0000
107 LOG: MAIN
108   == CALLER@the.local.host.name R=r1 T=t1 defer (dd): Connection refused
109 set_process_info: pppp tidying up after delivering 10HmaZ-000000005vi-0000
110 >>>>>>>>>>>>>>>> Exim pid=p1237 (local-accept-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
111 >>>>>>>>>>>>>>>> Exim pid=p1236 (bounce-message) terminating with rc=0 >>>>>>>>>>>>>>>>
112 LOG: MAIN
113   Completed
114 >>>>>>>>>>>>>>>> Exim pid=p1239 (qrun-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
115 set_process_info: pppp running queue: waiting for children of pppp
116 set_process_info: pppp running queue
117 set_process_info: pppp running queue: 10HmaY-000000005vi-0000
118 set_process_info: pppp delivering 10HmaY-000000005vi-0000 (queue run pid p1234)
119 >>>>>>>>>>>>>>>> Remote deliveries >>>>>>>>>>>>>>>>
120 --------> usery@test.ex <--------
121 set_process_info: pppp delivering 10HmaY-000000005vi-0000 using t1
122 t1 transport entered
123   usery@test.ex
124 hostlist:
125   '127.0.0.1' IP 127.0.0.1 port -1
126 checking retry status of 127.0.0.1
127 no message retry record
128 127.0.0.1 [127.0.0.1]:1111 retry-status = unusable
129 all IP addresses skipped or deferred at least one address
130 updating wait-t1 database
131 added 10HmaY-000000005vi-0000 to queue for 127.0.0.1
132 Leaving t1 transport
133 set_process_info: pppp delivering 10HmaY-000000005vi-0000 (just run t1 for usery@test.ex in subprocess)
134 >>>>>>>>>>>>>>>> Exim pid=p1240 (transport) terminating with rc=0 >>>>>>>>>>>>>>>>
135 set_process_info: pppp running queue: waiting for 10HmaY-000000005vi-0000 (pppp)
136 set_process_info: pppp delivering 10HmaY-000000005vi-0000: waiting for a remote delivery subprocess to finish
137 set_process_info: pppp delivering 10HmaY-000000005vi-0000
138 LOG: retry_defer MAIN
139   == usery@test.ex R=r1 T=t1 defer (-54): retry time not reached for any host for 'test.ex'
140 set_process_info: pppp tidying up after delivering 10HmaY-000000005vi-0000
141 >>>>>>>>>>>>>>>> Exim pid=p1241 (qrun-delivery) terminating with rc=0 >>>>>>>>>>>>>>>>
142 set_process_info: pppp running queue: waiting for children of pppp
143 set_process_info: pppp running queue
144 LOG: queue_run MAIN
145   End queue run: pid=p1234
146 >>>>>>>>>>>>>>>> Exim pid=p1234 (fresh-exec) terminating with rc=0 >>>>>>>>>>>>>>>>