Attachment 907956 Details for Bug 1104035 – supervdsm.log

supervdsm.log

supervdsm_rmfile.log (text/x-log), 14.68 KB, created by Douglas Schilling Landgraf on 2014-06-12 06:38:39 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Douglas Schilling Landgraf

Created: 2014-06-12 06:38:39 UTC

Size: 14.68 KB

patch

obsolete

>MainThread::DEBUG::2014-06-12 06:31:26,992::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:31:26,992::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:31:27,033::supervdsmServer::387::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2014-06-12 06:31:27,033::supervdsmServer::396::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2014-06-12 06:31:27,033::supervdsmServer::399::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2014-06-12 06:31:27,033::supervdsmServer::403::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2014-06-12 06:31:27,033::supervdsmServer::409::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2014-06-12 06:31:27,036::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36)
>MainThread::DEBUG::2014-06-12 06:31:27,037::supervdsmServer::420::SuperVdsm.Server::(main) Started serving super vdsm object
>sourceRoute::DEBUG::2014-06-12 06:31:27,038::sourceroutethread::75::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started
>sourceRoute::DEBUG::2014-06-12 06:32:12,145::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1402554732
>sourceRoute::DEBUG::2014-06-12 06:32:12,150::libvirtconnection::146::root::(get) trying to connect libvirt
>sourceRoute::INFO::2014-06-12 06:32:12,166::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-06-12 06:32:12,167::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-06-12 06:33:22,901::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1402554802
>sourceRoute::INFO::2014-06-12 06:33:22,907::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-06-12 06:33:22,907::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed
>sourceRoute::DEBUG::2014-06-12 06:33:29,188::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1402554809
>sourceRoute::INFO::2014-06-12 06:33:29,190::sourceroute::81::root::(configure) Configuring gateway - ip: 192.168.100.224, network: 192.168.100.0/24, subnet: 255.255.255.0, gateway: 192.168.100.1, table: 3232261344, device: ovirtmgmt
>sourceRoute::DEBUG::2014-06-12 06:33:29,191::utils::683::root::(execCmd) '/sbin/ip -4 route add 0.0.0.0/0 via 192.168.100.1 dev ovirtmgmt table 3232261344' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:33:29,210::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>sourceRoute::DEBUG::2014-06-12 06:33:29,211::utils::683::root::(execCmd) '/sbin/ip -4 route add 192.168.100.0/24 via 192.168.100.224 dev ovirtmgmt table 3232261344' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:33:29,215::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>sourceRoute::DEBUG::2014-06-12 06:33:29,215::utils::683::root::(execCmd) '/sbin/ip rule add from 192.168.100.0/24 table 3232261344' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:33:29,219::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>sourceRoute::DEBUG::2014-06-12 06:33:29,219::utils::683::root::(execCmd) '/sbin/ip rule add from all to 192.168.100.0/24 dev ovirtmgmt table 3232261344' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:33:29,222::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:16,206::supervdsmServer::427::SuperVdsm.Server::(main) Terminated normally
>MainThread::DEBUG::2014-06-12 06:35:27,656::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:35:27,679::supervdsmServer::387::SuperVdsm.Server::(main) Making sure I'm root - SuperVdsm
>MainThread::DEBUG::2014-06-12 06:35:27,680::supervdsmServer::396::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2014-06-12 06:35:27,680::supervdsmServer::399::SuperVdsm.Server::(main) Cleaning old socket /var/run/vdsm/svdsm.sock
>MainThread::DEBUG::2014-06-12 06:35:27,680::supervdsmServer::403::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2014-06-12 06:35:27,680::supervdsmServer::409::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2014-06-12 06:35:27,684::fileUtils::192::Storage.fileUtils::(chown) Changing owner for /var/run/vdsm/svdsm.sock, to (36:36)
>MainThread::DEBUG::2014-06-12 06:35:27,685::supervdsmServer::420::SuperVdsm.Server::(main) Started serving super vdsm object
>sourceRoute::DEBUG::2014-06-12 06:35:27,686::sourceroutethread::75::root::(_subscribeToInotifyLoop) sourceRouteThread.subscribeToInotifyLoop started
>MainThread::DEBUG::2014-06-12 06:35:36,775::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:35:36,775::vdsm-restore-net-config::54::root::(unified_restoration) Removing all networks ({'ovirtmgmt': {'remove': True}}) and bonds ({}) in running config.
>MainThread::DEBUG::2014-06-12 06:35:36,775::libvirtconnection::146::root::(get) trying to connect libvirt
>MainThread::DEBUG::2014-06-12 06:35:36,790::utils::683::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:36,792::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:36,794::utils::683::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:36,796::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:36,801::api::613::setupNetworks::(setupNetworks) Setting up network according to configuration: networks:{'ovirtmgmt': {'remove': True}}, bondings:{}, options:{'_inRollback': True, 'connectivityCheck': False}
>MainThread::DEBUG::2014-06-12 06:35:36,801::api::617::root::(setupNetworks) Validating configuration
>MainThread::DEBUG::2014-06-12 06:35:36,804::api::629::setupNetworks::(setupNetworks) Applying...
>MainThread::DEBUG::2014-06-12 06:35:36,805::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:35:36,805::api::634::setupNetworks::(setupNetworks) Removing network 'ovirtmgmt'
>MainThread::INFO::2014-06-12 06:35:36,805::api::426::root::(delNetwork) Removing network ovirtmgmt with vlan=None, bonding=None, nics=['eth0'],options={}
>MainThread::DEBUG::2014-06-12 06:35:36,805::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:35:36,806::ifcfg::325::root::(_atomicNetworkBackup) Backed up ovirtmgmt
>MainThread::DEBUG::2014-06-12 06:35:36,809::models::163::root::(remove) Removing bridge Bridge(ovirtmgmt: Nic(eth0))
>MainThread::DEBUG::2014-06-12 06:35:36,809::utils::683::root::(execCmd) '/sbin/ifdown ovirtmgmt' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:35:36,960::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1402554936
>sourceRoute::INFO::2014-06-12 06:35:36,961::sourceroute::169::root::(remove) Removing gateway - device: ovirtmgmt
>sourceRoute::DEBUG::2014-06-12 06:35:36,961::utils::683::root::(execCmd) '/sbin/ip rule' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:35:36,963::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>sourceRoute::DEBUG::2014-06-12 06:35:36,964::utils::683::root::(execCmd) '/sbin/ip route show table all' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:35:36,968::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>sourceRoute::DEBUG::2014-06-12 06:35:36,968::sourceroute::119::root::(_getRoutes) Could not parse route broadcast 192.168.100.0 dev ovirtmgmt  table local  proto kernel  scope link  src 192.168.100.224 
>sourceRoute::DEBUG::2014-06-12 06:35:36,968::sourceroute::119::root::(_getRoutes) Could not parse route broadcast 127.255.255.255 dev lo  table local  proto kernel  scope link  src 127.0.0.1 
>sourceRoute::DEBUG::2014-06-12 06:35:36,968::sourceroute::119::root::(_getRoutes) Could not parse route broadcast 192.168.100.255 dev ovirtmgmt  table local  proto kernel  scope link  src 192.168.100.224 
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route broadcast 127.0.0.0 dev lo  table local  proto kernel  scope link  src 127.0.0.1 
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable ::/96 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable ::ffff:0.0.0.0/96 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:a00::/24 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:7f00::/24 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:a9fe::/32 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:ac10::/28 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:c0a8::/32 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 2002:e000::/19 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable 3ffe:ffff::/32 dev lo  metric 1024  error -101 mtu 16436 advmss 16376 hoplimit 4294967295
>sourceRoute::DEBUG::2014-06-12 06:35:36,969::sourceroute::119::root::(_getRoutes) Could not parse route unreachable default dev lo  table unspec  proto kernel  metric -1  error -101 hoplimit 255
>sourceRoute::DEBUG::2014-06-12 06:35:36,970::sourceroute::119::root::(_getRoutes) Could not parse route unreachable default dev lo  table unspec  proto kernel  metric -1  error -101 hoplimit 255
>sourceRoute::DEBUG::2014-06-12 06:35:36,970::utils::683::root::(execCmd) '/sbin/ip route del 192.168.100.0/24 via 192.168.100.224 dev ovirtmgmt table 3232261344' (cwd None)
>sourceRoute::DEBUG::2014-06-12 06:35:36,973::utils::703::root::(execCmd) FAILED: <err> = 'RTNETLINK answers: No such process\n'; <rc> = 2
>sourceRoute::ERROR::2014-06-12 06:35:36,974::sourceroute::181::root::(remove) ip binary failed during source route removal: ['RTNETLINK answers: No such process']
>MainThread::DEBUG::2014-06-12 06:35:36,995::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:36,995::utils::683::root::(execCmd) '/usr/sbin/brctl delbr ovirtmgmt' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,009::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:37,009::ifcfg::372::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-ovirtmgmt
>MainThread::DEBUG::2014-06-12 06:35:37,010::ifcfg::279::root::(_removeFile) Removed file /etc/sysconfig/network-scripts/ifcfg-ovirtmgmt
>MainThread::DEBUG::2014-06-12 06:35:37,011::utils::683::root::(execCmd) '/sbin/ifdown eth0' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,144::utils::703::root::(execCmd) SUCCESS: <err> = 'bridge ovirtmgmt does not exist!\n'; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:37,145::ifcfg::372::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth0
>MainThread::DEBUG::2014-06-12 06:35:37,147::utils::683::root::(execCmd) '/sbin/ifup eth0' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,213::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::INFO::2014-06-12 06:35:37,214::netconfpersistence::73::root::(removeNetwork) Removing network ovirtmgmt
>MainThread::DEBUG::2014-06-12 06:35:37,214::utils::683::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,215::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:37,219::utils::683::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,221::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2014-06-12 06:35:37,226::utils::683::root::(execCmd) '/sbin/ip route show to 0.0.0.0/0 table all' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,228::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::INFO::2014-06-12 06:35:37,232::netconfpersistence::158::root::(_clearDisk) Clearing /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainThread::DEBUG::2014-06-12 06:35:37,233::netconfpersistence::166::root::(_clearDisk) No existent config to clear.
>MainThread::INFO::2014-06-12 06:35:37,233::netconfpersistence::182::root::(save) Saved new config RunningConfig({}, {}) to /var/run/vdsm/netconf/nets/ and /var/run/vdsm/netconf/bonds/
>MainThread::DEBUG::2014-06-12 06:35:37,234::netconfpersistence::134::root::(_getConfigs) Non-existing config set.
>MainThread::DEBUG::2014-06-12 06:35:37,236::utils::683::root::(execCmd) '/sbin/ifdown eth0' (cwd None)
>MainThread::DEBUG::2014-06-12 06:35:37,304::utils::703::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
>MainThread::ERROR::2014-06-12 06:35:37,305::utils::131::root::(rmFile) Removing file: /etc/sysconfig/network-scripts/ifcfg-eth0 failed
>Traceback (most recent call last):
>  File "/usr/lib64/python2.6/site-packages/vdsm/utils.py", line 125, in rmFile
>    os.unlink(fileToRemove)
>OSError: [Errno 16] Device or resource busy: '/etc/sysconfig/network-scripts/ifcfg-eth0'
>sourceRoute::DEBUG::2014-06-12 06:37:03,346::sourceroutethread::39::root::(process_IN_CLOSE_WRITE_filePath) Responding to DHCP response in /var/run/vdsm/sourceRoutes/1402555023
>sourceRoute::DEBUG::2014-06-12 06:37:03,347::libvirtconnection::146::root::(get) trying to connect libvirt
>sourceRoute::INFO::2014-06-12 06:37:03,395::sourceroutethread::56::root::(process_IN_CLOSE_WRITE_filePath) interface eth0 is not a libvirt interface
>sourceRoute::WARNING::2014-06-12 06:37:03,395::utils::128::root::(rmFile) File: /var/run/vdsm/trackedInterfaces/eth0 already removed

Actions: View

Attachments on bug 1104035: 907956