cloudstack-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Chirag Choudhary <chirag200...@gmail.com>
Subject Re: could not register iso
Date Fri, 18 Oct 2013 12:10:16 GMT
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