You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Syed Abdullah (JIRA)" <ji...@apache.org> on 2016/09/30 03:46:20 UTC
[jira] [Updated] (CLOUDSTACK-9510) Can't start Cloudstack Agent,
[ https://issues.apache.org/jira/browse/CLOUDSTACK-9510?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Syed Abdullah updated CLOUDSTACK-9510:
--------------------------------------
Attachment: agent.log
> Can't start Cloudstack Agent,
> ------------------------------
>
> Key: CLOUDSTACK-9510
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-9510
> Project: CloudStack
> Issue Type: Test
> Security Level: Public(Anyone can view this level - this is the default.)
> Components: cloudstack-agent
> Affects Versions: 4.9.0
> Environment: Ubuntu 14.04
> Reporter: Syed Abdullah
> Labels: build, newbie, test
> Attachments: agent.log
>
> Original Estimate: 24h
> Remaining Estimate: 24h
>
> /etc/init.d/cloudstack-agent start gives me the error
> "cat: /var/run/cloudstack-agent.pid: No such file or directory
> * jsvc failed to start [fail]"
> Putting sudo in front of the command (sudo /etc/init.d/cloudstack-agent) makes the error message go away, but when I run a status check immediately after that, it says that cloudstack-agent is not running.
> Here's what /var/log/cloudstack/agent/agent.log gives me but I don't know what to look for, quite new to Ubuntu and all this.
> 2016-09-28 09:44:54,614 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 09:44:54,706 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 09:44:54,843 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 09:44:54,983 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 09:44:54,985 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 09:44:55,141 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 09:44:55,165 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 09:44:56,537 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 09:44:56,541 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 09:44:56,551 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 09:44:56,843 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 09:44:59,435 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 949 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 09:44:59,585 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 12:15:09,579 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 12:15:09,620 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 12:15:09,622 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 12:15:09,628 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 12:15:09,631 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 12:15:09,648 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 12:15:09,672 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 12:15:10,250 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 12:15:10,397 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 12:15:10,400 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Private NIC is not configured
> 2016-09-28 12:19:42,010 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 12:19:42,052 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 12:19:42,054 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 12:19:42,060 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 12:19:42,062 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 12:19:42,080 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 12:19:42,108 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 12:19:42,579 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 12:19:42,678 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 12:19:42,684 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 12:19:43,292 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 12:19:43,846 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent:
> com.cloud.utils.exception.CloudRuntimeException: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
> at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:817)
> at com.cloud.agent.Agent.<init>(Agent.java:165)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:397)
> at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:367)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:351)
> at com.cloud.agent.AgentShell.start(AgentShell.java:456)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at org.apache.commons.daemon.support.DaemonLoader.start(DaemonLoader.java:243)
> 2016-09-28 12:24:02,457 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 12:24:02,459 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 12:24:02,460 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 12:24:02,467 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 12:24:02,469 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 12:24:02,486 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 12:24:02,510 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 12:24:02,576 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 12:24:02,579 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 12:24:02,585 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 12:24:02,616 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 12:24:02,819 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent:
> com.cloud.utils.exception.CloudRuntimeException: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
> at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:817)
> at com.cloud.agent.Agent.<init>(Agent.java:165)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:397)
> at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:367)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:351)
> at com.cloud.agent.AgentShell.start(AgentShell.java:456)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at org.apache.commons.daemon.support.DaemonLoader.start(DaemonLoader.java:243)
> 2016-09-28 12:24:42,030 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 12:24:42,032 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 12:24:42,034 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 12:24:42,041 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 12:24:42,043 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 12:24:42,059 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 12:24:42,083 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 12:24:42,148 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 12:24:42,152 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 12:24:42,157 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 12:24:42,192 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 12:24:42,398 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent:
> com.cloud.utils.exception.CloudRuntimeException: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
> at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:817)
> at com.cloud.agent.Agent.<init>(Agent.java:165)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:397)
> at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:367)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:351)
> at com.cloud.agent.AgentShell.start(AgentShell.java:456)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at org.apache.commons.daemon.support.DaemonLoader.start(DaemonLoader.java:243)
> 2016-09-28 12:30:19,334 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 12:30:19,337 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 12:30:19,338 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 12:30:19,345 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 12:30:19,347 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 12:30:19,364 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 12:30:19,387 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 12:30:19,453 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 12:30:19,456 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 12:30:19,462 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 12:30:19,493 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 12:30:19,696 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent:
> com.cloud.utils.exception.CloudRuntimeException: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
> at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:817)
> at com.cloud.agent.Agent.<init>(Agent.java:165)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:397)
> at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:367)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:351)
> at com.cloud.agent.AgentShell.start(AgentShell.java:456)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at org.apache.commons.daemon.support.DaemonLoader.start(DaemonLoader.java:243)
> 2016-09-28 14:26:18,644 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 14:26:18,832 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 14:26:18,835 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 14:26:18,842 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 14:26:18,845 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 14:26:18,873 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 14:26:18,898 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 14:26:19,304 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 14:26:19,506 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 14:26:19,509 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Private NIC is not configured
> 2016-09-28 14:29:21,720 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 14:29:21,722 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 14:29:21,724 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 14:29:21,730 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 14:29:21,732 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 14:29:21,750 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 14:29:21,773 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 14:29:21,845 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 14:29:21,849 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 14:29:21,851 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Private NIC is not configured
> 2016-09-28 14:50:28,182 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 14:50:28,245 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 14:50:28,246 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 14:50:28,253 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 14:50:28,255 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 14:50:28,272 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 14:50:28,299 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 14:50:28,555 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 14:50:28,570 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 14:50:28,577 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 14:50:29,498 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 14:50:29,785 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent:
> com.cloud.utils.exception.CloudRuntimeException: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file or directory
> at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.configure(LibvirtComputingResource.java:817)
> at com.cloud.agent.Agent.<init>(Agent.java:165)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:397)
> at com.cloud.agent.AgentShell.launchAgentFromClassInfo(AgentShell.java:367)
> at com.cloud.agent.AgentShell.launchAgent(AgentShell.java:351)
> at com.cloud.agent.AgentShell.start(AgentShell.java:456)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:606)
> at org.apache.commons.daemon.support.DaemonLoader.start(DaemonLoader.java:243)
> 2016-09-28 14:55:53,608 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 14:55:53,610 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 14:55:53,612 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 14:55:53,618 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 14:55:53,620 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 14:55:53,637 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 14:55:53,661 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 14:55:53,726 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 14:55:53,765 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 14:55:54,246 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 132 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 14:55:54,572 INFO [kvm.resource.LibvirtComputingResource] (main:null) (logid:) No libvirt.vif.driver specified. Defaults to BridgeVifDriver.
> 2016-09-28 14:55:54,680 INFO [cloud.agent.Agent] (main:null) (logid:) Agent [id = new : type = LibvirtComputingResource : zone = default : pod = default : workers = 5 : host = localhost : port = 8250
> 2016-09-28 14:55:54,684 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:55:54,685 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:55:59,686 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:55:59,687 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:04,688 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:04,689 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:09,690 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:09,692 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:14,693 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:14,694 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:19,695 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:19,696 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:24,697 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:24,698 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:29,699 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:29,700 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:34,701 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:34,702 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:39,703 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:39,705 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:44,706 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:44,707 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:49,713 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:49,715 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:54,716 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:54,717 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:56:59,718 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:56:59,719 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:57:03,460 INFO [cloud.agent.Agent] (AgentShutdownThread:null) (logid:) Stopping the agent: Reason = sig.kill
> 2016-09-28 14:57:04,478 ERROR [kvm.resource.LibvirtConnection] (AgentShutdownThread:null) (logid:) Connection with libvirtd is broken: Cannot write data: Broken pipe
> 2016-09-28 14:58:12,069 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 14:58:12,125 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 14:58:12,149 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 14:58:12,156 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 14:58:12,158 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 14:58:12,175 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 14:58:12,199 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 14:58:12,497 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 14:58:13,370 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 14:58:14,357 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 450 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 14:58:19,960 INFO [kvm.resource.LibvirtComputingResource] (main:null) (logid:) No libvirt.vif.driver specified. Defaults to BridgeVifDriver.
> 2016-09-28 14:58:20,161 INFO [cloud.agent.Agent] (main:null) (logid:) Agent [id = new : type = LibvirtComputingResource : zone = default : pod = default : workers = 5 : host = localhost : port = 8250
> 2016-09-28 14:58:20,165 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:20,167 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:25,168 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:25,168 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:30,169 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:30,169 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:35,170 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:35,170 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:40,171 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:40,171 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:45,172 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:45,172 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:50,173 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:50,174 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:58:55,174 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:58:55,175 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:00,175 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:00,176 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:05,176 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:05,177 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:10,177 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:10,178 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:15,178 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:15,180 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:20,181 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:20,182 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:25,183 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:25,185 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:30,185 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:30,187 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:35,188 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:35,189 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:40,190 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:40,192 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:45,192 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:45,194 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:50,195 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:50,196 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 14:59:55,197 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 14:59:55,198 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:00,199 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:00,201 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:05,202 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:05,203 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:10,204 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:10,205 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:15,206 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:15,208 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:20,208 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:20,210 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:25,211 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:25,212 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:30,213 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:30,214 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:35,215 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:35,217 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:40,217 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:40,219 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:45,220 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:45,221 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:50,222 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:50,224 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:00:55,224 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:00:55,226 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:00,227 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:00,228 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:05,229 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:05,230 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:10,231 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:10,232 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:15,233 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:15,235 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:20,235 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:20,237 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:25,238 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:25,239 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:30,240 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:30,242 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:35,242 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:35,244 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:40,245 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:40,246 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:45,247 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:45,248 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:50,249 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:50,251 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:01:55,251 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:01:55,253 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:00,254 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:00,255 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:05,256 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:05,257 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:10,258 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:10,259 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:15,260 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:15,261 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:20,262 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:20,264 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:25,264 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:25,266 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:30,267 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:30,268 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:35,269 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:35,270 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:40,271 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:40,272 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:45,273 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:45,275 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:50,276 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:50,277 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:02:55,278 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:02:55,279 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:00,280 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:00,282 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:05,282 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:05,284 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:10,285 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:10,286 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:15,287 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:15,288 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:20,289 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:20,291 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:25,292 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:25,293 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:30,294 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:30,295 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:35,296 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:35,297 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:40,298 INFO [utils.nio.NioClient] (main:null) (logid:) Connecting to localhost:8250
> 2016-09-28 15:03:40,300 WARN [utils.nio.NioConnection] (main:null) (logid:) Unable to connect to remote: is there a server running on port 8250
> 2016-09-28 15:03:40,333 INFO [cloud.agent.Agent] (AgentShutdownThread:null) (logid:) Stopping the agent: Reason = sig.kill
> 2016-09-28 15:03:41,354 ERROR [kvm.resource.LibvirtConnection] (AgentShutdownThread:null) (logid:) Connection with libvirtd is broken: Cannot write data: Broken pipe
> 2016-09-28 15:04:52,787 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 15:04:52,877 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 15:04:52,878 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 15:04:52,885 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 15:04:52,887 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 15:04:52,904 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 15:04:52,928 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 15:04:53,463 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 15:04:53,540 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 15:04:53,546 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 15:04:54,096 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 15:04:55,210 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 523 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 15:04:55,358 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 15:08:09,870 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 15:08:09,872 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 15:08:09,874 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 15:08:09,880 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 15:08:09,882 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 15:08:09,899 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 15:08:09,923 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 15:08:09,988 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 15:08:09,991 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 15:08:09,997 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 15:08:10,029 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 15:08:10,455 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 108 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 15:08:10,474 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 15:09:30,710 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 15:09:30,713 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 15:09:30,714 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 15:09:30,721 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 15:09:30,723 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 15:09:30,740 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 15:09:30,764 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 15:09:30,829 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 15:09:30,832 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 15:09:30,838 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 15:09:30,869 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 15:09:31,278 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 99 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 15:09:31,313 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 15:15:44,221 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 15:15:44,224 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 15:15:44,225 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 15:15:44,232 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 15:15:44,234 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 15:15:44,251 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 15:15:44,274 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 15:15:44,340 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 15:15:44,343 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 15:15:44,349 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 15:15:44,382 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 15:15:44,790 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 98 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 15:15:44,821 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 15:22:17,478 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 15:22:17,481 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 15:22:17,482 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 15:22:17,488 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 15:22:17,490 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 15:22:17,508 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 15:22:17,532 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 15:22:17,599 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 15:22:17,603 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 15:22:17,609 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 15:22:17,643 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 15:22:18,079 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 114 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 15:22:18,098 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
> 2016-09-28 16:16:31,387 INFO [cloud.agent.AgentShell] (main:null) (logid:) Agent started
> 2016-09-28 16:16:31,389 INFO [cloud.agent.AgentShell] (main:null) (logid:) Implementation Version is 4.9.0
> 2016-09-28 16:16:31,391 INFO [cloud.agent.AgentShell] (main:null) (logid:) agent.properties found at /etc/cloudstack/agent/agent.properties
> 2016-09-28 16:16:31,397 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: guest.network.device
> 2016-09-28 16:16:31,397 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: workers
> 2016-09-28 16:16:31,397 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: private.network.device
> 2016-09-28 16:16:31,397 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: port
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: resource
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: pod
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: zone
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: guid
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: hypervisor.type
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: cluster
> 2016-09-28 16:16:31,398 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: public.network.device
> 2016-09-28 16:16:31,399 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: local.storage.uuid
> 2016-09-28 16:16:31,399 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: domr.scripts.dir
> 2016-09-28 16:16:31,399 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Found property: host
> 2016-09-28 16:16:31,399 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to using properties file for storage
> 2016-09-28 16:16:31,401 INFO [cloud.agent.AgentShell] (main:null) (logid:) Defaulting to the constant time backoff algorithm
> 2016-09-28 16:16:31,418 INFO [cloud.utils.LogUtils] (main:null) (logid:) log4j configuration found at /etc/cloudstack/agent/log4j-cloud.xml
> 2016-09-28 16:16:31,442 INFO [cloud.agent.AgentShell] (main:null) (logid:) Using default Java settings for IPv6 preference for agent connection
> 2016-09-28 16:16:31,442 DEBUG [cloud.agent.AgentShell] (main:null) (logid:) Checking to see if agent.pid exists.
> 2016-09-28 16:16:31,450 DEBUG [cloud.utils.ProcessUtil] (main:null) (logid:) Executing: bash -c echo $PPID
> 2016-09-28 16:16:31,457 DEBUG [cloud.utils.ProcessUtil] (main:null) (logid:) Execution is successful.
> 2016-09-28 16:16:31,519 INFO [cloud.agent.Agent] (main:null) (logid:) id is
> 2016-09-28 16:16:31,520 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Retrieving network interface: cloudbr0
> 2016-09-28 16:16:31,523 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Unable to get network interface for cloudbr0
> 2016-09-28 16:16:31,523 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Retrieving network interface: cloudbr0
> 2016-09-28 16:16:31,523 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Unable to get network interface for cloudbr0
> 2016-09-28 16:16:31,523 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Retrieving network interface: null
> 2016-09-28 16:16:31,523 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Retrieving network interface: null
> 2016-09-28 16:16:31,523 WARN [cloud.resource.ServerResourceBase] (main:null) (logid:) Nics are not specified in properties file/db, will try to autodiscover
> 2016-09-28 16:16:31,524 DEBUG [cloud.resource.ServerResourceBase] (main:null) (logid:) Skipping nic virbr0
> 2016-09-28 16:16:31,529 INFO [cloud.resource.ServerResourceBase] (main:null) (logid:) Designating private to be nic eth0
> 2016-09-28 16:16:31,536 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in the classpath
> 2016-09-28 16:16:31,536 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,537 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,537 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh
> 2016-09-28 16:16:31,537 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/update_host_passwd.sh
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-agent/lib/scripts/vm/update_host_passwd.sh
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-agent/lib/scripts/update_host_passwd.sh
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-agent/lib/update_host_passwd.sh
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-agent/update_host_passwd.sh
> 2016-09-28 16:16:31,538 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/update_host_passwd.sh
> 2016-09-28 16:16:31,539 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/update_host_passwd.sh
> 2016-09-28 16:16:31,539 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /update_host_passwd.sh
> 2016-09-28 16:16:31,539 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,539 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,540 DEBUG [utils.script.Script] (main:null) (logid:) Looking for update_host_passwd.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/update_host_passwd.sh
> 2016-09-28 16:16:31,540 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in the classpath
> 2016-09-28 16:16:31,540 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,541 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,541 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh
> 2016-09-28 16:16:31,541 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/vnet/modifyvlan.sh
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/modifyvlan.sh
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/lib/scripts/vm/modifyvlan.sh
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/lib/scripts/modifyvlan.sh
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/lib/modifyvlan.sh
> 2016-09-28 16:16:31,542 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-agent/modifyvlan.sh
> 2016-09-28 16:16:31,543 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/modifyvlan.sh
> 2016-09-28 16:16:31,543 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/modifyvlan.sh
> 2016-09-28 16:16:31,543 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /modifyvlan.sh
> 2016-09-28 16:16:31,543 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,544 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,544 DEBUG [utils.script.Script] (main:null) (logid:) Looking for modifyvlan.sh in /usr/share/cloudstack-common/scripts/vm/network/vnet/modifyvlan.sh
> 2016-09-28 16:16:31,544 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in the classpath
> 2016-09-28 16:16:31,544 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,545 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,545 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh
> 2016-09-28 16:16:31,545 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvm/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/lib/scripts/vm/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/lib/scripts/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/lib/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-agent/versions.sh
> 2016-09-28 16:16:31,546 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/versions.sh
> 2016-09-28 16:16:31,547 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/versions.sh
> 2016-09-28 16:16:31,547 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /versions.sh
> 2016-09-28 16:16:31,547 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,547 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,547 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/versions.sh
> 2016-09-28 16:16:31,548 DEBUG [utils.script.Script] (main:null) (logid:) Looking for versions.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/versions.sh
> 2016-09-28 16:16:31,548 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in the classpath
> 2016-09-28 16:16:31,548 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,549 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,549 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py
> 2016-09-28 16:16:31,549 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,549 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvm/patch/patchviasocket.py
> 2016-09-28 16:16:31,549 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvm/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/scripts/vm/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/scripts/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/lib/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-agent/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/patchviasocket.py
> 2016-09-28 16:16:31,550 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /patchviasocket.py
> 2016-09-28 16:16:31,551 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,551 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,551 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/patch/patchviasocket.py
> 2016-09-28 16:16:31,551 DEBUG [utils.script.Script] (main:null) (logid:) Looking for patchviasocket.py in /usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/patchviasocket.py
> 2016-09-28 16:16:31,551 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in the classpath
> 2016-09-28 16:16:31,552 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,553 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,553 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh
> 2016-09-28 16:16:31,553 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,553 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvm/kvmheartbeat.sh
> 2016-09-28 16:16:31,553 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/lib/scripts/vm/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/lib/scripts/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/lib/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-agent/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/kvmheartbeat.sh
> 2016-09-28 16:16:31,554 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /kvmheartbeat.sh
> 2016-09-28 16:16:31,555 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,555 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,555 DEBUG [utils.script.Script] (main:null) (logid:) Looking for kvmheartbeat.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/kvmheartbeat.sh
> 2016-09-28 16:16:31,555 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in the classpath
> 2016-09-28 16:16:31,556 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,556 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,556 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-agent/lib/scripts/storage/qcow2/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-agent/lib/scripts/storage/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-agent/lib/scripts/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-agent/lib/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-agent/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/createvm.sh
> 2016-09-28 16:16:31,557 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/createvm.sh
> 2016-09-28 16:16:31,558 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /createvm.sh
> 2016-09-28 16:16:31,558 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,558 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,558 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createvm.sh in /usr/share/cloudstack-common/scripts/storage/qcow2/createvm.sh
> 2016-09-28 16:16:31,558 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in the classpath
> 2016-09-28 16:16:31,559 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,559 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,559 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/storage/qcow2/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/storage/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/managesnapshot.sh
> 2016-09-28 16:16:31,560 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /managesnapshot.sh
> 2016-09-28 16:16:31,561 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,561 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,561 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-common/scripts/storage/qcow2/managesnapshot.sh
> 2016-09-28 16:16:31,561 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in the classpath
> 2016-09-28 16:16:31,562 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,562 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,562 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh
> 2016-09-28 16:16:31,562 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-agent/lib/scripts/storage/qcow2/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-agent/lib/scripts/storage/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-agent/lib/scripts/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-agent/lib/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-agent/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /resizevolume.sh
> 2016-09-28 16:16:31,563 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,564 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,564 DEBUG [utils.script.Script] (main:null) (logid:) Looking for resizevolume.sh in /usr/share/cloudstack-common/scripts/storage/qcow2/resizevolume.sh
> 2016-09-28 16:16:31,564 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in the classpath
> 2016-09-28 16:16:31,564 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,565 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,565 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh
> 2016-09-28 16:16:31,565 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,565 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-agent/lib/scripts/storage/qcow2/createtmplt.sh
> 2016-09-28 16:16:31,565 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-agent/lib/scripts/storage/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-agent/lib/scripts/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-agent/lib/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-agent/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /createtmplt.sh
> 2016-09-28 16:16:31,566 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,567 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,567 DEBUG [utils.script.Script] (main:null) (logid:) Looking for createtmplt.sh in /usr/share/cloudstack-common/scripts/storage/qcow2/createtmplt.sh
> 2016-09-28 16:16:31,567 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in the classpath
> 2016-09-28 16:16:31,567 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/lib/scripts/vm/network/vnet/security_group.py
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/lib/scripts/vm/network/security_group.py
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/lib/scripts/vm/security_group.py
> 2016-09-28 16:16:31,568 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/lib/scripts/security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/lib/security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-agent/security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /security_group.py
> 2016-09-28 16:16:31,569 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,570 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,570 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-common/scripts/vm/network/vnet/security_group.py
> 2016-09-28 16:16:31,570 DEBUG [utils.script.Script] (main:null) (logid:) Looking for security_group.py in /usr/share/cloudstack-common/scripts/vm/network/security_group.py
> 2016-09-28 16:16:31,570 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in the classpath
> 2016-09-28 16:16:31,570 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,571 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,571 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py
> 2016-09-28 16:16:31,571 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,571 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/lib/scripts/vm/network/vnet/ovstunnel.py
> 2016-09-28 16:16:31,571 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/lib/scripts/vm/network/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/lib/scripts/vm/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/lib/scripts/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/lib/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-agent/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /ovstunnel.py
> 2016-09-28 16:16:31,572 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,573 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,573 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovstunnel.py in /usr/share/cloudstack-common/scripts/vm/network/vnet/ovstunnel.py
> 2016-09-28 16:16:31,573 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in the classpath
> 2016-09-28 16:16:31,573 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,574 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,574 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh
> 2016-09-28 16:16:31,574 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,574 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-agent/lib/scripts/network/domr/router_proxy.sh
> 2016-09-28 16:16:31,574 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-agent/lib/scripts/network/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-agent/lib/scripts/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-agent/lib/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-agent/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /router_proxy.sh
> 2016-09-28 16:16:31,575 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,576 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,576 DEBUG [utils.script.Script] (main:null) (logid:) Looking for router_proxy.sh in /usr/share/cloudstack-common/scripts/network/domr/router_proxy.sh
> 2016-09-28 16:16:31,576 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in the classpath
> 2016-09-28 16:16:31,576 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/vnet/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,577 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/lib/scripts/vm/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/lib/scripts/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/lib/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-agent/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,578 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,579 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,579 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-common/scripts/vm/network/vnet/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,579 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-dhcp-host.sh in /usr/share/cloudstack-common/scripts/vm/network/ovs-pvlan-dhcp-host.sh
> 2016-09-28 16:16:31,579 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in the classpath
> 2016-09-28 16:16:31,580 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,580 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,580 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,580 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/vnet/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/lib/scripts/vm/network/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/lib/scripts/vm/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/lib/scripts/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/lib/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-agent/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,581 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,582 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,582 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,582 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-common/scripts/vm/network/vnet/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,582 DEBUG [utils.script.Script] (main:null) (logid:) Looking for ovs-pvlan-vm.sh in /usr/share/cloudstack-common/scripts/vm/network/ovs-pvlan-vm.sh
> 2016-09-28 16:16:31,583 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in the classpath
> 2016-09-28 16:16:31,584 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,584 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,584 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh
> 2016-09-28 16:16:31,584 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/kvm/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/lib/scripts/vm/hypervisor/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/lib/scripts/vm/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/lib/scripts/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/lib/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-agent/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/pingtest.sh
> 2016-09-28 16:16:31,585 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /pingtest.sh
> 2016-09-28 16:16:31,586 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,586 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,586 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/kvm/pingtest.sh
> 2016-09-28 16:16:31,586 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-common/scripts/vm/hypervisor/pingtest.sh
> 2016-09-28 16:16:31,586 DEBUG [utils.script.Script] (main:null) (logid:) Looking for pingtest.sh in /usr/share/cloudstack-common/scripts/vm/pingtest.sh
> 2016-09-28 16:16:31,587 DEBUG [kvm.resource.LibvirtConnection] (main:null) (logid:) Looking for libvirtd connection at: qemu:///system
> 2016-09-28 16:16:31,587 INFO [kvm.resource.LibvirtConnection] (main:null) (logid:) No existing libvirtd connection found. Opening a new one
> 2016-09-28 16:16:31,783 DEBUG [kvm.resource.LibvirtConnection] (main:null) (logid:) Successfully connected to libvirt at: qemu:///system
> 2016-09-28 16:16:31,807 DEBUG [kvm.resource.LibvirtCapXMLParser] (main:null) (logid:) Found /usr/bin/kvm-spice as a suiteable emulator
> 2016-09-28 16:16:31,835 DEBUG [kvm.resource.LibvirtCapXMLParser] (main:null) (logid:) Found /usr/bin/kvm-spice as a suiteable emulator
> 2016-09-28 16:16:31,852 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in the classpath
> 2016-09-28 16:16:31,852 DEBUG [utils.script.Script] (main:null) (logid:) System resource: null
> 2016-09-28 16:16:31,852 DEBUG [utils.script.Script] (main:null) (logid:) Classpath resource: null
> 2016-09-28 16:16:31,852 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Current binaries reside at /usr/share/cloudstack-agent/lib
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/storage/qcow2/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/storage/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/scripts/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/lib/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-agent/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/managesnapshot.sh
> 2016-09-28 16:16:31,853 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /managesnapshot.sh
> 2016-09-28 16:16:31,854 DEBUG [utils.script.Script] (main:null) (logid:) Searching in environment.properties
> 2016-09-28 16:16:31,854 DEBUG [utils.script.Script] (main:null) (logid:) environment.properties says scripts should be in /usr/share/cloudstack-common
> 2016-09-28 16:16:31,854 DEBUG [utils.script.Script] (main:null) (logid:) Looking for managesnapshot.sh in /usr/share/cloudstack-common/scripts/storage/qcow2/managesnapshot.sh
> 2016-09-28 16:16:31,899 DEBUG [org.reflections.Reflections] (main:null) (logid:) going to scan these urls:
> jar:file:/usr/share/cloudstack-agent/lib/cloud-plugin-hypervisor-kvm-4.9.0.jar!/
> 2016-09-28 16:16:32,010 INFO [org.reflections.Reflections] (main:null) (logid:) Reflections took 110 ms to scan 1 urls, producing 7 keys and 10 values
> 2016-09-28 16:16:32,028 DEBUG [kvm.storage.KVMStoragePoolManager] (main:null) (logid:) Registered a StorageAdaptor for libvirt
> 2016-09-28 16:16:32,028 DEBUG [kvm.storage.KVMStoragePoolManager] (main:null) (logid:) Registered a StorageAdaptor for ManagedNFS
> 2016-09-28 16:16:32,028 DEBUG [kvm.storage.KVMStoragePoolManager] (main:null) (logid:) Registered a StorageAdaptor for Iscsi
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) looking in file /sys/devices/virtual/net/virbr0/bridge
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) Found bridge virbr0
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) looking in file /sys/devices/virtual/net/lo/bridge
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) looking for pif for bridge virbr0
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) failing to get physical interface from bridge virbr0, did not find an eth*, bond*, team*, vlan*, em*, p*p*, ens*, eno*, enp*, or enx* in /sys/devices/virtual/net/virbr0/brif
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) guest(private) traffic label 'cloudbr0' not found as bridge, looking for physical interface
> 2016-09-28 16:16:32,029 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) public traffic label 'cloudbr0' not found as bridge, looking for physical interface
> 2016-09-28 16:16:32,030 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) done looking for pifs, no more bridges
> 2016-09-28 16:16:32,030 DEBUG [kvm.resource.LibvirtComputingResource] (main:null) (logid:) Failed to get private nic name
> 2016-09-28 16:16:32,030 ERROR [cloud.agent.AgentShell] (main:null) (logid:) Unable to start agent: Failed to get private nic name
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)