You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Leeno (JIRA)" <ji...@apache.org> on 2013/05/20 14:15:15 UTC
[jira] [Commented] (CLOUDSTACK-569) Host remains in Connecting
states after host restarted(Xenserver 6.0.2)
[ https://issues.apache.org/jira/browse/CLOUDSTACK-569?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13661921#comment-13661921 ]
Leeno commented on CLOUDSTACK-569:
----------------------------------
I am also facing the same issue. Is there any fix for this?
> Host remains in Connecting states after host restarted(Xenserver 6.0.2)
> -----------------------------------------------------------------------
>
> Key: CLOUDSTACK-569
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-569
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the default.)
> Environment: Xenserver 6.0.2, Apache Cloudstack 4.0.0-incubating
> Basic Networking
> Reporter: Sung Ho Kim
> Priority: Blocker
> Attachments: screenshot-1.jpg, screenshot-2.jpg
>
>
> After host reboot it remains in Connecting states.
> In Xencenter it looks ok.
> 012-11-30 17:34:50,109 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-251:null) Seq 5-247660797: Executing request
> 2012-11-30 17:34:50,389 DEBUG [xen.resource.XenServerConnectionPool] (DirectAgent-361:null) XmlRpcException for method: host.call_plugin due to Failed to create input stream: Read timed out. Reconnecting...retry=1
> 2012-11-30 17:34:50,389 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-361:null) callHostPlugin failed for cmd: setLinkLocalIP with args brName: xapi2, due to Failed to create input stream: Read timed out
> 2012-11-30 17:34:50,389 WARN [agent.manager.DirectAgentAttache] (DirectAgent-361:null) Seq 1-1342439431: Exception Caught while executing command
> com.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: setLinkLocalIP with args brName: xapi2, due to Failed to create input stream: Read timed out
> at com.cloud.hypervisor.xen.resource.CitrixResourceBase.callHostPlugin(CitrixResourceBase.java:3749)
> at com.cloud.hypervisor.xen.resource.CitrixResourceBase.setupLinkLocalNetwork(CitrixResourceBase.java:4417)
> at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:2917)
> at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:433)
> at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:73)
> at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:191)
> 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:1110)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> 2012-11-30 17:34:50,390 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-361:null) Seq 1-1342439431: Response Received:
> 2012-11-30 17:34:50,390 DEBUG [agent.transport.Request] (DirectAgent-361:null) Seq 1-1342439431: Processing: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, [{"Answer":{"result":false,"details":"com.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: setLinkLocalIP with args brName: xapi2, due to Failed to create input stream: Read timed out","wait":0}}] }
> 2012-11-30 17:34:50,390 DEBUG [agent.manager.AgentAttache] (DirectAgent-361:null) Seq 1-1342439431: No more commands found
> 2012-11-30 17:34:50,390 DEBUG [agent.transport.Request] (AgentTaskPool-8:null) Seq 1-1342439431: Received: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, { Answer } }
> 2012-11-30 17:34:50,391 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Details from executing class com.cloud.agent.api.ReadyCommand: com.cloud.utils.exception.CloudRuntimeException: callHostPlugin failed for cmd: setLinkLocalIP with args brName: xapi2, due to Failed to create input stream: Read timed out
> 2012-11-30 17:34:50,391 INFO [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Host 1 is disconnecting with event AgentDisconnected
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) The next status of agent 1is Alert, current status is Connecting
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Deregistering link for 1 with state Alert
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Remove Agent : 1
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.DirectAgentAttache] (AgentTaskPool-8:null) Processing disconnect 1
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.hypervisor.xen.discoverer.XcpServerDiscoverer$$EnhancerByCGLIB$$1baae760
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.hypervisor.vmware.manager.VmwareManagerImpl$$EnhancerByCGLIB$$4d87e44b
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.vm.ClusteredVirtualMachineManagerImpl$$EnhancerByCGLIB$$18737205
> 2012-11-30 17:34:50,393 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.network.security.SecurityGroupListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.storage.listener.StoragePoolMonitor
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.storage.secondary.SecondaryStorageListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.network.NetworkManagerImpl$$EnhancerByCGLIB$$c2335121
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.agent.manager.AgentMonitor$$EnhancerByCGLIB$$32d3d381
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.storage.download.DownloadListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.storage.upload.UploadListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.storage.LocalStoragePoolListener$$EnhancerByCGLIB$$c2c1f9b5
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.capacity.StorageCapacityListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.capacity.ComputeCapacityListener
> 2012-11-30 17:34:50,394 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.consoleproxy.ConsoleProxyListener
> 2012-11-30 17:34:50,396 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.network.SshKeysDistriMonitor
> 2012-11-30 17:34:50,396 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.network.router.VpcVirtualNetworkApplianceManagerImpl$$EnhancerByCGLIB$$c52c78a9
> 2012-11-30 17:34:50,396 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-8:null) Sending Disconnect to listener: com.cloud.network.NetworkUsageManagerImpl$DirectNetworkStatsListener
> 2012-11-30 17:34:50,396 DEBUG [cloud.network.NetworkUsageManagerImpl] (AgentTaskPool-8:null) Disconnected called on 1 with status Alert
> 2012-11-30 17:34:50,396 DEBUG [cloud.host.Status] (AgentTaskPool-8:null) Transition:[Resource state = Enabled, Agent event = AgentDisconnected, Host id = 1, name = xenserver-mercury]
> 2012-11-30 17:34:50,459 DEBUG [cloud.host.Status] (AgentTaskPool-8:null) Agent status update: [id = 1; name = xenserver-mercury; old status = Connecting; event = AgentDisconnected; new status = Alert; old update count = 72; new update count = 73]
> 2012-11-30 17:34:50,459 DEBUG [agent.manager.ClusteredAgentManagerImpl] (AgentTaskPool-8:null) Notifying other nodes of to disconnect
> 2012-11-30 17:34:50,462 DEBUG [cloud.host.Status] (AgentTaskPool-8:null) Transition:[Resource state = Enabled, Agent event = Ready, Host id = 1, name = xenserver-mercury]
> 2012-11-30 17:34:50,468 DEBUG [cloud.host.Status] (AgentTaskPool-8:null) Unable to update host for event:Ready. Name=xenserver-mercury; New=[status=Up:msid=null:lastpinged=1322523028]; Old=[status=Connecting:msid=null:lastpinged=1322523028]; DB=[status=Connecting:msid=27114220232708:lastpinged=1322523323:old update count=73]
> 2012-11-30 17:34:50,862 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-251:null) Seq 5-247660797: Response Received:
> 2012-11-30 17:34:50,863 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 5-247660797: Received: { Ans: , MgmtId: 27114220232708, via: 5, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> 2012-11-30 17:35:00,361 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is not ready to launch console proxy yet
> 2012-11-30 17:35:02,087 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) HostStatsCollector is running...
> 2012-11-30 17:35:02,099 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-224:null) Seq 5-247660798: Executing request
> 2012-11-30 17:35:02,311 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-224:null) Seq 5-247660798: Response Received:
> 2012-11-30 17:35:02,312 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 5-247660798: Received: { Ans: , MgmtId: 27114220232708, via: 5, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
> 2012-11-30 17:35:06,165 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers.
> 2012-11-30 17:35:11,058 DEBUG [agent.manager.ClusteredAgentManagerImpl] (ClusteredAgentManager Timer:null) Found 1 unmanaged direct hosts, processing connect for them...
> 2012-11-30 17:35:11,058 DEBUG [agent.manager.ClusteredAgentManagerImpl] (ClusteredAgentManager Timer:null) Loading directly connected host 1(xenserver-mercury)
> 2012-11-30 17:35:11,145 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Simulating start for resource 7a888894-c79a-412a-b454-333b7c443d83 id 1
> 2012-11-30 17:35:11,372 DEBUG [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Management network is on pif=507ee037-a330-e2cc-9a6b-129e4c501c56
> 2012-11-30 17:35:11,402 DEBUG [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Found a network called mgmt on host=192.168.22.100; Network=204f664d-e6f8-a47b-8358-d7ebce6672dd; pif=507ee037-a330-e2cc-9a6b-129e4c501c56
> 2012-11-30 17:35:11,402 INFO [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Private Network is mgmt for host 192.168.22.100
> 2012-11-30 17:35:11,402 INFO [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Guest Network is mgmt for host 192.168.22.100
> 2012-11-30 17:35:11,402 INFO [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Public Network is mgmt for host 192.168.22.100
> 2012-11-30 17:35:11,446 DEBUG [xen.resource.CitrixResourceBase] (AgentTaskPool-9:null) Total Ram: 64430763264 dom0 Ram: 3084124160
> 2012-11-30 17:35:13,229 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq -1--1: Startup request from directly connected host: { Cmd , MgmtId: -1, via: -1, Ver: v1, Flags: 11, [{"cpus":8,"speed":2400,"memory":64430763264,"dom0MinMemory":3084124160,"poolSync":false,"caps":"xen-3.0-x86_64 , xen-3.0-x86_32p , hvm-3.0-x86_32 , hvm-3.0-x86_32p , hvm-3.0-x86_64","pool":"fb9b37b3-b336-87b6-0dac-89b88b996d83","hypervisorType":"XenServer","hostDetails":{"product_version":"6.0.2","private.network.device":"mgmt","com.cloud.network.Networks.RouterPrivateIpStrategy":"DcGlobal","product_brand":"XenServer","product_version_text_short":"6.0.2","can_bridge_firewall":"false"},"hypervisorVersion":"6.0.2","type":"Routing","dataCenter":"1","pod":"1","cluster":"1","guid":"7a888894-c79a-412a-b454-333b7c443d83","name":"xenserver-mercury","version":"4.0.0.20121024012150","iqn":"iqn.2005-03.org.open-iscsi:fa1c355fa1d","publicIpAddress":"192.168.22.100","publicNetmask":"255.255.254.0","publicMacAddress":"e0:db:55:07:eb:37","privateIpAddress":"192.168.22.100","privateMacAddress":"e0:db:55:07:eb:37","privateNetmask":"255.255.254.0","storageIpAddress":"192.168.22.100","storageNetmask":"255.255.254.0","storageMacAddress":"e0:db:55:07:eb:37","wait":0},{"totalSize":0,"poolInfo":{"uuid":"18dfc1f2-ff0c-449a-34f8-098d0475501e","host":"192.168.22.100","localPath":"lvm","hostPath":"lvm","poolType":"LVM","capacityBytes":137510256640,"availableBytes":137506062336},"resourceType":"STORAGE_POOL","hostDetails":{},"type":"Storage","dataCenter":"1","guid":"7a888894-c79a-412a-b454-333b7c443d83","wait":0}] }
> 2012-11-30 17:35:13,235 DEBUG [cloud.resource.ResourceManagerImpl] (AgentTaskPool-9:null) Dispatching resource state event CREATE_HOST_VO_FOR_DIRECT_CONNECT to NetscalerElement$$EnhancerByCGLIB$$a66a29ab
> 2012-11-30 17:35:13,235 DEBUG [cloud.resource.ResourceManagerImpl] (AgentTaskPool-9:null) Dispatching resource state event CREATE_HOST_VO_FOR_DIRECT_CONNECT to PxeServerManagerImpl$$EnhancerByCGLIB$$2b8d2ea8
> 2012-11-30 17:35:13,235 DEBUG [cloud.resource.ResourceManagerImpl] (AgentTaskPool-9:null) Dispatching resource state event CREATE_HOST_VO_FOR_DIRECT_CONNECT to JuniperSRXExternalFirewallElement$$EnhancerByCGLIB$$eb1ee513
> 2012-11-30 17:35:13,235 DEBUG [cloud.resource.ResourceManagerImpl] (AgentTaskPool-9:null) Dispatching resource state event CREATE_HOST_VO_FOR_DIRECT_CONNECT to NetworkUsageManagerImpl$$EnhancerByCGLIB$$375b6270
> 2012-11-30 17:35:13,236 DEBUG [cloud.resource.ResourceManagerImpl] (AgentTaskPool-9:null) Dispatching resource state event CREATE_HOST_VO_FOR_DIRECT_CONNECT to XcpServerDiscoverer$$EnhancerByCGLIB$$1baae760
> 2012-11-30 17:35:13,236 INFO [xen.discoverer.XcpServerDiscoverer] (AgentTaskPool-9:null) Host: xenserver-mercury connected with hypervisor type: XenServer. Checking CIDR...
> 2012-11-30 17:35:13,357 DEBUG [cloud.resource.ResourceState] (AgentTaskPool-9:null) Resource state update: [id = 1; name = xenserver-mercury; old state = Enabled; event = InternalCreated; new state = Enabled]
> 2012-11-30 17:35:13,357 DEBUG [cloud.host.Status] (AgentTaskPool-9:null) Transition:[Resource state = Enabled, Agent event = AgentConnected, Host id = 1, name = xenserver-mercury]
> 2012-11-30 17:35:13,422 DEBUG [cloud.host.Status] (AgentTaskPool-9:null) Agent status update: [id = 1; name = xenserver-mercury; old status = Alert; event = AgentConnected; new status = Connecting; old update count = 73; new update count = 74]
> 2012-11-30 17:35:13,422 DEBUG [agent.manager.ClusteredAgentManagerImpl] (AgentTaskPool-9:null) create ClusteredDirectAgentAttache for 1
> 2012-11-30 17:35:13,422 INFO [agent.manager.DirectAgentAttache] (AgentTaskPool-9:null) StartupAnswer received 1 Interval = 60
> 2012-11-30 17:35:13,424 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: XcpServerDiscoverer$$EnhancerByCGLIB$$1baae760
> 2012-11-30 17:35:13,428 DEBUG [xen.discoverer.XcpServerDiscoverer] (AgentTaskPool-9:null) Setting up host 1
> 2012-11-30 17:35:13,430 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572481: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"SetupCommand":{"env":{},"multipath":false,"needSetup":false,"wait":0}}] }
> 2012-11-30 17:35:13,430 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572481: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"SetupCommand":{"env":{},"multipath":false,"needSetup":false,"wait":0}}] }
> 2012-11-30 17:35:13,437 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-35:null) Seq 1-855572481: Executing request
> 2012-11-30 17:35:13,671 INFO [xen.resource.CitrixResourceBase] (DirectAgent-35:null) Host 192.168.22.100 OpaqueRef:fbf8d32c-c065-342e-ea3c-14852b4fa2b1: Host 192.168.22.100 is already setup.
> 2012-11-30 17:35:18,374 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-35:null) Seq 1-855572481: Response Received:
> 2012-11-30 17:35:18,375 DEBUG [agent.transport.Request] (DirectAgent-35:null) Seq 1-855572481: Processing: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, [{"SetupAnswer":{"_reconnect":false,"result":true,"wait":0}}] }
> 2012-11-30 17:35:18,375 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572481: Received: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, { SetupAnswer } }
> 2012-11-30 17:35:18,376 DEBUG [agent.manager.AgentAttache] (DirectAgent-35:null) Seq 1-855572481: No more commands found
> 2012-11-30 17:35:18,443 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: VmwareManagerImpl$$EnhancerByCGLIB$$4d87e44b
> 2012-11-30 17:35:18,443 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: ClusteredVirtualMachineManagerImpl$$EnhancerByCGLIB$$18737205
> 2012-11-30 17:35:18,446 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572482: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ClusterSyncCommand":{"_interval":60,"_clusterId":1,"wait":0}}] }
> 2012-11-30 17:35:18,446 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572482: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ClusterSyncCommand":{"_interval":60,"_clusterId":1,"wait":0}}] }
> 2012-11-30 17:35:18,446 DEBUG [cloud.vm.VirtualMachineManagerImpl] (AgentTaskPool-9:null) Cluster VM sync started with jobid 855572482
> 2012-11-30 17:35:18,446 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: SecurityGroupListener
> 2012-11-30 17:35:18,446 INFO [network.security.SecurityGroupListener] (AgentTaskPool-9:null) Received a host startup notification
> 2012-11-30 17:35:18,449 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572483: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"CleanupNetworkRulesCmd":{"interval":2520,"wait":0}}] }
> 2012-11-30 17:35:18,449 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572483: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"CleanupNetworkRulesCmd":{"interval":2520,"wait":0}}] }
> 2012-11-30 17:35:18,449 INFO [network.security.SecurityGroupListener] (AgentTaskPool-9:null) Scheduled network rules cleanup, interval=2520
> 2012-11-30 17:35:18,449 INFO [network.security.SecurityGroupListener] (AgentTaskPool-9:null) Received a host startup notification
> 2012-11-30 17:35:18,449 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: StoragePoolMonitor
> 2012-11-30 17:35:18,452 DEBUG [storage.listener.StoragePoolMonitor] (AgentTaskPool-9:null) Host 1 connected, sending down storage pool information ...
> 2012-11-30 17:35:18,452 DEBUG [cloud.storage.StorageManagerImpl] (AgentTaskPool-9:null) Adding pool Galaxy-PSS to host 1
> 2012-11-30 17:35:18,457 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572484: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ModifyStoragePoolCommand":{"add":true,"pool":{"id":200,"uuid":"198d8c53-024a-3628-8a8d-bc7c9e13e5a4","host":"10.0.0.2","path":"/cloud/primary","port":2049,"type":"NetworkFilesystem"},"localPath":"/mnt//198d8c53-024a-3628-8a8d-bc7c9e13e5a4","wait":0}}] }
> 2012-11-30 17:35:18,457 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572484: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ModifyStoragePoolCommand":{"add":true,"pool":{"id":200,"uuid":"198d8c53-024a-3628-8a8d-bc7c9e13e5a4","host":"10.0.0.2","path":"/cloud/primary","port":2049,"type":"NetworkFilesystem"},"localPath":"/mnt//198d8c53-024a-3628-8a8d-bc7c9e13e5a4","wait":0}}] }
> 2012-11-30 17:35:18,457 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-445:null) Seq 1-855572484: Executing request
> 2012-11-30 17:35:18,608 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-445:null) SR retrieved for 200
> 2012-11-30 17:35:18,621 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-445:null) Checking 198d8c53-024a-3628-8a8d-bc7c9e13e5a4 or SR 78987364-2aed-89bb-d1a7-c34cf909b315 on XS[7a888894-c79a-412a-b454-333b7c443d83-192.168.22.100]
> 2012-11-30 17:35:19,039 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-445:null) Seq 1-855572484: Response Received:
> 2012-11-30 17:35:19,039 DEBUG [agent.transport.Request] (DirectAgent-445:null) Seq 1-855572484: Processing: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 10, [{"ModifyStoragePoolAnswer":{"poolInfo":{"host":"10.0.0.2","localPath":"/mnt//198d8c53-024a-3628-8a8d-bc7c9e13e5a4","hostPath":"/cloud/primary","poolType":"NetworkFilesystem","capacityBytes":9996400066560,"availableBytes":9790674436096},"templateInfo":{},"result":true,"wait":0}}] }
> 2012-11-30 17:35:19,039 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572484: Received: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 10, { ModifyStoragePoolAnswer } }
> 2012-11-30 17:35:19,100 INFO [cloud.storage.StorageManagerImpl] (AgentTaskPool-9:null) Connection established between Pool[200|NetworkFilesystem] host + 1
> 2012-11-30 17:35:19,103 DEBUG [cloud.storage.StorageManagerImpl] (AgentTaskPool-9:null) Successfully set Capacity - 19992800133120 for capacity type - 3 , DataCenterId - 1, HostOrPoolId - 200, PodId 1
> 2012-11-30 17:35:19,103 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: SecondaryStorageListener
> 2012-11-30 17:35:19,103 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: NetworkManagerImpl$$EnhancerByCGLIB$$c2335121
> 2012-11-30 17:35:19,105 DEBUG [cloud.network.NetworkManagerImpl] (AgentTaskPool-9:null) Host's hypervisorType is: XenServer
> 2012-11-30 17:35:19,114 DEBUG [cloud.network.NetworkManagerImpl] (AgentTaskPool-9:null) Sending CheckNetworkCommand to check the Network is setup correctly on Agent
> 2012-11-30 17:35:19,117 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572485: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"CheckNetworkCommand":{"networkInfoList":[{"physicalNetworkId":1,"privateNetworkName":"mgmt","guestNetworkName":"guest","storageNetworkName":"mgmt"}],"wait":0}}] }
> 2012-11-30 17:35:19,117 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572485: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"CheckNetworkCommand":{"networkInfoList":[{"physicalNetworkId":1,"privateNetworkName":"mgmt","guestNetworkName":"guest","storageNetworkName":"mgmt"}],"wait":0}}] }
> 2012-11-30 17:35:19,118 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-105:null) Seq 1-855572485: Executing request
> 2012-11-30 17:35:19,118 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-105:null) Checking if network name setup is done on the resource
> 2012-11-30 17:35:19,118 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-105:null) Looking for network setup by name guest
> 2012-11-30 17:35:19,245 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-105:null) Found more than one network with the name guest
> 2012-11-30 17:35:19,287 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-105:null) Found a network called guest on host=192.168.22.100; Network=2e8c202b-64ee-75fa-dd49-31818fd960b7; pif=d98b02ff-f841-6696-6301-377a37f00a46
> 2012-11-30 17:35:19,287 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-105:null) Looking for network setup by name mgmt
> 2012-11-30 17:35:19,413 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-105:null) Seq 1-855572485: Response Received:
> 2012-11-30 17:35:19,413 DEBUG [agent.transport.Request] (DirectAgent-105:null) Seq 1-855572485: Processing: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, [{"CheckNetworkAnswer":{"_reconnect":false,"result":true,"details":"Network Setup check by names is done","wait":0}}] }
> 2012-11-30 17:35:19,413 DEBUG [agent.manager.AgentAttache] (DirectAgent-105:null) Seq 1-855572485: No more commands found
> 2012-11-30 17:35:19,413 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572485: Received: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 110, { CheckNetworkAnswer } }
> 2012-11-30 17:35:19,413 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Details from executing class com.cloud.agent.api.CheckNetworkCommand: Network Setup check by names is done
> 2012-11-30 17:35:19,413 DEBUG [cloud.network.NetworkManagerImpl] (AgentTaskPool-9:null) Network setup is correct on Agent
> 2012-11-30 17:35:19,413 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: AgentMonitor$$EnhancerByCGLIB$$32d3d381
> 2012-11-30 17:35:19,414 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: DownloadListener
> 2012-11-30 17:35:19,416 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: UploadListener
> 2012-11-30 17:35:19,416 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: LocalStoragePoolListener$$EnhancerByCGLIB$$c2c1f9b5
> 2012-11-30 17:35:19,416 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: StorageCapacityListener
> 2012-11-30 17:35:19,416 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: ComputeCapacityListener
> 2012-11-30 17:35:19,422 DEBUG [cloud.capacity.CapacityManagerImpl] (AgentTaskPool-9:null) Found 0 VMs on host 1
> 2012-11-30 17:35:19,425 DEBUG [cloud.capacity.CapacityManagerImpl] (AgentTaskPool-9:null) Found 1 VM, not running on host 1
> 2012-11-30 17:35:19,427 DEBUG [cloud.capacity.CapacityManagerImpl] (AgentTaskPool-9:null) No need to calibrate cpu capacity, host:1 usedCpu: 0 reservedCpu: 0
> 2012-11-30 17:35:19,428 DEBUG [cloud.capacity.CapacityManagerImpl] (AgentTaskPool-9:null) No need to calibrate memory capacity, host:1 usedMem: 0 reservedMem: 0
> 2012-11-30 17:35:19,428 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: ConsoleProxyListener
> 2012-11-30 17:35:19,428 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: SshKeysDistriMonitor
> 2012-11-30 17:35:19,432 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572486: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ModifySshKeysCommand":{"wait":0}}] }
> 2012-11-30 17:35:19,432 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572486: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100011, [{"ModifySshKeysCommand":{"wait":0}}] }
> 2012-11-30 17:35:19,433 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: VpcVirtualNetworkApplianceManagerImpl$$EnhancerByCGLIB$$c52c78a9
> 2012-11-30 17:35:19,433 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-444:null) Seq 1-855572486: Executing request
> 2012-11-30 17:35:19,433 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-444:null) Seq 1-855572486: Response Received:
> 2012-11-30 17:35:19,433 DEBUG [agent.transport.Request] (DirectAgent-444:null) Seq 1-855572486: Processing: { Ans: , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 10, [{"Answer":{"result":true,"wait":0}}] }
> 2012-11-30 17:35:19,435 DEBUG [agent.manager.AgentManagerImpl] (AgentTaskPool-9:null) Sending Connect to listener: DirectNetworkStatsListener
> 2012-11-30 17:35:19,438 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572487: Sending { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"ReadyCommand":{"dcId":1,"wait":0}}] }
> 2012-11-30 17:35:19,438 DEBUG [agent.transport.Request] (AgentTaskPool-9:null) Seq 1-855572487: Executing: { Cmd , MgmtId: 27114220232708, via: 1, Ver: v1, Flags: 100111, [{"ReadyCommand":{"dcId":1,"wait":0}}] }
> 2012-11-30 17:35:19,441 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) VmStatsCollector is running...
> 2012-11-30 17:35:19,463 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-328:null) Seq 1-855572487: Executing request
> 2012-11-30 17:35:19,498 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-148:null) Seq 5-247660799: Executing request
> 2012-11-30 17:35:19,838 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-328:null) already have a vif on dom0 for link local network
> 2012-11-30 17:35:19,937 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.35000000000000003
> 2012-11-30 17:35:19,937 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.16
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.13
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.8
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.36
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 2.1350000000000002
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 1.76
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.2
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.12
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.26999999999999996
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.38
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.8175
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.74
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.16
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.12
> 2012-11-30 17:35:19,938 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-148:null) Vm cpu utilization 0.12000000000000001
> 2012-11-30 17:35:19,938 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-148:null) Seq 5-247660799: Response Received:
> 2012-11-30 17:35:19,938 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-247660799: Received: { Ans: , MgmtId: 27114220232708, via: 5, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
> 2012-11-30 17:35:30,361 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is not ready to launch console proxy yet
> 2012-11-30 17:35:36,166 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers.
> 2012-11-30 17:35:37,708 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 3
> 2012-11-30 17:35:37,825 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) Ping from 8
> 2012-11-30 17:35:44,565 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-151:null) Ping from 5
> 2012-11-30 17:35:46,285 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-182:null) Seq 5-247660546: Executing request
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira