Re: [Xen-devel] Latest snap shot on FC4 problems

[Ted Kaczmarek <tedkaz@xxxxxxxxxxxxx>]

On Tue, 2005-08-30 at 14:51 -0400, Ted Kaczmarek wrote:
> Using  2.6.12-1.1447_FC4xen0 from FC4 testing updates.
> Xend will start most of the time, vm will rarely start, and even less
> liklely to complete booting, most common error is
>  "12, 'Cannot allocate memory'" at cli when starting vm.
> 
> Test machine is an SMP Atlon with 1 gig on ram. 
> 
> cat /var/log/xend.log
> 
> [2005-08-30 14:45:10 xend] INFO (SrvDaemon:300) Xend Daemon started
> [2005-08-30 14:45:10 xend] INFO (channel:163) Virq 3 on port 19
> [2005-08-30 14:45:10 xend] INFO (XendDomain:356) Creating entry for
> unknown domain: id=0 uuid=a980a99f-8eb3-4cdf-8d59-93388419f366
> [2005-08-30 14:45:10 xend] DEBUG (XendDomainInfo:180)
> info={'vcpu_to_cpu': [0, 1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1,
> -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1, -1,
> -1], 'cpu_time': 103922623839L, 'ssidref': 0, 'shutdown_reason': 0,
> 'dying': 0, 'dom': 0, 'running': 1, 'maxmem_kb': -4, 'paused': 0,
> 'crashed': 0, 'vcpus': 2, 'shutdown': 0, 'mem_kb': 846848, 'cpumap':
> [-1, -1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0,
> 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0}
> [2005-08-30 14:45:10 xend] DEBUG (XendDomainInfo:181) config='None'
> 
> [2005-08-30 14:45:10 xend] INFO (xsobj:326) DBMap>introduceDomain> 0
> 117556 <EventChannel dom1:0:24
> dom2:0:25> /domain/a980a99f-8eb3-4cdf-8d59-93388419f366
> [2005-08-30 14:45:10 xend] DEBUG (XendDomainInfo:640) writing key
> availability=online to path /cpu/0 in store
> [2005-08-30 14:45:10 xend] DEBUG (XendDomainInfo:640) writing key
> availability=online to path /cpu/1 in store
> [2005-08-30 14:45:10 xend] INFO (XendRoot:141) EVENT> xend.domain.create
> ['Domain-0', 0]
> [2005-08-30 14:45:10 xend] INFO (SrvServer:78) unix
> path=/var/lib/xend/xend-socket
> [2005-08-30 14:45:10 xend] INFO (XendRoot:141) EVENT> xend.start 0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + set -e
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> OP=start
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + shift
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + for arg
> in '"$@"'
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + export
> bridge=xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> bridge=xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + for arg
> in '"$@"'
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + export
> antispoof=no
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> antispoof=no
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> bridge=xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> netdev=eth0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> antispoof=no
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + echo
> '*network start bridge=xen-br0 netdev=eth0 antispoof=no'
> [2005-08-30 14:45:10 xend] INFO (process:35) [network-bridge] network
> start bridge=xen-br0 netdev=eth0 antispoof=no
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + case
> ${OP} in
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> op_start
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + '['
> xen-br0 == null ']'
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> create_bridge xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + local
> bridge=xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + brctl
> show
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + grep -q
> xen-br0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> ifconfig xen-br0 up
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> ifconfig
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + grep -q
> veth0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] +
> ifconfig veth0
> [2005-08-30 14:45:10 xend] ERROR (process:37) [network-bridge] + grep -q
> veth0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] +
> transfer_addrs eth0 xen-br0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + local
> src=eth0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + local
> dst=xen-br0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + ip addr
> show dev xen-br0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + egrep
> -q '^ *inet '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + ip addr
> show dev eth0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + egrep
> '^ *inet '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sed -e
> '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] s/inet/ip
> addr add/
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] s@
> \([0-9]\+\.[0-9]\+\.[0-9]\+\.[0-9]\+/[0-9]\+\)@\1@
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]
> s/eth0/dev xen-br0/
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sh -e
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + ip
> route list
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sed -ne
> '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] /dev
> xen-br0\( \|$\)/ {
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   s/^/ip
> route del /
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   p
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] }'
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sh -e
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] +
> transfer_routes eth0 xen-br0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + local
> src=eth0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + local
> dst=xen-br0
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + ip
> route list
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sed -ne
> '
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] /dev eth0
> \( \|$\)/ {
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   h
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   s/^/ip
> route del /
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   P
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   g
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]
> s/eth0/xen-br0/
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   s/^/ip
> route add /
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   P
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge]   d
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] }'
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + sh -e
> [2005-08-30 14:45:11 xend] ERROR (process:37) [network-bridge] + '[' no
> == yes ']'
> 
> cat /var/log/xend-debug.log
> Traceback (most recent call last):
>   File "/usr/lib/python2.4/logging/handlers.py", line 62, in emit
>     if self.shouldRollover(record):
>   File "/usr/lib/python2.4/logging/handlers.py", line 132, in
> shouldRollover
>     self.stream.seek(0, 2)  #due to non-posix-compliant Windows feature
> ValueError: I/O operation on closed file
> Traceback (most recent call last):
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py",
> line 590, in construct
>     self.init_domain()
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py",
> line 778, in init_domain
>     id = self.image.initDomain(self.id, self.memory, self.ssidref, cpu,
> self.cpu_weight)
>   File "/usr/lib/python2.4/site-packages/xen/xend/image.py", line 159,
> in initDomain
>     xc.domain_memory_increase_reservation(dom, mem_kb)
> error: (12, 'Cannot allocate memory')
> Traceback (most recent call last):
>   File
> "/usr/lib/python2.4/site-packages/xen/xend/server/SrvDomainDir.py", line
> 78, in op_create
>     dominfo = self.xd.domain_create(config)
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomain.py", line
> 292, in domain_create
>     dominfo = XendDomainInfo.create(self.dbmap, config)
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py",
> line 161, in create
>     vm.construct(config)
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py",
> line 590, in construct
>     self.init_domain()
>   File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py",
> line 778, in init_domain
>     id = self.image.initDomain(self.id, self.memory, self.ssidref, cpu,
> self.cpu_weight)
>   File "/usr/lib/python2.4/site-packages/xen/xend/image.py", line 159,
> in initDomain
>     xc.domain_memory_increase_reservation(dom, mem_kb)
> error: (12, 'Cannot allocate memory')
> 
> 
> Regards,
> Ted
> 
> 
> _______________________________________________
The patch at 
http://lists.xensource.com/archives/html/xen-devel/2005-08/msg00649.html

seems to have resolved most of the issues I was seeing :-)

Regards,
Ted


_______________________________________________
Xen-devel mailing list
Xen-devel@xxxxxxxxxxxxxxxxxxx
http://lists.xensource.com/xen-devel