socket.io-client-swift icon indicating copy to clipboard operation
socket.io-client-swift copied to clipboard

socket event listeners not removing after disconnect in objective c

Open NarasimhaDo opened this issue 7 years ago • 11 comments

I'm disconnecting socket from client side. whenever server is restarted on connect method is triggering.

NarasimhaDo avatar Dec 17 '18 07:12 NarasimhaDo

Can you post some logs around this happening?

nuclearace avatar Dec 17 '18 12:12 nuclearace

2018-12-17 17:50:10.799123+0530 DemoApp[2382:681711] connecting to socket 2018-12-17 17:50:10.869857+0530 DemoApp[2382:681711] socket connected :: xNDgfWoRILV6arciAACz (socketID) 2018-12-17 17:51:07.822233+0530 DemoApp[2382:681711] User logging out -> socket disconnecting 2018-12-17 17:51:07.824516+0530 DemoApp[2382:681711] socket disconnected from client

-------------------- socket server restarted --------------------

2018-12-17 17:53:52.101874+0530 DemoApp[2382:681711] socket connected :: rGlir5Q9AKiqeXPZAAAH (socketID)

Notes : After socket server got restarted , client is receiving onConnect method called automatically (should not, as the client was already logged out (disconnected))

NarasimhaDo avatar Dec 17 '18 12:12 NarasimhaDo

Can you post the actual client logs? I want to see what the engine/manager/client think they should do in this case.

nuclearace avatar Dec 17 '18 12:12 nuclearace

2018-12-17 18:29:30.442719+0530 DemoApp[19774:531010] libMobileGestalt MobileGestalt.c:890: MGIsDeviceOneOfType is not supported on this platform. 2018-12-17 18:29:30.669262+0530 DemoApp[19774:531010] the launch options is (null) 2018-12-17 18:29:30.669449+0530 DemoApp[19774:531010] the launch options is (null) 2018-12-17 18:29:30.851201+0530 DemoApp[19774:531010] the network connected to wifi 1 2018-12-17 18:29:30.881476+0530 DemoApp[19774:531010] db path is /Users/narasimhudu/Library/Developer/CoreSimulator/Devices/F530DA8D-7614-484D-8E73-2F919CC2C0AD/data/Containers/Data/Application/124FABFB-002B-41F8-88CA-F5F05627546B/Documents/DemoApp.db 2018-12-17 18:29:31.026244+0530 DemoApp[19774:531293] the network connected to wifi 2 2018-12-17 18:29:31.028496+0530 DemoApp[19774:531293] the network connected to wifi 2 2018-12-17 18:29:32.004458+0530 DemoApp[19774:531010] the search string is *** 2018-12-17 18:29:32.012219+0530 DemoApp[19774:531010] Push registration success. 2018-12-17 18:29:32.033757+0530 DemoApp[19774:531287] 4.9.0 - [Firebase/Analytics][I-ACS023007] Firebase Analytics v.40009000 started 2018-12-17 18:29:32.035019+0530 DemoApp[19774:531287] 4.9.0 - [Firebase/Analytics][I-ACS023008] To enable debug logging set the following application argument: -FIRAnalyticsDebugEnabled (see http://goo.gl/RfcP7r) 2018-12-17 18:29:32.187433+0530 DemoApp[19774:531311] 4.9.0 - [Firebase/Messaging][I-FCM001000] FIRMessaging Remote Notifications proxy enabled, will swizzle remote notification receiver handlers. If you'd prefer to manually integrate Firebase Messaging, add "FirebaseAppDelegateProxyEnabled" to your Info.plist, and set it to NO. Follow the instructions at: https://firebase.google.com/docs/cloud-messaging/ios/client#method_swizzling_in_firebase_messaging to ensure proper integration. 2018-12-17 18:29:32.194418+0530 DemoApp[19774:531010] the generate fcm token is 152 2018-12-17 18:29:32.289234+0530 DemoApp[19774:531010] user_id available 2018-12-17 18:29:32.292752+0530 DemoApp[19774:531010] the dict is <SocketIO.SocketIOClient: 0x600001eb5560> 2018-12-17 18:29:32.293068+0530 DemoApp[19774:531010] connecting socket 2018-12-17 18:29:32.299259+0530 DemoApp[19774:531272] 4.9.0 - [Firebase/Messaging][I-FCM012002] Error in application:didFailToRegisterForRemoteNotificationsWithError: remote notifications are not supported in the simulator 2018-12-17 18:29:32.301541+0530 DemoApp[19774:531272] 4.9.0 - [Firebase/Messaging][I-FCM012002] Error in application:didFailToRegisterForRemoteNotificationsWithError: remote notifications are not supported in the simulator 2018-12-17 18:29:32.324998+0530 DemoApp[19774:531319] the data is voip not exist { message = success; success = 1; } 2018-12-17 18:29:32.377231+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_online_users 2018-12-17 18:29:32.377623+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: receive_message 2018-12-17 18:29:32.377759+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: typing 2018-12-17 18:29:32.377874+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: message_sent 2018-12-17 18:29:32.378024+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: message_read 2018-12-17 18:29:32.378177+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: message_delivered 2018-12-17 18:29:32.378320+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: group_updated 2018-12-17 18:29:32.378428+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: group_created 2018-12-17 18:29:32.378593+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: new_online 2018-12-17 18:29:32.378753+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: new_offline 2018-12-17 18:29:32.378944+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_new_users_available_status 2018-12-17 18:29:32.379333+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: new_dnd 2018-12-17 18:29:32.379693+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: unread_messages_count 2018-12-17 18:29:32.379840+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: user_created 2018-12-17 18:29:32.380116+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_user_last_seen 2018-12-17 18:29:32.380274+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: user_unread_messages_count 2018-12-17 18:29:32.380539+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_missing_messages 2018-12-17 18:29:32.380790+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: sync_offline_messages 2018-12-17 18:29:32.381074+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: logout 2018-12-17 18:29:32.381310+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: user_pic_updated 2018-12-17 18:29:32.382259+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: disconnect 2018-12-17 18:29:32.382499+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: socket_error 2018-12-17 18:29:32.382801+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: video_call_user 2018-12-17 18:29:32.405643+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: video_call_permission 2018-12-17 18:29:32.405843+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: video_signal 2018-12-17 18:29:32.406040+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: stop_video_call 2018-12-17 18:29:32.406167+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: screen_share_permission 2018-12-17 18:29:32.406326+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: sharing_signal 2018-12-17 18:29:32.406469+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: stop_screen_sharing 2018-12-17 18:29:32.406650+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: share_screen 2018-12-17 18:29:32.407503+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: is_calling_screen_active 2018-12-17 18:29:32.407831+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: account_verified 2018-12-17 18:29:32.408023+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_active_devices 2018-12-17 18:29:32.408320+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: delete_message 2018-12-17 18:29:32.408622+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: recall_message 2018-12-17 18:29:32.408977+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Closing socket 2018-12-17 18:29:32.409309+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Disconnected: Namespace leave 2018-12-17 18:29:32.433712+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [disconnected] 2018-12-17 18:29:32.434245+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: disconnect with data: ["Namespace leave"] 2018-12-17 18:29:32.434615+0530 DemoApp[19774:531010] the socket disconnect ( "Namespace leave" ) ***** 2018-12-17 18:29:32.435592+0530 DemoApp[19774:531010] socket disconnected 2018-12-17 18:29:32.435704+0530 DemoApp[19774:531010] the socket disconnet data is called 2018-12-17 18:29:32.436012+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: private_chat_permission 2018-12-17 18:29:32.436142+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: end_private_chat 2018-12-17 18:29:32.436300+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: check_private_chat 2018-12-17 18:29:32.436413+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: init_private_chat 2018-12-17 18:29:32.436547+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: get_all_burnouts 2018-12-17 18:29:32.436740+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: user_status_updated 2018-12-17 18:29:32.436858+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: reconnect 2018-12-17 18:29:32.436969+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: disconnect 2018-12-17 18:29:32.437122+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Adding handler for event: connect 2018-12-17 18:29:32.437247+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [connecting] 2018-12-17 18:29:32.437400+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Joining namespace / 2018-12-17 18:29:32.437625+0530 DemoApp[19774:531010] LOG SocketManager: Tried connecting socket when engine isn't open. Connecting 2018-12-17 18:29:32.437783+0530 DemoApp[19774:531010] LOG SocketManager: Adding engine 2018-12-17 18:29:32.439508+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:29:32.453071+0530 DemoApp[19774:531287] LOG SocketEngine: Starting engine. Server: http://192.168.2.55:8080 2018-12-17 18:29:32.453263+0530 DemoApp[19774:531287] LOG SocketEngine: Handshaking 2018-12-17 18:29:32.453688+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ 2018-12-17 18:29:32.460525+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Got polling response 2018-12-17 18:29:32.460728+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Got poll message: 96:0{"sid":"mEseJQeozIo0A9f_AAAh","upgrades":["websocket"],"pingInterval":25000,"pingTimeout":5000}2:40 2018-12-17 18:29:32.461118+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 0{"sid":"mEseJQeozIo0A9f_AAAh","upgrades":["websocket"],"pingInterval":25000,"pingTimeout":5000} 2018-12-17 18:29:32.464502+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 40 2018-12-17 18:29:32.464777+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=mEseJQeozIo0A9f_AAAh 2018-12-17 18:29:32.465986+0530 DemoApp[19774:531319] LOG SocketEngine: Writing poll: has data: false 2018-12-17 18:29:32.466337+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Sending poll: as type: 2 2018-12-17 18:29:32.467099+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Created POST string: 1:2 2018-12-17 18:29:32.467649+0530 DemoApp[19774:531319] LOG SocketEnginePolling: POSTing 2018-12-17 18:29:32.468153+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Doing polling POST http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=mEseJQeozIo0A9f_AAAh 2018-12-17 18:29:32.473909+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Got polling response 2018-12-17 18:29:32.474071+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Got poll message: 33:42["new_online",{"user_id":"33"}]96:42["get_users_available_status",{"online":["138","54","6","46","70","33","102","157"],"dnd":[]}] 2018-12-17 18:29:32.474507+0530 DemoApp[19774:531287] LOG SocketEngine: Got message: 42["new_online",{"user_id":"33"}] 2018-12-17 18:29:32.474915+0530 DemoApp[19774:531287] LOG SocketEngine: Got message: 42["get_users_available_status",{"online":["138","54","6","46","70","33","102","157"],"dnd":[]}] 2018-12-17 18:29:32.475691+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=mEseJQeozIo0A9f_AAAh 2018-12-17 18:29:32.477364+0530 DemoApp[19774:531272] LOG SocketEngineWebSocket: Sending ws: probe as type: 2 2018-12-17 18:29:32.480787+0530 DemoApp[19774:531272] LOG SocketEnginePolling: Got polling response 2018-12-17 18:29:32.480953+0530 DemoApp[19774:531272] LOG SocketEnginePolling: Got poll message: 1:3 2018-12-17 18:29:32.481096+0530 DemoApp[19774:531272] LOG SocketEngine: Got message: 3 2018-12-17 18:29:32.481595+0530 DemoApp[19774:531272] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=mEseJQeozIo0A9f_AAAh 2018-12-17 18:29:32.482911+0530 DemoApp[19774:531287] LOG SocketEngine: Got message: 3probe 2018-12-17 18:29:32.484339+0530 DemoApp[19774:531287] LOG SocketEngine: Received probe response, should upgrade to WebSockets 2018-12-17 18:29:32.484906+0530 DemoApp[19774:531287] LOG SocketEngine: Upgrading transport to WebSockets 2018-12-17 18:29:32.485541+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Sending poll: as type: 6 2018-12-17 18:29:32.486476+0530 DemoApp[19774:531287] LOG SocketEnginePolling: Created POST string: 1:6 2018-12-17 18:29:32.487036+0530 DemoApp[19774:531287] LOG SocketEnginePolling: POSTing 2018-12-17 18:29:32.595194+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Got polling response 2018-12-17 18:29:32.595452+0530 DemoApp[19774:531319] LOG SocketEnginePolling: Got poll message: 1:6 2018-12-17 18:29:32.595708+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 6 2018-12-17 18:29:32.595830+0530 DemoApp[19774:531319] LOG SocketEngine: Switching to WebSockets 2018-12-17 18:29:32.595944+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: as type: 5 2018-12-17 18:29:32.596127+0530 DemoApp[19774:531319] LOG SocketEngine: Flushing probe wait 2018-12-17 18:29:32.731544+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: ping with data: [] 2018-12-17 18:29:32.732017+0530 DemoApp[19774:531010] LOG SocketManager: Engine opened Connect 2018-12-17 18:29:32.732325+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Socket connected 2018-12-17 18:29:32.732593+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [connected] 2018-12-17 18:29:32.733592+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: connect with data: ["/"] 2018-12-17 18:29:32.734872+0530 DemoApp[19774:531010] socket connceted :: mEseJQeozIo0A9f_AAAh 2018-12-17 18:29:32.735146+0530 DemoApp[19774:531010] the suer ID 33 2018-12-17 18:29:32.736040+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 21["version_management",{"app_version":"1.1.10"}] 2018-12-17 18:29:32.736352+0530 DemoApp[19774:531319] LOG SocketEngine: Writing ws: 21["version_management",{"app_version":"1.1.10"}] has data: false 2018-12-17 18:29:32.736499+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: 21["version_management",{"app_version":"1.1.10"}] as type: 4 2018-12-17 18:29:32.736636+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] 2018-12-17 18:29:33.179058+0530 DemoApp[19774:531319] LOG SocketEngine: Writing ws: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] has data: false 2018-12-17 18:29:33.179137+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["sync_undelivered_messages",{"user_id":"33"}] 2018-12-17 18:29:33.179223+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] as type: 4 2018-12-17 18:29:33.179343+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["user_unread_messages_count",{"user_id":"33"}] 2018-12-17 18:29:33.179380+0530 DemoApp[19774:531319] LOG SocketEngine: Writing ws: 2["sync_undelivered_messages",{"user_id":"33"}] has data: false 2018-12-17 18:29:33.179572+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: 2["sync_undelivered_messages",{"user_id":"33"}] as type: 4 2018-12-17 18:29:33.179754+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 22["get_all_burnouts",{}] 2018-12-17 18:29:33.179986+0530 DemoApp[19774:531319] LOG SocketEngine: Writing ws: 2["user_unread_messages_count",{"user_id":"33"}] has data: false 2018-12-17 18:29:33.180619+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: 2["user_unread_messages_count",{"user_id":"33"}] as type: 4 2018-12-17 18:29:33.180948+0530 DemoApp[19774:531319] LOG SocketEngine: Writing ws: 22["get_all_burnouts",{}] has data: false 2018-12-17 18:29:33.181188+0530 DemoApp[19774:531319] LOG SocketEngineWebSocket: Sending ws: 22["get_all_burnouts",{}] as type: 4 2018-12-17 18:29:33.181472+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 431[{"success":false,"message":"No updates"}] 2018-12-17 18:29:33.182015+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 42["get_new_users_available_status",{"online":["6","33","46","54","70","138"],"dnd":[]}] 2018-12-17 18:29:33.189109+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["get_missing_messages",{"message_ids":["6096","6095","6094","6093","6092","6091","6090","6089","6088","6087","6086","6085","6084","6083","6082","6081","6080","6079","6078","6077","6076","6075","6074","6073","6072","6071","6070","6069","6068","6067","6066","6065","6064","6063","6062","6061","6060","6059","6058","6057","6056","6055","6054","6053","6052","6051","6050","6046","6045","6044","6043","6042","6041","6040","6018","6017","6016","6014","6012","6011","6010","6009","6008","6007","6006","6005","6004","6003","6002","6001","6000","5999","5998","5997","5996","5995","5994","5993","5992","5991","5990","5989","5988","5987","5986","5985","5984","5983","5982","5981","5980","5979","5978","5977","5976","5975","5974","5973","5972","5971"],"user_id":"33"}] 2018-12-17 18:29:33.189444+0530 DemoApp[19774:531311] LOG SocketEngine: Writing ws: 2["get_missing_messages",{"message_ids":["6096","6095","6094","6093","6092","6092018-12-17 18:29:33.189546+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 23["get_all_burnouts",{}] 1","6090","6089","6088","6087","6086","6085","6084","6083","6082","6081","6080","6079","6078","6077","6076","6075","6074","6073","6072","6071","6070","6069","6068","6067","6066","6065","6064","6063","6062","6061","6060","6059","6058","6057","6056","6055","6054","6053","6052","6051","6050","6046","6045","6044","6043","6042","6041","6040","6018","6017","6016","6014","6012","6011","6010","6009","6008","6007","6006","6005","6004","6003","6002","6001","6000","5999","5998","5997","5996","5995","5994","5993","5992","5991","5990","5989","5988","5987","5986","5985","5984","5983","5982","5981","5980","5979","5978","5977","5976","5975","5974","5973","5972","5971"],"user_id":"33"}] has data: false 2018-12-17 18:29:33.190062+0530 DemoApp[19774:531311] LOG SocketEngineWebSocket: Sending ws: 2["get_missing_messages",{"message_ids":["6096","6095","6094","6093","6092","6091","6090","6089","6088","6087","6086","6085","6084","6083","6082","6081","6080","6079","6078","6077","6076","6075","6074","6073","6072","6071","6070","6069","6068","6067","6066","6065","6064","6063","6062","6061","6060","6059","6058","6057","6056","6055","6054","6053","6052","6051","6050","6046","6045","6044","6043","6042","6041","6040","6018","6017","6016","6014","6012","6011","6010","6009","6008","6007","6006","6005","6004","6003","6002","6001","6000","5999","5998","5997","5996","5995","5994","5993","5992","5991","5990","5989","5988","5987","5986","5985","5984","5983","5982","5981","5980","5979","5978","5977","5976","5975","5974","5973","5972","5971"],"user_id":"33"}] as type: 4 2018-12-17 18:29:33.190078+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 0 2018-12-17 18:29:33.211417+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 0; data: []; id: -1; placeholders: 0; nsp: /} 2018-12-17 18:29:33.211429+0530 DemoApp[19774:531311] LOG SocketEngine: Writing ws: 23["get_all_burnouts",{}] has data: false 2018-12-17 18:29:33.211589+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["new_online",{"user_id":"33"}] 2018-12-17 18:29:33.211639+0530 DemoApp[19774:531311] LOG SocketEngineWebSocket: Sending ws: 23["get_all_burnouts",{}] as type: 4 2018-12-17 18:29:33.211935+0530 DemoApp[19774:531311] LOG SocketEngine: Got message: 42["user_unread_messages_count",[{"unread_messages":1,"entity_type":1,"entity_id":6}]] 2018-12-17 18:29:33.211943+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [new_online, { "user_id" = 33; }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.212078+0530 DemoApp[19774:531311] LOG SocketEngine: Got message: 432[{"success":true,"data":[]}] 2018-12-17 18:29:33.212550+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: new_online with data: [{ "user_id" = 33; }] 2018-12-17 18:29:33.213315+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] 2018-12-17 18:29:33.213497+0530 DemoApp[19774:531311] LOG SocketEngine: Writing ws: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] has data: false 2018-12-17 18:29:33.213524+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["get_users_available_status",{"online":["138","54","6","46","70","33","102","157"],"dnd":[]}] 2018-12-17 18:29:33.213628+0530 DemoApp[19774:531311] LOG SocketEngineWebSocket: Sending ws: 2["get_new_users_available_status",{"user_id":"33","token":"0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ"}] as type: 4 2018-12-17 18:29:33.213800+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [get_users_available_status, { dnd = ( ); online = ( 138, 54, 6, 46, 70, 33, 102, 157 ); }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.214281+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: get_users_available_status with data: [{ dnd = ( ); online = ( 138, 54, 6, 46, 70, 33, 102, 157 ); }] 2018-12-17 18:29:33.214515+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: pong with data: [] 2018-12-17 18:29:33.214775+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: pong with data: [] 2018-12-17 18:29:33.216315+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 31[{"success":false,"message":"No updates"}] 2018-12-17 18:29:33.216588+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 3; data: [{ message = "No updates"; success = 0; }]; id: 1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.216737+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling ack: 1 with data: [{ message = "No updates"; success = 0; }] 2018-12-17 18:29:33.216970+0530 DemoApp[19774:531010] teh data is ( { message = "No updates"; success = 0; } ) 2018-12-17 18:29:33.217126+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["get_new_users_available_status",{"online":["6","33","46","54","70","138"],"dnd":[]}] 2018-12-17 18:29:33.217340+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [get_new_users_available_status, { dnd = ( ); online = ( 6, 33, 46, 54, 70, 138 ); }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.217502+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: get_new_users_available_status with data: [{ dnd = ( ); online = ( 6, 33, 46, 54, 70, 138 ); }] 2018-12-17 18:29:33.218054+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["user_unread_messages_count",[{"unread_messages":1,"entity_type":1,"entity_id":6}]] 2018-12-17 18:29:33.218399+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [user_unread_messages_count, <__NSArrayM 0x6000034e4960>( { "entity_id" = 6; "entity_type" = 1; "unread_messages" = 1; } ) ]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.218611+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: user_unread_messages_count with data: [<__NSArrayM 0x6000034e4960>( { "entity_id" = 6; "entity_type" = 1; "unread_messages" = 1; } ) ] 2018-12-17 18:29:33.219228+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 32[{"success":true,"data":[]}] 2018-12-17 18:29:33.219461+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 3; data: [{ data = ( ); success = 1; }]; id: 2; placeholders: -1; nsp: /} 2018-12-17 18:29:33.219610+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling ack: 2 with data: [{ data = ( ); success = 1; }] 2018-12-17 18:29:33.219755+0530 DemoApp[19774:531010] get all burnout list ( { data = ( ); success = 1; } ) 2018-12-17 18:29:33.221530+0530 DemoApp[19774:531010] Statement FAILED is message exist or not (no more rows available) 2018-12-17 18:29:33.253000+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 433[{"success":true,"data":[]}] 2018-12-17 18:29:33.253424+0530 DemoApp[19774:531319] LOG SocketEngine: Got message: 42["get_new_users_available_status",{"online":["6","33","46","54","70","138"],"dnd":[]}] 2018-12-17 18:29:33.277393+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 33[{"success":true,"data":[]}] 2018-12-17 18:29:33.277714+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 3; data: [{ data = ( ); success = 1; }]; id: 3; placeholders: -1; nsp: /} 2018-12-17 18:29:33.277879+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling ack: 3 with data: [{ data = ( ); success = 1; }] 2018-12-17 18:29:33.278021+0530 DemoApp[19774:531010] the burn out list data is ( { data = ( ); success = 1; } ) 2018-12-17 18:29:33.278139+0530 DemoApp[19774:531010] get all burnout list ( { data = ( ); success = 1; } ) 2018-12-17 18:29:33.280103+0530 DemoApp[19774:531010] Statement FAILED is message exist or not (no more rows available) 2018-12-17 18:29:33.280797+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["get_new_users_available_status",{"online":["6","33","46","54","70","138"],"dnd":[]}] 2018-12-17 18:29:33.281322+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [get_new_users_available_status, { dnd = ( ); online = ( 6, 33, 46, 54, 70, 138 ); }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:29:33.281551+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: get_new_users_available_status with data: [{ dnd = ( ); online = ( 6, 33, 46, 54, 70, 138 ); }] 2018-12-17 18:29:35.382499+0530 DemoApp[19774:531010] the time is 2018-12-17 18:29:32 2018-12-17 18:29:35.382837+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 24["sync_messages_by_time",{"time":"2018-12-17 18:29:32"}] 2018-12-17 18:29:35.383013+0530 DemoApp[19774:531311] LOG SocketEngine: Writing ws: 24["sync_messages_by_time",{"time":"2018-12-17 18:29:32"}] has data: false 2018-12-17 18:29:35.383188+0530 DemoApp[19774:531311] LOG SocketEngineWebSocket: Sending ws: 24["sync_messages_by_time",{"time":"2018-12-17 18:29:32"}] as type: 4 2018-12-17 18:29:35.408828+0530 DemoApp[19774:531294] LOG SocketEngine: Got message: 434[{"success":true,"data":[]}] 2018-12-17 18:29:35.409207+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 34[{"success":true,"data":[]}] 2018-12-17 18:29:35.409599+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 3; data: [{ data = ( ); success = 1; }]; id: 4; placeholders: -1; nsp: /} 2018-12-17 18:29:35.409791+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling ack: 4 with data: [{ data = ( ); success = 1; }] 2018-12-17 18:29:35.409928+0530 DemoApp[19774:531010] teh delete and recall data is ( { data = ( ); success = 1; } ) 2018-12-17 18:29:35.737510+0530 DemoApp[19774:531010] teh data is ( "NO ACK" ) 2018-12-17 18:29:35.737765+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 0 being released 2018-12-17 18:29:35.737978+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 1 being released 2018-12-17 18:29:38.437760+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 2 being released 2018-12-17 18:29:38.437954+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 3 being released 2018-12-17 18:29:38.438107+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 4 being released 2018-12-17 18:29:59.847673+0530 DemoApp[19774:531334] LOG SocketEngine: Writing ws: has data: false 2018-12-17 18:29:59.847670+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: ping with data: [] 2018-12-17 18:29:59.847860+0530 DemoApp[19774:531334] LOG SocketEngineWebSocket: Sending ws: as type: 2 2018-12-17 18:29:59.849172+0530 DemoApp[19774:531334] LOG SocketEngine: Got message: 3 2018-12-17 18:29:59.849386+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: pong with data: [] 2018-12-17 18:30:24.848564+0530 DemoApp[19774:531294] LOG SocketEngine: Writing ws: has data: false 2018-12-17 18:30:24.848564+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: ping with data: [] 2018-12-17 18:30:24.848764+0530 DemoApp[19774:531294] LOG SocketEngineWebSocket: Sending ws: as type: 2 2018-12-17 18:30:24.850042+0530 DemoApp[19774:531294] LOG SocketEngine: Got message: 3 2018-12-17 18:30:24.850216+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: pong with data: [] 2018-12-17 18:30:28.426799+0530 DemoApp[19774:531010] appdelegate search string is 2018-12-17 18:30:31.546341+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Closing socket 2018-12-17 18:30:31.546596+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Disconnected: Namespace leave 2018-12-17 18:30:31.546642+0530 DemoApp[19774:531785] LOG SocketEngine: Writing ws: 1/, has data: false 2018-12-17 18:30:31.546739+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [disconnected] 2018-12-17 18:30:31.546750+0530 DemoApp[19774:531785] LOG SocketEngineWebSocket: Sending ws: 1/, as type: 4 2018-12-17 18:30:31.546888+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: disconnect with data: ["Namespace leave"] 2018-12-17 18:30:31.547012+0530 DemoApp[19774:531010] the socket disconnect ( "Namespace leave" ) ***** 2018-12-17 18:30:31.548116+0530 DemoApp[19774:531010] socket starting disconnect 2018-12-17 18:30:31.549144+0530 DemoApp[19774:531010] socket disconnected from client 2018-12-17 18:30:31.551054+0530 DemoApp[19774:531010] Statement FAILED is message exist or not (no more rows available) 2018-12-17 18:30:31.946163+0530 DemoApp[19774:531010] Statement FAILED is message exist or not (no more rows available)

2018-12-17 18:30:31.972611+0530 DemoApp[19774:531010] [<__NSCFString 0x60000342bdb0> valueForUndefinedKey:]: this class is not key value coding-compliant for the key api_key. 2018-12-17 18:30:31.973424+0530 DemoApp[19774:531010] the socket is is (null) ******** (null) 2018-12-17 18:30:32.007298+0530 DemoApp[19774:531294] the data is voip not exist { errorCode = 2; message = "UnAuthorized Request"; success = 0; } 2018-12-17 18:30:32.166431+0530 DemoApp[19774:531010] [MC] System group container for systemgroup.com.apple.configurationprofiles path is /Users/narasimhudu/Library/Developer/CoreSimulator/Devices/F530DA8D-7614-484D-8E73-2F919CC2C0AD/data/Containers/Shared/SystemGroup/systemgroup.com.apple.configurationprofiles 2018-12-17 18:30:32.166916+0530 DemoApp[19774:531010] [MC] Reading from private effective user settings. 2018-12-17 18:30:45.412006+0530 DemoApp[19774:531010] LOG SocketManager: Starting reconnect 2018-12-17 18:30:45.412177+0530 DemoApp[19774:531010] LOG SocketManager: Trying to reconnect 2018-12-17 18:30:45.412401+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: reconnectAttempt with data: [-1] 2018-12-17 18:30:45.412698+0530 DemoApp[19774:531785] LOG SocketEngine: Starting engine. Server: http://192.168.2.55:8080 2018-12-17 18:30:45.412806+0530 DemoApp[19774:531785] LOG SocketEngine: Handshaking 2018-12-17 18:30:45.413082+0530 DemoApp[19774:531785] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ 2018-12-17 18:30:45.518390+0530 DemoApp[19774:532341] [] nw_socket_handle_socket_event [C11:2] Socket SO_ERROR [61: Connection refused] 2018-12-17 18:30:45.519352+0530 DemoApp[19774:532341] TIC TCP Conn Failed [11:0x600000fab300]: 1:61 Err(61) 2018-12-17 18:30:45.521195+0530 DemoApp[19774:532341] Task <97D240BE-3490-4377-8781-DD183D4D22CB>.<1> HTTP load failed (error code: -1004 [1:61]) 2018-12-17 18:30:45.521463+0530 DemoApp[19774:531785] Task <97D240BE-3490-4377-8781-DD183D4D22CB>.<1> finished with error - code: -1004 2018-12-17 18:30:45.539693+0530 DemoApp[19774:532341] ERROR SocketEnginePolling: Could not connect to the server. 2018-12-17 18:30:45.539977+0530 DemoApp[19774:532341] ERROR SocketEngine: Could not connect to the server. 2018-12-17 18:30:45.540139+0530 DemoApp[19774:531010] ERROR SocketManager: Could not connect to the server. 2018-12-17 18:30:45.540276+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Could not connect to the server."] 2018-12-17 18:30:55.413137+0530 DemoApp[19774:531010] LOG SocketManager: Trying to reconnect 2018-12-17 18:30:55.413339+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: reconnectAttempt with data: [-2] 2018-12-17 18:30:55.413571+0530 DemoApp[19774:532342] LOG SocketEngine: Starting engine. Server: http://192.168.2.55:8080 2018-12-17 18:30:55.413702+0530 DemoApp[19774:532342] LOG SocketEngine: Handshaking 2018-12-17 18:30:55.414041+0530 DemoApp[19774:532342] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ 2018-12-17 18:30:55.421212+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Got polling response 2018-12-17 18:30:55.421365+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Got poll message: 96:0{"sid":"WZiCBS7K7Qvt8gqRAAAG","upgrades":["websocket"],"pingInterval":25000,"pingTimeout":5000}2:40 2018-12-17 18:30:55.421529+0530 DemoApp[19774:532454] LOG SocketEngine: Got message: 0{"sid":"WZiCBS7K7Qvt8gqRAAAG","upgrades":["websocket"],"pingInterval":25000,"pingTimeout":5000} 2018-12-17 18:30:55.422910+0530 DemoApp[19774:532454] LOG SocketEngine: Got message: 40 2018-12-17 18:30:55.422910+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: ping with data: [] 2018-12-17 18:30:55.423165+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Doing polling GET http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=WZiCBS7K7Qvt8gqRAAAG 2018-12-17 18:30:55.429243+0530 DemoApp[19774:531010] LOG SocketManager: Engine opened Connect 2018-12-17 18:30:55.429384+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Socket connected 2018-12-17 18:30:55.429502+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [connected] 2018-12-17 18:30:55.429437+0530 DemoApp[19774:532454] LOG SocketEngine: Writing poll: has data: false 2018-12-17 18:30:55.429636+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Sending poll: as type: 2 2018-12-17 18:30:55.429675+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: connect with data: ["/"] 2018-12-17 18:30:55.430248+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Created POST string: 1:2 2018-12-17 18:30:55.430459+0530 DemoApp[19774:531010] socket connceted :: WZiCBS7K7Qvt8gqRAAAG 2018-12-17 18:30:55.430920+0530 DemoApp[19774:532454] LOG SocketEnginePolling: POSTing 2018-12-17 18:30:55.431080+0530 DemoApp[19774:531010] No user login 2018-12-17 18:30:55.431294+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Doing polling POST http://192.168.2.55:8080/socket.io/?transport=polling&b64=1&platform=4&is_background=0&user_id=33&device_name=iPhone%208%20Plus&token=0jhWvsQF04rVSwNjeJ0g5xy8lS7rPVEQ&sid=WZiCBS7K7Qvt8gqRAAAG 2018-12-17 18:30:55.431639+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["force_disconnect"] 2018-12-17 18:30:55.432026+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Closing socket 2018-12-17 18:30:55.432241+0530 DemoApp[19774:532454] LOG SocketEngineWebSocket: Sending ws: probe as type: 2 2018-12-17 18:30:55.432421+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Disconnected: Namespace leave 2018-12-17 18:30:55.433015+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [disconnected] 2018-12-17 18:30:55.433246+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: disconnect with data: ["Namespace leave"] 2018-12-17 18:30:55.433526+0530 DemoApp[19774:531010] the socket disconnect ( "Namespace leave" ) ***** 2018-12-17 18:30:55.434664+0530 DemoApp[19774:531010] socket starting disconnect

-------------------- socket server restarted --------------------

2018-12-17 18:30:55.435118+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:55.435140+0530 DemoApp[19774:532454] LOG SocketEngine: Got message: 3probe 2018-12-17 18:30:55.435357+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting get_new_users_available_status when not connected"] 2018-12-17 18:30:55.435518+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting user_unread_messages_count when not connected"] 2018-12-17 18:30:55.435798+0530 DemoApp[19774:532454] LOG SocketEngine: Received probe response, should upgrade to WebSockets 2018-12-17 18:30:55.436721+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:55.436977+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 0 2018-12-17 18:30:55.436977+0530 DemoApp[19774:532454] LOG SocketEngine: Upgrading transport to WebSockets 2018-12-17 18:30:55.437139+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 0; data: []; id: -1; placeholders: 0; nsp: /} 2018-12-17 18:30:55.437244+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Socket connected 2018-12-17 18:30:55.437372+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [connected] 2018-12-17 18:30:55.437437+0530 DemoApp[19774:532454] LOG SocketEnginePolling: Sending poll: as type: 6 2018-12-17 18:30:55.437508+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: connect with data: ["/"] 2018-12-17 18:30:55.437707+0530 DemoApp[19774:531010] socket connceted :: WZiCBS7K7Qvt8gqRAAAG 2018-12-17 18:30:55.438277+0530 DemoApp[19774:531010] No user login 2018-12-17 18:30:55.438470+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Emitting: 2["force_disconnect"] 2018-12-17 18:30:55.438637+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Closing socket 2018-12-17 18:30:55.439020+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Disconnected: Namespace leave 2018-12-17 18:30:55.439166+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: statusChange with data: [disconnected] 2018-12-17 18:30:55.439466+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: disconnect with data: ["Namespace leave"] 2018-12-17 18:30:55.439638+0530 DemoApp[19774:531010] the socket disconnect ( "Namespace leave" ) ***** 2018-12-17 18:30:55.439937+0530 DemoApp[19774:531010] socket starting disconnect 2018-12-17 18:30:55.440624+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:55.440887+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting get_new_users_available_status when not connected"] 2018-12-17 18:30:55.441063+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting user_unread_messages_count when not connected"] 2018-12-17 18:30:55.442225+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:55.442514+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: pong with data: [] 2018-12-17 18:30:55.651695+0530 DemoApp[19774:532342] LOG SocketEnginePolling: Got polling response 2018-12-17 18:30:55.651879+0530 DemoApp[19774:532342] LOG SocketEnginePolling: Got poll message: 33:42["new_online",{"user_id":"33"}]78:42["get_users_available_status",{"online":["46","6","54","70","33"],"dnd":[]}] 2018-12-17 18:30:55.652038+0530 DemoApp[19774:532342] LOG SocketEngine: Got message: 42["new_online",{"user_id":"33"}] 2018-12-17 18:30:55.652233+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["new_online",{"user_id":"33"}] 2018-12-17 18:30:55.652275+0530 DemoApp[19774:532342] LOG SocketEngine: Got message: 42["get_users_available_status",{"online":["46","6","54","70","33"],"dnd":[]}] 2018-12-17 18:30:55.653011+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [new_online, { "user_id" = 33; }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:30:55.653107+0530 DemoApp[19774:532342] LOG SocketEngine: Switching to WebSockets 2018-12-17 18:30:55.653988+0530 DemoApp[19774:531010] LOG SocketParser: Parsing 2["get_users_available_status",{"online":["46","6","54","70","33"],"dnd":[]}] 2018-12-17 18:30:55.654291+0530 DemoApp[19774:532342] LOG SocketEngineWebSocket: Sending ws: as type: 5 2018-12-17 18:30:55.654946+0530 DemoApp[19774:531010] LOG SocketParser: Decoded packet as: SocketPacket {type: 2; data: [get_users_available_status, { dnd = ( ); online = ( 46, 6, 54, 70, 33 ); }]; id: -1; placeholders: -1; nsp: /} 2018-12-17 18:30:55.655207+0530 DemoApp[19774:532342] LOG SocketEngine: Flushing probe wait 2018-12-17 18:30:55.656568+0530 DemoApp[19774:532342] LOG SocketEngine: Writing ws: 2["force_disconnect"] has data: false 2018-12-17 18:30:55.669594+0530 DemoApp[19774:532342] LOG SocketEngineWebSocket: Sending ws: 2["force_disconnect"] as type: 4 2018-12-17 18:30:55.669801+0530 DemoApp[19774:532342] LOG SocketEngine: Writing ws: 1/, has data: false 2018-12-17 18:30:55.669947+0530 DemoApp[19774:532342] LOG SocketEngineWebSocket: Sending ws: 1/, as type: 4 2018-12-17 18:30:55.670105+0530 DemoApp[19774:532342] LOG SocketEngine: Writing ws: 2["force_disconnect"] has data: false 2018-12-17 18:30:55.670234+0530 DemoApp[19774:532342] LOG SocketEngineWebSocket: Sending ws: 2["force_disconnect"] as type: 4 2018-12-17 18:30:55.670399+0530 DemoApp[19774:532342] LOG SocketEngine: Writing ws: 1/, has data: false 2018-12-17 18:30:55.670552+0530 DemoApp[19774:532342] LOG SocketEngineWebSocket: Sending ws: 1/, as type: 4 2018-12-17 18:30:57.501370+0530 DemoApp[19774:531010] the time is 2018-12-17 18:30:55 2018-12-17 18:30:57.501665+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:57.502397+0530 DemoApp[19774:531010] the time is 2018-12-17 18:30:55 2018-12-17 18:30:57.502648+0530 DemoApp[19774:531010] LOG SocketIOClient{/}: Handling event: error with data: ["Tried emitting when not connected"] 2018-12-17 18:30:58.595905+0530 DemoApp[19774:531010] teh data is ( "NO ACK" ) 2018-12-17 18:30:58.596527+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 5 being released 2018-12-17 18:30:58.596898+0530 DemoApp[19774:531010] teh data is ( "NO ACK" ) 2018-12-17 18:30:58.597279+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 7 being released 2018-12-17 18:31:00.631464+0530 DemoApp[19774:531010] the burn out list data is ( "NO ACK" ) 2018-12-17 18:31:00.631733+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 6 being released 2018-12-17 18:31:00.631887+0530 DemoApp[19774:531010] the burn out list data is ( "NO ACK" ) 2018-12-17 18:31:00.632006+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 8 being released 2018-12-17 18:31:00.632120+0530 DemoApp[19774:531010] teh delete and recall data is ( "NO ACK" ) 2018-12-17 18:31:00.632245+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 9 being released 2018-12-17 18:31:00.632362+0530 DemoApp[19774:531010] teh delete and recall data is ( "NO ACK" ) 2018-12-17 18:31:00.632474+0530 DemoApp[19774:531010] LOG OnAckCallback: OnAckCallback for 10 being released 2018-12-17 18:31:01.817526+0530 DemoApp[19774:531010] [<__NSCFString 0x6000034fee20> valueForUndefinedKey:]: this class is not key value coding-compliant for the key api_key. 2018-12-17 18:31:01.818563+0530 DemoApp[19774:531010] [<__NSCFString 0x6000034fd800> valueForUndefinedKey:]: this class is not key value coding-compliant for the key api_key.

NarasimhaDo avatar Dec 17 '18 13:12 NarasimhaDo

Hi nuclerace! Have you seen the client log?

NarasimhaDo avatar Dec 19 '18 06:12 NarasimhaDo

Oh I think I misunderstood the issue. Currently the client doesn't remove handlers on a disconnect, you'll have to manually call removeAllHandlers.

nuclearace avatar Dec 19 '18 19:12 nuclearace

Does manually calling removeAllHandlers before your disconnect solve your issue?

nuclearace avatar Dec 19 '18 19:12 nuclearace

Thanks nuclearace, however manually calling removeAllHandlers did not solve the issue. Now at client app we are not receiving onConnect callback after socket.io server restarts, but at the server end the client is reconnected with a new socketID after server is restarted.

NarasimhaDo avatar Dec 21 '18 06:12 NarasimhaDo

@nuclearace, I'm having the same issue. When i disconnect via the socket client everything is fine, no reconnect happens. Now, if the server restarts the client reconnects shortly after. I expect the client to stay disconnected until i specifically reconnect it manually.

A simple test case:

iOS client:

import UIKit
import SocketIO

class ViewController: UIViewController {
  
  private var manager = SocketManager(socketURL: URL(string: "http://localhost:3210")!);

  override func viewDidLoad() {
    super.viewDidLoad()

    let socket = self.manager.defaultSocket
    socket.on("connect") { (data, ack) in
      print("connect")
    }
    socket.on("disconnect") { (data, ack) in
      print("disconnect")
    }
    socket.connect()
    
    DispatchQueue.main.asyncAfter(deadline: .now() + 2.0) {
      socket.disconnect() // just disconnecting the client will make it reconnect after server restart
      //socket.manager?.disconnect() // disconnecting the whole manager works as expected.
    }
  }
}

socket.io node.js server

const io = require('socket.io');
const server = io(3210);

Logs:

2019-02-05 20:16:30.902367+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Adding handler for event: connect
2019-02-05 20:16:30.902948+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Adding handler for event: disconnect
2019-02-05 20:16:30.904117+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: statusChange with data: [connecting]
2019-02-05 20:16:30.904247+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Joining namespace /
2019-02-05 20:16:30.904434+0100 socket-test[76663:9803276] LOG SocketManager: Tried connecting socket when engine isn't open. Connecting
2019-02-05 20:16:30.904544+0100 socket-test[76663:9803276] LOG SocketManager: Adding engine
2019-02-05 20:16:30.907935+0100 socket-test[76663:9803339] LOG SocketEngine: Starting engine. Server: http://localhost:3210
2019-02-05 20:16:30.908061+0100 socket-test[76663:9803339] LOG SocketEngine: Handshaking
2019-02-05 20:16:30.910371+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1
2019-02-05 20:16:30.986352+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:30.987017+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got poll message: 95:0{"sid":"zqWUQ4CoI1Zt2c9mAAAC","upgrades":["websocket"],"pingInterval":2000,"pingTimeout":5000}
2019-02-05 20:16:30.988009+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 0{"sid":"zqWUQ4CoI1Zt2c9mAAAC","upgrades":["websocket"],"pingInterval":2000,"pingTimeout":5000}
2019-02-05 20:16:30.996417+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:30.996441+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1&sid=zqWUQ4CoI1Zt2c9mAAAC
2019-02-05 20:16:30.996565+0100 socket-test[76663:9803276] LOG SocketManager: Engine opened Connect
2019-02-05 20:16:30.996801+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Socket connected
2019-02-05 20:16:30.996932+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: statusChange with data: [connected]
2019-02-05 20:16:30.997202+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: connect with data: ["/"]
2019-02-05 20:16:30.997463+0100 socket-test[76663:9803339] LOG SocketEngine: Writing poll:  has data: false
2019-02-05 20:16:31.047770+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Sending poll:  as type: 2
2019-02-05 20:16:31.048003+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Created POST string: 1:2
2019-02-05 20:16:31.048319+0100 socket-test[76663:9803339] LOG SocketEnginePolling: POSTing
2019-02-05 20:16:31.048448+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Doing polling POST http://localhost:3210/socket.io/?transport=polling&b64=1&sid=zqWUQ4CoI1Zt2c9mAAAC
!!! connect
2019-02-05 20:16:31.048831+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:31.048938+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got poll message: 2:40
2019-02-05 20:16:31.049040+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 40
2019-02-05 20:16:31.049196+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: websocketUpgrade with data: [["sec-websocket-accept": "CBdfUFVHiU4RtQnKjDm7AhVje3o=", "upgrade": "websocket", "connection": "Upgrade"]]
2019-02-05 20:16:31.049208+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1&sid=zqWUQ4CoI1Zt2c9mAAAC
2019-02-05 20:16:31.049450+0100 socket-test[76663:9803276] LOG SocketParser: Parsing 0
2019-02-05 20:16:31.049456+0100 socket-test[76663:9803339] LOG SocketEngineWebSocket: Sending ws: probe as type: 2
2019-02-05 20:16:31.061533+0100 socket-test[76663:9803276] LOG SocketParser: Decoded packet as: SocketPacket {type: 0; data: []; id: -1; placeholders: 0; nsp: /}
2019-02-05 20:16:31.061570+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:31.061686+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Got poll message: 1:3
2019-02-05 20:16:31.061809+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 3
2019-02-05 20:16:31.061942+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:31.061982+0100 socket-test[76663:9803339] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1&sid=zqWUQ4CoI1Zt2c9mAAAC
2019-02-05 20:16:31.099733+0100 socket-test[76663:9803355] LOG SocketEngine: Got message: 3probe
2019-02-05 20:16:31.100036+0100 socket-test[76663:9803355] LOG SocketEngine: Received probe response, should upgrade to WebSockets
2019-02-05 20:16:31.100164+0100 socket-test[76663:9803355] LOG SocketEngine: Upgrading transport to WebSockets
2019-02-05 20:16:31.100339+0100 socket-test[76663:9803355] LOG SocketEnginePolling: Sending poll:  as type: 6
2019-02-05 20:16:31.100506+0100 socket-test[76663:9803355] LOG SocketEnginePolling: Created POST string: 1:6
2019-02-05 20:16:31.100887+0100 socket-test[76663:9803355] LOG SocketEnginePolling: POSTing
2019-02-05 20:16:31.101082+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:31.167047+0100 socket-test[76663:9803355] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:31.167150+0100 socket-test[76663:9803355] LOG SocketEnginePolling: Got poll message: 1:6
2019-02-05 20:16:31.167252+0100 socket-test[76663:9803355] LOG SocketEngine: Got message: 6
2019-02-05 20:16:31.167370+0100 socket-test[76663:9803355] LOG SocketEngine: Switching to WebSockets
2019-02-05 20:16:31.167471+0100 socket-test[76663:9803355] LOG SocketEngineWebSocket: Sending ws:  as type: 5
2019-02-05 20:16:31.167611+0100 socket-test[76663:9803355] LOG SocketEngine: Flushing probe wait
2019-02-05 20:16:33.104174+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Closing socket
2019-02-05 20:16:33.104180+0100 socket-test[76663:9803339] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:33.104358+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Disconnected: Namespace leave
2019-02-05 20:16:33.104337+0100 socket-test[76663:9803339] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:33.104442+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: statusChange with data: [disconnected]
2019-02-05 20:16:33.104507+0100 socket-test[76663:9803339] LOG SocketEngine: Writing ws: 1/, has data: false
2019-02-05 20:16:33.104553+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: disconnect with data: ["Namespace leave"]
!!! dis2019-02-05 20:16:33.104627+0100 socket-test[76663:9803339] LOG SocketEngineWebSocket: Sending ws: 1/, as type: 4
connect
2019-02-05 20:16:33.104754+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:33.105170+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 3
2019-02-05 20:16:33.105311+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:35.299871+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:35.299903+0100 socket-test[76663:9803338] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:35.300150+0100 socket-test[76663:9803338] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:35.301275+0100 socket-test[76663:9803338] LOG SocketEngine: Got message: 3
2019-02-05 20:16:35.301556+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:37.299914+0100 socket-test[76663:9803337] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:37.299914+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:37.300091+0100 socket-test[76663:9803337] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:37.301011+0100 socket-test[76663:9803337] LOG SocketEngine: Got message: 3
2019-02-05 20:16:37.301177+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:39.300008+0100 socket-test[76663:9803339] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:39.300032+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:39.300233+0100 socket-test[76663:9803339] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:39.301442+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 3
2019-02-05 20:16:39.301658+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:41.495863+0100 socket-test[76663:9803337] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:41.495882+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:41.496112+0100 socket-test[76663:9803337] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:41.496958+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 3
2019-02-05 20:16:41.497139+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:43.495970+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:43.495979+0100 socket-test[76663:9803337] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:43.496179+0100 socket-test[76663:9803337] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:43.497004+0100 socket-test[76663:9803339] LOG SocketEngine: Got message: 3
2019-02-05 20:16:43.497157+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:44.658389+0100 socket-test[76663:9803276] LOG SocketManager: Starting reconnect
2019-02-05 20:16:44.658567+0100 socket-test[76663:9803276] LOG SocketManager: Trying to reconnect
2019-02-05 20:16:44.658868+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: reconnectAttempt with data: [-1]
2019-02-05 20:16:44.659208+0100 socket-test[76663:9803337] LOG SocketEngine: Starting engine. Server: http://localhost:3210
2019-02-05 20:16:44.659345+0100 socket-test[76663:9803337] LOG SocketEngine: Handshaking
2019-02-05 20:16:44.659643+0100 socket-test[76663:9803337] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1
2019-02-05 20:16:44.668115+0100 socket-test[76663:9803339] [] nw_socket_handle_socket_event [C4.1:2] Socket SO_ERROR [61: Connection refused]
2019-02-05 20:16:44.669891+0100 socket-test[76663:9803339] [] nw_socket_handle_socket_event [C4.2:2] Socket SO_ERROR [61: Connection refused]
2019-02-05 20:16:44.671055+0100 socket-test[76663:9803339] TIC TCP Conn Failed [4:0x600003e7c000]: 1:61 Err(61)
2019-02-05 20:16:44.675372+0100 socket-test[76663:9803339] Task <8B14817F-C381-4ECD-B75B-7BFAACD70DB2>.<1> HTTP load failed (error code: -1004 [1:61])
2019-02-05 20:16:44.675555+0100 socket-test[76663:9803354] Task <8B14817F-C381-4ECD-B75B-7BFAACD70DB2>.<1> finished with error - code: -1004
2019-02-05 20:16:44.677437+0100 socket-test[76663:9803339] ERROR SocketEnginePolling: Could not connect to the server.
2019-02-05 20:16:44.677664+0100 socket-test[76663:9803339] ERROR SocketEngine: Could not connect to the server.
2019-02-05 20:16:44.677811+0100 socket-test[76663:9803276] ERROR SocketManager: Could not connect to the server.
2019-02-05 20:16:44.677937+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: error with data: ["Could not connect to the server."]
2019-02-05 20:16:55.657754+0100 socket-test[76663:9803276] LOG SocketManager: Trying to reconnect
2019-02-05 20:16:55.658055+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: reconnectAttempt with data: [-2]
2019-02-05 20:16:55.658393+0100 socket-test[76663:9803337] LOG SocketEngine: Starting engine. Server: http://localhost:3210
2019-02-05 20:16:55.658643+0100 socket-test[76663:9803337] LOG SocketEngine: Handshaking
2019-02-05 20:16:55.659111+0100 socket-test[76663:9803337] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1
2019-02-05 20:16:55.668714+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:55.668854+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Got poll message: 95:0{"sid":"a-t5k9s9SzmS4e64AAAB","upgrades":["websocket"],"pingInterval":2000,"pingTimeout":5000}
2019-02-05 20:16:55.668987+0100 socket-test[76663:9803748] LOG SocketEngine: Got message: 0{"sid":"a-t5k9s9SzmS4e64AAAB","upgrades":["websocket"],"pingInterval":2000,"pingTimeout":5000}
2019-02-05 20:16:55.669914+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:55.669972+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1&sid=a-t52019-02-05 20:16:55.670025+0100 socket-test[76663:9803276] LOG SocketManager: Engine opened Connect
k9s9SzmS4e64AAAB
2019-02-05 20:16:55.674466+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Socket connected
2019-02-05 20:16:55.674634+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: statusChange with data: [connected]
2019-02-05 20:16:55.674799+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: connect with data: ["/"]
!!! connect
2019-02-05 20:16:55.675474+0100 socket-test[76663:9803748] LOG SocketEngine: Writing poll:  has data: false
2019-02-05 20:16:55.675678+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Sending poll:  as type: 2
2019-02-05 20:16:55.675874+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Created POST string: 1:2
2019-02-05 20:16:55.676244+0100 socket-test[76663:9803748] LOG SocketEnginePolling: POSTing
2019-02-05 20:16:55.676606+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Doing polling POST http://localhost:3210/socket.io/?transport=polling&b64=1&sid=a-t5k9s9SzmS4e64AAAB
2019-02-05 20:16:55.677290+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: websocketUpgrade with data: [["sec-websocket-accept": "asH7PBPw89BcKPCmSs5XtMh0l0I=", "upgrade": "websocket", "connection": "Upgrade"]]
2019-02-05 20:16:55.677429+0100 socket-test[76663:9803337] LOG SocketEngineWebSocket: Sending ws: probe as type: 2
2019-02-05 20:16:55.678282+0100 socket-test[76663:9803753] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:55.678487+0100 socket-test[76663:9803753] LOG SocketEnginePolling: Got poll message: 2:40
2019-02-05 20:16:55.678685+0100 socket-test[76663:9803753] LOG SocketEngine: Got message: 40
2019-02-05 20:16:55.678943+0100 socket-test[76663:9803276] LOG SocketParser: Parsing 0
2019-02-05 20:16:55.679010+0100 socket-test[76663:9803753] LOG SocketEnginePolling: Doing polling GET http://localhost:3210/socket.io/?transport=polling&b64=1&sid=a-t5k9s9SzmS4e64AAAB
2019-02-05 20:16:55.679235+0100 socket-test[76663:9803276] LOG SocketParser: Decoded packet as: SocketPacket {type: 0; data: []; id: -1; placeholders: 0; nsp: /}
2019-02-05 20:16:55.679596+0100 socket-test[76663:9803753] LOG SocketEngine: Got message: 3probe
2019-02-05 20:16:55.679915+0100 socket-test[76663:9803753] LOG SocketEngine: Received probe response, should upgrade to WebSockets
2019-02-05 20:16:55.680092+0100 socket-test[76663:9803753] LOG SocketEngine: Upgrading transport to WebSockets
2019-02-05 20:16:55.680274+0100 socket-test[76663:9803753] LOG SocketEnginePolling: Sending poll:  as type: 6
2019-02-05 20:16:55.680513+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:55.686013+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Got polling response
2019-02-05 20:16:55.686108+0100 socket-test[76663:9803748] LOG SocketEnginePolling: Got poll message: 1:3
2019-02-05 20:16:55.686210+0100 socket-test[76663:9803748] LOG SocketEngine: Got message: 3
2019-02-05 20:16:55.686355+0100 socket-test[76663:9803748] LOG SocketEngine: Switching to WebSockets
2019-02-05 20:16:55.686364+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:55.686464+0100 socket-test[76663:9803748] LOG SocketEngineWebSocket: Sending ws:  as type: 5
2019-02-05 20:16:55.686568+0100 socket-test[76663:9803748] LOG SocketEngine: Flushing probe wait
2019-02-05 20:16:57.670029+0100 socket-test[76663:9803676] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:57.670073+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:57.670279+0100 socket-test[76663:9803676] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:57.671375+0100 socket-test[76663:9803676] LOG SocketEngine: Got message: 3
2019-02-05 20:16:57.671594+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []
2019-02-05 20:16:59.861943+0100 socket-test[76663:9803748] LOG SocketEngine: Writing ws:  has data: false
2019-02-05 20:16:59.861949+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: ping with data: []
2019-02-05 20:16:59.862265+0100 socket-test[76663:9803748] LOG SocketEngineWebSocket: Sending ws:  as type: 2
2019-02-05 20:16:59.863958+0100 socket-test[76663:9803337] LOG SocketEngine: Got message: 3
2019-02-05 20:16:59.864314+0100 socket-test[76663:9803276] LOG SocketIOClient{/}: Handling event: pong with data: []

janvennemann avatar Feb 05 '19 19:02 janvennemann

I'm experiencing this same issue as well

mmmoussa avatar Apr 20 '20 03:04 mmmoussa

Same issue here, also trying to make socket to nil but still receiving LOG callback

danielerpl avatar Jun 09 '23 12:06 danielerpl