> Am 08.03.2021 um 13:49 schrieb Yann Ylavic <ylavic....@gmail.com>:
>
> Hi Stefan,
>
>>
>> I see an crash and log entries:
>>
>> [mpm_event:error] [pid 28031:tid 4595580416] (9)Bad file descriptor:
>> AH00468: error closing socket
>> [core:crit] [pid 28031:tid 4595580416] AH00102: [Mon Mar 08 11:32:48 2021]
>> file event.c, line 565, assertion "0" failed
>>
>> when a client opens a connection and closes it right away, not sending
>> anything. My tls modules sees EOF in the in filter and returns it, setting
>> c->aborted = 1.
>>
>> I am not sure what the failed assertion is about. Is there a double close of
>> the socket? Seems I do handle the connection state in such a case not quite
>> as it is expected.
>
> Hmm, if you breakpoint on apr_socket_close(), do you see it called
> multiple times?
> (I wonder if it could be the ones in core_create_conn() if/when
> apr_socket_addr_get() fails..).
Seems to happen on server shutdown and it looks as if the socket is in
lingering close. I try to get more info what is happening...
...
[Mon Mar 08 13:56:49.158001 2021] [tls:trace3] [pid 78522:tid 123145423650816]
tls_filter.c(648): [client 127.0.0.1:53402] bb_dump(132):
filter_conn_output(FLUSH EOC)
[Mon Mar 08 13:56:49.158004 2021] [tls:trace2] [pid 78522:tid 123145423650816]
tls_filter.c(724): (53)Software caused connection abort: [client
127.0.0.1:53402] tls_filter_conn_output: passed 0 bytes
[Mon Mar 08 13:56:49.162077 2021] [core:trace3] [pid 78521:tid 123145421504512]
request.c(361): [client 127.0.0.1:53403] request authorized without
authentication by access_checker_ex hook: /
[Mon Mar 08 13:56:49.273972 2021] [core:trace4] [pid 78518:tid 4427951616]
mpm_common.c(558): mpm child 78527 (gen 0/slot 3) started
[Mon Mar 08 13:56:49.274139 2021] [mpm_event:error] [pid 78522:tid 4427951616]
(9)Bad file descriptor: AH00468: error closing socket
[Mon Mar 08 13:56:49.274616 2021] [core:crit] [pid 78522:tid 4427951616]
AH00102: [Mon Mar 08 12:56:49 2021] file event.c, line 565, assertion "0" failed
[Mon Mar 08 13:56:49.275632 2021] [mpm_event:debug] [pid 78527:tid
123145420967936] event.c(2611): AH02471: start_threads: Using kqueue (wakeable)
[Mon Mar 08 13:56:49.291162 2021] [core:trace4] [pid 78518:tid 4427951616]
mpm_common.c(558): mpm child 78520 (gen 0/slot 0) exited
[Mon Mar 08 13:56:49.291199 2021] [core:trace4] [pid 78518:tid 4427951616]
mpm_common.c(558): mpm child 78521 (gen 0/slot 1) exited
[Mon Mar 08 13:56:49.291213 2021] [core:notice] [pid 78518:tid 4427951616]
AH00052: child pid 78522 exit signal Abort trap (6)
[Mon Mar 08 13:56:49.291217 2021] [core:trace4] [pid 78518:tid 4427951616]
mpm_common.c(558): mpm child 78522 (gen 0/slot 2) exited
[Mon Mar 08 13:56:49.291222 2021] [core:trace4] [pid 78518:tid 4427951616]
mpm_common.c(558): mpm child 78527 (gen 0/slot 3) exited
[Mon Mar 08 13:56:49.291342 2021] [core:info] [pid 78518:tid 4427951616]
AH00096: removed PID file
/Users/sei/projects/abetterinternet/mod_tls/test/gen/apache/logs/httpd.pid
(pid=78518)
[Mon Mar 08 13:56:49.291359 2021] [mpm_event:notice] [pid 78518:tid 4427951616]
AH00491: caught SIGTERM, shutting down
[Mon Mar 08 13:56:49.301655 2021] [core:trace4] [pid 78518] mpm_common.c(454):
end of generation 0
