https://bz.apache.org/bugzilla/show_bug.cgi?id=61616

--- Comment #29 from Carsten Wolff <[email protected]> ---
Hi, unfortunately, using patch#35446 and patch#35447, there is another problem,
when I change the scenario by making the client connection through SSL. In that
case, the transfer stalls very quickly within the proxy_connect poll loop:


[Thu Nov 09 20:34:28.029653 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(246): [client 127.0.0.1:56996] AH01019:
connecting localhost:873 to localhost:873
[Thu Nov 09 20:34:28.029745 2017] [proxy_connect:trace1] [pid 19603:tid
139754421921536] mod_proxy_connect.c(272): [client 127.0.0.1:56996] connecting
to remote proxy localhost on port 873
[Thu Nov 09 20:34:28.029776 2017] [proxy:trace2] [pid 19603:tid
139754421921536] proxy_util.c(2080): [client 127.0.0.1:56996] CONNECT: fam 10
socket created to connect to localhost
[Thu Nov 09 20:34:28.029877 2017] [proxy_connect:trace3] [pid 19603:tid
139754421921536] mod_proxy_connect.c(355): [client 127.0.0.1:56996] connection
complete to [::1]:873 (localhost)
[Thu Nov 09 20:34:28.029905 2017] [proxy_connect:trace1] [pid 19603:tid
139754421921536] mod_proxy_connect.c(377): [client 127.0.0.1:56996] Returning
200 OK
[Thu Nov 09 20:34:28.029921 2017] [ssl:trace6] [pid 19603:tid 139754421921536]
ssl_engine_io.c(859): [client 127.0.0.1:56996] ssl_filter_write: 76 bytes
[Thu Nov 09 20:34:28.029946 2017] [ssl:trace6] [pid 19603:tid 139754421921536]
ssl_engine_io.c(219): [client 127.0.0.1:56996] bio_filter_out_write: 105 bytes
[Thu Nov 09 20:34:28.029967 2017] [ssl:trace4] [pid 19603:tid 139754421921536]
ssl_engine_io.c(2220): [client 127.0.0.1:56996] OpenSSL: write 105/105 bytes to
BIO#7f1b040021b0 [mem: 7f1b040025a3] (BIO dump follows)
[Thu Nov 09 20:34:28.029982 2017] [core:trace6] [pid 19603:tid 139754421921536]
core_filters.c(458): [client 127.0.0.1:56996] core_output_filter: flushing
because of FLUSH bucket
[Thu Nov 09 20:34:28.030012 2017] [core:trace6] [pid 19603:tid 139754421921536]
core_filters.c(857): [client 127.0.0.1:56996] writev_nonblocking: 105/105 bytes
[Thu Nov 09 20:34:28.030027 2017] [proxy_connect:trace2] [pid 19603:tid
139754421921536] mod_proxy_connect.c(399): [client 127.0.0.1:56996] setting up
poll()
[Thu Nov 09 20:34:28.032931 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(454): [client 127.0.0.1:56996] AH01024:
woke from poll(), i=1
[Thu Nov 09 20:34:28.032971 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(485): [client 127.0.0.1:56996] AH01025:
client was readable
[Thu Nov 09 20:34:28.033024 2017] [ssl:trace4] [pid 19603:tid 139754421921536]
ssl_engine_io.c(2220): [client 127.0.0.1:56996] OpenSSL: read 5/5 bytes from
BIO#7f1b04002270 [mem: 7f1b040025a3] (BIO dump follows)
[Thu Nov 09 20:34:28.033041 2017] [ssl:trace4] [pid 19603:tid 139754421921536]
ssl_engine_io.c(2220): [client 127.0.0.1:56996] OpenSSL: read 38/38 bytes from
BIO#7f1b04002270 [mem: 7f1b040025a8] (BIO dump follows)
[Thu Nov 09 20:34:28.033060 2017] [proxy:debug] [pid 19603:tid 139754421921536]
proxy_util.c(3803): [client 127.0.0.1:56996] AH03306:
ap_proxy_transfer_between_connections: read 14 bytes from client
[Thu Nov 09 20:34:28.033089 2017] [proxy:trace2] [pid 19603:tid
139754421921536] proxy_util.c(3847): [client 127.0.0.1:56996]
ap_proxy_transfer_between_connections complete
[Thu Nov 09 20:34:28.033098 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(519): [client 127.0.0.1:56996] backend
wait writable
[Thu Nov 09 20:34:28.033112 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(454): [client 127.0.0.1:56996] AH01024:
woke from poll(), i=1
[Thu Nov 09 20:34:28.033120 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(530): [client 127.0.0.1:56996] backend was
writable
[Thu Nov 09 20:34:28.033159 2017] [core:trace6] [pid 19603:tid 139754421921536]
core_filters.c(857): [remote ::1:873] writev_nonblocking: 14/14 bytes
[Thu Nov 09 20:34:28.033755 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(454): [client 127.0.0.1:56996] AH01024:
woke from poll(), i=1
[Thu Nov 09 20:34:28.033782 2017] [proxy_connect:debug] [pid 19603:tid
139754421921536] mod_proxy_connect.c(485): [client 127.0.0.1:56996] AH01025:
backend was readable
[Thu Nov 09 20:34:28.033817 2017] [proxy:debug] [pid 19603:tid 139754421921536]
proxy_util.c(3803): [client 127.0.0.1:56996] AH03306:
ap_proxy_transfer_between_connections: read 14 bytes from backend
[Thu Nov 09 20:34:28.033835 2017] [ssl:trace4] [pid 19603:tid 139754421921536]
ssl_engine_io.c(1674): [client 127.0.0.1:56996] coalesce: have 0 bytes, adding
14 more
[Thu Nov 09 20:34:28.033848 2017] [proxy:trace2] [pid 19603:tid
139754421921536] proxy_util.c(3847): [client 127.0.0.1:56996]
ap_proxy_transfer_between_connections complete
[Thu Nov 09 20:34:28.582512 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1781): AH00925: initializing worker proxy:forward shared
[Thu Nov 09 20:34:28.582666 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1823): AH00927: initializing worker proxy:forward local
[Thu Nov 09 20:34:28.582706 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1858): AH00930: initialized pool in child 19638 for (*) min=0
max=25 smax=25
[Thu Nov 09 20:34:28.582732 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1781): AH00925: initializing worker proxy:forward shared
[Thu Nov 09 20:34:28.582746 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1823): AH00927: initializing worker proxy:forward local
[Thu Nov 09 20:34:28.582772 2017] [proxy:debug] [pid 19638:tid 139754523858112]
proxy_util.c(1858): AH00930: initialized pool in child 19638 for (*) min=0
max=25 smax=25
[Thu Nov 09 20:39:28.134117 2017] [proxy_connect:error] [pid 19603:tid
139754421921536] (70007)The timeout specified has expired: [client
127.0.0.1:56996] AH01023: polling
[Thu Nov 09 20:39:28.134322 2017] [proxy_connect:trace2] [pid 19603:tid
139754421921536] mod_proxy_connect.c(560): [client 127.0.0.1:56996] finished
with poll() - cleaning up
[Thu Nov 09 20:39:28.134870 2017] [ssl:trace4] [pid 19603:tid 139754421921536]
ssl_engine_io.c(1734): [client 127.0.0.1:56996] coalesce: passing on 14 bytes

-- 
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to