12345678910111213141516171819202122232425262728293031323334353637383940414243444546474849505152535455565758596061626364656667686970717273747576777879808182838485868788899091929394959697989910010110210310410510610710810911011111211311411511611711811912012112212312412512612712812913013113213313413513613713813914014114214314414514614714814915015115215315415515615715815916016116216316416516616716816917017117217317417517617717817918018118218318418518618718818919019119219319419519619719819920020120220320420520620720820921021121221321421521621721821922022122222322422522622722822923023123223323423523623723823924024124224324424524624724824925025125225325425525625725825926026126226326426526626726826927027127227327427527627727827928028128228328428528628728828929029129229329429529629729829930030130230330430530630730830931031131231331431531631731831932032132232332432532632732832933033133233333433533633733833934034134234334434534634734834935035135235335435535635735835936036136236336436536636736836937037137237337437537637737837938038138238338438538638738838939039139239339439539639739839940040140240340440540640740840941041141241341441541641741841942042142242342442542642742842943043143243343443543643743843944044144244344444544644744844945045145245345445545645745845946046146246346446546646746846947047147247347447547647747847948048148248348448548648748848949049149249349449549649749849950050150250350450550650750850951051151251351451551651751851952052152252352452552652752852953053153253353453553653753853954054154254354454554654754854955055155255355455555655755855956056156256356456556656756856957057157257357457557657757857958058158258358458558658758858959059159259359459559659759859960060160260360460560660760860961061161261361461561661761861962062162262362462562662762862963063163263363463563663763863964064164264364464564664764864965065165265365465565665765865966066166266366466566666766866967067167267367467567667767867968068168268368468568668768868969069169269369469569669769869970070170270370470570670770870971071171271371471571671771871972072172272372472572672772872973073173273373473573673773873974074174274374474574674774874975075175275375475575675775875976076176276376476576676776876977077177277377477577677777877978078178278378478578678778878979079179279379479579679779879980080180280380480580680780880981081181281381481581681781881982082182282382482582682782882983083183283383483583683783883984084184284384484584684784884985085185285385485585685785885986086186286386486586686786886987087187287387487587687787887988088188288388488588688788888989089189289389489589689789889990090190290390490590690790890991091191291391491591691791891992092192292392492592692792892993093193293393493593693793893994094194294394494594694794894995095195295395495595695795895996096196296396496596696796896997097197297397497597697797897998098198298398498598698798898999099199299399499599699799899910001001100210031004100510061007100810091010101110121013101410151016101710181019102010211022102310241025102610271028102910301031103210331034103510361037103810391040104110421043104410451046104710481049105010511052105310541055105610571058105910601061106210631064106510661067106810691070107110721073107410751076107710781079108010811082108310841085108610871088108910901091109210931094109510961097109810991100110111021103110411051106110711081109111011111112111311141115111611171118111911201121112211231124112511261127112811291130113111321133113411351136113711381139114011411142114311441145114611471148114911501151115211531154115511561157115811591160116111621163116411651166116711681169117011711172117311741175117611771178117911801181118211831184118511861187118811891190119111921193119411951196119711981199120012011202120312041205120612071208120912101211121212131214121512161217121812191220122112221223122412251226122712281229123012311232123312341235123612371238123912401241124212431244124512461247124812491250125112521253125412551256125712581259126012611262126312641265126612671268126912701271127212731274127512761277127812791280128112821283128412851286128712881289129012911292129312941295129612971298129913001301130213031304130513061307130813091310131113121313131413151316131713181319132013211322132313241325132613271328132913301331133213331334133513361337133813391340134113421343134413451346134713481349135013511352135313541355135613571358135913601361136213631364136513661367136813691370137113721373137413751376137713781379138013811382138313841385138613871388138913901391139213931394139513961397139813991400140114021403140414051406140714081409141014111412141314141415141614171418141914201421142214231424142514261427142814291430143114321433143414351436143714381439144014411442144314441445144614471448144914501451145214531454145514561457145814591460146114621463146414651466146714681469147014711472147314741475147614771478147914801481148214831484148514861487148814891490149114921493149414951496149714981499150015011502150315041505150615071508150915101511151215131514151515161517151815191520152115221523152415251526152715281529153015311532153315341535153615371538153915401541154215431544154515461547154815491550155115521553155415551556155715581559156015611562156315641565156615671568156915701571157215731574157515761577157815791580158115821583158415851586158715881589159015911592159315941595159615971598159916001601160216031604160516061607160816091610161116121613161416151616161716181619162016211622162316241625162616271628162916301631163216331634163516361637163816391640164116421643164416451646164716481649165016511652165316541655165616571658165916601661166216631664166516661667166816691670167116721673167416751676167716781679168016811682168316841685168616871688168916901691169216931694169516961697169816991700170117021703170417051706170717081709171017111712171317141715171617171718171917201721172217231724172517261727172817291730173117321733173417351736173717381739174017411742174317441745174617471748174917501751175217531754175517561757175817591760176117621763176417651766176717681769177017711772177317741775177617771778177917801781178217831784178517861787178817891790179117921793179417951796179717981799180018011802180318041805180618071808180918101811181218131814181518161817181818191820182118221823182418251826182718281829183018311832183318341835183618371838183918401841184218431844184518461847184818491850185118521853185418551856185718581859186018611862186318641865186618671868186918701871187218731874187518761877187818791880188118821883188418851886188718881889189018911892189318941895189618971898189919001901190219031904190519061907190819091910191119121913191419151916191719181919192019211922192319241925192619271928192919301931193219331934193519361937193819391940194119421943194419451946194719481949195019511952195319541955195619571958195919601961196219631964196519661967196819691970197119721973197419751976197719781979198019811982198319841985198619871988198919901991199219931994199519961997199819992000200120022003200420052006200720082009201020112012201320142015201620172018201920202021202220232024202520262027202820292030203120322033203420352036203720382039204020412042204320442045204620472048204920502051205220532054205520562057205820592060206120622063206420652066206720682069207020712072207320742075207620772078207920802081208220832084208520862087208820892090209120922093209420952096209720982099210021012102210321042105210621072108210921102111211221132114211521162117211821192120212121222123212421252126212721282129213021312132213321342135213621372138213921402141214221432144214521462147214821492150215121522153215421552156215721582159216021612162216321642165216621672168216921702171217221732174217521762177217821792180218121822183218421852186218721882189219021912192219321942195219621972198219922002201220222032204220522062207220822092210221122122213221422152216221722182219222022212222222322242225222622272228222922302231223222332234223522362237223822392240224122422243 |
- /*
- * Copyright (c) Contributors, http://opensimulator.org/
- * See CONTRIBUTORS.TXT for a full list of copyright holders.
- *
- * Redistribution and use in source and binary forms, with or without
- * modification, are permitted provided that the following conditions are met:
- * * Redistributions of source code must retain the above copyright
- * notice, this list of conditions and the following disclaimer.
- * * Redistributions in binary form must reproduce the above copyright
- * notice, this list of conditions and the following disclaimer in the
- * documentation and/or other materials provided with the distribution.
- * * Neither the name of the OpenSimulator Project nor the
- * names of its contributors may be used to endorse or promote products
- * derived from this software without specific prior written permission.
- *
- * THIS SOFTWARE IS PROVIDED BY THE DEVELOPERS ``AS IS'' AND ANY
- * EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT LIMITED TO, THE IMPLIED
- * WARRANTIES OF MERCHANTABILITY AND FITNESS FOR A PARTICULAR PURPOSE ARE
- * DISCLAIMED. IN NO EVENT SHALL THE CONTRIBUTORS BE LIABLE FOR ANY
- * DIRECT, INDIRECT, INCIDENTAL, SPECIAL, EXEMPLARY, OR CONSEQUENTIAL DAMAGES
- * (INCLUDING, BUT NOT LIMITED TO, PROCUREMENT OF SUBSTITUTE GOODS OR SERVICES;
- * LOSS OF USE, DATA, OR PROFITS; OR BUSINESS INTERRUPTION) HOWEVER CAUSED AND
- * ON ANY THEORY OF LIABILITY, WHETHER IN CONTRACT, STRICT LIABILITY, OR TORT
- * (INCLUDING NEGLIGENCE OR OTHERWISE) ARISING IN ANY WAY OUT OF THE USE OF THIS
- * SOFTWARE, EVEN IF ADVISED OF THE POSSIBILITY OF SUCH DAMAGE.
- */
- using System;
- using System.Collections.Generic;
- using System.Diagnostics;
- using System.IO;
- using System.Net;
- using System.Net.Sockets;
- using System.Reflection;
- using System.Threading;
- using log4net;
- using Nini.Config;
- using OpenMetaverse.Packets;
- using OpenSim.Framework;
- using OpenSim.Framework.Console;
- using OpenSim.Framework.Monitoring;
- using OpenSim.Region.Framework.Scenes;
- using OpenSim.Region.Framework.Interfaces;
- using OpenMetaverse;
- using Mono.Addins;
- using TokenBucket = OpenSim.Region.ClientStack.LindenUDP.TokenBucket;
- namespace OpenSim.Region.ClientStack.LindenUDP
- {
- /// <summary>
- /// A shim around LLUDPServer that implements the IClientNetworkServer interface
- /// </summary>
- [Extension(Path = "/OpenSim/RegionModules", NodeName = "RegionModule", Id = "LLUDPServerShim")]
- public class LLUDPServerShim : INonSharedRegionModule
- {
- protected IConfigSource m_Config;
- protected LLUDPServer m_udpServer;
- #region INonSharedRegionModule
- public virtual string Name
- {
- get { return "LLUDPServerShim"; }
- }
- public virtual Type ReplaceableInterface
- {
- get { return null; }
- }
- public virtual void Initialise(IConfigSource source)
- {
- m_Config = source;
- }
- public virtual void Close()
- {
- }
- public virtual void AddRegion(Scene scene)
- {
- uint port = (uint)scene.RegionInfo.InternalEndPoint.Port;
- IPAddress listenIP = scene.RegionInfo.InternalEndPoint.Address;
- Initialise(listenIP, ref port, scene.RegionInfo.ProxyOffset, m_Config, scene.AuthenticateHandler);
- scene.RegionInfo.InternalEndPoint.Port = (int)port;
- AddScene(scene);
- }
- public virtual void RemoveRegion(Scene scene)
- {
- Stop();
- }
- public virtual void RegionLoaded(Scene scene)
- {
- Start();
- }
- #endregion
- public virtual void Initialise(IPAddress listenIP, ref uint port, int proxyPortOffsetParm, IConfigSource configSource, AgentCircuitManager circuitManager)
- {
- m_udpServer = new LLUDPServer(listenIP, ref port, proxyPortOffsetParm, configSource, circuitManager);
- }
- public virtual void AddScene(IScene scene)
- {
- m_udpServer.AddScene(scene);
- StatsManager.RegisterStat(
- new Stat(
- "ClientLogoutsDueToNoReceives",
- "Number of times a client has been logged out because no packets were received before the timeout.",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.None,
- stat => stat.Value = m_udpServer.ClientLogoutsDueToNoReceives,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "IncomingUDPReceivesCount",
- "Number of UDP receives performed",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.UdpReceives,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "IncomingPacketsProcessedCount",
- "Number of inbound LL protocol packets processed",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.IncomingPacketsProcessed,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "IncomingPacketsMalformedCount",
- "Number of inbound UDP packets that could not be recognized as LL protocol packets.",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.IncomingMalformedPacketCount,
- StatVerbosity.Info));
- StatsManager.RegisterStat(
- new Stat(
- "IncomingPacketsOrphanedCount",
- "Number of inbound packets that were not initial connections packets and could not be associated with a viewer.",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.IncomingOrphanedPacketCount,
- StatVerbosity.Info));
- StatsManager.RegisterStat(
- new Stat(
- "IncomingPacketsResentCount",
- "Number of inbound packets that clients indicate are resends.",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.IncomingPacketsResentCount,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "OutgoingUDPSendsCount",
- "Number of UDP sends performed",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.UdpSends,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "OutgoingPacketsResentCount",
- "Number of packets resent because a client did not acknowledge receipt",
- "",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = m_udpServer.PacketsResentCount,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "AverageUDPProcessTime",
- "Average number of milliseconds taken to process each incoming UDP packet in a sample.",
- "This is for initial receive processing which is separate from the later client LL packet processing stage.",
- "ms",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.None,
- stat => stat.Value = m_udpServer.AverageReceiveTicksForLastSamplePeriod,
- // stat =>
- // stat.Value = Math.Round(m_udpServer.AverageReceiveTicksForLastSamplePeriod, 7),
- StatVerbosity.Debug));
- }
- public virtual bool HandlesRegion(Location x)
- {
- return m_udpServer.HandlesRegion(x);
- }
- public virtual void Start()
- {
- m_udpServer.Start();
- }
- public virtual void Stop()
- {
- m_udpServer.Stop();
- }
- }
- /// <summary>
- /// The LLUDP server for a region. This handles incoming and outgoing
- /// packets for all UDP connections to the region
- /// </summary>
- public class LLUDPServer : OpenSimUDPBase
- {
- private static readonly ILog m_log = LogManager.GetLogger(MethodBase.GetCurrentMethod().DeclaringType);
- /// <summary>Maximum transmission unit, or UDP packet size, for the LLUDP protocol</summary>
- public const int MTU = 1400;
- /// <summary>Number of forced client logouts due to no receipt of packets before timeout.</summary>
- public int ClientLogoutsDueToNoReceives { get; protected set; }
- /// <summary>
- /// Default packet debug level given to new clients
- /// </summary>
- public int DefaultClientPacketDebugLevel { get; set; }
- /// <summary>
- /// If set then all inbound agent updates are discarded. For debugging purposes.
- /// discard agent update.
- /// </summary>
- public bool DiscardInboundAgentUpdates { get; set; }
- /// <summary>The measured resolution of Environment.TickCount</summary>
- public readonly float TickCountResolution;
- /// <summary>Number of prim updates to put on the queue each time the
- /// OnQueueEmpty event is triggered for updates</summary>
- public readonly int PrimUpdatesPerCallback;
- /// <summary>Number of texture packets to put on the queue each time the
- /// OnQueueEmpty event is triggered for textures</summary>
- public readonly int TextureSendLimit;
- /// <summary>Handlers for incoming packets</summary>
- //PacketEventDictionary packetEvents = new PacketEventDictionary();
- /// <summary>Incoming packets that are awaiting handling</summary>
- //protected OpenMetaverse.BlockingQueue<IncomingPacket> packetInbox = new OpenMetaverse.BlockingQueue<IncomingPacket>();
- protected OpenSim.Framework.BlockingQueue<IncomingPacket> packetInbox = new OpenSim.Framework.BlockingQueue<IncomingPacket>();
- /// <summary>Bandwidth throttle for this UDP server</summary>
- public TokenBucket Throttle { get; protected set; }
- /// <summary>Per client throttle rates enforced by this server</summary>
- /// <remarks>
- /// If the total rate is non-zero, then this is the maximum total throttle setting that any client can ever have.
- /// The other rates (resend, asset, etc.) are the defaults for a new client and can be changed (and usually
- /// do get changed immediately). They do not need to sum to the total.
- /// </remarks>
- public ThrottleRates ThrottleRates { get; protected set; }
- /// <summary>Manages authentication for agent circuits</summary>
- protected AgentCircuitManager m_circuitManager;
- /// <summary>Reference to the scene this UDP server is attached to</summary>
- public Scene Scene { get; protected set; }
- /// <summary>The X/Y coordinates of the scene this UDP server is attached to</summary>
- protected Location m_location;
- /// <summary>The size of the receive buffer for the UDP socket. This value
- /// is passed up to the operating system and used in the system networking
- /// stack. Use zero to leave this value as the default</summary>
- protected int m_recvBufferSize;
- /// <summary>Tracks whether or not a packet was sent each round so we know
- /// whether or not to sleep</summary>
- protected bool m_packetSent;
- /// <summary>Environment.TickCount of the last time that packet stats were reported to the scene</summary>
- protected int m_elapsedMSSinceLastStatReport = 0;
- /// <summary>Environment.TickCount of the last time the outgoing packet handler executed</summary>
- protected double m_tickLastOutgoingPacketHandler;
- /// <summary>Keeps track of the number of elapsed milliseconds since the last time the outgoing packet handler looped</summary>
- protected double m_elapsedMSOutgoingPacketHandler;
- /// <summary>Keeps track of the number of 100 millisecond periods elapsed in the outgoing packet handler executed</summary>
- protected int m_elapsed100MSOutgoingPacketHandler;
- /// <summary>Keeps track of the number of 500 millisecond periods elapsed in the outgoing packet handler executed</summary>
- protected int m_elapsed500MSOutgoingPacketHandler;
- /// <summary>Flag to signal when clients should check for resends</summary>
- protected bool m_resendUnacked;
- /// <summary>Flag to signal when clients should send ACKs</summary>
- protected bool m_sendAcks;
- /// <summary>Flag to signal when clients should send pings</summary>
- protected bool m_sendPing;
- protected int m_animationSequenceNumber;
- public int NextAnimationSequenceNumber
- {
- get
- {
- m_animationSequenceNumber++;
- if (m_animationSequenceNumber > 2147482624)
- m_animationSequenceNumber = 1;
- return m_animationSequenceNumber;
- }
- }
- protected ExpiringCache<IPEndPoint, Queue<UDPPacketBuffer>> m_pendingCache = new ExpiringCache<IPEndPoint, Queue<UDPPacketBuffer>>();
- protected Pool<IncomingPacket> m_incomingPacketPool;
- /// <summary>
- /// Stat for number of packets in the main pool awaiting use.
- /// </summary>
- protected Stat m_poolCountStat;
- /// <summary>
- /// Stat for number of packets in the inbound packet pool awaiting use.
- /// </summary>
- protected Stat m_incomingPacketPoolStat;
- protected int m_defaultRTO = 0;
- protected int m_maxRTO = 0;
- protected int m_ackTimeout = 0;
- protected int m_pausedAckTimeout = 0;
- protected bool m_disableFacelights = false;
- public Socket Server { get { return null; } }
- /// <summary>
- /// Record how many packets have been resent
- /// </summary>
- internal int PacketsResentCount { get; set; }
- /// <summary>
- /// Record how many packets have been sent
- /// </summary>
- internal int PacketsSentCount { get; set; }
- /// <summary>
- /// Record how many incoming packets are indicated as resends by clients.
- /// </summary>
- internal int IncomingPacketsResentCount { get; set; }
- /// <summary>
- /// Record how many inbound packets could not be recognized as LLUDP packets.
- /// </summary>
- public int IncomingMalformedPacketCount { get; protected set; }
- /// <summary>
- /// Record how many inbound packets could not be associated with a simulator circuit.
- /// </summary>
- public int IncomingOrphanedPacketCount { get; protected set; }
- /// <summary>
- /// Record current outgoing client for monitoring purposes.
- /// </summary>
- protected IClientAPI m_currentOutgoingClient;
- /// <summary>
- /// Recording current incoming client for monitoring purposes.
- /// </summary>
- protected IClientAPI m_currentIncomingClient;
- /// <summary>
- /// Queue some low priority but potentially high volume async requests so that they don't overwhelm available
- /// threadpool threads.
- /// </summary>
- // public JobEngine IpahEngine { get; protected set; }
- /// <summary>
- /// Run queue empty processing within a single persistent thread.
- /// </summary>
- /// <remarks>
- /// This is the alternative to having every
- /// connection schedule its own job in the threadpool which causes performance problems when there are many
- /// connections.
- /// </remarks>
- public JobEngine OqrEngine { get; protected set; }
- public LLUDPServer(
- IPAddress listenIP, ref uint port, int proxyPortOffsetParm,
- IConfigSource configSource, AgentCircuitManager circuitManager)
- : base(listenIP, (int)port)
- {
- #region Environment.TickCount Measurement
- // Update the port with the one we actually got
- port = (uint)Port;
- // Measure the resolution of Environment.TickCount
- TickCountResolution = 0f;
- for (int i = 0; i < 10; i++)
- {
- int start = Environment.TickCount;
- int now = start;
- while (now == start)
- now = Environment.TickCount;
- TickCountResolution += (float)(now - start);
- }
- m_log.Info("[LLUDPSERVER]: Average Environment.TickCount resolution: " + TickCountResolution * 0.1f + "ms");
- TickCountResolution = 0f;
- for (int i = 0; i < 100; i++)
- {
- double start = Util.GetTimeStampMS();
- double now = start;
- while (now == start)
- now = Util.GetTimeStampMS();
- TickCountResolution += (float)((now - start));
- }
- TickCountResolution = (float)Math.Round(TickCountResolution * 0.01f,6,MidpointRounding.AwayFromZero);
- m_log.Info("[LLUDPSERVER]: Average Util.GetTimeStampMS resolution: " + TickCountResolution + "ms");
- #endregion Environment.TickCount Measurement
- m_circuitManager = circuitManager;
- int sceneThrottleBps = 0;
- bool usePools = false;
- IConfig config = configSource.Configs["ClientStack.LindenUDP"];
- if (config != null)
- {
- m_recvBufferSize = config.GetInt("client_socket_rcvbuf_size", 0);
- sceneThrottleBps = config.GetInt("scene_throttle_max_bps", 0);
- PrimUpdatesPerCallback = config.GetInt("PrimUpdatesPerCallback", 100);
- TextureSendLimit = config.GetInt("TextureSendLimit", 20);
- m_defaultRTO = config.GetInt("DefaultRTO", 0);
- m_maxRTO = config.GetInt("MaxRTO", 0);
- m_disableFacelights = config.GetBoolean("DisableFacelights", false);
- m_ackTimeout = 1000 * config.GetInt("AckTimeout", 60);
- m_pausedAckTimeout = 1000 * config.GetInt("PausedAckTimeout", 300);
- }
- else
- {
- PrimUpdatesPerCallback = 100;
- TextureSendLimit = 20;
- m_ackTimeout = 1000 * 60; // 1 minute
- m_pausedAckTimeout = 1000 * 300; // 5 minutes
- }
- // FIXME: This actually only needs to be done once since the PacketPool is shared across all servers.
- // However, there is no harm in temporarily doing it multiple times.
- IConfig packetConfig = configSource.Configs["PacketPool"];
- if (packetConfig != null)
- {
- PacketPool.Instance.RecyclePackets = packetConfig.GetBoolean("RecyclePackets", true);
- PacketPool.Instance.RecycleDataBlocks = packetConfig.GetBoolean("RecycleDataBlocks", true);
- usePools = packetConfig.GetBoolean("RecycleBaseUDPPackets", usePools);
- }
- #region BinaryStats
- config = configSource.Configs["Statistics.Binary"];
- m_shouldCollectStats = false;
- if (config != null)
- {
- m_shouldCollectStats = config.GetBoolean("Enabled", false);
- binStatsMaxFilesize = TimeSpan.FromSeconds(config.GetInt("packet_headers_period_seconds", 300));
- binStatsDir = config.GetString("stats_dir", ".");
- m_aggregatedBWStats = config.GetBoolean("aggregatedBWStats", false);
- }
- #endregion BinaryStats
- Throttle = new TokenBucket(null, sceneThrottleBps, sceneThrottleBps * 10e-3f);
- ThrottleRates = new ThrottleRates(configSource);
- Random rnd = new Random(Util.EnvironmentTickCount());
- m_animationSequenceNumber = rnd.Next(11474826);
- // if (usePools)
- // EnablePools();
- base.DisablePools();
- }
- public void Start()
- {
- StartInbound();
- StartOutbound();
- // IpahEngine.Start();
- OqrEngine.Start();
- m_elapsedMSSinceLastStatReport = Environment.TickCount;
- }
- public void StartInbound()
- {
- m_log.InfoFormat(
- "[LLUDPSERVER]: Starting inbound packet processing for the LLUDP server");
- base.StartInbound(m_recvBufferSize);
- // This thread will process the packets received that are placed on the packetInbox
- WorkManager.StartThread(
- IncomingPacketHandler,
- string.Format("Incoming Packets ({0})", Scene.Name),
- ThreadPriority.Normal,
- false,
- true,
- GetWatchdogIncomingAlarmData,
- Watchdog.DEFAULT_WATCHDOG_TIMEOUT_MS);
- }
- public override void StartOutbound()
- {
- m_log.Info("[LLUDPSERVER]: Starting outbound packet processing for the LLUDP server");
- base.StartOutbound();
- WorkManager.StartThread(
- OutgoingPacketHandler,
- string.Format("Outgoing Packets ({0})", Scene.Name),
- ThreadPriority.Normal,
- false,
- true,
- GetWatchdogOutgoingAlarmData,
- Watchdog.DEFAULT_WATCHDOG_TIMEOUT_MS);
- }
- public void Stop()
- {
- m_log.Info("[LLUDPSERVER]: Shutting down the LLUDP server for " + Scene.Name);
- base.StopOutbound();
- base.StopInbound();
- // IpahEngine.Stop();
- OqrEngine.Stop();
- }
- public override bool EnablePools()
- {
- if (!UsePools)
- {
- base.EnablePools();
- m_incomingPacketPool = new Pool<IncomingPacket>(() => new IncomingPacket(), 500);
- return true;
- }
- return false;
- }
- public override bool DisablePools()
- {
- if (UsePools)
- {
- base.DisablePools();
- StatsManager.DeregisterStat(m_incomingPacketPoolStat);
- // We won't null out the pool to avoid a race condition with code that may be in the middle of using it.
- return true;
- }
- return false;
- }
- /// <summary>
- /// This is a seperate method so that it can be called once we have an m_scene to distinguish different scene
- /// stats.
- /// </summary>
- protected internal void EnablePoolStats()
- {
- m_poolCountStat
- = new Stat(
- "UDPPacketBufferPoolCount",
- "Objects within the UDPPacketBuffer pool",
- "The number of objects currently stored within the UDPPacketBuffer pool",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat => stat.Value = Pool.Count,
- StatVerbosity.Debug);
- StatsManager.RegisterStat(m_poolCountStat);
- m_incomingPacketPoolStat
- = new Stat(
- "IncomingPacketPoolCount",
- "Objects within incoming packet pool",
- "The number of objects currently stored within the incoming packet pool",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat => stat.Value = m_incomingPacketPool.Count,
- StatVerbosity.Debug);
- StatsManager.RegisterStat(m_incomingPacketPoolStat);
- }
- /// <summary>
- /// Disables pool stats.
- /// </summary>
- protected internal void DisablePoolStats()
- {
- StatsManager.DeregisterStat(m_poolCountStat);
- m_poolCountStat = null;
- StatsManager.DeregisterStat(m_incomingPacketPoolStat);
- m_incomingPacketPoolStat = null;
- }
- /// <summary>
- /// If the outgoing UDP thread times out, then return client that was being processed to help with debugging.
- /// </summary>
- /// <returns></returns>
- protected string GetWatchdogIncomingAlarmData()
- {
- return string.Format(
- "Client is {0}",
- m_currentIncomingClient != null ? m_currentIncomingClient.Name : "none");
- }
- /// <summary>
- /// If the outgoing UDP thread times out, then return client that was being processed to help with debugging.
- /// </summary>
- /// <returns></returns>
- protected string GetWatchdogOutgoingAlarmData()
- {
- return string.Format(
- "Client is {0}",
- m_currentOutgoingClient != null ? m_currentOutgoingClient.Name : "none");
- }
- public void AddScene(IScene scene)
- {
- if (Scene != null)
- {
- m_log.Error("[LLUDPSERVER]: AddScene() called on an LLUDPServer that already has a scene");
- return;
- }
- if (!(scene is Scene))
- {
- m_log.Error("[LLUDPSERVER]: AddScene() called with an unrecognized scene type " + scene.GetType());
- return;
- }
- Scene = (Scene)scene;
- m_location = new Location(Scene.RegionInfo.RegionHandle);
- /*
- IpahEngine
- = new JobEngine(
- string.Format("Incoming Packet Async Handling Engine ({0})", Scene.Name),
- "INCOMING PACKET ASYNC HANDLING ENGINE");
- */
- OqrEngine
- = new JobEngine(
- string.Format("Outgoing Queue Refill Engine ({0})", Scene.Name),
- "OUTGOING QUEUE REFILL ENGINE");
- StatsManager.RegisterStat(
- new Stat(
- "InboxPacketsCount",
- "Number of LL protocol packets waiting for the second stage of processing after initial receive.",
- "Number of LL protocol packets waiting for the second stage of processing after initial receive.",
- "",
- "clientstack",
- scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = packetInbox.Count(),
- StatVerbosity.Debug));
- // XXX: These stats are also pool stats but we register them separately since they are currently not
- // turned on and off by EnablePools()/DisablePools()
- StatsManager.RegisterStat(
- new PercentageStat(
- "PacketsReused",
- "Packets reused",
- "Number of packets reused out of all requests to the packet pool",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat =>
- { PercentageStat pstat = (PercentageStat)stat;
- pstat.Consequent = PacketPool.Instance.PacketsRequested;
- pstat.Antecedent = PacketPool.Instance.PacketsReused; },
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new PercentageStat(
- "PacketDataBlocksReused",
- "Packet data blocks reused",
- "Number of data blocks reused out of all requests to the packet pool",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat =>
- { PercentageStat pstat = (PercentageStat)stat;
- pstat.Consequent = PacketPool.Instance.BlocksRequested;
- pstat.Antecedent = PacketPool.Instance.BlocksReused; },
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "PacketsPoolCount",
- "Objects within the packet pool",
- "The number of objects currently stored within the packet pool",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat => stat.Value = PacketPool.Instance.PacketsPooled,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "PacketDataBlocksPoolCount",
- "Objects within the packet data block pool",
- "The number of objects currently stored within the packet data block pool",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- stat => stat.Value = PacketPool.Instance.BlocksPooled,
- StatVerbosity.Debug));
- StatsManager.RegisterStat(
- new Stat(
- "OutgoingPacketsQueuedCount",
- "Packets queued for outgoing send",
- "Number of queued outgoing packets across all connections",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- MeasuresOfInterest.AverageChangeOverTime,
- stat => stat.Value = GetTotalQueuedOutgoingPackets(),
- StatVerbosity.Info));
- /*
- StatsManager.RegisterStat(
- new Stat(
- "IncomingPacketAsyncRequestsWaiting",
- "Number of incoming packets waiting for async processing in engine.",
- "",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- MeasuresOfInterest.None,
- stat => stat.Value = IpahEngine.JobsWaiting,
- StatVerbosity.Debug));
- */
- StatsManager.RegisterStat(
- new Stat(
- "OQRERequestsWaiting",
- "Number of outgong queue refill requests waiting for processing.",
- "",
- "",
- "clientstack",
- Scene.Name,
- StatType.Pull,
- MeasuresOfInterest.None,
- stat => stat.Value = OqrEngine.JobsWaiting,
- StatVerbosity.Debug));
- // We delay enabling pool stats to AddScene() instead of Initialize() so that we can distinguish pool stats by
- // scene name
- if (UsePools)
- EnablePoolStats();
- LLUDPServerCommands commands = new LLUDPServerCommands(MainConsole.Instance, this);
- commands.Register();
- }
- public bool HandlesRegion(Location x)
- {
- return x == m_location;
- }
- public int GetTotalQueuedOutgoingPackets()
- {
- int total = 0;
- foreach (ScenePresence sp in Scene.GetScenePresences())
- {
- // XXX: Need a better way to determine which IClientAPIs have UDPClients (NPCs do not, for instance).
- if (sp.ControllingClient is LLClientView)
- {
- LLUDPClient udpClient = ((LLClientView)sp.ControllingClient).UDPClient;
- total += udpClient.GetTotalPacketsQueuedCount();
- }
- }
- return total;
- }
- // public void BroadcastPacket(Packet packet, ThrottleOutPacketType category, bool sendToPausedAgents, bool allowSplitting)
- // {
- // // CoarseLocationUpdate and AvatarGroupsReply packets cannot be split in an automated way
- // if ((packet.Type == PacketType.CoarseLocationUpdate || packet.Type == PacketType.AvatarGroupsReply) && allowSplitting)
- // allowSplitting = false;
- //
- // if (allowSplitting && packet.HasVariableBlocks)
- // {
- // byte[][] datas = packet.ToBytesMultiple();
- // int packetCount = datas.Length;
- //
- // if (packetCount < 1)
- // m_log.Error("[LLUDPSERVER]: Failed to split " + packet.Type + " with estimated length " + packet.Length);
- //
- // for (int i = 0; i < packetCount; i++)
- // {
- // byte[] data = datas[i];
- // m_scene.ForEachClient(
- // delegate(IClientAPI client)
- // {
- // if (client is LLClientView)
- // SendPacketData(((LLClientView)client).UDPClient, data, packet.Type, category, null);
- // }
- // );
- // }
- // }
- // else
- // {
- // byte[] data = packet.ToBytes();
- // m_scene.ForEachClient(
- // delegate(IClientAPI client)
- // {
- // if (client is LLClientView)
- // SendPacketData(((LLClientView)client).UDPClient, data, packet.Type, category, null);
- // }
- // );
- // }
- // }
- /// <summary>
- /// Start the process of sending a packet to the client.
- /// </summary>
- /// <param name="udpClient"></param>
- /// <param name="packet"></param>
- /// <param name="category"></param>
- /// <param name="allowSplitting"></param>
- /// <param name="method">
- /// The method to call if the packet is not acked by the client. If null, then a standard
- /// resend of the packet is done.
- /// </param>
- public virtual void SendPacket(
- LLUDPClient udpClient, Packet packet, ThrottleOutPacketType category, bool allowSplitting, UnackedPacketMethod method)
- {
- // CoarseLocationUpdate packets cannot be split in an automated way
- if (packet.Type == PacketType.CoarseLocationUpdate && allowSplitting)
- allowSplitting = false;
- bool packetQueued = false;
- if (allowSplitting && packet.HasVariableBlocks)
- {
- byte[][] datas = packet.ToBytesMultiple();
- int packetCount = datas.Length;
- if (packetCount < 1)
- m_log.Error("[LLUDPSERVER]: Failed to split " + packet.Type + " with estimated length " + packet.Length);
- for (int i = 0; i < packetCount; i++)
- {
- byte[] data = datas[i];
- if (!SendPacketData(udpClient, data, packet.Type, category, method))
- packetQueued = true;
- }
- }
- else
- {
- byte[] data = packet.ToBytes();
- if (!SendPacketData(udpClient, data, packet.Type, category, method))
- packetQueued = true;
- }
- PacketPool.Instance.ReturnPacket(packet);
- }
- /// <summary>
- /// Start the process of sending a packet to the client.
- /// </summary>
- /// <param name="udpClient"></param>
- /// <param name="data"></param>
- /// <param name="type"></param>
- /// <param name="category"></param>
- /// <param name="method">
- /// The method to call if the packet is not acked by the client. If null, then a standard
- /// resend of the packet is done.
- /// </param>
- /// <returns>true if the data was sent immediately, false if it was queued for sending</returns>
- public bool SendPacketData(
- LLUDPClient udpClient, byte[] data, PacketType type, ThrottleOutPacketType category, UnackedPacketMethod method)
- {
- int dataLength = data.Length;
- bool doZerocode = (data[0] & Helpers.MSG_ZEROCODED) != 0;
- bool doCopy = true;
- // Frequency analysis of outgoing packet sizes shows a large clump of packets at each end of the spectrum.
- // The vast majority of packets are less than 200 bytes, although due to asset transfers and packet splitting
- // there are a decent number of packets in the 1000-1140 byte range. We allocate one of two sizes of data here
- // to accomodate for both common scenarios and provide ample room for ACK appending in both
- int bufferSize = (dataLength > 180) ? LLUDPServer.MTU : 200;
- UDPPacketBuffer buffer = new UDPPacketBuffer(udpClient.RemoteEndPoint, bufferSize);
- // Zerocode if needed
- if (doZerocode)
- {
- try
- {
- dataLength = Helpers.ZeroEncode(data, dataLength, buffer.Data);
- doCopy = false;
- }
- catch (IndexOutOfRangeException)
- {
- // The packet grew larger than the bufferSize while zerocoding.
- // Remove the MSG_ZEROCODED flag and send the unencoded data
- // instead
- m_log.Debug("[LLUDPSERVER]: Packet exceeded buffer size during zerocoding for " + type + ". DataLength=" + dataLength +
- " and BufferLength=" + buffer.Data.Length + ". Removing MSG_ZEROCODED flag");
- data[0] = (byte)(data[0] & ~Helpers.MSG_ZEROCODED);
- }
- }
- // If the packet data wasn't already copied during zerocoding, copy it now
- if (doCopy)
- {
- if (dataLength <= buffer.Data.Length)
- {
- Buffer.BlockCopy(data, 0, buffer.Data, 0, dataLength);
- }
- else
- {
- bufferSize = dataLength;
- buffer = new UDPPacketBuffer(udpClient.RemoteEndPoint, bufferSize);
- m_log.Error("[LLUDPSERVER]: Packet exceeded buffer size! This could be an indication of packet assembly not obeying the MTU. Type=" +
- type + ", DataLength=" + dataLength + ", BufferLength=" + buffer.Data.Length);
- Buffer.BlockCopy(data, 0, buffer.Data, 0, dataLength);
- }
- }
- buffer.DataLength = dataLength;
- #region Queue or Send
- bool highPriority = false;
- if (category != ThrottleOutPacketType.Unknown && (category & ThrottleOutPacketType.HighPriority) != 0)
- {
- category = (ThrottleOutPacketType)((int)category & 127);
- highPriority = true;
- }
- OutgoingPacket outgoingPacket = new OutgoingPacket(udpClient, buffer, category, null);
- // If we were not provided a method for handling unacked, use the UDPServer default method
- if ((outgoingPacket.Buffer.Data[0] & Helpers.MSG_RELIABLE) != 0)
- outgoingPacket.UnackedMethod = ((method == null) ? delegate(OutgoingPacket oPacket) { ResendUnacked(oPacket); } : method);
- // If a Linden Lab 1.23.5 client receives an update packet after a kill packet for an object, it will
- // continue to display the deleted object until relog. Therefore, we need to always queue a kill object
- // packet so that it isn't sent before a queued update packet.
- bool requestQueue = type == PacketType.KillObject;
- if (!outgoingPacket.Client.EnqueueOutgoing(outgoingPacket, requestQueue, highPriority))
- {
- SendPacketFinal(outgoingPacket);
- return true;
- }
- return false;
- #endregion Queue or Send
- }
- public void SendAcks(LLUDPClient udpClient)
- {
- uint ack;
- if (udpClient.PendingAcks.Dequeue(out ack))
- {
- List<PacketAckPacket.PacketsBlock> blocks = new List<PacketAckPacket.PacketsBlock>();
- PacketAckPacket.PacketsBlock block = new PacketAckPacket.PacketsBlock();
- block.ID = ack;
- blocks.Add(block);
- while (udpClient.PendingAcks.Dequeue(out ack))
- {
- block = new PacketAckPacket.PacketsBlock();
- block.ID = ack;
- blocks.Add(block);
- }
- PacketAckPacket packet = new PacketAckPacket();
- packet.Header.Reliable = false;
- packet.Packets = blocks.ToArray();
- SendPacket(udpClient, packet, ThrottleOutPacketType.Unknown, true, null);
- }
- }
- public void SendPing(LLUDPClient udpClient)
- {
- StartPingCheckPacket pc = (StartPingCheckPacket)PacketPool.Instance.GetPacket(PacketType.StartPingCheck);
- pc.Header.Reliable = false;
- pc.PingID.PingID = (byte)udpClient.CurrentPingSequence++;
- // We *could* get OldestUnacked, but it would hurt performance and not provide any benefit
- pc.PingID.OldestUnacked = 0;
- SendPacket(udpClient, pc, ThrottleOutPacketType.Unknown, false, null);
- udpClient.m_lastStartpingTimeMS = Util.EnvironmentTickCount();
- }
- public void CompletePing(LLUDPClient udpClient, byte pingID)
- {
- CompletePingCheckPacket completePing = new CompletePingCheckPacket();
- completePing.PingID.PingID = pingID;
- SendPacket(udpClient, completePing, ThrottleOutPacketType.Unknown, false, null);
- }
- public void HandleUnacked(LLClientView client)
- {
- LLUDPClient udpClient = client.UDPClient;
- if (!udpClient.IsConnected)
- return;
- // Disconnect an agent if no packets are received for some time
- int timeoutTicks = m_ackTimeout;
- // Allow more slack if the client is "paused" eg file upload dialogue is open
- // Some sort of limit is needed in case the client crashes, loses its network connection
- // or some other disaster prevents it from sendung the AgentResume
- if (udpClient.IsPaused)
- timeoutTicks = m_pausedAckTimeout;
- if (client.IsActive &&
- (Environment.TickCount & Int32.MaxValue) - udpClient.TickLastPacketReceived > timeoutTicks)
- {
- // We must set IsActive synchronously so that we can stop the packet loop reinvoking this method, even
- // though it's set later on by LLClientView.Close()
- client.IsActive = false;
- // Fire this out on a different thread so that we don't hold up outgoing packet processing for
- // everybody else if this is being called due to an ack timeout.
- // This is the same as processing as the async process of a logout request.
- Util.FireAndForget(
- o => DeactivateClientDueToTimeout(client, timeoutTicks), null, "LLUDPServer.DeactivateClientDueToTimeout");
- return;
- }
- // Get a list of all of the packets that have been sitting unacked longer than udpClient.RTO
- List<OutgoingPacket> expiredPackets = udpClient.NeedAcks.GetExpiredPackets(udpClient.RTO);
- if (expiredPackets != null)
- {
- //m_log.Debug("[LLUDPSERVER]: Handling " + expiredPackets.Count + " packets to " + udpClient.AgentID + ", RTO=" + udpClient.RTO);
- // Exponential backoff of the retransmission timeout
- udpClient.BackoffRTO();
- for (int i = 0; i < expiredPackets.Count; ++i)
- expiredPackets[i].UnackedMethod(expiredPackets[i]);
- }
- }
- public void ResendUnacked(OutgoingPacket outgoingPacket)
- {
- //m_log.DebugFormat("[LLUDPSERVER]: Resending packet #{0} (attempt {1}), {2}ms have passed",
- // outgoingPacket.SequenceNumber, outgoingPacket.ResendCount, Environment.TickCount - outgoingPacket.TickCount);
- // Set the resent flag
- outgoingPacket.Buffer.Data[0] = (byte)(outgoingPacket.Buffer.Data[0] | Helpers.MSG_RESENT);
- outgoingPacket.Category = ThrottleOutPacketType.Resend;
- // Bump up the resend count on this packet
- Interlocked.Increment(ref outgoingPacket.ResendCount);
- // Requeue or resend the packet
- if (!outgoingPacket.Client.EnqueueOutgoing(outgoingPacket, false))
- SendPacketFinal(outgoingPacket);
- }
- public void Flush(LLUDPClient udpClient)
- {
- // FIXME: Implement?
- }
- /// <summary>
- /// Actually send a packet to a client.
- /// </summary>
- /// <param name="outgoingPacket"></param>
- internal void SendPacketFinal(OutgoingPacket outgoingPacket)
- {
- UDPPacketBuffer buffer = outgoingPacket.Buffer;
- byte flags = buffer.Data[0];
- bool isResend = (flags & Helpers.MSG_RESENT) != 0;
- bool isReliable = (flags & Helpers.MSG_RELIABLE) != 0;
- bool isZerocoded = (flags & Helpers.MSG_ZEROCODED) != 0;
- LLUDPClient udpClient = outgoingPacket.Client;
- if (!udpClient.IsConnected)
- return;
- #region ACK Appending
- int dataLength = buffer.DataLength;
- // NOTE: I'm seeing problems with some viewers when ACKs are appended to zerocoded packets so I've disabled that here
- if (!isZerocoded && !isResend && outgoingPacket.UnackedMethod == null)
- {
- // Keep appending ACKs until there is no room left in the buffer or there are
- // no more ACKs to append
- uint ackCount = 0;
- uint ack;
- while (dataLength + 5 < buffer.Data.Length && udpClient.PendingAcks.Dequeue(out ack))
- {
- Utils.UIntToBytesBig(ack, buffer.Data, dataLength);
- dataLength += 4;
- ++ackCount;
- }
- if (ackCount > 0)
- {
- // Set the last byte of the packet equal to the number of appended ACKs
- buffer.Data[dataLength++] = (byte)ackCount;
- // Set the appended ACKs flag on this packet
- buffer.Data[0] = (byte)(buffer.Data[0] | Helpers.MSG_APPENDED_ACKS);
- }
- }
- buffer.DataLength = dataLength;
- #endregion ACK Appending
- #region Sequence Number Assignment
- if (!isResend)
- {
- // Not a resend, assign a new sequence number
- uint sequenceNumber = (uint)Interlocked.Increment(ref udpClient.CurrentSequence);
- Utils.UIntToBytesBig(sequenceNumber, buffer.Data, 1);
- outgoingPacket.SequenceNumber = sequenceNumber;
- if (isReliable)
- {
- // Add this packet to the list of ACK responses we are waiting on from the server
- udpClient.NeedAcks.Add(outgoingPacket);
- }
- }
- else
- {
- Interlocked.Increment(ref udpClient.PacketsResent);
- // We're not going to worry about interlock yet since its not currently critical that this total count
- // is 100% correct
- PacketsResentCount++;
- }
- #endregion Sequence Number Assignment
- // Stats tracking
- Interlocked.Increment(ref udpClient.PacketsSent);
- // We're not going to worry about interlock yet since its not currently critical that this total count
- // is 100% correct
- PacketsSentCount++;
- if (udpClient.DebugDataOutLevel > 0)
- m_log.DebugFormat(
- "[LLUDPSERVER]: Sending packet #{0} (rel: {1}, res: {2}) to {3} from {4}",
- outgoingPacket.SequenceNumber, isReliable, isResend, udpClient.AgentID, Scene.Name);
- // Put the UDP payload on the wire
- // AsyncBeginSend(buffer);
- SyncSend(buffer);
- // Keep track of when this packet was sent out (right now)
- outgoingPacket.TickCount = Environment.TickCount & Int32.MaxValue;
- }
- protected void RecordMalformedInboundPacket(IPEndPoint endPoint)
- {
- // if (m_malformedCount < 100)
- // m_log.DebugFormat("[LLUDPSERVER]: Dropped malformed packet: " + e.ToString());
- IncomingMalformedPacketCount++;
- if ((IncomingMalformedPacketCount % 10000) == 0)
- m_log.WarnFormat(
- "[LLUDPSERVER]: Received {0} malformed packets so far, probable network attack. Last was from {1}",
- IncomingMalformedPacketCount, endPoint);
- }
- public override void PacketReceived(UDPPacketBuffer buffer)
- {
- // Debugging/Profiling
- //try { Thread.CurrentThread.Name = "PacketReceived (" + m_scene.RegionInfo.RegionName + ")"; }
- //catch (Exception) { }
- // m_log.DebugFormat(
- // "[LLUDPSERVER]: Packet received from {0} in {1}", buffer.RemoteEndPoint, m_scene.RegionInfo.RegionName);
- LLUDPClient udpClient = null;
- Packet packet = null;
- int packetEnd = buffer.DataLength - 1;
- IPEndPoint endPoint = (IPEndPoint)buffer.RemoteEndPoint;
- #region Decoding
- if (buffer.DataLength < 7)
- {
- // m_log.WarnFormat(
- // "[LLUDPSERVER]: Dropping undersized packet with {0} bytes received from {1} in {2}",
- // buffer.DataLength, buffer.RemoteEndPoint, m_scene.RegionInfo.RegionName);
- RecordMalformedInboundPacket(endPoint);
- return; // Drop undersized packet
- }
- int headerLen = 7;
- if (buffer.Data[6] == 0xFF)
- {
- if (buffer.Data[7] == 0xFF)
- headerLen = 10;
- else
- headerLen = 8;
- }
- if (buffer.DataLength < headerLen)
- {
- // m_log.WarnFormat(
- // "[LLUDPSERVER]: Dropping packet with malformed header received from {0} in {1}",
- // buffer.RemoteEndPoint, m_scene.RegionInfo.RegionName);
- RecordMalformedInboundPacket(endPoint);
- return; // Malformed header
- }
- try
- {
- // packet = Packet.BuildPacket(buffer.Data, ref packetEnd,
- // // Only allocate a buffer for zerodecoding if the packet is zerocoded
- // ((buffer.Data[0] & Helpers.MSG_ZEROCODED) != 0) ? new byte[4096] : null);
- // If OpenSimUDPBase.UsePool == true (which is currently separate from the PacketPool) then we
- // assume that packet construction does not retain a reference to byte[] buffer.Data (instead, all
- // bytes are copied out).
- packet = PacketPool.Instance.GetPacket(buffer.Data, ref packetEnd,
- // Only allocate a buffer for zerodecoding if the packet is zerocoded
- ((buffer.Data[0] & Helpers.MSG_ZEROCODED) != 0) ? new byte[4096] : null);
- }
- catch (Exception e)
- {
- if (IncomingMalformedPacketCount < 100)
- m_log.DebugFormat("[LLUDPSERVER]: Dropped malformed packet: " + e.ToString());
- }
- // Fail-safe check
- if (packet == null)
- {
- if (IncomingMalformedPacketCount < 100)
- {
- m_log.WarnFormat("[LLUDPSERVER]: Malformed data, cannot parse {0} byte packet from {1}, data {2}:",
- buffer.DataLength, buffer.RemoteEndPoint, Utils.BytesToHexString(buffer.Data, buffer.DataLength, null));
- }
- RecordMalformedInboundPacket(endPoint);
- return;
- }
- #endregion Decoding
- #region Packet to Client Mapping
- // If there is already a client for this endpoint, don't process UseCircuitCode
- IClientAPI client = null;
- if (!Scene.TryGetClient(endPoint, out client) || !(client is LLClientView))
- {
- // UseCircuitCode handling
- if (packet.Type == PacketType.UseCircuitCode)
- {
- // And if there is a UseCircuitCode pending, also drop it
- lock (m_pendingCache)
- {
- if (m_pendingCache.Contains(endPoint))
- return;
- m_pendingCache.AddOrUpdate(endPoint, new Queue<UDPPacketBuffer>(), 60);
- }
- // We need to copy the endpoint so that it doesn't get changed when another thread reuses the
- // buffer.
- object[] array = new object[] { new IPEndPoint(endPoint.Address, endPoint.Port), packet };
- Util.FireAndForget(HandleUseCircuitCode, array);
- return;
- }
- }
- // If this is a pending connection, enqueue, don't process yet
- lock (m_pendingCache)
- {
- Queue<UDPPacketBuffer> queue;
- if (m_pendingCache.TryGetValue(endPoint, out queue))
- {
- //m_log.DebugFormat("[LLUDPSERVER]: Enqueued a {0} packet into the pending queue", packet.Type);
- queue.Enqueue(buffer);
- return;
- }
- /*
- else if (packet.Type == PacketType.CompleteAgentMovement)
- {
- // Send ack straight away to let the viewer know that we got it.
- SendAckImmediate(endPoint, packet.Header.Sequence);
- // We need to copy the endpoint so that it doesn't get changed when another thread reuses the
- // buffer.
- object[] array = new object[] { new IPEndPoint(endPoint.Address, endPoint.Port), packet };
- Util.FireAndForget(HandleCompleteMovementIntoRegion, array);
- return;
- }
- */
- }
- // Determine which agent this packet came from
- if (client == null || !(client is LLClientView))
- {
- //m_log.Debug("[LLUDPSERVER]: Received a " + packet.Type + " packet from an unrecognized source: " + address + " in " + m_scene.RegionInfo.RegionName);
- IncomingOrphanedPacketCount++;
- if ((IncomingOrphanedPacketCount % 10000) == 0)
- m_log.WarnFormat(
- "[LLUDPSERVER]: Received {0} orphaned packets so far. Last was from {1}",
- IncomingOrphanedPacketCount, endPoint);
- return;
- }
- udpClient = ((LLClientView)client).UDPClient;
- if (!udpClient.IsConnected)
- {
- m_log.Debug("[LLUDPSERVER]: Received a " + packet.Type + " packet for a unConnected client in " + Scene.RegionInfo.RegionName);
- return;
- }
- #endregion Packet to Client Mapping
- // Stats tracking
- Interlocked.Increment(ref udpClient.PacketsReceived);
- int now = Environment.TickCount & Int32.MaxValue;
- udpClient.TickLastPacketReceived = now;
- #region ACK Receiving
- // Handle appended ACKs
- if (packet.Header.AppendedAcks && packet.Header.AckList != null)
- {
- // m_log.DebugFormat(
- // "[LLUDPSERVER]: Handling {0} appended acks from {1} in {2}",
- // packet.Header.AckList.Length, client.Name, m_scene.Name);
- for (int i = 0; i < packet.Header.AckList.Length; i++)
- udpClient.NeedAcks.Acknowledge(packet.Header.AckList[i], now, packet.Header.Resent);
- }
- // Handle PacketAck packets
- if (packet.Type == PacketType.PacketAck)
- {
- PacketAckPacket ackPacket = (PacketAckPacket)packet;
- // m_log.DebugFormat(
- // "[LLUDPSERVER]: Handling {0} packet acks for {1} in {2}",
- // ackPacket.Packets.Length, client.Name, m_scene.Name);
- for (int i = 0; i < ackPacket.Packets.Length; i++)
- udpClient.NeedAcks.Acknowledge(ackPacket.Packets[i].ID, now, packet.Header.Resent);
- // We don't need to do anything else with PacketAck packets
- return;
- }
- #endregion ACK Receiving
- #region ACK Sending
- if (packet.Header.Reliable)
- {
- // m_log.DebugFormat(
- // "[LLUDPSERVER]: Adding ack request for {0} {1} from {2} in {3}",
- // packet.Type, packet.Header.Sequence, client.Name, m_scene.Name);
- udpClient.PendingAcks.Enqueue(packet.Header.Sequence);
- // This is a somewhat odd sequence of steps to pull the client.BytesSinceLastACK value out,
- // add the current received bytes to it, test if 2*MTU bytes have been sent, if so remove
- // 2*MTU bytes from the value and send ACKs, and finally add the local value back to
- // client.BytesSinceLastACK. Lockless thread safety
- int bytesSinceLastACK = Interlocked.Exchange(ref udpClient.BytesSinceLastACK, 0);
- bytesSinceLastACK += buffer.DataLength;
- if (bytesSinceLastACK > LLUDPServer.MTU * 2)
- {
- bytesSinceLastACK -= LLUDPServer.MTU * 2;
- SendAcks(udpClient);
- }
- Interlocked.Add(ref udpClient.BytesSinceLastACK, bytesSinceLastACK);
- }
- #endregion ACK Sending
- #region Incoming Packet Accounting
- // We're not going to worry about interlock yet since its not currently critical that this total count
- // is 100% correct
- if (packet.Header.Resent)
- IncomingPacketsResentCount++;
- // Check the archive of received reliable packet IDs to see whether we already received this packet
- if (packet.Header.Reliable && !udpClient.PacketArchive.TryEnqueue(packet.Header.Sequence))
- {
- if (packet.Header.Resent)
- m_log.DebugFormat(
- "[LLUDPSERVER]: Received a resend of already processed packet #{0}, type {1} from {2}",
- packet.Header.Sequence, packet.Type, client.Name);
- else
- m_log.WarnFormat(
- "[LLUDPSERVER]: Received a duplicate (not marked as resend) of packet #{0}, type {1} from {2}",
- packet.Header.Sequence, packet.Type, client.Name);
- // Avoid firing a callback twice for the same packet
- return;
- }
- #endregion Incoming Packet Accounting
- #region BinaryStats
- LogPacketHeader(true, udpClient.CircuitCode, 0, packet.Type, (ushort)packet.Length);
- #endregion BinaryStats
- //AgentUpdate removed from here
- #region Ping Check Handling
- if (packet.Type == PacketType.StartPingCheck)
- {
- // m_log.DebugFormat("[LLUDPSERVER]: Handling ping from {0} in {1}", client.Name, m_scene.Name);
- // We don't need to do anything else with ping checks
- StartPingCheckPacket startPing = (StartPingCheckPacket)packet;
- CompletePing(udpClient, startPing.PingID.PingID);
- if ((Environment.TickCount - m_elapsedMSSinceLastStatReport) >= 3000)
- {
- udpClient.SendPacketStats();
- m_elapsedMSSinceLastStatReport = Environment.TickCount;
- }
- return;
- }
- else if (packet.Type == PacketType.CompletePingCheck)
- {
- int t = Util.EnvironmentTickCountSubtract(udpClient.m_lastStartpingTimeMS);
- int c = udpClient.m_pingMS;
- c = 800 * c + 200 * t;
- c /= 1000;
- udpClient.m_pingMS = c;
- return;
- }
- #endregion Ping Check Handling
- IncomingPacket incomingPacket;
- // Inbox insertion
- if (UsePools)
- {
- incomingPacket = m_incomingPacketPool.GetObject();
- incomingPacket.Client = (LLClientView)client;
- incomingPacket.Packet = packet;
- }
- else
- {
- incomingPacket = new IncomingPacket((LLClientView)client, packet);
- }
- // if (incomingPacket.Packet.Type == PacketType.AgentUpdate ||
- // incomingPacket.Packet.Type == PacketType.ChatFromViewer)
- if (incomingPacket.Packet.Type == PacketType.ChatFromViewer)
- packetInbox.PriorityEnqueue(incomingPacket);
- else
- packetInbox.Enqueue(incomingPacket);
- }
- #region BinaryStats
- public class PacketLogger
- {
- public DateTime StartTime;
- public string Path = null;
- public System.IO.BinaryWriter Log = null;
- }
- public static PacketLogger PacketLog;
- protected static bool m_shouldCollectStats = false;
- // Number of seconds to log for
- static TimeSpan binStatsMaxFilesize = TimeSpan.FromSeconds(300);
- static object binStatsLogLock = new object();
- static string binStatsDir = "";
- //for Aggregated In/Out BW logging
- static bool m_aggregatedBWStats = false;
- static long m_aggregatedBytesIn = 0;
- static long m_aggregatedByestOut = 0;
- static object aggBWStatsLock = new object();
- public static long AggregatedLLUDPBytesIn
- {
- get { return m_aggregatedBytesIn; }
- }
- public static long AggregatedLLUDPBytesOut
- {
- get {return m_aggregatedByestOut;}
- }
- public static void LogPacketHeader(bool incoming, uint circuit, byte flags, PacketType packetType, ushort size)
- {
- if (m_aggregatedBWStats)
- {
- lock (aggBWStatsLock)
- {
- if (incoming)
- m_aggregatedBytesIn += size;
- else
- m_aggregatedByestOut += size;
- }
- }
- if (!m_shouldCollectStats) return;
- // Binary logging format is TTTTTTTTCCCCFPPPSS, T=Time, C=Circuit, F=Flags, P=PacketType, S=size
- // Put the incoming bit into the least significant bit of the flags byte
- if (incoming)
- flags |= 0x01;
- else
- flags &= 0xFE;
- // Put the flags byte into the most significant bits of the type integer
- uint type = (uint)packetType;
- type |= (uint)flags << 24;
- // m_log.Debug("1 LogPacketHeader(): Outside lock");
- lock (binStatsLogLock)
- {
- DateTime now = DateTime.Now;
- // m_log.Debug("2 LogPacketHeader(): Inside lock. now is " + now.Ticks);
- try
- {
- if (PacketLog == null || (now > PacketLog.StartTime + binStatsMaxFilesize))
- {
- if (PacketLog != null && PacketLog.Log != null)
- {
- PacketLog.Log.Close();
- }
- // First log file or time has expired, start writing to a new log file
- PacketLog = new PacketLogger();
- PacketLog.StartTime = now;
- PacketLog.Path = (binStatsDir.Length > 0 ? binStatsDir + System.IO.Path.DirectorySeparatorChar.ToString() : "")
- + String.Format("packets-{0}.log", now.ToString("yyyyMMddHHmmss"));
- PacketLog.Log = new BinaryWriter(File.Open(PacketLog.Path, FileMode.Append, FileAccess.Write));
- }
- // Serialize the data
- byte[] output = new byte[18];
- Buffer.BlockCopy(BitConverter.GetBytes(now.Ticks), 0, output, 0, 8);
- Buffer.BlockCopy(BitConverter.GetBytes(circuit), 0, output, 8, 4);
- Buffer.BlockCopy(BitConverter.GetBytes(type), 0, output, 12, 4);
- Buffer.BlockCopy(BitConverter.GetBytes(size), 0, output, 16, 2);
- // Write the serialized data to disk
- if (PacketLog != null && PacketLog.Log != null)
- PacketLog.Log.Write(output);
- }
- catch (Exception ex)
- {
- m_log.Error("Packet statistics gathering failed: " + ex.Message, ex);
- if (PacketLog.Log != null)
- {
- PacketLog.Log.Close();
- }
- PacketLog = null;
- }
- }
- }
- #endregion BinaryStats
- protected void HandleUseCircuitCode(object o)
- {
- IPEndPoint endPoint = null;
- IClientAPI client = null;
- try
- {
- // DateTime startTime = DateTime.Now;
- object[] array = (object[])o;
- endPoint = (IPEndPoint)array[0];
- UseCircuitCodePacket uccp = (UseCircuitCodePacket)array[1];
- m_log.DebugFormat(
- "[LLUDPSERVER]: Handling UseCircuitCode request for circuit {0} to {1} from IP {2}",
- uccp.CircuitCode.Code, Scene.RegionInfo.RegionName, endPoint);
- AuthenticateResponse sessionInfo;
- if (IsClientAuthorized(uccp, out sessionInfo))
- {
- AgentCircuitData aCircuit = Scene.AuthenticateHandler.GetAgentCircuitData(uccp.CircuitCode.Code);
- // Begin the process of adding the client to the simulator
- client
- = AddClient(
- uccp.CircuitCode.Code,
- uccp.CircuitCode.ID,
- uccp.CircuitCode.SessionID,
- endPoint,
- sessionInfo);
- // This will be true if the client is new, e.g. not
- // an existing child agent, and there is no circuit data
- if (client != null && aCircuit == null)
- {
- Scene.CloseAgent(client.AgentId, true);
- return;
- }
- // Now we know we can handle more data
- Thread.Sleep(200);
- // Obtain the pending queue and remove it from the cache
- Queue<UDPPacketBuffer> queue = null;
- lock (m_pendingCache)
- {
- if (!m_pendingCache.TryGetValue(endPoint, out queue))
- {
- m_log.DebugFormat("[LLUDPSERVER]: Client created but no pending queue present");
- return;
- }
- m_pendingCache.Remove(endPoint);
- }
- m_log.DebugFormat("[LLUDPSERVER]: Client created, processing pending queue, {0} entries", queue.Count);
- // Reinject queued packets
- while (queue.Count > 0)
- {
- UDPPacketBuffer buf = queue.Dequeue();
- PacketReceived(buf);
- }
- queue = null;
- // Send ack straight away to let the viewer know that the connection is active.
- // The client will be null if it already exists (e.g. if on a region crossing the client sends a use
- // circuit code to the existing child agent. This is not particularly obvious.
- SendAckImmediate(endPoint, uccp.Header.Sequence);
- // We only want to send initial data to new clients, not ones which are being converted from child to root.
- if (client != null)
- {
- bool tp = (aCircuit.teleportFlags > 0);
- // Let's delay this for TP agents, otherwise the viewer doesn't know where to get resources from
- if (!tp)
- client.SceneAgent.SendInitialDataToMe();
- }
- }
- else
- {
- // Don't create clients for unauthorized requesters.
- m_log.WarnFormat(
- "[LLUDPSERVER]: Ignoring connection request for {0} to {1} with unknown circuit code {2} from IP {3}",
- uccp.CircuitCode.ID, Scene.RegionInfo.RegionName, uccp.CircuitCode.Code, endPoint);
- lock (m_pendingCache)
- m_pendingCache.Remove(endPoint);
- }
- // m_log.DebugFormat(
- // "[LLUDPSERVER]: Handling UseCircuitCode request from {0} took {1}ms",
- // buffer.RemoteEndPoint, (DateTime.Now - startTime).Milliseconds);
- }
- catch (Exception e)
- {
- m_log.ErrorFormat(
- "[LLUDPSERVER]: UseCircuitCode handling from endpoint {0}, client {1} {2} failed. Exception {3}{4}",
- endPoint != null ? endPoint.ToString() : "n/a",
- client != null ? client.Name : "unknown",
- client != null ? client.AgentId.ToString() : "unknown",
- e.Message,
- e.StackTrace);
- }
- }
- /*
- protected void HandleCompleteMovementIntoRegion(object o)
- {
- IPEndPoint endPoint = null;
- IClientAPI client = null;
- try
- {
- object[] array = (object[])o;
- endPoint = (IPEndPoint)array[0];
- CompleteAgentMovementPacket packet = (CompleteAgentMovementPacket)array[1];
- m_log.DebugFormat(
- "[LLUDPSERVER]: Handling CompleteAgentMovement request from {0} in {1}", endPoint, Scene.Name);
- // Determine which agent this packet came from
- // We need to wait here because in when using the OpenSimulator V2 teleport protocol to travel to a destination
- // simulator with no existing child presence, the viewer (at least LL 3.3.4) will send UseCircuitCode
- // and then CompleteAgentMovement immediately without waiting for an ack. As we are now handling these
- // packets asynchronously, we need to account for this thread proceeding more quickly than the
- // UseCircuitCode thread.
- int count = 40;
- while (count-- > 0)
- {
- if (Scene.TryGetClient(endPoint, out client))
- {
- if (!client.IsActive)
- {
- // This check exists to catch a condition where the client has been closed by another thread
- // but has not yet been removed from the client manager (and possibly a new connection has
- // not yet been established).
- m_log.DebugFormat(
- "[LLUDPSERVER]: Received a CompleteAgentMovement from {0} for {1} in {2} but client is not active yet. Waiting.",
- endPoint, client.Name, Scene.Name);
- }
- else if (client.SceneAgent == null)
- {
- // This check exists to catch a condition where the new client has been added to the client
- // manager but the SceneAgent has not yet been set in Scene.AddNewAgent(). If we are too
- // eager, then the new ScenePresence may not have registered a listener for this messsage
- // before we try to process it.
- // XXX: A better long term fix may be to add the SceneAgent before the client is added to
- // the client manager
- m_log.DebugFormat(
- "[LLUDPSERVER]: Received a CompleteAgentMovement from {0} for {1} in {2} but client SceneAgent not set yet. Waiting.",
- endPoint, client.Name, Scene.Name);
- }
- else
- {
- break;
- }
- }
- else
- {
- m_log.DebugFormat(
- "[LLUDPSERVER]: Received a CompleteAgentMovement from {0} in {1} but no client exists yet. Waiting.",
- endPoint, Scene.Name);
- }
- Thread.Sleep(200);
- }
- if (client == null)
- {
- m_log.DebugFormat(
- "[LLUDPSERVER]: No client found for CompleteAgentMovement from {0} in {1} after wait. Dropping.",
- endPoint, Scene.Name);
- return;
- }
- else if (!client.IsActive || client.SceneAgent == null)
- {
- // This check exists to catch a condition where the client has been closed by another thread
- // but has not yet been removed from the client manager.
- // The packet could be simply ignored but it is useful to know if this condition occurred for other debugging
- // purposes.
- m_log.DebugFormat(
- "[LLUDPSERVER]: Received a CompleteAgentMovement from {0} for {1} in {2} but client is not active after wait. Dropping.",
- endPoint, client.Name, Scene.Name);
- return;
- }
- IncomingPacket incomingPacket1;
- // Inbox insertion
- if (UsePools)
- {
- incomingPacket1 = m_incomingPacketPool.GetObject();
- incomingPacket1.Client = (LLClientView)client;
- incomingPacket1.Packet = packet;
- }
- else
- {
- incomingPacket1 = new IncomingPacket((LLClientView)client, packet);
- }
- packetInbox.Enqueue(incomingPacket1);
- }
- catch (Exception e)
- {
- m_log.ErrorFormat(
- "[LLUDPSERVER]: CompleteAgentMovement handling from endpoint {0}, client {1} {2} failed. Exception {3}{4}",
- endPoint != null ? endPoint.ToString() : "n/a",
- client != null ? client.Name : "unknown",
- client != null ? client.AgentId.ToString() : "unknown",
- e.Message,
- e.StackTrace);
- }
- }
- */
- /// <summary>
- /// Send an ack immediately to the given endpoint.
- /// </summary>
- /// <remarks>
- /// FIXME: Might be possible to use SendPacketData() like everything else, but this will require refactoring so
- /// that we can obtain the UDPClient easily at this point.
- /// </remarks>
- /// <param name="remoteEndpoint"></param>
- /// <param name="sequenceNumber"></param>
- protected void SendAckImmediate(IPEndPoint remoteEndpoint, uint sequenceNumber)
- {
- PacketAckPacket ack = new PacketAckPacket();
- ack.Header.Reliable = false;
- ack.Packets = new PacketAckPacket.PacketsBlock[1];
- ack.Packets[0] = new PacketAckPacket.PacketsBlock();
- ack.Packets[0].ID = sequenceNumber;
- SendAckImmediate(remoteEndpoint, ack);
- }
- public virtual void SendAckImmediate(IPEndPoint remoteEndpoint, PacketAckPacket ack)
- {
- byte[] packetData = ack.ToBytes();
- int length = packetData.Length;
- UDPPacketBuffer buffer = new UDPPacketBuffer(remoteEndpoint, length);
- buffer.DataLength = length;
- Buffer.BlockCopy(packetData, 0, buffer.Data, 0, length);
- // AsyncBeginSend(buffer);
- SyncSend(buffer);
- }
- protected bool IsClientAuthorized(UseCircuitCodePacket useCircuitCode, out AuthenticateResponse sessionInfo)
- {
- UUID agentID = useCircuitCode.CircuitCode.ID;
- UUID sessionID = useCircuitCode.CircuitCode.SessionID;
- uint circuitCode = useCircuitCode.CircuitCode.Code;
- sessionInfo = m_circuitManager.AuthenticateSession(sessionID, agentID, circuitCode);
- return sessionInfo.Authorised;
- }
- /// <summary>
- /// Add a client.
- /// </summary>
- /// <param name="circuitCode"></param>
- /// <param name="agentID"></param>
- /// <param name="sessionID"></param>
- /// <param name="remoteEndPoint"></param>
- /// <param name="sessionInfo"></param>
- /// <returns>The client if it was added. Null if the client already existed.</returns>
- protected virtual IClientAPI AddClient(
- uint circuitCode, UUID agentID, UUID sessionID, IPEndPoint remoteEndPoint, AuthenticateResponse sessionInfo)
- {
- IClientAPI client = null;
- bool createNew = false;
- // We currently synchronize this code across the whole scene to avoid issues such as
- // http://opensimulator.org/mantis/view.php?id=5365 However, once locking per agent circuit can be done
- // consistently, this lock could probably be removed.
- lock (this)
- {
- if (!Scene.TryGetClient(agentID, out client))
- {
- createNew = true;
- }
- else
- {
- if (client.SceneAgent == null)
- {
- Scene.CloseAgent(agentID, true);
- createNew = true;
- }
- }
- if (createNew)
- {
- LLUDPClient udpClient = new LLUDPClient(this, ThrottleRates, Throttle, circuitCode, agentID, remoteEndPoint, m_defaultRTO, m_maxRTO);
- client = new LLClientView(Scene, this, udpClient, sessionInfo, agentID, sessionID, circuitCode);
- client.OnLogout += LogoutHandler;
- client.DebugPacketLevel = DefaultClientPacketDebugLevel;
- ((LLClientView)client).DisableFacelights = m_disableFacelights;
- client.Start();
- }
- }
- return client;
- }
- /// <summary>
- /// Deactivates the client if we don't receive any packets within a certain amount of time (default 60 seconds).
- /// </summary>
- /// <remarks>
- /// If a connection is active then we will always receive packets even if nothing else is happening, due to
- /// regular client pings.
- /// </remarks>
- /// <param name='client'></param>
- /// <param name='timeoutTicks'></param>
- protected void DeactivateClientDueToTimeout(LLClientView client, int timeoutTicks)
- {
- lock (client.CloseSyncLock)
- {
- ClientLogoutsDueToNoReceives++;
- if (client.SceneAgent != null)
- {
- m_log.WarnFormat(
- "[LLUDPSERVER]: No packets received from {0} agent of {1} for {2}ms in {3}. Disconnecting.",
- client.SceneAgent.IsChildAgent ? "child" : "root", client.Name, timeoutTicks, Scene.Name);
- if (!client.SceneAgent.IsChildAgent)
- client.Kick("Simulator logged you out due to connection timeout.");
- }
- }
- if (!Scene.CloseAgent(client.AgentId, true))
- client.Close(true,true);
- }
- protected void IncomingPacketHandler()
- {
- Thread.CurrentThread.Priority = ThreadPriority.Highest;
- IncomingPacket incomingPacket;
- // Set this culture for the thread that incoming packets are received
- // on to en-US to avoid number parsing issues
- Culture.SetCurrentCulture();
- while (IsRunningInbound)
- {
- Scene.ThreadAlive(1);
- try
- {
- incomingPacket = packetInbox.Dequeue(250);
- if (incomingPacket != null && IsRunningInbound)
- {
- ProcessInPacket(incomingPacket);
- if (UsePools)
- {
- incomingPacket.Client = null;
- m_incomingPacketPool.ReturnObject(incomingPacket);
- }
- incomingPacket = null;
- }
- }
- catch(Exception ex)
- {
- m_log.Error("[LLUDPSERVER]: Error in the incoming packet handler loop: " + ex.Message, ex);
- }
- Watchdog.UpdateThread();
- }
- if (packetInbox.Count() > 0)
- m_log.Warn("[LLUDPSERVER]: IncomingPacketHandler is shutting down, dropping " + packetInbox.Count() + " packets");
- packetInbox.Clear();
- Watchdog.RemoveThread();
- }
- protected void OutgoingPacketHandler()
- {
- Thread.CurrentThread.Priority = ThreadPriority.Highest;
- // Set this culture for the thread that outgoing packets are sent
- // on to en-US to avoid number parsing issues
- Culture.SetCurrentCulture();
- // Typecast the function to an Action<IClientAPI> once here to avoid allocating a new
- // Action generic every round
- Action<IClientAPI> clientPacketHandler = ClientOutgoingPacketHandler;
- while (base.IsRunningOutbound)
- {
- Scene.ThreadAlive(2);
- try
- {
- m_packetSent = false;
- #region Update Timers
- m_resendUnacked = false;
- m_sendAcks = false;
- m_sendPing = false;
- // Update elapsed time
- double thisTick = Util.GetTimeStampMS();
- // update some 1ms resolution chained timers
- m_elapsedMSOutgoingPacketHandler += thisTick - m_tickLastOutgoingPacketHandler;
- m_tickLastOutgoingPacketHandler = thisTick;
- // Check for pending outgoing resends every 100ms
- if (m_elapsedMSOutgoingPacketHandler >= 100.0)
- {
- m_resendUnacked = true;
- m_elapsedMSOutgoingPacketHandler = 0.0;
- m_elapsed100MSOutgoingPacketHandler += 1;
- }
- // Check for pending outgoing ACKs every 500ms
- if (m_elapsed100MSOutgoingPacketHandler >= 5)
- {
- m_sendAcks = true;
- m_elapsed100MSOutgoingPacketHandler = 0;
- m_elapsed500MSOutgoingPacketHandler += 1;
- }
- // Send pings to clients every 5000ms
- if (m_elapsed500MSOutgoingPacketHandler >= 10)
- {
- m_sendPing = true;
- m_elapsed500MSOutgoingPacketHandler = 0;
- }
- #endregion Update Timers
- // Handle outgoing packets, resends, acknowledgements, and pings for each
- // client. m_packetSent will be set to true if a packet is sent
- Scene.ForEachClient(clientPacketHandler);
- m_currentOutgoingClient = null;
- // If nothing was sent, sleep for the minimum amount of time before a
- // token bucket could get more tokens
- if(Scene.GetNumberOfClients() == 0)
- {
- Thread.Sleep(100);
- }
- else if (!m_packetSent)
- // Thread.Sleep((int)TickCountResolution); outch this is bad on linux
- Thread.Sleep(15); // match the 16ms of windows7, dont ask 16 or win may decide to do 32ms.
- Watchdog.UpdateThread();
- }
- catch (Exception ex)
- {
- m_log.Error("[LLUDPSERVER]: OutgoingPacketHandler loop threw an exception: " + ex.Message, ex);
- }
- }
- Watchdog.RemoveThread();
- }
- protected void ClientOutgoingPacketHandler(IClientAPI client)
- {
- m_currentOutgoingClient = client;
- try
- {
- if (client is LLClientView)
- {
- LLClientView llClient = (LLClientView)client;
- LLUDPClient udpClient = llClient.UDPClient;
- if (udpClient.IsConnected)
- {
- if (m_resendUnacked)
- HandleUnacked(llClient);
- if (m_sendAcks)
- SendAcks(udpClient);
- if (m_sendPing)
- SendPing(udpClient);
- // Dequeue any outgoing packets that are within the throttle limits
- if (udpClient.DequeueOutgoing())
- m_packetSent = true;
- }
- }
- }
- catch (Exception ex)
- {
- m_log.Error(
- string.Format("[LLUDPSERVER]: OutgoingPacketHandler iteration for {0} threw ", client.Name), ex);
- }
- }
- #region Emergency Monitoring
- // Alternative packet handler fuull of instrumentation
- // Handy for hunting bugs
- protected Stopwatch watch1 = new Stopwatch();
- protected Stopwatch watch2 = new Stopwatch();
- protected float avgProcessingTicks = 0;
- protected float avgResendUnackedTicks = 0;
- protected float avgSendAcksTicks = 0;
- protected float avgSendPingTicks = 0;
- protected float avgDequeueTicks = 0;
- protected long nticks = 0;
- protected long nticksUnack = 0;
- protected long nticksAck = 0;
- protected long nticksPing = 0;
- protected int npacksSent = 0;
- protected int npackNotSent = 0;
- /// <summary>
- /// Number of inbound packets processed since startup.
- /// </summary>
- public long IncomingPacketsProcessed { get; protected set; }
- #endregion
- protected void ProcessInPacket(IncomingPacket incomingPacket)
- {
- Packet packet = incomingPacket.Packet;
- LLClientView client = incomingPacket.Client;
- if(!client.IsActive)
- return;
- m_currentIncomingClient = client;
- try
- {
- // Process this packet
- client.ProcessInPacket(packet);
- }
- catch(ThreadAbortException)
- {
- // If something is trying to abort the packet processing thread, take that as a hint that it's time to shut down
- m_log.Info("[LLUDPSERVER]: Caught a thread abort, shutting down the LLUDP server");
- Stop();
- }
- catch(Exception e)
- {
- // Don't let a failure in an individual client thread crash the whole sim.
- m_log.Error(
- string.Format(
- "[LLUDPSERVER]: Client packet handler for {0} for packet {1} threw ",
- client.Name,packet.Type),
- e);
- }
- finally
- {
- m_currentIncomingClient = null;
- }
- IncomingPacketsProcessed++;
- }
- protected void LogoutHandler(IClientAPI client)
- {
- client.SendLogoutPacket();
- if (!client.IsLoggingOut)
- {
- client.IsLoggingOut = true;
- Scene.CloseAgent(client.AgentId, false);
- }
- }
- }
- }
|