BKnight
Seeing a situation where the HTML client connects to our adapter, LS server creates session, subscribe sent to adapter.
Later a network error occurs, causing the connection to be dropped, session is destroyed in the LS server, and unsubscribe is sent to the adapter.
Reconnect occurs (automatically), and a new session is created in the LS server, however no new subscribe is sent to the adapter, so our adapter knows nothing about the client.
Here's some information from the log:
Initial connection:
01-Mar-12 08:32:35,145|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Starting new session: Sb101a15975bb8a12T3235145 from 192.168.56.19:15955
01-Mar-12 08:32:35,208|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Serving request: /lightstreamer/bind_session.js --> LS_session=Sb101a15975bb8a12T3235145&LS_phase=5403&LS_domain=justanswer.local& from 192.168.56.19:15955
01-Mar-12 08:32:35,208|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Attaching session: Sb101a15975bb8a12T3235145 from 192.168.56.19:15955
01-Mar-12 08:32:35,411|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Serving request: /lightstreamer/control.js --> LS_session=Sb101a15975bb8a12T3235145&LS_table=1&LS_win_phase=9&LS_req_phase=361&LS_op=add&LS_mode=DISTINCT&LS_id=325%3A14004778%3A1330619545824&LS_schema=jsonString&LS_data_adapter=JAExpertQLAdapter&LS_unique=1& from 192.168.56.19:16000
01-Mar-12 08:32:35,411|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Controlling session: Sb101a15975bb8a12T3235145 from 192.168.56.19:16000
01-Mar-12 08:32:39,302|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 10 |Serving request: /lightstreamer/control.js --> LS_session=Sb101a15975bb8a12T3235145&LS_op=force_rebind&LS_cause=sense&LS_unique=2& from 192.168.56.19:16000
01-Mar-12 08:32:39,302|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 10 |Forcing rebind on session: Sb101a15975bb8a12T3235145 from 192.168.56.19:16000
01-Mar-12 08:32:39,442|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 1 |Resuming session Sb101a15975bb8a12T3235145 with polling from 192.168.56.19:16000
01-Mar-12 08:33:09,662|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 6 |Polling (3) to session: Sb101a15975bb8a12T3235145 from 192.168.56.19:15955
Here's where the network error occurred, followed by the reconnect:
01-Mar-12 09:20:56,435|INFO |LightstreamerLogger.requests |FOR PUMPS PARKING DESTROYER|Closed session Sb101a15975bb8a12T3235145 with internal cause code: 39
01-Mar-12 09:21:23,217|INFO |LightstreamerMonitorText |Timer-0 |Total threads = 78, Total heap = 64880640 (free = 42244464), Sessions = 4 (max = 6), New sessions = [+0, -2], Connections = 4 (max = 10), New connections = [+0, -2], In-pool threads = 22, Active threads = 0, Available threads = 22, Queued tasks = 0, Pool queue wait = 0, NIO write queue = 0, NIO write queue wait = 0, NIO active write selectors = 1, NIO total selectors = 4, Subscribed items = 4, Inbound throughput = 0 updates/s, (pre-filtered = 0), Outbound throughput = 0 updates/s, (0kbit/s, max = 162.69), Lost updates = 0 (total = 0), Total bytes sent = 822553, Extra sleep = 0, Notify delay = 0
01-Mar-12 09:21:40,436|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 10 |Serving request: /lightstreamer/create_session.js --> LS_phase=5817&LS_domain=justanswer.local&LS_cause=timeout&LS_polling=true&LS_polling_millis=0&LS_idle_millis=0&LS_client_version=5.0&LS_adapter_set=JustAnswer& from 192.168.56.19:18655
01-Mar-12 09:21:40,436|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 10 |Starting new session: Scd2c9d1570e21ad8T2140436 from 192.168.56.19:18655
01-Mar-12 09:21:40,514|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 4 |Serving request: /lightstreamer/bind_session.js --> LS_session=Scd2c9d1570e21ad8T2140436&LS_phase=5819&LS_domain=justanswer.local& from 192.168.56.19:18655
01-Mar-12 09:21:40,514|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 4 |Attaching session: Scd2c9d1570e21ad8T2140436 from 192.168.56.19:18655
01-Mar-12 09:21:40,702|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Serving request: /lightstreamer/control.js --> LS_session=Scd2c9d1570e21ad8T2140436&LS_table=2&LS_win_phase=26&LS_req_phase=362&LS_op=add&LS_mode=DISTINCT&LS_id=325%3A14004778%3A1330619545824&LS_schema=jsonString&LS_data_adapter=JAExpertQLAdapter&LS_unique=4& from 192.168.56.19:18658
01-Mar-12 09:21:40,702|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 5 |Controlling session: Scd2c9d1570e21ad8T2140436 from 192.168.56.19:18658
01-Mar-12 09:21:44,577|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Serving request: /lightstreamer/control.js --> LS_session=Scd2c9d1570e21ad8T2140436&LS_op=force_rebind&LS_cause=sense&LS_polling_millis=0&LS_unique=5& from 192.168.56.19:18658
01-Mar-12 09:21:44,577|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 9 |Forcing rebind on session: Scd2c9d1570e21ad8T2140436 from 192.168.56.19:18658
01-Mar-12 09:21:44,749|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 2 |Resuming session Scd2c9d1570e21ad8T2140436 with polling from 192.168.56.19:18658
01-Mar-12 09:22:15,093|INFO |LightstreamerLogger.requests |SERVER POOLED THREAD 7 |Polling (3) to session: Scd2c9d1570e21ad8T2140436 from 192.168.56.19:18655
Under what circumstances would the subscribe not be sent to the adapter, when it had previously sent the unsubscribe on a reconnect?
