Mobserver Out
DEBUG [2008-07-15 08:20:37,406] main                 Using property file multiverse.properties
DEBUG [2008-07-15 08:20:37,406] main                 Properties are:
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Startup = Instance,Proxy,Voice
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Voice = Instance
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Inventory = 
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.ObjectManager = 
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Instance = ObjectManager,Quest,MobManager,Inventory,WorldManager
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.db_user = root
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Proxy = Instance,Voice
DEBUG [2008-07-15 08:20:37,406] main                     log4j.appender.FILE.File = ${multiverse.logs}/${multiverse.loggername}.out
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.Trainer = 
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.plugin_dep.WorldManager = 
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.startup_timeout = 120
DEBUG [2008-07-15 08:20:37,406] main                     log4j.appender.FILE.MaxFileSize = 50MB
DEBUG [2008-07-15 08:20:37,406] main                     log4j.appender.FILE.layout = org.apache.log4j.PatternLayout
DEBUG [2008-07-15 08:20:37,406] main                     multiverse.rotate_logs_on_startup = true
DEBUG [2008-07-15 08:20:37,421] main                     log4j.rootLogger = DEBUG, FILE
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.db_hostname = localhost
DEBUG [2008-07-15 08:20:37,421] main                     log4j.appender.FILE.MaxBackupIndex = 3
DEBUG [2008-07-15 08:20:37,421] main                     log4j.appender.FILE.layout.ConversionPattern = %-5p [%d{ISO8601}] %-20t %m%n
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.MobManager = ObjectManager,WorldManager,Inventory,Quest
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.log_level = 1
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.db_type = mysql
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.db_driver = com.mysql.jdbc.Driver
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.log_proxy_histograms = true
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.voiceserver = :same
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.sampleworld.MobManager = ObjectManager,WorldManager,Inventory,Quest,Combat
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.buildNumber = 1063
DEBUG [2008-07-15 08:20:37,421] main                     log4j.appender.FILE = org.apache.log4j.RollingFileAppender
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.worldmgrport = 5040
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.buildDate = 20080623.171257
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.worldname = sampleworld
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.msgsvr_hostname = localhost
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.db_password = test
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.voiceport = 5051
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.proxyport = 5050
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.msgsvr_port = 20374
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.db_name = multiverse
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.precreated_positional_voice_group = 1
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.proxyserver = :same
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.jdbcJarPath = g:\mysql-connector-java-3.1.14\mysql-connector-java-3.1.14-bin.jar
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.buildString = GA
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.Combat = 
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.Login = ObjectManager,Instance
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.Quest = 
DEBUG [2008-07-15 08:20:37,421] main                     multiverse.plugin_dep.ClassAbility = 
INFO  [2008-07-15 08:20:37,421] main                 The log level is 1
INFO  [2008-07-15 08:20:37,421] main                 Entered MarshallingRuntime.initialize()
DEBUG [2008-07-15 08:20:37,421] main                 MarshallingRuntime.initialize: Installing primitive types
DEBUG [2008-07-15 08:20:37,421] main                 MarshallingRuntime.initialize: Initializing InjectionGenerator
DEBUG [2008-07-15 08:20:37,984] main                 MarshallingRuntime.initialize: Registering Marshalling Classes
DEBUG [2008-07-15 08:20:37,984] main                 Processing marshaller file '..\config\common\mvmarshallers.txt'
DEBUG [2008-07-15 08:20:38,000] main                 Processing of marshallers file '..\config\common\mvmarshallers.txt' completed
DEBUG [2008-07-15 08:20:38,000] main                 Processing marshaller file '..\config\sampleworld\worldmarshallers.txt'
WARN  [2008-07-15 08:20:38,000] main                 Didn't find marshallers file '..\config\sampleworld\worldmarshallers.txt'
INFO  [2008-07-15 08:20:38,000] main                 MarshallingRuntime.initialize: Registered 237 marshalling classes
DEBUG [2008-07-15 08:20:38,171] main                 MarshallingRuntime.initialize: Finished checking type references
DEBUG [2008-07-15 08:20:38,234] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$TargetedComMessage', typeNum 177/0xb1
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.msgsys.TargetMessage', typeNum 39/0x27
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.msgsys.Message', typeNum 100/0x64
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$LoadSubObjectMessage', typeNum 221/0xdd
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$LoadSubObjectMessage', typeNum 162/0xa2
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.server.messages.OIDNamespaceMessage', typeNum 88/0x58
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.msgsys.SubjectMessage', typeNum 37/0x25
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$GetQuestStatusMessage', typeNum 255/0xff
DEBUG [2008-07-15 08:20:38,250] main                 Generated marshalling for 'multiverse.mars.objects.KillQuestState', typeNum 233/0xe9
DEBUG [2008-07-15 08:20:38,609] main                 Generated marshalling for 'multiverse.mars.objects.QuestState', typeNum 243/0xf3
DEBUG [2008-07-15 08:20:38,625] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$StateStatusChangeMessage', typeNum 260/0x104
DEBUG [2008-07-15 08:20:38,625] main                 Generated marshalling for 'multiverse.msgsys.FilterUpdate$Instruction', typeNum 31/0x1f
DEBUG [2008-07-15 08:20:38,625] main                 Recorded by-hand marshaller 'multiverse.msgsys.MessageType', typeNum 101/0x65
DEBUG [2008-07-15 08:20:38,625] main                 Generated marshalling for 'multiverse.server.objects.RoadRegionConfig', typeNum 131/0x83
DEBUG [2008-07-15 08:20:38,625] main                 Generated marshalling for 'multiverse.server.objects.RegionConfig', typeNum 129/0x81
DEBUG [2008-07-15 08:20:38,703] main                 Generated marshalling for 'multiverse.server.objects.Entity', typeNum 114/0x72
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.server.objects.NamedPropertyClass', typeNum 122/0x7a
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.server.engine.EnginePlugin$GetPropertyMessage', typeNum 61/0x3d
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$RefreshWNodeMessage', typeNum 204/0xcc
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.server.engine.BaseBehavior$GotoCommandMessage', typeNum 56/0x38
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.server.engine.Behavior$CommandMessage', typeNum 59/0x3b
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.msgsys.IntegerResponseMessage', typeNum 46/0x2e
DEBUG [2008-07-15 08:20:38,734] main                 Generated marshalling for 'multiverse.msgsys.ResponseMessage', typeNum 104/0x68
DEBUG [2008-07-15 08:20:38,859] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$GetInstanceInfoMessage', typeNum 146/0x92
DEBUG [2008-07-15 08:20:38,859] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DisplayContextMessage', typeNum 183/0xb7
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.mars.plugins.MarsInventoryPlugin$EquipMap', typeNum 253/0xfd
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$UpdateWorldNodeReqMessage', typeNum 218/0xda
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.pathing.PathPolygon', typeNum 85/0x55
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.msgsys.AllocNameMessage', typeNum 102/0x66
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.mars.objects.MarsAttachSocket', typeNum 234/0xea
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.objects.DisplayState', typeNum 113/0x71
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.engine.BaseBehavior$StopCommandMessage', typeNum 57/0x39
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.msgsys.Filter', typeNum 29/0x1d
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.objects.ObjectTracker$NotifyReactionRadiusMessage', typeNum 261/0x105
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.msgsys.GenericResponseMessage', typeNum 42/0x2a
DEBUG [2008-07-15 08:20:38,875] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$MobPathMessage', typeNum 190/0xbe
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$MobPathMessageBaseClass', typeNum 191/0xbf
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$SaveObjectDataMessage', typeNum 164/0xa4
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$OrientMessage', typeNum 201/0xc9
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ExtensionMessage', typeNum 185/0xb9
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.messages.PropertyMessage', typeNum 89/0x59
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$NewRegionMessage', typeNum 196/0xc4
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$TargetedPropertyMessage', typeNum 215/0xd7
DEBUG [2008-07-15 08:20:38,890] main                 Generated marshalling for 'multiverse.server.objects.Region', typeNum 127/0x7f
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.engine.Perceiver', typeNum 68/0x44
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ComMessage', typeNum 176/0xb0
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$TargetedExtensionMessage', typeNum 214/0xd6
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.objects.TerrainDecalData', typeNum 137/0x89
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.msgsys.FilterUpdateMessage', typeNum 32/0x20
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$DeleteSubObjectMessage', typeNum 173/0xad
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$PerceiverRegionsMessage', typeNum 203/0xcb
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.messages.SearchMessage', typeNum 98/0x62
DEBUG [2008-07-15 08:20:38,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$HostInstanceMessage', typeNum 222/0xde
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.objects.OceanData', typeNum 124/0x7c
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$AutoAttackMessage', typeNum 247/0xf7
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$CombatTargetMessage', typeNum 252/0xfc
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$GetTemplateMessage', typeNum 169/0xa9
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.engine.TerrainConfig', typeNum 69/0x45
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.engine.EnginePlugin$TransferObjectMessage', typeNum 64/0x40
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.msgsys.AllocNameResponseMessage', typeNum 103/0x67
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$InstanceEntryReqMessage', typeNum 145/0x91
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.objects.DisplayContext', typeNum 111/0x6f
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ObjInfoReqMessage', typeNum 198/0xc6
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerPlugin$LocationNamespaceFilter', typeNum 223/0xdf
DEBUG [2008-07-15 08:20:38,921] main                 Generated marshalling for 'multiverse.server.messages.NamespaceFilter', typeNum 94/0x5e
DEBUG [2008-07-15 08:20:39,453] main                 Generated marshalling for 'multiverse.msgsys.MessageTypeFilter', typeNum 35/0x23
DEBUG [2008-07-15 08:20:39,453] main                 Generated marshalling for 'multiverse.mars.objects.CollectionQuestState', typeNum 231/0xe7
DEBUG [2008-07-15 08:20:39,453] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$RemoveOrFindItemMessage', typeNum 156/0x9c
DEBUG [2008-07-15 08:20:39,453] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$ItemInfo', typeNum 154/0x9a
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.server.objects.Boundary', typeNum 108/0x6c
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerPlugin$WorldManagerTransferFilter', typeNum 224/0xe0
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.server.engine.EnginePlugin$TransferFilter', typeNum 65/0x41
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.msgsys.HelloResponseMessage', typeNum 33/0x21
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.server.messages.PerceptionMessage', typeNum 91/0x5b
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$LoadObjectDataMessage', typeNum 160/0xa0
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.msgsys.AdvertiseMessage', typeNum 26/0x1a
DEBUG [2008-07-15 08:20:39,578] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$DamageMessage', typeNum 249/0xf9
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.msgsys.AgentStateMessage', typeNum 28/0x1c
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DirLocOrientMessage', typeNum 182/0xb6
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.msgsys.ExceptionData', typeNum 52/0x34
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$InvUpdateMessage', typeNum 152/0x98
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.objects.PermissionCallback', typeNum 125/0x7d
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.msgsys.AwaitPluginDependentsMessage', typeNum 49/0x31
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$CreateInstanceMessage', typeNum 144/0x90
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.engine.BaseBehavior$ArrivedEventMessage', typeNum 54/0x36
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.engine.Behavior$EventMessage', typeNum 60/0x3c
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.pathing.PathPortal', typeNum 86/0x56
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$AbilityUpdateMessage', typeNum 246/0xf6
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.msgsys.ExceptionResponseMessage', typeNum 51/0x33
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.server.pathing.PathObjectType', typeNum 84/0x54
DEBUG [2008-07-15 08:20:39,593] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$StartAbilityMessage', typeNum 251/0xfb
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.msgsys.TargetFilter', typeNum 45/0x2d
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.Color', typeNum 109/0x6d
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.messages.PerceptionMessage$ObjectNote', typeNum 92/0x5c
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.messages.LoginMessage', typeNum 96/0x60
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.msgsys.SubjectFilter', typeNum 44/0x2c
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.LightData', typeNum 120/0x78
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$NewRemoteObjectMessage', typeNum 197/0xc5
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.Fog', typeNum 117/0x75
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.PointLight', typeNum 126/0x7e
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.Light', typeNum 119/0x77
DEBUG [2008-07-15 08:20:39,609] main                 Generated marshalling for 'multiverse.server.objects.MVObject', typeNum 121/0x79
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.math.Point', typeNum 76/0x4c
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.engine.BasicWorldNode', typeNum 58/0x3a
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.objects.ObjState', typeNum 123/0x7b
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.math.MVVector', typeNum 74/0x4a
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.math.Quaternion', typeNum 77/0x4d
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.pathing.PathData', typeNum 80/0x50
DEBUG [2008-07-15 08:20:39,625] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ObjectInfo', typeNum 200/0xc8
DEBUG [2008-07-15 08:20:39,750] main                 Generated marshalling for 'multiverse.msgsys.SubscribeMessage', typeNum 38/0x26
DEBUG [2008-07-15 08:20:39,750] main                 Generated marshalling for 'multiverse.server.engine.EnginePlugin$SetPropertyMessage', typeNum 63/0x3f
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$RequestQuestInfoMessage', typeNum 259/0x103
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$NewQuestStateMessage', typeNum 256/0x100
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.objects.Vector2', typeNum 138/0x8a
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$SaveObjectMessage', typeNum 165/0xa5
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$MobPathReqMessage', typeNum 192/0xc0
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.msgsys.LongResponseMessage', typeNum 47/0x2f
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerPlugin$WorldManagerFilter', typeNum 225/0xe1
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$GenerateSubObjectMessage', typeNum 159/0x9f
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.util.AnimationCommand', typeNum 78/0x4e
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ReparentWNodeReqMessage', typeNum 205/0xcd
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.msgsys.AgentHelloMessage', typeNum 27/0x1b
DEBUG [2008-07-15 08:20:39,765] main                 Generated marshalling for 'multiverse.server.messages.PopulationFilter', typeNum 105/0x69
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$RoadInfo', typeNum 206/0xce
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SetWorldNodeReqMessage', typeNum 209/0xd1
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SetAmbientLightMessage', typeNum 208/0xd0
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.messages.LogoutMessage', typeNum 97/0x61
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.objects.Road', typeNum 130/0x82
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.mars.core.EquipActivateHook', typeNum 230/0xe6
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$CooldownMessage', typeNum 248/0xf8
DEBUG [2008-07-15 08:20:39,781] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$SubObjectDepsReadyMessage', typeNum 167/0xa7
DEBUG [2008-07-15 08:20:39,796] main                 Generated marshalling for 'multiverse.msgsys.StackFrame', typeNum 53/0x35
DEBUG [2008-07-15 08:20:39,796] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DisplayContextReqMessage', typeNum 184/0xb8
DEBUG [2008-07-15 08:20:39,796] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$ConcludeMessage', typeNum 254/0xfe
DEBUG [2008-07-15 08:20:39,796] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$RequestConcludeMessage', typeNum 258/0x102
DEBUG [2008-07-15 08:20:39,812] main                 Generated marshalling for 'multiverse.server.engine.PropertySearch', typeNum 70/0x46
DEBUG [2008-07-15 08:20:39,812] main                 Generated marshalling for 'multiverse.server.objects.Bag', typeNum 106/0x6a
DEBUG [2008-07-15 08:20:39,812] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$GenerateObjectMessage', typeNum 157/0x9d
DEBUG [2008-07-15 08:20:39,875] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$AbilityProgressMessage', typeNum 245/0xf5
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$GetMarkerMessage', typeNum 148/0x94
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.plugins.MobManagerClient$CreateSpawnGeneratorMessage', typeNum 262/0x106
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.objects.SpawnData', typeNum 135/0x87
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DespawnedMessage', typeNum 180/0xb4
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$AnimationMessage', typeNum 175/0xaf
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SpawnReqMessage', typeNum 211/0xd3
DEBUG [2008-07-15 08:20:39,890] main                 Generated marshalling for 'multiverse.server.pathing.PathArc', typeNum 79/0x4f
DEBUG [2008-07-15 08:20:39,890] main                 Recorded by-hand marshaller 'multiverse.server.objects.ObjectType', typeNum 115/0x73
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.engine.SearchSelection', typeNum 71/0x47
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.msgsys.MessageTrigger', typeNum 34/0x22
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.objects.EntityHandle', typeNum 116/0x74
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ModifyDisplayContextMessage', typeNum 193/0xc1
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.mars.objects.MarsKillQuest', typeNum 239/0xef
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.mars.objects.MarsQuest', typeNum 241/0xf1
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.math.FPoint', typeNum 72/0x48
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.mars.plugins.QuestClient$QuestResponseMessage', typeNum 257/0x101
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DespawnReqMessage', typeNum 179/0xb3
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.mars.objects.MarsEquipSlot', typeNum 238/0xee
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SoundMessage', typeNum 210/0xd2
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.server.pathing.PathInfo', typeNum 82/0x52
DEBUG [2008-07-15 08:20:39,906] main                 Generated marshalling for 'multiverse.mars.objects.CollectionQuestState$CollectionGoalStatus', typeNum 232/0xe8
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.msgsys.UnsubscribeMessage', typeNum 40/0x28
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ComReqMessage', typeNum 178/0xb2
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$FreeObjectMessage', typeNum 195/0xc3
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.pathing.PathEdge', typeNum 81/0x51
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerPlugin$WorldManagerFilter$InstanceGeometry', typeNum 226/0xe2
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.objects.RoadSegment', typeNum 132/0x84
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.engine.BaseBehavior$FollowCommandMessage', typeNum 55/0x37
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$UpdateMessage', typeNum 216/0xd8
DEBUG [2008-07-15 08:20:39,921] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$SetPersistenceMessage', typeNum 166/0xa6
DEBUG [2008-07-15 08:20:39,921] main                 Recorded by-hand marshaller 'multiverse.mars.core.DCMap', typeNum 229/0xe5
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.mars.plugins.AnimationClient$InvokeEffectMessage', typeNum 244/0xf4
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$PerceptionInfo', typeNum 220/0xdc
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$NewDirLightMessage', typeNum 194/0xc2
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.server.messages.SearchMessageFilter', typeNum 99/0x63
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerPlugin$HostInstanceFilter', typeNum 227/0xe3
DEBUG [2008-07-15 08:20:39,968] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$FixWorldNodeMessage', typeNum 174/0xae
DEBUG [2008-07-15 08:20:39,984] main                 Generated marshalling for 'multiverse.server.messages.NamespaceMessage', typeNum 87/0x57
DEBUG [2008-07-15 08:20:39,984] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$FreeRoadMessage', typeNum 188/0xbc
DEBUG [2008-07-15 08:20:39,984] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$SetSubPersistenceMessage', typeNum 168/0xa8
DEBUG [2008-07-15 08:20:39,984] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$UpdateWorldNodeMessage', typeNum 217/0xd9
DEBUG [2008-07-15 08:20:39,984] main                 Generated marshalling for 'multiverse.server.objects.InstanceRestorePoint', typeNum 139/0x8b
DEBUG [2008-07-15 08:20:40,000] main                 Recorded by-hand marshaller 'multiverse.server.messages.PerceptionFilter', typeNum 90/0x5a
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$AddItemMessage', typeNum 151/0x97
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.mars.objects.MarsKillQuest$KillGoal', typeNum 240/0xf0
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$LoadObjectMessage', typeNum 161/0xa1
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$DeleteObjectMessage', typeNum 172/0xac
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.mars.objects.MarsEquipInfo', typeNum 237/0xed
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$GetRegionMessage', typeNum 149/0x95
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$FogMessage', typeNum 186/0xba
DEBUG [2008-07-15 08:20:40,000] main                 Generated marshalling for 'multiverse.mars.objects.MarsStat', typeNum 242/0xf2
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.objects.SearchEntry', typeNum 142/0x8e
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.math.Plane', typeNum 75/0x4b
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$DetachMessage', typeNum 181/0xb5
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SpawnedMessage', typeNum 212/0xd4
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.objects.BinaryState', typeNum 107/0x6b
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.objects.SoundRegionConfig', typeNum 134/0x86
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$ActivateMessage', typeNum 150/0x96
DEBUG [2008-07-15 08:20:40,015] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$ObjInfoRespMessage', typeNum 199/0xc7
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.mars.plugins.CombatClient$ReleaseObjectMessage', typeNum 250/0xfa
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.objects.SoundData', typeNum 133/0x85
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$RoadMessage', typeNum 207/0xcf
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.objects.Template', typeNum 136/0x88
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.msgsys.MessageTypeSessionIdFilter', typeNum 43/0x2b
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.msgsys.NewAgentMessage', typeNum 36/0x24
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$MobPathCorrectionMessage', typeNum 189/0xbd
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.mars.objects.MarsCollectionQuest', typeNum 235/0xeb
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.engine.FixedPerceiver', typeNum 66/0x42
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$InvUpdateMessage$InvPos', typeNum 153/0x99
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$UnloadSubObjectMessage', typeNum 171/0xab
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.msgsys.PluginAvailableMessage', typeNum 50/0x32
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.objects.Marker', typeNum 140/0x8c
DEBUG [2008-07-15 08:20:40,031] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$FreeRemoteObjectMessage', typeNum 187/0xbb
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.msgsys.BooleanResponseMessage', typeNum 48/0x30
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.objects.Marker$Search', typeNum 141/0x8d
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.math.Geometry', typeNum 73/0x49
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.messages.SubObjectFilter', typeNum 95/0x5f
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.objects.FogRegionConfig', typeNum 118/0x76
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.engine.EnginePlugin$PluginStateMessage', typeNum 62/0x3e
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$OrientReqMessage', typeNum 202/0xca
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.pathing.PathObject', typeNum 83/0x53
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$WorldNodeCorrectMessage', typeNum 219/0xdb
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.objects.DisplayContext$Submesh', typeNum 112/0x70
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.messages.PerceptionTrigger', typeNum 93/0x5d
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.msgsys.FilterUpdate', typeNum 30/0x1e
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.objects.DirectionalLight', typeNum 110/0x6e
DEBUG [2008-07-15 08:20:40,046] main                 Generated marshalling for 'multiverse.server.plugins.WorldManagerClient$SysChatMessage', typeNum 213/0xd5
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$GenerateStructureMessage', typeNum 158/0x9e
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$RegisterTemplateMessage', typeNum 163/0xa3
DEBUG [2008-07-15 08:20:40,062] main                 Recorded by-hand marshaller 'multiverse.server.engine.Namespace', typeNum 67/0x43
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.objects.Region$Search', typeNum 128/0x80
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.InventoryClient$LootAllMessage', typeNum 155/0x9b
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.mars.objects.MarsCollectionQuest$CollectionGoal', typeNum 236/0xec
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$InstanceInfo', typeNum 147/0x93
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.mars.core.AbilityActivateHook', typeNum 228/0xe4
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.msgsys.GenericMessage', typeNum 41/0x29
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.InstanceClient$RegisterInstanceTemplateMessage', typeNum 143/0x8f
DEBUG [2008-07-15 08:20:40,062] main                 Generated marshalling for 'multiverse.server.plugins.ObjectManagerClient$UnloadObjectMessage', typeNum 170/0xaa
INFO  [2008-07-15 08:20:40,140] main                 Multiverse server version 1.5 1063 (GA 20080623.171257)
DEBUG [2008-07-15 08:20:41,281] main                 Engine, multiverse.cputime_logging_interval disabled
INFO  [2008-07-15 08:20:42,125] main                 Engine: PROCESS ID IS 4724@sp
INFO  [2008-07-15 08:20:42,125] main                 Engine: server name is mobserver
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: option i: mobserver_local.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: option i: ..\config\common\mvmessages.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: option i: ..\config\sampleworld\worldmessages.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\global_props.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\sampleworld\global_props.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\mobserver_init.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\sampleworld\mobserver_init.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\questplugin.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\trainerplugin.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\mobserver.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\sampleworld\mobserver.py
DEBUG [2008-07-15 08:20:42,171] main                 Engine, populateScriptList: nonoption args element: ..\config\common\extensions_mobserver.py
DEBUG [2008-07-15 08:20:44,281] main                 Engine, Engine: reading in script: mobserver_local.py
DEBUG [2008-07-15 08:20:44,281] main                 Engine, Executing init script file: mobserver_local.py
DEBUG [2008-07-15 08:20:44,281] main                 runPYFile: file=mobserver_local.py
DEBUG [2008-07-15 08:20:44,453] main                 BasicInterpolator.startUpdates: updating with interval=5000
DEBUG [2008-07-15 08:20:44,453] main                 mobserver_local: done with local config
DEBUG [2008-07-15 08:20:44,453] main                 Engine, script completed
DEBUG [2008-07-15 08:20:44,453] main                 Engine, Engine: reading in script: ..\config\common\mvmessages.py
DEBUG [2008-07-15 08:20:44,453] main                 Engine, Executing init script file: ..\config\common\mvmessages.py
DEBUG [2008-07-15 08:20:44,453] main                 runPYFile: file=..\config\common\mvmessages.py
DEBUG [2008-07-15 08:20:44,578] main                 Adding msg type 'mv.PROPERTY', msgNumber 1/0x1
DEBUG [2008-07-15 08:20:44,578] main                 Adding msg type 'mv.LOGIN', msgNumber 2/0x2
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.LOGOUT', msgNumber 3/0x3
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.ANIMATION', msgNumber 4/0x4
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.COM', msgNumber 5/0x5
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.COM_REQ', msgNumber 6/0x6
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DC_REQ', msgNumber 7/0x7
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DESPAWNED', msgNumber 8/0x8
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DESPAWN_REQ', msgNumber 9/0x9
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DETACH', msgNumber 10/0xa
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DIR_LOC_ORIENT', msgNumber 11/0xb
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DISPLAY_CONTEXT', msgNumber 12/0xc
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.EXTENSION', msgNumber 13/0xd
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.FOG', msgNumber 14/0xe
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.FREE_REMOTE_OBJ', msgNumber 15/0xf
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GETWNODE_REQ', msgNumber 16/0x10
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.MOB_PATH', msgNumber 17/0x11
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.MOB_PATH_CORRECTION', msgNumber 18/0x12
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.MOB_PATH_REQ', msgNumber 19/0x13
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.MODIFY_DC', msgNumber 20/0x14
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.NEW_DIRLIGHT', msgNumber 21/0x15
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.FREE_OBJECT', msgNumber 22/0x16
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.NEW_REGION', msgNumber 23/0x17
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.NEW_REMOTE_OBJ', msgNumber 24/0x18
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.OBJINFO_REQ', msgNumber 25/0x19
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.ORIENT', msgNumber 26/0x1a
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.ORIENT_REQ', msgNumber 27/0x1b
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.PERCEIVER_REGIONS', msgNumber 28/0x1c
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.REFRESH_WNODE', msgNumber 29/0x1d
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.REPARENT_WNODE_REQ', msgNumber 30/0x1e
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.ROAD', msgNumber 31/0x1f
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.FREE_ROAD', msgNumber 32/0x20
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SETWNODE_REQ', msgNumber 33/0x21
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SET_AMBIENT', msgNumber 34/0x22
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SOUND', msgNumber 35/0x23
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SPAWNED', msgNumber 36/0x24
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SPAWN_REQ', msgNumber 37/0x25
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SYS_CHAT', msgNumber 38/0x26
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.TARGETED_PROPERTY', msgNumber 39/0x27
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.UPDATEWNODE', msgNumber 40/0x28
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.UPDATEWNODE_REQ', msgNumber 41/0x29
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.UPDATE_OBJECT', msgNumber 42/0x2a
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.WNODECORRECT', msgNumber 43/0x2b
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.P2P_EXTENSION', msgNumber 44/0x2c
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.PERCEPTION_INFO', msgNumber 45/0x2d
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.PERCEPTION', msgNumber 46/0x2e
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.HOST_INSTANCE', msgNumber 47/0x2f
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SET_PERSISTENCE', msgNumber 48/0x30
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SET_SUBPERSISTENCE', msgNumber 49/0x31
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.LOAD_OBJECT', msgNumber 50/0x32
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.LOAD_SUBOBJECT', msgNumber 51/0x33
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.LOAD_OBJECT_DATA', msgNumber 52/0x34
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SAVE_OBJECT_DATA', msgNumber 53/0x35
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SAVE_OBJECT', msgNumber 54/0x36
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SAVE_SUBOBJECT', msgNumber 55/0x37
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GENERATE_OBJECT', msgNumber 56/0x38
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GENERATE_SUB_OBJECT', msgNumber 57/0x39
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.SUB_OBJECT_DEPS_READY', msgNumber 58/0x3a
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.REGISTER_TEMPLATE', msgNumber 59/0x3b
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GENERATE_STRUCTURE', msgNumber 60/0x3c
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GET_TEMPLATE', msgNumber 61/0x3d
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.GET_TEMPLATE_NAMES', msgNumber 62/0x3e
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.UNLOAD_OBJECT', msgNumber 63/0x3f
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.UNLOAD_SUBOBJECT', msgNumber 64/0x40
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DELETE_OBJECT', msgNumber 65/0x41
DEBUG [2008-07-15 08:20:44,593] main                 Adding msg type 'mv.DELETE_SUBOBJECT', msgNumber 66/0x42
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.FIX_WNODE_REQ', msgNumber 67/0x43
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.ADD_ITEM', msgNumber 68/0x44
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.CREATE_INV', msgNumber 69/0x45
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.INV_UPDATE', msgNumber 70/0x46
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.ACTIVATE', msgNumber 71/0x47
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.LOOTALL', msgNumber 72/0x48
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.INV_FIND', msgNumber 73/0x49
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.INV_REMOVE', msgNumber 74/0x4a
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.DESTROY_ITEM', msgNumber 75/0x4b
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.NOTIFY_REACTION_RADIUS', msgNumber 76/0x4c
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.DUMP_ALL_THREAD_STACKS', msgNumber 77/0x4d
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.GET_PROPERTY', msgNumber 78/0x4e
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.PLUGIN_STATE', msgNumber 79/0x4f
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.SET_PROPERTY', msgNumber 80/0x50
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.SET_PROPERTY_NONBLOCK', msgNumber 81/0x51
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRANSFER_OBJECT', msgNumber 82/0x52
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.COMMAND', msgNumber 83/0x53
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.EVENT', msgNumber 84/0x54
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.REQ_QUEST_INFO', msgNumber 85/0x55
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.REQ_CONCLUDE_QUEST', msgNumber 86/0x56
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.QUEST_INFO', msgNumber 87/0x57
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.GET_QUEST_STATUS', msgNumber 88/0x58
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.QUEST_RESP', msgNumber 89/0x59
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.NEW_QUESTSTATE', msgNumber 90/0x5a
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.CONCLUDE_QUEST', msgNumber 91/0x5b
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.QUEST_STATE_STATUS_CHANGE', msgNumber 92/0x5c
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.QUEST_LOG_INFO', msgNumber 93/0x5d
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.QUEST_STATE_INFO', msgNumber 94/0x5e
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.REMOVE_QUEST_RESP', msgNumber 95/0x5f
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.REQ_RESET_QUESTS', msgNumber 96/0x60
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.MARS_INV_FIND', msgNumber 97/0x61
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRADE_START_REQ', msgNumber 98/0x62
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRADE_START', msgNumber 99/0x63
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRADE_COMPLETE', msgNumber 100/0x64
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRADE_OFFER_REQ', msgNumber 101/0x65
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRADE_OFFER_UPDATE', msgNumber 102/0x66
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.AUTO_ATTACK', msgNumber 103/0x67
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.START_ABILITY', msgNumber 104/0x68
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.COOLDOWN', msgNumber 105/0x69
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.ABILITY_PROGRESS', msgNumber 106/0x6a
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.DAMAGE', msgNumber 107/0x6b
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.RELEASE_OBJECT', msgNumber 108/0x6c
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.ABILITY_UPDATE', msgNumber 109/0x6d
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.ADD_SKILL', msgNumber 110/0x6e
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.TRAINING_FAILED', msgNumber 111/0x6f
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.COMBAT_ABILITY_MISSED', msgNumber 112/0x70
DEBUG [2008-07-15 08:20:44,609] main                 Adding msg type 'mv.SKILL_UPDATE', msgNumber 113/0x71
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.INVOKE_EFFECT', msgNumber 114/0x72
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.REGISTER_INSTANCE_TEMPLATE', msgNumber 115/0x73
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.CREATE_INSTANCE', msgNumber 116/0x74
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.GET_INSTANCE_INFO', msgNumber 117/0x75
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.INSTANCE_ENTRY_REQ', msgNumber 118/0x76
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.GET_MARKER', msgNumber 119/0x77
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.GET_REGION', msgNumber 120/0x78
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.LOAD_INSTANCE', msgNumber 121/0x79
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.UNLOAD_INSTANCE', msgNumber 122/0x7a
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.DELETE_INSTANCE', msgNumber 123/0x7b
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.LOAD_INSTANCE_CONTENT', msgNumber 124/0x7c
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.INSTANCE_UNLOADED', msgNumber 125/0x7d
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.INSTANCE_DELETED', msgNumber 126/0x7e
DEBUG [2008-07-15 08:20:44,625] main                 Adding msg type 'mv.CREATE_SPAWN_GEN', msgNumber 127/0x7f
DEBUG [2008-07-15 08:20:44,640] main                 Adding msg type 'mv.VOICE_PARMS', msgNumber 128/0x80
DEBUG [2008-07-15 08:20:44,640] main                 Adding msg type 'mv.SEARCH', msgNumber 129/0x81
DEBUG [2008-07-15 08:20:44,671] main                 Adding msg type 'mv.REQ_TRAINER_INFO', msgNumber 130/0x82
DEBUG [2008-07-15 08:20:44,671] main                 Adding msg type 'mv.REQ_SKILL_TRAINING', msgNumber 131/0x83
DEBUG [2008-07-15 08:20:44,671] main                 Adding msg type 'mv.TRAINING_INFO', msgNumber 132/0x84
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.STAT_XP_UPDATE', msgNumber 133/0x85
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.HANDLE_EXP', msgNumber 134/0x86
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_INVITE', msgNumber 135/0x87
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_INVITE_RESPONSE', msgNumber 136/0x88
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_REMOVE_MEMBER', msgNumber 137/0x89
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_CHAT', msgNumber 138/0x8a
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.REQUEST_GROUP_INFO', msgNumber 139/0x8b
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_INFO_RESPONSE', msgNumber 140/0x8c
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_SET_ALLOWED_SPEAKER', msgNumber 141/0x8d
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_MUTE_VOICE_CHAT', msgNumber 142/0x8e
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.GROUP_VOICE_CHAT_STATUS', msgNumber 143/0x8f
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.VOICECLIENT', msgNumber 144/0x90
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.VOICE_MEMBER_ADDED', msgNumber 145/0x91
DEBUG [2008-07-15 08:20:44,687] main                 Adding msg type 'mv.VOICE_MEMBER_REMOVED', msgNumber 146/0x92
DEBUG [2008-07-15 08:20:44,687] main                 Engine, script completed
DEBUG [2008-07-15 08:20:44,687] main                 Engine, Engine: reading in script: ..\config\sampleworld\worldmessages.py
DEBUG [2008-07-15 08:20:44,687] main                 Engine, Executing init script file: ..\config\sampleworld\worldmessages.py
DEBUG [2008-07-15 08:20:44,687] main                 runPYFile: file=..\config\sampleworld\worldmessages.py
DEBUG [2008-07-15 08:20:44,687] main                 Engine, script completed
DEBUG [2008-07-15 08:20:44,703] main                 Initializing Database with driver com.mysql.jdbc.Driver
DEBUG [2008-07-15 08:20:44,703] main                 classpath = ..\dist\lib\multiverse.jar;..\dist\lib\mars.jar;..\dist\lib\sampleworld.jar;..\other\rhino1_5R5\js.jar;..\other\java-getopt-1.0.11.jar;..\other\jython.jar;g:\mysql-connector-java-3.1.14\mysql-connector-java-3.1.14-bin.jar;..\other\log4j-1.2.14.jar;..\other\bcel-5.2.jar
DEBUG [2008-07-15 08:20:44,703] main                 com.mysql.jdbc.Driver driver loaded
DEBUG [2008-07-15 08:20:44,703] main                 Database: starting keepalive
DEBUG [2008-07-15 08:20:44,703] main                 Engine, connecting to localhostuser = root passwd=test
DEBUG [2008-07-15 08:20:44,703] main                 *** url = jdbc:mysql://localhost/multiverse username = root password = test
INFO  [2008-07-15 08:20:44,921] main                 connected to database
DEBUG [2008-07-15 08:20:44,921] main                 Reading namespaces from the database
DEBUG [2008-07-15 08:20:44,921] main                 Read 15 namespaces from the database
INFO  [2008-07-15 08:20:44,921] main                 encached the mapping of namespace strings to ints
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.AGENT_HELLO', msgNumber 5000/0x1388
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.HELLO_RESPONSE', msgNumber 5001/0x1389
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.ALLOC_NAME', msgNumber 5002/0x138a
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.NEW_AGENT', msgNumber 5003/0x138b
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.AGENT_STATE', msgNumber 5004/0x138c
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.ADVERTISE', msgNumber 5005/0x138d
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.SUBSCRIBE', msgNumber 5006/0x138e
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.UNSUBSCRIBE', msgNumber 5007/0x138f
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.FILTER_UPDATE', msgNumber 5008/0x1390
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.AWAIT_PLUGIN_DEPENDENTS', msgNumber 5009/0x1391
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.PLUGIN_AVAILABLE', msgNumber 5010/0x1392
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.RESPONSE', msgNumber 5011/0x1393
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.BOOLEAN_RESPONSE', msgNumber 5012/0x1394
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.LONG_RESPONSE', msgNumber 5013/0x1395
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.INT_RESPONSE', msgNumber 5014/0x1396
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.STRING_RESPONSE', msgNumber 5015/0x1397
DEBUG [2008-07-15 08:20:44,937] main                 Adding msg type 'msgsys.ALLOC_NAME_RESPONSE', msgNumber 5016/0x1398
DEBUG [2008-07-15 08:20:45,000] main                 Sending msgsys.ADVERTISE id=1 to mobserver
DEBUG [2008-07-15 08:20:45,000] SelfMessage          SelfMessageHandler.handle pos=174
DEBUG [2008-07-15 08:20:45,015] SelfMessage          handleSelfMessage id=1 type=msgsys.ADVERTISE class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,015] SelfMessage          [mobserver,1] handleAdvertise: Adding 37 and removing 0
DEBUG [2008-07-15 08:20:45,015] SelfMessage          sendResponse to mobserver,1 type=msgsys.RESPONSE id=2 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,015] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:45,015] SelfMessage          handleSelfMessage id=2 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,031] main                 TcpServer, bound to port: 4690
DEBUG [2008-07-15 08:20:45,031] main                 MessageAgent: connected to domain server java.nio.channels.SocketChannel[connected local=/127.0.0.1:4708 remote=localhost/127.0.0.1:20374]
INFO  [2008-07-15 08:20:45,125] main                 My agent-id: 8
DEBUG [2008-07-15 08:20:45,125] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=55 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,125] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=53 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,125] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=49 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,125] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=48 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,140] AgentConnection-1    MessageAgent: connected to agent java.nio.channels.SocketChannel[connected local=/127.0.0.1:4986 remote=/127.0.0.1:4438]
DEBUG [2008-07-15 08:20:45,140] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=48 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,140] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=50 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,140] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=48 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,140] AgentConnection-3    MessageAgent: connected to agent java.nio.channels.SocketChannel[connected local=/127.0.0.1:1029 remote=/127.0.0.1:4464]
DEBUG [2008-07-15 08:20:45,140] AgentConnection-1    connect: Accepted connection from login_manager
DEBUG [2008-07-15 08:20:45,171] AgentConnection-3    connect: Accepted connection from instance
DEBUG [2008-07-15 08:20:45,171] MessageIO            handleMessageData from login_manager,9 type=msgsys.ADVERTISE len=46 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,171] MessageIO            [login_manager,9] handleAdvertise: Adding 6 and removing 0
DEBUG [2008-07-15 08:20:45,171] AgentConnection-2    MessageAgent: connected to agent java.nio.channels.SocketChannel[connected local=/127.0.0.1:1053 remote=/127.0.0.1:4393]
DEBUG [2008-07-15 08:20:45,171] MessageIO            handleMessageData from instance,9 type=msgsys.ADVERTISE len=66 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,171] MessageIO            [instance,9] handleAdvertise: Adding 11 and removing 0
DEBUG [2008-07-15 08:20:45,218] AgentConnection-5    received connect: Accepting connection from wmgr_1
DEBUG [2008-07-15 08:20:45,234] AgentConnection-6    received connect: Accepting connection from objmgr
DEBUG [2008-07-15 08:20:45,234] AgentConnection-4    received connect: Accepting connection from voiceserver
DEBUG [2008-07-15 08:20:45,234] AgentConnection-1    received connect: Accepting connection from proxy_1
DEBUG [2008-07-15 08:20:45,234] MessageIO            handleMessageData from wmgr_1,9 type=msgsys.ADVERTISE len=138 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,234] MessageIO            [wmgr_1,9] handleAdvertise: Adding 29 and removing 0
DEBUG [2008-07-15 08:20:45,250] MessageIO            handleMessageData from objmgr,9 type=msgsys.ADVERTISE len=158 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,250] MessageIO            [objmgr,9] handleAdvertise: Adding 34 and removing 0
DEBUG [2008-07-15 08:20:45,250] MessageIO            handleMessageData from voiceserver,9 type=msgsys.ADVERTISE len=42 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,250] MessageIO            [voiceserver,9] handleAdvertise: Adding 5 and removing 0
DEBUG [2008-07-15 08:20:45,250] MessageIO            handleMessageData from proxy_1,9 type=msgsys.ADVERTISE len=298 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,250] MessageIO            [proxy_1,9] handleAdvertise: Adding 69 and removing 0
DEBUG [2008-07-15 08:20:45,250] AgentConnection-2    connect: Accepted connection from combat
DEBUG [2008-07-15 08:20:45,250] MessageIO            handleMessageData from combat,9 type=msgsys.ADVERTISE len=138 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,250] MessageIO            [combat,9] handleAdvertise: Adding 29 and removing 0
DEBUG [2008-07-15 08:20:45,500] MessageIO            handleMessageData from DomainServer,0 type=msgsys.NEW_AGENT len=49 class=multiverse.msgsys.NewAgentMessage
DEBUG [2008-07-15 08:20:45,531] AgentConnection-2    received connect: Accepting connection from startup
DEBUG [2008-07-15 08:20:45,640] MessageIO            handleMessageData from startup,6 type=msgsys.ADVERTISE len=26 class=multiverse.msgsys.AdvertiseMessage
DEBUG [2008-07-15 08:20:45,640] MessageIO            [startup,6] handleAdvertise: Adding 1 and removing 0
DEBUG [2008-07-15 08:20:45,640] main                 Engine, Executing script file: ..\config\common\global_props.py
DEBUG [2008-07-15 08:20:45,640] main                 runPYFile: file=..\config\common\global_props.py
DEBUG [2008-07-15 08:20:45,640] main                 Engine, script completed
DEBUG [2008-07-15 08:20:45,640] main                 Engine, Executing script file: ..\config\sampleworld\global_props.py
DEBUG [2008-07-15 08:20:45,640] main                 runPYFile: file=..\config\sampleworld\global_props.py
DEBUG [2008-07-15 08:20:45,640] main                 Engine, script completed
DEBUG [2008-07-15 08:20:45,640] main                 Engine, Executing script file: ..\config\common\mobserver_init.py
DEBUG [2008-07-15 08:20:45,640] main                 runPYFile: file=..\config\common\mobserver_init.py
DEBUG [2008-07-15 08:20:45,656] main                 Engine, script completed
DEBUG [2008-07-15 08:20:45,656] main                 Engine, Executing script file: ..\config\sampleworld\mobserver_init.py
DEBUG [2008-07-15 08:20:45,656] main                 runPYFile: file=..\config\sampleworld\mobserver_init.py
DEBUG [2008-07-15 08:20:45,671] main                 Engine, script completed
DEBUG [2008-07-15 08:20:45,671] main                 Engine, Executing script file: ..\config\common\questplugin.py
DEBUG [2008-07-15 08:20:45,671] main                 runPYFile: file=..\config\common\questplugin.py
DEBUG [2008-07-15 08:20:45,671] main                 questplugin.py: starting
DEBUG [2008-07-15 08:20:45,671] main                 Engine, Engine.registerPlugin: loading class multiverse.mars.plugins.QuestPlugin
DEBUG [2008-07-15 08:20:45,671] main                 Engine, Engine.registerPlugin: registering Quest
DEBUG [2008-07-15 08:20:45,671] main                 EnginePlugin.activate: plugin=Quest
DEBUG [2008-07-15 08:20:45,671] main                 subscribe [MessageTypeFilter types=mv.DUMP_ALL_THREAD_STACKS,] matching agents 1
DEBUG [2008-07-15 08:20:45,671] main                 Sending msgsys.SUBSCRIBE id=11 to proxy_1
DEBUG [2008-07-15 08:20:45,750] MessageIO            handleMessageData from proxy_1,13 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,765] main                 EnginePlugin.activate: plugin=Quest, created createSubscription for dumpAllStacks
DEBUG [2008-07-15 08:20:45,765] main                 EnginePlugin.activate: registered DumpAllStacksMessageHook
DEBUG [2008-07-15 08:20:45,765] main                 Sending msgsys.AWAIT_PLUGIN_DEPENDENTS id=12 to DomainServer
DEBUG [2008-07-15 08:20:45,937] MessageIO            handleMessageData from DomainServer,0 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,937] main                 subscribe [MessageTypeFilter types=mv.UPDATE_OBJECT,mv.REQ_RESET_QUESTS,] matching agents 1
DEBUG [2008-07-15 08:20:45,937] main                 Sending msgsys.SUBSCRIBE id=13 to proxy_1
DEBUG [2008-07-15 08:20:45,937] MessageIO            handleMessageData from proxy_1,17 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,937] main                 subscribe [MessageTypeFilter types=mv.NEW_QUESTSTATE,mv.GET_QUEST_STATUS,] matching agents 1
DEBUG [2008-07-15 08:20:45,937] main                 Sending msgsys.SUBSCRIBE id=14 to mobserver
DEBUG [2008-07-15 08:20:45,937] SelfMessage          SelfMessageHandler.handle pos=46
DEBUG [2008-07-15 08:20:45,937] SelfMessage          handleSelfMessage id=14 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,937] SelfMessage          [mobserver,14] Got subscription subId=3 filter [MessageTypeFilter types=mv.NEW_QUESTSTATE,mv.GET_QUEST_STATUS,]
DEBUG [2008-07-15 08:20:45,937] SelfMessage          sendResponse to mobserver,14 type=msgsys.RESPONSE id=15 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,937] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:45,937] SelfMessage          handleSelfMessage id=15 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,937] main                 QuestPlugin, QuestPlugin activated
DEBUG [2008-07-15 08:20:45,937] main                 EnginePlugin.activate: plugin=Quest, onActivate complete, calling activateHooks
DEBUG [2008-07-15 08:20:45,937] main                 EnginePlugin.activate: plugin=Quest, activate hooks called
DEBUG [2008-07-15 08:20:45,937] main                 Sending msgsys.PLUGIN_AVAILABLE id=16 to DomainServer
DEBUG [2008-07-15 08:20:45,937] main                 questplugin.py: done
DEBUG [2008-07-15 08:20:45,937] main                 Engine, script completed
DEBUG [2008-07-15 08:20:45,937] main                 Engine, Executing script file: ..\config\common\trainerplugin.py
DEBUG [2008-07-15 08:20:45,937] main                 runPYFile: file=..\config\common\trainerplugin.py
DEBUG [2008-07-15 08:20:45,937] main                 trainerplugin.py: starting
DEBUG [2008-07-15 08:20:45,937] main                 Engine, Engine.registerPlugin: loading class multiverse.mars.plugins.TrainerPlugin
DEBUG [2008-07-15 08:20:45,953] main                 Engine, Engine.registerPlugin: registering Trainer
DEBUG [2008-07-15 08:20:45,953] main                 EnginePlugin.activate: plugin=Trainer
DEBUG [2008-07-15 08:20:45,953] main                 Sending msgsys.AWAIT_PLUGIN_DEPENDENTS id=17 to DomainServer
DEBUG [2008-07-15 08:20:45,953] MessageIO            handleMessageData from wmgr_1,15 type=msgsys.SUBSCRIBE len=68 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,953] MessageIO            [wmgr_1,15] Got subscription subId=3 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.wmgr,NS.wminstance]
DEBUG [2008-07-15 08:20:45,953] MessageIO            sendResponse to wmgr_1,15 type=msgsys.RESPONSE id=18 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,953] MessageIO            handleMessageData from wmgr_1,16 type=msgsys.SUBSCRIBE len=48 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,953] MessageIO            [wmgr_1,16] Got subscription subId=4 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.wmgr,NS.wminstance]
DEBUG [2008-07-15 08:20:45,953] MessageIO            sendResponse to wmgr_1,16 type=msgsys.RESPONSE id=19 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,968] MessageIO            handleMessageData from wmgr_1,19 type=msgsys.SUBSCRIBE len=60 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,968] MessageIO            [wmgr_1,19] Got subscription subId=7 filter [PerceptionFilter types=mv.REPARENT_WNODE_REQ,mv.REFRESH_WNODE,mv.COM_REQ,mv.UPDATE_OBJECT,mv.UPDATEWNODE_REQ,mv.ORIENT_REQ,mv.MODIFY_DC,mv.MOB_PATH_REQ, targets=]
DEBUG [2008-07-15 08:20:45,968] MessageIO            sendResponse to wmgr_1,19 type=msgsys.RESPONSE id=20 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,968] MessageIO            handleMessageData from wmgr_1,20 type=msgsys.SUBSCRIBE len=54 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,968] MessageIO            [wmgr_1,20] Got subscription subId=8 filter [PerceptionFilter types=mv.SETWNODE_REQ,mv.DC_REQ,mv.GETWNODE_REQ,mv.SPAWN_REQ,mv.DESPAWN_REQ,mv.OBJINFO_REQ, targets=]
DEBUG [2008-07-15 08:20:45,968] MessageIO            sendResponse to wmgr_1,20 type=msgsys.RESPONSE id=21 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,984] MessageIO            handleMessageData from wmgr_1,21 type=msgsys.SUBSCRIBE len=51 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,984] MessageIO            [wmgr_1,21] Got subscription subId=9 filter [PerceptionFilter types=mv.REFRESH_WNODE,mv.UPDATE_OBJECT,mv.UPDATEWNODE_REQ,mv.ORIENT_REQ,mv.MODIFY_DC, targets=]
DEBUG [2008-07-15 08:20:45,984] MessageIO            sendResponse to wmgr_1,21 type=msgsys.RESPONSE id=22 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:45,984] MessageIO            handleMessageData from wmgr_1,22 type=msgsys.SUBSCRIBE len=51 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:45,984] MessageIO            [wmgr_1,22] Got subscription subId=10 filter [PerceptionFilter types=mv.SETWNODE_REQ,mv.DC_REQ,mv.GETWNODE_REQ,mv.DESPAWN_REQ,mv.OBJINFO_REQ, targets=]
DEBUG [2008-07-15 08:20:45,984] MessageIO            sendResponse to wmgr_1,22 type=msgsys.RESPONSE id=23 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,000] MessageIO            handleMessageData from DomainServer,0 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,000] main                 subscribe [MessageTypeFilter types=mv.REQ_SKILL_TRAINING,mv.REQ_TRAINER_INFO,] matching agents 2
DEBUG [2008-07-15 08:20:46,000] main                 Sending msgsys.SUBSCRIBE id=24 to mobserver
DEBUG [2008-07-15 08:20:46,000] main                 Sending msgsys.SUBSCRIBE id=24 to proxy_1
DEBUG [2008-07-15 08:20:46,000] SelfMessage          SelfMessageHandler.handle pos=46
DEBUG [2008-07-15 08:20:46,000] SelfMessage          handleSelfMessage id=24 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,000] SelfMessage          [mobserver,24] Got subscription subId=4 filter [MessageTypeFilter types=mv.REQ_SKILL_TRAINING,mv.REQ_TRAINER_INFO,]
DEBUG [2008-07-15 08:20:46,000] MessageIO            handleMessageData from proxy_1,24 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          sendResponse to mobserver,24 type=msgsys.RESPONSE id=25 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:46,015] SelfMessage          handleSelfMessage id=25 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] main                 EnginePlugin.registerPluginNamespaces: namespaces [Namespace NS.trainer:13]
DEBUG [2008-07-15 08:20:46,015] main                 subscribe [NamespaceFilter types=mv.GENERATE_SUB_OBJECT,mv.LOAD_SUBOBJECT, namespaces=NS.trainer] matching agents 1
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=26 to objmgr
DEBUG [2008-07-15 08:20:46,015] MessageIO            handleMessageData from objmgr,18 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] main                 subscribe [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.trainer] matching agents 4
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=27 to mobserver
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=27 to objmgr
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=27 to proxy_1
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=27 to combat
DEBUG [2008-07-15 08:20:46,015] SelfMessage          SelfMessageHandler.handle pos=70
DEBUG [2008-07-15 08:20:46,015] SelfMessage          handleSelfMessage id=27 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          [mobserver,27] Got subscription subId=6 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SAVE_SUBOBJECT,mv.SET_PROPERTY,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.trainer]
DEBUG [2008-07-15 08:20:46,015] SelfMessage          sendResponse to mobserver,27 type=msgsys.RESPONSE id=28 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:46,015] SelfMessage          handleSelfMessage id=28 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] MessageIO            handleMessageData from combat,17 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] MessageIO            handleMessageData from proxy_1,25 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] MessageIO            handleMessageData from objmgr,19 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] main                 subscribe [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.trainer] matching agents 4
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=29 to mobserver
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=29 to objmgr
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=29 to proxy_1
DEBUG [2008-07-15 08:20:46,015] main                 Sending msgsys.SUBSCRIBE id=29 to combat
DEBUG [2008-07-15 08:20:46,015] SelfMessage          SelfMessageHandler.handle pos=50
DEBUG [2008-07-15 08:20:46,015] SelfMessage          handleSelfMessage id=29 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          [mobserver,29] Got subscription subId=7 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.trainer]
DEBUG [2008-07-15 08:20:46,015] SelfMessage          sendResponse to mobserver,29 type=msgsys.RESPONSE id=30 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,015] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:46,031] MessageIO            handleMessageData from objmgr,20 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,031] SelfMessage          handleSelfMessage id=30 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,031] MessageIO            handleMessageData from proxy_1,26 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,031] MessageIO            handleMessageData from combat,18 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,031] main                 TrainerPlugin, TrainerPlugin activated
DEBUG [2008-07-15 08:20:46,031] main                 EnginePlugin.activate: plugin=Trainer, onActivate complete, calling activateHooks
DEBUG [2008-07-15 08:20:46,031] main                 EnginePlugin.activate: plugin=Trainer, activate hooks called
DEBUG [2008-07-15 08:20:46,031] main                 Sending msgsys.PLUGIN_AVAILABLE id=31 to DomainServer
DEBUG [2008-07-15 08:20:46,031] main                 trainerplugin.py: done
DEBUG [2008-07-15 08:20:46,031] main                 Engine, script completed
DEBUG [2008-07-15 08:20:46,031] main                 Engine, Executing script file: ..\config\common\mobserver.py
DEBUG [2008-07-15 08:20:46,031] main                 runPYFile: file=..\config\common\mobserver.py
DEBUG [2008-07-15 08:20:46,031] main                 Engine, Engine.registerPlugin: loading class multiverse.server.plugins.MobManagerPlugin
DEBUG [2008-07-15 08:20:46,031] main                 Engine, Engine.registerPlugin: registering MobManager
DEBUG [2008-07-15 08:20:46,031] main                 EnginePlugin.activate: plugin=MobManager
DEBUG [2008-07-15 08:20:46,031] main                 Sending msgsys.AWAIT_PLUGIN_DEPENDENTS id=32 to DomainServer
DEBUG [2008-07-15 08:20:46,140] MessageIO            handleMessageData from objmgr,23 type=msgsys.SUBSCRIBE len=98 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,140] MessageIO            [objmgr,23] Got subscription subId=2 filter [MessageTypeFilter types=mv.GENERATE_OBJECT,mv.DELETE_OBJECT,mv.SAVE_OBJECT,mv.UNLOAD_INSTANCE,mv.GENERATE_STRUCTURE,mv.GET_TEMPLATE,mv.SET_PERSISTENCE,mv.DELETE_INSTANCE,mv.UNLOAD_OBJECT,mv.REGISTER_TEMPLATE,mv.SAVE_OBJECT_DATA,mv.LOAD_INSTANCE_CONTENT,mv.FIX_WNODE_REQ,mv.LOAD_OBJECT,mv.LOAD_OBJECT_DATA,mv.GET_TEMPLATE_NAMES,]
DEBUG [2008-07-15 08:20:46,140] MessageIO            sendResponse to objmgr,23 type=msgsys.RESPONSE id=33 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,156] MessageIO            handleMessageData from objmgr,27 type=msgsys.SUBSCRIBE len=66 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,156] MessageIO            [objmgr,27] Got subscription subId=4 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.master]
DEBUG [2008-07-15 08:20:46,156] MessageIO            sendResponse to objmgr,27 type=msgsys.RESPONSE id=34 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,156] MessageIO            handleMessageData from objmgr,29 type=msgsys.SUBSCRIBE len=46 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,156] MessageIO            [objmgr,29] Got subscription subId=5 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.master]
DEBUG [2008-07-15 08:20:46,156] MessageIO            sendResponse to objmgr,29 type=msgsys.RESPONSE id=35 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,187] MessageIO            handleMessageData from combat,27 type=msgsys.SUBSCRIBE len=66 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,187] MessageIO            [combat,27] Got subscription subId=4 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.classability]
DEBUG [2008-07-15 08:20:46,187] MessageIO            sendResponse to combat,27 type=msgsys.RESPONSE id=36 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,187] MessageIO            handleMessageData from combat,29 type=msgsys.SUBSCRIBE len=46 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,187] MessageIO            [combat,29] Got subscription subId=5 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.classability]
DEBUG [2008-07-15 08:20:46,187] MessageIO            sendResponse to combat,29 type=msgsys.RESPONSE id=37 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,250] MessageIO            handleMessageData from combat,33 type=msgsys.SUBSCRIBE len=62 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,250] MessageIO            [combat,33] Got subscription subId=6 filter [MessageTypeFilter types=mv.RELEASE_OBJECT,mv.PROPERTY,mv.UPDATE_OBJECT,mv.ADD_SKILL,mv.DESPAWNED,mv.START_ABILITY,mv.AUTO_ATTACK,]
DEBUG [2008-07-15 08:20:46,250] MessageIO            sendResponse to combat,33 type=msgsys.RESPONSE id=38 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,265] MessageIO            handleMessageData from combat,36 type=msgsys.SUBSCRIBE len=66 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,265] MessageIO            [combat,36] Got subscription subId=8 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.combat]
DEBUG [2008-07-15 08:20:46,265] MessageIO            sendResponse to combat,36 type=msgsys.RESPONSE id=39 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,265] MessageIO            handleMessageData from combat,38 type=msgsys.SUBSCRIBE len=46 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,265] MessageIO            [combat,38] Got subscription subId=9 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.combat]
DEBUG [2008-07-15 08:20:46,265] MessageIO            sendResponse to combat,38 type=msgsys.RESPONSE id=40 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            handleMessageData from objmgr,104 type=msgsys.SUBSCRIBE len=54 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            [objmgr,104] Got subscription subId=7 filter [MessageTypeFilter types=mv.INV_FIND,mv.ADD_ITEM,mv.LOOTALL,mv.CREATE_INV,mv.INV_REMOVE,]
DEBUG [2008-07-15 08:20:46,359] MessageIO            sendResponse to objmgr,104 type=msgsys.RESPONSE id=41 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            handleMessageData from objmgr,108 type=msgsys.SUBSCRIBE len=68 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            [objmgr,108] Got subscription subId=9 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SET_PROPERTY,mv.SAVE_SUBOBJECT,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.inv,NS.item]
DEBUG [2008-07-15 08:20:46,359] MessageIO            sendResponse to objmgr,108 type=msgsys.RESPONSE id=42 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            handleMessageData from objmgr,110 type=msgsys.SUBSCRIBE len=48 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,359] MessageIO            [objmgr,110] Got subscription subId=10 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.inv,NS.item]
DEBUG [2008-07-15 08:20:46,359] MessageIO            sendResponse to objmgr,110 type=msgsys.RESPONSE id=43 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,375] MessageIO            handleMessageData from DomainServer,0 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,375] main                 MobManagerPlugin, onActivate()
DEBUG [2008-07-15 08:20:46,375] main                 subscribe [MessageTypeFilter types=mv.INSTANCE_DELETED,mv.CREATE_SPAWN_GEN,mv.INSTANCE_UNLOADED,] matching agents 2
DEBUG [2008-07-15 08:20:46,375] main                 Sending msgsys.SUBSCRIBE id=44 to instance
DEBUG [2008-07-15 08:20:46,375] main                 Sending msgsys.SUBSCRIBE id=44 to objmgr
DEBUG [2008-07-15 08:20:46,375] MessageIO            handleMessageData from instance,17 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,375] MessageIO            handleMessageData from objmgr,115 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,375] main                 EnginePlugin.activate: plugin=MobManager, onActivate complete, calling activateHooks
DEBUG [2008-07-15 08:20:46,375] main                 EnginePlugin.activate: plugin=MobManager, activate hooks called
DEBUG [2008-07-15 08:20:46,375] main                 Sending msgsys.PLUGIN_AVAILABLE id=45 to DomainServer
DEBUG [2008-07-15 08:20:46,375] main                 Engine, script completed
DEBUG [2008-07-15 08:20:46,375] main                 Engine, Executing script file: ..\config\sampleworld\mobserver.py
DEBUG [2008-07-15 08:20:46,375] main                 runPYFile: file=..\config\sampleworld\mobserver.py
DEBUG [2008-07-15 08:20:46,390] MessageIO            handleMessageData from instance,18 type=msgsys.SUBSCRIBE len=58 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,390] MessageIO            [instance,18] Got subscription subId=2 filter [MessageTypeFilter types=mv.GET_INSTANCE_INFO,mv.REGISTER_INSTANCE_TEMPLATE,mv.CREATE_INSTANCE,mv.GET_MARKER,mv.GET_REGION,mv.LOAD_INSTANCE,]
DEBUG [2008-07-15 08:20:46,390] MessageIO            sendResponse to instance,18 type=msgsys.RESPONSE id=46 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,406] MessageIO            handleMessageData from instance,22 type=msgsys.SUBSCRIBE len=66 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,406] MessageIO            [instance,22] Got subscription subId=5 filter [NamespaceFilter types=mv.GET_PROPERTY,mv.UNLOAD_SUBOBJECT,mv.SAVE_SUBOBJECT,mv.SET_PROPERTY,mv.SET_SUBPERSISTENCE,mv.DELETE_SUBOBJECT, namespaces=NS.instance]
DEBUG [2008-07-15 08:20:46,406] MessageIO            sendResponse to instance,22 type=msgsys.RESPONSE id=47 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,406] MessageIO            handleMessageData from instance,23 type=msgsys.SUBSCRIBE len=46 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,406] MessageIO            [instance,23] Got subscription subId=6 filter [NamespaceFilter types=mv.SET_PROPERTY_NONBLOCK, namespaces=NS.instance]
DEBUG [2008-07-15 08:20:46,406] MessageIO            sendResponse to instance,23 type=msgsys.RESPONSE id=48 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,484] main                 OIDManager.getNewChunk: begin=5001, end=5100
DEBUG [2008-07-15 08:20:46,500] main                 done with mobserver.py
DEBUG [2008-07-15 08:20:46,500] main                 Engine, script completed
DEBUG [2008-07-15 08:20:46,500] main                 Engine, Executing script file: ..\config\common\extensions_mobserver.py
DEBUG [2008-07-15 08:20:46,500] main                 runPYFile: file=..\config\common\extensions_mobserver.py
WARN  [2008-07-15 08:20:46,515] main                 ScriptManager.runPYFile: file not found: ..\config\common\extensions_mobserver.py
DEBUG [2008-07-15 08:20:46,515] main                 Engine, script completed
DEBUG [2008-07-15 08:20:46,515] MessageIO            handleMessageData from proxy_1,56 type=msgsys.SUBSCRIBE len=38 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,515] MessageIO            [proxy_1,56] Got subscription subId=2 filter [MessageTypeFilter types=mv.SYS_CHAT,]
DEBUG [2008-07-15 08:20:46,515] MessageIO            sendResponse to proxy_1,56 type=msgsys.RESPONSE id=49 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,515] MessageIO            handleMessageData from proxy_1,58 type=msgsys.SUBSCRIBE len=143 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,515] MessageIO            [proxy_1,58] Got subscription subId=3 filter [PerceptionFilter types=mv.DETACH,mv.ANIMATION,mv.DAMAGE,mv.FREE_OBJECT,mv.QUEST_LOG_INFO,mv.TRAINING_FAILED,mv.MOB_PATH,mv.COMBAT_ABILITY_MISSED,mv.PROPERTY,mv.EXTENSION,mv.INVOKE_EFFECT,mv.QUEST_INFO,mv.WNODECORRECT,mv.INSTANCE_ENTRY_REQ,mv.PERCEPTION_INFO,mv.VOICE_PARMS,mv.ABILITY_UPDATE,mv.TRAINING_INFO,mv.TARGETED_PROPERTY,mv.TRADE_START,mv.INV_UPDATE,mv.ORIENT,mv.TRADE_OFFER_UPDATE,mv.ROAD,mv.P2P_EXTENSION,mv.COM,mv.REMOVE_QUEST_RESP,mv.NEW_DIRLIGHT,mv.FOG,mv.UPDATEWNODE,mv.DISPLAY_CONTEXT,mv.STAT_XP_UPDATE,mv.TRADE_COMPLETE,mv.SET_AMBIENT,mv.SOUND, targets=]
DEBUG [2008-07-15 08:20:46,515] MessageIO            sendResponse to proxy_1,58 type=msgsys.RESPONSE id=50 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,781] MessageIO            handleMessageData from wmgr_1,44 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,781] MessageIO            [wmgr_1,44] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,781] MessageIO            ADD SUBJECT 4902
DEBUG [2008-07-15 08:20:46,781] MessageIO            sendResponse to wmgr_1,44 type=msgsys.RESPONSE id=51 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,781] MessageIO            handleMessageData from wmgr_1,45 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,781] MessageIO            [wmgr_1,45] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,781] MessageIO            ADD SUBJECT 4902
DEBUG [2008-07-15 08:20:46,781] MessageIO            sendResponse to wmgr_1,45 type=msgsys.RESPONSE id=52 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,796] MessageIO            handleMessageData from wmgr_1,49 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,796] MessageIO            [wmgr_1,49] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,796] MessageIO            ADD SUBJECT 4903
DEBUG [2008-07-15 08:20:46,796] MessageIO            sendResponse to wmgr_1,49 type=msgsys.RESPONSE id=53 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,796] MessageIO            handleMessageData from wmgr_1,50 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,796] MessageIO            [wmgr_1,50] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,796] MessageIO            ADD SUBJECT 4903
DEBUG [2008-07-15 08:20:46,796] MessageIO            sendResponse to wmgr_1,50 type=msgsys.RESPONSE id=54 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,812] MessageIO            handleMessageData from wmgr_1,54 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,812] MessageIO            [wmgr_1,54] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,812] MessageIO            ADD SUBJECT 4904
DEBUG [2008-07-15 08:20:46,812] MessageIO            sendResponse to wmgr_1,54 type=msgsys.RESPONSE id=55 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,828] MessageIO            handleMessageData from wmgr_1,55 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,828] MessageIO            [wmgr_1,55] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,828] MessageIO            ADD SUBJECT 4904
DEBUG [2008-07-15 08:20:46,828] MessageIO            sendResponse to wmgr_1,55 type=msgsys.RESPONSE id=56 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,828] MessageIO            handleMessageData from wmgr_1,59 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,828] MessageIO            [wmgr_1,59] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,828] MessageIO            ADD SUBJECT 4905
DEBUG [2008-07-15 08:20:46,828] MessageIO            sendResponse to wmgr_1,59 type=msgsys.RESPONSE id=57 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,843] MessageIO            handleMessageData from wmgr_1,60 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,843] MessageIO            [wmgr_1,60] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,843] MessageIO            ADD SUBJECT 4905
DEBUG [2008-07-15 08:20:46,843] MessageIO            sendResponse to wmgr_1,60 type=msgsys.RESPONSE id=58 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,843] MessageIO            handleMessageData from wmgr_1,64 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,843] MessageIO            [wmgr_1,64] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,843] MessageIO            ADD SUBJECT 4906
DEBUG [2008-07-15 08:20:46,843] MessageIO            sendResponse to wmgr_1,64 type=msgsys.RESPONSE id=59 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,859] MessageIO            handleMessageData from wmgr_1,65 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,859] MessageIO            [wmgr_1,65] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,859] MessageIO            ADD SUBJECT 4906
DEBUG [2008-07-15 08:20:46,859] MessageIO            sendResponse to wmgr_1,65 type=msgsys.RESPONSE id=60 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,875] MessageIO            handleMessageData from wmgr_1,69 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,875] MessageIO            [wmgr_1,69] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,875] MessageIO            ADD SUBJECT 4907
DEBUG [2008-07-15 08:20:46,875] MessageIO            sendResponse to wmgr_1,69 type=msgsys.RESPONSE id=61 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,875] MessageIO            handleMessageData from wmgr_1,70 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,875] MessageIO            [wmgr_1,70] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,875] MessageIO            ADD SUBJECT 4907
DEBUG [2008-07-15 08:20:46,875] MessageIO            sendResponse to wmgr_1,70 type=msgsys.RESPONSE id=62 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,906] MessageIO            handleMessageData from wmgr_1,74 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,906] MessageIO            [wmgr_1,74] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,906] MessageIO            ADD SUBJECT 4908
DEBUG [2008-07-15 08:20:46,906] MessageIO            sendResponse to wmgr_1,74 type=msgsys.RESPONSE id=63 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,906] MessageIO            handleMessageData from wmgr_1,75 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,906] MessageIO            [wmgr_1,75] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,906] MessageIO            ADD SUBJECT 4908
DEBUG [2008-07-15 08:20:46,906] MessageIO            sendResponse to wmgr_1,75 type=msgsys.RESPONSE id=64 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,921] MessageIO            handleMessageData from wmgr_1,79 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,921] MessageIO            [wmgr_1,79] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,921] MessageIO            ADD SUBJECT 4909
DEBUG [2008-07-15 08:20:46,921] MessageIO            sendResponse to wmgr_1,79 type=msgsys.RESPONSE id=65 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,921] MessageIO            handleMessageData from wmgr_1,80 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,921] MessageIO            [wmgr_1,80] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,921] MessageIO            ADD SUBJECT 4909
DEBUG [2008-07-15 08:20:46,921] MessageIO            sendResponse to wmgr_1,80 type=msgsys.RESPONSE id=66 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,937] MessageIO            handleMessageData from instance,74 type=mv.CREATE_SPAWN_GEN len=208 class=multiverse.server.plugins.MobManagerClient$CreateSpawnGeneratorMessage
DEBUG [2008-07-15 08:20:46,937] MessageIO            deliverMessage rpc to multiverse.server.plugins.MobManagerPlugin@d40c4e
DEBUG [2008-07-15 08:20:46,937] MobManager-1         EnginePlugin.handleMessage: got msg id 74, matching 1 hooks for msgtype MessageType['mv.CREATE_SPAWN_GEN', 127]
DEBUG [2008-07-15 08:20:46,937] MobManager-1         WEObjFactory.makeObject: templateName=Brax instanceOid=4901 Behaviors=BaseBehavior,RadiusRoamBehavior propsize=2
DEBUG [2008-07-15 08:20:46,937] MobManager-1         MobManagerPlugin, createObject: template=Brax, point=(-120785,30854,-179363), calling into objectmanager to generate
DEBUG [2008-07-15 08:20:46,937] MobManager-1         MobManagerPlugin, createObject: template=Brax, override=[Template: name=null (ns=NS.wmgr, key=:loc, val=(-120785,30854,-179363))(ns=NS.wmgr, key=:followsTerrain, val=true)(ns=NS.wmgr, key=:orient, val=(0.0,0.0,0.0,1.0))(ns=NS.wmgr, key=:instance, val=4901), instanceOid=null calling into objectmanager to generate
DEBUG [2008-07-15 08:20:46,937] MobManager-1         sendRPC type=mv.GENERATE_OBJECT id=67 class=multiverse.server.plugins.ObjectManagerClient$GenerateObjectMessage
DEBUG [2008-07-15 08:20:46,937] MobManager-1         Sending mv.GENERATE_OBJECT id=67 to objmgr
DEBUG [2008-07-15 08:20:46,968] MessageIO            handleMessageData from wmgr_1,84 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,968] MessageIO            [wmgr_1,84] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:46,968] MessageIO            ADD SUBJECT 4910
DEBUG [2008-07-15 08:20:46,968] MessageIO            sendResponse to wmgr_1,84 type=msgsys.RESPONSE id=68 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,968] MessageIO            handleMessageData from wmgr_1,85 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:46,968] MessageIO            [wmgr_1,85] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:46,968] MessageIO            ADD SUBJECT 4910
DEBUG [2008-07-15 08:20:46,968] MessageIO            sendResponse to wmgr_1,85 type=msgsys.RESPONSE id=69 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,968] MessageIO            handleMessageData from objmgr,164 type=msgsys.LONG_RESPONSE len=33 class=multiverse.msgsys.LongResponseMessage
DEBUG [2008-07-15 08:20:46,968] MobManager-1         MobManagerPlugin, generated object oid=4910
DEBUG [2008-07-15 08:20:46,968] MobManager-1         sendRPC type=mv.GETWNODE_REQ id=70 class=multiverse.msgsys.SubjectMessage
DEBUG [2008-07-15 08:20:46,968] MobManager-1         Sending mv.GETWNODE_REQ id=70 to wmgr_1
DEBUG [2008-07-15 08:20:46,968] MessageIO            handleMessageData from wmgr_1,87 type=msgsys.RESPONSE len=78 class=multiverse.msgsys.GenericResponseMessage
DEBUG [2008-07-15 08:20:46,968] MobManager-1         WorldManagerClient.getWorldNode: oid=4910 wnode=BasicWorldNode[instanceOid=4901 loc=(-120785,30854,-179363) dir=[x=0.0,y=0.0,z=0.0] orient=(0.0,0.0,0.0,1.0)]
DEBUG [2008-07-15 08:20:46,968] MobManager-1         MobManagerPlugin, createObject: obj=[ObjectStub: oid=4910 node=[InterpolatedWorldNode: objHandle=null, instanceOid=4901, rawLoc=(-120785,30854,-179363), interpLoc=(-120785,30854,-179363), dir=[x=0.0,y=0.0,z=0.0], orient=(0.0,0.0,0.0,1.0)]]
DEBUG [2008-07-15 08:20:46,968] MobManager-1         PathState, clear: oid = 4910
DEBUG [2008-07-15 08:20:46,968] MobManager-1         subscribe [SubjectFilter oid=4910 types=mv.MOB_PATH_CORRECTION,mv.COMMAND,] matching agents 3
DEBUG [2008-07-15 08:20:46,968] MobManager-1         Sending msgsys.SUBSCRIBE id=71 to mobserver
DEBUG [2008-07-15 08:20:46,968] MobManager-1         Sending msgsys.SUBSCRIBE id=71 to wmgr_1
DEBUG [2008-07-15 08:20:46,968] MobManager-1         Sending msgsys.SUBSCRIBE id=71 to proxy_1
DEBUG [2008-07-15 08:20:46,968] SelfMessage          SelfMessageHandler.handle pos=54
DEBUG [2008-07-15 08:20:46,968] SelfMessage          handleSelfMessage id=71 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,968] SelfMessage          [mobserver,71] Got subscription subId=9 filter [SubjectFilter oid=4910 types=mv.MOB_PATH_CORRECTION,mv.COMMAND,]
DEBUG [2008-07-15 08:20:46,968] SelfMessage          sendResponse to mobserver,71 type=msgsys.RESPONSE id=72 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,968] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:46,968] SelfMessage          handleSelfMessage id=72 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from proxy_1,81 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,88 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         subscribe [SubjectFilter oid=4910 types=mv.EVENT,] matching agents 1
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.SUBSCRIBE id=73 to mobserver
DEBUG [2008-07-15 08:20:46,984] SelfMessage          SelfMessageHandler.handle pos=50
DEBUG [2008-07-15 08:20:46,984] SelfMessage          handleSelfMessage id=73 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:46,984] SelfMessage          [mobserver,73] Got subscription subId=10 filter [SubjectFilter oid=4910 types=mv.EVENT,]
DEBUG [2008-07-15 08:20:46,984] SelfMessage          sendResponse to mobserver,73 type=msgsys.RESPONSE id=74 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:46,984] SelfMessage          handleSelfMessage id=74 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         SpawnGenerator.spawnObject: name=WESpawn, created object [ObjectStub: oid=4910 node=[InterpolatedWorldNode: objHandle=null, instanceOid=4901, rawLoc=(-120785,30854,-179363), interpLoc=(-120785,30854,-179363), dir=[x=0.0,y=0.0,z=0.0], orient=(0.0,0.0,0.0,1.0)]] at loc=(-120785,30854,-179363)
DEBUG [2008-07-15 08:20:46,984] MobManager-1         SpawnGenerator.addDeathWatch: oid=4910
DEBUG [2008-07-15 08:20:46,984] MobManager-1         subscribe [SubjectFilter oid=4910 types=mv.PROPERTY,] matching agents 3
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.SUBSCRIBE id=75 to wmgr_1
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.SUBSCRIBE id=75 to objmgr
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.SUBSCRIBE id=75 to combat
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from combat,73 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,89 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from objmgr,165 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         MobManagerPlugin, Creating ObjectTracker for instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] MobManager-1         subscribe [PerceptionFilter types=mv.PERCEPTION,mv.UPDATEWNODE, targets=] matching agents 1
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.SUBSCRIBE id=76 to wmgr_1
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,90 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         sendRPC type=mv.GET_PROPERTY id=77 class=multiverse.server.engine.EnginePlugin$GetPropertyMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending mv.GET_PROPERTY id=77 to wmgr_1
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,91 type=msgsys.RESPONSE len=31 class=multiverse.msgsys.GenericResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending msgsys.FILTER_UPDATE id=78 to wmgr_1
DEBUG [2008-07-15 08:20:46,984] MobManager-1         ObjectTracker.addLocalObject: oid=4910 reactionRadius=null instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] MobManager-1         WorldManagerClient.spawn: oid=4910
DEBUG [2008-07-15 08:20:46,984] MobManager-1         sendRPC type=mv.SPAWN_REQ id=79 class=multiverse.server.plugins.WorldManagerClient$SpawnReqMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending mv.SPAWN_REQ id=79 to wmgr_1
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,93 type=mv.PERCEPTION len=198 class=multiverse.server.messages.PerceptionMessage
DEBUG [2008-07-15 08:20:46,984] MessageIO            deliverMessage to multiverse.server.objects.ObjectTracker@1e35108
DEBUG [2008-07-15 08:20:46,984] MessageIO            defaultDispatchMessage wmgr_1,93 MessageType['mv.PERCEPTION', 46]
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.handlePerception: start instanceOid=4901 8 gain and 0 lost
DEBUG [2008-07-15 08:20:46,984] MessageIO            handleMessageData from wmgr_1,95 type=msgsys.RESPONSE len=30 class=multiverse.msgsys.GenericResponseMessage
DEBUG [2008-07-15 08:20:46,984] MobManager-1         WorldManagerClient.spawn: response for oid=4910 result=8
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4902 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4906 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4903 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] MobManager-1         sendBroadcast type=mv.COMMAND id=80 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4907 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] MobManager-1         Sending mv.COMMAND id=80 to mobserver
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4904 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4909 objType=[TDECAL,5] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4905 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] EngineDispatch-1     ObjectTracker.maybeAddRemoteObject: ignoring oid=4908 objType=[STRUCTURE,0] detected by 4910, instanceOid=4901
DEBUG [2008-07-15 08:20:46,984] MobManager-1         SpawnGenerator.spawnObject: name=WESpawn, spawned obj [ObjectStub: oid=4910 node=[InterpolatedWorldNode: objHandle=null, instanceOid=4901, rawLoc=(-120785,30854,-179363), interpLoc=(-120785,30854,-179363), dir=[x=0.0,y=0.0,z=0.0], orient=(0.0,0.0,0.0,1.0)]]
DEBUG [2008-07-15 08:20:46,984] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:20:46,984] MobManager-1         sendResponse to instance,74 type=msgsys.RESPONSE id=81 class=multiverse.msgsys.BooleanResponseMessage
DEBUG [2008-07-15 08:20:46,984] SelfMessage          handleSelfMessage id=80 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:20:46,984] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:20:46,984] SelfMessage          defaultDispatchMessage mobserver,80 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:20:46,984] MobManager-1         EnginePlugin.handleMessage: processed msg 74, type=MessageType['mv.CREATE_SPAWN_GEN', 127], time in ms=47
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-120785,30854,-179363); dest = (-122789,30854,-188040)
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-120785,30854,-179363), (-122789,30854,-188040)]]
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     sendBroadcast type=mv.MOB_PATH_REQ id=82 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     Sending mv.MOB_PATH_REQ id=82 to wmgr_1
DEBUG [2008-07-15 08:20:47,000] EngineDispatch-2     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-120785,30854,-179363), (-122789,30854,-188040)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:20:47,015] MessageIO            handleMessageData from instance,76 type=mv.CREATE_SPAWN_GEN len=85 class=multiverse.server.plugins.MobManagerClient$CreateSpawnGeneratorMessage
DEBUG [2008-07-15 08:20:47,015] MessageIO            deliverMessage rpc to multiverse.server.plugins.MobManagerPlugin@d40c4e
DEBUG [2008-07-15 08:20:47,015] MobManager-2         EnginePlugin.handleMessage: got msg id 76, matching 1 hooks for msgtype MessageType['mv.CREATE_SPAWN_GEN', 127]
DEBUG [2008-07-15 08:20:47,015] MobManager-2         MobManagerPlugin, createObject: template=Wolf, point=(-84046,0,-222143), calling into objectmanager to generate
DEBUG [2008-07-15 08:20:47,015] MobManager-2         MobManagerPlugin, createObject: template=Wolf, override=[Template: name=null (ns=NS.wmgr, key=:loc, val=(-84046,0,-222143))(ns=NS.wmgr, key=:followsTerrain, val=true)(ns=NS.wmgr, key=:instance, val=4901), instanceOid=null calling into objectmanager to generate
DEBUG [2008-07-15 08:20:47,015] MobManager-2         sendRPC type=mv.GENERATE_OBJECT id=83 class=multiverse.server.plugins.ObjectManagerClient$GenerateObjectMessage
DEBUG [2008-07-15 08:20:47,015] MobManager-2         Sending mv.GENERATE_OBJECT id=83 to objmgr
DEBUG [2008-07-15 08:20:47,031] MessageIO            handleMessageData from wmgr_1,97 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            [wmgr_1,97] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,031] MessageIO            ADD SUBJECT 4911
DEBUG [2008-07-15 08:20:47,031] MessageIO            sendResponse to wmgr_1,97 type=msgsys.RESPONSE id=84 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            handleMessageData from wmgr_1,98 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            [wmgr_1,98] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,031] MessageIO            ADD SUBJECT 4911
DEBUG [2008-07-15 08:20:47,031] MessageIO            sendResponse to wmgr_1,98 type=msgsys.RESPONSE id=85 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            handleMessageData from objmgr,171 type=msgsys.SUBSCRIBE len=50 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            [objmgr,171] Got subscription subId=13 filter [SubjectFilter oid=4912 types=mv.GET_PROPERTY,mv.SET_PROPERTY,]
DEBUG [2008-07-15 08:20:47,031] MessageIO            sendResponse to objmgr,171 type=msgsys.RESPONSE id=86 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            handleMessageData from objmgr,173 type=msgsys.SUBSCRIBE len=50 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,031] MessageIO            [objmgr,173] Got subscription subId=14 filter [SubjectFilter oid=4913 types=mv.GET_PROPERTY,mv.SET_PROPERTY,]
DEBUG [2008-07-15 08:20:47,031] MessageIO            sendResponse to objmgr,173 type=msgsys.RESPONSE id=87 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            handleMessageData from objmgr,175 type=msgsys.SUBSCRIBE len=50 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            [objmgr,175] Got subscription subId=15 filter [SubjectFilter oid=4914 types=mv.GET_PROPERTY,mv.SET_PROPERTY,]
DEBUG [2008-07-15 08:20:47,046] MessageIO            sendResponse to objmgr,175 type=msgsys.RESPONSE id=88 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            handleMessageData from objmgr,177 type=msgsys.SUBSCRIBE len=50 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            [objmgr,177] Got subscription subId=16 filter [SubjectFilter oid=4915 types=mv.GET_PROPERTY,mv.SET_PROPERTY,]
DEBUG [2008-07-15 08:20:47,046] MessageIO            sendResponse to objmgr,177 type=msgsys.RESPONSE id=89 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            handleMessageData from objmgr,179 type=msgsys.SUBSCRIBE len=50 class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,046] MessageIO            [objmgr,179] Got subscription subId=17 filter [SubjectFilter oid=4916 types=mv.GET_PROPERTY,mv.SET_PROPERTY,]
DEBUG [2008-07-15 08:20:47,046] MessageIO            sendResponse to objmgr,179 type=msgsys.RESPONSE id=90 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,062] MessageIO            handleMessageData from objmgr,194 type=msgsys.LONG_RESPONSE len=33 class=multiverse.msgsys.LongResponseMessage
DEBUG [2008-07-15 08:20:47,062] MobManager-2         MobManagerPlugin, generated object oid=4911
DEBUG [2008-07-15 08:20:47,062] MobManager-2         sendRPC type=mv.GETWNODE_REQ id=91 class=multiverse.msgsys.SubjectMessage
DEBUG [2008-07-15 08:20:47,062] MobManager-2         Sending mv.GETWNODE_REQ id=91 to wmgr_1
DEBUG [2008-07-15 08:20:47,062] MessageIO            handleMessageData from wmgr_1,100 type=msgsys.RESPONSE len=78 class=multiverse.msgsys.GenericResponseMessage
DEBUG [2008-07-15 08:20:47,062] MobManager-2         WorldManagerClient.getWorldNode: oid=4911 wnode=BasicWorldNode[instanceOid=4901 loc=(-84046,0,-222143) dir=[x=0.0,y=0.0,z=0.0] orient=(0.0,0.0,0.0,1.0)]
DEBUG [2008-07-15 08:20:47,062] MobManager-2         MobManagerPlugin, createObject: obj=[ObjectStub: oid=4911 node=[InterpolatedWorldNode: objHandle=null, instanceOid=4901, rawLoc=(-84046,0,-222143), interpLoc=(-84046,0,-222143), dir=[x=0.0,y=0.0,z=0.0], orient=(0.0,0.0,0.0,1.0)]]
DEBUG [2008-07-15 08:20:47,062] MobManager-2         sendRPC type=mv.GET_MARKER id=92 class=multiverse.server.plugins.InstanceClient$GetMarkerMessage
DEBUG [2008-07-15 08:20:47,062] MobManager-2         Sending mv.GET_MARKER id=92 to instance
DEBUG [2008-07-15 08:20:47,062] MessageIO            handleMessageData from instance,78 type=msgsys.RESPONSE len=40 class=multiverse.msgsys.GenericResponseMessage
DEBUG [2008-07-15 08:20:47,062] MobManager-2         PathState, clear: oid = 4911
DEBUG [2008-07-15 08:20:47,062] MobManager-2         subscribe [SubjectFilter oid=4911 types=mv.MOB_PATH_CORRECTION,mv.COMMAND,] matching agents 3
DEBUG [2008-07-15 08:20:47,062] MobManager-2         Sending msgsys.SUBSCRIBE id=93 to mobserver
DEBUG [2008-07-15 08:20:47,062] MobManager-2         Sending msgsys.SUBSCRIBE id=93 to wmgr_1
DEBUG [2008-07-15 08:20:47,062] SelfMessage          SelfMessageHandler.handle pos=54
DEBUG [2008-07-15 08:20:47,062] MobManager-2         Sending msgsys.SUBSCRIBE id=93 to proxy_1
DEBUG [2008-07-15 08:20:47,062] SelfMessage          handleSelfMessage id=93 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,062] SelfMessage          [mobserver,93] Got subscription subId=13 filter [SubjectFilter oid=4911 types=mv.MOB_PATH_CORRECTION,mv.COMMAND,]
DEBUG [2008-07-15 08:20:47,062] SelfMessage          sendResponse to mobserver,93 type=msgsys.RESPONSE id=94 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,062] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:47,062] SelfMessage          handleSelfMessage id=94 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,062] MessageIO            handleMessageData from proxy_1,89 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] MessageIO            handleMessageData from wmgr_1,101 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] MobManager-2         subscribe [SubjectFilter oid=4911 types=mv.EVENT,] matching agents 1
DEBUG [2008-07-15 08:20:47,078] MobManager-2         Sending msgsys.SUBSCRIBE id=95 to mobserver
DEBUG [2008-07-15 08:20:47,078] SelfMessage          SelfMessageHandler.handle pos=50
DEBUG [2008-07-15 08:20:47,078] SelfMessage          handleSelfMessage id=95 type=msgsys.SUBSCRIBE class=multiverse.msgsys.SubscribeMessage
DEBUG [2008-07-15 08:20:47,078] SelfMessage          [mobserver,95] Got subscription subId=14 filter [SubjectFilter oid=4911 types=mv.EVENT,]
DEBUG [2008-07-15 08:20:47,078] SelfMessage          sendResponse to mobserver,95 type=msgsys.RESPONSE id=96 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] SelfMessage          SelfMessageHandler.handle pos=28
DEBUG [2008-07-15 08:20:47,078] SelfMessage          handleSelfMessage id=96 type=msgsys.RESPONSE class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] MobManager-2         subscribe [SubjectFilter oid=4911 types=mv.PROPERTY,mv.DAMAGE,] matching agents 3
DEBUG [2008-07-15 08:20:47,078] MobManager-2         Sending msgsys.SUBSCRIBE id=97 to wmgr_1
DEBUG [2008-07-15 08:20:47,078] MobManager-2         Sending msgsys.SUBSCRIBE id=97 to objmgr
DEBUG [2008-07-15 08:20:47,078] MobManager-2         Sending msgsys.SUBSCRIBE id=97 to combat
DEBUG [2008-07-15 08:20:47,078] MessageIO            handleMessageData from wmgr_1,102 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] MessageIO            handleMessageData from combat,82 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,078] MessageIO            handleMessageData from objmgr,195 type=msgsys.RESPONSE len=24 class=multiverse.msgsys.ResponseMessage
ERROR [2008-07-15 08:20:47,078] MobManager-2         handleMessageImpl multiverse.server.util.MVRuntimeException: activate failed multiverse.server.util.MVRuntimeException: activate failed
       at multiverse.mars.objects.SpawnGenerator.activate(SpawnGenerator.java:57)
       at multiverse.server.plugins.MobManagerPlugin$CreateSpawnGenHook.processMessage(MobManagerPlugin.java:174)
       at multiverse.server.engine.EnginePlugin.handleMessageImpl(EnginePlugin.java:406)
       at multiverse.server.engine.EnginePlugin$QueuedMessage.run(EnginePlugin.java:440)
       at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
       at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
       at java.lang.Thread.run(Unknown Source)
Caused by: Traceback (innermost last):
  File "..\config\sampleworld\mobserver.py", line 28, in makeObject
NameError: SteeringBehavior

       at org.python.core.Py.NameError(Unknown Source)
       at org.python.core.PyFrame.getglobal(Unknown Source)
       at org.python.pycode._pyx10.makeObject$2(..\config\sampleworld\mobserver.py:28)
       at org.python.pycode._pyx10.call_function(..\config\sampleworld\mobserver.py)
       at org.python.core.PyTableCode.call(Unknown Source)
       at org.python.core.PyTableCode.call(Unknown Source)
       at org.python.core.PyTableCode.call(Unknown Source)
       at org.python.core.PyFunction.__call__(Unknown Source)
       at org.python.core.PyMethod.__call__(Unknown Source)
       at org.python.core.PyObject.__call__(Unknown Source)
       at org.python.core.PyObject._jcallexc(Unknown Source)
       at org.python.core.PyObject._jcall(Unknown Source)
       at org.python.proxies.mvmodule$WolfFactory$0.makeObject(Unknown Source)
       at multiverse.mars.objects.SpawnGenerator.spawnObject(SpawnGenerator.java:108)
       at multiverse.mars.objects.SpawnGenerator.activate(SpawnGenerator.java:53)
       at multiverse.server.plugins.MobManagerPlugin$CreateSpawnGenHook.processMessage(MobManagerPlugin.java:174)
       at multiverse.server.engine.EnginePlugin.handleMessageImpl(EnginePlugin.java:406)
       at multiverse.server.engine.EnginePlugin$QueuedMessage.run(EnginePlugin.java:440)
       at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
       at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
       at java.lang.Thread.run(Unknown Source)
DEBUG [2008-07-15 08:20:47,078] MobManager-2         sendResponse to instance,76 type=msgsys.RESPONSE id=98 class=multiverse.msgsys.ExceptionResponseMessage
DEBUG [2008-07-15 08:20:47,109] MessageIO            handleMessageData from wmgr_1,106 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,109] MessageIO            [wmgr_1,106] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,109] MessageIO            ADD SUBJECT 4920
DEBUG [2008-07-15 08:20:47,109] MessageIO            sendResponse to wmgr_1,106 type=msgsys.RESPONSE id=99 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,109] MessageIO            handleMessageData from wmgr_1,107 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,109] MessageIO            [wmgr_1,107] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,109] MessageIO            ADD SUBJECT 4920
DEBUG [2008-07-15 08:20:47,109] MessageIO            sendResponse to wmgr_1,107 type=msgsys.RESPONSE id=100 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,125] MessageIO            handleMessageData from wmgr_1,111 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,125] MessageIO            [wmgr_1,111] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,125] MessageIO            ADD SUBJECT 4921
DEBUG [2008-07-15 08:20:47,125] MessageIO            sendResponse to wmgr_1,111 type=msgsys.RESPONSE id=101 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,125] MessageIO            handleMessageData from wmgr_1,112 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,125] MessageIO            [wmgr_1,112] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,125] MessageIO            ADD SUBJECT 4921
DEBUG [2008-07-15 08:20:47,125] MessageIO            sendResponse to wmgr_1,112 type=msgsys.RESPONSE id=102 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,140] MessageIO            handleMessageData from wmgr_1,116 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,140] MessageIO            [wmgr_1,116] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,140] MessageIO            ADD SUBJECT 4922
DEBUG [2008-07-15 08:20:47,140] MessageIO            sendResponse to wmgr_1,116 type=msgsys.RESPONSE id=103 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,156] MessageIO            handleMessageData from wmgr_1,117 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,156] MessageIO            [wmgr_1,117] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,156] MessageIO            ADD SUBJECT 4922
DEBUG [2008-07-15 08:20:47,156] MessageIO            sendResponse to wmgr_1,117 type=msgsys.RESPONSE id=104 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,171] MessageIO            handleMessageData from wmgr_1,121 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,171] MessageIO            [wmgr_1,121] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,171] MessageIO            ADD SUBJECT 4923
DEBUG [2008-07-15 08:20:47,171] MessageIO            sendResponse to wmgr_1,121 type=msgsys.RESPONSE id=105 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,171] MessageIO            handleMessageData from wmgr_1,122 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,171] MessageIO            [wmgr_1,122] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,171] MessageIO            ADD SUBJECT 4923
DEBUG [2008-07-15 08:20:47,171] MessageIO            sendResponse to wmgr_1,122 type=msgsys.RESPONSE id=106 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,187] MessageIO            handleMessageData from wmgr_1,126 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,187] MessageIO            [wmgr_1,126] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,187] MessageIO            ADD SUBJECT 4924
DEBUG [2008-07-15 08:20:47,187] MessageIO            sendResponse to wmgr_1,126 type=msgsys.RESPONSE id=107 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,187] MessageIO            handleMessageData from wmgr_1,127 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,187] MessageIO            [wmgr_1,127] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,187] MessageIO            ADD SUBJECT 4924
DEBUG [2008-07-15 08:20:47,187] MessageIO            sendResponse to wmgr_1,127 type=msgsys.RESPONSE id=108 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,218] MessageIO            handleMessageData from wmgr_1,134 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,218] MessageIO            [wmgr_1,134] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,218] MessageIO            ADD SUBJECT 4926
DEBUG [2008-07-15 08:20:47,218] MessageIO            sendResponse to wmgr_1,134 type=msgsys.RESPONSE id=109 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,218] MessageIO            handleMessageData from wmgr_1,135 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,218] MessageIO            [wmgr_1,135] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,218] MessageIO            ADD SUBJECT 4926
DEBUG [2008-07-15 08:20:47,218] MessageIO            sendResponse to wmgr_1,135 type=msgsys.RESPONSE id=110 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,234] MessageIO            handleMessageData from wmgr_1,139 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,234] MessageIO            [wmgr_1,139] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,234] MessageIO            ADD SUBJECT 4927
DEBUG [2008-07-15 08:20:47,234] MessageIO            sendResponse to wmgr_1,139 type=msgsys.RESPONSE id=111 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,234] MessageIO            handleMessageData from wmgr_1,140 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,234] MessageIO            [wmgr_1,140] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,234] MessageIO            ADD SUBJECT 4927
DEBUG [2008-07-15 08:20:47,234] MessageIO            sendResponse to wmgr_1,140 type=msgsys.RESPONSE id=112 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,250] MessageIO            handleMessageData from wmgr_1,144 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,250] MessageIO            [wmgr_1,144] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,250] MessageIO            ADD SUBJECT 4928
DEBUG [2008-07-15 08:20:47,250] MessageIO            sendResponse to wmgr_1,144 type=msgsys.RESPONSE id=113 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,250] MessageIO            handleMessageData from wmgr_1,145 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,250] MessageIO            [wmgr_1,145] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,250] MessageIO            ADD SUBJECT 4928
DEBUG [2008-07-15 08:20:47,250] MessageIO            sendResponse to wmgr_1,145 type=msgsys.RESPONSE id=114 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,250] MessageIO            handleMessageData from wmgr_1,149 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,265] MessageIO            [wmgr_1,149] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,265] MessageIO            ADD SUBJECT 4929
DEBUG [2008-07-15 08:20:47,265] MessageIO            sendResponse to wmgr_1,149 type=msgsys.RESPONSE id=115 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,265] MessageIO            handleMessageData from wmgr_1,150 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,265] MessageIO            [wmgr_1,150] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,265] MessageIO            ADD SUBJECT 4929
DEBUG [2008-07-15 08:20:47,265] MessageIO            sendResponse to wmgr_1,150 type=msgsys.RESPONSE id=116 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,281] MessageIO            handleMessageData from wmgr_1,157 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,281] MessageIO            [wmgr_1,157] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,281] MessageIO            ADD SUBJECT 4931
DEBUG [2008-07-15 08:20:47,281] MessageIO            sendResponse to wmgr_1,157 type=msgsys.RESPONSE id=117 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,281] MessageIO            handleMessageData from wmgr_1,158 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,281] MessageIO            [wmgr_1,158] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,281] MessageIO            ADD SUBJECT 4931
DEBUG [2008-07-15 08:20:47,281] MessageIO            sendResponse to wmgr_1,158 type=msgsys.RESPONSE id=118 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,296] MessageIO            handleMessageData from wmgr_1,162 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,296] MessageIO            [wmgr_1,162] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,296] MessageIO            ADD SUBJECT 4932
DEBUG [2008-07-15 08:20:47,296] MessageIO            sendResponse to wmgr_1,162 type=msgsys.RESPONSE id=119 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,296] MessageIO            handleMessageData from wmgr_1,163 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,296] MessageIO            [wmgr_1,163] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,296] MessageIO            ADD SUBJECT 4932
DEBUG [2008-07-15 08:20:47,296] MessageIO            sendResponse to wmgr_1,163 type=msgsys.RESPONSE id=120 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,328] MessageIO            handleMessageData from wmgr_1,167 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,328] MessageIO            [wmgr_1,167] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,328] MessageIO            ADD SUBJECT 4933
DEBUG [2008-07-15 08:20:47,328] MessageIO            sendResponse to wmgr_1,167 type=msgsys.RESPONSE id=121 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,328] MessageIO            handleMessageData from wmgr_1,168 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,328] MessageIO            [wmgr_1,168] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,328] MessageIO            ADD SUBJECT 4933
DEBUG [2008-07-15 08:20:47,328] MessageIO            sendResponse to wmgr_1,168 type=msgsys.RESPONSE id=122 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,343] MessageIO            handleMessageData from wmgr_1,172 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,343] MessageIO            [wmgr_1,172] Got filter update subid=7 rpc=true
DEBUG [2008-07-15 08:20:47,343] MessageIO            ADD SUBJECT 4934
DEBUG [2008-07-15 08:20:47,343] MessageIO            sendResponse to wmgr_1,172 type=msgsys.RESPONSE id=123 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:47,343] MessageIO            handleMessageData from wmgr_1,173 type=msgsys.FILTER_UPDATE len=51 class=multiverse.msgsys.FilterUpdateMessage
DEBUG [2008-07-15 08:20:47,343] MessageIO            [wmgr_1,173] Got filter update subid=8 rpc=true
DEBUG [2008-07-15 08:20:47,343] MessageIO            ADD SUBJECT 4934
DEBUG [2008-07-15 08:20:47,343] MessageIO            sendResponse to wmgr_1,173 type=msgsys.RESPONSE id=124 class=multiverse.msgsys.ResponseMessage
DEBUG [2008-07-15 08:20:49,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:20:50,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 15/15, Removed 0/0
INFO  [2008-07-15 08:20:50,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 27/27, Removed 0/0
INFO  [2008-07-15 08:20:50,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 1/1, Remote 92/92
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-122978,30854,-188862)
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          sendBroadcast type=mv.MOB_PATH_REQ id=125 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          Sending mv.MOB_PATH_REQ id=125 to wmgr_1
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          sendBroadcast type=mv.EVENT id=126 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          Sending mv.EVENT id=126 to mobserver
DEBUG [2008-07-15 08:20:50,250] Scheduled-1          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-122978,30854,-188862); destLoc = (-122789,30854,-188040)
DEBUG [2008-07-15 08:20:50,250] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:20:50,250] SelfMessage          handleSelfMessage id=126 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:20:50,250] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:20:50,250] SelfMessage          defaultDispatchMessage mobserver,126 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:20:54,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:20:55,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:20:55,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:20:55,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:20:55,250] Scheduled-9          sendBroadcast type=mv.COMMAND id=127 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:20:55,250] Scheduled-9          Sending mv.COMMAND id=127 to mobserver
DEBUG [2008-07-15 08:20:55,250] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:20:55,250] SelfMessage          handleSelfMessage id=127 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:20:55,250] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:20:55,250] SelfMessage          defaultDispatchMessage mobserver,127 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-122978,30854,-188862); dest = (-120848,30854,-180365)
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-122978,30854,-188862), (-120848,30854,-180365)]]
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     sendBroadcast type=mv.MOB_PATH_REQ id=128 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     Sending mv.MOB_PATH_REQ id=128 to wmgr_1
DEBUG [2008-07-15 08:20:55,250] EngineDispatch-4     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-122978,30854,-188862), (-120848,30854,-180365)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-120790,30854,-180133)
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          sendBroadcast type=mv.MOB_PATH_REQ id=129 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          Sending mv.MOB_PATH_REQ id=129 to wmgr_1
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          sendBroadcast type=mv.EVENT id=130 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          Sending mv.EVENT id=130 to mobserver
DEBUG [2008-07-15 08:20:58,250] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:20:58,250] SelfMessage          handleSelfMessage id=130 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:20:58,250] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:20:58,250] SelfMessage          defaultDispatchMessage mobserver,130 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:20:58,250] Scheduled-7          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-120790,30854,-180133); destLoc = (-120848,30854,-180365)
DEBUG [2008-07-15 08:20:59,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:00,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:00,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:00,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:03,250] Scheduled-7          sendBroadcast type=mv.COMMAND id=131 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:03,250] Scheduled-7          Sending mv.COMMAND id=131 to mobserver
DEBUG [2008-07-15 08:21:03,250] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:03,250] SelfMessage          handleSelfMessage id=131 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:03,250] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:03,250] SelfMessage          defaultDispatchMessage mobserver,131 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-120790,30854,-180133); dest = (-110107,30854,-187635)
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-120790,30854,-180133), (-110107,30854,-187635)]]
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     sendBroadcast type=mv.MOB_PATH_REQ id=132 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     Sending mv.MOB_PATH_REQ id=132 to wmgr_1
DEBUG [2008-07-15 08:21:03,250] EngineDispatch-6     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-120790,30854,-180133), (-110107,30854,-187635)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:04,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:05,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:05,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:05,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-109742,30854,-187891)
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          sendBroadcast type=mv.MOB_PATH_REQ id=133 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          Sending mv.MOB_PATH_REQ id=133 to wmgr_1
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          sendBroadcast type=mv.EVENT id=134 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          Sending mv.EVENT id=134 to mobserver
DEBUG [2008-07-15 08:21:07,750] Scheduled-6          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-109742,30854,-187891); destLoc = (-110107,30854,-187635)
DEBUG [2008-07-15 08:21:07,750] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:07,750] SelfMessage          handleSelfMessage id=134 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:07,750] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:07,750] SelfMessage          defaultDispatchMessage mobserver,134 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:09,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:10,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:10,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:10,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:12,750] Scheduled-10         sendBroadcast type=mv.COMMAND id=135 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:12,750] Scheduled-10         Sending mv.COMMAND id=135 to mobserver
DEBUG [2008-07-15 08:21:12,750] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:12,750] SelfMessage          handleSelfMessage id=135 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:12,750] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:12,750] SelfMessage          defaultDispatchMessage mobserver,135 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-109742,30854,-187891); dest = (-121472,30854,-191906)
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-109742,30854,-187891), (-121472,30854,-191906)]]
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     sendBroadcast type=mv.MOB_PATH_REQ id=136 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:12,750] EngineDispatch-8     Sending mv.MOB_PATH_REQ id=136 to wmgr_1
DEBUG [2008-07-15 08:21:12,765] EngineDispatch-8     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-109742,30854,-187891), (-121472,30854,-191906)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:14,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:15,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:15,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:15,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-122514,30854,-192262)
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         sendBroadcast type=mv.MOB_PATH_REQ id=137 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         Sending mv.MOB_PATH_REQ id=137 to wmgr_1
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         sendBroadcast type=mv.EVENT id=138 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         Sending mv.EVENT id=138 to mobserver
DEBUG [2008-07-15 08:21:17,250] Scheduled-10         BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-122514,30854,-192262); destLoc = (-121472,30854,-191906)
DEBUG [2008-07-15 08:21:17,250] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:17,250] SelfMessage          handleSelfMessage id=138 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:17,250] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:17,250] SelfMessage          defaultDispatchMessage mobserver,138 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:19,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:20,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:20,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:20,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:22,250] Scheduled-7          sendBroadcast type=mv.COMMAND id=139 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:22,250] Scheduled-7          Sending mv.COMMAND id=139 to mobserver
DEBUG [2008-07-15 08:21:22,250] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:22,250] SelfMessage          handleSelfMessage id=139 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:22,250] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:22,250] SelfMessage          defaultDispatchMessage mobserver,139 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    BaseBehavior.gotoSetup: oid = 4910; myLoc = (-122514,30854,-192262); dest = (-107026,30854,-190299)
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-122514,30854,-192262), (-107026,30854,-190299)]]
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    sendBroadcast type=mv.MOB_PATH_REQ id=140 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:22,250] EngineDispatch-10    Sending mv.MOB_PATH_REQ id=140 to wmgr_1
DEBUG [2008-07-15 08:21:22,265] EngineDispatch-10    BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-122514,30854,-192262), (-107026,30854,-190299)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:24,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:25,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:25,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:25,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-106145,30854,-190187)
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         sendBroadcast type=mv.MOB_PATH_REQ id=141 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         Sending mv.MOB_PATH_REQ id=141 to wmgr_1
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         sendBroadcast type=mv.EVENT id=142 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         Sending mv.EVENT id=142 to mobserver
DEBUG [2008-07-15 08:21:27,750] Scheduled-10         BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-106145,30854,-190187); destLoc = (-107026,30854,-190299)
DEBUG [2008-07-15 08:21:27,750] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:27,750] SelfMessage          handleSelfMessage id=142 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:27,750] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:27,750] SelfMessage          defaultDispatchMessage mobserver,142 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:29,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:30,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:30,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:30,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:32,765] Scheduled-6          sendBroadcast type=mv.COMMAND id=143 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:32,765] Scheduled-6          Sending mv.COMMAND id=143 to mobserver
DEBUG [2008-07-15 08:21:32,765] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:32,765] SelfMessage          handleSelfMessage id=143 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:32,765] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:32,765] SelfMessage          defaultDispatchMessage mobserver,143 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-106145,30854,-190187); dest = (-113408,30854,-176318)
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-106145,30854,-190187), (-113408,30854,-176318)]]
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     sendBroadcast type=mv.MOB_PATH_REQ id=144 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     Sending mv.MOB_PATH_REQ id=144 to wmgr_1
DEBUG [2008-07-15 08:21:32,765] EngineDispatch-2     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-106145,30854,-190187), (-113408,30854,-176318)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:34,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:35,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:35,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:35,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-113821,30854,-175528)
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          sendBroadcast type=mv.MOB_PATH_REQ id=145 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          Sending mv.MOB_PATH_REQ id=145 to wmgr_1
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          sendBroadcast type=mv.EVENT id=146 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          Sending mv.EVENT id=146 to mobserver
DEBUG [2008-07-15 08:21:38,281] Scheduled-8          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-113821,30854,-175528); destLoc = (-113408,30854,-176318)
DEBUG [2008-07-15 08:21:38,281] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:38,281] SelfMessage          handleSelfMessage id=146 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:38,281] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:38,281] SelfMessage          defaultDispatchMessage mobserver,146 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:39,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:40,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:40,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:40,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:43,281] Scheduled-3          sendBroadcast type=mv.COMMAND id=147 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:43,281] Scheduled-3          Sending mv.COMMAND id=147 to mobserver
DEBUG [2008-07-15 08:21:43,281] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:43,281] SelfMessage          handleSelfMessage id=147 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:43,281] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:43,281] SelfMessage          defaultDispatchMessage mobserver,147 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-113821,30854,-175528); dest = (-119298,30854,-185278)
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-113821,30854,-175528), (-119298,30854,-185278)]]
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     sendBroadcast type=mv.MOB_PATH_REQ id=148 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     Sending mv.MOB_PATH_REQ id=148 to wmgr_1
DEBUG [2008-07-15 08:21:43,281] EngineDispatch-4     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-113821,30854,-175528), (-119298,30854,-185278)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:44,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
DEBUG [2008-07-15 08:21:44,750] DBKeepalive          Database: ping
INFO  [2008-07-15 08:21:45,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:45,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:45,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-119697,30854,-185989)
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          sendBroadcast type=mv.MOB_PATH_REQ id=149 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          Sending mv.MOB_PATH_REQ id=149 to wmgr_1
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          sendBroadcast type=mv.EVENT id=150 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          Sending mv.EVENT id=150 to mobserver
DEBUG [2008-07-15 08:21:47,281] Scheduled-4          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-119697,30854,-185989); destLoc = (-119298,30854,-185278)
DEBUG [2008-07-15 08:21:47,281] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:47,281] SelfMessage          handleSelfMessage id=150 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:47,281] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:47,281] SelfMessage          defaultDispatchMessage mobserver,150 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:49,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:50,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:50,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:50,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
DEBUG [2008-07-15 08:21:52,281] Scheduled-9          sendBroadcast type=mv.COMMAND id=151 class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:52,281] Scheduled-9          Sending mv.COMMAND id=151 to mobserver
DEBUG [2008-07-15 08:21:52,281] SelfMessage          SelfMessageHandler.handle pos=55
DEBUG [2008-07-15 08:21:52,281] SelfMessage          handleSelfMessage id=151 type=mv.COMMAND class=multiverse.server.engine.BaseBehavior$GotoCommandMessage
DEBUG [2008-07-15 08:21:52,281] SelfMessage          deliverMessage to multiverse.server.engine.BaseBehavior@52cdcc
DEBUG [2008-07-15 08:21:52,281] SelfMessage          defaultDispatchMessage mobserver,151 MessageType['mv.COMMAND', 83]
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     BaseBehavior.onMessage: command = goto; oid = 4910; name null
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     BaseBehavior.gotoSetup: oid = 4910; myLoc = (-119697,30854,-185989); dest = (-121904,30854,-183623)
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     PathState, setupPathInterpolator: findPath result = Success; path.size() = 2; terrainString = TT
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     PathState, setupPathInterpolator: pathInterpolator = [PathLinear oid = 4910; speed = 3000.0; path = [(-119697,30854,-185989), (-121904,30854,-183623)]]
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     sendBroadcast type=mv.MOB_PATH_REQ id=152 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     Sending mv.MOB_PATH_REQ id=152 to wmgr_1
DEBUG [2008-07-15 08:21:52,281] EngineDispatch-6     BaseBehavior.setupPathInterpolator: send MobPathReqMessage [MobPathMessageReq oid=4910, interpKind=linear, speed=3000.0, terrainString=TT, pathPoints=[(-119697,30854,-185989), (-121904,30854,-183623)], super=[multiverse.server.plugins.WorldManagerClient$MobPathReqMessage subject=4910]]
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          BaseBehavior.interpolatePath: cancelling path: oid = 4910; myLoc = (-122766,30854,-182699)
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          sendBroadcast type=mv.MOB_PATH_REQ id=153 class=multiverse.server.plugins.WorldManagerClient$MobPathReqMessage
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          Sending mv.MOB_PATH_REQ id=153 to wmgr_1
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          sendBroadcast type=mv.EVENT id=154 class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          Sending mv.EVENT id=154 to mobserver
DEBUG [2008-07-15 08:21:53,781] Scheduled-3          BaseBehavior.gotoUpdate sending ArrivedEventMessage: oid = 4910; myLoc = (-122766,30854,-182699); destLoc = (-121904,30854,-183623)
DEBUG [2008-07-15 08:21:53,781] SelfMessage          SelfMessageHandler.handle pos=40
DEBUG [2008-07-15 08:21:53,781] SelfMessage          handleSelfMessage id=154 type=mv.EVENT class=multiverse.server.engine.BaseBehavior$ArrivedEventMessage
DEBUG [2008-07-15 08:21:53,781] SelfMessage          deliverMessage to multiverse.mars.behaviors.RadiusRoamBehavior@133d68a
DEBUG [2008-07-15 08:21:53,781] SelfMessage          defaultDispatchMessage mobserver,154 MessageType['mv.EVENT', 84]
DEBUG [2008-07-15 08:21:54,453] Scheduled-1          BasicInterpolator.run: interpolating all objects
INFO  [2008-07-15 08:21:55,000] Stats:MessageAgent   MessageAgent Local Subscription Counters: last interval/total: Created 0/15, Removed 0/0
INFO  [2008-07-15 08:21:55,000] Stats:MessageAgent   MessageAgent Remote Subscription Counters: last interval/total: Created 0/27, Removed 0/0
INFO  [2008-07-15 08:21:55,000] Stats:MessageAgent   MessageAgent Filter Updates: last interval/total: Local 0/1, Remote 0/92
Unless otherwise stated, the content of this page is licensed under Creative Commons Attribution-ShareAlike 3.0 License