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
page revision: 1, last edited: 15 Jul 2008 15:34