Bender192

I was driving 19947 on DE1 starting in Warszawa Wschodnia. As soon as I left Centralna I got my first disconnect for "an unknown reason". I then reconnected and got disconnected again after less than a minute for several times. When looking at the logs the following lines look suspicious and the time seems to correlate with the disconnects: [2023-03-11 11:54:39]: Curl error 28: Failed to connect to 192.168.10.14 port 8080 after 21023 ms: Timed out [2023-03-11 11:54:39]: Request timeout My subnet is 192.168.1.0/24, therefore 192.168.10.14 looks like an inadvertently hardcoded IP. I haven't driven anything else since the patch (only dispatching) and stopped trying to reconnect at Zachodnia. Before the latest patch I did not have such problems. 192.168.10.14 does not appear in any earlier logfile (I also played as dispatcher since the patch). Supplement: I later reconnected to 19947 and did get further disconnects. But the logfile now contains more entries with 192.168.10.14. So it might not be related. I got disconnected 3 times and now found these 3 log entries that better match the times: [2023-03-11 12:13:19]: Disconnecting connId=0 to prevent exploits from an Exception in MessageHandler: ArgumentException An item with the same key has already been added. Key: 08/13/2022 11:33:12 at System.Collections.Generic.Dictionary`2[TKey,TValue].TryInsert (TKey key, TValue value, System.Collections.Generic.InsertionBehavior behavior) [0x00000] in <00000000000000000000000000000000>:0 at TrainAnnouncement.AnnouncementController.AddHighPriorityAnnouncement (System.DateTime date, TrainAnnouncement.TrainAnnouncementData announcementData) [0x00000] in <00000000000000000000000000000000>:0 at Assets.Scripts.PersistantWorldData.StationAnnoucements.StationAnnoucementsControllerClient.AddHighPriorityStationAnnouncementToQueue (System.String dateTime, Assets.Scripts.PersistantWorldData.StationAnnoucements.StationIdentifier stationIdentifier, Assets.Scripts.PersistantWorldData.StationAnnoucements.AnnouncementData announcementData) [0x00000] in <00000000000000000000000000000000>:0 at Assets.Scripts.PersistantWorldData.StationAnnoucements.StationAnnoucementsControllerServer.InvokeUserCode_RpcSyncHighPriorityQueue__StationIdentifier__String__AnnouncementData (Mirror.NetworkBehaviour obj, Mirror.NetworkReader reader, Mirror.NetworkConnectionToClient senderConnection) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkIdentity.HandleRemoteCall (System.Int32 componentIndex, System.Int32 functionHash, Mirror.RemoteCalls.RemoteCallType remoteCallType, Mirror.NetworkReader reader, Mirror.NetworkConnectionToClient senderConnection) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkClient.OnRPCMessage (Mirror.RpcMessage message) [0x00000] in <00000000000000000000000000000000>:0 at System.Action`1[T].Invoke (T obj) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkClient+<>c__DisplayClass47_0`1[T].<RegisterHandler>g__HandlerWrapped|0 (Mirror.NetworkConnection _, T value) [0x00000] in <00000000000000000000000000000000>:0 at BestHTTP.WebSocket.OnWebSocketIncompleteFrameDelegate.Invoke (BestHTTP.WebSocket.WebSocket webSocket, BestHTTP.WebSocket.Frames.WebSocketFrameReader frame) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.MessagePacking+<>c__DisplayClass7_0`2[T,C].<WrapHandler>g__Wrapped|0 (C conn, T msg, System.Int32 _) [0x00000] in <00000000000000000000000000000000>:0 at System.Action`3[T1,T2,T3].Invoke (T1 arg1, T2 arg2, T3 arg3) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.MessagePacking+<>c__DisplayClass6_0`2[T,C].<WrapHandler>b__0 (Mirror.NetworkConnection conn, Mirror.NetworkReader reader, System.Int32 channelId) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkClient.UnpackAndInvoke (Mirror.NetworkReader reader, System.Int32 channelId) [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkClient.OnTransportData (System.ArraySegment`1[T] data, System.Int32 channelId) [0x00000] in <00000000000000000000000000000000>:0 at kcp2k.KcpTransport.<Awake>b__22_5 (System.ArraySegment`1[T] message, kcp2k.KcpChannel channel) [0x00000] in <00000000000000000000000000000000>:0 at kcp2k.KcpClient.<Connect>b__7_1 (System.ArraySegment`1[T] message, kcp2k.KcpChannel channel) [0x00000] in <00000000000000000000000000000000>:0 at kcp2k.KcpConnection.TickIncoming_Authenticated (System.UInt32 time) [0x00000] in <00000000000000000000000000000000>:0 at kcp2k.KcpConnection.TickIncoming () [0x00000] in <00000000000000000000000000000000>:0 at Mirror.NetworkLoop.NetworkEarlyUpdate () [0x00000] in <00000000000000000000000000000000>:0 [2023-03-11 12:13:19]: Client disconnected without intent! Going back to main menu! SimRail_log_2023-03-11_11-39-34.txt

I was part of this deadlock, too, while Tin was dispatcher (server DE3). I was stuck at SP trying to get to the SG single line. The radio was automatically set to channel 5, but a lot of other drivers were discussing this deadlock on channel 1. I tried contacting Tin several times by chat over the channels 1,2 and 5, but did not get a response. Other drivers had no luck either. Me and the other drivers concluded that the deadlock was caused by dispatching error in SG (sending trains to SP without line lock). And because we weren't able to reach the dispatcher, some of us reported Tin. I'm actually not sure whether the radio channel setting affects chat and voice chat. If so, is the dispatcher connected to all channels? After Tin left, we had another dispatcher who first didn't respond, but was later using voice. Maybe there is a problem preventing communication with the dispatcher at SG that makes them look like trolls? Finally we were able to fix the deadlock by using the bug to slowly pass red lights and then derail two trains. Sorry for the report!