Disconnect by timeout issue

Recently some of our players met a new issue - they can't connect to the game. The amount of those players are not great, but the issue is stable, they can't connect whenever they are trying. We made a special build for them and collected a few logs, that looks quite the same

16:30:42, msgType: Warning, message: Can't execute Disconnect() while not connected. Nothing changed. State: PeerCreated
16:30:43, msgType: Log, message: OnStatusChanged: Connect current State: ConnectingToMasterserver
16:30:43, msgType: Log, message: Connected to masterserver.
16:30:43, msgType: Log, message: OnEvent: Event 226.
16:30:43, msgType: Log, message: OnStatusChanged: EncryptionEstablished current State: Authenticating
16:30:44, msgType: Log, message: OperationResponse 230: ReturnCode: 0.
16:30:44, msgType: Log, message: OnConnectedToMaster() was called by PUN. Now this client is connected and could join a room. Calling: PhotonNetwork.JoinRandomRoom();
16:30:44, msgType: Log, message: OnPhotonRandomJoinFailed() was called by PUN. No random room available, so we create one. Calling: PhotonNetwork.CreateRoom(null, new RoomOptions() {maxPlayers = 4}, null);
16:30:44, msgType: Log, message: OperationResponse 227: ReturnCode: 0.
16:30:44, msgType: Log, message: OnStatusChanged: Disconnect current State: DisconnectingFromMasterserver
16:30:54, msgType: Log, message: OnStatusChanged: TimeoutDisconnect current State: ConnectingToGameserver
16:30:54, msgType: Error, message: OnFailedToConnectToPhoton, cause: DisconnectByClientTimeout
16:30:55, msgType: Log, message: OnStatusChanged: Disconnect current State: Disconnecting
16:30:55, msgType: Error, message: OnDisconnectedFromPhoton


So it looks like they are connecting, trying to find the room, failed, trying to create one and disconnecting 10 seconds after.

We are using Photon On-Premise with a custom plugin. Any help or advices would be appreciated.

Comments

  • DontShooot
    edited April 2018
    We've found an exception on photon server, but not sure why it's happening.
    I've removed ip's info

    4652: 11:51:31.847 - CENetThreadPool::Process - Exception - CENetPeerBase::HandleIncomingCommands() - CENetPeerBase::HandleIncomingCommands()- Unexpected: id = 61145 [0] Command length: 0 is either zero, or more than available data: 430 : id = 61145 [0] Datagram: EE D9 00 09 00 01 23 82 4D 4B FB FB 01 00 00 04 00 00 00 14 00 00 00 00 00 00 02 D1 96 2E DD D4 01 00 00 04 00 00 00 14 00 00 00 00 00 00 02 D2 96 2E DD D4 01 00 00 04 00 00 00 14 00 00 00 00 00 00 02 D3 96 2E DD F3 01 00 00 04 00 00 00 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 66 3F A6 66 66 6B FF FF 63 56 00 0C 43 9B 25 41 43 61 7D DA 42 F7 F9 34 69 00 00 0B B9 69 FF FF FF FF 06 00 01 04 00 00 00 38 00 00 00 8B F3 02 FC 00 03 FB 68 00 01 73 00 12 54 4F 54 41 4C 5F 44 41 4D 41 47 45 5F 44 45 41 4C 54 66 46 AC 49 9A FE 69 00 00 00 03 FA 6F 01 06 00 01 04 00 00 00 56 00 00 00 8C F3 02 FD 00 02 F4 62 C8 F5 68 00 04 62 00 69 00 00 01 F7 62 02 69 96 2E DE D9 62 05 62 06 62 04 7A 00 06 66 43 0C 00 00 66 3F A6 66 66 6B FF FF 63 56 00 0C 43 9B 78 78 43 62 3C 8C 42 F9 25 F0 69 00 00 0B B9 69 FF FF FF FF 06 00 01 04 00 00 00 38 00 00 00 8D F3 02 FC 00 03 FB 68 00 01 73 00 12 54 4F 54 41 4C 5F 44 41 4D 41 47 45 5F 44 45 41 4C 54 66 46 AD 61 9A FE 69 00 00 00 03 FA 6F 01 07 00 00 04 00 00 00 92 00 00 00 8D 00 00 01 02 F3 02 FD 00 02 F4 62 C9 F5 68 00 03 62 0A 7A 00 0D 69 00 00 0B B9 6F 00 2A 66 3F 3F D9 74 66 3F 1F 29 CE 63 56 00 0C 43 9C 74 52 43 63 F3 AC 42 FC A0 A0 63 56 00 0C 41 85 64 4B 41 92 F9 61 41 5F 15 D4 63 51 00 10 3F 56 73 9C BB B5 49 39 3F 0B CC 7A 3B 68 2F 98 63 56 00 0C 39 BD 67 F5 3E 87 C8 7B BA 4A 75 4B 6F 00 69 00 00 7B ED 69 00 00 01 F7 6B 00 01 62 00 69 96 2E DF 62 62 01 6B 00 00 - ......#.MK......................................................................................................................................f?.ffk..cV..C.%ACa}.B..4i....i...........8..........h..s..TOTAL_DAMAGE_DEALTfF.I..i.....o........V..........b..h..b.i....b.i....b.b.b.z..fC...f?.ffk..cV..C.xxCb<.B.%.i....i...........8..........h..s..TOTAL_DAMAGE_DEALTfF.a..i.....o.......................b..h..b.z..i....o.*f??.tf?.).cV..C.tRCc..B...cV..A.dKA..aA_..cQ..?Vs...I9?..z;h/.cV..9.g.>..{.JuKo.i..{.i....k..b.i...bb.k.. from: 42...: on: 37...:

    4652: 11:51:31.847 - 0000000008AB70E0: id = 61145 [0] disconnected due to exception

    4652: 11:51:31.847 - 0000000008AB70E0: Inbound ACK: id = 61145 [0] Received packet with sent time in the future: now = 2519654610receivedSentTime = 0
  • JohnTube
    JohnTube ✭✭✭✭✭
    Hi @DontShooot

    Thank you for choosing Photon and for your detailed report.
    I have moved this discussion to the Photon Server category.
  • hi, @DontShooot

    Please report us, what version of PUN is used and what version of PhotonSocketServer sdk are used. Did you upgrade client sdk before update or not? when? what version did you have before

    best,
    ilya
  • DontShooot
    edited April 2018
    Hello, Ilya
    We're using latest Photon Server (v4.0.29.11263) and PUN v 1.81

    I'll be honest, I couldn't find any commits which can affect multiplayer behaviour. Also we didn't upgrade anything. We moved to il2cpp scripting backend, but asked our players to try special Mono version and there are no difference.

    By the way. Some of our players reports that issue occures only on Mobile network, some of them says that they have connection problem only on WiFi
  • Also we've found out that those exceptions are increasing over time and it doesn't correlate with DAU, CCU or app updates
    http://prntscr.com/ja0g5k
  • ok, please send us mail on developer@photonengine.com

    please attach logs and configs from server side.

    best,
    ilya