Looking at the catalina.out log from the management server I see this right
before the error:
INFO [vmware.resource.VmwareResource] (DirectAgent-317:10.0.97.107) Preparing
NIC device on network cloud.public.2502.0.1-vSwitch2
INFO [vmware.resource.VmwareResource] (DirectAgent-317:10.0.97.107) Configure
VNC port for VM r-134-VM, port: 5927, host: 10.0.97.107
Fault {http://schemas.xmlsoap.org/soap/envelope/}Server.generalException
Message A specified parameter was not correct.
ERROR [vmware.mo.VirtualMachineMO] (DirectAgent-317:10.0.97.107) VMware
reconfigVM_Task failed due to A specified parameter was not correct.
com.vmware.vim25.InvalidArgument
WARN [vmware.resource.VmwareResource] (DirectAgent-317:10.0.97.107)
StartCommand failed due to Exception: java.lang.Exception
Message: Failed to configure VM before start. vmName: r-134-VM
java.lang.Exception: Failed to configure VM before start. vmName: r-134-VM
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:2315)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:419)
at
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:679)
INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-21:job-662) Unable to
start VM on Host[-21-Routing] due to StartCommand failed due to Exception:
java.lang.Exception
Message: Failed to configure VM before start. vmName: r-134-VM
It looks like the last thing it was trying to do is configure the VNC port for
the VM?
----- Original Message -----
From: "John Skinner" <[email protected]>
To: [email protected]
Sent: Tuesday, September 17, 2013 11:12:37 AM
Subject: Re: Unable to start DomR CS 4.1 VMware esxi 5.1
Still working on this. Here is a full trace from vCenter logs. Still unsure
what is going on.
http://fpaste.org/40151/
----- Original Message -----
From: "John Skinner" <[email protected]>
To: [email protected]
Sent: Monday, September 16, 2013 6:08:09 PM
Subject: Re: Unable to start DomR CS 4.1 VMware esxi 5.1
Found this in the esxi logs, not sure what it means. I am still not sure which
"parameter" was not correct. Not sure if this is of any help or not.
2013-09-16T10:22:29.858-05:00 [02968 info 'commonvpxLro' opID=8a6a6788]
[VpxLRO] -- FINISH task-46421 -- vm-4730 -- vim.VirtualMachine.reconfigure --
2013-09-16T10:22:29.859-05:00 [02968 info 'Default' opID=8a6a6788] [VpxLRO] --
ERROR task-46421 -- vm-4730 -- vim.VirtualMachine.reconfigure:
vmodl.fault.InvalidArgument:
--> Result:
--> (vmodl.fault.InvalidArgument) {
--> dynamicType = <unset>,
--> faultCause = (vmodl.MethodFault) null,
--> invalidProperty = <unset>,
--> msg = "A specified parameter was not correct.
--> ",
--> }
----- Original Message -----
From: "John Skinner" <[email protected]>
To: [email protected]
Sent: Monday, September 16, 2013 3:56:10 PM
Subject: Unable to start DomR CS 4.1 VMware esxi 5.1
Having an issue getting domain routers to start in a VMware 5.1 environment
with CS 4.1. The fun thing is, this was all working fine last week, and of
course, no one changed anything . Has anyone seen anything like this:
2013-09-16 15:46:16,927 TRACE [vmware.mo.ClusterMO]
(DirectAgent-69:10.0.97.107) vCenter API trace - retrieveProperties() for VM
properties. target MOR: domain-c3887, properties:
["name","config.extraConfig[\"RemoteDisplay.vnc.port\"]"]
2013-09-16 15:46:17,222 TRACE [vmware.mo.ClusterMO]
(DirectAgent-69:10.0.97.107) vCenter API trace - retrieveProperties() done
2013-09-16 15:46:17,451 INFO [vmware.resource.VmwareResource]
(DirectAgent-69:10.0.97.107) Configure VNC port for VM r-126-VM, port: 5905,
host: 10.0.97.107
2013-09-16 15:46:18,931 ERROR [vmware.mo.VirtualMachineMO]
(DirectAgent-69:10.0.97.107) VMware reconfigVM_Task failed due to A specified
parameter was not correct.
com.vmware.vim25.InvalidArgument
2013-09-16 15:46:18,933 WARN [vmware.resource.VmwareResource]
(DirectAgent-69:10.0.97.107) StartCommand failed due to Exception:
java.lang.Exception
Message: Failed to configure VM before start. vmName: r-126-VM
java.lang.Exception: Failed to configure VM before start. vmName: r-126-VM
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:2315)
at
com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:419)
at
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:679)
2013-09-16 15:46:18,938 TRACE [vmware.resource.VmwareResource]
(DirectAgent-69:null) End executeRequest(), cmd: StartCommand
2013-09-16 15:46:18,938 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-69:null) Seq 21-2006712339: Cancelling because one of the answers
is false and it is stop on error.
Thats just the tail end of the thread for the start of the domr. Let me know if
you need more details. Any help would be appreciated. Thanks!
