On Wed, Feb 9, 2022 at 2:09 PM Ruediger Pluem <rpl...@apache.org> wrote:
>
> On 2/9/22 1:25 PM, Graham Leggett wrote:
> > On 08 Feb 2022, at 19:01, Yann Ylavic <ylavic....@gmail.com
> > <mailto:ylavic....@gmail.com>> wrote:
> >
> >>> The most concurrent requests I can get out of MacOS appears to be roughly
> >>> 2000 concurrent requests before “connection reset by
> >>> peer” kills the test.
> >>>
> >>> One small detail I have uncovered is that a small percentage of requests
> >>> fail, caused by httpd gracefully shutting the
> >>> connection down before returning a response. Stopping ab in a debugger
> >>> confirms that openssl returns SSL_ERROR_ZERO_RETURN on
> >>> read, which is what openssl does when the other side gracefully triggers
> >>> close notify.
> >>>
> >>> Is this something anyone has seen before while I go digging?
> >>
> >> I tried with ab too and indeed there was some (dead-)locking issue at
> >> some point, could you please retry with the latest pushes I just made
> >> to the PR?
> >
> > I tried with the latest patch, then I tried with no patch (vanilla trunk)
> > and got the same results.
> >
> > Working backwards looking for graceful attempts to shut down the SSL
> > connection, I found this:
> >
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [info] [pid 8747]
> > mod_ssl.c(681): [client ::1:56237] AH01964: Connection to child
> > 29 established (server localhost:443)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_kernel.c(2402): [client ::1:56237] AH02043: SSL
> > virtual host for servername localhost found
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_kernel.c(2262): [client ::1:56237] AH02041: Protocol:
> > TLSv1.2, Cipher: ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_kernel.c(415): [client ::1:56237] AH02034: Initial
> > (No.1) HTTPS request received for child 29 (server localhost:443)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > mod_authz_core.c(843): [client ::1:56237] AH01628: authorization
> > result: granted (no directives)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_kernel.c(415): [client ::1:56237] AH02034: Subsequent
> > (No.2) HTTPS request received for child 27 (server localhost:443)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > mod_authz_core.c(843): [client ::1:56237] AH01628: authorization
> > result: granted (no directives)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_kernel.c(415): [client ::1:56237] AH02034: Subsequent
> > (No.3) HTTPS request received for child 37 (server localhost:443)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > mod_authz_core.c(843): [client ::1:56237] AH01628: authorization
> > result: granted (no directives)
> > ::1:56237 ::1:56237 [Wed Feb 09 09:52:21 2022] [debug] [pid 8747]
> > ssl_engine_io.c(1184): [client ::1:56237] AH02001: Connection
> > closed to child 29 with standard shutdown (server localhost:443)
> >
> > It seems reasonably normal, except what looks like is happening is when
> > "Connection closed to child 29 with standard shutdown” is
> > run, this seems to happen before a response is sent to the client.
> >
> > ab then goes “that’s weird, I was trying to read a response, but got a
> > connection closed instead”, and marks that request as failed.
> >
> > This is limited to keepalive requests, and happens after a number of
> > keepalive requests have been correctly handled.
>
> Probably you get hit by this?
>
> https://github.com/apache/httpd/blob/46a9db4c6f9fadca4e362872021fa62a37908ece/server/mpm/event/event.c#L2096-L2101
With PR #294 this specific case shouldn't happen anymore (thanks to
https://github.com/apache/httpd/pull/294/commits/7089813e13bba0f25bd34df4c643cf5878e6e043).
Also, every connection killed by the MPM (timeout, internal error..)
should have an mpm_event:info message (AH10380), do you see any in
your logs Graham?
What might happen still is that a kept-alive connection gets closed by
httpd, after KeepAliveTimeout usually but also as soon as httpd gets
short in worker threads
(https://github.com/apache/httpd/blob/trunk/server/mpm/event/event.c#L2260-L2262),
and in both cases I'm not sure ab wouldn't report the same error
anyway..
With the latest version (sorry I keep updating this PR), I have no
mpm_event:info message (using "LogLevel info ssl:warn"), and ab
reports some "Failed length" requests:
```
$ bin/ab -k -R 20 -n 409600 -c 4096 https://127.0.0.1:40443/index.html
This is ApacheBench, Version 2.3 <$Revision$>
Copyright 1996 Adam Twiss, Zeus Technology Ltd,
http://web.archive.org/web/20000304112933/http://www.zeustech.net/
Licensed to The Apache Software Foundation, http://www.apache.org/
Benchmarking 127.0.0.1 (be patient)
Completed 40960 requests
Completed 81920 requests
Completed 122880 requests
Completed 163840 requests
Completed 204800 requests
Completed 245760 requests
Completed 286720 requests
Completed 327680 requests
Completed 368640 requests
Completed 409600 requests
Finished 409600 requests
Server Software: Apache/2.5.1-dev
Server Hostname: 127.0.0.1
Server Port: 40443
SSL/TLS Protocol: TLSv1.2,ECDHE-RSA-AES256-GCM-SHA384,2048,256
Server Temp Key: X25519 253 bits
Document Path: /index.html
Document Length: 321 bytes
Concurrency Level: 4096
Concurrency achieved: 4096
Rampup delay: 20 [ms]
Time taken for tests: 106.912 seconds
Complete requests: 409600
Failed requests: 8517
(Connect: 0, Receive: 0, Length: 8517, Exceptions: 0)
Keep-Alive requests: 396304
Total transferred: 249548361 bytes
HTML transferred: 128820510 bytes
Requests per second: 3831.19 [#/sec] (mean)
Time per request: 1069.118 [ms] (mean)
Time per request: 0.261 [ms] (mean, across all concurrent requests)
Transfer rate: 2279.44 [Kbytes/sec] received
Connection Times (ms)
min mean[+/-sd] median max
Connect: 0 72 480.4 0 7428
Processing: 0 580 543.7 442 6290
Waiting: 0 562 541.2 415 6271
Total: 0 652 766.9 464 9050
Percentage of the requests served within a certain time (ms)
50% 464
66% 794
75% 995
80% 1083
90% 1268
95% 1632
98% 2831
99% 4490
100% 9050 (longest request)
```
This is on my laptop with full-throttle cpu/fans during the test, so
the above results are not really exciting..
FWIW, the MPM setting have enough processes/threads for the load:
```
StartServers 1
ServerLimit 1000
ThreadLimit 50
ThreadsPerChild 50
MinSpareThreads 400
MaxSpareThreads 1000
MaxRequestWorkers 5000
```
The "length" errors reported by ab show like this in httpd logs
(LogLevel info mpm_event:trace6 ssl:debug):
```
[Wed Feb 09 15:15:44.184365 2022] [mpm_event:trace1] [pid 2489139:tid
139983741224512] event.c(2139): All workers are busy or dying, will
shutdown 1 keep-alive connections
[Wed Feb 09 15:15:44.184373 2022] [mpm_event:trace6] [pid 2489139:tid
139983741224512] event.c(992): [client 127.0.0.1:47568] deferring
close for connection
60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.184393 2022] [mpm_event:trace6] [pid 2489139:tid
139984080422464] event.c(2806): [client 127.0.0.1:47568] deferred
close for connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184399 2022] [mpm_event:trace6] [pid 2489139:tid
139984080422464] event.c(1366): [client 127.0.0.1:47568] processing
connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184411 2022] [mpm_event:trace6] [pid 2489139:tid
139984080422464] event.c(2030): [client 127.0.0.1:47568] lingering
close for connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184464 2022] [ssl:debug] [pid 2489139:tid
139984080422464] ssl_engine_io.c(1209): [client 127.0.0.1:47568]
AH02001: Connection closed to child 260 with standard shutdown (server
localhost:40443)
[Wed Feb 09 15:15:44.189968 2022] [mpm_event:trace6] [pid 2489139:tid
139983741224512] event.c(2371): [client 127.0.0.1:47568] polled
connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190262 2022] [mpm_event:trace6] [pid 2489139:tid
139983877019200] event.c(1366): [client 127.0.0.1:47568] processing
connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190273 2022] [mpm_event:trace6] [pid 2489139:tid
139983877019200] event.c(2030): [client 127.0.0.1:47568] lingering
close for connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190283 2022] [mpm_event:trace6] [pid 2489139:tid
139983877019200] event.c(1023): [client 127.0.0.1:47568] closing
connection 60e00017e100:60700001b1b0:26:LINGER_SHORT at
process_lingering_close:2074
```
(full log attached for [client 127.0.0.1:47568], I got the client port
by using the attached ab patch too).
So ab is (appropriately?) reporting errors for kept-alive connections
that had to be shutdown earlier than KeepAliveTimeout, although I
suspect that it would report the same after the KeepAliveTimeout.
Regards;
Yann.
[Wed Feb 09 15:15:39.541677 2022] [mpm_event:trace5] [pid 2488892:tid 139984250435648] event.c(3670): Spawning new child: slot 8 active / total daemons: 8/8
[Wed Feb 09 15:15:40.542772 2022] [mpm_event:trace5] [pid 2488892:tid 139984250435648] event.c(3670): Spawning new child: slot 9 active / total daemons: 9/9
[Wed Feb 09 15:15:40.543932 2022] [mpm_event:trace5] [pid 2488892:tid 139984250435648] event.c(3670): Spawning new child: slot 10 active / total daemons: 10/10
[Wed Feb 09 15:15:42.545563 2022] [mpm_event:trace5] [pid 2488892:tid 139984250435648] event.c(3670): Spawning new child: slot 11 active / total daemons: 11/11
[Wed Feb 09 15:15:43.185173 2022] [ssl:info] [pid 2489139:tid 139983758202432] [client 127.0.0.1:47568] AH01964: Connection to child 298 established (server localhost:40443)
[Wed Feb 09 15:15:43.185261 2022] [mpm_event:trace6] [pid 2489139:tid 139983758202432] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.185340 2022] [ssl:debug] [pid 2489139:tid 139983758202432] ssl_engine_kernel.c(2432): [client 127.0.0.1:47568] AH02645: Server name not provided via TLS extension (using default/first virtual host)
[Wed Feb 09 15:15:43.186547 2022] [ssl:debug] [pid 2489139:tid 139983758202432] mod_ssl.c(729): [client 127.0.0.1:47568] AH10372: SSL handshake in progress, try again later
[Wed Feb 09 15:15:43.186556 2022] [mpm_event:trace6] [pid 2489139:tid 139983758202432] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.196278 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.196497 2022] [mpm_event:trace6] [pid 2489139:tid 139984139171392] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.196900 2022] [ssl:debug] [pid 2489139:tid 139984139171392] ssl_engine_kernel.c(2262): [client 127.0.0.1:47568] AH02041: Protocol: TLSv1.2, Cipher: ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)
[Wed Feb 09 15:15:43.196912 2022] [ssl:debug] [pid 2489139:tid 139984139171392] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.205067 2022] [ssl:debug] [pid 2489139:tid 139984139171392] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Initial (No.1) HTTPS request received for child 253 (server localhost:40443)
[Wed Feb 09 15:15:43.233417 2022] [mpm_event:trace6] [pid 2489139:tid 139984139171392] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.233439 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.239449 2022] [mpm_event:trace6] [pid 2489139:tid 139984155956800] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.239476 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.239563 2022] [mpm_event:trace6] [pid 2489139:tid 139983749711424] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.239573 2022] [ssl:debug] [pid 2489139:tid 139983749711424] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.241273 2022] [ssl:debug] [pid 2489139:tid 139983749711424] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.2) HTTPS request received for child 299 (server localhost:40443)
[Wed Feb 09 15:15:43.241569 2022] [mpm_event:trace6] [pid 2489139:tid 139983749711424] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.243849 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.244114 2022] [mpm_event:trace6] [pid 2489139:tid 139983995840064] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.244150 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.244190 2022] [mpm_event:trace6] [pid 2489139:tid 139983809123904] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.244197 2022] [ssl:debug] [pid 2489139:tid 139983809123904] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.244685 2022] [ssl:debug] [pid 2489139:tid 139983809123904] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.3) HTTPS request received for child 292 (server localhost:40443)
[Wed Feb 09 15:15:43.261730 2022] [mpm_event:trace6] [pid 2489139:tid 139983809123904] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.262407 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.262451 2022] [mpm_event:trace6] [pid 2489139:tid 139983775176256] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.262606 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.262901 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.262908 2022] [ssl:debug] [pid 2489139:tid 139984080422464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.275796 2022] [ssl:debug] [pid 2489139:tid 139984080422464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.4) HTTPS request received for child 260 (server localhost:40443)
[Wed Feb 09 15:15:43.276085 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.277636 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.277861 2022] [mpm_event:trace6] [pid 2489139:tid 139983953401408] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.277921 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.278098 2022] [mpm_event:trace6] [pid 2489139:tid 139983860045376] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.278110 2022] [ssl:debug] [pid 2489139:tid 139983860045376] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.296861 2022] [ssl:debug] [pid 2489139:tid 139983860045376] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.5) HTTPS request received for child 286 (server localhost:40443)
[Wed Feb 09 15:15:43.297155 2022] [mpm_event:trace6] [pid 2489139:tid 139983860045376] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.297165 2022] [ssl:debug] [pid 2489139:tid 139983860045376] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.297662 2022] [ssl:debug] [pid 2489139:tid 139983860045376] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.6) HTTPS request received for child 286 (server localhost:40443)
[Wed Feb 09 15:15:43.297934 2022] [mpm_event:trace6] [pid 2489139:tid 139983860045376] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.300101 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.300135 2022] [mpm_event:trace6] [pid 2489139:tid 139983893993024] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.300215 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.300763 2022] [mpm_event:trace6] [pid 2489139:tid 139984155956800] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.300776 2022] [ssl:debug] [pid 2489139:tid 139984155956800] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.303602 2022] [ssl:debug] [pid 2489139:tid 139984155956800] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.7) HTTPS request received for child 251 (server localhost:40443)
[Wed Feb 09 15:15:43.303849 2022] [mpm_event:trace6] [pid 2489139:tid 139984155956800] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.307984 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.308085 2022] [mpm_event:trace6] [pid 2489139:tid 139983826097728] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.308110 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.312042 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.312056 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.315126 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.8) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.322904 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.322915 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.327402 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.9) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.327725 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.327735 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.328233 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.10) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.328527 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.328536 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.329039 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.11) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.329318 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.329327 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.329843 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.12) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.330101 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.330110 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.330623 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.13) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.330883 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.330892 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.331415 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.14) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.331698 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.331708 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.332202 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.15) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.332430 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.332440 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.332886 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.16) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.333138 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.333145 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.333566 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.17) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.333806 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.333814 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.414529 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.18) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.414818 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.422504 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.422665 2022] [mpm_event:trace6] [pid 2489139:tid 139983885506112] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.422715 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.422829 2022] [mpm_event:trace6] [pid 2489139:tid 139984139171392] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.422839 2022] [ssl:debug] [pid 2489139:tid 139984139171392] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.433646 2022] [ssl:debug] [pid 2489139:tid 139984139171392] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.19) HTTPS request received for child 253 (server localhost:40443)
[Wed Feb 09 15:15:43.434615 2022] [mpm_event:trace6] [pid 2489139:tid 139984139171392] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.445171 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.445528 2022] [mpm_event:trace6] [pid 2489139:tid 139983749711424] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.446274 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.446289 2022] [mpm_event:trace6] [pid 2489139:tid 139983877019200] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.446304 2022] [ssl:debug] [pid 2489139:tid 139983877019200] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.447431 2022] [ssl:debug] [pid 2489139:tid 139983877019200] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.20) HTTPS request received for child 284 (server localhost:40443)
[Wed Feb 09 15:15:43.447699 2022] [mpm_event:trace6] [pid 2489139:tid 139983877019200] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.456496 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.456628 2022] [mpm_event:trace6] [pid 2489139:tid 139983800636992] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.457906 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.458625 2022] [mpm_event:trace6] [pid 2489139:tid 139983749711424] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.458632 2022] [ssl:debug] [pid 2489139:tid 139983749711424] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.470376 2022] [ssl:debug] [pid 2489139:tid 139983749711424] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.21) HTTPS request received for child 299 (server localhost:40443)
[Wed Feb 09 15:15:43.470654 2022] [mpm_event:trace6] [pid 2489139:tid 139983749711424] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.482494 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.483002 2022] [mpm_event:trace6] [pid 2489139:tid 139984063637056] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.483025 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.487098 2022] [mpm_event:trace6] [pid 2489139:tid 139983885506112] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.487109 2022] [ssl:debug] [pid 2489139:tid 139983885506112] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.492019 2022] [ssl:debug] [pid 2489139:tid 139983885506112] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.22) HTTPS request received for child 283 (server localhost:40443)
[Wed Feb 09 15:15:43.492316 2022] [mpm_event:trace6] [pid 2489139:tid 139983885506112] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.497486 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.498416 2022] [mpm_event:trace6] [pid 2489139:tid 139983826097728] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.498440 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.498493 2022] [mpm_event:trace6] [pid 2489139:tid 139984012813888] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.498503 2022] [ssl:debug] [pid 2489139:tid 139984012813888] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.506261 2022] [ssl:debug] [pid 2489139:tid 139984012813888] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.23) HTTPS request received for child 268 (server localhost:40443)
[Wed Feb 09 15:15:43.506571 2022] [mpm_event:trace6] [pid 2489139:tid 139984012813888] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.514663 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.514682 2022] [mpm_event:trace6] [pid 2489139:tid 139983902479936] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.514839 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.514849 2022] [mpm_event:trace6] [pid 2489139:tid 139983970379328] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.514858 2022] [ssl:debug] [pid 2489139:tid 139983970379328] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.519936 2022] [ssl:debug] [pid 2489139:tid 139983970379328] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.24) HTTPS request received for child 273 (server localhost:40443)
[Wed Feb 09 15:15:43.520215 2022] [mpm_event:trace6] [pid 2489139:tid 139983970379328] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.526505 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.526545 2022] [mpm_event:trace6] [pid 2489139:tid 139983843071552] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.526573 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.526778 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.526788 2022] [ssl:debug] [pid 2489139:tid 139984072029760] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.551759 2022] [ssl:debug] [pid 2489139:tid 139984072029760] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.25) HTTPS request received for child 261 (server localhost:40443)
[Wed Feb 09 15:15:43.552081 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.552294 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.552436 2022] [mpm_event:trace6] [pid 2489139:tid 139983817610816] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.552454 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.552468 2022] [mpm_event:trace6] [pid 2489139:tid 139983953401408] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.552476 2022] [ssl:debug] [pid 2489139:tid 139983953401408] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.570799 2022] [ssl:debug] [pid 2489139:tid 139983953401408] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.26) HTTPS request received for child 275 (server localhost:40443)
[Wed Feb 09 15:15:43.571144 2022] [mpm_event:trace6] [pid 2489139:tid 139983953401408] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.579111 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.579219 2022] [mpm_event:trace6] [pid 2489139:tid 139983970379328] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.579317 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.579472 2022] [mpm_event:trace6] [pid 2489139:tid 139984113993280] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.579481 2022] [ssl:debug] [pid 2489139:tid 139984113993280] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.589052 2022] [ssl:debug] [pid 2489139:tid 139984113993280] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.27) HTTPS request received for child 256 (server localhost:40443)
[Wed Feb 09 15:15:43.589352 2022] [mpm_event:trace6] [pid 2489139:tid 139984113993280] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.589544 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.590413 2022] [mpm_event:trace6] [pid 2489139:tid 139984130778688] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.590870 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.591690 2022] [mpm_event:trace6] [pid 2489139:tid 139984038274624] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.591698 2022] [ssl:debug] [pid 2489139:tid 139984038274624] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.592304 2022] [ssl:debug] [pid 2489139:tid 139984038274624] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.28) HTTPS request received for child 265 (server localhost:40443)
[Wed Feb 09 15:15:43.592615 2022] [mpm_event:trace6] [pid 2489139:tid 139984038274624] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.595922 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.596123 2022] [mpm_event:trace6] [pid 2489139:tid 139983775176256] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.596172 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.596293 2022] [mpm_event:trace6] [pid 2489139:tid 139983783663168] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.596306 2022] [ssl:debug] [pid 2489139:tid 139983783663168] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.619229 2022] [ssl:debug] [pid 2489139:tid 139983783663168] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.29) HTTPS request received for child 295 (server localhost:40443)
[Wed Feb 09 15:15:43.619548 2022] [mpm_event:trace6] [pid 2489139:tid 139983783663168] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.652226 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.652251 2022] [mpm_event:trace6] [pid 2489139:tid 139983800636992] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.656658 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.657265 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.657276 2022] [ssl:debug] [pid 2489139:tid 139984072029760] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.686950 2022] [ssl:debug] [pid 2489139:tid 139984072029760] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.30) HTTPS request received for child 261 (server localhost:40443)
[Wed Feb 09 15:15:43.687245 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.689156 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.689368 2022] [mpm_event:trace6] [pid 2489139:tid 139984097207872] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.689394 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.689407 2022] [mpm_event:trace6] [pid 2489139:tid 139984029787712] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.689416 2022] [ssl:debug] [pid 2489139:tid 139984029787712] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.697533 2022] [ssl:debug] [pid 2489139:tid 139984029787712] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.31) HTTPS request received for child 266 (server localhost:40443)
[Wed Feb 09 15:15:43.699012 2022] [mpm_event:trace6] [pid 2489139:tid 139984029787712] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.713245 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.713519 2022] [mpm_event:trace6] [pid 2489139:tid 139983834584640] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.713918 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.714417 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.714427 2022] [ssl:debug] [pid 2489139:tid 139984072029760] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.723985 2022] [ssl:debug] [pid 2489139:tid 139984072029760] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.32) HTTPS request received for child 261 (server localhost:40443)
[Wed Feb 09 15:15:43.724269 2022] [mpm_event:trace6] [pid 2489139:tid 139984072029760] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.728226 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.729027 2022] [mpm_event:trace6] [pid 2489139:tid 139984113993280] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.729074 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.729955 2022] [mpm_event:trace6] [pid 2489139:tid 139983792150080] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.729962 2022] [ssl:debug] [pid 2489139:tid 139983792150080] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.757718 2022] [ssl:debug] [pid 2489139:tid 139983792150080] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.33) HTTPS request received for child 294 (server localhost:40443)
[Wed Feb 09 15:15:43.758025 2022] [mpm_event:trace6] [pid 2489139:tid 139983792150080] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.768671 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.769944 2022] [mpm_event:trace6] [pid 2489139:tid 139983843071552] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.770230 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.770564 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.770576 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.798235 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.34) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:43.798510 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.810318 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.810452 2022] [mpm_event:trace6] [pid 2489139:tid 139983902479936] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.810478 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.810950 2022] [mpm_event:trace6] [pid 2489139:tid 139983860045376] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.810960 2022] [ssl:debug] [pid 2489139:tid 139983860045376] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.815651 2022] [ssl:debug] [pid 2489139:tid 139983860045376] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.35) HTTPS request received for child 286 (server localhost:40443)
[Wed Feb 09 15:15:43.815904 2022] [mpm_event:trace6] [pid 2489139:tid 139983860045376] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.823187 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.823549 2022] [mpm_event:trace6] [pid 2489139:tid 139984113993280] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.827507 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.828823 2022] [mpm_event:trace6] [pid 2489139:tid 139983783663168] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.828835 2022] [ssl:debug] [pid 2489139:tid 139983783663168] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.843004 2022] [ssl:debug] [pid 2489139:tid 139983783663168] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.36) HTTPS request received for child 295 (server localhost:40443)
[Wed Feb 09 15:15:43.855518 2022] [mpm_event:trace6] [pid 2489139:tid 139983783663168] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.872359 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.874933 2022] [mpm_event:trace6] [pid 2489139:tid 139984113993280] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.875405 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.878503 2022] [mpm_event:trace6] [pid 2489139:tid 139984046761536] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.878514 2022] [ssl:debug] [pid 2489139:tid 139984046761536] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.891547 2022] [ssl:debug] [pid 2489139:tid 139984046761536] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.37) HTTPS request received for child 264 (server localhost:40443)
[Wed Feb 09 15:15:43.922111 2022] [mpm_event:trace6] [pid 2489139:tid 139984046761536] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.955135 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.955599 2022] [mpm_event:trace6] [pid 2489139:tid 139983826097728] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.959582 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:43.959875 2022] [mpm_event:trace6] [pid 2489139:tid 139984164349504] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:43.959884 2022] [ssl:debug] [pid 2489139:tid 139984164349504] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:43.970939 2022] [ssl:debug] [pid 2489139:tid 139984164349504] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.38) HTTPS request received for child 250 (server localhost:40443)
[Wed Feb 09 15:15:43.981600 2022] [mpm_event:trace6] [pid 2489139:tid 139984164349504] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.998016 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.998362 2022] [mpm_event:trace6] [pid 2489139:tid 139984097207872] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:43.998513 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.001211 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:44.001221 2022] [ssl:debug] [pid 2489139:tid 139983851558464] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.009639 2022] [ssl:debug] [pid 2489139:tid 139983851558464] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.39) HTTPS request received for child 287 (server localhost:40443)
[Wed Feb 09 15:15:44.009958 2022] [mpm_event:trace6] [pid 2489139:tid 139983851558464] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.027801 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.027947 2022] [mpm_event:trace6] [pid 2489139:tid 139983970379328] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.028338 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.031929 2022] [mpm_event:trace6] [pid 2489139:tid 139983936427584] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:44.031938 2022] [ssl:debug] [pid 2489139:tid 139983936427584] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.048068 2022] [ssl:debug] [pid 2489139:tid 139983936427584] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.40) HTTPS request received for child 277 (server localhost:40443)
[Wed Feb 09 15:15:44.048410 2022] [mpm_event:trace6] [pid 2489139:tid 139983936427584] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.058703 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.059279 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.059972 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.060110 2022] [mpm_event:trace6] [pid 2489139:tid 139984130778688] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:44.060123 2022] [ssl:debug] [pid 2489139:tid 139984130778688] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.091588 2022] [ssl:debug] [pid 2489139:tid 139984130778688] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.41) HTTPS request received for child 254 (server localhost:40443)
[Wed Feb 09 15:15:44.091932 2022] [mpm_event:trace6] [pid 2489139:tid 139984130778688] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.091942 2022] [ssl:debug] [pid 2489139:tid 139984130778688] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.092707 2022] [ssl:debug] [pid 2489139:tid 139984130778688] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.42) HTTPS request received for child 254 (server localhost:40443)
[Wed Feb 09 15:15:44.093046 2022] [mpm_event:trace6] [pid 2489139:tid 139984130778688] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.102127 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.102250 2022] [mpm_event:trace6] [pid 2489139:tid 139984155956800] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.104468 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.105222 2022] [mpm_event:trace6] [pid 2489139:tid 139983809123904] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:44.105233 2022] [ssl:debug] [pid 2489139:tid 139983809123904] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.154827 2022] [ssl:debug] [pid 2489139:tid 139983809123904] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.43) HTTPS request received for child 292 (server localhost:40443)
[Wed Feb 09 15:15:44.155162 2022] [mpm_event:trace6] [pid 2489139:tid 139983809123904] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.157017 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.157027 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(1763): [client 127.0.0.1:47568] pushing pending connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.157246 2022] [mpm_event:trace6] [pid 2489139:tid 139984055244352] event.c(2826): [client 127.0.0.1:47568] pulled pending connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.157253 2022] [mpm_event:trace6] [pid 2489139:tid 139984055244352] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.157556 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.172473 2022] [mpm_event:trace6] [pid 2489139:tid 139984021300800] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:READ_REQUEST_LINE
[Wed Feb 09 15:15:44.172482 2022] [ssl:debug] [pid 2489139:tid 139984021300800] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.173265 2022] [ssl:debug] [pid 2489139:tid 139984021300800] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.44) HTTPS request received for child 267 (server localhost:40443)
[Wed Feb 09 15:15:44.173605 2022] [mpm_event:trace6] [pid 2489139:tid 139984021300800] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.173618 2022] [ssl:debug] [pid 2489139:tid 139984021300800] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.174368 2022] [ssl:debug] [pid 2489139:tid 139984021300800] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.45) HTTPS request received for child 267 (server localhost:40443)
[Wed Feb 09 15:15:44.174722 2022] [mpm_event:trace6] [pid 2489139:tid 139984021300800] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.174732 2022] [ssl:debug] [pid 2489139:tid 139984021300800] mod_ssl.c(715): [client 127.0.0.1:47568] AH10370: SSL handshake completed, continuing
[Wed Feb 09 15:15:44.176231 2022] [ssl:debug] [pid 2489139:tid 139984021300800] ssl_engine_kernel.c(415): [client 127.0.0.1:47568] AH02034: Subsequent (No.46) HTTPS request received for child 267 (server localhost:40443)
[Wed Feb 09 15:15:44.181792 2022] [mpm_event:trace6] [pid 2489139:tid 139984021300800] event.c(1401): [client 127.0.0.1:47568] processed connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.184064 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.184074 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(1763): [client 127.0.0.1:47568] pushing pending connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.184345 2022] [mpm_event:trace6] [pid 2489139:tid 139983766689344] event.c(2826): [client 127.0.0.1:47568] pulled pending connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.184352 2022] [mpm_event:trace6] [pid 2489139:tid 139983766689344] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:WRITE_COMPLETION
[Wed Feb 09 15:15:44.184365 2022] [mpm_event:trace1] [pid 2489139:tid 139983741224512] event.c(2139): All workers are busy or dying, will shutdown 1 keep-alive connections
[Wed Feb 09 15:15:44.184373 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(992): [client 127.0.0.1:47568] deferring close for connection 60e00017e100:60700001b1b0:26:CHECK_REQUEST_LINE_READABLE
[Wed Feb 09 15:15:44.184393 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(2806): [client 127.0.0.1:47568] deferred close for connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184399 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184411 2022] [mpm_event:trace6] [pid 2489139:tid 139984080422464] event.c(2030): [client 127.0.0.1:47568] lingering close for connection 60e00017e100:60700001b1b0:26:LINGER
[Wed Feb 09 15:15:44.184464 2022] [ssl:debug] [pid 2489139:tid 139984080422464] ssl_engine_io.c(1209): [client 127.0.0.1:47568] AH02001: Connection closed to child 260 with standard shutdown (server localhost:40443)
[Wed Feb 09 15:15:44.189968 2022] [mpm_event:trace6] [pid 2489139:tid 139983741224512] event.c(2371): [client 127.0.0.1:47568] polled connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190262 2022] [mpm_event:trace6] [pid 2489139:tid 139983877019200] event.c(1366): [client 127.0.0.1:47568] processing connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190273 2022] [mpm_event:trace6] [pid 2489139:tid 139983877019200] event.c(2030): [client 127.0.0.1:47568] lingering close for connection 60e00017e100:60700001b1b0:26:LINGER_SHORT
[Wed Feb 09 15:15:44.190283 2022] [mpm_event:trace6] [pid 2489139:tid 139983877019200] event.c(1023): [client 127.0.0.1:47568] closing connection 60e00017e100:60700001b1b0:26:LINGER_SHORT at process_lingering_close:2074
diff --git a/support/ab.c b/support/ab.c
index e9580ab587..279f014771 100644
--- a/support/ab.c
+++ b/support/ab.c
@@ -1541,6 +1541,17 @@ static void close_connection(struct connection * c)
doclen = c->bread;
}
else if ((c->bread != doclen) && !nolength) {
+ apr_sockaddr_t *laddr = NULL;
+ apr_socket_addr_get(&laddr, APR_LOCAL, c->aprsock);
+ if (laddr) {
+ char local[256];
+ apr_snprintf(local, sizeof local, "%pI", laddr);
+ fprintf(stderr, "Response for %s incomplete "
+ "(%" APR_SIZE_T_FMT " / %" APR_SIZE_T_FMT ")\n",
+ local, c->bread, doclen);
+ fflush(stderr);
+ }
+ apr_socket_close(c->aprsock);
bad++;
err_length++;
}
