[Libwebsockets] Crash during client pipelining post

Karl Vogel karl.vogel at gmail.com
Thu Mar 12 12:46:16 CET 2020


On Thu, Mar 12, 2020 at 12:34 PM Karl Vogel <karl.vogel at gmail.com> wrote:
>
> On Tue, Mar 10, 2020 at 8:21 PM Andy Green <andy at warmcat.com> wrote:
> >
> > On 3/10/20 8:27 AM, Karl Vogel wrote:
> > > Was trying to use the PIPELINE feature in my POST client, but once I enable
> > > LCCSCF_PIPELINE, the client crashes when the first client gets a
> > > reply. Works fine if I
> > > don't include the pipeline option. Seems to be caused by a NULL
> > > pointer dereference, not
> > > sure if it's something I'm doing wrong or some other bug:
> >
> > I could reproduce it using lws examples
> >
> > ./bin/lws-minimal-http-client-multi --event --post -p --h1
> >
> > the main problem is although the handoff scheme for h1 pipelining is
> > fine as it is, it doesn't take account of event lib -specific magic when
> > doing the handover.  I pushed some patches on master that should solve
> > the thing you hit, but it seems there is more to do under some
> > conditions, I'll look at it tomorrow.  Please try the patches in the
> > meanwhile.
>
> I seem to have a knack for breaking things :-)
>
> New commits seem to have resolved the issue!

Might have been a bit too quick to cheer victory. When I add
LCCSCF_USE_SSL to the connection, the 2nd connection gets a connection
error, though on the server I see the data of both come in.

[2020/03/12 12:42:25:7903] I: Initial logging level 1119
[2020/03/12 12:42:25:7904] I: Libwebsockets version: 4.0.99 v4.0.0-6-g5b9fe018
[2020/03/12 12:42:25:7904] I: IPV6 compiled in and enabled
[2020/03/12 12:42:25:7904] I:  LWS_DEF_HEADER_LEN    : 4096
[2020/03/12 12:42:25:7904] I:  LWS_MAX_SMP           : 1
[2020/03/12 12:42:25:7905] I:  sizeof (*info)        : 680
[2020/03/12 12:42:25:7905] I:  SYSTEM_RANDOM_FILEPATH: '/dev/urandom'
[2020/03/12 12:42:25:7905] I:  HTTP2 support         : available
[2020/03/12 12:42:25:7905] D: _realloc: size 6264: context
[2020/03/12 12:42:25:7905] I: context created
[2020/03/12 12:42:25:7905] I: Using event loop: libevent
[2020/03/12 12:42:25:7905] I: Default ALPN advertisment: h2,http/1.1
[2020/03/12 12:42:25:7905] I:  default timeout (secs): 20
[2020/03/12 12:42:25:7906] I:  Threads: 1 each 1024 fds
[2020/03/12 12:42:25:7906] I:  mem: context:          5456 B (1360 ctx
+ (1 thr x 4096))
[2020/03/12 12:42:25:7906] I:  mem: http hdr size:   (4096 + 976), max
count 1024
[2020/03/12 12:42:25:7906] D: _realloc: size 8192: fds table
[2020/03/12 12:42:25:7906] I:  mem: pollfd map:       8192 B
[2020/03/12 12:42:25:7906] D: _realloc: size 8192: lws_lookup
[2020/03/12 12:42:25:7906] I:  mem: platform fd map:  8192 B
[2020/03/12 12:42:25:7907] I: elops_init_pt_event: loop 0xf202e0
[2020/03/12 12:42:25:7907] D: _realloc: size 808: event pipe wsi
[2020/03/12 12:42:25:7907] D: lws_role_transition: 0xf2cf90: wsistate
0x200, ops pipe
[2020/03/12 12:42:25:7907] D: event pipe fd 9
[2020/03/12 12:42:25:7907] D: __insert_wsi_socket_into_fds: 0xf2cf90:
tsi=0, sock=9, pos-in-fds=0
[2020/03/12 12:42:25:7907] I:  Compiled with OpenSSL support
[2020/03/12 12:42:25:7907] I: Doing SSL library init
[2020/03/12 12:42:25:7918] I:  canonical_hostname = nero.localdomain
[2020/03/12 12:42:25:7918] D: _realloc: size 696: create vhost
[2020/03/12 12:42:25:7919] D: _realloc: size 112: vhost-specific plugin table
[2020/03/12 12:42:25:7919] D: _realloc: size 24: same vh list
[2020/03/12 12:42:25:7919] I: Creating Vhost 'default' (serving
disabled), 1 protocols, IPv6 on
[2020/03/12 12:42:25:7924] D: _realloc: size 72: client ctx tcr
[2020/03/12 12:42:25:7924] I: lws_tls_client_create_vhost_context: vh
default: created new client ctx 0
[2020/03/12 12:42:25:8027] I: created client ssl context for default
>>>>>>>>>>>>>> 0. LWS_CALLBACK_OPENSSL_LOAD_EXTRA_CLIENT_VERIFY_CERT
[2020/03/12 12:42:25:8027] I:  mem: per-conn:          808 bytes +
protocol rx buf
[2020/03/12 12:42:25:8027] I: lws_plat_drop_app_privileges: not changing group
[2020/03/12 12:42:25:8027] I: lws_plat_drop_app_privileges: not changing user
[2020/03/12 12:42:25:8027] I: lws_cancel_service
>>>>>>>>>>>>>> 0. LWS_CALLBACK_EVENT_WAIT_CANCELLED
connecting to host agent-server port 9898
connecting to host agent-server port 9898
[2020/03/12 12:42:25:8030] I: lws_protocol_init
>>>>>>>>>>>>>> 0. LWS_CALLBACK_PROTOCOL_INIT
[2020/03/12 12:42:25:8030] D: _realloc: size 808: client wsi
[2020/03/12 12:42:25:8030] D: lws_vhost_bind_wsi: vh default: wsi
none/none, count_bound_wsi 1
[2020/03/12 12:42:25:8030] D: lws_role_transition: 0x1061020: wsistate
0x10000200, ops h1
[2020/03/12 12:42:25:8030] I: lws_client_connect_via_info: role binding to h1
[2020/03/12 12:42:25:8030] I: lws_client_connect_via_info: protocol
binding to http
[2020/03/12 12:42:25:8031] D: _realloc: size 8: user space
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLIENT_HTTP_BIND_PROTOCOL
[2020/03/12 12:42:25:8031] I: lws_client_connect_via_info: wsi
0x1061020: h1 http entry
[2020/03/12 12:42:25:8031] D: _realloc: size 140: client stash
[2020/03/12 12:42:25:8031] I: lws_header_table_attach: wsi 0x1061020:
ah (nil) (tsi 0, count = 0) in
[2020/03/12 12:42:25:8031] D: _realloc: size 976: ah struct
[2020/03/12 12:42:25:8031] D: _realloc: size 4096: ah data
[2020/03/12 12:42:25:8031] I: _lws_create_ah: created ah 0x1061350
(size 4096): pool length 1
[2020/03/12 12:42:25:8031] I: lws_header_table_attach: did attach wsi
0x1061020: ah 0x1061350: count 1 (on exit)
[2020/03/12 12:42:25:8031] D: __lws_set_timeout: 0x1061020: 10 secs, reason 25
[2020/03/12 12:42:25:8031] D: lws_http_client_connect_via_info2:
0x1061020 (stash 0x101bd80)
[2020/03/12 12:42:25:8031] D: _realloc: size 13: strdup
[2020/03/12 12:42:25:8031] I: lws_client_connect_2_dnsreq: adding
active conn 0x1061020
[2020/03/12 12:42:25:8031] D: lwsi_set_state(0x1061020, 0x10000201)
[2020/03/12 12:42:25:8032] I: lws_client_connect_2_dnsreq: 0x1061020:
lookup agent-server:9898
[2020/03/12 12:42:25:8034] I: lws_getaddrinfo46: getaddrinfo
'agent-server' says 0
[2020/03/12 12:42:25:8035] D: lws_client_connect_3_connect: result
0xf7d0f0 result->ai_next (nil)
[2020/03/12 12:42:25:8035] I: lws_client_connect_3_connect:
agent-server ipv6 2001:db8::1
[2020/03/12 12:42:25:8035] D: lws_client_connect_3_connect: 0x1061020:
WAITING_CONNECT
[2020/03/12 12:42:25:8035] D: lwsi_set_state(0x1061020, 0x10000202)
[2020/03/12 12:42:25:8035] D: __insert_wsi_socket_into_fds: 0x1061020:
tsi=0, sock=10, pos-in-fds=1
[2020/03/12 12:42:25:8035] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 1 -> 1
[2020/03/12 12:42:25:8035] D: __lws_set_timeout: 0x1061020: 20 secs, reason 2
[2020/03/12 12:42:25:8036] D: lws_client_connect_3_connect: connect
says errno: 115
[2020/03/12 12:42:25:8036] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 1 -> 5
[2020/03/12 12:42:25:8036] D: lws_client_connect_via_info: wsi
0x1061020: adoption cb 19 to h1 http
>>>>>>>>>>>>>> 0. LWS_CALLBACK_SERVER_NEW_CLIENT_INSTANTIATED
[2020/03/12 12:42:25:8036] D: _realloc: size 808: client wsi
[2020/03/12 12:42:25:8037] D: lws_vhost_bind_wsi: vh default: wsi
none/none, count_bound_wsi 2
[2020/03/12 12:42:25:8037] D: lws_role_transition: 0x1062c90: wsistate
0x10000200, ops h1
[2020/03/12 12:42:25:8037] I: lws_client_connect_via_info: role binding to h1
[2020/03/12 12:42:25:8037] D: _realloc: size 135: client stash
[2020/03/12 12:42:25:8037] D: _realloc: size 8: user space
[2020/03/12 12:42:25:8037] I: lws_header_table_attach: wsi 0x1062c90:
ah (nil) (tsi 0, count = 1) in
[2020/03/12 12:42:25:8037] D: _realloc: size 976: ah struct
[2020/03/12 12:42:25:8037] D: _realloc: size 4096: ah data
[2020/03/12 12:42:25:8037] I: _lws_create_ah: created ah 0x1063050
(size 4096): pool length 2
[2020/03/12 12:42:25:8037] I: lws_header_table_attach: did attach wsi
0x1062c90: ah 0x1063050: count 2 (on exit)
[2020/03/12 12:42:25:8037] D: __lws_set_timeout: 0x1062c90: 10 secs, reason 25
[2020/03/12 12:42:25:8037] D: lws_http_client_connect_via_info2:
0x1062c90 (stash 0x1062fc0)
[2020/03/12 12:42:25:8037] D: lws_vhost_active_conns: check 0x1062c90
0x1061020 agent-server agent-server 9898 9898
[2020/03/12 12:42:25:8037] N: lws_vhost_active_conns: apply 0x1062c90
to txn queue on 0x1061020 state 0x10000202
[2020/03/12 12:42:25:8037] D: lwsi_set_state(0x1062c90, 0x1000010f)
[2020/03/12 12:42:25:8038] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 5 -> 5
[2020/03/12 12:42:25:8038] I: lws_client_connect_4_established: wsi
0x1062c90: waiting to send hdrs (par state 0x202)
[2020/03/12 12:42:25:8038] D: lws_client_connect_via_info: wsi
0x1062c90: adoption cb 19 to h1 http
>>>>>>>>>>>>>> 1. LWS_CALLBACK_SERVER_NEW_CLIENT_INSTANTIATED
[2020/03/12 12:42:25:8040] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 5 -> 1
[2020/03/12 12:42:25:8040] I: lws_client_connect_3_connect: getsockopt
check: conn OK
[2020/03/12 12:42:25:8040] I: lws_client_connect_3_connect: Connection
started 0xf7d0f0
>>>>>>>>>>>>>> 0. LWS_CALLBACK_WSI_CREATE
[2020/03/12 12:42:25:8040] I: lws_client_connect_4_established: wsi
0x1061020: h1 http client created own conn (raw 0) vh defaultm st
0x202
[2020/03/12 12:42:25:8040] I: h1 client conn using alpn list '(null)'
[2020/03/12 12:42:25:8042] D: lws_ssl_get_error: 0x101e210 -1 -> 2 (errno 11)
[2020/03/12 12:42:25:8042] D: lwsi_set_state(0x1061020, 0x10000204)
[2020/03/12 12:42:25:8049] N: accepting self-signed certificate
(verify_callback)
>>>>>>>>>>>>>> 0. LWS_CALLBACK_OPENSSL_PERFORM_SERVER_CERT_VERIFICAT
[2020/03/12 12:42:25:8052] D: lws_ssl_get_error: 0x101e210 1 -> 0 (errno 0)
[2020/03/12 12:42:25:8052] I: lws_role_call_alpn_negotiated: ''
[2020/03/12 12:42:25:8052] I: client connect OK
[2020/03/12 12:42:25:8052] I: lws_openssl_describe_cipher: wsi
0x1061020: TLS_AES_256_GCM_SHA384, TLS_AES_256_GCM_SHA384, 256 bits,
TLSv1.3
[2020/03/12 12:42:25:8052] D: lws_ssl_client_connect2: SSL_connect says 0
[2020/03/12 12:42:25:8052] D: get_verify says 0
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLIENT_APPEND_HANDSHAKE_HEADER
[2020/03/12 12:42:25:8053] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 1 -> 5
[2020/03/12 12:42:25:8053] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 5 -> 5
[2020/03/12 12:42:25:8053] I: lws_client_socket_service: HANDSHAKE2:
0x1061020: sending headers (wsistate 0x10000204), w sock 10
[2020/03/12 12:42:25:8053] D: body pending
[2020/03/12 12:42:25:8053] D: lwsi_set_state(0x1061020, 0x10000014)
[2020/03/12 12:42:25:8053] D: __lws_set_timeout: 0x1061020: 20 secs, reason 18
[2020/03/12 12:42:25:8053] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 5 -> 1
[2020/03/12 12:42:25:8053] D: lws_handle_POLLOUT_event: 0x1061020: non
mux: wsistate 0x10000014, ops h1
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLIENT_HTTP_WRITEABLE
writing payload 57 bytes
[2020/03/12 12:42:25:8054] I: lws_issue_raw: ssl_capable_write (57) says 57
[2020/03/12 12:42:25:8054] D: lwsi_set_state(0x1061020, 0x1000020b)
[2020/03/12 12:42:25:8054] D: __lws_set_timeout: 0x1061020: 20 secs, reason 4
[2020/03/12 12:42:25:8054] D: 0x1061020: SSL_read says -1
[2020/03/12 12:42:25:8054] D: lws_ssl_get_error: 0x101e210 -1 -> 2 (errno 11)
[2020/03/12 12:42:25:8055] D: 0x1061020: ssl err 2 errno 11
[2020/03/12 12:42:25:8055] D: lws_ssl_capable_read: WANT_READ
[2020/03/12 12:42:25:8055] D: 0x1061020: LWS_SSL_CAPABLE_MORE_SERVICE
[2020/03/12 12:42:25:8055] I: lws_buflist_aware_read: wsi 0x1061020:
lws_client_socket_service: ssl_capable_read -4
[2020/03/12 12:42:25:8055] D: lws_client_socket_service: buflist-aware-read 0 -4
[2020/03/12 12:42:25:8065] D: lws_event_idle_timer_cb: wait
[2020/03/12 12:42:25:8065] D: _lws_state_transition: system: changed 1
'CONTEXT_CREATED' -> 2 'INITIALIZED'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 2
'INITIALIZED' -> 3 'IFACE_COLDPLUG'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 3
'IFACE_COLDPLUG' -> 4 'DHCP'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 4
'DHCP' -> 5 'TIME_VALID'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 5
'TIME_VALID' -> 6 'POLICY_VALID'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 6
'POLICY_VALID' -> 7 'REGISTERED'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 7
'REGISTERED' -> 8 'AUTH1'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 8
'AUTH1' -> 9 'AUTH2'
[2020/03/12 12:42:25:8066] D: _lws_state_transition: system: changed 9
'AUTH2' -> 10 'OPERATIONAL'
[2020/03/12 12:42:25:8066] I: lws_state_transition_steps:
CONTEXT_CREATED -> OPERATIONAL
[2020/03/12 12:42:25:8068] D: 0x1061020: SSL_read says 120
[2020/03/12 12:42:25:8068] I: lws_buflist_aware_read: wsi 0x1061020:
lws_client_socket_service: ssl_capable_read 120
[2020/03/12 12:42:25:8069] D: lws_client_socket_service:
buflist-aware-read 0 120
[2020/03/12 12:42:25:8069] D: lws_client_interpret_server_handshake:
0x1061020: transitioning to h1 client
[2020/03/12 12:42:25:8069] D: lws_role_transition: 0x1061020: wsistate
0x10000119, ops h1
[2020/03/12 12:42:25:8069] I: lws_client_interpret_server_handshake:
incoming content length 0
[2020/03/12 12:42:25:8069] D: lws_ensure_user_space: 0x1061020
protocol pss 8, user_space=0x101a7c0
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLIENT_FILTER_PRE_ESTABLISH
>>>>>>>>>>>>>> 0. LWS_CALLBACK_ESTABLISHED_CLIENT_HTTP
LWS_CALLBACK_ESTABLISHED_CLIENT_HTTP: resp 200
[2020/03/12 12:42:25:8069] I: lws_client_interpret_server_handshake:
client connection up
[2020/03/12 12:42:25:8069] I: lws_http_transaction_completed_client:
wsi: 0x1061020 (http)
>>>>>>>>>>>>>> 0. LWS_CALLBACK_COMPLETED_CLIENT_HTTP
[2020/03/12 12:42:25:8069] D: _lws_change_pollfd: wsi 0x1061020: fd 10
events 1 -> 0
[2020/03/12 12:42:25:8070] D: __insert_wsi_socket_into_fds: 0x1062c90:
tsi=0, sock=10, pos-in-fds=1
[2020/03/12 12:42:25:8070] D: __lws_set_timeout: 0x1061020: 0 secs, reason 1
[2020/03/12 12:42:25:8070] N:
_lws_generic_transaction_completed_active_conn: pipeline queue passed
wsi 0x1061020 on to queued wsi 0x1062c90
[2020/03/12 12:42:25:8070] D: lwsi_set_state(0x1062c90, 0x1000020b)
[2020/03/12 12:42:25:8070] D: __lws_set_timeout: 0x1062c90: 20 secs, reason 4
[2020/03/12 12:42:25:8070] D: lwsi_set_state(0x1062c90, 0x10000013)
[2020/03/12 12:42:25:8070] I: lws_http_transaction_completed_client:
0x1062c90: new queued transaction
[2020/03/12 12:42:25:8070] D: _lws_change_pollfd: wsi 0x1062c90: fd 10
events 1 -> 5
[2020/03/12 12:42:25:8070] D: _lws_change_pollfd: wsi 0x1062c90: fd 10
events 5 -> 1
>>>>>>>>>>>>>> 1. LWS_CALLBACK_CLIENT_APPEND_HANDSHAKE_HEADER
[2020/03/12 12:42:25:8070] D: _lws_change_pollfd: wsi 0x1062c90: fd 10
events 1 -> 5
[2020/03/12 12:42:25:8070] D: _lws_change_pollfd: wsi 0x1062c90: fd 10
events 5 -> 5
[2020/03/12 12:42:25:8070] I: lws_client_socket_service: HANDSHAKE2:
0x1062c90: sending headers (wsistate 0x10000013), w sock 10
[2020/03/12 12:42:25:8071] D: body pending
[2020/03/12 12:42:25:8071] D: lwsi_set_state(0x1062c90, 0x10000014)
[2020/03/12 12:42:25:8071] D: __lws_set_timeout: 0x1062c90: 20 secs, reason 18
[2020/03/12 12:42:25:8071] D: _lws_change_pollfd: wsi 0x1062c90: fd 10
events 5 -> 1
[2020/03/12 12:42:25:8071] D: lws_handle_POLLOUT_event: 0x1062c90: non
mux: wsistate 0x10000014, ops h1
>>>>>>>>>>>>>> 1. LWS_CALLBACK_CLIENT_HTTP_WRITEABLE
writing payload 57 bytes
[2020/03/12 12:42:25:8071] I: lws_issue_raw: ssl_capable_write (57) says 57
[2020/03/12 12:42:25:8071] D: lwsi_set_state(0x1062c90, 0x1000020b)
[2020/03/12 12:42:25:8071] D: __lws_set_timeout: 0x1062c90: 20 secs, reason 4
[2020/03/12 12:42:25:8071] D: 0x1062c90: SSL_read says 0
[2020/03/12 12:42:25:8071] D: lws_ssl_get_error: 0x101e210 0 -> 5 (errno 0)
[2020/03/12 12:42:25:8071] D: 0x1062c90: ssl err 5 errno 0
[2020/03/12 12:42:25:8071] I: lws_buflist_aware_read: wsi 0x1062c90:
lws_client_socket_service: ssl_capable_read -1
[2020/03/12 12:42:25:8071] D: lws_buflist_aware_read: SSL_CAPABLE_ERROR
[2020/03/12 12:42:25:8072] D: lws_client_socket_service:
buflist-aware-read -1 -1
[2020/03/12 12:42:25:8072] I: closing conn at LWS_CONNMODE...SERVER_REPLY
[2020/03/12 12:42:25:8072] I: reason: read failed
>>>>>>>>>>>>>> 1. LWS_CALLBACK_CLIENT_CONNECTION_ERROR
LWS_CALLBACK_CLIENT_CONNECTION_ERROR: read failed
[2020/03/12 12:42:25:8072] I: __lws_close_free_wsi: 0x1062c90: caller: cbail3
[2020/03/12 12:42:25:8072] D: __lws_close_free_wsi: real
just_kill_connection: 0x1062c90 (sockfd 10)
[2020/03/12 12:42:25:8072] D: lwsi_set_state(0x1062c90, 0x10000020)
>>>>>>>>>>>>>> 1. LWS_CALLBACK_CLOSED_CLIENT_HTTP
>>>>>>>>>>>>>> 1. LWS_CALLBACK_WSI_DESTROY
[2020/03/12 12:42:25:8073] I: rops_destroy_role_h1: ah det due to close
[2020/03/12 12:42:25:8073] I: __lws_header_table_detach: wsi
0x1062c90: ah 0x1063050 (tsi=0, count = 2)
[2020/03/12 12:42:25:8073] I: __lws_header_table_detach: nobody usable waiting
[2020/03/12 12:42:25:8073] I: _lws_destroy_ah: freed ah 0x1063050 :
pool length 1
[2020/03/12 12:42:25:8073] I: __lws_header_table_detach: wsi
0x1062c90: ah 0x1063050 (tsi=0, count = 1)
[2020/03/12 12:42:25:8073] D: lws_vhost_unbind_wsi: vh default:
count_bound_wsi 1
[2020/03/12 12:42:25:8073] D: __lws_free_wsi: 0x1062c90, remaining wsi
2, tsi fds count 1
[2020/03/12 12:42:25:8084] D: lws_event_idle_timer_cb: wait
[2020/03/12 12:42:25:8084] I: wsi 0x1061020: TIMEDOUT WAITING on 1
(did hdr 1, ah 0x1061350, wl 0)
[2020/03/12 12:42:25:8084] I: __lws_close_free_wsi: 0x1061020: caller: timeout
[2020/03/12 12:42:25:8084] D: __lws_close_free_wsi: 0x1061020:
DROP_PROTOCOL http
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLIENT_HTTP_DROP_PROTOCOL
[2020/03/12 12:42:25:8084] D: __lws_close_free_wsi: real
just_kill_connection: 0x1061020 (sockfd -1)
[2020/03/12 12:42:25:8084] D: lwsi_set_state(0x1061020, 0x10000020)
>>>>>>>>>>>>>> 0. LWS_CALLBACK_CLOSED_CLIENT_HTTP
>>>>>>>>>>>>>> 0. LWS_CALLBACK_WSI_DESTROY
[2020/03/12 12:42:25:8085] I: rops_destroy_role_h1: ah det due to close
[2020/03/12 12:42:25:8085] I: __lws_header_table_detach: wsi
0x1061020: ah 0x1061350 (tsi=0, count = 1)
[2020/03/12 12:42:25:8085] I: __lws_header_table_detach: nobody usable waiting
[2020/03/12 12:42:25:8085] I: _lws_destroy_ah: freed ah 0x1061350 :
pool length 0
[2020/03/12 12:42:25:8085] I: __lws_header_table_detach: wsi
0x1061020: ah 0x1061350 (tsi=0, count = 0)
[2020/03/12 12:42:25:8085] D: lws_vhost_unbind_wsi: vh default:
count_bound_wsi 0
[2020/03/12 12:42:25:8085] D: __lws_free_wsi: 0x1061020, remaining wsi
1, tsi fds count 1
^C[2020/03/12 12:42:26:5924] I: lws_context_destroy: ctx 0xf2e5b0
[2020/03/12 12:42:26:5924] D: _lws_state_transition: system: changed
10 'OPERATIONAL' -> 11 'POLICY_INVALID'
[2020/03/12 12:42:26:5924] I: lws_destroy_event_pipe
[2020/03/12 12:42:26:5925] I: lws_pt_destroy: pt destroyed
[2020/03/12 12:42:26:5925] I: lws_vhost_destroy1
[2020/03/12 12:42:26:5925] I: lws_context_destroy2: ctx 0xf2e5b0
[2020/03/12 12:42:26:5925] I: elops_destroy_pt_event
>>>>>>>>>>>>>> 0. LWS_CALLBACK_PROTOCOL_DESTROY
[2020/03/12 12:42:26:5925] I: __lws_vhost_destroy2: 0xf52190
[2020/03/12 12:42:26:5936] I:   __lws_vhost_destroy2: Freeing vhost 0xf52190
[2020/03/12 12:42:26:5937] D: 0x452500: post vh listl
[2020/03/12 12:42:26:5937] D: 0x452500: post pdl
[2020/03/12 12:42:26:5937] D: 0x452500: baggage
[2020/03/12 12:42:26:5937] D: elops_destroy_context2_event: in
[2020/03/12 12:42:26:5937] D: elops_destroy_context2_event: out
[2020/03/12 12:42:26:5937] D: 0x452500: post dc2
[2020/03/12 12:42:26:5937] E: lws_context_destroy3
[2020/03/12 12:42:26:5937] D: elops_destroy_context2_event: in
[2020/03/12 12:42:26:5937] D: elops_destroy_context2_event: out
[2020/03/12 12:42:26:5937] I: lws_system_blob_destroy: blob 0xf2e670
[2020/03/12 12:42:26:5937] I: lws_system_blob_destroy: blob 0xf2e688
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e6a0
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e6b8
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e6d0
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e6e8
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e700
[2020/03/12 12:42:26:5938] I: lws_system_blob_destroy: blob 0xf2e718
[2020/03/12 12:42:26:5938] I: lws_context_destroy3: ctx 0xf2e5b0 freed


More information about the Libwebsockets mailing list