Mailing List Archive

[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #15 from Naresh <nareshkumar.ankuskani@gmail.com> ---
Even we have a similar use-case and encountering the same issue and all the
PUT/POST requests are failing after upgrading to Apache-2.4.51.

Any resolutions or inputs here will be a great help.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

Naresh <nareshkumar.ankuskani@gmail.com> changed:

What |Removed |Added
----------------------------------------------------------------------------
CC| |nareshkumar.ankuskani@gmail
| |.com

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #16 from Simon Brown <simon_b@beezwax.net> ---
(In reply to Naresh from comment #15)

Have you tried adding the line below to your config?

> Proxy100Continue Off

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #17 from Naresh <nareshkumar.ankuskani@gmail.com> ---
(In reply to Simon Brown from comment #16)
> (In reply to Naresh from comment #15)
>
> Have you tried adding the line below to your config?
>
> > Proxy100Continue Off

Thanks Simon for your prompt response here. I tested at a high level & post
adding this directive, the issue seems to be resolved. But is it a must for
Apache version >= 2.4.51 please confirm.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #18 from Simon Brown <simon_b@beezwax.net> ---
(In reply to Naresh from comment #17)

I'm not an Apache developer, just a sys admin that uses Apache, so not under my
control.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

Naresh <nareshkumar.ankuskani@gmail.com> changed:

What |Removed |Added
----------------------------------------------------------------------------
Status|NEEDINFO |NEW

--- Comment #19 from Naresh <nareshkumar.ankuskani@gmail.com> ---
Hi,

Please confirm if latest version of Apache e.g. 2.4.51 is used as a Reverse
Proxy server then adding the directive "Proxy100Continue Off" is must when a
Http Header "Expect: 100-continue" is received in the request ?

Sorry if I have overtaken this BUG-65729. Since there was a Bug created already
inline to the same issue, thought it wise to post the message here.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #20 from Yann Ylavic <ylavic.dev@gmail.com> ---
(In reply to Naresh from comment #19)
>
> Please confirm if latest version of Apache e.g. 2.4.51 is used as a Reverse
> Proxy server then adding the directive "Proxy100Continue Off" is must when a
> Http Header "Expect: 100-continue" is received in the request ?

It's not a must, needed only if the backend server does not handle the
100-continue by itself (or not correctly).

Without a trace of the transaction (LogLevel trace8) it's hard to tell for your
case.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

Yann Ylavic <ylavic.dev@gmail.com> changed:

What |Removed |Added
----------------------------------------------------------------------------
Status|NEW |NEEDINFO

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #21 from Naresh <nareshkumar.ankuskani@gmail.com> ---
Hi,

We have two instances of Apache web server running inside our container image.

One instance acts a Inbound from receiving requests from outside & other acts
as Outbound. So all the requests received by Inbound instance are forwarded to
the Outbound instance.

Previously we never encountered this error with the same configuration files in
place, and started noticing the error after upgrading to the latest Apache.

Attached are the logs of Inbound component.

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #22 from Naresh <nareshkumar.ankuskani@gmail.com> ---
Inbound Component Access Logs
----------------------------
127.0.0.1 - - [24/Jan/2022:14:50:12 -0500] "PUT
/esrs/v1/devices/abcd/klmn/mft/test.txt HTTP/1.1" 408 221

Inbound Component Error Logs
----------------------------
[Mon Jan 24 14:50:12.807193 2022] [rewrite:trace2] [pid 33804]
mod_rewrite.c(480): [client 127.0.0.1:52936] 127.0.0.1 - -
[127.0.0.1/sid#7f2cf0652e60][rid#7f2ce7f710a0/initial] init rewrite engine with
requested uri /esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807202 2022] [rewrite:trace3] [pid 33804]
mod_rewrite.c(480): [client 127.0.0.1:52936] 127.0.0.1 - -
[127.0.0.1/sid#7f2cf0652e60][rid#7f2ce7f710a0/initial] applying pattern '.*' to
uri '/esrs/v1/devices/abcd/klmn/mft/test.txt'
[Mon Jan 24 14:50:12.807209 2022] [rewrite:trace4] [pid 33804]
mod_rewrite.c(480): [client 127.0.0.1:52936] 127.0.0.1 - -
[127.0.0.1/sid#7f2cf0652e60][rid#7f2ce7f710a0/initial] RewriteCond: input='PUT'
pattern='^(TRACE|TRACK|COPY|MOVE|LOCK|UNLOCK|PROPFIND|PROPPATCH|SEARCH|MKCOL|OPTIONS)'
=> not-matched
[Mon Jan 24 14:50:12.807212 2022] [rewrite:trace3] [pid 33804]
mod_rewrite.c(480): [client 127.0.0.1:52936] 127.0.0.1 - -
[127.0.0.1/sid#7f2cf0652e60][rid#7f2ce7f710a0/initial] applying pattern
'^\\/swagger(.*)$' to uri '/esrs/v1/devices/abcd/klmn/mft/test.txt'
[Mon Jan 24 14:50:12.807219 2022] [rewrite:trace1] [pid 33804]
mod_rewrite.c(480): [client 127.0.0.1:52936] 127.0.0.1 - -
[127.0.0.1/sid#7f2cf0652e60][rid#7f2ce7f710a0/initial] pass through
/esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807222 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v1/devices/(.*(context).*)' for proxying
[Mon Jan 24 14:50:12.807228 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v2/gateway/(.*(readiness).*)$' for proxying
[Mon Jan 24 14:50:12.807234 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v2/gateway/(.*(details).*)$' for proxying
[Mon Jan 24 14:50:12.807238 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/(.*(gateway).*)$' for proxying
[Mon Jan 24 14:50:12.807241 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix '/esrs/provision' for
proxying
[Mon Jan 24 14:50:12.807244 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix '/connectivity' for
proxying
[Mon Jan 24 14:50:12.807247 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix
'/esrs/v1/remotescripts' for proxying
[Mon Jan 24 14:50:12.807250 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix
'/esrs/v2/devices/remotescripts' for proxying
[Mon Jan 24 14:50:12.807253 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/(.*(scripts).*)$' for proxying
[Mon Jan 24 14:50:12.807257 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix
'/esrs/v1/casemanagement' for proxying
[Mon Jan 24 14:50:12.807260 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix '/sae/v1' for proxying
[Mon Jan 24 14:50:12.807263 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/product/v1/(.*)/(.*)/context/(.*(SA-DOWNLOADS).*)$' for proxying
[Mon Jan 24 14:50:12.807266 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/product/v1/(.*)/(.*)/(.*(soap).*)$' for proxying
[Mon Jan 24 14:50:12.807269 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/product/v1/(.*)/(.*)/(.*context.*)$' for proxying
[Mon Jan 24 14:50:12.807272 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against prefix '/esrs/v1/sysinfo' for
proxying
[Mon Jan 24 14:50:12.807275 2022] [proxy:trace2] [pid 33804] mod_proxy.c(883):
[client 127.0.0.1:52936] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v1/devices/(.*(mft).*)$' for proxying
[Mon Jan 24 14:50:12.807280 2022] [proxy:trace1] [pid 33804] mod_proxy.c(999):
[client 127.0.0.1:52936] AH03464: URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' matches proxy handler
'proxy:http://localhost:6516/esrs/v1/devices/abcd/klmn/mft/test.txt'
[Mon Jan 24 14:50:12.807297 2022] [authz_core:debug] [pid 33804]
mod_authz_core.c(844): [client 127.0.0.1:52936] AH01628: authorization result:
granted (no directives)
[Mon Jan 24 14:50:12.807303 2022] [core:trace3] [pid 33804] request.c(365):
[client 127.0.0.1:52936] request authorized without authentication by
access_checker_ex hook: /esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807323 2022] [proxy_http:trace1] [pid 33804]
mod_proxy_http.c(98): [client 127.0.0.1:52936] HTTP: canonicalising URL
http://localhost:6516/esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807369 2022] [proxy:trace2] [pid 33804]
proxy_util.c(2330): [client 127.0.0.1:52936] http: found worker
http://localhost:6516/esrs/v1/devices/$1 for
http://localhost:6516/esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807376 2022] [proxy:debug] [pid 33804] mod_proxy.c(1504):
[client 127.0.0.1:52936] AH01143: Running scheme http handler (attempt 0)
[Mon Jan 24 14:50:12.807379 2022] [proxy_http:trace1] [pid 33804]
mod_proxy_http.c(1875): [client 127.0.0.1:52936] HTTP: serving URL
http://localhost:6516/esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 14:50:12.807382 2022] [proxy:debug] [pid 33804] proxy_util.c(2528):
AH00942: http: has acquired connection for (localhost)
[Mon Jan 24 14:50:12.807386 2022] [proxy:debug] [pid 33804] proxy_util.c(2583):
[client 127.0.0.1:52936] AH00944: connecting
http://localhost:6516/esrs/v1/devices/abcd/klmn/mft/test.txt to localhost:6516
[Mon Jan 24 14:50:12.807475 2022] [proxy:debug] [pid 33804] proxy_util.c(2806):
[client 127.0.0.1:52936] AH00947: connected
/esrs/v1/devices/abcd/klmn/mft/test.txt to localhost:6516
[Mon Jan 24 14:50:12.807507 2022] [ssl:trace4] [pid 33804]
ssl_engine_io.c(2379): [client 127.0.0.1:52936] OpenSSL: I/O error, 5 bytes
expected to read on BIO#55c198227f00 [mem: 55c1982314f3]
[Mon Jan 24 14:50:12.807528 2022] [proxy:trace2] [pid 33804]
proxy_util.c(3241): http: fam 2 socket created to connect to localhost
[Mon Jan 24 14:50:12.807570 2022] [proxy:debug] [pid 33804] proxy_util.c(3275):
AH02824: http: connection established with 127.0.0.1:6516 (localhost)
[Mon Jan 24 14:50:12.807581 2022] [proxy:debug] [pid 33804] proxy_util.c(3459):
AH00962: http: connection complete to 127.0.0.1:6516 (localhost)
[Mon Jan 24 14:50:12.807607 2022] [core:trace6] [pid 33804]
core_filters.c(812): [remote 127.0.0.1:6516] writev_nonblocking: 608/608
[Mon Jan 24 14:50:32.898741 2022] [proxy_http:trace3] [pid 33804]
mod_proxy_http.c(1198): [client 127.0.0.1:52936] Status from backend: 408
[Mon Jan 24 14:50:32.898763 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(820): [client 127.0.0.1:52936] Headers received from backend:
[Mon Jan 24 14:50:32.898769 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(842): [client 127.0.0.1:52936] Date: Mon, 24 Jan 2022 19:50:12
GMT
[Mon Jan 24 14:50:32.898793 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(842): [client 127.0.0.1:52936] Server: Apache
[Mon Jan 24 14:50:32.898799 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(842): [client 127.0.0.1:52936] Content-Length: 221
[Mon Jan 24 14:50:32.898802 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(842): [client 127.0.0.1:52936] Connection: close
[Mon Jan 24 14:50:32.898805 2022] [proxy_http:trace4] [pid 33804]
mod_proxy_http.c(842): [client 127.0.0.1:52936] Content-Type: text/html;
charset=iso-8859-1
[Mon Jan 24 14:50:32.898814 2022] [proxy_http:debug] [pid 33804]
mod_proxy_http.c(1439): [client 127.0.0.1:52936] AH10153: HTTP: no 100 continue
sent by 127.0.0.1:6516 (localhost): not sending body (response: HTTP/1.1 408
Request Timeout)
[Mon Jan 24 14:50:32.898871 2022] [proxy_http:trace3] [pid 33804]
mod_proxy_http.c(1633): [client 127.0.0.1:52936] start body send
[Mon Jan 24 14:50:32.898880 2022] [headers:trace2] [pid 33804]
mod_headers.c(866): AH01502: headers: ap_headers_output_filter()
[Mon Jan 24 14:50:32.898895 2022] [http:trace3] [pid 33804]
http_filters.c(1129): [client 127.0.0.1:52936] Response sent with status 408,
headers:
[Mon Jan 24 14:50:32.898898 2022] [http:trace5] [pid 33804]
http_filters.c(1136): [client 127.0.0.1:52936] Date: Mon, 24 Jan 2022
19:50:12 GMT
[Mon Jan 24 14:50:32.898900 2022] [http:trace5] [pid 33804]
http_filters.c(1139): [client 127.0.0.1:52936] Server: Apache
[Mon Jan 24 14:50:32.898905 2022] [http:trace4] [pid 33804]
http_filters.c(958): [client 127.0.0.1:52936] Strict-Transport-Security:
max-age=31536000; includeSubDomains;
[Mon Jan 24 14:50:32.898907 2022] [http:trace4] [pid 33804]
http_filters.c(958): [client 127.0.0.1:52936] Content-Length: 221
[Mon Jan 24 14:50:32.898910 2022] [http:trace4] [pid 33804]
http_filters.c(958): [client 127.0.0.1:52936] Content-Type: text/html;
charset=iso-8859-1
[Mon Jan 24 14:50:32.898912 2022] [http:trace4] [pid 33804]
http_filters.c(958): [client 127.0.0.1:52936] Content-Security-Policy:
frame-ancestors 'self'
[Mon Jan 24 14:50:32.898915 2022] [http:trace4] [pid 33804]
http_filters.c(958): [client 127.0.0.1:52936] Connection: close
[Mon Jan 24 14:50:32.898929 2022] [ssl:trace4] [pid 33804]
ssl_engine_io.c(1820): [client 127.0.0.1:52936] coalesce: have 0 bytes, adding
284 more (buckets=1)
[Mon Jan 24 14:50:32.898933 2022] [ssl:trace4] [pid 33804]
ssl_engine_io.c(1881): [client 127.0.0.1:52936] coalesce: passing on 284 bytes
[Mon Jan 24 14:50:32.898938 2022] [ssl:trace6] [pid 33804]
ssl_engine_io.c(856): [client 127.0.0.1:52936] ssl_filter_write: 284 bytes
[Mon Jan 24 14:50:32.898952 2022] [ssl:trace6] [pid 33804]
ssl_engine_io.c(215): [client 127.0.0.1:52936] bio_filter_out_write: 313 bytes

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org
[Bug 65729] Request with the HTTP Header 'Expect: 100-continue' take over 20s response time in Apache 2.4.51 [ In reply to ]
https://bz.apache.org/bugzilla/show_bug.cgi?id=65729

--- Comment #23 from Naresh <nareshkumar.ankuskani@gmail.com> ---
Outbound Component - Access Logs:
---------------------------------
127.0.0.1 - - [24/Jan/2022:16:27:43 -0500] "PUT
/esrs/v1/devices/abcd/klmn/mft/test.txt HTTP/1.1" 408 221


Outbound Component - Error Logs:
---------------------------------

[Mon Jan 24 16:27:13.256151 2022] [proxy:debug] [pid 42559] proxy_util.c(2227):
AH00931: initialized single connection worker in child 42559 for
(temp-fqdn.com)
[Mon Jan 24 16:27:43.079447 2022] [core:trace5] [pid 42555] protocol.c(713):
[client 127.0.0.1:51092] Request received from client: PUT
/esrs/v1/devices/abcd/klmn/mft/test.txt HTTP/1.1
[Mon Jan 24 16:27:43.079528 2022] [:debug] [pid 42555] mod_xyz.c(594):
------Start - PostReadRequest: (null), /esrs/v1/devices/abcd/klmn/mft/test.txt
200------
[Mon Jan 24 16:27:43.079542 2022] [:debug] [pid 42555] mod_xyz.c(346): Not
valid getDatacenter request, returned to mft
[Mon Jan 24 16:27:43.079545 2022] [:debug] [pid 42555] mod_xyz.c(598):
------End - PostReadRequest------
[Mon Jan 24 16:27:43.079549 2022] [http:trace4] [pid 42555]
http_request.c(436): [client 127.0.0.1:51092] Headers received from client:
[Mon Jan 24 16:27:43.079553 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] Host: localhost:6516
[Mon Jan 24 16:27:43.079572 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] Date: Mon Jan 24 16:27:43 EST
2022
[Mon Jan 24 16:27:43.079575 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] X-Esrs-Host: 127.0.0.1:9443
[Mon Jan 24 16:27:43.079577 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] X-Forwarded-For: 127.0.0.1
[Mon Jan 24 16:27:43.079581 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] X-Forwarded-Host:
127.0.0.1:9443
[Mon Jan 24 16:27:43.079584 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] X-Forwarded-Server: httpd
[Mon Jan 24 16:27:43.079586 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] Content-Length: 10
[Mon Jan 24 16:27:43.079590 2022] [http:trace4] [pid 42555]
http_request.c(440): [client 127.0.0.1:51092] Connection: close
[Mon Jan 24 16:27:43.079610 2022] [rewrite:trace2] [pid 42555]
mod_rewrite.c(480): [client 127.0.0.1:51092] 127.0.0.1 - -
[localhost/sid#7fcf4a0f9a78][rid#7fcf49fb30a0/initial] init rewrite engine with
requested uri /esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.079615 2022] [rewrite:trace3] [pid 42555]
mod_rewrite.c(480): [client 127.0.0.1:51092] 127.0.0.1 - -
[localhost/sid#7fcf4a0f9a78][rid#7fcf49fb30a0/initial] applying pattern '.*' to
uri '/esrs/v1/devices/abcd/klmn/mft/test.txt'
[Mon Jan 24 16:27:43.079628 2022] [rewrite:trace4] [pid 42555]
mod_rewrite.c(480): [client 127.0.0.1:51092] 127.0.0.1 - -
[localhost/sid#7fcf4a0f9a78][rid#7fcf49fb30a0/initial] RewriteCond: input='PUT'
pattern='^(TRACE|TRACK|COPY|MOVE|LOCK|UNLOCK|PROPFIND|PROPPATCH|SEARCH|MKCOL|OPTIONS)'
=> not-matched
[Mon Jan 24 16:27:43.079631 2022] [rewrite:trace1] [pid 42555]
mod_rewrite.c(480): [client 127.0.0.1:51092] 127.0.0.1 - -
[localhost/sid#7fcf4a0f9a78][rid#7fcf49fb30a0/initial] pass through
/esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.079635 2022] [proxy:trace2] [pid 42555] mod_proxy.c(883):
[client 127.0.0.1:51092] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v1/devices/(.*)/(.*)/mft/(.*)/DATACENTER-HOP$' for proxying
[Mon Jan 24 16:27:43.079647 2022] [proxy:trace2] [pid 42555] mod_proxy.c(883):
[client 127.0.0.1:51092] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v1/devices/(.*)/(.*)/mft/(.*)/DATACENTER-DUR$' for proxying
[Mon Jan 24 16:27:43.079653 2022] [proxy:trace2] [pid 42555] mod_proxy.c(883):
[client 127.0.0.1:51092] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/v1/datacenter/prodlocker/(.*)/(.*)$' for proxying
[Mon Jan 24 16:27:43.079656 2022] [proxy:trace2] [pid 42555] mod_proxy.c(883):
[client 127.0.0.1:51092] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern '^/esrs/(.*)/mft$'
for proxying
[Mon Jan 24 16:27:43.079660 2022] [proxy:trace2] [pid 42555] mod_proxy.c(883):
[client 127.0.0.1:51092] AH03461: attempting to match URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' against pattern
'^/esrs/(.*)/mft/(.*)$' for proxying
[Mon Jan 24 16:27:43.079664 2022] [proxy:trace1] [pid 42555] mod_proxy.c(999):
[client 127.0.0.1:51092] AH03464: URI path
'/esrs/v1/devices/abcd/klmn/mft/test.txt' matches proxy handler
'proxy:https://temp-fqdn.com/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt'
[Mon Jan 24 16:27:43.103477 2022] [authz_core:debug] [pid 42555]
mod_authz_core.c(844): [client 127.0.0.1:51092] AH01628: authorization result:
granted (no directives)
[Mon Jan 24 16:27:43.103482 2022] [core:trace3] [pid 42555] request.c(365):
[client 127.0.0.1:51092] request authorized without authentication by
access_checker_ex hook: /esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.103510 2022] [proxy_http:trace1] [pid 42555]
mod_proxy_http.c(98): [client 127.0.0.1:51092] HTTP: canonicalising URL
https://temp-fqdn.com/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.103560 2022] [proxy:trace2] [pid 42555]
proxy_util.c(2330): [client 127.0.0.1:51092] https: found worker
https://temp-fqdn.com/r/mft/incoming/ for
https://temp-fqdn.com/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.103564 2022] [proxy:debug] [pid 42555] mod_proxy.c(1504):
[client 127.0.0.1:51092] AH01143: Running scheme https handler (attempt 0)
[Mon Jan 24 16:27:43.103569 2022] [proxy_http:trace1] [pid 42555]
mod_proxy_http.c(1875): [client 127.0.0.1:51092] HTTP: serving URL
https://temp-fqdn.com/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt
[Mon Jan 24 16:27:43.103573 2022] [proxy:debug] [pid 42555] proxy_util.c(2528):
AH00942: https: has acquired connection for (temp-fqdn.com)
[Mon Jan 24 16:27:43.103579 2022] [proxy:debug] [pid 42555] proxy_util.c(2583):
[client 127.0.0.1:51092] AH00944: connecting
https://temp-fqdn.com/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt to
temp-fqdn.com:443
[Mon Jan 24 16:27:43.106536 2022] [proxy:debug] [pid 42555] proxy_util.c(2806):
[client 127.0.0.1:51092] AH00947: connected
/r/mft/incoming//esrs/v1/devices/abcd/klmn/mft/test.txt to temp-fqdn.com:443
[Mon Jan 24 16:27:43.271433 2022] [core:trace4] [pid 42454] mpm_common.c(540):
mpm child 42659 (gen 0/slot 5) started
[Mon Jan 24 16:27:43.271954 2022] [proxy:debug] [pid 42659] proxy_util.c(2119):
AH00925: initializing worker proxy:reverse shared
[Mon Jan 24 16:27:43.271981 2022] [proxy:debug] [pid 42659] proxy_util.c(2179):
AH00927: initializing worker proxy:reverse local
[Mon Jan 24 16:27:43.272014 2022] [proxy:debug] [pid 42659] proxy_util.c(2227):
AH00931: initialized single connection worker in child 42659 for (*)
[Mon Jan 24 16:27:43.272260 2022] [proxy:debug] [pid 42659] proxy_util.c(2119):
AH00925: initializing worker
https://temp-fqdn.com/r/mft/incoming/esrs/v1/devices/$1/$2/mft/$3 shared
[Mon Jan 24 16:27:43.272267 2022] [proxy:debug] [pid 42659] proxy_util.c(2179):
AH00927: initializing worker
https://temp-fqdn.com/r/mft/incoming/esrs/v1/devices/$1/$2/mft/$3 local
[Mon Jan 24 16:27:43.272288 2022] [proxy:debug] [pid 42659] proxy_util.c(2227):
AH00931: initialized single connection worker in child 42659 for
(temp-fqdn.com)
[Mon Jan 24 16:27:43.272294 2022] [proxy:debug] [pid 42659] proxy_util.c(2119):
AH00925: initializing worker https://temp-fqdn.com/r/mft/incoming/ shared
[Mon Jan 24 16:27:43.272300 2022] [proxy:debug] [pid 42659] proxy_util.c(2179):
AH00927: initializing worker https://temp-fqdn.com/r/mft/incoming/ local
[Mon Jan 24 16:27:43.272319 2022] [proxy:debug] [pid 42659] proxy_util.c(2227):
AH00931: initialized single connection worker in child 42659 for
(temp-fqdn.com)
[Mon Jan 24 16:27:43.272381 2022] [proxy:debug] [pid 42659] proxy_util.c(2227):
AH00931: initialized single connection worker in child 42659 for
(temp-fqdn.com)
[Mon Jan 24 16:28:03.124552 2022] [reqtimeout:info] [pid 42555] [client
127.0.0.1:51092] AH01382: Request body read timeout
[Mon Jan 24 16:28:03.124573 2022] [mfttoorg:error] [pid 42555] [client
127.0.0.1:51092] mfttoorg-input: get brigade failed.
[Mon Jan 24 16:28:03.153042 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(301): send_transfer_aborted_toorg: 200
[Mon Jan 24 16:28:03.153057 2022] [proxy:error] [pid 42555] (70007)The timeout
specified has expired: [client 127.0.0.1:51092] AH01095: prefetch request body
failed to 10.105.130.237:443 (temp-fqdn.com) from 127.0.0.1 ()
[Mon Jan 24 16:28:03.153068 2022] [proxy:debug] [pid 42555] proxy_util.c(2543):
AH00943: https: has released connection for (temp-fqdn.com)
[Mon Jan 24 16:28:03.153132 2022] [http:trace3] [pid 42555]
http_filters.c(1129): [client 127.0.0.1:51092] Response sent with status 408,
headers:
[Mon Jan 24 16:28:03.153135 2022] [http:trace5] [pid 42555]
http_filters.c(1136): [client 127.0.0.1:51092] Date: Mon, 24 Jan 2022
21:27:43 GMT
[Mon Jan 24 16:28:03.153138 2022] [http:trace5] [pid 42555]
http_filters.c(1139): [client 127.0.0.1:51092] Server: Apache
[Mon Jan 24 16:28:03.153142 2022] [http:trace4] [pid 42555]
http_filters.c(958): [client 127.0.0.1:51092] Content-Length: 221
[Mon Jan 24 16:28:03.153145 2022] [http:trace4] [pid 42555]
http_filters.c(958): [client 127.0.0.1:51092] Connection: close
[Mon Jan 24 16:28:03.153147 2022] [http:trace4] [pid 42555]
http_filters.c(958): [client 127.0.0.1:51092] Content-Type: text/html;
charset=iso-8859-1
[Mon Jan 24 16:28:03.153152 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(676): [client 127.0.0.1:51092] transfer status in out filter 408
[Mon Jan 24 16:28:03.153154 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(679): [client 127.0.0.1:51092] transfer aborted in out filter 0
[Mon Jan 24 16:28:03.153157 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(697): [client 127.0.0.1:51092] inside if condition transfer
status in out filter before 408
[Mon Jan 24 16:28:03.167489 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(301): send_transfer_aborted_toorg: 200
[Mon Jan 24 16:28:03.167502 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(700): [client 127.0.0.1:51092] inside if condition transfer
status in out filter 408
[Mon Jan 24 16:28:03.167533 2022] [core:trace6] [pid 42555]
core_filters.c(812): [client 127.0.0.1:51092] writev_nonblocking: 170/170
[Mon Jan 24 16:28:03.167542 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(676): [client 127.0.0.1:51092] transfer status in out filter 408
[Mon Jan 24 16:28:03.167545 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(679): [client 127.0.0.1:51092] transfer aborted in out filter 0
[Mon Jan 24 16:28:03.167547 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(697): [client 127.0.0.1:51092] inside if condition transfer
status in out filter before 408
[Mon Jan 24 16:28:03.181159 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(301): send_transfer_aborted_toorg: 200
[Mon Jan 24 16:28:03.181173 2022] [mfttoorg:debug] [pid 42555]
mod_xyztoorg.c(700): [client 127.0.0.1:51092] inside if condition transfer
status in out filter 408
[Mon Jan 24 16:28:03.181197 2022] [core:trace6] [pid 42555]
core_filters.c(812): [client 127.0.0.1:51092] writev_nonblocking: 221/221
[Mon Jan 24 16:28:43.548013 2022] [core:info] [pid 42454] AH00096: removed PID
file /var/run/httpdR.pid (pid=42454)
[Mon Jan 24 16:28:43.548027 2022] [mpm_prefork:notice] [pid 42454] AH00170:
caught SIGWINCH, shutting down gracefully

--
You are receiving this mail because:
You are the assignee for the bug.
---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org