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 Thu, 17 Oct 2013 14:22:35 GMT
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
>

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