cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Syed Abdullah (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (CLOUDSTACK-9510) Can't start Cloudstack Agent,
Date Fri, 30 Sep 2016 03:47:20 GMT

     [ https://issues.apache.org/jira/browse/CLOUDSTACK-9510?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Syed Abdullah updated CLOUDSTACK-9510:
--------------------------------------
    Description: 
/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.

I've attached /var/log/cloudstack/agent/agent.log in the post. Ask for other logs from me if needed.


  was:
/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


> 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.
> I've attached /var/log/cloudstack/agent/agent.log in the post. Ask for other logs from me if needed.



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Mime
View raw message