Hi Erik,
the attached file is my log,
virtual router started, I can ping anywhere from virtual router; System VMs
are up as well, except I can't build any instances.
On Sat, Aug 9, 2014 at 3:44 AM, Erik Weber <terbol...@gmail.com> wrote:
> Yes, paste your log :-)
>
> Erik
> 9. aug. 2014 00:25 skrev "motty cruz" <motty.c...@gmail.com> følgende:
>
> > Hello All,
> > I am having the issue "Job failed due to exception Unable to create a
> > deployment for VM[User]i-2-18-VM]" it was able to create router but not
> VM.
> > Systems VMs are up,
> >
> > any suggestions?
> >
> > Thanks,
> >
>
2014-08-11 07:17:51,903 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Allocating entries for VM:
VM[User|i-2-23-VM]
2014-08-11 07:17:51,906 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Allocating nics for
VM[User|i-2-23-VM]
2014-08-11 07:17:51,907 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Allocating nic for vm
VM[User|i-2-23-VM] in network
Ntwk[442b3502-7074-4b6c-9bd6-572c6eee20e9|Guest|8] with requested profile
NicProfile[0-0-null-null-null
2014-08-11 07:17:51,963 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Allocating disks for
VM[User|i-2-23-VM]
2014-08-11 07:17:51,976 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Allocation completed for VM:
VM[User|i-2-23-VM]
2014-08-11 07:17:51,976 DEBUG [c.c.v.UserVmManagerImpl]
(catalina-exec-1:ctx-4d749382 ctx-a95ca79d) Successfully allocated DB entry for
VM[User|i-2-23-VM]
2014-08-11 07:17:54,029 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Successfully
transitioned to start state for VM[User|i-2-23-VM] reservation id =
5d6a15eb-ef32-48b6-9d50-e73222f15d5b
2014-08-11 07:17:54,097 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Deployment
found - P0=VM[User|i-2-23-VM],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage(Volume(23|ROOT-->Pool(1))]
2014-08-11 07:17:56,112 DEBUG [o.a.c.e.o.VolumeOrchestrator]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Checking if we
need to prepare 1 volumes for VM[User|i-2-23-VM]
2014-08-11 07:17:56,225 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662158: Sending { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"wait":0}}]
}
2014-08-11 07:17:56,226 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662158: Executing: { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"wait":0}}]
}
2014-08-11 07:17:59,282 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662159: Sending { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.13","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:17:59,285 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662159: Executing: { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.13","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:17:59,294 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-33:ctx-c834531d) 1. The VM i-2-23-VM is in Starting state.
2014-08-11 07:17:59,299 WARN [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-33:ctx-c834531d) Unable to start i-2-23-VM due to
2014-08-11 07:17:59,300 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-33:ctx-c834531d) The VM is in stopped state, detected problem
during startup : i-2-23-VM
2014-08-11 07:17:59,303 DEBUG [c.c.a.t.Request] (DirectAgent-33:ctx-c834531d)
Seq 2-4235916924518662159: Processing: { Ans: , MgmtId: 113356693032, via: 2,
Ver: v1, Flags: 10,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"_iqnToPath":{},"result":false,"details":"Unable
to start i-2-23-VM due to ","wait":0}}] }
2014-08-11 07:17:59,335 INFO [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Unable to start
VM on Host[-2-Routing] due to Unable to start i-2-23-VM due to
2014-08-11 07:17:59,375 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Cleaning up
resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:17:59,380 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662160: Sending { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:17:59,380 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Seq
2-4235916924518662160: Executing: { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:17:59,439 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Successfully
released network resources for the vm VM[User|i-2-23-VM]
2014-08-11 07:17:59,439 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Successfully
cleanued up resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:17:59,639 ERROR [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Invocation
exception, caused by: com.cloud.exception.InsufficientServerCapacityException:
Unable to create a deployment for VM[User|i-2-23-VM]Scope=interface
com.cloud.dc.DataCenter; id=1
2014-08-11 07:17:59,639 INFO [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-4:ctx-dc46ff90 job-273/job-274 ctx-2e2c9774) Rethrow
exception com.cloud.exception.InsufficientServerCapacityException: Unable to
create a deployment for VM[User|i-2-23-VM]Scope=interface
com.cloud.dc.DataCenter; id=1
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-23-VM]Scope=interface com.cloud.dc.DataCenter; id=1
2014-08-11 07:17:59,980 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
transitioned to start state for VM[User|i-2-23-VM] reservation id =
b9261ff0-1bbb-49a9-840a-19d0e9cdb496
2014-08-11 07:18:00,116 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Deployment
found - P0=VM[User|i-2-23-VM],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(3)-Storage()]
2014-08-11 07:18:02,082 DEBUG [o.a.c.e.o.VolumeOrchestrator]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Checking if we
need to prepare 1 volumes for VM[User|i-2-23-VM]
2014-08-11 07:18:02,191 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
3-6584825605169086478: Sending { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.12","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:02,194 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
3-6584825605169086478: Executing: { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.12","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:02,202 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-3:ctx-0d020571) 1. The VM i-2-23-VM is in Starting state.
2014-08-11 07:18:02,208 WARN [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-3:ctx-0d020571) Unable to start i-2-23-VM due to
2014-08-11 07:18:02,209 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-3:ctx-0d020571) The VM is in stopped state, detected problem
during startup : i-2-23-VM
2014-08-11 07:18:02,211 DEBUG [c.c.a.t.Request] (DirectAgent-3:ctx-0d020571)
Seq 3-6584825605169086478: Processing: { Ans: , MgmtId: 113356693032, via: 3,
Ver: v1, Flags: 10,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"_iqnToPath":{},"result":false,"details":"Unable
to start i-2-23-VM due to ","wait":0}}] }
2014-08-11 07:18:02,245 INFO [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Unable to start
VM on Host[-3-Routing] due to Unable to start i-2-23-VM due to
2014-08-11 07:18:02,277 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Cleaning up
resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:02,281 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
3-6584825605169086479: Sending { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:02,282 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
3-6584825605169086479: Executing: { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:02,353 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
released network resources for the vm VM[User|i-2-23-VM]
2014-08-11 07:18:02,353 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
cleanued up resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:02,421 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Deployment
found - P0=VM[User|i-2-23-VM],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage()]
2014-08-11 07:18:04,330 DEBUG [o.a.c.e.o.VolumeOrchestrator]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Checking if we
need to prepare 1 volumes for VM[User|i-2-23-VM]
2014-08-11 07:18:04,438 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
1-4006796293476188173: Sending { Cmd , MgmtId: 113356693032, via: 1(xen1),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.11","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:04,441 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
1-4006796293476188173: Executing: { Cmd , MgmtId: 113356693032, via: 1(xen1),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.11","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:04,449 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-24:ctx-53652956) 1. The VM i-2-23-VM is in Starting state.
2014-08-11 07:18:04,455 WARN [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-24:ctx-53652956) Unable to start i-2-23-VM due to
2014-08-11 07:18:04,455 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-24:ctx-53652956) The VM is in stopped state, detected problem
during startup : i-2-23-VM
2014-08-11 07:18:04,458 DEBUG [c.c.a.t.Request] (DirectAgent-24:ctx-53652956)
Seq 1-4006796293476188173: Processing: { Ans: , MgmtId: 113356693032, via: 1,
Ver: v1, Flags: 10,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"_iqnToPath":{},"result":false,"details":"Unable
to start i-2-23-VM due to ","wait":0}}] }
2014-08-11 07:18:04,484 INFO [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Unable to start
VM on Host[-1-Routing] due to Unable to start i-2-23-VM due to
2014-08-11 07:18:04,512 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Cleaning up
resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:04,516 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
1-4006796293476188174: Sending { Cmd , MgmtId: 113356693032, via: 1(xen1),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:04,517 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
1-4006796293476188174: Executing: { Cmd , MgmtId: 113356693032, via: 1(xen1),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:04,568 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
released network resources for the vm VM[User|i-2-23-VM]
2014-08-11 07:18:04,568 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
cleanued up resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:04,624 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Deployment
found - P0=VM[User|i-2-23-VM],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
: Dest[Zone(1)-Pod(1)-Cluster(1)-Host(2)-Storage()]
2014-08-11 07:18:06,622 DEBUG [o.a.c.e.o.VolumeOrchestrator]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Checking if we
need to prepare 1 volumes for VM[User|i-2-23-VM]
2014-08-11 07:18:06,725 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
2-4235916924518662167: Sending { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.13","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:06,728 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
2-4235916924518662167: Executing: { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StartCommand":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"hostIp":"10.107.0.13","executeInSequence":false,"wait":0}}]
}
2014-08-11 07:18:06,736 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-11:ctx-9deba9cf) 1. The VM i-2-23-VM is in Starting state.
2014-08-11 07:18:06,741 WARN [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-11:ctx-9deba9cf) Unable to start i-2-23-VM due to
2014-08-11 07:18:06,741 DEBUG [c.c.h.x.r.CitrixResourceBase]
(DirectAgent-11:ctx-9deba9cf) The VM is in stopped state, detected problem
during startup : i-2-23-VM
2014-08-11 07:18:06,744 DEBUG [c.c.a.t.Request] (DirectAgent-11:ctx-9deba9cf)
Seq 2-4235916924518662167: Processing: { Ans: , MgmtId: 113356693032, via: 2,
Ver: v1, Flags: 10,
[{"com.cloud.agent.api.StartAnswer":{"vm":{"id":23,"name":"i-2-23-VM","bootloader":"CD","type":"User","cpus":2,"minSpeed":2000,"maxSpeed":2000,"minRam":2147483648,"maxRam":2147483648,"arch":"x86_64","os":"FreeBSD
(64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"37a40ee6c8a29fe5","params":{"Message.ReservedCapacityFreed.Flag":"false"},"uuid":"cf598649-6367-4733-8ed1-dd91c13c9dd7","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"diskSeq":0,"path":"8388da50-c965-4971-956e-389558d0caf1","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"localhost","volumeSize":"21474836480"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/203/203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066.iso","origUrl":"http://ftp.freebsd.org/pub/FreeBSD/releases/amd64/amd64/ISO-IMAGES/10.0/FreeBSD-10.0-RELEASE-amd64-disc1.iso","uuid":"d58473c0-2fb5-4323-b17d-a053b0dfcc4d","id":203,"format":"ISO","accountId":2,"checksum":"fd25619fa0d69c29bea8347b1070ac75","hvm":true,"displayText":"64amd
FreeBSD
10","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.107.0.6/export/secondary","_role":"Image"}},"name":"203-2-6e8f8268-4dd4-3cff-8b2d-ae08e9b4d066","guestOsType":"FreeBSD
(64-bit)","hypervisorType":"None"}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":1024,"defaultNic":true,"uuid":"442b3502-7074-4b6c-9bd6-572c6eee20e9","ip":"10.10.1.242","netmask":"255.255.255.0","gateway":"10.10.1.1","mac":"02:00:69:a6:00:09","dns1":"207.36.36.2","dns2":"207.36.37.2","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://501","isolationUri":"vlan://501","isSecurityGroupEnabled":false,"name":"cloudstack1"}]},"_iqnToPath":{},"result":false,"details":"Unable
to start i-2-23-VM due to ","wait":0}}] }
2014-08-11 07:18:06,779 INFO [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Unable to start
VM on Host[-2-Routing] due to Unable to start i-2-23-VM due to
2014-08-11 07:18:06,811 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Cleaning up
resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:06,815 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
2-4235916924518662168: Sending { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:06,815 DEBUG [c.c.a.t.Request]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Seq
2-4235916924518662168: Executing: { Cmd , MgmtId: 113356693032, via: 2(xen3),
Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"checkBeforeCleanup":false,"vmName":"i-2-23-VM","wait":0}}]
}
2014-08-11 07:18:06,871 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
released network resources for the vm VM[User|i-2-23-VM]
2014-08-11 07:18:06,871 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Successfully
cleanued up resources for the vm VM[User|i-2-23-VM] in Starting state
2014-08-11 07:18:07,072 ERROR [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Invocation
exception, caused by: com.cloud.exception.InsufficientServerCapacityException:
Unable to create a deployment for VM[User|i-2-23-VM]Scope=interface
com.cloud.dc.DataCenter; id=1
2014-08-11 07:18:07,072 INFO [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-5:ctx-b6364821 job-273/job-275 ctx-84c85772) Rethrow
exception com.cloud.exception.InsufficientServerCapacityException: Unable to
create a deployment for VM[User|i-2-23-VM]Scope=interface
com.cloud.dc.DataCenter; id=1
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-23-VM]Scope=interface com.cloud.dc.DataCenter; id=1
2014-08-11 07:18:07,185 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-2:ctx-6a8bf283 job-273 ctx-f654bddd) Destroying vm
VM[User|i-2-23-VM] as it failed to create on Host with Id:null
java.lang.RuntimeException: Job failed due to exception Unable to create a
deployment for VM[User|i-2-23-VM]
2014-08-11 07:18:07,505 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-2:ctx-6a8bf283 job-273) Complete async job-273, jobStatus:
FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Job
failed due to exception Unable to create a deployment for VM[User|i-2-23-VM]"}
2014-08-11 07:18:33,747 DEBUG [c.c.v.VirtualMachineManagerImpl]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Destroying vm
VM[User|i-2-23-VM]
2014-08-11 07:18:33,987 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-6:ctx-dc5f2443 job-276/job-277 ctx-1b8e178f) Stopped called
on VM[User|i-2-23-VM] but the state is Error
2014-08-11 07:18:37,986 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-8:ctx-2c1973b6 job-276/job-279 ctx-4d9d4558) Stopped called
on VM[User|i-2-23-VM] but the state is Expunging
2014-08-11 07:18:38,156 DEBUG [c.c.v.VirtualMachineManagerImpl]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Destroying vm
VM[User|i-2-23-VM]
2014-08-11 07:18:38,289 DEBUG [c.c.a.t.Request]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Seq
3-6584825605169086481: Sending { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"org.apache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"wait":0}}]
}
2014-08-11 07:18:38,290 DEBUG [c.c.a.t.Request]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Seq
3-6584825605169086481: Executing: { Cmd , MgmtId: 113356693032, via: 3(xen2),
Ver: v1, Flags: 100011,
[{"org.apache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f341118e-626f-4b2c-a2f7-e3e1433cf0e4","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"xen-1","id":1,"poolType":"PreSetup","host":"localhost","path":"/xen-1","port":0,"url":"PreSetup://localhost/xen-1/?ROLE=Primary&STOREUUID=xen-1"}},"name":"ROOT-23","size":21474836480,"path":"8388da50-c965-4971-956e-389558d0caf1","volumeId":23,"vmName":"i-2-23-VM","accountId":2,"format":"VHD","id":23,"deviceId":0,"hypervisorType":"XenServer"}},"wait":0}}]
}
2014-08-11 07:18:40,568 DEBUG [c.c.v.VirtualMachineManagerImpl]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Expunged
VM[User|i-2-23-VM]
2014-08-11 07:18:40,628 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Starting cleaning up vm
VM[User|i-2-23-VM] resources...
2014-08-11 07:18:40,700 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-3:ctx-e91035ce job-276 ctx-9dbf346c) Successfully cleaned up
vm VM[User|i-2-23-VM] resources as a part of expunge process
