[Libreoffice-bugs] [Bug 129306] New: SSL cnx early closure on EAGAIN

[bugzilla-daemon at bugs.documentfoundation.org]

https://bugs.documentfoundation.org/show_bug.cgi?id=129306

            Bug ID: 129306
           Summary: SSL cnx early closure on EAGAIN
           Product: LibreOffice Online
           Version: unspecified
          Hardware: All
                OS: All
            Status: UNCONFIRMED
          Severity: critical
          Priority: medium
         Component: LibreOffice
          Assignee: libreoffice-bugs at lists.freedesktop.org
          Reporter: michael.meeks at collabora.com

Just saw this frightening thing under load:

wsd-29423-29659 2019-12-10 09:50:46.582530 [ websrv_poll ] TRC  #36 Socket
dtor.| ./net/Socket.hpp:116
wsd-29423-29659 2019-12-10 09:50:46.582594 [ websrv_poll ] TRC  #35: Incoming
data buffer 405 bytes, closeSocket? false| ./net/Socket.hpp:1046
wsd-29423-29659 2019-12-10 09:50:46.582606 [ websrv_poll ] TRC  #35 handling
incoming 405 bytes.| net/Socket.cpp:641
wsd-29423-29659 2019-12-10 09:50:46.582660 [ websrv_poll ] TRC  Fileserver
request: /loleaflet/3ecc73d37/node_modules/jquery-ui/jquery-ui.js|
wsd/FileServer.cpp:273
wsd-29423-29659 2019-12-10 09:50:46.582745 [ websrv_poll ] TRC  #35: Sending
compressed : file [/loleaflet/dist/node_modules/jquery-ui/jquery-ui.js]:
HTTP/1.0 200 OK
User-Agent: LOOLWSD HTTP Agent master..
Date: Tue, 10 Dec 2019 09:50:46
Content-Encoding: gzip
Content-Type: application/javascript
X-Content-Type-Options: nosniff

| wsd/FileServer.cpp:422
wsd-29423-29659 2019-12-10 09:50:46.582771 [ websrv_poll ] TRC  #35: Wrote
outgoing data 188 bytes of 188 bytes buffered.| ./net/Socket.hpp:1114
wsd-29423-29659 2019-12-10 09:50:46.582829 [ websrv_poll ] TRC  #35: Wrote
outgoing data 16384 bytes of 107389 bytes buffered.| ./net/Socket.hpp:1114
wsd-29423-29659 2019-12-10 09:50:46.582870 [ websrv_poll ] TRC  Socket #35 SSL
error: WANT_WRITE (3).| ./net/SslSocket.hpp:215
wsd-29423-29659 2019-12-10 09:50:46.582898 [ websrv_poll ] TRC  #35: Async
shutdown requested.| ./net/Socket.hpp:857
wsd-29423-29659 2019-12-10 09:50:46.582912 [ websrv_poll ] TRC  Poll start|
./net/Socket.hpp:511
wsd-29423-29659 2019-12-10 09:50:46.585441 [ websrv_poll ] TRC  #35: Incoming
data buffer 0 bytes, closeSocket? false| ./net/Socket.hpp:1046
wsd-29423-29659 2019-12-10 09:50:46.585457 [ websrv_poll ] TRC  #35: Wrote
outgoing data 16384 bytes of 91005 bytes buffered.| ./net/Socket.hpp:1114
wsd-29423-29659 2019-12-10 09:50:46.587312 [ websrv_poll ] TRC  #35: Incoming
data buffer 0 bytes, closeSocket? false| ./net/Socket.hpp:1046
wsd-29423-29659 2019-12-10 09:50:46.587343 [ websrv_poll ] TRC  #35: Wrote
outgoing data 16384 bytes of 74621 bytes buffered.| ./net/Socket.hpp:1114
wsd-29423-29659 2019-12-10 09:50:46.587369 [ websrv_poll ] TRC  Socket #35 SSL
error: WANT_WRITE (3).| ./net/SslSocket.hpp:215
wsd-29423-29659 2019-12-10 09:50:46.587375 [ websrv_poll ] TRC  Socket #35 SSL
error: WANT_WRITE (3).| ./net/SslSocket.hpp:215
wsd-29423-29659 2019-12-10 09:50:46.587379 [ websrv_poll ] TRC  Poll start|
./net/Socket.hpp:511
wsd-29423-29659 2019-12-10 09:50:46.593357 [ websrv_poll ] TRC  #35: Incoming
data buffer 0 bytes, closeSocket? false| ./net/Socket.hpp:1046
wsd-29423-29659 2019-12-10 09:50:46.593376 [ websrv_poll ] TRC  #35: Wrote
outgoing data 16384 bytes of 58237 bytes buffered.| ./net/Socket.hpp:1114
wsd-29423-29659 2019-12-10 09:50:46.593428 [ websrv_poll ] TRC  #35: Wrote
outgoing data 16384 bytes of 41853 bytes buffered.| ./net/Socket.hpp:1114
...
wsd-29423-29659 2019-12-10 09:50:46.597629 [ websrv_poll ] TRC  Socket #35 SSL
error: SSL (1).| ./net/SslSocket.hpp:244
wsd-29423-29659 2019-12-10 09:50:46.597643 [ websrv_poll ] ERR  Socket #35 SSL
BIO error: error:140E0197:SSL routines:SSL_shutdown:shutdown while in init
(EAGAIN: Resource temporarily unavailable)| ./net/SslSocket.hpp:281
wsd-29423-29659 2019-12-10 09:50:46.597693 [ websrv_poll ] ERR  Error while
handling poll for socket #35 in websrv_poll: error:140E0197:SSL
routines:SSL_shutdown:shutdown while in init| ./net/Socket.hpp:594
wsd-29423-29659 2019-12-10 09:50:46.597703 [ websrv_poll ] DBG  Removing socket
#35 (of 2) from websrv_poll| ./net/Socket.hpp:601
wsd-29423-29659 2019-12-10 09:50:46.597711 [ websrv_poll ] DBG  SslStreamSocket
dtor #35| ./net/SslSocket.hpp:61
wsd-29423-29659 2019-12-10 09:50:46.597735 [ websrv_poll ] DBG  StreamSocket
dtor #35| ./net/Socket.hpp:834
wsd-29423-29659 2019-12-10 09:50:46.597749 [ websrv_poll ] TRC  #35 Socket
dtor.| ./net/Socket.hpp:116

working on a patch.

-- 
You are receiving this mail because:
You are the assignee for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/libreoffice-bugs/attachments/20191210/3a7c7370/attachment.htm>