Mailing-List: contact users-help@cloudstack.apache.org; run by ezmlm
Precedence: bulk
Reply-To: users@cloudstack.apache.org
From: Keyur Bhalerao <keyur_bhalerao@persistent.com>
To: "users@cloudstack.apache.org" <users@cloudstack.apache.org>
Subject: RE: DB Exception while adding Host on Cloud stack 4.6 fresh
 installation (Ubuntu 15.04)
Thread-Topic: DB Exception while adding Host on Cloud stack 4.6 fresh
 installation (Ubuntu 15.04)
Thread-Index: AdFdKFGTngKfwzGDTkacZfkohvZBzQAIvvcQ
Date: Mon, 1 Feb 2016 23:55:15 +0000
Message-ID: 
 <SIXPR04MB0761763517445C0BB0626D1A81DE0@SIXPR04MB0761.apcprd04.prod.outlook.com>
References: 
 <SIXPR04MB076198E62B36C9C2CFEE54F181DE0@SIXPR04MB0761.apcprd04.prod.outlook.com>
In-Reply-To: 
 <SIXPR04MB076198E62B36C9C2CFEE54F181DE0@SIXPR04MB0761.apcprd04.prod.outlook.com>
Accept-Language: en-US
Content-Language: en-US
spamdiagnosticoutput: 1:23
spamdiagnosticmetadata: NSPM
Content-Type: text/plain; charset="us-ascii"
content-transfer-encoding: quoted-printable
MIME-Version: 1.0
X-MS-Exchange-CrossTenant-originalarrivaltime: 01 Feb 2016 23:55:15.4604
 (UTC)
X-MS-Exchange-CrossTenant-fromentityheader: Hosted
X-MS-Exchange-CrossTenant-id: 1f4beacd-b7aa-49b2-aaa1-b8525cb257e0
X-MS-Exchange-Transport-CrossTenantHeadersStamped: SIXPR04MB0761
X-OriginatorOrg: Persistent.com
X-Brightmail-Tracker: 
 H4sIAAAAAAAAA+NgFupjk+LIzCtJLcpLzFFi42Lh0vnjqJv7YX2YwZLd1hYvJn5lc2D02Nfc
	yRTAGNXAaJOYl5dfkliSqpCSWpxsq+STmp6Yo+uSWZyck5iZm1qkpJCZYqtkpKRQkJOYnJqb
	mldiq5RYUJCal6Jkx6WAAWyAyjLzFFLzkvNTMvPSbZU8g/11LSxMLXUNlexcPIOdfRw9fV2D
	1JQNja25bIEgYSdrxou+VtaCc5sYKzZM+M/YwHh2PmMXIyeHhICJxNebc6BsMYkL99azdTFy
	cQgJbGGS6F63lxHCOcQoMW1WO1iGRWA2s8S9xsfsEJmrjBJHpl2AKjvKKHF2xRFmkGFsQIMn
	vTrHDmKLCNhLNN/9yAJiCwskSWxsf8EEEU+WaLjxkxXCNpJourQBLM4ioCKxfc8XMJtXIEZi
	yo9PYAcKAdkHPm0Fm8kpECvx/PBpNhCbEejw76fWgNUzC4hL3HoynwniIQGJJXvOM0PYohIv
	H/9jBTmUUWAlo8T+VcfZIRKKEhOu3gT7QEJgD5PE9L3bWSASbhKHZ02Hsn0lru38BNWQLfFh
	8xKoqVoSHUdmMUE0r2eU+HhzEdRqGYmLl8+wQCSeskrsOLsT6n8picY9K6FsGYkXd/ayTmDU
	nIXkdAhbR2LB7k9sELa2xLKFr5lngYNDUOLkzCcsCxhZVjFKZ2TpFueWFOhm5ukVpBYVZxaX
	AJOOXnK+XmbeJkZQqtTlENjBuGCd2yFGAQ5GJR5ehzfrw4RYE8uKK3OBUcrBrCTCu/I9UIg3
	JbGyKrUoP76oNCe1+BCjNjAOJjJLcSfn54GMjDc2MFAS5+28tzJMSCAdmI6zU1OBFsJUM3Fw
	SjUw9mjFPu5pDOKa18C9abde8seL7Gv/y0dltupICb7ZaHFsnegdnxULquqYRVfz19133xj4
	+Y7Tnx0Oys9bE/ollvo9Cr1r5eu/IOKJ4TfJhBlnRNZ+1rN+mvDjd8RRm71OHpvmHD5cML2q
	1j3u4jsR7qfN/b6nG6bmLlrj5mJlPO2cuk+51dS7SizFGYmGWsxFxYkA+zG00KEDAAA=

Some things which are observed in the log while troubleshooting this issue -=
 

1. There some errors found in cloudstack-agent in setup.log

Feb 01 15:42:50 cstest-virtual-machine sudo[2772]: root : TTY=3Dunknown ; PW=
D=3D/ ; USER=3Droot ; COMMAND=3D/bin/grep InitiatorName=3D /etc/iscsi/initia=
torname.iscsi
Feb 01 15:42:50 cstest-virtual-machine sudo[2772]: pam_unix(sudo:session): s=
ession opened for user root by (uid=3D0)
Feb 01 15:42:50 cstest-virtual-machine sudo[2772]: pam_unix(sudo:session): s=
ession closed for user root
Feb 01 15:42:52 cstest-virtual-machine jsvc.exec[1348]: libvirt: Storage Dri=
ver error : Storage pool not found: no storage pool with matching uuid '654e=
ecc6-a2fd-468f-a725-6d41e9a6b875'
Feb 01 15:42:52 cstest-virtual-machine jsvc.exec[1348]: 2016-02-01 15:42:52=
 1341 jsvc.exec error: Service exit with a return value of 1
Feb 01 15:43:00 cstest-virtual-machine systemd[1]: Stopping LSB: Start/stop=
 Apache CloudStack Agent...
Feb 01 15:43:00 cstest-virtual-machine cloudstack-agent[2809]: * Stopping Cl=
oudStack Agent cloudstack-agent
Feb 01 15:43:00 cstest-virtual-machine cloudstack-agent[2809]: ...done.
Feb 01 15:43:00 cstest-virtual-machine systemd[1]: Stopped LSB: Start/stop A=
pache CloudStack Agent.
Feb 01 15:46:07 cstest-virtual-machine systemd[1]: Stopped LSB: Start/stop A=
pache CloudStack Agent.


2. Tried to create storage pool and it's also giving following error - 

2016-02-01 15:48:42,329 DEBUG [o.s.b.f.a.AutowiredAnnotationBeanPostProcesso=
r] (catalina-exec-18:ctx-57983598 ctx-9c5f1bc3) Autowiring by type from bean=
 name 'org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl' to bean=
 named 'volumeDaoImpl'
2016-02-01 15:48:42,331 DEBUG [o.a.c.s.d.l.CloudStackPrimaryDataStoreLifeCyc=
leImpl] (catalina-exec-18:ctx-57983598 ctx-9c5f1bc3) In createPool. Attachin=
g the pool to each of the hosts.
2016-02-01 15:48:42,331 WARN  [o.a.c.s.d.l.CloudStackPrimaryDataStoreLifeCyc=
leImpl] (catalina-exec-18:ctx-57983598 ctx-9c5f1bc3) No host can access stor=
age pool org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@117bf3=
f8 in this zone.
2016-02-01 15:48:42,389 DEBUG [c.c.s.StorageManagerImpl] (catalina-exec-18:c=
tx-57983598 ctx-9c5f1bc3) Failed to add data store: Failed to create storage=
 pool as it is not accessible to hosts.
com.cloud.utils.exception.CloudRuntimeException: Failed to create storage po=
ol as it is not accessible to hosts.
        at org.apache.cloudstack.storage.datastore.lifecycle.CloudStackPrima=
ryDataStoreLifeCycleImpl.attachZone(CloudStackPrimaryDataStoreLifeCycleImpl.=
java:453)
        at com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImp=
l.java:705)
        at com.cloud.storage.StorageManagerImpl.createPool(StorageManagerImp=
l.java:193)
        at org.apache.cloudstack.api.command.admin.storage.CreateStoragePool=
Cmd.execute(CreateStoragePoolCmd.java:163)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
        at com.cloud.api.ApiServer.queueCommand(ApiServer.java:703)
        at com.cloud.api.ApiServer.handleRequest(ApiServer.java:528)
        at com.cloud.api.ApiServlet.processRequestInContext(ApiServlet.java:=
296)
        at com.cloud.api.ApiServlet$1.run(ApiServlet.java:127)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$=
1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.=
callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.=
runWithContext(DefaultManagedContext.java:53)
        at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:124)
        at com.cloud.api.ApiServlet.doGet(ApiServlet.java:86)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:723)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(=
ApplicationFilterChain.java:290)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(Applicat=
ionFilterChain.java:206)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrap=
perValve.java:233)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardCont=
extValve.java:191)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostVal=
ve.java:127)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportVal=
ve.java:103)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.j=
ava:615)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngin=
eValve.java:109)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter=
.java:293)
        at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProc=
essor.java:891)
        at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandle=
r.process(Http11NioProtocol.java:750)
        at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEnd=
point.java:2283)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecu=
tor.java:1145)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExec=
utor.java:615)
        at java.lang.Thread.run(Thread.java:745)
2016-02-01 15:48:42,395 DEBUG [c.c.s.StorageManagerImpl] (catalina-exec-18:c=
tx-57983598 ctx-9c5f1bc3) Failed to clean up storage pool: null
2016-02-01 15:48:42,395 INFO  [c.c.a.ApiServer] (catalina-exec-18:ctx-579835=
98 ctx-9c5f1bc3) Failed to add data store: Failed to create storage pool as=
 it is not accessible to hosts.

I have cross checked the storage from libvirt and its active. 

root@cstest-virtual-machine:/var/log/cloudstack/agent# virsh
Welcome to virsh, the virtualization interactive terminal.

Type:  'help' for help with commands
       'quit' to quit

virsh # pool-list
 Name                 State      Autostart
-------------------------------------------
 654eecc6-a2fd-468f-a725-6d41e9a6b875 active     no

virsh # exit

Thanks,
Keyur Bhalerao
-----Original Message-----
From: Keyur Bhalerao [mailto:keyur_bhalerao@persistent.com] 
Sent: Monday, February 01, 2016 11:40 AM
To: users@cloudstack.apache.org
Subject: DB Exception while adding Host on Cloud stack 4.6 fresh installatio=
n (Ubuntu 15.04)

Hi ,

I have installed Cloud stack version 4.6 to be more precise  - {"listcapabil=
itiesresponse":{"capability":{"securitygroupsenabled":false,"cloudstackversi=
on":"4.6.2","userpublictemplateenabled":true,"supportELB":"false","projectin=
viterequired":false,"allowusercreateprojects":true,"customdiskofferingminsiz=
e":1,"customdiskofferingmaxsize":1024,"regionsecondaryenabled":false,"kvmsna=
pshotenabled":false,"allowuserviewdestroyedvm":true,"allowuserexpungerecover=
vm":true}}}

On Ubuntu 15.04 with following KVM details -

Compiled against library: libvirt 1.2.12 Using library: libvirt 1.2.12 Using=
 API: QEMU 1.2.12 Running hypervisor: QEMU 2.2.0

It's a single VM installation where storage and management server are on sam=
e VM . I have followed all the required steps from the documentation provide=
d in - http://www.shapeblue.com/deploying-cloudstack-with-kvm/
I am able to successfully login management console and create ZONE , POD , C=
LUSTER successfully. Its failing while adding the host . Here are the log de=
tails (Log level changed to TRACE). Cloud stack- Agent dies after we try to=
 add host from the console. It says active (exited)

Management Logs :

2016-02-01 11:14:15,761 TRACE [c.c.u.d.T.Connection] (AgentConnectTaskPool-1=
:ctx-370d8502) Creating a DB connection with  txn:  for 0: dbconn78420063. S=
tack: -TransactionLegacy.prepareAutoCloseStatement:477-GenericDaoBase.persis=
t:1368-HostDaoImpl.persist:797-HostDaoImpl.persist:69-NativeMethodAccessorIm=
pl.invoke0:-2-NativeMethodAccessorImpl.invoke:57-DelegatingMethodAccessorImp=
l.invoke:43-Method.invoke:606-AopUtils.invokeJoinpointUsingReflection:317-Re=
flectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proc=
eed:150-TransactionContextInterceptor.invoke:34
2016-02-01 11:14:15,769 TRACE [c.c.u.d.T.Statement] (AgentConnectTaskPool-1:=
ctx-370d8502) Preparing: INSERT INTO host (host.disconnected, host.name, hos=
t.status, host.type, host.private_ip_address, host.private_mac_address, host=
.private_netmask, host.public_netmask, host.public_ip_address, host.public_m=
ac_address, host.storage_ip_address, host.cluster_id, host.storage_netmask,=
 host.storage_mac_address, host.storage_ip_address_2, host.storage_netmask_2=
, host.storage_mac_address_2, host.hypervisor_type, host.proxy_port, host.re=
source, host.fs_type, host.available, host.setup, host.resource_state, host.=
hypervisor_version, host.update_count, host.uuid, host.data_center_id, host.=
pod_id, host.cpu_sockets, host.cpus, host.url, host.speed, host.ram, host.pa=
rent, host.guid, host.capabilities, host.total_size, host.last_ping, host.mg=
mt_server_id, host.dom0_memory, host.version, host.created) VALUES (?, ?, ?,=
 ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?,=
 ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
2016-02-01 11:14:15,837 TRACE [c.c.u.d.T.Statement] (AgentConnectTaskPool-1:=
ctx-370d8502) Closing: com.mysql.jdbc.JDBC4PreparedStatement@5f47875e: INSER=
T INTO host (host.disconnected, host.name, host.status, host.type, host.priv=
ate_ip_address, host.private_mac_address, host.private_netmask, host.public_=
netmask, host.public_ip_address, host.public_mac_address, host.storage_ip_ad=
dress, host.cluster_id, host.storage_netmask, host.storage_mac_address, host=
.storage_ip_address_2, host.storage_netmask_2, host.storage_mac_address_2, h=
ost.hypervisor_type, host.proxy_port, host.resource, host.fs_type, host.avai=
lable, host.setup, host.resource_state, host.hypervisor_version, host.update=
_count, host.uuid, host.data_center_id, host.pod_id, host.cpu_sockets, host.=
cpus, host.url, host.speed, host.ram, host.parent, host.guid, host.capabilit=
ies, host.total_size, host.last_ping, host.mgmt_server_id, host.dom0_memory,=
 host.version, host.created) VALUES (null, _binary'cstest-virtual-machine',=
 'Creating', 'Routing', _binary'10.25.1.89', _binary'00:50:56:b3:1f:88', _bi=
nary'255.255.252.0', _binary'255.255.252.0', _binary'10.25.1.89', _binary'00=
:50:56:b3:1f:88', _binary'10.25.1.89', 2, _binary'255.255.252.0', _binary'00=
:50:56:b3:1f:88', null, null, null, 'KVM', null, null, null, 1, 0, 'Creating=
', null, 0, _binary'ffc9fd4c-4778-427d-95ba-e17293bb145e', 2, 2, 1, 1, null,=
 2393, -32403456, null, _binary'6f373eb2-fb89-31bf-85d7-ace20f4e5471-Libvirt=
ComputingResource', _binary'hvm,snapshot', null, 1420267632, 20696084691758,=
 0, _binary'4.6.2', '2016-02-01 19:14:15')
2016-02-01 11:14:15,837 TRACE [c.c.u.d.T.Connection] (AgentConnectTaskPool-1=
:ctx-370d8502) txn: Not closing DB connection because we're still in a trans=
action.
2016-02-01 11:14:15,837 DEBUG [c.c.u.d.T.Transaction] (AgentConnectTaskPool-=
1:ctx-370d8502) Rolling back the transaction: Time =3D 77 Name =3D  AgentCon=
nectTaskPool-1; called by -TransactionLegacy.rollback:879-TransactionLegacy.=
removeUpTo:822-TransactionLegacy.close:646-TransactionContextInterceptor.inv=
oke:36-ReflectiveMethodInvocation.proceed:161-ExposeInvocationInterceptor.in=
voke:91-ReflectiveMethodInvocation.proceed:172-JdkDynamicAopProxy.invoke:204=
-$Proxy59.persist:-1-ResourceManagerImpl.createHostVO:1701-ResourceManagerIm=
pl.createHostVOForConnectedAgent:1952-NativeMethodAccessorImpl.invoke0:-2
2016-02-01 11:14:15,838 TRACE [c.c.u.d.T.Connection] (AgentConnectTaskPool-1=
:ctx-370d8502) Closing DB connection: dbconn78420063
2016-02-01 11:14:15,839 DEBUG [c.c.a.m.AgentManagerImpl] (AgentConnectTaskPo=
ol-1:ctx-370d8502) Failed to handle host connection: com.cloud.utils.excepti=
on.CloudRuntimeException: DB Exception on: com.mysql.jdbc.JDBC4PreparedState=
ment@5f47875e: INSERT INTO host (host.disconnected, host.name, host.status,=
 host.type, host.private_ip_address, host.private_mac_address, host.private_=
netmask, host.public_netmask, host.public_ip_address, host.public_mac_addres=
s, host.storage_ip_address, host.cluster_id, host.storage_netmask, host.stor=
age_mac_address, host.storage_ip_address_2, host.storage_netmask_2, host.sto=
rage_mac_address_2, host.hypervisor_type, host.proxy_port, host.resource, ho=
st.fs_type, host.available, host.setup, host.resource_state, host.hypervisor=
_version, host.update_count, host.uuid, host.data_center_id, host.pod_id, ho=
st.cpu_sockets, host.cpus, host.url, host.speed, host.ram, host.parent, host=
.guid, host.capabilities, host.total_size, host.last_ping, host.mgmt_server_=
id, host.dom0_memory, host.version, host.created) VALUES (null, _binary'cste=
st-virtual-machine', 'Creating', 'Routing', _binary'10.25.1.89', _binary'00:=
50:56:b3:1f:88', _binary'255.255.252.0', _binary'255.255.252.0', _binary'10.=
25.1.89', _binary'00:50:56:b3:1f:88', _binary'10.25.1.89', 2, _binary'255.25=
5.252.0', _binary'00:50:56:b3:1f:88', null, null, null, 'KVM', null, null, n=
ull, 1, 0, 'Creating', null, 0, _binary'ffc9fd4c-4778-427d-95ba-e17293bb145e=
', 2, 2, 1, 1, null, 2393, -32403456, null, _binary'6f373eb2-fb89-31bf-85d7-=
ace20f4e5471-LibvirtComputingResource', _binary'hvm,snapshot', null, 1420267=
632, 20696084691758, 0, _binary'4.6.2', '2016-02-01 19:14:15')
2016-02-01 11:14:15,850 TRACE [c.c.u.n.Link] (AgentConnectTaskPool-1:ctx-370=
d8502) Sending packet of length 1837


Agent Logs :
2016-02-01 11:14:14,930 INFO  [kvm.storage.LibvirtStorageAdaptor] (Agent-Han=
dler-1:null) Trying to fetch storage pool 654eecc6-a2fd-468f-a725-6d41e9a6b8=
75 from libvirt
2016-02-01 11:14:14,930 DEBUG [kvm.resource.LibvirtConnection] (Agent-Handle=
r-1:null) Looking for libvirtd connection at: qemu:///system
2016-02-01 11:14:14,955 DEBUG [kvm.storage.LibvirtStorageAdaptor] (Agent-Han=
dler-1:null) Succesfully refreshed pool 654eecc6-a2fd-468f-a725-6d41e9a6b875=
 Capacity: 210186788864 Used: 5995126784 Available: 204191662080
2016-02-01 11:14:14,959 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Exe=
cuting: hostname
2016-02-01 11:14:14,961 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Exe=
cution is successful.
2016-02-01 11:14:14,961 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Exe=
cuting: hostname
2016-02-01 11:14:14,962 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Exe=
cution is successful.
2016-02-01 11:14:15,116 INFO  [cloud.serializer.GsonHelper] (Agent-Handler-1=
:null) Default Builder inited.
2016-02-01 11:14:15,203 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Sen=
ding Startup: Seq 0-0:  { Cmd , MgmtId: -1, via: 0, Ver: v1, Flags: 1, [{"co=
m.cloud.agent.api.StartupRoutingCommand":{"cpuSockets":1,"cpus":1,"speed":23=
93,"memory":-32403456,"dom0MinMemory":1073741824,"poolSync":false,"caps":"hv=
m,snapshot","pool":"/root","hypervisorType":"KVM","hostDetails":{"com.cloud.=
network.Networks.RouterPrivateIpStrategy":"HostLocal","Host.OS":"Ubuntu","Ho=
st.OS.Kernel.Version":"3.19.0-15-generic","Host.OS.Version":"15.04"},"hostTa=
gs":[],"groupDetails":{},"type":"Routing","dataCenter":"2","pod":"2","cluste=
r":"2","guid":"6f373eb2-fb89-31bf-85d7-ace20f4e5471-LibvirtComputingResource=
","name":"cstest-virtual-machine","id":0,"version":"4.6.2","publicIpAddress"=
:"10.25.1.89","publicNetmask":"255.255.252.0","publicMacAddress":"00:50:56:b=
3:1f:88","privateIpAddress":"10.25.1.89","privateMacAddress":"00:50:56:b3:1f=
:88","privateNetmask":"255.255.252.0","storageIpAddress":"10.25.1.89","stora=
geNetmask":"255.255.252.0","storageMacAddress":"00:50:56:b3:1f:88","resource=
Name":"LibvirtComputingResource","gatewayIpAddress":"10.25.0.1","wait":0}},{=
"com.cloud.agent.api.StartupStorageCommand":{"totalSize":0,"poolInfo":{"uuid=
":"654eecc6-a2fd-468f-a725-6d41e9a6b875","host":"10.25.1.89","localPath":"/v=
ar/lib/libvirt/images","hostPath":"/var/lib/libvirt/images","poolType":"File=
system","capacityBytes":210186788864,"availableBytes":204191662080},"resourc=
eType":"STORAGE_POOL","hostDetails":{},"type":"Storage","dataCenter":"2","po=
d":"2","guid":"6f373eb2-fb89-31bf-85d7-ace20f4e5471-LibvirtComputingResource=
","name":"cstest-virtual-machine","id":0,"version":"4.6.2","resourceName":"L=
ibvirtComputingResource","wait":0}}] }
2016-02-01 11:14:15,203 DEBUG [cloud.agent.Agent] (Agent-Handler-1:null) Sta=
rtup task created
2016-02-01 11:14:15,396 DEBUG [cloud.agent.Agent] (Agent-Handler-2:null) Rec=
eived response: Seq 0-0:  { Ans: , MgmtId: 20696084691758, via: -1, Ver: v1,=
 Flags: 100000, [{"com.cloud.agent.api.StartupAnswer":{"hostId":0,"pingInter=
val":60,"result":true,"wait":0}}] }
2016-02-01 11:14:15,397 DEBUG [cloud.agent.Agent] (Agent-Handler-2:null) Sta=
rtup task cancelled
2016-02-01 11:14:15,397 INFO  [cloud.agent.Agent] (Agent-Handler-2:null) Pro=
ccess agent startup answer, agent id =3D 0
2016-02-01 11:14:15,397 INFO  [cloud.agent.Agent] (Agent-Handler-2:null) Set=
 agent id 0
2016-02-01 11:14:15,422 DEBUG [cloud.agent.Agent] (Agent-Handler-2:null) Add=
ing a watch list
2016-02-01 11:14:15,423 INFO  [cloud.agent.Agent] (Agent-Handler-2:null) Sta=
rtup Response Received: agent id =3D 0
2016-02-01 11:14:15,423 DEBUG [kvm.resource.LibvirtComputingResource] (Ugent=
Task-1:null) Executing: /usr/share/cloudstack-common/scripts/vm/network/secu=
rity_group.py get_rule_logs_for_vms
2016-02-01 11:14:16,024 DEBUG [cloud.agent.Agent] (agentRequest-Handler-1:nu=
ll) Request:Seq -1--1:  { Cmd , MgmtId: -1, via: -1, Ver: v1, Flags: 111, [{=
"com.cloud.agent.api.ReadyCommand":{"_details":"com.cloud.utils.exception.Cl=
oudRuntimeException: DB Exception on: com.mysql.jdbc.JDBC4PreparedStatement@=
5f47875e: INSERT INTO host (host.disconnected, host.name, host.status, host.=
type, host.private_ip_address, host.private_mac_address, host.private_netmas=
k, host.public_netmask, host.public_ip_address, host.public_mac_address, hos=
t.storage_ip_address, host.cluster_id, host.storage_netmask, host.storage_ma=
c_address, host.storage_ip_address_2, host.storage_netmask_2, host.storage_m=
ac_address_2, host.hypervisor_type, host.proxy_port, host.resource, host.fs_=
type, host.available, host.setup, host.resource_state, host.hypervisor_versi=
on, host.update_count, host.uuid, host.data_center_id, host.pod_id, host.cpu=
_sockets, host.cpus, host.url, host.speed, host.ram, host.parent, host.guid,=
 host.capabilities, host.total_size, host.last_ping, host.mgmt_server_id, ho=
st.dom0_memory, host.version, host.created) VALUES (null, _binary'cstest-vir=
tual-machine', 'Creating', 'Routing', _binary'10.25.1.89', _binary'00:50:56:=
b3:1f:88', _binary'255.255.252.0', _binary'255.255.252.0', _binary'10.25.1.8=
9', _binary'00:50:56:b3:1f:88', _binary'10.25.1.89', 2, _binary'255.255.252.=
0', _binary'00:50:56:b3:1f:88', null, null, null, 'KVM', null, null, null, 1=
, 0, 'Creating', null, 0, _binary'ffc9fd4c-4778-427d-95ba-e17293bb145e', 2,=
 2, 1, 1, null, 2393, -32403456, null, _binary'6f373eb2-fb89-31bf-85d7-ace20=
f4e5471-LibvirtComputingResource', _binary'hvm,snapshot', null, 1420267632,=
 20696084691758, 0, _binary'4.6.2', '2016-02-01 19:14:15')","wait":0}}] }
2016-02-01 11:14:16,028 DEBUG [cloud.agent.Agent] (agentRequest-Handler-1:nu=
ll) Processing command: com.cloud.agent.api.ReadyCommand
2016-02-01 11:14:16,028 DEBUG [cloud.agent.Agent] (agentRequest-Handler-1:nu=
ll) Not ready to connect to mgt server: com.cloud.utils.exception.CloudRunti=
meException: DB Exception on: com.mysql.jdbc.JDBC4PreparedStatement@5f47875e=
: INSERT INTO host (host.disconnected, host.name, host.status, host.type, ho=
st.private_ip_address, host.private_mac_address, host.private_netmask, host.=
public_netmask, host.public_ip_address, host.public_mac_address, host.storag=
e_ip_address, host.cluster_id, host.storage_netmask, host.storage_mac_addres=
s, host.storage_ip_address_2, host.storage_netmask_2, host.storage_mac_addre=
ss_2, host.hypervisor_type, host.proxy_port, host.resource, host.fs_type, ho=
st.available, host.setup, host.resource_state, host.hypervisor_version, host=
.update_count, host.uuid, host.data_center_id, host.pod_id, host.cpu_sockets=
, host.cpus, host.url, host.speed, host.ram, host.parent, host.guid, host.ca=
pabilities, host.total_size, host.last_ping, host.mgmt_server_id, host.dom0_=
memory, host.version, host.created) VALUES (null, _binary'cstest-virtual-mac=
hine', 'Creating', 'Routing', _binary'10.25.1.89', _binary'00:50:56:b3:1f:88=
', _binary'255.255.252.0', _binary'255.255.252.0', _binary'10.25.1.89', _bin=
ary'00:50:56:b3:1f:88', _binary'10.25.1.89', 2, _binary'255.255.252.0', _bin=
ary'00:50:56:b3:1f:88', null, null, null, 'KVM', null, null, null, 1, 0, 'Cr=
eating', null, 0, _binary'ffc9fd4c-4778-427d-95ba-e17293bb145e', 2, 2, 1, 1,=
 null, 2393, -32403456, null, _binary'6f373eb2-fb89-31bf-85d7-ace20f4e5471-L=
ibvirtComputingResource', _binary'hvm,snapshot', null, 1420267632, 206960846=
91758, 0, _binary'4.6.2', '2016-02-01 19:14:15')
2016-02-01 11:14:16,030 DEBUG [kvm.resource.LibvirtComputingResource] (Ugent=
Task-1:null) Execution is successful.
2016-02-01 11:14:16,036 DEBUG [kvm.resource.LibvirtConnection] (UgentTask-1:=
null) Looking for libvirtd connection at: qemu:///system
2016-02-01 11:14:16,039 DEBUG [cloud.agent.Agent] (UgentTask-1:null) Sending=
 ping: Seq 0-1:  { Cmd , MgmtId: -1, via: 0, Ver: v1, Flags: 11, [{"com.clou=
d.agent.api.PingRoutingWithNwGroupsCommand":{"newGroupStates":{},"_hostVmSta=
teReport":{},"_gatewayAccessible":true,"_vnetAccessible":true,"hostType":"Ro=
uting","hostId":0,"wait":0}}] }
2016-02-01 11:14:16,042 INFO  [cloud.agent.Agent] (AgentShutdownThread:null)=
 Stopping the agent: Reason =3D sig.kill
2016-02-01 11:14:16,043 DEBUG [cloud.agent.Agent] (AgentShutdownThread:null)=
 Sending shutdown to management server


Any pointers or help would be much appreciated.

Thanks,
Keyur Bhalerao

DISCLAIMER
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
This e-mail may contain privileged and confidential information which is the=
 property of Persistent Systems Ltd. It is intended only for the use of the=
 individual or entity to which it is addressed. If you are not the intended=
 recipient, you are not authorized to read, retain, copy, print, distribute=
 or use this message. If you have received this communication in error, plea=
se notify the sender and delete all copies of this message. Persistent Syste=
ms Ltd. does not accept any liability for virus infected mails.


DISCLAIMER
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
This e-mail may contain privileged and confidential information which is the=
 property of Persistent Systems Ltd. It is intended only for the use of the=
 individual or entity to which it is addressed. If you are not the intended=
 recipient, you are not authorized to read, retain, copy, print, distribute=
 or use this message. If you have received this communication in error, plea=
se notify the sender and delete all copies of this message. Persistent Syste=
ms Ltd. does not accept any liability for virus infected mails.