Invalid Handshake over WS

[Ike]

Hello, I have been running the below c# code snippet with the .net client SDK (version 5.1.0) against our Lightstreamer server version 7.2.2 and I kept getting invalid handshake and the connection kept switching to HTTP.
I tried ForcedTransport = "WS" and "WS-Streaming" as well but still no luck.

I tried the quickstart example on here, https://sdk.lightstreamer.com/ls-dot...les/intro.html and it worked fine with me.

Also I have a Java version (similar to the C# code below) and it works fine as well. It connects and I get status WS-Streaming back successfully. Somehow I can't make it work with the following though. Any idea what could be the reason?

using com.lightstreamer.client;
using Lightstreamer.DotNet.Logging.Log;

namespace ConsoleApp;

internal class Program
{
public static void Main()
{
LightstreamerClient.setLoggerProvider(new Log4NetLoggerProviderWrapper());

var client = new LightstreamerClient("https://myurl", "myAdapterSet")
{
connectionDetails =
{
User = "user",
Password = "token"
}
};
}
}


Log;

Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
Opening new session ...
Cookies to be inserted for https://myurl/lightstreamer/create_s...ol=TLCP-2.1.0: <none>
Start message handler
Session ID value changed to Saff0bf8ac9a1ffc2M19aT4954759
Server Instance Address value changed to https://myurl/
Server Socket Name value changed to Lightstreamer HTTP Server
Client IP value changed to xxxxxx
Cookies to be inserted for https://myurl: <none>
WS upgrade error: Invalid handshake response getStatus: 404 Not Found
CONNECTED:STREAM-SENSING
Unable to establish session of the current type. Switching session type WS Streaming->prepare HTTP Streaming
Sending request to the server to force a rebind on the current connection during FIRST_BINDING
Cookies to be inserted for https://myurl/lightstreamer/control....ol=TLCP-2.1.0: <none>
Batch completed
CONNECTED:STREAM-SENSING
CONNECTED:STREAM-SENSING
Failed to switch session type. Starting new session prepare HTTP Streaming
Closing session new.switch.timeout.timeout.FIRST_BINDING.1
Sending request to the server to destroy the current session during FIRST_BINDING
Reset message handler
Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
Stop Protocol
Protocol dismissed
Closing wsc
Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
Opening new session ...
Cookies to be inserted for https://myurl/lightstreamer/create_s...ol=TLCP-2.1.0: <none>
Start message handler
Session ID value changed to Sd47a36c69c7bcb65M19aT5003510
Server Instance Address value changed to https://myurl/
Server Socket Name value changed to Lightstreamer HTTP Server
Client IP value changed to xxxxxxx
Cookies to be inserted for https://myurl/lightstreamer/bind_ses...ol=TLCP-2.1.0: <none>
Keepalive Interval value changed to 5000
CONNECTED:HTTP-STREAMING
CONNECTED:HTTP-STREAMING
CONNECTED:HTTP-STREAMING

[DarioCrivelli]

We need to see if and how the request reaches LS Server.
If the Server is under your control, please check the log. The following log configuration is needed:
LightstreamerLogger.connections at INFO level
LightstreamerLogger.connections.http at INFO level
LightstreamerLogger.webServerat DEBUG level

Moreover, even though, as far as we know, there isn't such a bug in .net SDK 5.1.0, please upgrade to 5.1.8 to simplify the scenario.

[Ike]

New Lightstreamer Client instanced (library version: Lightstreamer.DotNetStandard.Client 5.1.8)
Server Address value changed to https://myurl
Adapter Set value changed to Futures
Forced Transport value changed to
User value changed to
Password value changed
Server Address value changed to https://myurl
Connect requested
SessionManager state change: OFF -> STREAMING_WS
New protocol oid=1
rhb max interval 0
rhb current interval 0
New session oid=1
Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
Opening new session ...
IsOffline check now ...
... online, go!
Create Request: https://myurl
Requested cookies for uri https://myurl/lightstreamer/create_s...ol=TLCP-2.1.0:
cs ---- address: https://myurl/lightstreamer/create_s...col=TLCP-2.1.0, 52.177.141.126
HTTP transport connection establishing (oid=1): https://myurl:443
/lightstreamer/create_session.txt?LS_protocol=TLCP-2.1.0
LS_polling=true&LS_cause=new.api&LS_polling_millis =0&LS_idle_millis=0&LS_cid=jqWtj1twChtfDxikwp1ltvc B4CJCJi kOj2CKDk&LS_myAdapter_set=Futures&LS_user=&LS_pass word

- target: 52.177.141.126:443 - True(com.lightstreamer.client.transport.providers. netty.NettyFullAddress).
New HTTP channel pool created. Remote address: 52.177.141.126:443
sessionStatusChanged 1, 1
Session state change (1): OFF -> CREATING
phasing : 1 - 1
Status timeout in 4000 [currentConnectTimeout] due to
HTTP channel created [674c2ca9, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
HTTP channel active [674c2ca9]
- acquired! 674c2ca9
Go with the request True
Send Request Task status = RanToCompletion
SetCookie received for uri https://myurl/lightstreamer: -futureslightstreamer=1698240888.178.56570.767405; Expires=Fri, 27-Oct-23 13:34:47 GMT; Max-Age=172800; Path=/; Secure; HttpOnly
Cookies to be saved for https://myurl/lightstreamer: -futureslightstreamer=1698240888.178.56570.767405; Expires=Fri, 27-Oct-23 13:34:47 GMT; Max-Age=172800; Path=/; Secure; HttpOnly
HTTP transport receiving [674c2ca9]:
CONOK,S0abbfd1b7bc558dcM19aT3447185,50000,0,*
SERVNAME,Lightstreamer HTTP Server
CLIENTIP,10.42.51.9
LOOP,0

Readeable bytes: 112
Start: 0
findEol: 0 <> 112
findEol - cr: 45 eol:46
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CONOK,S0abbfd1b7bc558dcM19aT3447185,50000,0,*
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CONOK,S0abbfd1b7bc558dcM19aT3447185,50000,0,*
findEol: 47 <> 112
findEol - cr: 81 eol:82
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - SERVNAME,Lightstreamer HTTP Server
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - SERVNAME,Lightstreamer HTTP Server
findEol: 83 <> 112
findEol - cr: 102 eol:103
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CLIENTIP,10.42.51.9
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CLIENTIP,10.42.51.9
findEol: 104 <> 112
findEol - cr: 110 eol:111
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - LOOP,0
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - LOOP,0
findEol: 112 <> 112
.:.: False(0)
linePart: 0
Reuse failed for 674c2ca9
HTTP channel inactive [674c2ca9]
... False - False
Socket Closed.
New message (1 - OPENING_STREAM): CONOK,S0abbfd1b7bc558dcM19aT3447185,50000,0,*
OK event while CREATING
Address to use after create: https://myurl
Data event while CREATING
sessionStatusChanged 1, 1
Session state change (1): CREATING -> CREATED
phasing : 2 - 2
Status timeout in 2000 [executionTimeout] due to
Check Point 1a120ak.
SubscriptionManager sessionAlive set to true.
sendAllSubscriptions: 0
sendAllSubscriptions done!
Start message handler
Sending queued messages
Session ID value changed to S0abbfd1b7bc558dcM19aT3447185
Server Instance Address value changed to https://myurl
New message (1 - READING_STREAM): SERVNAME,Lightstreamer HTTP Server
Server Socket Name value changed to Lightstreamer HTTP Server
New message (1 - READING_STREAM): CLIENTIP,10.42.51.9
Client IP value changed to 10.42.51.9
New message (1 - READING_STREAM): LOOP,0
sessionStatusChanged 1, 1
Session state change (1): CREATED -> FIRST_PAUSE
phasing : 3 - 3
Timeout event [noPause] while FIRST_PAUSE cause=
SessionWS state change (1) (sendBind): WS_NOT_CONNECTED -> WS_CONNECTING
Requested cookies for uri https://myurl/lightstreamer: -futureslightstreamer=1698240888.178.56570.767405
New WS channel pool created. Remote address: 52.177.141.126:443
... try get parent pool channel ... com.lightstreamer.client.transport.providers.netty .HttpPoolManager+HttpChannelPool
HTTP channel created [2a2b01d5, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
WebSocket transport - : CONNECTING
Status timeout in 4000 [currentConnectTimeoutWS]
SessionWS state change (1) (sendBind): WS_CONNECTING
HTTP channel active [2a2b01d5]
... Channel [id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 => [::ffff:52.177.141.126]:443]
Wait WS upgrade completition.
[id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 => [::ffff:52.177.141.126]:443] is active -> upgrade ...
[id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 => [::ffff:52.177.141.126]:443] ... wait upgrade ... False
CONNECTED:STREAM-SENSING

[Ike]

... wsHandshakeHandler ...
... add ws pipeline ...
... ws handshake task: 232 - False - False - False
[id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 => [::ffff:52.177.141.126]:443] ... wait upgrade 2 ... False
[id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 => [::ffff:52.177.141.126]:443] ... done False - True
WS Read0 - False - Msg:AggregatedFullHttpResponse(decodeResult: success, version: HTTP/1.1, content: CompositeByteBuffer(ridx: 0, widx: 146, cap: 146, components=1))
HTTP/1.1 404 Not Found
Date:Wed, 25 Oct 2023 13:34:49 GMT
Content-Type:text/html
Content-Length:146
Connection:keep-alive
Strict-Transport-Security:max-age=15724800; includeSubDomains
WS Read0 -- 404 Not Found
WS upgrade error: Invalid handshake response getStatus: 404 Not Found
WS Read0 ----- 404 Not Found
[id: 0x2a2b01d5, [::ffff:10.249.0.114]:50962 :> [::ffff:52.177.141.126]:443] ... wait upgrade 3 ... True
WebSocket transport onOpen: CONNECTED
SessionWS state change (1) (ok): WS_CONNECTING -> WS_CONNECTED
WS transport sending [2a2b01d5]: bind_session
LS_cause=loop1&LS_session=S0abbfd1b7bc558dcM19aT34 47185&
sessionStatusChanged 1, 1
Session state change (1): FIRST_PAUSE -> FIRST_BINDING
phasing : 4 - 4
Status timeout in 4000 [bindTimeout] due to
Timeout event [currentConnectTimeoutWS]
CONNECTED:STREAM-SENSING
Timeout event [bindTimeout] while FIRST_BINDING cause=
Unable to establish session of the current type. Switching session type WS Streaming->prepare HTTP Streaming
WebSocket support has been disabled.
SessionManager state change: STREAMING_WS -> SWITCHING_STREAMING_HTTP
Switch requested phase=FIRST_BINDING cause=timeout.FIRST_BINDING.1
Sending request to the server to force a rebind on the current connection during FIRST_BINDING
Ready to dequeue control requests to be sent to server
Requested cookies for uri https://myurl/lightstreamer/control....ol=TLCP-2.1.0: -futureslightstreamer=1698240888.178.56570.767405
cs ---- address: https://myurl/lightstreamer/control....col=TLCP-2.1.0, 52.177.141.126
HTTP transport connection establishing (oid=1): https://futures-lightstreamer-api..com:443
/lightstreamer/control.txt?LS_protocol=TLCP-2.1.0
LS_reqId=1&LS_op=force_rebind&LS_session=S0abbfd1b 7bc558dcM19aT3447185&LS_cause=timeout.FIRST_BINDIN G.1&

- target: 52.177.141.126:443 - True(com.lightstreamer.client.transport.providers. netty.NettyFullAddress).
HTTP channel created [6cb7edea, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
HTTP channel active [6cb7edea]
- acquired! 6cb7edea
Go with the request True
Send Request Task status = RanToCompletion
HTTP transport receiving [6cb7edea]:
REQOK,1

Readeable bytes: 9
Start: 0
findEol: 0 <> 9
findEol - cr: 7 eol:8
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - REQOK,1
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - REQOK,1
findEol: 9 <> 9
.:.: False(0)
linePart: 0
Reuse failed for 6cb7edea
HTTP channel inactive [6cb7edea]
... False - False
Socket Closed.
Batch completed
OnOk - REQOK,1
CONNECTED:STREAM-SENSING
Failed to switch session type. Starting new session prepare HTTP Streaming
Closing the session.
Closing session new.switch.timeout.timeout.FIRST_BINDING.1
Sending request to the server to destroy the current session during FIRST_BINDING
Pending request - post - 2
WS transport sending [2a2b01d5]: control
LS_reqId=2&LS_op=destroy&LS_cause=new.switch.timeo ut.timeout.FIRST_BINDING.1&
SubscriptionManager sessionAlive set to false.
pauseAllSubscriptions: 0
pauseAllSubscriptions done!
Reset message handler
Aborting pending messages
SessionManager state change: SWITCHING_STREAMING_HTTP -> SWITCHING_STREAMING_HTTP
Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
sessionStatusChanged 3, 3
Session state change (1): FIRST_BINDING -> SLEEP
phasing : 5 - 5
Stop Protocol
Protocol dismissed
ws closing :com.lightstreamer.client.transport.WebSocket
Closing wsc
WebSocket transport (close): DISCONNECTED
WS disconnect [2a2b01d5]
WS channel closed [2a2b01d5]
ws closing :
SessionManager state change: SWITCHING_STREAMING_HTTP -> STREAMING_HTTP
New protocol oid=2
rhb max interval 0
rhb current interval 0
New session oid=2
Session ID value changed to
Server Socket Name value changed to
Client IP value changed to
Server Instance Address value changed to
Opening new session ...
IsOffline check now ...
... online, go!
Create Request: https://myurl
Requested cookies for uri https://myurl/lightstreamer/create_s...ol=TLCP-2.1.0: -futureslightstreamer=1698240888.178.56570.767405
cs ---- address: https://myurl/lightstreamer/create_s...col=TLCP-2.1.0, 52.177.141.126
HTTP transport connection establishing (oid=2): https://futures-lightstreamer-api..com:443
/lightstreamer/create_session.txt?LS_protocol=TLCP-2.1.0
LS_polling=true&LS_cause=new.switch.timeout.timeou t.FIRST_BINDING.1&LS_polling_millis=0&LS_idle_mill is=0&LS_cid=jqWtj1twChtfDxikwp1ltvcB4CJCJi kOj2CKDk&LS_adapter_set=Futures&LS_user=98cddc5d-e4af-49ac-89ec-7f83f8fd22c6:fa097f61-c884-4f80-b35a-b3d4c2ee5872&LS_password=AK-ca2f5093362344959abe5534fd4f49c8&LS_old_session=S0 abbfd1b7bc558dcM19aT3447185&

- target: 52.177.141.126:443 - True(com.lightstreamer.client.transport.providers. netty.NettyFullAddress).
sessionStatusChanged 4, 4
Session state change (2): OFF -> CREATING
phasing : 1 - 1
Status timeout in 4000 [currentConnectTimeout] due to
HTTP channel created [6d09d700, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
ReleaseAsync for 2a2b01d5
ReleaseAsync -0- for False
DoReleaseChannel -0- for 2a2b01d5
DoReleaseChannel -1- for myChannelPool
DoReleaseChannel -- True
HTTP channel active [6d09d700]
- acquired! 6d09d700
Go with the request True
Send Request Task status = RanToCompletion
HTTP transport receiving [6d09d700]:
CONOK,Sc08e180f5b179934M19aT3457743,50000,0,*
SERVNAME,Lightstreamer HTTP Server
CLIENTIP,10.42.51.11
LOOP,0

[Ike]

Readeable bytes: 113
Start: 0
findEol: 0 <> 113
findEol - cr: 45 eol:46
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CONOK,Sc08e180f5b179934M19aT3457743,50000,0,*
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CONOK,Sc08e180f5b179934M19aT3457743,50000,0,*
findEol: 47 <> 113
findEol - cr: 81 eol:82
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - SERVNAME,Lightstreamer HTTP Server
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - SERVNAME,Lightstreamer HTTP Server
findEol: 83 <> 113
findEol - cr: 103 eol:104
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CLIENTIP,10.42.51.11
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CLIENTIP,10.42.51.11
findEol: 105 <> 113
findEol - cr: 111 eol:112
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - LOOP,0
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - LOOP,0
findEol: 113 <> 113
.:.: False(0)
linePart: 0
Reuse failed for 6d09d700
HTTP channel inactive [6d09d700]
... False - False
Socket Closed.
New message (2 - OPENING_STREAM): CONOK,Sc08e180f5b179934M19aT3457743,50000,0,*
OK event while CREATING
Address to use after create: https://myurl
Data event while CREATING
sessionStatusChanged 4, 4
Session state change (2): CREATING -> CREATED
phasing : 2 - 2
Status timeout in 2000 [executionTimeout] due to
Check Point 1a120ak.
SubscriptionManager sessionAlive set to true.
sendAllSubscriptions: 0
sendAllSubscriptions done!
Start message handler
Sending queued messages
Session ID value changed to Sc08e180f5b179934M19aT3457743
Server Instance Address value changed to https://myurl
New message (2 - READING_STREAM): SERVNAME,Lightstreamer HTTP Server
Server Socket Name value changed to Lightstreamer HTTP Server
New message (2 - READING_STREAM): CLIENTIP,10.42.51.11
Client IP value changed to 10.42.51.11
New message (2 - READING_STREAM): LOOP,0
sessionStatusChanged 4, 4
Session state change (2): CREATED -> FIRST_PAUSE
phasing : 3 - 3
Timeout event [noPause] while FIRST_PAUSE cause=
SessionManager state change: STREAMING_HTTP -> SWITCHING_STREAMING_WS
Switch requested phase=FIRST_PAUSE cause=ip
Switching current session type prepare WS Streaming
SessionManager state change: SWITCHING_STREAMING_WS -> STREAMING_WS
New protocol oid=3
rhb max interval 0
rhb current interval 0
New session oid=3
sessionStatusChanged 5, 6
Session state change (2): FIRST_PAUSE -> OFF
phasing : 4 - 4
Protocol dismissed
sessionStatusChanged 6, 6
Session state change (3): OFF -> FIRST_PAUSE
phasing : 1 - 1
SessionWS state change (3) (sendBind): WS_NOT_CONNECTED -> WS_CONNECTING
Requested cookies for uri https://myurl/lightstreamer: -futureslightstreamer=1698240888.178.56570.767405
... try get parent pool channel ... com.lightstreamer.client.transport.providers.netty .HttpPoolManager+HttpChannelPool
HTTP channel created [2fe8ac2c, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
WebSocket transport - : CONNECTING
Status timeout in 4000 [currentConnectTimeoutWS]
SessionWS state change (3) (sendBind): WS_CONNECTING
HTTP channel active [2fe8ac2c]
... Channel [id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 => [::ffff:52.177.141.126]:443]
Wait WS upgrade completition.
[id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 => [::ffff:52.177.141.126]:443] is active -> upgrade ...
[id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 => [::ffff:52.177.141.126]:443] ... wait upgrade ... False
[id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 => [::ffff:52.177.141.126]:443] ... wait upgrade 2 ... False
[id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 => [::ffff:52.177.141.126]:443] ... done False - True
CONNECTED:STREAM-SENSING
... wsHandshakeHandler ...
... add ws pipeline ...
... ws handshake task: 542 - False - False - False
WS Read0 - False - Msg:AggregatedFullHttpResponse(decodeResult: success, version: HTTP/1.1, content: CompositeByteBuffer(ridx: 0, widx: 146, cap: 146, components=1))
HTTP/1.1 404 Not Found
Date:Wed, 25 Oct 2023 13:34:59 GMT
Content-Type:text/html
Content-Length:146
Connection:keep-alive
Strict-Transport-Security:max-age=15724800; includeSubDomains
WS Read0 -- 404 Not Found
WS upgrade error: Invalid handshake response getStatus: 404 Not Found
WS Read0 ----- 404 Not Found
[id: 0x2fe8ac2c, [::ffff:10.249.0.114]:50971 :> [::ffff:52.177.141.126]:443] ... wait upgrade 3 ... True
WebSocket handshake error,
SessionWS state change (3) (error): WS_CONNECTING -> WS_BROKEN
Status timeout in 0 [zeroDelay] due to ws.broken.wait
Error event while FIRST_PAUSE reason: network.error tryRecovery=True timeLeft=15000 closedOnServer=False unableToOpen=True wsError=True
WebSocket was broken while we were waiting the first bind
Unable to establish session of the current type. Switching session type WS Streaming->prepare HTTP Streaming
SessionManager state change: STREAMING_WS -> SWITCHING_STREAMING_HTTP
Switch requested phase=FIRST_PAUSE cause=network.error
Switching current session type prepare HTTP Streaming
SessionManager state change: SWITCHING_STREAMING_HTTP -> STREAMING_HTTP
New protocol oid=4
rhb max interval 0
rhb current interval 0
New session oid=4

[Ike]

sessionStatusChanged 7, 8
Session state change (3): FIRST_PAUSE -> OFF
phasing : 2 - 2
Stop Protocol
Protocol dismissed
ws closing :com.lightstreamer.client.transport.WebSocket
Closing wsc
WebSocket transport (close): DISCONNECTED
ws closing :
sessionStatusChanged 8, 8
Session state change (4): OFF -> FIRST_PAUSE
phasing : 1 - 1
Requested cookies for uri https://myurl/lightstreamer/bind_ses...ol=TLCP-2.1.0: -futureslightstreamer=1698240888.178.56570.767405
cs ---- address: https://myurl/lightstreamer/bind_ses...col=TLCP-2.1.0, 52.177.141.126
HTTP transport connection establishing (oid=4): https://futures-lightstreamer-api..com:443
/lightstreamer/bind_session.txt?LS_protocol=TLCP-2.1.0
LS_content_length=50000000&LS_cause=network.error& LS_session=Sc08e180f5b179934M19aT3457743&

- target: 52.177.141.126:443 - True(com.lightstreamer.client.transport.providers. netty.NettyFullAddress).
HTTP channel created [37d56971, com.lightstreamer.client.transport.providers.netty .NettyFullAddress]: False
sessionStatusChanged 8, 8
Session state change (4): FIRST_PAUSE -> FIRST_BINDING
phasing : 2 - 2
Status timeout in 4000 [bindTimeout] due to
HTTP channel active [37d56971]
- acquired! 37d56971
Go with the request True
Send Request Task status = RanToCompletion
HTTP transport receiving [37d56971]:
CONOK,Sc08e180f5b179934M19aT3457743,50000,5000,*
CLIENTIP,10.42.51.9
NOOP,sending placeholder data
NOOP,sending placeholder data
NOOP,sending placeholder data
NOOP,sending placeholde
Readeable bytes: 993
Start: 0
findEol: 0 <> 993
findEol - cr: 48 eol:49
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CONOK,Sc08e180f5b179934M19aT3457743,50000,5000,*
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CONOK,Sc08e180f5b179934M19aT3457743,50000,5000,*
findEol: 50 <> 993
findEol - cr: 69 eol:70
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CLIENTIP,10.42.51.9
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CLIENTIP,10.42.51.9
findEol: 71 <> 993
findEol - cr: 100 eol:101
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 102 <> 993
findEol - cr: 131 eol:132
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 133 <> 993
findEol - cr: 162 eol:163
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 164 <> 993
findEol - cr: 193 eol:194
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 195 <> 993
findEol - cr: 224 eol:225
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 226 <> 993
findEol - cr: 255 eol:256
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 257 <> 993
findEol - cr: 286 eol:287
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 288 <> 993
findEol - cr: 317 eol:318
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 319 <> 993
findEol - cr: 348 eol:349
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 350 <> 993
findEol - cr: 379 eol:380
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 381 <> 993
findEol - cr: 410 eol:411
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 412 <> 993
findEol - cr: 441 eol:442
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 443 <> 993
findEol - cr: 472 eol:473
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 474 <> 993
findEol - cr: 503 eol:504
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 505 <> 993
findEol - cr: 534 eol:535
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 536 <> 993
findEol - cr: 565 eol:566
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 567 <> 993
findEol - cr: 596 eol:597
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 598 <> 993
findEol - cr: 627 eol:628
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 629 <> 993
findEol - cr: 658 eol:659
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 660 <> 993
findEol - cr: 689 eol:690
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 691 <> 993
findEol - cr: 720 eol:721
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 722 <> 993
findEol - cr: 751 eol:752
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 753 <> 993

[Ike]

findEol - cr: 782 eol:783
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 784 <> 993
findEol - cr: 813 eol:814
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 815 <> 993
findEol - cr: 844 eol:845
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 846 <> 993
findEol - cr: 875 eol:876
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 877 <> 993
findEol - cr: 906 eol:907
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 908 <> 993
findEol - cr: 937 eol:938
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 939 <> 993
findEol - cr: 968 eol:969
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 970 <> 993
No CR.
findEol - cr: -1 eol:-1
.:.: True(23)
copyLinePart: 0 - 0
HTTP transport receiving [37d56971]:
r data
NOOP,sending placeholder data
NOOP,sending placeholder data
NOOP,sending placeholder data
NOOP,sending placeholder data
Readeable bytes: 1062
Start: 0
findEol: 0 <> 1062
findEol - cr: 6 eol:7
prev line incomplete case B: 7
copyLinePart: 23 - 23
Post copyLinePart hasHead.
toLine: 31
Temp String before: NOOP,sending placeholder data

Temp String after: NOOP,sending placeholder data
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
:.: com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 8 <> 1062
findEol - cr: 37 eol:38
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 39 <> 1062
findEol - cr: 68 eol:69
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 70 <> 1062
findEol - cr: 99 eol:100
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 101 <> 1062
findEol - cr: 130 eol:131
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 132 <> 1062
findEol - cr: 161 eol:162
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 163 <> 1062
findEol - cr: 192 eol:193
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 194 <> 1062
findEol - cr: 223 eol:224
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 225 <> 1062
findEol - cr: 254 eol:255
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 256 <> 1062
findEol - cr: 285 eol:286
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 287 <> 1062
findEol - cr: 316 eol:317
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 318 <> 1062
findEol - cr: 347 eol:348
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 349 <> 1062
findEol - cr: 378 eol:379

[Ike]

never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 380 <> 1062
findEol - cr: 409 eol:410
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 411 <> 1062
findEol - cr: 440 eol:441
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 442 <> 1062
findEol - cr: 471 eol:472
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 473 <> 1062
findEol - cr: 502 eol:503
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 504 <> 1062
findEol - cr: 533 eol:534
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 535 <> 1062
findEol - cr: 564 eol:565
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 566 <> 1062
findEol - cr: 595 eol:596
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 597 <> 1062
findEol - cr: 626 eol:627
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 628 <> 1062
findEol - cr: 657 eol:658
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 659 <> 1062
findEol - cr: 688 eol:689
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 690 <> 1062
findEol - cr: 719 eol:720
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 721 <> 1062
findEol - cr: 750 eol:751
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 752 <> 1062
findEol - cr: 781 eol:782
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 783 <> 1062
findEol - cr: 812 eol:813
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 814 <> 1062
findEol - cr: 843 eol:844
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 845 <> 1062
findEol - cr: 874 eol:875
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 876 <> 1062
findEol - cr: 905 eol:906
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 907 <> 1062
findEol - cr: 936 eol:937
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 938 <> 1062
findEol - cr: 967 eol:968
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 969 <> 1062
findEol - cr: 998 eol:999
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 1000 <> 1062
findEol - cr: 1029 eol:1030
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 1031 <> 1062
findEol - cr: 1060 eol:1061
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - NOOP,sending placeholder data
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - NOOP,sending placeholder data
findEol: 1062 <> 1062
.:.: False(0)
linePart: 0
HTTP transport receiving [37d56971]:
CONS,unlimited

Readeable bytes: 16
Start: 0
findEol: 0 <> 16
findEol - cr: 14 eol:15
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - CONS,unlimited
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - CONS,unlimited
findEol: 16 <> 16
.:.: False(0)
linePart: 0
New message (4 - OPENING_STREAM): CONOK,Sc08e180f5b179934M19aT3457743,50000,5000,*
OK event while FIRST_BINDING
Address to use after create: https://myurl
Keepalive Interval value changed to 5000
CurrentConnectTimeout: 4328, 4000, 328
Data event while FIRST_BINDING
sessionStatusChanged 8, 8
Session state change (4): FIRST_BINDING -> RECEIVING
phasing : 3 - 3
Offline check 1.
Status timeout in 5000 [keepaliveInterval] due to
Reset currentRetryDelay: 4000
New message (4 - READING_STREAM): CLIENTIP,10.42.51.9
Client IP value changed to 10.42.51.9
New message (4 - READING_STREAM): NOOP,sending placeholder data
New message (4 - READING_STREAM): NOOP,sending placeholder data
New message (4 - READING_STREAM): CONS,unlimited
CONNECTED:HTTP-STREAMING
Timeout event [currentConnectTimeoutWS]
CONNECTED:HTTP-STREAMING
HTTP transport receiving [37d56971]:
PROBE

Readeable bytes: 7
Start: 0
findEol: 0 <> 7
findEol - cr: 5 eol:6
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - PROBE
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - PROBE
findEol: 7 <> 7
.:.: False(0)
linePart: 0
New message (4 - READING_STREAM): PROBE
Data event while RECEIVING
Status timeout in 5000 [keepaliveInterval] due to
Cancellation requested, nothing to do.
CONNECTED:HTTP-STREAMING
HTTP transport receiving [37d56971]:
PROBE

Readeable bytes: 7
Start: 0
findEol: 0 <> 7
findEol - cr: 5 eol:6
never ending story of a message com.lightstreamer.client.transport.Http+MyHttpList ener - PROBE
.:. com.lightstreamer.client.transport.providers.netty .NettyRequestListener - PROBE
findEol: 7 <> 7
.:.: False(0)
linePart: 0
New message (4 - READING_STREAM): PROBE
Data event while RECEIVING
Status timeout in 5000 [keepaliveInterval] due to
Cancellation requested, nothing to do.
CONNECTED:HTTP-STREAMING
CONNECTED:HTTP-STREAMING

[giuseppe.corti]

Hi Ike,

Unfortunately, switching to version 5.1.8 and enabling client-side DEBUG logging did not change the situation and did not help us understand the reason for the 404 error on the WS upgrade request.
At this point, as my colleague Dario mentioned, it is interesting to understand how the 101 request reaches the Lightstreamer server and whether it does. Can you confirm that you have access to server-side logs?

[Ike]

I do have access to LS server side logs.