/* * 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; using System.Collections.Generic; using System.Net; using System.Reflection; using System.Threading; using log4net; using Nini.Config; using Mono.Addins; using OpenMetaverse; using OpenMetaverse.Messages.Linden; using OpenMetaverse.Packets; using OpenMetaverse.StructuredData; using OpenSim.Framework; using OpenSim.Framework.Console; using OpenSim.Framework.Servers; using OpenSim.Framework.Servers.HttpServer; using OpenSim.Region.Framework.Interfaces; using OpenSim.Region.Framework.Scenes; using BlockingLLSDQueue = OpenSim.Framework.BlockingQueue; using Caps=OpenSim.Framework.Capabilities.Caps; namespace OpenSim.Region.ClientStack.Linden { public struct QueueItem { public int id; public OSDMap body; } [Extension(Path = "/OpenSim/RegionModules", NodeName = "RegionModule", Id = "EventQueueGetModule")] public class EventQueueGetModule : IEventQueue, INonSharedRegionModule { private static readonly ILog m_log = LogManager.GetLogger(MethodBase.GetCurrentMethod().DeclaringType); /// /// Debug level. /// public int DebugLevel { get; set; } protected Scene m_scene; private Dictionary m_ids = new Dictionary(); private Dictionary> queues = new Dictionary>(); private Dictionary m_QueueUUIDAvatarMapping = new Dictionary(); private Dictionary m_AvatarQueueUUIDMapping = new Dictionary(); #region INonSharedRegionModule methods public virtual void Initialise(IConfigSource config) { } public void AddRegion(Scene scene) { m_scene = scene; scene.RegisterModuleInterface(this); scene.EventManager.OnClientClosed += ClientClosed; scene.EventManager.OnMakeChildAgent += MakeChildAgent; scene.EventManager.OnRegisterCaps += OnRegisterCaps; MainConsole.Instance.Commands.AddCommand( "Debug", false, "debug eq", "debug eq [0|1|2]", "Turn on event queue debugging\n" + " <= 0 - turns off all event queue logging\n" + " >= 1 - turns on outgoing event logging\n" + " >= 2 - turns on poll notification", HandleDebugEq); MainConsole.Instance.Commands.AddCommand( "Debug", false, "show eq", "show eq", "Show contents of event queues for logged in avatars. Used for debugging.", HandleShowEq); } public void RemoveRegion(Scene scene) { if (m_scene != scene) return; scene.EventManager.OnClientClosed -= ClientClosed; scene.EventManager.OnMakeChildAgent -= MakeChildAgent; scene.EventManager.OnRegisterCaps -= OnRegisterCaps; scene.UnregisterModuleInterface(this); m_scene = null; } public void RegionLoaded(Scene scene) { } public virtual void Close() { } public virtual string Name { get { return "EventQueueGetModule"; } } public Type ReplaceableInterface { get { return null; } } #endregion protected void HandleDebugEq(string module, string[] args) { int debugLevel; if (!(args.Length == 3 && int.TryParse(args[2], out debugLevel))) { MainConsole.Instance.OutputFormat("Usage: debug eq [0|1|2]"); } else { DebugLevel = debugLevel; MainConsole.Instance.OutputFormat( "Set event queue debug level to {0} in {1}", DebugLevel, m_scene.RegionInfo.RegionName); } } protected void HandleShowEq(string module, string[] args) { MainConsole.Instance.OutputFormat("For scene {0}", m_scene.Name); lock (queues) { foreach (KeyValuePair> kvp in queues) { MainConsole.Instance.OutputFormat( "For agent {0} there are {1} messages queued for send.", kvp.Key, kvp.Value.Count); } } } /// /// Always returns a valid queue /// /// /// private Queue TryGetQueue(UUID agentId) { lock (queues) { if (!queues.ContainsKey(agentId)) { /* m_log.DebugFormat( "[EVENTQUEUE]: Adding new queue for agent {0} in region {1}", agentId, m_scene.RegionInfo.RegionName); */ queues[agentId] = new Queue(); } return queues[agentId]; } } /// /// May return a null queue /// /// /// private Queue GetQueue(UUID agentId) { lock (queues) { if (queues.ContainsKey(agentId)) { return queues[agentId]; } else return null; } } #region IEventQueue Members public bool Enqueue(OSD ev, UUID avatarID) { //m_log.DebugFormat("[EVENTQUEUE]: Enqueuing event for {0} in region {1}", avatarID, m_scene.RegionInfo.RegionName); try { Queue queue = GetQueue(avatarID); if (queue != null) lock (queue) queue.Enqueue(ev); } catch (NullReferenceException e) { m_log.Error("[EVENTQUEUE] Caught exception: " + e); return false; } return true; } #endregion private void ClientClosed(UUID agentID, Scene scene) { // m_log.DebugFormat("[EVENTQUEUE]: Closed client {0} in region {1}", agentID, m_scene.RegionInfo.RegionName); int count = 0; while (queues.ContainsKey(agentID) && queues[agentID].Count > 0 && count++ < 5) { Thread.Sleep(1000); } lock (queues) { queues.Remove(agentID); } List removeitems = new List(); lock (m_AvatarQueueUUIDMapping) m_AvatarQueueUUIDMapping.Remove(agentID); // lock (m_AvatarQueueUUIDMapping) // { // foreach (UUID ky in m_AvatarQueueUUIDMapping.Keys) // { //// m_log.DebugFormat("[EVENTQUEUE]: Found key {0} in m_AvatarQueueUUIDMapping while looking for {1}", ky, AgentID); // if (ky == agentID) // { // removeitems.Add(ky); // } // } // // foreach (UUID ky in removeitems) // { // UUID eventQueueGetUuid = m_AvatarQueueUUIDMapping[ky]; // m_AvatarQueueUUIDMapping.Remove(ky); // // string eqgPath = GenerateEqgCapPath(eventQueueGetUuid); // MainServer.Instance.RemovePollServiceHTTPHandler("", eqgPath); // //// m_log.DebugFormat( //// "[EVENT QUEUE GET MODULE]: Removed EQG handler {0} for {1} in {2}", //// eqgPath, agentID, m_scene.RegionInfo.RegionName); // } // } UUID searchval = UUID.Zero; removeitems.Clear(); lock (m_QueueUUIDAvatarMapping) { foreach (UUID ky in m_QueueUUIDAvatarMapping.Keys) { searchval = m_QueueUUIDAvatarMapping[ky]; if (searchval == agentID) { removeitems.Add(ky); } } foreach (UUID ky in removeitems) m_QueueUUIDAvatarMapping.Remove(ky); } } private void MakeChildAgent(ScenePresence avatar) { //m_log.DebugFormat("[EVENTQUEUE]: Make Child agent {0} in region {1}.", avatar.UUID, m_scene.RegionInfo.RegionName); //lock (m_ids) // { //if (m_ids.ContainsKey(avatar.UUID)) //{ // close the event queue. //m_ids[avatar.UUID] = -1; //} //} } /// /// Generate an Event Queue Get handler path for the given eqg uuid. /// /// private string GenerateEqgCapPath(UUID eqgUuid) { return string.Format("/CAPS/EQG/{0}/", eqgUuid); } public void OnRegisterCaps(UUID agentID, Caps caps) { // Register an event queue for the client //m_log.DebugFormat( // "[EVENTQUEUE]: OnRegisterCaps: agentID {0} caps {1} region {2}", // agentID, caps, m_scene.RegionInfo.RegionName); // Let's instantiate a Queue for this agent right now TryGetQueue(agentID); UUID eventQueueGetUUID; lock (m_AvatarQueueUUIDMapping) { // Reuse open queues. The client does! if (m_AvatarQueueUUIDMapping.ContainsKey(agentID)) { //m_log.DebugFormat("[EVENTQUEUE]: Found Existing UUID!"); eventQueueGetUUID = m_AvatarQueueUUIDMapping[agentID]; } else { eventQueueGetUUID = UUID.Random(); //m_log.DebugFormat("[EVENTQUEUE]: Using random UUID!"); } } lock (m_QueueUUIDAvatarMapping) { if (!m_QueueUUIDAvatarMapping.ContainsKey(eventQueueGetUUID)) m_QueueUUIDAvatarMapping.Add(eventQueueGetUUID, agentID); } lock (m_AvatarQueueUUIDMapping) { if (!m_AvatarQueueUUIDMapping.ContainsKey(agentID)) m_AvatarQueueUUIDMapping.Add(agentID, eventQueueGetUUID); } caps.RegisterPollHandler( "EventQueueGet", new PollServiceEventArgs(null, GenerateEqgCapPath(eventQueueGetUUID), HasEvents, GetEvents, NoEvents, agentID, 40000)); Random rnd = new Random(Environment.TickCount); lock (m_ids) { if (!m_ids.ContainsKey(agentID)) m_ids.Add(agentID, rnd.Next(30000000)); } } public bool HasEvents(UUID requestID, UUID agentID) { // Don't use this, because of race conditions at agent closing time //Queue queue = TryGetQueue(agentID); Queue queue = GetQueue(agentID); if (queue != null) lock (queue) { //m_log.WarnFormat("POLLED FOR EVENTS BY {0} in {1} -- {2}", agentID, m_scene.RegionInfo.RegionName, queue.Count); return queue.Count > 0; } return false; } /// /// Logs a debug line for an outbound event queue message if appropriate. /// /// Element containing message private void LogOutboundDebugMessage(OSD element, UUID agentId) { if (element is OSDMap) { OSDMap ev = (OSDMap)element; m_log.DebugFormat( "Eq OUT {0,-30} to {1,-20} {2,-20}", ev["message"], m_scene.GetScenePresence(agentId).Name, m_scene.RegionInfo.RegionName); } } public Hashtable GetEvents(UUID requestID, UUID pAgentId) { if (DebugLevel >= 2) m_log.WarnFormat("POLLED FOR EQ MESSAGES BY {0} in {1}", pAgentId, m_scene.RegionInfo.RegionName); Queue queue = TryGetQueue(pAgentId); OSD element; lock (queue) { if (queue.Count == 0) return NoEvents(requestID, pAgentId); element = queue.Dequeue(); // 15s timeout } int thisID = 0; lock (m_ids) thisID = m_ids[pAgentId]; OSDArray array = new OSDArray(); if (element == null) // didn't have an event in 15s { // Send it a fake event to keep the client polling! It doesn't like 502s like the proxys say! array.Add(EventQueueHelper.KeepAliveEvent()); //m_log.DebugFormat("[EVENTQUEUE]: adding fake event for {0} in region {1}", pAgentId, m_scene.RegionInfo.RegionName); } else { if (DebugLevel > 0) LogOutboundDebugMessage(element, pAgentId); array.Add(element); lock (queue) { while (queue.Count > 0) { element = queue.Dequeue(); if (DebugLevel > 0) LogOutboundDebugMessage(element, pAgentId); array.Add(element); thisID++; } } } OSDMap events = new OSDMap(); events.Add("events", array); events.Add("id", new OSDInteger(thisID)); lock (m_ids) { m_ids[pAgentId] = thisID + 1; } Hashtable responsedata = new Hashtable(); responsedata["int_response_code"] = 200; responsedata["content_type"] = "application/xml"; responsedata["keepalive"] = false; responsedata["reusecontext"] = false; responsedata["str_response_string"] = OSDParser.SerializeLLSDXmlString(events); //m_log.DebugFormat("[EVENTQUEUE]: sending response for {0} in region {1}: {2}", pAgentId, m_scene.RegionInfo.RegionName, responsedata["str_response_string"]); return responsedata; } public Hashtable NoEvents(UUID requestID, UUID agentID) { Hashtable responsedata = new Hashtable(); responsedata["int_response_code"] = 502; responsedata["content_type"] = "text/plain"; responsedata["keepalive"] = false; responsedata["reusecontext"] = false; responsedata["str_response_string"] = "Upstream error: "; responsedata["error_status_text"] = "Upstream error:"; responsedata["http_protocol_version"] = "HTTP/1.0"; return responsedata; } // public Hashtable ProcessQueue(Hashtable request, UUID agentID, Caps caps) // { // // TODO: this has to be redone to not busy-wait (and block the thread), // // TODO: as soon as we have a non-blocking way to handle HTTP-requests. // //// if (m_log.IsDebugEnabled) //// { //// String debug = "[EVENTQUEUE]: Got request for agent {0} in region {1} from thread {2}: [ "; //// foreach (object key in request.Keys) //// { //// debug += key.ToString() + "=" + request[key].ToString() + " "; //// } //// m_log.DebugFormat(debug + " ]", agentID, m_scene.RegionInfo.RegionName, System.Threading.Thread.CurrentThread.Name); //// } // // Queue queue = TryGetQueue(agentID); // OSD element; // // lock (queue) // element = queue.Dequeue(); // 15s timeout // // Hashtable responsedata = new Hashtable(); // // int thisID = 0; // lock (m_ids) // thisID = m_ids[agentID]; // // if (element == null) // { // //m_log.ErrorFormat("[EVENTQUEUE]: Nothing to process in " + m_scene.RegionInfo.RegionName); // if (thisID == -1) // close-request // { // m_log.ErrorFormat("[EVENTQUEUE]: 404 in " + m_scene.RegionInfo.RegionName); // responsedata["int_response_code"] = 404; //501; //410; //404; // responsedata["content_type"] = "text/plain"; // responsedata["keepalive"] = false; // responsedata["str_response_string"] = "Closed EQG"; // return responsedata; // } // responsedata["int_response_code"] = 502; // responsedata["content_type"] = "text/plain"; // responsedata["keepalive"] = false; // responsedata["str_response_string"] = "Upstream error: "; // responsedata["error_status_text"] = "Upstream error:"; // responsedata["http_protocol_version"] = "HTTP/1.0"; // return responsedata; // } // // OSDArray array = new OSDArray(); // if (element == null) // didn't have an event in 15s // { // // Send it a fake event to keep the client polling! It doesn't like 502s like the proxys say! // array.Add(EventQueueHelper.KeepAliveEvent()); // //m_log.DebugFormat("[EVENTQUEUE]: adding fake event for {0} in region {1}", agentID, m_scene.RegionInfo.RegionName); // } // else // { // array.Add(element); // // if (element is OSDMap) // { // OSDMap ev = (OSDMap)element; // m_log.DebugFormat( // "[EVENT QUEUE GET MODULE]: Eq OUT {0} to {1}", // ev["message"], m_scene.GetScenePresence(agentID).Name); // } // // lock (queue) // { // while (queue.Count > 0) // { // element = queue.Dequeue(); // // if (element is OSDMap) // { // OSDMap ev = (OSDMap)element; // m_log.DebugFormat( // "[EVENT QUEUE GET MODULE]: Eq OUT {0} to {1}", // ev["message"], m_scene.GetScenePresence(agentID).Name); // } // // array.Add(element); // thisID++; // } // } // } // // OSDMap events = new OSDMap(); // events.Add("events", array); // // events.Add("id", new OSDInteger(thisID)); // lock (m_ids) // { // m_ids[agentID] = thisID + 1; // } // // responsedata["int_response_code"] = 200; // responsedata["content_type"] = "application/xml"; // responsedata["keepalive"] = false; // responsedata["str_response_string"] = OSDParser.SerializeLLSDXmlString(events); // // m_log.DebugFormat("[EVENTQUEUE]: sending response for {0} in region {1}: {2}", agentID, m_scene.RegionInfo.RegionName, responsedata["str_response_string"]); // // return responsedata; // } // public Hashtable EventQueuePath2(Hashtable request) // { // string capuuid = (string)request["uri"]; //path.Replace("/CAPS/EQG/",""); // // pull off the last "/" in the path. // Hashtable responsedata = new Hashtable(); // capuuid = capuuid.Substring(0, capuuid.Length - 1); // capuuid = capuuid.Replace("/CAPS/EQG/", ""); // UUID AvatarID = UUID.Zero; // UUID capUUID = UUID.Zero; // // // parse the path and search for the avatar with it registered // if (UUID.TryParse(capuuid, out capUUID)) // { // lock (m_QueueUUIDAvatarMapping) // { // if (m_QueueUUIDAvatarMapping.ContainsKey(capUUID)) // { // AvatarID = m_QueueUUIDAvatarMapping[capUUID]; // } // } // // if (AvatarID != UUID.Zero) // { // return ProcessQueue(request, AvatarID, m_scene.CapsModule.GetCapsForUser(AvatarID)); // } // else // { // responsedata["int_response_code"] = 404; // responsedata["content_type"] = "text/plain"; // responsedata["keepalive"] = false; // responsedata["str_response_string"] = "Not Found"; // responsedata["error_status_text"] = "Not Found"; // responsedata["http_protocol_version"] = "HTTP/1.0"; // return responsedata; // // return 404 // } // } // else // { // responsedata["int_response_code"] = 404; // responsedata["content_type"] = "text/plain"; // responsedata["keepalive"] = false; // responsedata["str_response_string"] = "Not Found"; // responsedata["error_status_text"] = "Not Found"; // responsedata["http_protocol_version"] = "HTTP/1.0"; // return responsedata; // // return 404 // } // } public OSD EventQueueFallBack(string path, OSD request, string endpoint) { // This is a fallback element to keep the client from loosing EventQueueGet // Why does CAPS fail sometimes!? m_log.Warn("[EVENTQUEUE]: In the Fallback handler! We lost the Queue in the rest handler!"); string capuuid = path.Replace("/CAPS/EQG/",""); capuuid = capuuid.Substring(0, capuuid.Length - 1); // UUID AvatarID = UUID.Zero; UUID capUUID = UUID.Zero; if (UUID.TryParse(capuuid, out capUUID)) { /* Don't remove this yet code cleaners! * Still testing this! * lock (m_QueueUUIDAvatarMapping) { if (m_QueueUUIDAvatarMapping.ContainsKey(capUUID)) { AvatarID = m_QueueUUIDAvatarMapping[capUUID]; } } if (AvatarID != UUID.Zero) { // Repair the CAP! //OpenSim.Framework.Capabilities.Caps caps = m_scene.GetCapsHandlerForUser(AvatarID); //string capsBase = "/CAPS/EQG/"; //caps.RegisterHandler("EventQueueGet", //new RestHTTPHandler("POST", capsBase + capUUID.ToString() + "/", //delegate(Hashtable m_dhttpMethod) //{ // return ProcessQueue(m_dhttpMethod, AvatarID, caps); //})); // start new ID sequence. Random rnd = new Random(System.Environment.TickCount); lock (m_ids) { if (!m_ids.ContainsKey(AvatarID)) m_ids.Add(AvatarID, rnd.Next(30000000)); } int thisID = 0; lock (m_ids) thisID = m_ids[AvatarID]; BlockingLLSDQueue queue = GetQueue(AvatarID); OSDArray array = new OSDArray(); LLSD element = queue.Dequeue(15000); // 15s timeout if (element == null) { array.Add(EventQueueHelper.KeepAliveEvent()); } else { array.Add(element); while (queue.Count() > 0) { array.Add(queue.Dequeue(1)); thisID++; } } OSDMap events = new OSDMap(); events.Add("events", array); events.Add("id", new LLSDInteger(thisID)); lock (m_ids) { m_ids[AvatarID] = thisID + 1; } return events; } else { return new LLSD(); } * */ } else { //return new LLSD(); } return new OSDString("shutdown404!"); } public void DisableSimulator(ulong handle, UUID avatarID) { OSD item = EventQueueHelper.DisableSimulator(handle); Enqueue(item, avatarID); } public virtual void EnableSimulator(ulong handle, IPEndPoint endPoint, UUID avatarID) { OSD item = EventQueueHelper.EnableSimulator(handle, endPoint); Enqueue(item, avatarID); } public virtual void EstablishAgentCommunication(UUID avatarID, IPEndPoint endPoint, string capsPath) { OSD item = EventQueueHelper.EstablishAgentCommunication(avatarID, endPoint.ToString(), capsPath); Enqueue(item, avatarID); } public virtual void TeleportFinishEvent(ulong regionHandle, byte simAccess, IPEndPoint regionExternalEndPoint, uint locationID, uint flags, string capsURL, UUID avatarID) { OSD item = EventQueueHelper.TeleportFinishEvent(regionHandle, simAccess, regionExternalEndPoint, locationID, flags, capsURL, avatarID); Enqueue(item, avatarID); } public virtual void CrossRegion(ulong handle, Vector3 pos, Vector3 lookAt, IPEndPoint newRegionExternalEndPoint, string capsURL, UUID avatarID, UUID sessionID) { OSD item = EventQueueHelper.CrossRegion(handle, pos, lookAt, newRegionExternalEndPoint, capsURL, avatarID, sessionID); Enqueue(item, avatarID); } public void ChatterboxInvitation(UUID sessionID, string sessionName, UUID fromAgent, string message, UUID toAgent, string fromName, byte dialog, uint timeStamp, bool offline, int parentEstateID, Vector3 position, uint ttl, UUID transactionID, bool fromGroup, byte[] binaryBucket) { OSD item = EventQueueHelper.ChatterboxInvitation(sessionID, sessionName, fromAgent, message, toAgent, fromName, dialog, timeStamp, offline, parentEstateID, position, ttl, transactionID, fromGroup, binaryBucket); Enqueue(item, toAgent); //m_log.InfoFormat("########### eq ChatterboxInvitation #############\n{0}", item); } public void ChatterBoxSessionAgentListUpdates(UUID sessionID, UUID fromAgent, UUID toAgent, bool canVoiceChat, bool isModerator, bool textMute) { OSD item = EventQueueHelper.ChatterBoxSessionAgentListUpdates(sessionID, fromAgent, canVoiceChat, isModerator, textMute); Enqueue(item, toAgent); //m_log.InfoFormat("########### eq ChatterBoxSessionAgentListUpdates #############\n{0}", item); } public void ParcelProperties(ParcelPropertiesMessage parcelPropertiesMessage, UUID avatarID) { OSD item = EventQueueHelper.ParcelProperties(parcelPropertiesMessage); Enqueue(item, avatarID); } public void GroupMembership(AgentGroupDataUpdatePacket groupUpdate, UUID avatarID) { OSD item = EventQueueHelper.GroupMembership(groupUpdate); Enqueue(item, avatarID); } public void QueryReply(PlacesReplyPacket groupUpdate, UUID avatarID) { OSD item = EventQueueHelper.PlacesQuery(groupUpdate); Enqueue(item, avatarID); } public OSD ScriptRunningEvent(UUID objectID, UUID itemID, bool running, bool mono) { return EventQueueHelper.ScriptRunningReplyEvent(objectID, itemID, running, mono); } public OSD BuildEvent(string eventName, OSD eventBody) { return EventQueueHelper.BuildEvent(eventName, eventBody); } public void partPhysicsProperties(uint localID, byte physhapetype, float density, float friction, float bounce, float gravmod,UUID avatarID) { OSD item = EventQueueHelper.partPhysicsProperties(localID, physhapetype, density, friction, bounce, gravmod); Enqueue(item, avatarID); } } }