cloudstack-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From raj kumar <rajkumar600...@gmail.com>
Subject Re: could not register iso
Date Fri, 18 Oct 2013 15:32:31 GMT
I've added sec. storage and also tested mounting and unmounting in ssvm.

the logs are same as I mentioned before.

Note console vm has agent running successfully. but not in ssvm.

I've downloaded
http://d21ifhcun6b1t2.cloudfront.net/templates/4.2/systemvmtemplate-2013-06-12-master-kvm.qcow2.bz2.
is there anything latest i can try.


On Fri, Oct 18, 2013 at 5:40 PM, Chirag Choudhary <chirag200666@gmail.com>wrote:

> Hi Raj,
>    Looks like you have not added the secondary storage or its unreachable,
> export the secondary storage if not done already, to test it mount it in
> the hypervisor host and unmount , then add it to management server .Then it
> should work if dosen't post more logs through pastebin.com
>
>
> Regards,
>
>
> On Fri, Oct 18, 2013 at 3:39 PM, raj kumar <rajkumar600003@gmail.com>
> wrote:
>
> > I can ping and wget the iso from the webserver from
> > ssvm. /var/log/cloud.log shows,
> >
> > ERROR [cloud.agent.AgentShell] (main:null) Unable to start agent:
> Resource
> > class not found:
> com.cloud.storage.resource.PremiumSecondaryStorageResource
> > due to: java.lang.ClassNotFoundException:
> > com.cloud.storage.resource.PremiumSecondaryStorageResource.
> >
> >
> > On Thu, Oct 17, 2013 at 8:18 PM, Chirag Choudhary <
> chirag200666@gmail.com
> > >wrote:
> >
> > > Hi Raj,
> > >     To add more IPs go to Infrastructure -> Pods -> <pod name> ->
> > details :
> > > edit the start and end IPs to increase the range. I would recommend
> > keeping
> > > the previous ones in range.
> > >
> > >     There are no error messages in the logs, so try to ping your web
> > server
> > > from ssvm, try wget.
> > > and next time paste logs on http://pastebin.com/ and then send the
> > > link, readability of logs is lost in an email.
> > > Try to find error in the logs and send logs near to that block.
> > >
> > > Regards,
> > >
> > >
> > > On Thu, Oct 17, 2013 at 7:52 PM, raj kumar <rajkumar600003@gmail.com>
> > > wrote:
> > >
> > > > yes. you're right. I was using wrong template. got the correct
> template
> > > now
> > > > and I can reach ssvm now.  But still adding iso still failing.
> > > > Also pls let me know how to add more management IPs. I provided only
> 3
> > > and
> > > > all used now.
> > > >
> > > >
> > > >
> > > > apilog.log:
> > > > 2013-10-17 19:35:10,381 INFO  [cloud.api.ApiServer]
> > > (catalina-exec-25:null)
> > > > (userId=2 accountId=2 sessionId=6460C7CC9DF1F61DB90B3135A3A1ECEA)
> > > > 192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=registerIso&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&name=centos64&displayText=x86_64&url=http%3A%2F%2F192.168.210.26%2Fcentos%2F6.4%2Fisos%2Fx86_64%2FCentOS-6.4-x86_64-bin-DVD1.iso&zoneid=-1&isextractable=true&bootable=true&osTypeId=ec21dd22-3730-11e3-821d-005056ac122f&ispublic=true&isfeatured=true&_=1382018726636
> > > > 200 { "registerisoresponse" : { "count":1 ,"iso" : [
> > > >
> > > >
> > >
> >
>  {"id":"81b3713e-5754-4ef7-b703-78f32f2cdc06","name":"centos64","displaytext":"x86_64","ispublic":true,"created":"2013-10-17T19:35:10+0530","isready":false,"bootable":true,"isfeatured":true,"crossZones":true,"ostypeid":"ec21dd22-3730-11e3-821d-005056ac122f","ostypename":"CentOS
> > > > 6.4
> > > >
> > > >
> > >
> >
> (64-bit)","account":"admin","zoneid":"55443fe9-72f0-43f5-8ed7-f4d018c144a9","zonename":"zone-India","status":"","domain":"ROOT","domainid":"ebdae444-3730-11e3-821d-005056ac122f","isextractable":true,"tags":[]}
> > > > ] } }
> > > > 2013-10-17 19:36:45,530 INFO  [cloud.api.ApiServer]
> > > (catalina-exec-1:null)
> > > > (userId=2 accountId=2 sessionId=6460C7CC9DF1F61DB90B3135A3A1ECEA)
> > > > 192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=self&_=1382018821928
> > > > 200 { "listisosresponse" : { } }
> > > > 2013-10-17 19:36:47,507 INFO  [cloud.api.ApiServer]
> > > (catalina-exec-23:null)
> > > > (userId=2 accountId=2 sessionId=6460C7CC9DF1F61DB90B3135A3A1ECEA)
> > > > 192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=all&_=1382018823909
> > > > 200 { "listisosresponse" : { "count":2 ,"iso" : [
> > > >
> > > >
> > >
> >
>  {"id":"e0b59408-72c8-416d-b577-6c3dda0a2313","name":"xs-tools.iso","displaytext":"xen-pv-drv-iso","ispublic":true,"isready":true,"bootable":false,"isfeatured":true,"crossZones":false,"ostypeid":"ebe573aa-3730-11e3-821d-005056ac122f","ostypename":"CentOS
> > > > 4.5
> > > >
> > > >
> > >
> >
> (32-bit)","account":"system","domain":"ROOT","domainid":"ebdae444-3730-11e3-821d-005056ac122f","isextractable":false,"tags":[]},
> > > >
> > > >
> > >
> >
> {"id":"97978b7d-1eb2-43c5-954a-10a37ae1a20c","name":"vmware-tools.iso","displaytext":"VMware
> > > > Tools Installer
> > > >
> > > >
> > >
> >
> ISO","ispublic":true,"isready":true,"bootable":false,"isfeatured":true,"crossZones":false,"ostypeid":"ebe573aa-3730-11e3-821d-005056ac122f","ostypename":"CentOS
> > > > 4.5
> > > >
> > > >
> > >
> >
> (32-bit)","account":"system","domain":"ROOT","domainid":"ebdae444-3730-11e3-821d-005056ac122f","isextractable":false,"tags":[]}
> > > > ] } }
> > > >
> > > >
> > > >
> > > >
> > > >
> > > >
> > > >
> > > > management server log:
> > > >
> > > > 2013-10-17 19:35:10,239 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-25:null) ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=registerIso&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&name=centos64&displayText=x86_64&url=http%3A%2F%2F192.168.210.26%2Fcentos%2F6.4%2Fisos%2Fx86_64%2FCentOS-6.4-x86_64-bin-DVD1.iso&zoneid=-1&isextractable=true&bootable=true&osTypeId=ec21dd22-3730-11e3-821d-005056ac122f&ispublic=true&isfeatured=true&_=1382018726636
> > > >
> > > > 2013-10-17 19:35:10,297 DEBUG [storage.image.TemplateDataFactoryImpl]
> > > > (catalina-exec-25:null) template 203 is not in store:1, type:Image
> > > >
> > > > 2013-10-17 19:35:10,315 DEBUG [storage.image.TemplateDataFactoryImpl]
> > > > (catalina-exec-25:null) template 203 is already in store:1,
> type:Image
> > > >
> > > > 2013-10-17 19:35:10,333 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (catalina-exec-25:null) Downloading template to data store 1
> > > >
> > > > 2013-10-17 19:35:10,344 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (catalina-exec-25:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:35:10,346 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (catalina-exec-25:null) Performing image store createTemplate async
> > > > callback
> > > >
> > > > 2013-10-17 19:35:10,382 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-25:null) ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=registerIso&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&name=centos64&displayText=x86_64&url=http%3A%2F%2F192.168.210.26%2Fcentos%2F6.4%2Fisos%2Fx86_64%2FCentOS-6.4-x86_64-bin-DVD1.iso&zoneid=-1&isextractable=true&bootable=true&osTypeId=ec21dd22-3730-11e3-821d-005056ac122f&ispublic=true&isfeatured=true&_=1382018726636
> > > >
> > > > 2013-10-17 19:35:11,224 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-181: Processing Seq 3-181:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:11,230 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-181: Sending Seq 3-181:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:14,542 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) Ping from 3
> > > >
> > > > 2013-10-17 19:35:16,223 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-183: Processing Seq 3-183:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:16,229 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-183: Sending Seq 3-183:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:20,360 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > Executing: sudo mount
> > > >
> > > > 2013-10-17 19:35:20,406 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > Execution is successful.
> > > >
> > > > 2013-10-17 19:35:20,408 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > mount point
> > > > /var/cloudstack/mnt/secStorage/5a2e3ebc-6c94-3641-bc29-599acc77cdac
> > > already
> > > > exists
> > > >
> > > > 2013-10-17 19:35:21,225 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-184: Processing Seq 3-184:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:21,231 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-184: Sending Seq 3-184:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:22,646 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) VmStatsCollector is running...
> > > >
> > > > 2013-10-17 19:35:27,112 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-1:null) StorageCollector is running...
> > > >
> > > > 2013-10-17 19:35:27,120 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (StatsCollector-1:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:35:27,221 DEBUG [agent.transport.Request]
> > > > (StatsCollector-1:null) Seq 1-147587108: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:35:27,493 DEBUG [agent.transport.Request]
> > > > (StatsCollector-1:null) Seq 2-891486253: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:35:31,228 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-10:null) SeqA 3-185: Processing Seq 3-185:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,266 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-10:null) SeqA 3-185: Sending Seq 3-185:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,352 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) HostStatsCollector is running...
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-11:null) Seq 1-147587109: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 1, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 1-147587109: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:35:31,578 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 1 statistics.
> > > >
> > > > 2013-10-17 19:35:31,579 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 1
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-12:null) Seq 2-891486254: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 2, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 2-891486254: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:35:31,799 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 2 statistics.
> > > >
> > > > 2013-10-17 19:35:31,799 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 2
> > > >
> > > > 2013-10-17 19:35:36,192 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-14:null) SeqA 3-186: Processing Seq 3-186:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:36,198 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-14:null) SeqA 3-186: Sending Seq 3-186:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:36,375 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:35:36,551 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:35:37,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:35:37,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:35:37,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:35:37,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:35:40,346 DEBUG [storage.download.DownloadListener]
> > > > (Timer-10:null) Scheduling timeout at 30000 ms, TEMPLATE: 203 at
> host 0
> > > >
> > > > 2013-10-17 19:35:41,233 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-13:null) SeqA 3-187: Processing Seq 3-187:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:41,239 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-13:null) SeqA 3-187: Sending Seq 3-187:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,235 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-188: Processing Seq 3-188:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,242 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-188: Sending Seq 3-188:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,973 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) Ping from 1
> > > >
> > > > 2013-10-17 19:35:54,604 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) Ping from 2
> > > >
> > > > 2013-10-17 19:35:56,237 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) SeqA 3-189: Processing Seq 3-189:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:56,244 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) SeqA 3-189: Sending Seq 3-189:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:01,243 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-190: Processing Seq 3-190:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:01,257 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-190: Sending Seq 3-190:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,242 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-191: Processing Seq 3-191:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,248 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-191: Sending Seq 3-191:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:36:06,548 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:36:07,330 DEBUG
> > > > [cloud.network.ExternalDeviceUsageManagerImpl]
> > > > (ExternalNetworkMonitor-1:null) External devices stats collector is
> > > > running...
> > > >
> > > > 2013-10-17 19:36:07,413 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > (RouterMonitor-1:null)
> > > > Found 0 running routers.
> > > >
> > > > 2013-10-17 19:36:07,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:07,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:07,457 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > (RouterMonitor-1:null)
> > > > Found 0 running routers.
> > > >
> > > > 2013-10-17 19:36:07,459 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:07,461 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:10,346 DEBUG [storage.download.DownloadListener]
> > > > (Timer-10:null) timeout: transitioning to download error state,
> > > > currstate=NOT_DOWNLOADED, TEMPLATE: 203 at host 0
> > > >
> > > > 2013-10-17 19:36:10,347 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (Timer-10:null) Performing image store createTemplate async callback
> > > >
> > > > 2013-10-17 19:36:10,378 WARN  [storage.download.DownloadListener]
> > > > (Timer-10:null) Entering download error state: timeout waiting for
> > > response
> > > > from storage host, TEMPLATE: 203 at host 0
> > > >
> > > > 2013-10-17 19:36:12,518 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Resetting hosts suitable for reconnect
> > > >
> > > > 2013-10-17 19:36:12,521 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed resetting hosts suitable for reconnect
> > > >
> > > > 2013-10-17 19:36:12,521 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Acquiring hosts for clusters already owned by this
> > management
> > > > server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed acquiring hosts for clusters already owned by
> > this
> > > > management server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Acquiring hosts for clusters not owned by any management
> > > server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed acquiring hosts for clusters not owned by any
> > > > management server
> > > >
> > > > 2013-10-17 19:36:14,540 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) Ping from 3
> > > >
> > > > 2013-10-17 19:36:16,244 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-193: Processing Seq 3-193:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:16,250 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-193: Sending Seq 3-193:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:17,529 DEBUG [network.lb.LBHealthCheckManagerImpl]
> > > > (LBHealthCheck-1:null) LB HealthCheck Manager is running and getting
> > the
> > > > updates from LB providers and updating service status
> > > >
> > > > 2013-10-17 19:36:17,551 DEBUG [network.lb.LBHealthCheckManagerImpl]
> > > > (LBHealthCheck-1:null) LB HealthCheck Manager is running and getting
> > the
> > > > updates from LB providers and updating service status
> > > >
> > > > 2013-10-17 19:36:17,598 DEBUG
> [storage.snapshot.SnapshotSchedulerImpl]
> > > > (SnapshotPollTask:null) Snapshot scheduler.poll is being called at
> > > > 2013-10-17 14:06:17 GMT
> > > >
> > > > 2013-10-17 19:36:17,600 DEBUG
> [storage.snapshot.SnapshotSchedulerImpl]
> > > > (SnapshotPollTask:null) Got 0 snapshots to be executed at 2013-10-17
> > > > 14:06:17 GMT
> > > >
> > > > 2013-10-17 19:36:21,248 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-8:null) SeqA 3-194: Processing Seq 3-194:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:21,254 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-8:null) SeqA 3-194: Sending Seq 3-194:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:22,654 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) VmStatsCollector is running...
> > > >
> > > > 2013-10-17 19:36:26,252 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-195: Processing Seq 3-195:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:26,264 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-195: Sending Seq 3-195:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:27,493 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-2:null) StorageCollector is running...
> > > >
> > > > 2013-10-17 19:36:27,501 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (StatsCollector-2:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:36:27,600 DEBUG [agent.transport.Request]
> > > > (StatsCollector-2:null) Seq 1-147587110: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:36:27,864 DEBUG [agent.transport.Request]
> > > > (StatsCollector-2:null) Seq 2-891486255: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:36:31,262 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-12:null) SeqA 3-196: Processing Seq 3-196:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:31,268 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-12:null) SeqA 3-196: Sending Seq 3-196:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:31,800 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) HostStatsCollector is running...
> > > >
> > > > 2013-10-17 19:36:32,023 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-14:null) Seq 1-147587111: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 1, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:32,024 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 1-147587111: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:36:32,024 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:36:32,024 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 1 statistics.
> > > >
> > > > 2013-10-17 19:36:32,025 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 1
> > > >
> > > > 2013-10-17 19:36:32,237 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-13:null) Seq 2-891486256: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 2, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:32,238 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 2-891486256: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:36:32,238 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:36:32,238 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 2 statistics.
> > > >
> > > > 2013-10-17 19:36:32,238 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 2
> > > >
> > > > 2013-10-17 19:36:36,265 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-197: Processing Seq 3-197:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:36,271 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-197: Sending Seq 3-197:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:36,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:36:36,549 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:36:37,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:37,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:37,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:37,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:41,266 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) SeqA 3-198: Processing Seq 3-198:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:41,272 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) SeqA 3-198: Sending Seq 3-198:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:45,506 DEBUG [cloud.api.ApiServlet]
> > > (catalina-exec-1:null)
> > > > ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=self&_=1382018821928
> > > >
> > > > 2013-10-17 19:36:45,530 DEBUG [cloud.api.ApiServlet]
> > > (catalina-exec-1:null)
> > > > ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=self&_=1382018821928
> > > >
> > > > 2013-10-17 19:36:46,268 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) SeqA 3-199: Processing Seq 3-199:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:46,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) SeqA 3-199: Sending Seq 3-199:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:47,060 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) Ping from 1
> > > >
> > > > 2013-10-17 19:36:47,474 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-23:null) ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=all&_=1382018823909
> > > >
> > > > 2013-10-17 19:36:47,507 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-23:null) ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=all&_=1382018823909
> > > >
> > > > 2013-10-17 19:36:47,593 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Checking if any host reservation
> > can
> > > > be released ...
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Running Capacity Checker ...
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) recalculating system capacity
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing cpu/ram capacity update
> > > >
> > > > 2013-10-17 19:36:47,604 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Cannot release reservation,
> Found
> > 2
> > > > VMs Running on host 1
> > > >
> > > > 2013-10-17 19:36:47,605 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Done running
> > > > HostReservationReleaseChecker ...
> > > >
> > > > 2013-10-17 19:36:47,609 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 2 VMs on host 1
> > > >
> > > > 2013-10-17 19:36:47,622 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VM, not running on host 1
> > > >
> > > > 2013-10-17 19:36:47,626 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate cpu capacity, host:1
> > usedCpu:
> > > > 1000 reservedCpu: 0
> > > >
> > > > 2013-10-17 19:36:47,626 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate memory capacity, host:1
> > > > usedMem: 1342177280 reservedMem: 0
> > > >
> > > > 2013-10-17 19:36:47,633 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VMs on host 2
> > > >
> > > > 2013-10-17 19:36:47,640 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VM, not running on host 2
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate cpu capacity, host:2
> > > usedCpu: 0
> > > > reservedCpu: 0
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate memory capacity, host:2
> > > > usedMem: 0 reservedMem: 0
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing cpu/ram capacity update
> > > >
> > > > 2013-10-17 19:36:47,645 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing storage capacity update
> > > >
> > > > 2013-10-17 19:36:47,660 DEBUG [cloud.storage.StorageManagerImpl]
> > > > (CapacityChecker:null) Successfully set Capacity - 526333771776 for
> > > > capacity type - 3 , DataCenterId - 1, HostOrPoolId - 1, PodId null
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.storage.StorageManagerImpl]
> > > > (CapacityChecker:null) Successfully set Capacity - 159972442112 for
> > > > capacity type - 3 , DataCenterId - 1, HostOrPoolId - 2, PodId 1
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing storage capacity update
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing capacity updates for public ip and
> > Vlans
> > > >
> > > > 2013-10-17 19:36:47,678 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done capacity updates for public ip and Vlans
> > > >
> > > > 2013-10-17 19:36:47,679 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing capacity updates for private ip
> > > >
> > > > 2013-10-17 19:36:47,689 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing capacity updates for private ip
> > > >
> > > > 2013-10-17 19:36:47,690 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done recalculating system capacity
> > > >
> > > > 2013-10-17 19:36:47,707 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) System Alert: Number of unallocated private
> IPs
> > is
> > > > low in pod pod-rack1 of availability zone zone-India
> > > >
> > > > 2013-10-17 19:36:47,708 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Number of unallocated private IPs is low,
> total:
> > > > 3.0, allocated: 3.0 (100%)
> > > >
> > > > 2013-10-17 19:36:47,708 WARN  [apache.cloudstack.alerts]
> > > > (CapacityChecker:null)  alertType:: 5 // dataCenterId:: 1 // podId::
> 1
> > //
> > > > clusterId:: null // message:: System Alert: Number of unallocated
> > private
> > > > IPs is low in pod pod-rack1 of availability zone zone-India
> > > >
> > > > 2013-10-17 19:36:47,710 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Have already sent: 1 emails for alert type '5'
> > --
> > > > skipping send email
> > > >
> > > > 2013-10-17 19:36:47,740 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done running Capacity Checker ...
> > > >
> > > > 2013-10-17 19:36:54,617 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) Ping from 2
> > > >
> > > > 2013-10-17 19:36:56,270 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-200: Processing Seq 3-200:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:56,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-200: Sending Seq 3-200:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:01,272 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-201: Processing Seq 3-201:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:01,278 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-201: Sending Seq 3-201:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-202: Processing Seq 3-202:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,280 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-202: Sending Seq 3-202:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:37:06,548 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:37:07,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:37:07,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:37:07,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:37:07,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:35:10,239 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-25:null) ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=registerIso&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&name=centos64&displayText=x86_64&url=http%3A%2F%2F192.168.210.26%2Fcentos%2F6.4%2Fisos%2Fx86_64%2FCentOS-6.4-x86_64-bin-DVD1.iso&zoneid=-1&isextractable=true&bootable=true&osTypeId=ec21dd22-3730-11e3-821d-005056ac122f&ispublic=true&isfeatured=true&_=1382018726636
> > > >
> > > > 2013-10-17 19:35:10,297 DEBUG [storage.image.TemplateDataFactoryImpl]
> > > > (catalina-exec-25:null) template 203 is not in store:1, type:Image
> > > >
> > > > 2013-10-17 19:35:10,315 DEBUG [storage.image.TemplateDataFactoryImpl]
> > > > (catalina-exec-25:null) template 203 is already in store:1,
> type:Image
> > > >
> > > > 2013-10-17 19:35:10,333 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (catalina-exec-25:null) Downloading template to data store 1
> > > >
> > > > 2013-10-17 19:35:10,344 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (catalina-exec-25:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:35:10,346 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (catalina-exec-25:null) Performing image store createTemplate async
> > > > callback
> > > >
> > > > 2013-10-17 19:35:10,382 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-25:null) ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=registerIso&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&name=centos64&displayText=x86_64&url=http%3A%2F%2F192.168.210.26%2Fcentos%2F6.4%2Fisos%2Fx86_64%2FCentOS-6.4-x86_64-bin-DVD1.iso&zoneid=-1&isextractable=true&bootable=true&osTypeId=ec21dd22-3730-11e3-821d-005056ac122f&ispublic=true&isfeatured=true&_=1382018726636
> > > >
> > > > 2013-10-17 19:35:11,224 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-181: Processing Seq 3-181:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:11,230 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-181: Sending Seq 3-181:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:14,542 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) Ping from 3
> > > >
> > > > 2013-10-17 19:35:16,223 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-183: Processing Seq 3-183:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:16,229 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-183: Sending Seq 3-183:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:20,360 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > Executing: sudo mount
> > > >
> > > > 2013-10-17 19:35:20,406 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > Execution is successful.
> > > >
> > > > 2013-10-17 19:35:20,408 DEBUG
> > > > [storage.resource.LocalNfsSecondaryStorageResource]
> > > (pool-42-thread-1:null)
> > > > mount point
> > > > /var/cloudstack/mnt/secStorage/5a2e3ebc-6c94-3641-bc29-599acc77cdac
> > > already
> > > > exists
> > > >
> > > > 2013-10-17 19:35:21,225 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-184: Processing Seq 3-184:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:21,231 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-184: Sending Seq 3-184:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:22,646 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) VmStatsCollector is running...
> > > >
> > > > 2013-10-17 19:35:27,112 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-1:null) StorageCollector is running...
> > > >
> > > > 2013-10-17 19:35:27,120 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (StatsCollector-1:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:35:27,221 DEBUG [agent.transport.Request]
> > > > (StatsCollector-1:null) Seq 1-147587108: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:35:27,493 DEBUG [agent.transport.Request]
> > > > (StatsCollector-1:null) Seq 2-891486253: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:35:31,228 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-10:null) SeqA 3-185: Processing Seq 3-185:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,266 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-10:null) SeqA 3-185: Sending Seq 3-185:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,352 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) HostStatsCollector is running...
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-11:null) Seq 1-147587109: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 1, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 1-147587109: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:35:31,578 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:35:31,578 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 1 statistics.
> > > >
> > > > 2013-10-17 19:35:31,579 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 1
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-12:null) Seq 2-891486254: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 2, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 2-891486254: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:35:31,799 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:35:31,799 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 2 statistics.
> > > >
> > > > 2013-10-17 19:35:31,799 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 2
> > > >
> > > > 2013-10-17 19:35:36,192 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-14:null) SeqA 3-186: Processing Seq 3-186:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:36,198 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-14:null) SeqA 3-186: Sending Seq 3-186:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:36,375 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:35:36,551 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:35:37,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:35:37,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:35:37,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:35:37,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:35:40,346 DEBUG [storage.download.DownloadListener]
> > > > (Timer-10:null) Scheduling timeout at 30000 ms, TEMPLATE: 203 at
> host 0
> > > >
> > > > 2013-10-17 19:35:41,233 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-13:null) SeqA 3-187: Processing Seq 3-187:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:41,239 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-13:null) SeqA 3-187: Sending Seq 3-187:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,235 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-188: Processing Seq 3-188:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,242 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-188: Sending Seq 3-188:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:46,973 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) Ping from 1
> > > >
> > > > 2013-10-17 19:35:54,604 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) Ping from 2
> > > >
> > > > 2013-10-17 19:35:56,237 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) SeqA 3-189: Processing Seq 3-189:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:35:56,244 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) SeqA 3-189: Sending Seq 3-189:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:01,243 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-190: Processing Seq 3-190:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:01,257 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) SeqA 3-190: Sending Seq 3-190:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,242 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-191: Processing Seq 3-191:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,248 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-191: Sending Seq 3-191:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:06,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:36:06,548 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:36:07,330 DEBUG
> > > > [cloud.network.ExternalDeviceUsageManagerImpl]
> > > > (ExternalNetworkMonitor-1:null) External devices stats collector is
> > > > running...
> > > >
> > > > 2013-10-17 19:36:07,413 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > (RouterMonitor-1:null)
> > > > Found 0 running routers.
> > > >
> > > > 2013-10-17 19:36:07,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:07,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:07,457 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > (RouterMonitor-1:null)
> > > > Found 0 running routers.
> > > >
> > > > 2013-10-17 19:36:07,459 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:07,461 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:10,346 DEBUG [storage.download.DownloadListener]
> > > > (Timer-10:null) timeout: transitioning to download error state,
> > > > currstate=NOT_DOWNLOADED, TEMPLATE: 203 at host 0
> > > >
> > > > 2013-10-17 19:36:10,347 DEBUG
> [storage.image.BaseImageStoreDriverImpl]
> > > > (Timer-10:null) Performing image store createTemplate async callback
> > > >
> > > > 2013-10-17 19:36:10,378 WARN  [storage.download.DownloadListener]
> > > > (Timer-10:null) Entering download error state: timeout waiting for
> > > response
> > > > from storage host, TEMPLATE: 203 at host 0
> > > >
> > > > 2013-10-17 19:36:12,518 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Resetting hosts suitable for reconnect
> > > >
> > > > 2013-10-17 19:36:12,521 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed resetting hosts suitable for reconnect
> > > >
> > > > 2013-10-17 19:36:12,521 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Acquiring hosts for clusters already owned by this
> > management
> > > > server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed acquiring hosts for clusters already owned by
> > this
> > > > management server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Acquiring hosts for clusters not owned by any management
> > > server
> > > >
> > > > 2013-10-17 19:36:12,522 DEBUG [host.dao.HostDaoImpl]
> > > (ClusteredAgentManager
> > > > Timer:null) Completed acquiring hosts for clusters not owned by any
> > > > management server
> > > >
> > > > 2013-10-17 19:36:14,540 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) Ping from 3
> > > >
> > > > 2013-10-17 19:36:16,244 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-193: Processing Seq 3-193:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:16,250 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-193: Sending Seq 3-193:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:17,529 DEBUG [network.lb.LBHealthCheckManagerImpl]
> > > > (LBHealthCheck-1:null) LB HealthCheck Manager is running and getting
> > the
> > > > updates from LB providers and updating service status
> > > >
> > > > 2013-10-17 19:36:17,551 DEBUG [network.lb.LBHealthCheckManagerImpl]
> > > > (LBHealthCheck-1:null) LB HealthCheck Manager is running and getting
> > the
> > > > updates from LB providers and updating service status
> > > >
> > > > 2013-10-17 19:36:17,598 DEBUG
> [storage.snapshot.SnapshotSchedulerImpl]
> > > > (SnapshotPollTask:null) Snapshot scheduler.poll is being called at
> > > > 2013-10-17 14:06:17 GMT
> > > >
> > > > 2013-10-17 19:36:17,600 DEBUG
> [storage.snapshot.SnapshotSchedulerImpl]
> > > > (SnapshotPollTask:null) Got 0 snapshots to be executed at 2013-10-17
> > > > 14:06:17 GMT
> > > >
> > > > 2013-10-17 19:36:21,248 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-8:null) SeqA 3-194: Processing Seq 3-194:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:21,254 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-8:null) SeqA 3-194: Sending Seq 3-194:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:22,654 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) VmStatsCollector is running...
> > > >
> > > > 2013-10-17 19:36:26,252 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-195: Processing Seq 3-195:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:26,264 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-195: Sending Seq 3-195:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:27,493 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-2:null) StorageCollector is running...
> > > >
> > > > 2013-10-17 19:36:27,501 INFO
> >  [storage.endpoint.DefaultEndPointSelector]
> > > > (StatsCollector-2:null) No running ssvm is found, so command will be
> > sent
> > > > to LocalHostEndPoint
> > > >
> > > > 2013-10-17 19:36:27,600 DEBUG [agent.transport.Request]
> > > > (StatsCollector-2:null) Seq 1-147587110: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:36:27,864 DEBUG [agent.transport.Request]
> > > > (StatsCollector-2:null) Seq 2-891486255: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > > >
> > > > 2013-10-17 19:36:31,262 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-12:null) SeqA 3-196: Processing Seq 3-196:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:31,268 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-12:null) SeqA 3-196: Sending Seq 3-196:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:31,800 DEBUG [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) HostStatsCollector is running...
> > > >
> > > > 2013-10-17 19:36:32,023 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-14:null) Seq 1-147587111: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 1, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:32,024 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 1-147587111: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 1, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:36:32,024 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:36:32,024 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 1 statistics.
> > > >
> > > > 2013-10-17 19:36:32,025 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 1
> > > >
> > > > 2013-10-17 19:36:32,237 DEBUG [agent.transport.Request]
> > > > (AgentManager-Handler-13:null) Seq 2-891486256: Processing:  { Ans: ,
> > > > MgmtId: 345051501103, via: 2, Ver: v1, Flags: 10,
> > > > [{"com.cloud.agent.api.Answer":{"result":false,"details":"empty
> > > > String","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:32,238 DEBUG [agent.transport.Request]
> > > > (StatsCollector-3:null) Seq 2-891486256: Received:  { Ans: , MgmtId:
> > > > 345051501103, via: 2, Ver: v1, Flags: 10, { Answer } }
> > > >
> > > > 2013-10-17 19:36:32,238 DEBUG [agent.manager.AgentManagerImpl]
> > > > (StatsCollector-3:null) Details from executing class
> > > > com.cloud.agent.api.GetHostStatsCommand: empty String
> > > >
> > > > 2013-10-17 19:36:32,238 WARN  [cloud.resource.ResourceManagerImpl]
> > > > (StatsCollector-3:null) Unable to obtain host 2 statistics.
> > > >
> > > > 2013-10-17 19:36:32,238 WARN  [cloud.server.StatsCollector]
> > > > (StatsCollector-3:null) Received invalid host stats for host: 2
> > > >
> > > > 2013-10-17 19:36:36,265 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-197: Processing Seq 3-197:  {
> > Cmd ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:36,271 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-15:null) SeqA 3-197: Sending Seq 3-197:  {
> Ans: ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:36,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:36:36,549 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:36:37,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:37,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:37,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:36:37,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:36:41,266 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) SeqA 3-198: Processing Seq 3-198:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:41,272 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-1:null) SeqA 3-198: Sending Seq 3-198:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:45,506 DEBUG [cloud.api.ApiServlet]
> > > (catalina-exec-1:null)
> > > > ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=self&_=1382018821928
> > > >
> > > > 2013-10-17 19:36:45,530 DEBUG [cloud.api.ApiServlet]
> > > (catalina-exec-1:null)
> > > > ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=self&_=1382018821928
> > > >
> > > > 2013-10-17 19:36:46,268 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) SeqA 3-199: Processing Seq 3-199:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:46,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-2:null) SeqA 3-199: Sending Seq 3-199:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:47,060 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-3:null) Ping from 1
> > > >
> > > > 2013-10-17 19:36:47,474 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-23:null) ===START===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=all&_=1382018823909
> > > >
> > > > 2013-10-17 19:36:47,507 DEBUG [cloud.api.ApiServlet]
> > > > (catalina-exec-23:null) ===END===  192.168.208.127 -- GET
> > > >
> > > >
> > >
> >
> command=listIsos&response=json&sessionkey=EXQ0WpFeyo7Gq26%2FTOXQJxfVN2E%3D&listAll=true&page=1&pagesize=20&isofilter=all&_=1382018823909
> > > >
> > > > 2013-10-17 19:36:47,593 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Checking if any host reservation
> > can
> > > > be released ...
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Running Capacity Checker ...
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) recalculating system capacity
> > > >
> > > > 2013-10-17 19:36:47,596 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing cpu/ram capacity update
> > > >
> > > > 2013-10-17 19:36:47,604 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Cannot release reservation,
> Found
> > 2
> > > > VMs Running on host 1
> > > >
> > > > 2013-10-17 19:36:47,605 DEBUG
> > > [cloud.deploy.DeploymentPlanningManagerImpl]
> > > > (HostReservationReleaseChecker:null) Done running
> > > > HostReservationReleaseChecker ...
> > > >
> > > > 2013-10-17 19:36:47,609 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 2 VMs on host 1
> > > >
> > > > 2013-10-17 19:36:47,622 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VM, not running on host 1
> > > >
> > > > 2013-10-17 19:36:47,626 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate cpu capacity, host:1
> > usedCpu:
> > > > 1000 reservedCpu: 0
> > > >
> > > > 2013-10-17 19:36:47,626 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate memory capacity, host:1
> > > > usedMem: 1342177280 reservedMem: 0
> > > >
> > > > 2013-10-17 19:36:47,633 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VMs on host 2
> > > >
> > > > 2013-10-17 19:36:47,640 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) Found 0 VM, not running on host 2
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate cpu capacity, host:2
> > > usedCpu: 0
> > > > reservedCpu: 0
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.capacity.CapacityManagerImpl]
> > > > (CapacityChecker:null) No need to calibrate memory capacity, host:2
> > > > usedMem: 0 reservedMem: 0
> > > >
> > > > 2013-10-17 19:36:47,644 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing cpu/ram capacity update
> > > >
> > > > 2013-10-17 19:36:47,645 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing storage capacity update
> > > >
> > > > 2013-10-17 19:36:47,660 DEBUG [cloud.storage.StorageManagerImpl]
> > > > (CapacityChecker:null) Successfully set Capacity - 526333771776 for
> > > > capacity type - 3 , DataCenterId - 1, HostOrPoolId - 1, PodId null
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.storage.StorageManagerImpl]
> > > > (CapacityChecker:null) Successfully set Capacity - 159972442112 for
> > > > capacity type - 3 , DataCenterId - 1, HostOrPoolId - 2, PodId 1
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing storage capacity update
> > > >
> > > > 2013-10-17 19:36:47,667 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing capacity updates for public ip and
> > Vlans
> > > >
> > > > 2013-10-17 19:36:47,678 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done capacity updates for public ip and Vlans
> > > >
> > > > 2013-10-17 19:36:47,679 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Executing capacity updates for private ip
> > > >
> > > > 2013-10-17 19:36:47,689 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done executing capacity updates for private ip
> > > >
> > > > 2013-10-17 19:36:47,690 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done recalculating system capacity
> > > >
> > > > 2013-10-17 19:36:47,707 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) System Alert: Number of unallocated private
> IPs
> > is
> > > > low in pod pod-rack1 of availability zone zone-India
> > > >
> > > > 2013-10-17 19:36:47,708 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Number of unallocated private IPs is low,
> total:
> > > > 3.0, allocated: 3.0 (100%)
> > > >
> > > > 2013-10-17 19:36:47,708 WARN  [apache.cloudstack.alerts]
> > > > (CapacityChecker:null)  alertType:: 5 // dataCenterId:: 1 // podId::
> 1
> > //
> > > > clusterId:: null // message:: System Alert: Number of unallocated
> > private
> > > > IPs is low in pod pod-rack1 of availability zone zone-India
> > > >
> > > > 2013-10-17 19:36:47,710 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Have already sent: 1 emails for alert type '5'
> > --
> > > > skipping send email
> > > >
> > > > 2013-10-17 19:36:47,740 DEBUG [cloud.alert.AlertManagerImpl]
> > > > (CapacityChecker:null) Done running Capacity Checker ...
> > > >
> > > > 2013-10-17 19:36:54,617 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-4:null) Ping from 2
> > > >
> > > > 2013-10-17 19:36:56,270 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-200: Processing Seq 3-200:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:36:56,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-5:null) SeqA 3-200: Sending Seq 3-200:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:01,272 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-201: Processing Seq 3-201:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:01,278 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-6:null) SeqA 3-201: Sending Seq 3-201:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,274 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-202: Processing Seq 3-202:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,280 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-7:null) SeqA 3-202: Sending Seq 3-202:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:06,374 DEBUG
> > > > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Zone
> > > 1
> > > > is ready to launch secondary storage VM
> > > >
> > > > 2013-10-17 19:37:06,548 DEBUG
> > > [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > > > (consoleproxy-1:null) Zone 1 is ready to launch console proxy
> > > >
> > > > 2013-10-17 19:37:07,417 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:37:07,419 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:37:07,458 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 routers to update status.
> > > >
> > > > 2013-10-17 19:37:07,460 DEBUG
> > > > [network.router.VirtualNetworkApplianceManagerImpl]
> > > > (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
> > > >
> > > > 2013-10-17 19:37:14,537 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-8:null) Ping from 3
> > > >
> > > > 2013-10-17 19:37:16,277 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-204: Processing Seq 3-204:  {
> Cmd
> > ,
> > > > MgmtId: -1, via: 3, Ver: v1, Flags: 11,
> > > >
> > > >
> > >
> >
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> > > > \"connections\": []\n}","wait":0}}] }
> > > >
> > > > 2013-10-17 19:37:16,282 DEBUG [agent.manager.AgentManagerImpl]
> > > > (AgentManager-Handler-9:null) SeqA 3-204: Sending Seq 3-204:  { Ans:
> ,
> > > > MgmtId: 345051501103, via: 3, Ver: v1, Flags: 100010,
> > > >
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> > > >
> > > >
> > > > On Thu, Oct 17, 2013 at 5:15 PM, Chirag Choudhary <
> > > chirag200666@gmail.com
> > > > >wrote:
> > > >
> > > > > Hi Raj,
> > > > >    You don't have to configure bridges on the host in the first
> > place,
> > > as
> > > > > you pointed out cloudstack does that, check if the version of
> system
> > > > > template you have added is correct. As pointed out reboot ssvm
> once.
> > > > >
> > > > >       The above log is a warning not an error, I am not sure about
> > the
> > > > > reason as I am getting this warning, but everything else seems to
> > work
> > > > > fine, I added KVM and Xen hosts, created instances. Can you post
> > > detailed
> > > > > logs from management server , cloud agent logs and the ssvm logs.
> > > > >
> > > > > Regards,
> > > > >
> > > > >
> > > > > On Thu, Oct 17, 2013 at 3:05 PM, raj kumar <
> rajkumar600003@gmail.com
> > >
> > > > > wrote:
> > > > >
> > > > > > configured manually link-local ip listed in the web in ssvm with
> > > > gateway
> > > > > > ip(hypervisor link local ip)
> > > > > > stopped iptables in both hypervisor and ssvm. I can able to ssh.
> if
> > > > > > iptables is running i can't ssh.
> > > > > > tried adding iso. but not successful.
> > > > > >
> > > > > > log shows
> > > > > > [storage.endpoint.DefaultEndPointSelector]
> (StatsCollector-2:null)
> > No
> > > > > > running ssvm is found, so command will be sent to
> > LocalHostEndPoint.
> > > > > >
> > > > > >
> > > > > > On Thu, Oct 17, 2013 at 12:03 PM, raj kumar <
> > > rajkumar600003@gmail.com
> > > > > > >wrote:
> > > > > >
> > > > > > > Thank you all for your responses.
> > > > > > >
> > > > > > > After refering logs and logging into console through virsh,
> seen
> > > that
> > > > > > > there is no link-local ip even though the link ip address is
> > listed
> > > > in
> > > > > > web
> > > > > > > console. there is only 127.0.0.1 configure in ssvm.  So where
> i'm
> > > > > > missing.
> > > > > > >
> > > > > > > I'm using basic network, no vlans. only one subnet. Also pls
> > note I
> > > > > > > skipped totally "8.1.7.2. Configuring the network bridges" from
> > the
> > > > > > > installation guide for kvm hypervisor. But cloudstack itself
> has
> > > > > created
> > > > > > > cloud0, cloudbr0 and vnet interfaces.
> > > > > > >
> > > > > > > -Raj
> > > > > > >
> > > > > > >
> > > > > > > On Thu, Oct 17, 2013 at 12:48 AM, Nitin Mehta <
> > > > Nitin.Mehta@citrix.com
> > > > > > >wrote:
> > > > > > >
> > > > > > >> Did you reboot the SSVM as well ? You might have to do this to
> > > take
> > > > > into
> > > > > > >> affect. Let me know if that resolves it for you.
> > > > > > >>
> > > > > > >> On 16/10/13 6:22 AM, "raj kumar" <rajkumar600003@gmail.com>
> > > wrote:
> > > > > > >>
> > > > > > >> >Hi,
> > > > > > >> >
> > > > > > >> >while registering iso, registration is successful, but it is
> > not
> > > > > ready
> > > > > > >> for
> > > > > > >> >use. after 20 to 30sec, it vanishes.  I've added the http
> > server
> > > in
> > > > > > >> global
> > > > > > >> >settings secstorage.allowed.internal.sites. still no luck.
> > > > > > >> >
> > > > > > >> >both secodary and console system vms are running.
> > > > > > >>
> > > > > > >>
> > > > > > >
> > > > > >
> > > > >
> > > > >
> > > > >
> > > > > --
> > > > > Chirag Choudhary,
> > > > > Software Engineer
> > > > >
> > > >
> > >
> > >
> > >
> > > --
> > > Chirag Choudhary,
> > > Software Engineer
> > >
> >
>
>
>
> --
> Chirag Choudhary,
> Software Engineer
>

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message