[Libwebsockets] HS: UPGRADE malformed

Paolo Denti paolo.denti at gmail.com
Fri May 3 23:18:35 CEST 2019


Andy
this is the not working full header parsing (I just changed host name in
the log)

USER: LWS minimal ws client rx [-d <logs>] [--h2] [-t (test)]
INFO: Initial logging level 1151
INFO: Libwebsockets version: 3.1.99 v3.1.0-150-gdd759d10
INFO: IPV6 not compiled in
INFO:  LWS_DEF_HEADER_LEN    : 4096
INFO:  LWS_MAX_PROTOCOLS     : 5
INFO:  LWS_MAX_SMP           : 1
INFO:  sizeof (*info)        : 640
INFO:  SYSTEM_RANDOM_FILEPATH: '/dev/urandom'
INFO:  HTTP2 support         : available
DEBUG: _realloc: size 880: context
INFO: context created
INFO: Using event loop: poll
INFO: Default ALPN advertisment: h2,http/1.1
INFO:  default timeout (secs): 20
DEBUG: _realloc: size 4096: pt_serv_buf
INFO:  Threads: 1 each 1024 fds
INFO:  mem: context:          4976 B (880 ctx + (1 thr x 4096))
INFO:  mem: http hdr rsvd:   5193728 B (1 thr x (4096 + 976) x 1024))
DEBUG: _realloc: size 8192: fds table
INFO:  mem: pollfd map:       8192
DEBUG: _realloc: size 8192: lws_lookup
INFO:  mem: platform fd map:  8192 bytes
DEBUG: _realloc: size 592: event pipe wsi
DEBUG: lws_role_transition: 0x559119c138d0: wsistate 0x200, ops pipe
DEBUG: event pipe fd 4
DEBUG: __insert_wsi_socket_into_fds: 0x559119c138d0: tsi=0, sock=4,
pos-in-fds=0
INFO:  Compiled with OpenSSL support
INFO: Doing SSL library init
DEBUG: _realloc: size 720: create vhost
DEBUG: _realloc: size 168: vhost-specific plugin table
DEBUG: _realloc: size 32: same vh list
INFO: Creating Vhost 'default' (serving disabled), 2 protocols, IPv6 off
DEBUG: _realloc: size 64: client ctx tcr
INFO: lws_tls_client_create_vhost_context: vh default: created new client
ctx 0
NOTICE: created client ssl context for default
INFO:  LWS_MAX_EXTENSIONS_ACTIVE: 1
INFO:  mem: per-conn:          592 bytes + protocol rx buf
INFO:  canonical_hostname = paolo-mint
INFO: lws_plat_drop_app_privileges: not changing group
INFO: lws_plat_drop_app_privileges: not changing user
INFO: lws_cancel_service
INFO: lws_protocol_init
DEBUG: _realloc: size 16: protocol_vh_privs
DEBUG: _realloc: size 24: vh priv
DEBUG: _realloc: size 40: timed_vh
DEBUG: _realloc: size 592: client wsi
INFO: lws_vhost_bind_wsi: vh default: count_bound_wsi 1
DEBUG: _realloc: size 232: client ws struct
DEBUG: lws_role_transition: 0x559119c38a30: wsistate 0x10000200, ops h1
INFO: lws_client_connect_via_info: role binding to h1
INFO: lws_client_connect_via_info: protocol binding to
dumb-increment-protocol
DEBUG: lws_ensure_user_space: 0x559119c38a30 protocol pss 0,
user_space=(nil)
DEBUG: _realloc: size 72: client stash
DEBUG: _realloc: size 27: strdup
DEBUG: _realloc: size 11: strdup
DEBUG: _realloc: size 27: strdup
DEBUG: _realloc: size 27: strdup
DEBUG: _realloc: size 24: strdup
DEBUG: _realloc: size 9: strdup
INFO: lws_header_table_attach: wsi 0x559119c38a30: ah (nil) (tsi 0, count =
0) in
DEBUG: _realloc: size 976: ah struct
DEBUG: _realloc: size 4096: ah data
INFO: _lws_create_ah: created ah 0x559119c38ec0 (size 4096): pool length 1
INFO: lws_header_table_attach: did attach wsi 0x559119c38a30: ah
0x559119c38ec0: count 1 (on exit)
DEBUG: __lws_set_timeout: 0x559119c38a30: 10 secs (reason 25)
DEBUG: lws_http_client_connect_via_info2: 0x559119c38a30 (stash
0x559119c38d80)
DEBUG: _realloc: size 27: strdup
INFO: lws_client_connect_2: 0x559119c38a30: address <myservername>:443
DEBUG: lwsi_set_state(0x559119c38a30, 0x10000201)
DEBUG: __insert_wsi_socket_into_fds: 0x559119c38a30: tsi=0, sock=6,
pos-in-fds=1
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1 -> 1
DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 2)
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1 -> 5
DEBUG: lws_client_connect_via_info: wsi 0x559119c38a30: cb 19 to h1
dumb-increment-protocol
NOTICE: lws_broadcast: 0 dumb-increment-protocol
NOTICE: lws_broadcast: 1 lws-abs-cli-raw-skt
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 5 -> 1
INFO: lws_client_connect_2: 0x559119c38a30: address <myservername>:443
INFO: lws_client_connect_3: wsi 0x559119c38a30: h1 dumb-increment-protocol
client created own conn (raw 0)
DEBUG: lwsi_set_state(0x559119c38a30, 0x10000011)
DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 8)
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1 -> 1
INFO: client conn using alpn list 'http/1.1'
DEBUG: lws_ssl_get_error: 0x559119c3c620 -1 -> 2 (errno 11)
DEBUG: lwsi_set_state(0x559119c38a30, 0x10000203)
DEBUG: lws_ssl_get_error: 0x559119c3c620 -1 -> 2 (errno 11)
DEBUG: lws_ssl_client_connect2: SSL_connect says -2
DEBUG: lwsi_set_state(0x559119c38a30, 0x10000203)
DEBUG: _realloc: size 592: cbwsi
DEBUG: _realloc: size 40: cbtmr
DEBUG: lws_service_periodic_checks: timed cb: vh default, protocol
lws-abs-cli-raw-skt, reason 1000
DEBUG: _realloc: size 40: timed_vh
INFO: lws_role_call_alpn_negotiated: ''
INFO: client connect OK
INFO: lws_openssl_describe_cipher: wsi 0x559119c38a30:
ECDHE-RSA-AES256-GCM-SHA384, ECDHE-RSA-AES256-GCM-SHA384, 256 bits, TLSv1.2
DEBUG: lws_ssl_client_connect2: SSL_connect says 0
DEBUG: get_verify says 0
DEBUG: lwsi_set_state(0x559119c38a30, 0x10000012)
DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 11)
INFO: lws_client_socket_service: HANDSHAKE2: 0x559119c38a30: sending
headers on 0x559119c38a30 (wsistate 0x10000012 0x10000012), w sock 6, wsi
sock 6
DEBUG: lwsi_set_state(0x559119c38a30, 0x1000020a)
DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 4)
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1 -> 5
DEBUG: lwsi_set_state(0x559119c38a30, 0x1000020a)
DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 4)
DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 5 -> 1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'H' 0x48 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'T' 0x54 (role=0x10000000) wsi->lextable_pos=102
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'T' 0x54 (role=0x10000000) wsi->lextable_pos=296
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'P' 0x50 (role=0x10000000) wsi->lextable_pos=297
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '/' 0x2F (role=0x10000000) wsi->lextable_pos=298
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '1' 0x31 (role=0x10000000) wsi->lextable_pos=305
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '.' 0x2E (role=0x10000000) wsi->lextable_pos=306
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '1' 0x31 (role=0x10000000) wsi->lextable_pos=307
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ' ' 0x20 (role=0x10000000) wsi->lextable_pos=314
PARSER: known hdr 15
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(15) '1'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(15) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(15) '1'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(15) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(15) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'X' 0x58 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000)
wsi->lextable_pos=1097
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'A' 0x41 (role=0x10000000)
wsi->lextable_pos=1098
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000)
wsi->lextable_pos=1164
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'l' 0x6C (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'x' 0x78 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=-1
DEBUG: lws_parse: unk header 22 'x-application-context:'
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'U' 0x55 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=153
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'g' 0x67 (role=0x10000000) wsi->lextable_pos=163
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=164
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=165
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'd' 0x64 (role=0x10000000) wsi->lextable_pos=166
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=167
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=168
PARSER: known hdr 5
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'w'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'b'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 's'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'k'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(5) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=117
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=124
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=131
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=138
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=139
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=140
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=141
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=148
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=149
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=150
PARSER: known hdr 4
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'u'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'p'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'g'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'r'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'd'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=178
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=185
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=195
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'W' 0x57 (role=0x10000000) wsi->lextable_pos=196
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=197
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'b' 0x62 (role=0x10000000) wsi->lextable_pos=198
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=199
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=200
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=201
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'k' 0x6B (role=0x10000000) wsi->lextable_pos=202
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=203
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=204
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=205
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'A' 0x41 (role=0x10000000) wsi->lextable_pos=206
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=281
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=282
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=283
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=284
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=285
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=286
PARSER: known hdr 13
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'I'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '9'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'A'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '3'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'A'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'A'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'V'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '6'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'h'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '4'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '5'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'l'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '8'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'k'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '/'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'I'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'K'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'D'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'k'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'b'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) 'y'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '='
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(13) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'X' 0x58 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000)
wsi->lextable_pos=1097
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000)
wsi->lextable_pos=1098
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'T' 0x54 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'y' 0x79 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'O' 0x4F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 's' 0x73 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=-1
DEBUG: lws_parse: unk header 23 'x-content-type-options:'
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'X' 0x58 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000)
wsi->lextable_pos=1097
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'X' 0x58 (role=0x10000000)
wsi->lextable_pos=1098
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'P' 0x50 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=-1
DEBUG: lws_parse: unk header 17 'x-xss-protection:'
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=117
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=490
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'h' 0x68 (role=0x10000000) wsi->lextable_pos=491
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=492
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=493
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000) wsi->lextable_pos=494
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=495
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=496
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=497
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=498
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=499
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'l' 0x6C (role=0x10000000) wsi->lextable_pos=500
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=501
PARSER: known hdr 24
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'n'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'h'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ','
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'n'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 's'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'r'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ','
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'm'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'x'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'g'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '='
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ','
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'm'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'u'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 's'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'r'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'v'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'l'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'i'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'd'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(24) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'P' 0x50 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=69
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=477
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'g' 0x67 (role=0x10000000) wsi->lextable_pos=484
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'm' 0x6D (role=0x10000000) wsi->lextable_pos=485
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=486
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=487
PARSER: known hdr 23
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'n'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'h'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(23) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'E' 0x45 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'x' 0x78 (role=0x10000000) wsi->lextable_pos=783
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=795
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=796
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=808
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=809
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 's' 0x73 (role=0x10000000) wsi->lextable_pos=810
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=811
PARSER: known hdr 52
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(52) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(52) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(52) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=178
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=977
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=978
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=979
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=980
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=981
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'T' 0x54 (role=0x10000000) wsi->lextable_pos=982
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=983
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=984
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=985
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 's' 0x73 (role=0x10000000) wsi->lextable_pos=986
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=987
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=988
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=989
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=990
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=991
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'S' 0x53 (role=0x10000000) wsi->lextable_pos=992
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=993
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=994
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'u' 0x75 (role=0x10000000) wsi->lextable_pos=995
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000) wsi->lextable_pos=996
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=997
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=998
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'y' 0x79 (role=0x10000000) wsi->lextable_pos=999
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000)
wsi->lextable_pos=1000
PARSER: known hdr 67
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'm'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'x'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '-'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'g'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '='
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '3'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '1'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '5'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '3'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '6'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) ';'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'i'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'n'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'l'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'u'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'd'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'S'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'u'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'b'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'D'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'm'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'i'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 'n'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) 's'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(67) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'X' 0x58 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000)
wsi->lextable_pos=1097
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'F' 0x46 (role=0x10000000)
wsi->lextable_pos=1098
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'r' 0x72 (role=0x10000000)
wsi->lextable_pos=1120
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'm' 0x6D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '-' 0x2D (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'O' 0x4F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'p' 0x70 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 's' 0x73 (role=0x10000000) wsi->lextable_pos=-1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=-1
DEBUG: lws_parse: unk header 16 'x-frame-options:'
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'D' 0x44 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'a' 0x61 (role=0x10000000) wsi->lextable_pos=567
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=574
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=575
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=576
PARSER: known hdr 29
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'S'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'a'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 't'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ','
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '2'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '7'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'A'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'p'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'r'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '2'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '1'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '9'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '2'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ':'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '5'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '3'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ':'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '1'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '0'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'G'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'M'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) 'T'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(29) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=117
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=124
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=131
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000) wsi->lextable_pos=138
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000) wsi->lextable_pos=139
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000) wsi->lextable_pos=140
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000) wsi->lextable_pos=141
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000) wsi->lextable_pos=148
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000) wsi->lextable_pos=149
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000) wsi->lextable_pos=150
PARSER: known hdr 4
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) ' '
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'c'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'l'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'o'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 's'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) 'e'
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOK_(4) '
'
PARSER: *
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_SKIPPING_SAW_CR '

DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '
' 0x0D (role=0x10000000) wsi->lextable_pos=0
DEBUG: 0x559119c38a30: SSL_read says 1
PARSER: WSI_TOKEN_NAME_PART '
i->lextable_pos=238
PARSER: known hdr 8
PARSER: v00 hdrs done
ERR: CLIENT_CONNECTION_ERROR: HS: UPGRADE malformed
INFO: closing connection (prot dumb-increment-protocol) due to bail2
connection error: HS: UPGRADE malformed
INFO: __lws_close_free_wsi: 0x559119c38a30: caller: c hs interp
DEBUG: __lws_close_free_wsi: real just_kill_connection: 0x559119c38a30
(sockfd 6)
DEBUG: lwsi_set_state(0x559119c38a30, 0x1000001e)
INFO: __lws_header_table_detach: wsi 0x559119c38a30: ah 0x559119c38ec0
(tsi=0, count = 1)
INFO: __lws_header_table_detach: nobody usable waiting
INFO: _lws_destroy_ah: freed ah 0x559119c38ec0 : pool length 0
INFO: __lws_header_table_detach: wsi 0x559119c38a30: ah 0x559119c38ec0
(tsi=0, count = 0)
INFO: rops_destroy_role_h1: ah det due to close
INFO: lws_vhost_unbind_wsi: vh default: count_bound_wsi 0
DEBUG: __lws_free_wsi: 0x559119c38a30, remaining wsi 1
INFO: lws_context_destroy: ctx 0x559119c13550
INFO: lws_destroy_event_pipe
INFO: lws_vhost_destroy1
INFO: lws_context_destroy2: ctx 0x559119c13550
INFO: __lws_vhost_destroy2: 0x559119c25a70
INFO:   __lws_vhost_destroy2: Freeing vhost 0x559119c25a70
DEBUG: 0x7f4ec8de0e70: post vh listl
DEBUG: 0x7f4ec8de0e70: post pdl
DEBUG: 0x7f4ec8de0e70: baggage
DEBUG: 0x7f4ec8de0e70: post dc2
DEBUG: lws_context_destroy3
INFO: lws_context_destroy3: ctx 0x559119c13550 freed
USER: Completed Failed


On Mon, Apr 29, 2019 at 1:31 PM Andy Green <andy at warmcat.com> wrote:

>
>
> On 29/04/2019 03:54, Paolo Denti wrote:
> > Andy, In both logs I get back a correct
> > Connection Upgrade: websocket
>
> If I take that literally, it's not right... RFC6455 (1.2) says the
> server should reply with
>
>          Upgrade: websocket
>          Connection: Upgrade
>
> > And at the end, on the bad log, I get a connection close
>
> Hum... the "connection" header existed before ws, it's job was to manage
> connection keepalive or not for http/1.0.  With http/1.1, the default
> (ie, no connection header) became keepalive and supporting keepalive is
> mandatory.
>
> It can normally contain "close" or "keep-alive"... in ws they added a
> new token "upgrade" that should appear when the server is agreeing to
> the ws upgrade.
>
> In fact it's not told in RFC6455 it's illegal for connection: to mix the
> old and new tokens, the old tokens still tell the two sides what to do
> about keep-alive if the ws upgrade failed and it remains in http mode.
>
> > However what could be meaningful is that
> > on the Ok Log I get
> > Connection*:8080* Upgrade: websocket
>
> That's not a legal ws upgrade coming from your server if that's
> literally what it replies with.
>
> > while on the bad log I get
> > Connection Upgrade: websocket
> >
> > Where 8080 is the web server port, proxied by a haproxy on port 443,
> > with SSL termination on it
>
> That sounds like a pretty confused response from the server if that's
> literally what it sends.
>
> > I do not know if that could mean anything but that is the only
> > meaningful difference I see apart from the connection close right after
> > the XFrame options section
> >
> > This is the portion if the log where the connection is closed
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'C' 0x43 (role=0x10000000)
> wsi->lextable_pos=0
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000)
> wsi->lextable_pos=117
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000)
> wsi->lextable_pos=124
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000)
> wsi->lextable_pos=131
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'e' 0x65 (role=0x10000000)
> wsi->lextable_pos=138
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'c' 0x63 (role=0x10000000)
> wsi->lextable_pos=139
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 't' 0x74 (role=0x10000000)
> wsi->lextable_pos=140
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'i' 0x69 (role=0x10000000)
> wsi->lextable_pos=141
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'o' 0x6F (role=0x10000000)
> wsi->lextable_pos=148
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART 'n' 0x6E (role=0x10000000)
> wsi->lextable_pos=149
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOKEN_NAME_PART ':' 0x3A (role=0x10000000)
> wsi->lextable_pos=150
> > PARSER: known hdr 4
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) ' '
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) 'c'
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) 'l'
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) 'o'
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) 's'
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) 'e'
> > DEBUG: 0x559119c38a30: SSL_read says 1
> > PARSER: WSI_TOK_(4) '
>
> It might be worth pasting the whole of this header parsing so we can see
> the stuff mentioned above.
>
> -Andy
>
> >
> >
> > On Fri, Apr 26, 2019 at 11:24 PM Andy Green <andy at warmcat.com
> > <mailto:andy at warmcat.com>> wrote:
> >
> >
> >
> >     On 27/04/2019 04:14, Paolo Denti wrote:
> >      > I reproduced the problem using ona of the providex examples just
> >      > changing the host (default was 'libwebsockets.org
> >     <http://libwebsockets.org>
> >      > <http://libwebsockets.org>') and the url (defauilt was '/')
> >      >
> >      > I rebuilt both the two versions with -DCMAKE_BUILD_TYPE=DEBUG and
> >      > enabling extensions and ZLIB
> >      > i ran the example with "-d 1151"
> >      >
> >      > i compared the two outputs, cleaned from looked to me as
> >     neglectable and
> >      > below is the diff between the two logs.
> >      > I still have the original full logs and I can add additional
> >     information
> >      >
> >      > diff left < (3.0.0) is working
> >      > diff right > (3.1.99) is not working
> >
> >     Testing master is good, but it's always possible I can break master
> >     since I am working on it.
> >
> >      > I hope it helps
> >
> >     Diffing it isn't a bad idea if one works and the other doesn't, but
> in
> >     this case the question boils down to what did it actually send on the
> >     "connection" header... the code related to this in lws causes the
> >     "UPGRADE malformed" result, and it's different between v3 (it's best
> to
> >     use v3.whatever-stable, it has fixes since the original release[1])
> and
> >     master.
> >
> >     v3.0:
> >
> >               p = lws_hdr_simple_ptr(wsi, WSI_TOKEN_CONNECTION);
> >               if (!p) {
> >                       lwsl_info("no Connection hdr\n");
> >                       *cce = "HS: CONNECTION missing";
> >                       goto bail3;
> >               }
> >               strtolower(p);
> >               if (strcmp(p, "upgrade")) {
> >                       lwsl_warn("lws_client_int_s_hs: bad header %s\n",
> p);
> >                       *cce = "HS: UPGRADE malformed";
> >                       goto bail3;
> >               }
> >
> >     v3.1+
> >
> >              /* connection: must have "upgrade" */
> >
> >              lws_tokenize_init(&ts, buf, LWS_TOKENIZE_F_COMMA_SEP_LIST |
> >                                          LWS_TOKENIZE_F_MINUS_NONTERM);
> >              ts.len = lws_hdr_copy(wsi, buf, sizeof(buf) - 1,
> >     WSI_TOKEN_CONNECTION);
> >              if (ts.len <= 0) /* won't fit, or absent */
> >                      goto bad_conn_format;
> >
> >              do {
> >                      e = lws_tokenize(&ts);
> >                      switch (e) {
> >                      case LWS_TOKZE_TOKEN:
> >                              if (!strcasecmp(ts.token, "upgrade"))
> >                                      e = LWS_TOKZE_ENDED;
> >                              break;
> >
> >                      case LWS_TOKZE_DELIMITER:
> >                              break;
> >
> >                      default: /* includes ENDED */
> >     bad_conn_format:
> >                              *cce = "HS: UPGRADE malformed";
> >                              goto bail3;
> >                      }
> >              } while (e > 0);
> >
> >     If you look in either log, it should verbosely spell out character by
> >     character what was returned by the server for "Connection" header...
> it
> >     looks like it should be "Connection: upgrade" to pass on v3.0.  But
> >     that
> >     should pass OK on the tokenizer as well.
> >
> >     Can you paste that part please from either log.
> >
> >      > < INFO: Libwebsockets version: 3.0.0 unknown-build-hash
> >      > ---
> >      >  > INFO: Libwebsockets version: 3.1.99 v3.1.0-150-gdd759d10
> >      >
> >      > < INFO:  HTTP2 support         : not configured
> >      > < DEBUG: _realloc: size 792: context
> >      > ---
> >      >  > INFO:  HTTP2 support         : available
> >
> >     Hmmm on master only, h2 is enabled.
> >
> >      >  > DEBUG: _realloc: size 880: context
> >      >  > INFO: context created
> >      >
> >      > < INFO: Default ALPN advertisment: http/1.1
> >      > ---
> >      >  > INFO: Default ALPN advertisment: h2,http/1.1
> >
> >     I guess the server doesn't support h2, or the logging diff would be
> >     much
> >     larger.
> >
> >      > < DEBUG: _realloc: size 640: create vhost
> >      > < DEBUG: _realloc: size 112: vhost-specific plugin table
> >      > < DEBUG: _realloc: size 8: same vh list
> >      > < NOTICE: Creating Vhost 'default' (serving disabled), 1
> >     protocols, IPv6 off
> >      > ---
> >      >  > DEBUG: _realloc: size 720: create vhost
> >      >  > DEBUG: _realloc: size 168: vhost-specific plugin table
> >      >  > DEBUG: _realloc: size 32: same vh list
> >      >  > INFO: Creating Vhost 'default' (serving disabled), 2
> >     protocols, IPv6 off
> >      >  > DEBUG: _realloc: size 64: client ctx tcr
> >      >  > INFO: lws_tls_client_create_vhost_context: vh default: created
> >     new
> >      > client ctx 0
> >      >
> >      > < INFO: _lws_create_ah: created ah 0x5581dbbe78b0 (size 4096):
> >     pool length 1
> >      > < INFO: lws_header_table_attach: did attach wsi 0x5581dbbe74c0: ah
> >      > 0x5581dbbe78b0: count 1 (on exit)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 10 secs
> >      > < INFO: lws_client_connect_2: 0x5581dbbe74c0: address MYSERVER
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x10000201)
> >      > < DEBUG: __insert_wsi_socket_into_fds: 0x5581dbbe74c0: tsi=0,
> >     sock=6,
> >      > pos-in-fds=1
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 1 ->
> 1
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 20 secs
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 1 ->
> 5
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 5 ->
> 1
> >      > < INFO: lws_client_connect_2: 0x5581dbbe74c0: address MYSERVER
> >      > < INFO: lws_client_connect_2: wsi 0x5581dbbe74c0: client creating
> >     own
> >      > connection
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x10000011)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 20 secs
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 1 ->
> 1
> >      > ---
> >      >  > INFO: _lws_create_ah: created ah 0x559119c38ec0 (size 4096):
> pool
> >      > length 1
> >      >  > INFO: lws_header_table_attach: did attach wsi 0x559119c38a30:
> ah
> >      > 0x559119c38ec0: count 1 (on exit)
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 10 secs (reason 25)
> >      >  > DEBUG: lws_http_client_connect_via_info2: 0x559119c38a30 (stash
> >      > 0x559119c38d80)
> >      >  > DEBUG: _realloc: size 27: strdup
> >      >  > INFO: lws_client_connect_2: 0x559119c38a30: address
> MYSERVER:443
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x10000201)
> >      >  > DEBUG: __insert_wsi_socket_into_fds: 0x559119c38a30: tsi=0,
> >     sock=6,
> >      > pos-in-fds=1
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1
> -> 1
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 2)
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1
> -> 5
> >      >  > DEBUG: lws_client_connect_via_info: wsi 0x559119c38a30: cb 19
> >     to h1
> >      > dumb-increment-protocol
> >      >  > NOTICE: lws_broadcast: 0 dumb-increment-protocol
> >      >  > NOTICE: lws_broadcast: 1 lws-abs-cli-raw-skt
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 5
> -> 1
> >      >  > INFO: lws_client_connect_2: 0x559119c38a30: address
> MYSERVER:443
> >      >  > INFO: lws_client_connect_3: wsi 0x559119c38a30: h1
> >      > dumb-increment-protocol client created own conn (raw 0)
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x10000011)
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 8)
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1
> -> 1
> >      > 70,72c85,87
> >      > < DEBUG: lws_ssl_get_error: 0x5581dbbeb240 -1 -> 2 (errno 11)
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x10000203)
> >      > < DEBUG: lws_ssl_get_error: 0x5581dbbeb240 -1 -> 2 (errno 11)
> >      > ---
> >      >  > DEBUG: lws_ssl_get_error: 0x559119c3c620 -1 -> 2 (errno 11)
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x10000203)
> >      >  > DEBUG: lws_ssl_get_error: 0x559119c3c620 -1 -> 2 (errno 11)
> >      > 74c89,93
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x10000203)
> >      > ---
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x10000203)
> >      >  > DEBUG: _realloc: size 592: cbwsi
> >      >  > DEBUG: _realloc: size 40: cbtmr
> >      >  > DEBUG: lws_service_periodic_checks: timed cb: vh default,
> >     protocol
> >      > lws-abs-cli-raw-skt, reason 1000
> >      >  > DEBUG: _realloc: size 40: timed_vh
> >      > 76a96
> >      >  > INFO: lws_openssl_describe_cipher: wsi 0x559119c38a30:
> >      > ECDHE-RSA-AES256-GCM-SHA384, ECDHE-RSA-AES256-GCM-SHA384, 256
> >     bits, TLSv1.2
> >      > 79,88c99,108
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x10000012)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 20 secs
> >      > < INFO: lws_client_socket_service: HANDSHAKE2: 0x5581dbbe74c0:
> >     sending
> >      > headers on 0x5581dbbe74c0 (wsistate 0x10000012 0x10000012)
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x1000020a)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 20 secs
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 1 ->
> 5
> >      > < DEBUG: lwsi_set_state(0x5581dbbe74c0, 0x1000020a)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 20 secs
> >      > < DEBUG: _lws_change_pollfd: wsi 0x5581dbbe74c0: fd 6 events 5 ->
> 1
> >      > < DEBUG: 0x5581dbbe74c0: SSL_read says 1
> >      > ---
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x10000012)
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 11)
> >      >  > INFO: lws_client_socket_service: HANDSHAKE2: 0x559119c38a30:
> >     sending
> >      > headers on 0x559119c38a30 (wsistate 0x10000012 0x10000012), w
> >     sock 6,
> >      > wsi sock 6
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x1000020a)
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 4)
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 1
> -> 5
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x1000020a)
> >      >  > DEBUG: __lws_set_timeout: 0x559119c38a30: 20 secs (reason 4)
> >      >  > DEBUG: _lws_change_pollfd: wsi 0x559119c38a30: fd 6 events 5
> -> 1
> >      >  > DEBUG: 0x559119c38a30: SSL_read says 1
> >      >
> >      > < PARSER: lws_client_int_s_hs: protocol list
> >     'dumb-increment-protocol'
> >      > < INFO: lws_client_ws_upgrade: WSI_TOKEN_PROTOCOL is null
> >
> >     Well, I guess it's not the problem at the moment.
> >
> >      > < DEBUG: lws_ensure_user_space: 0x5581dbbe74c0 protocol pss 0,
> >      > user_space=(nil)
> >      > < DEBUG: __lws_set_timeout: 0x5581dbbe74c0: 0 secs
> >      > < INFO: __lws_header_table_detach: wsi 0x5581dbbe74c0: ah
> >     0x5581dbbe78b0
> >      > (tsi=0, count = 1)
> >      > ---
> >      >  > ERR: CLIENT_CONNECTION_ERROR: HS: UPGRADE malformed
> >      >  > INFO: closing connection (prot dumb-increment-protocol) due to
> >     bail2
> >      > connection error: HS: UPGRADE malformed
> >      >  > INFO: __lws_close_free_wsi: 0x559119c38a30: caller: c hs interp
> >      >  > DEBUG: __lws_close_free_wsi: real just_kill_connection:
> >      > 0x559119c38a30 (sockfd 6)
> >      >  > DEBUG: lwsi_set_state(0x559119c38a30, 0x1000001e)
> >      >  > INFO: __lws_header_table_detach: wsi 0x559119c38a30: ah
> >      > 0x559119c38ec0 (tsi=0, count = 1)
> >
> >     -Andy
> >
> >     [1]
> >
> https://libwebsockets.org/git/libwebsockets/tree/READMEs/README.release-policy.md
> >
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://libwebsockets.org/pipermail/libwebsockets/attachments/20190503/69f3534a/attachment-0001.htm>


More information about the Libwebsockets mailing list