ansible-role-varnish icon indicating copy to clipboard operation
ansible-role-varnish copied to clipboard

WebSocketChannelException

Open quangvinhvux opened this issue 2 years ago • 7 comments

Hi Connectycube, I am facing a problem with the chat connection, can you take a look? When I logged in to more than 2 devices, the WebSocket connection failed on all devices, after CubeChatConnection try to reconnect, the WebSocket connection failed again, and it did not stop, see the log below

WebSocketChannelException: WebSocket connection failed. [log] D/[Connection]: Handle connection done [log] D/[Connection]: State: XmppConnectionState.ForcefullyClosed CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed [log] D/[ReconnectionManager]: Connection forcefully closed! [log] D/[ReconnectionManager]: TimeOut is: 10000 reconnection counter 0 [log] D/[Connection]: State: XmppConnectionState.Reconnecting [log] D/[Connection]: State: XmppConnectionState.SocketOpening CB-SDK: CubeChatConnection: Chat connection Reconnecting [log] D/[Connection]: State: XmppConnectionState.SocketOpened [log] ---Xmpp Sending:--- [log] CB-SDK: CubeChatConnection: Chat connection SocketOpening CB-SDK: CubeChatConnection: Chat connection SocketOpened

[log] D/[Connection]: State: XmppConnectionState.SessionInitialized [log] D/[ConnectionNegotiatorManager]: Found matching false [log] D/[ConnectionNegotiatorManager]: Found matching false [log] D/[ConnectionNegotiatorManager]: No matching [log] D/[Connection]: State: XmppConnectionState.Ready

WebSocketChannelException: WebSocket connection failed.

quangvinhvux avatar Oct 05 '22 10:10 quangvinhvux

@quangvinhvux could you please provide the full log from starting the app till getting this issue? could you provide the steps on how we can reproduce this issue in our samples? Please provide the log without any cutting, e.g. there should present the XMPP stanza:

[log] ---Xmpp Sending:---
[log]

TatankaConCube avatar Oct 06 '22 08:10 TatankaConCube

[log] ---Xmpp Sending:---
[log] <iq id="VFYRMUHDS" type="set">
  <bind xmlns="urn:…AuMCBTYWZhcmkvNTM3LjM2</resource>
  </bind>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <enabled xmlns='urn:xmpp:sm:3' />

WebSocketChannelException: WebSocket connection failed.
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
[log] ---Xmpp Receiving:---
[log] <stream:error xmlns:stream="http://etherx.jabber.o…se xmlns='urn:ietf:params:xml:ns:xmpp-framing' />
[log] D/[Connection]: Handle connection done
[log] D/[Connection]: State: XmppConnectionState.ForcefullyClosed
[log] D/[ReconnectionManager]: Connection forcefully closed!
[log] D/[ReconnectionManager]: TimeOut is: 10000 reconnection counter 0
[log] ---Xmpp Receiving:---
[log] <iq id="VFYRMUHDS" xmlns="jabber:client" type="res…zEwNS4wLjAuMCBTYWZhcmkvNTM3LjM2</jid></bind></iq>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="PYVOEKXSM" type="set" to="chat.connectycub…lns="urn:ietf:params:xml:ns:xmpp-session"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="1"/>
[log] ---Xmpp Receiving:---
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
[log] <iq id="PYVOEKXSM" xmlns="jabber:client" from="cha…NrbykgQ2hyb21lLzEwNS4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
[log] D/[Connection]: State: XmppConnectionState.SessionInitialized
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="ATFCNAPNI" type="get" from="6244953-5833@c…s="http://jabber.org/protocol/disco#info"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="2"/>
[log] ---Xmpp Receiving:---
[log] <iq id="ATFCNAPNI" xmlns="jabber:client" from="cha…n:ietf:params:xml:ns:xmpp-session"/></query></iq>
[log] E/[ConnectionNegotiatorManager]: ADDING FEATURES c…, Instance of 'Feature$', Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Adding negotiator…ate.IDLE}, isReady: true [Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="KSQBNQUHG" type="set" xmlns="jabber:client">
  <enable xmlns="urn:xmpp:carbons:2"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="3"/>
[log] ---Xmpp Receiving:---
CB-SDK: CubeChatConnection: Chat connection Ready

[log] D/[ConnectionNegotiatorManager]: No matching negotiator
[log] D/[Connection]: State: XmppConnectionState.Ready
[log] ---Xmpp Sending:---
[log] <iq id="WXMUPIVMC" type="get">
  <query xmlns="jabber:iq:roster"/>
</iq>
[log] ---Xmpp Sending:---
[log] <presence/>
[log] D/[Connection]: State: XmppConnectionState.Reconnecting
CB-SDK: CubeChatConnection: Chat connection Reconnecting
CB-SDK: CubeChatConnection: Chat connection SocketOpening
CB-SDK: CubeChatConnection: Chat connection SocketOpened
[log] D/[Connection]: State: XmppConnectionState.SocketOpening
[log] D/[Connection]: State: XmppConnectionState.SocketOpened
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='10'/>
[log] D/[StreamManagementModule]: Delivered: PYVOEKXSM
[log] D/[StreamManagementModule]: Delivered: ATFCNAPNI
[log] D/[StreamManagementModule]: Delivered: KSQBNQUHG
[log] D/[StreamManagementModule]: Delivered: WXMUPIVMC
[log] D/[StreamManagementModule]: Delivered stanza without id presence
[log] D/[StreamManagementModule]: Delivered: XNDTBERWB
[log] D/[StreamManagementModule]: Delivered: KIWDGSMPK
[log] D/[StreamManagementModule]: Delivered: JXQBNVOWS
[log] D/[StreamManagementModule]: Delivered: AHMBNTWDN

[log] ---Xmpp Sending:---
[log] <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mec…M2QjJENEEzMjNCRTdFRDQxNjE5MTkyMUUwRDg3NzI=</auth>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true

CB-SDK: : [getReadDeliveredWidget] messageIsRead
CB-SDK: : [getReadDeliveredWidget] messageIsRead
CB-SDK: : [getReadDeliveredWidget] messageIsRead
CB-SDK: : [getReadDeliveredWidget] messageIsRead
CB-SDK: : [getReadDeliveredWidget] messageIsRead
CB-SDK: : [getReadDeliveredWidget] messageIsRead
[log] ---Xmpp Receiving:---
CB-SDK: CubeChatConnection: Chat connection Authenticated
[log] <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] D/[Connection]: State: XmppConnectionState.Authenticated
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <enable xmlns="urn:xmpp:sm:3"/>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] D/[ConnectionNegotiatorManager]: Feature Started Parsing
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …9f4c-deb07d93cae7' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…f:params:xml:ns:xmpp-session"/></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="MPUANWKYS" type="set">
  <bind xmlns="urn:…AuMCBTYWZhcmkvNTM3LjM2</resource>
  </bind>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <enabled xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Receiving:---
[log] <stream:error xmlns:stream="http://etherx.jabber.o…se xmlns='urn:ietf:params:xml:ns:xmpp-framing' />

WebSocketChannelException: WebSocket connection failed.
[log] D/[Connection]: Handle connection done
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
[log] D/[Connection]: State: XmppConnectionState.ForcefullyClosed
[log] D/[ReconnectionManager]: Connection forcefully closed!
[log] D/[ReconnectionManager]: TimeOut is: 10000 reconnection counter 0
[log] ---Xmpp Receiving:---
[log] <iq id="MPUANWKYS" xmlns="jabber:client" type="res…zEwNS4wLjAuMCBTYWZhcmkvNTM3LjM2</jid></bind></iq>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="KJKAUAGTA" type="set" to="chat.connectycub…lns="urn:ietf:params:xml:ns:xmpp-session"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="1"/>
[log] ---Xmpp Receiving:---
[log] <iq id="KJKAUAGTA" xmlns="jabber:client" from="cha…NrbykgQ2hyb21lLzEwNS4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
[log] D/[Connection]: State: XmppConnectionState.SessionInitialized
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---


quangvinhvux avatar Oct 07 '22 06:10 quangvinhvux

could you please provide all the requested before info

could you please provide the full log from starting the app till getting this issue? could you provide the steps on how we can reproduce this issue in our samples? Please provide the log without any cutting

TatankaConCube avatar Oct 07 '22 08:10 TatankaConCube

  POST https://api.connectycube.com/session 
HEADERS
  {Content-type: application/json, ConnectyCube-REST-API-Version: 0.1.1, CB-SDK: Flutter 2.2.3, CB-Token: }
BODY
  {"application_id":"5833","auth_key":"HL6VcqSOPFgQNWs","nonce":"1045337992","timestamp":"1666023704","signature":"b3c3d3a495b26116cac8d04043964beafc3ef28b","user":{"login":"eyJraWQiOiJDUXVlWDkzMmd0WEJZTUxKc0lMU0JTeW9nbTBsKzBtZjAxMzN0WVRWSWQwPSIsImFsZyI6IlJTMjU2In0.eyJzdWIiOiJhYmE4MDNhZC02YmNkLTQyMTktYjQ1MC00MmFhNGM5NzhjYmIiLCJlbWFpbF92ZXJpZmllZCI6dHJ1ZSwiaXNzIjoiaHR0cHM6XC9cL2NvZ25pdG8taWRwLmFwLXNvdXRoZWFzdC0xLmFtYXpvbmF3cy5jb21cL2FwLXNvdXRoZWFzdC0xX21lYjZtR1NjQiIsImNvZ25pdG86dXNlcm5hbWUiOiJhYmE4MDNhZC02YmNkLTQyMTktYjQ1MC00MmFhNGM5NzhjYmIiLCJjdXN0b206cHJvZmlsZV9pZCI6IjYzMDFkOTE4ZTFkMmQ4MDAwOWY5Y2ZhMyIsIm9yaWdpbl9qdGkiOiJiMmVkZDNiOS0wODQwLTQyYzgtOGY5OC01ZWIxNWVjMmFlOTciLCJhdWQiOiJ2a3AyMXU3aDJjdjBsY2VtbDVnczBzYWxsIiwiZXZlbnRfaWQiOiJmZTZmYTdmYS1kOTNmLTQwYjgtYmQ1Zi02YmI3NzIwODJjNTciLCJ0b2tlbl91c2UiOiJpZCIsImF1dGhfdGltZSI6MTY2NjAyMjYwMSwibmFtZSI6IlZ1eCIsImV4cCI6MTY2NjAyNjIwMSwiY3VzdG9tOnJvbGUiOiIyIiwiaWF0IjoxNjY2MDIyNjAxLCJqdGkiOiIxZjA3MDQ3Mi01NWM5LTQ3NmYtYTRkNS1jNmEzYmYyYmJhNDIiLCJlbWFpbCI6InF1YW5ndmluaHZ1eCt4eGMxQGdtYWlsLmNvbSJ9.WgAM0W-FySEM9cT3vaKaGvWtbj-Pitfthl3dzXb-dL14J84aBZhORcCUJRP4U-eBKaNWYtDibd6maKgxHk5oxTUUAesjB-3felZdQmoYq6Zi1UCOne_bRZdF_BaUcCo6s5QE6eFKGFV6z7fzNKVNV5lKaGvKFYfm9qknr7Fk1tkSXW-QwD2e_oHHZdrCdWIiTzTkpT2sRZCb3aVst7PyrBDXSBdGycwsJLk_VClL4mcjv00xyD1ZkUfql2MJcIIbVdvl0nrOyrq7uV5ctCiwaeKPxhpG5QcsyY3lpZLjLNyPGytcNZVtPx87119qdZ025f2XFOf_oiVQTuwpHuzG3w","password":"E49EAEB0EC60EA4557BF919F4DDCCA54857F"}}

CB-SDK: : *********************************************************
*** RESPONSE *** 201 *** 4fc7ed7a-4989-467f-aba0-ef5d594148b9 ***
HEADERS
  {cb-token-expirationdate: 2022-10-17 18:21:46 UTC, content-length: 812, content-type: application/json; charset=utf-8}
BODY
  {"session":{"created_at":"2022-10-17T16:21:46.866Z","updated_at":"2022-10-17T16:21:46.866Z","application_id":5833,"token":"7F8718CB5781F6293B01F166BCBF9F2BC3E1","nonce":1045337992,"ts":1666023704,"user_id":6268434,"user":{"_id":"6301d9355b0f88003b73e746","id":6268434,"created_at":"2022-08-21T07:05:25Z","updated_at":"2022-10-17T16:21:46Z","last_request_at":"2022-10-17T16:19:36Z","login":"custom_6301d918e1d2d80009f9cfa3","full_name":"Vuxxxxxxxxxx","avatar":"https://dev-modeltalk.s3.ap-southeast-1.amazonaws.com/media/6301d918e1d2d80009f9cfa3/avatar/6301d918e1d2d80009f9cfa3AVATAR.jpg?1662965633804","external_id":"6301d918e1d2d80009f9cfa3","timezone":null,"email":null,"phone":null,"website":null,"twitter_id":null,"external_user_id":null,"facebook_id":null,"custom_data":null,"user_tags":null},"id":6268434}}

CB-SDK: : =========================================================
=== REQUEST ==== 35da2a04-32b4-43ac-99de-711c9d5bf9d0 ===
REQUEST
  POST https://api.connectycube.com/login 
HEADERS
  {Content-type: application/json, ConnectyCube-REST-API-Version: 0.1.1, CB-SDK: Flutter 2.2.3, CB-Token: 7F8718CB5781F6293B01F166BCBF9F2BC3E1}
BODY
  {"login":"eyJraWQiOiJDUXVlWDkzMmd0WEJZTUxKc0lMU0JTeW9nbTBsKzBtZjAxMzN0WVRWSWQwPSIsImFsZyI6IlJTMjU2In0.eyJzdWIiOiJhYmE4MDNhZC02YmNkLTQyMTktYjQ1MC00MmFhNGM5NzhjYmIiLCJlbWFpbF92ZXJpZmllZCI6dHJ1ZSwiaXNzIjoiaHR0cHM6XC9cL2NvZ25pdG8taWRwLmFwLXNvdXRoZWFzdC0xLmFtYXpvbmF3cy5jb21cL2FwLXNvdXRoZWFzdC0xX21lYjZtR1NjQiIsImNvZ25pdG86dXNlcm5hbWUiOiJhYmE4MDNhZC02YmNkLTQyMTktYjQ1MC00MmFhNGM5NzhjYmIiLCJjdXN0b206cHJvZmlsZV9pZCI6IjYzMDFkOTE4ZTFkMmQ4MDAwOWY5Y2ZhMyIsIm9yaWdpbl9qdGkiOiJiMmVkZDNiOS0wODQwLTQyYzgtOGY5OC01ZWIxNWVjMmFlOTciLCJhdWQiOiJ2a3AyMXU3aDJjdjBsY2VtbDVnczBzYWxsIiwiZXZlbnRfaWQiOiJmZTZmYTdmYS1kOTNmLTQwYjgtYmQ1Zi02YmI3NzIwODJjNTciLCJ0b2tlbl91c2UiOiJpZCIsImF1dGhfdGltZSI6MTY2NjAyMjYwMSwibmFtZSI6IlZ1eCIsImV4cCI6MTY2NjAyNjIwMSwiY3VzdG9tOnJvbGUiOiIyIiwiaWF0IjoxNjY2MDIyNjAxLCJqdGkiOiIxZjA3MDQ3Mi01NWM5LTQ3NmYtYTRkNS1jNmEzYmYyYmJhNDIiLCJlbWFpbCI6InF1YW5ndmluaHZ1eCt4eGMxQGdtYWlsLmNvbSJ9.WgAM0W-FySEM9cT3vaKaGvWtbj-Pitfthl3dzXb-dL14J84aBZhORcCUJRP4U-eBKaNWYtDibd6maKgxHk5oxTUUAesjB-3felZdQmoYq6Zi1UCOne_bRZdF_BaUcCo6s5QE6eFKGFV6z7fzNKVNV5lKaGvKFYfm9qknr7Fk1tkSXW-QwD2e_oHHZdrCdWIiTzTkpT2sRZCb3aVst7PyrBDXSBdGycwsJLk_VClL4mcjv00xyD1ZkUfql2MJcIIbVdvl0nrOyrq7uV5ctCiwaeKPxhpG5QcsyY3lpZLjLNyPGytcNZVtPx87119qdZ025f2XFOf_oiVQTuwpHuzG3w"}

CB-SDK: : *********************************************************
*** RESPONSE *** 200 *** 35da2a04-32b4-43ac-99de-711c9d5bf9d0 ***
HEADERS
  {cb-token-expirationdate: 2022-10-17 18:21:48 UTC, content-length: 585, content-type: application/json; charset=utf-8}
BODY
  {"user":{"_id":"6301d9355b0f88003b73e746","id":6268434,"created_at":"2022-08-21T07:05:25Z","updated_at":"2022-10-17T16:21:48Z","last_request_at":"2022-10-17T16:19:36Z","login":"custom_6301d918e1d2d80009f9cfa3","full_name":"Vuxxxxxxxxxx","avatar":"https://dev-modeltalk.s3.ap-southeast-1.amazonaws.com/media/6301d918e1d2d80009f9cfa3/avatar/6301d918e1d2d80009f9cfa3AVATAR.jpg?1662965633804","external_id":"6301d918e1d2d80009f9cfa3","timezone":null,"email":null,"phone":null,"website":null,"twitter_id":null,"external_user_id":null,"facebook_id":null,"custom_data":null,"user_tags":null}}

CB-SDK: CubeChatConnection: [login] userId: 6268434, null
CB-SDK: CubeChatConnection: Chat connection SocketOpening
CB-SDK: CubeChatConnection: Chat connection SocketOpened
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …8d11-1dc23c82060c' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…f:params:xml:ns:xmpp-session"/></stream:features>
CB-SDK: CubeChatConnection: Chat connection Authenticated
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="LTEBHHPGR" type="set">
  <bind xmlns="urn:…AuMCBTYWZhcmkvNTM3LjM2</resource>
  </bind>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <enabled xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Receiving:---
[log] <iq id="LTEBHHPGR" xmlns="jabber:client" type="res…zEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2</jid></bind></iq>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="TPVNAUQJW" type="set" to="chat.connectycub…lns="urn:ietf:params:xml:ns:xmpp-session"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="466"/>
[log] ---Xmpp Sending:---
[log] <r xmlns="urn:xmpp:sm:3"/>
[log] ---Xmpp Receiving:---
[log] <iq id="TPVNAUQJW" xmlns="jabber:client" from="cha…NrbykgQ2hyb21lLzEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
[log] D/[Connection]: State: XmppConnectionState.SessionInitialized
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="JOYXRSUVR" type="get" from="6268434-5833@c…s="http://jabber.org/protocol/disco#info"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---

WebSocketChannelException: WebSocket connection failed.
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
Error: Unsupported operation: set length
dart-sdk/lib/_internal/js_dev_runtime/private/ddc_runtime/errors.dart 266:49  throw_
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 67:7              checkGrowable]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 545:5             length]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 194:5             clear]
packages/sweetie/ui/message/stores/message_store.dart 211:13                  clearData
packages/sweetie/ui/message/stores/message_store.g.dart 160:20                clearData
packages/sweetie/ui/message/stores/message_store.dart 15:7                    clearData
packages/sweetie/ui/message/stores/message_store.dart 52:11                   <fn>
dart-sdk/lib/async/zone.dart 1407:13                                          _rootRunUnary
dart-sdk/lib/async/zone.dart 1589:7                                           runUnaryGuarded
dart-sdk/lib/async/stream_impl.dart 339:11                                    [_sendData]
dart-sdk/lib/async/stream_impl.dart 515:13                                    perform
dart-sdk/lib/async/stream_impl.dart 620:10                                    handleNext
dart-sdk/lib/async/stream_impl.dart 591:7                                     <fn>
dart-sdk/lib/async/zone.dart 1383:47                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          <fn>
dart-sdk/lib/async/zone.dart 1391:13                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          callback
dart-sdk/lib/async/schedule_microtask.dart 40:11                              _microtaskLoop
dart-sdk/lib/async/schedule_microtask.dart 49:5                               _startMicrotaskLoop
dart-sdk/lib/_internal/js_dev_runtime/patch/async_patch.dart 166:15           <fn>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="481"/>
CB-SDK: CubeChatConnection: Chat connection Ready
>>> saveProfile 0
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='2'/>
[log] ---Xmpp Receiving:---
CB-SDK: : =========================================================
=== REQUEST ==== e32dcbcb-6d32-4f1d-8fb5-65c8e7398cc3 ===
REQUEST
  DELETE https://api.connectycube.com/session/list 
HEADERS
  {Content-type: application/json, ConnectyCube-REST-API-Version: 0.1.1, CB-SDK: Flutter 2.2.3, CB-Token: 7F8718CB5781F6293B01F166BCBF9F2BC3E1}
BODY


[log] <iq id="JOYXRSUVR" xmlns="jabber:client" from="cha…n:ietf:params:xml:ns:xmpp-session"/></query></iq>
[log] E/[ConnectionNegotiatorManager]: ADDING FEATURES c…, Instance of 'Feature$', Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Adding negotiator…te.DONE}, isReady: false [Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: No matching negotiator
[log] D/[Connection]: State: XmppConnectionState.Ready
[log] ---Xmpp Sending:---
[log] <iq id="LQFBTQADY" type="get">
  <query xmlns="jabber:iq:roster"/>
</iq>
[log] ---Xmpp Sending:---
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
CB-SDK: : *********************************************************
*** RESPONSE *** 404 *** e32dcbcb-6d32-4f1d-8fb5-65c8e7398cc3 ***
HEADERS
  {content-length: 70, content-type: text/plain; charset=utf-8}
BODY
  HttpException: E_ROUTE_NOT_FOUND: Route not found DELETE /session/list


ResponseException: 404: HttpException: E_ROUTE_NOT_FOUND: Route not found DELETE /session/list
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="496"/>
[log] ---Xmpp Sending:---
[log] <r xmlns="urn:xmpp:sm:3"/>
[log] ---Xmpp Receiving:---
[log] <iq id="LQFBTQADY" xmlns="jabber:client" type="res…vNTM3LjM2"><query xmlns="jabber:iq:roster"/></iq>
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="511"/>
[log] ---Xmpp Receiving:---
[log] <presence xmlns="jabber:client" from="6268434-5833…M3LjM2" to="[email protected]"/>
[log] D/[StanzaParser]: No id found for stanza
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
CB-SDK: CubeChatConnection: Chat connection Reconnecting
CB-SDK: CubeChatConnection: Chat connection SocketOpening
CB-SDK: CubeChatConnection: Chat connection SocketOpened
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="526"/>
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='5'/>
[log] ---Xmpp Sending:---
[log] <r xmlns="urn:xmpp:sm:3"/>
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='5'/>
[log] D/[Connection]: State: XmppConnectionState.SocketOpening
[log] D/[Connection]: State: XmppConnectionState.SocketOpened
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …97f2-272a436442e4' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…hod>zlib</method></compression></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mec…hDQjU3ODFGNjI5M0IwMUYxNjZCQ0JGOUYyQkMzRTE=</auth>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
CB-SDK: CubeChatConnection: Chat connection Authenticated

WebSocketChannelException: WebSocket connection failed.
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
Error: Unsupported operation: set length
dart-sdk/lib/_internal/js_dev_runtime/private/ddc_runtime/errors.dart 266:49  throw_
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 67:7              checkGrowable]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 545:5             length]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 194:5             clear]
packages/sweetie/ui/message/stores/message_store.dart 211:13                  clearData
packages/sweetie/ui/message/stores/message_store.g.dart 160:20                clearData
packages/sweetie/ui/message/stores/message_store.dart 15:7                    clearData
packages/sweetie/ui/message/stores/message_store.dart 52:11                   <fn>
dart-sdk/lib/async/zone.dart 1407:13                                          _rootRunUnary
dart-sdk/lib/async/zone.dart 1589:7                                           runUnaryGuarded
dart-sdk/lib/async/stream_impl.dart 339:11                                    [_sendData]
dart-sdk/lib/async/stream_impl.dart 515:13                                    perform
dart-sdk/lib/async/stream_impl.dart 620:10                                    handleNext
dart-sdk/lib/async/stream_impl.dart 591:7                                     <fn>
dart-sdk/lib/async/zone.dart 1383:47                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          <fn>
dart-sdk/lib/async/zone.dart 1391:13                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          callback
dart-sdk/lib/async/schedule_microtask.dart 40:11                              _microtaskLoop
dart-sdk/lib/async/schedule_microtask.dart 49:5                               _startMicrotaskLoop
dart-sdk/lib/_internal/js_dev_runtime/patch/async_patch.dart 166:15           <fn>
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
CB-SDK: CubeChatConnection: Chat connection Ready
CB-SDK: CubeChatConnection: Chat connection Reconnecting
CB-SDK: CubeChatConnection: Chat connection SocketOpening
CB-SDK: CubeChatConnection: Chat connection SocketOpened
CB-SDK: CubeChatConnection: Chat connection Authenticated

WebSocketChannelException: WebSocket connection failed.
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
Error: Unsupported operation: set length
dart-sdk/lib/_internal/js_dev_runtime/private/ddc_runtime/errors.dart 266:49  throw_
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 67:7              checkGrowable]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 545:5             length]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 194:5             clear]
packages/sweetie/ui/message/stores/message_store.dart 211:13                  clearData
packages/sweetie/ui/message/stores/message_store.g.dart 160:20                clearData
packages/sweetie/ui/message/stores/message_store.dart 15:7                    clearData
packages/sweetie/ui/message/stores/message_store.dart 52:11                   <fn>
dart-sdk/lib/async/zone.dart 1407:13                                          _rootRunUnary
dart-sdk/lib/async/zone.dart 1589:7                                           runUnaryGuarded
dart-sdk/lib/async/stream_impl.dart 339:11                                    [_sendData]
dart-sdk/lib/async/stream_impl.dart 515:13                                    perform
dart-sdk/lib/async/stream_impl.dart 620:10                                    handleNext
dart-sdk/lib/async/stream_impl.dart 591:7                                     <fn>
dart-sdk/lib/async/zone.dart 1383:47                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          <fn>
dart-sdk/lib/async/zone.dart 1391:13                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          callback
dart-sdk/lib/async/schedule_microtask.dart 40:11                              _microtaskLoop
dart-sdk/lib/async/schedule_microtask.dart 49:5                               _startMicrotaskLoop
dart-sdk/lib/_internal/js_dev_runtime/patch/async_patch.dart 166:15           <fn>
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
CB-SDK: CubeChatConnection: Chat connection Ready
dart-sdk/lib/_internal/js_dev_runtime/private/ddc_runtime/errors.dart 266:49  throw_
dart-sdk/lib/async/future_impl.dart 15:79                                     completeError
packages/dio/src/adapters/browser_adapter.dart 70:22                          <fn>
dart-sdk/lib/async/zone.dart 1399:47                                          _rootRunUnary
dart-sdk/lib/async/zone.dart 951:19                                           runUnary
packages/mobx/src/api/async/async_action.dart 57:28                           [_runUnary]
dart-sdk/lib/async/zone.dart 1300:19                                          runUnary
dart-sdk/lib/async/future_impl.dart 147:18                                    handleValue
dart-sdk/lib/async/future_impl.dart 766:44                                    handleValueCallback
dart-sdk/lib/async/future_impl.dart 795:13                                    _propagateToListeners
dart-sdk/lib/async/future_impl.dart 557:7                                     [_complete]
dart-sdk/lib/async/future.dart 421:15                                         <fn>
dart-sdk/lib/async/zone.dart 1383:47                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          <fn>
dart-sdk/lib/async/zone.dart 1391:13                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1225:23                                          <fn>
dart-sdk/lib/_internal/js_dev_runtime/private/isolate_helper.dart 48:19       internalCallback
Bad state: Future already completed
[log] ---Xmpp Receiving:---
[log] <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] D/[Connection]: State: XmppConnectionState.Authenticated
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <enable xmlns="urn:xmpp:sm:3"/>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] D/[ConnectionNegotiatorManager]: Feature Started Parsing
[log] ---Xmpp Sending:---
[log] <r xmlns="urn:xmpp:sm:3"/>
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …97f2-272a436442e4' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='5'/>
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…f:params:xml:ns:xmpp-session"/></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="KGYMITGOP" type="set">
  <bind xmlns="urn:…AuMCBTYWZhcmkvNTM3LjM2</resource>
  </bind>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <enabled xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Receiving:---
[log] <stream:error xmlns:stream="http://etherx.jabber.o…se xmlns='urn:ietf:params:xml:ns:xmpp-framing' />
[log] ---Xmpp Receiving:---
[log] <iq id="KGYMITGOP" xmlns="jabber:client" type="res…zEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2</jid></bind></iq>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="PFGCVHHVP" type="set" to="chat.connectycub…lns="urn:ietf:params:xml:ns:xmpp-session"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] D/[Connection]: Handle connection done
[log] D/[Connection]: State: XmppConnectionState.ForcefullyClosed
[log] D/[ReconnectionManager]: Connection forcefully closed!
[log] D/[ReconnectionManager]: TimeOut is: 10000 reconnection counter 0
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="1"/>
[log] ---Xmpp Receiving:---
[log] <iq id="PFGCVHHVP" xmlns="jabber:client" from="cha…NrbykgQ2hyb21lLzEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
[log] D/[Connection]: State: XmppConnectionState.SessionInitialized
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="NOYSYKBLN" type="get" from="6268434-5833@c…s="http://jabber.org/protocol/disco#info"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="2"/>
[log] ---Xmpp Receiving:---
[log] <iq id="NOYSYKBLN" xmlns="jabber:client" from="cha…n:ietf:params:xml:ns:xmpp-session"/></query></iq>
[log] E/[ConnectionNegotiatorManager]: ADDING FEATURES c…, Instance of 'Feature$', Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Adding negotiator…ate.IDLE}, isReady: true [Instance of 'Feature$']
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="SXXDGIFNU" type="set" xmlns="jabber:client">
  <enable xmlns="urn:xmpp:carbons:2"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="3"/>
[log] ---Xmpp Receiving:---
[log] <iq id="SXXDGIFNU" xmlns="jabber:client" type="res…NrbykgQ2hyb21lLzEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: No matching negotiator
[log] D/[Connection]: State: XmppConnectionState.Ready
[log] ---Xmpp Sending:---
[log] <iq id="IQRKVHHFL" type="get">
  <query xmlns="jabber:iq:roster"/>
</iq>
[log] ---Xmpp Sending:---
[log] <presence/>
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="4"/>
[log] ---Xmpp Receiving:---
[log] <iq id="IQRKVHHFL" xmlns="jabber:client" type="res…vNTM3LjM2"><query xmlns="jabber:iq:roster"/></iq>
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="5"/>
[log] ---Xmpp Receiving:---
[log] <presence xmlns="jabber:client" from="6268434-5833…M3LjM2" to="[email protected]"/>
[log] D/[StanzaParser]: No id found for stanza
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="6"/>
[log] ---Xmpp Sending:---
[log] <r xmlns="urn:xmpp:sm:3"/>
[log] ---Xmpp Receiving:---
[log] <a xmlns='urn:xmpp:sm:3' h='6'/>
[log] D/[StreamManagementModule]: Delivered: PFGCVHHVP
[log] D/[StreamManagementModule]: Delivered: NOYSYKBLN
[log] D/[StreamManagementModule]: Delivered: SXXDGIFNU
[log] D/[StreamManagementModule]: Delivered: IQRKVHHFL
[log] D/[StreamManagementModule]: Delivered stanza without id presence
[log] D/[Connection]: State: XmppConnectionState.Reconnecting
[log] D/[Connection]: State: XmppConnectionState.SocketOpening
[log] D/[Connection]: State: XmppConnectionState.SocketOpened
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …b865-e206b207b272' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…hod>zlib</method></compression></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mec…VCMEVDNjBFQTQ1NTdCRjkxOUY0RERDQ0E1NDg1N0Y=</auth>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>
CB-SDK: CubeChatConnection: Chat connection Reconnecting
CB-SDK: CubeChatConnection: Chat connection SocketOpening
CB-SDK: CubeChatConnection: Chat connection SocketOpened
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] D/[Connection]: State: XmppConnectionState.Authenticated
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <enable xmlns="urn:xmpp:sm:3"/>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] D/[ConnectionNegotiatorManager]: Feature Started Parsing
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …b865-e206b207b272' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…f:params:xml:ns:xmpp-session"/></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mec…hDQjU3ODFGNjI5M0IwMUYxNjZCQ0JGOUYyQkMzRTE=</auth>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
CB-SDK: CubeChatConnection: Chat connection Authenticated
[log] ---Xmpp Receiving:---
[log] <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>
[log] ---Xmpp Sending:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' to='chat.connectycube.com' version='1.0'/>
[log] D/[Connection]: State: XmppConnectionState.Authenticated
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <enable xmlns="urn:xmpp:sm:3"/>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] D/[ConnectionNegotiatorManager]: Feature Started Parsing
[log] ---Xmpp Receiving:---
[log] <open xmlns='urn:ietf:params:xml:ns:xmpp-framing' …a236-e69c3581a042' version='1.0' xml:lang='en' />
[log] ---Xmpp Receiving:---
[log] <enabled xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Receiving:---
[log] <stream:features xmlns:stream="http://etherx.jabbe…f:params:xml:ns:xmpp-session"/></stream:features>
[log] D/[ConnectionNegotiatorManager]: Negotiating features
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="ANIPUVCDK" type="set">
  <bind xmlns="urn:…AuMCBTYWZhcmkvNTM3LjM2</resource>
  </bind>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>

WebSocketChannelException: WebSocket connection failed.
CB-SDK: CubeChatConnection: Chat connection ForcefullyClosed
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
Error: Unsupported operation: set length
dart-sdk/lib/_internal/js_dev_runtime/private/ddc_runtime/errors.dart 266:49  throw_
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 67:7              checkGrowable]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 545:5             length]
dart-sdk/lib/_internal/js_dev_runtime/private/js_array.dart 194:5             clear]
packages/sweetie/ui/message/stores/message_store.dart 211:13                  clearData
packages/sweetie/ui/message/stores/message_store.g.dart 160:20                clearData
packages/sweetie/ui/message/stores/message_store.dart 15:7                    clearData
packages/sweetie/ui/message/stores/message_store.dart 52:11                   <fn>
dart-sdk/lib/async/zone.dart 1407:13                                          _rootRunUnary
dart-sdk/lib/async/zone.dart 1589:7                                           runUnaryGuarded
dart-sdk/lib/async/stream_impl.dart 339:11                                    [_sendData]
dart-sdk/lib/async/stream_impl.dart 515:13                                    perform
dart-sdk/lib/async/stream_impl.dart 620:10                                    handleNext
dart-sdk/lib/async/stream_impl.dart 591:7                                     <fn>
dart-sdk/lib/async/zone.dart 1383:47                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          <fn>
dart-sdk/lib/async/zone.dart 1391:13                                          _rootRun
dart-sdk/lib/async/zone.dart 944:19                                           run
packages/mobx/src/api/async/async_action.dart 43:28                           [_run]
dart-sdk/lib/async/zone.dart 1293:19                                          run
dart-sdk/lib/async/zone.dart 1201:7                                           runGuarded
dart-sdk/lib/async/zone.dart 1241:23                                          callback
dart-sdk/lib/async/schedule_microtask.dart 40:11                              _microtaskLoop
dart-sdk/lib/async/schedule_microtask.dart 49:5                               _startMicrotaskLoop
dart-sdk/lib/_internal/js_dev_runtime/patch/async_patch.dart 166:15           <fn>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="686"/>
[log] ---Xmpp Receiving:---
[log] <stream:error xmlns:stream="http://etherx.jabber.o…se xmlns='urn:ietf:params:xml:ns:xmpp-framing' />
[log] D/[Connection]: Handle connection done
[log] D/[Connection]: State: XmppConnectionState.ForcefullyClosed
[log] D/[ReconnectionManager]: Connection forcefully closed!
[log] D/[ReconnectionManager]: TimeOut is: 10000 reconnection counter 0
[log] ---Xmpp Receiving:---
[log] <iq id="ANIPUVCDK" xmlns="jabber:client" type="res…zEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2</jid></bind></iq>
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator true
[log] ---Xmpp Sending:---
[log] <iq id="XRUONHBTQ" type="set" to="chat.connectycub…lns="urn:ietf:params:xml:ns:xmpp-session"/>
</iq>
[log] D/[ConnectionNegotiatorManager]: ACTIVE FEATURE: {…tate: NegotiatorState.NEGOTIATING}, isReady: true
[log] ---Xmpp Receiving:---
[log] <r xmlns='urn:xmpp:sm:3' />
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
CB-SDK: CubeChatConnection: Chat connection SessionInitialized
CB-SDK: CubeChatConnection: Chat connection Ready
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Sending:---
[log] <a xmlns="urn:xmpp:sm:3" h="705"/>
[log] ---Xmpp Receiving:---
[log] <iq id="XRUONHBTQ" xmlns="jabber:client" from="cha…NrbykgQ2hyb21lLzEwNi4wLjAuMCBTYWZhcmkvNTM3LjM2"/>
[log] D/[Connection]: State: XmppConnectionState.SessionInitialized
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: Found matching negotiator false
[log] D/[ConnectionNegotiatorManager]: No matching negotiator
[log] D/[Connection]: State: XmppConnectionState.Ready
[log] ---Xmpp Sending:---
[log] <iq id="LGHIMVBEL" type="get">
  <query xmlns="jabber:iq:roster"/>

quangvinhvux avatar Oct 17 '22 16:10 quangvinhvux

@TatankaConCube i sent full log start from create session, and it keep continue Reconnecting and closed

quangvinhvux avatar Oct 17 '22 16:10 quangvinhvux

how can we reproduce it on our sample?

TatankaConCube avatar Oct 18 '22 08:10 TatankaConCube

could you provide the steps on how we can reproduce this issue in our samples?

I asked it too, could you please provide all the required info, it will help us to investigate the issue faster

TatankaConCube avatar Oct 20 '22 12:10 TatankaConCube

hi @TatankaConCube, I have tried to reproduce it on the samples project but can't. I'm using a custom identity provider, I log in to multiple devices then it happens.

quangvinhvux avatar Oct 22 '22 04:10 quangvinhvux

looks the same issue with https://github.com/ConnectyCube/connectycube-flutter-samples/issues/247

TatankaConCube avatar Apr 07 '23 13:04 TatankaConCube