[Pkg-xen-devel] Bug#389836: xend.log

[Bin Tian]

Skipped content of type multipart/alternative-------------- next part --------------
[2006-09-28 08:56:26 xend 4431] INFO (__init__:1072) Xend Daemon started
[2006-09-28 08:56:26 xend 4431] INFO (__init__:1072) Xend changeset: Thu Sep 21 17:56:14 2006 +0100 .
[2006-09-28 08:56:26 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 19408338149L, 'ssidref': 0, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'shutdown_reason': 0, 'dying': 0, 'dom': 0, 'mem_kb': 1927320, 'maxmem_kb': 17179869180, 'max_vcpu_id': 7, 'crashed': 0, 'running': 1, 'shutdown': 0, 'online_vcpus': 8, 'blocked': 0})
[2006-09-28 08:56:26 xend.XendDomainInfo 4431] INFO (__init__:1072) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
[2006-09-28 08:56:27 xend.XendDomainInfo 4431] WARNING (__init__:1072) No vm path in store for existing domain 0
[2006-09-28 08:56:27 xend.XendDomainInfo 4431] DEBUG (__init__:1072) Storing VM details: {'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'on_poweroff': 'destroy', 'name': 'Domain-0', 'xend/restart_count': '0', 'vcpus': '8', 'vcpu_avail': '255', 'memory': '1883', 'on_crash': 'restart', 'maxmem': '1883'}
[2006-09-28 08:56:27 xend.XendDomainInfo 4431] DEBUG (__init__:1072) Storing domain details: {'cpu/1/availability': 'online', 'cpu/3/availability': 'online', 'name': 'Domain-0', 'console/limit': '1048576', 'cpu/2/availability': 'online', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0', 'cpu/7/availability': 'online', 'cpu/0/availability': 'online', 'memory/target': '1928192', 'cpu/5/availability': 'online', 'cpu/6/availability': 'online', 'cpu/4/availability': 'online'}
[2006-09-28 08:56:27 xend 4431] DEBUG (__init__:1072) number of vcpus to use is 0
[2006-09-28 08:56:27 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.handleShutdownWatch
[2006-09-28 08:57:49 xend.XendDomainInfo 4431] DEBUG (__init__:1072) Setting memory target of domain Domain-0 (0) to 1024 MiB.
[2006-09-28 08:58:18 xend.util.security 4922] INFO (__init__:1072) Resource label file not found.
[2006-09-28 08:58:18 xend.util.security 4922] INFO (__init__:1072) Resource label file not found.
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.create(['vm', ['name', 'PlanetLab'], ['memory', 512], ['vcpus', 2], ['image', ['hvm', ['kernel', '/usr/lib/xen-3.0.2-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']]]])
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) parseConfig: config is ['vm', ['name', 'PlanetLab'], ['memory', 512], ['vcpus', 2], ['image', ['hvm', ['kernel', '/usr/lib/xen-3.0.2-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']]]]
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) parseConfig: result is {'shadow_memory': None, 'uuid': None, 'on_crash': None, 'on_reboot': None, 'localtime': None, 'image': ['hvm', ['kernel', '/usr/lib/xen-3.0.2-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']], 'on_poweroff': None, 'bootloader_args': None, 'cpus': None, 'name': 'PlanetLab', 'backend': [], 'vcpus': 2, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 512, 'device': [('vbd', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]), ('vbd', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]), ('vif', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']])], 'bootloader': None, 'cpu': None, 'maxmem': None}
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.construct: None
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.initDomain: 1 1.0
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: boot, val: d
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: fda, val: /home/sparrow/boot
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: fdb, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: soundhw, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: localtime, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: serial, val: pty
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: std-vga, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: isa, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: vcpus, val: 2
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: acpi, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: usb, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) args: usbdevice, val: None
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) Balloon: 1007232 KiB free; need 542928; done.
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] ERROR (__init__:1072) Domain construction failed
Traceback (most recent call last):
  File "/usr/lib/xen-3.0-unstable-1/bin/../lib/python/xen/xend/XendDomainInfo.py", line 195, in create
    vm.initDomain()
  File "/usr/lib/xen-3.0-unstable-1/bin/../lib/python/xen/xend/XendDomainInfo.py", line 1337, in initDomain
    channel_details = self.image.createImage()
  File "/usr/lib/xen-3.0-unstable-1/bin/../lib/python/xen/xend/image.py", line 119, in createImage
    return self.createDomain()
  File "/usr/lib/xen-3.0-unstable-1/bin/../lib/python/xen/xend/image.py", line 128, in createDomain
    raise VmError('Kernel image does not exist: %s' % self.kernel)
VmError: Kernel image does not exist: /usr/lib/xen-3.0.2-1/boot/hvmloader
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.destroy: domid=1
[2006-09-28 08:58:18 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.destroyDomain(1)
[2006-09-28 08:58:18 xend 4431] ERROR (__init__:1072) Unwatching hvm shutdown watch failed.
Traceback (most recent call last):
  File "/usr/lib/xen-3.0-unstable-1/bin/../lib/python/xen/xend/image.py", line 403, in unregister_shutdown_watch
    if self.shutdownWatch:
AttributeError: X86_HVM_ImageHandler instance has no attribute 'shutdownWatch'
[2006-09-28 08:58:18 xend 4431] DEBUG (__init__:1072) hvm shutdown watch unregistered
[2006-09-28 08:58:48 xend.util.security 4936] INFO (__init__:1072) Resource label file not found.
[2006-09-28 08:58:48 xend.util.security 4936] INFO (__init__:1072) Resource label file not found.
[2006-09-28 08:58:48 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.create(['vm', ['name', 'PlanetLab'], ['memory', 512], ['vcpus', 2], ['image', ['hvm', ['kernel', '/usr/lib/xen-3.0-unstable-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']]]])
[2006-09-28 08:58:48 xend.XendDomainInfo 4431] DEBUG (__init__:1072) parseConfig: config is ['vm', ['name', 'PlanetLab'], ['memory', 512], ['vcpus', 2], ['image', ['hvm', ['kernel', '/usr/lib/xen-3.0-unstable-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]], ['device', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']]]]
[2006-09-28 08:58:48 xend.XendDomainInfo 4431] DEBUG (__init__:1072) parseConfig: result is {'shadow_memory': None, 'uuid': None, 'on_crash': None, 'on_reboot': None, 'localtime': None, 'image': ['hvm', ['kernel', '/usr/lib/xen-3.0-unstable-1/boot/hvmloader'], ['vcpus', 2], ['boot', 'd'], ['fda', '/home/sparrow/boot'], ['serial', 'pty'], ['vnc', 1], ['vncunused', 1], ['xauthority', '/root/.Xauthority'], ['device_model', '/usr/lib64/xen/bin/qemu-dm']], 'on_poweroff': None, 'bootloader_args': None, 'cpus': None, 'name': 'PlanetLab', 'backend': [], 'vcpus': 2, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 512, 'device': [('vbd', ['vbd', ['uname', 'file:/small/xen-disks/debian/debian.img'], ['dev', 'ioemu:hda'], ['mode', 'w']]), ('vbd', ['vbd', ['uname', 'file:/small/xen-disks/debian/swap.img'], ['dev', 'ioemu:hdb'], ['mode', 'w']]), ('vif', ['vif', ['bridge', 'xenbr0'], ['mac', '00:16:3E:00:10:22'], ['type', 'ioemu']])], 'bootloader': None, 'cpu': None, 'maxmem': None}
[2006-09-28 08:58:48 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.construct: None
[2006-09-28 08:58:48 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.initDomain: 2 1.0
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: boot, val: d
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: fda, val: /home/sparrow/boot
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: fdb, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: soundhw, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: localtime, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: serial, val: pty
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: std-vga, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: isa, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: vcpus, val: 2
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: acpi, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: usb, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) args: usbdevice, val: None
[2006-09-28 08:58:48 xend 4431] DEBUG (__init__:1072) Balloon: 1007232 KiB free; need 542928; done.
[2006-09-28 08:58:49 xend 4431] INFO (__init__:1072) buildDomain os=hvm dom=2 vcpus=2
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) dom            = 2
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) image          = /usr/lib/xen-3.0-unstable-1/boot/hvmloader
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) store_evtchn   = 1
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) memsize        = 512
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) vcpus          = 2
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) pae            = 0
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) acpi           = 0
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) apic           = 0
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hvm shutdown watch registered
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/2/768'} to /local/domain/2/device/vbd/768.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'domain': 'PlanetLab', 'frontend': '/local/domain/2/device/vbd/768', 'dev': 'hda', 'state': '1', 'params': '/small/xen-disks/debian/debian.img', 'mode': 'w', 'online': '1', 'frontend-id': '2', 'type': 'file'} to /local/domain/0/backend/vbd/2/768.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) exception looking up device number for hdb: [Errno 2] No such file or directory: '/dev/hdb'
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'backend-id': '0', 'virtual-device': '832', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/2/832'} to /local/domain/2/device/vbd/832.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'domain': 'PlanetLab', 'frontend': '/local/domain/2/device/vbd/832', 'dev': 'hdb', 'state': '1', 'params': '/small/xen-disks/debian/swap.img', 'mode': 'w', 'online': '1', 'frontend-id': '2', 'type': 'file'} to /local/domain/0/backend/vbd/2/832.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/2/0'} to /local/domain/2/device/vif/0.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) DevController: writing {'bridge': 'xenbr0', 'domain': 'PlanetLab', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/2/device/vif/0', 'mac': '00:16:3E:00:10:22', 'online': '1', 'frontend-id': '2', 'type': 'ioemu'} to /local/domain/0/backend/vif/2/0.
[2006-09-28 08:58:49 xend 4431] INFO (__init__:1072) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '2', '-m', '512', '-boot', 'd', '-fda', '/home/sparrow/boot', '-serial', 'pty', '-vcpus', '2', '-domain-name', 'PlanetLab', '-net', 'nic,vlan=1,macaddr=00:16:3E:00:10:22,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-vnc', '2', '-k', 'en-us', '-vncunused']
[2006-09-28 08:58:49 xend 4431] INFO (__init__:1072) device model pid: 4959
[2006-09-28 08:58:49 xend.XendDomainInfo 4431] DEBUG (__init__:1072) Storing VM details: {'shadow_memory': '5', 'uuid': 'c334db3d-4d53-e46d-492c-a2f13b1b60c7', 'on_reboot': 'restart', 'start_time': '1159405129.25', 'on_poweroff': 'destroy', 'name': 'PlanetLab', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'memory': '512', 'on_crash': 'restart', 'image': '(hvm (kernel /usr/lib/xen-3.0-unstable-1/boot/hvmloader) (vcpus 2) (boot d) (fda /home/sparrow/boot) (serial pty) (vnc 1) (vncunused 1) (xauthority /root/.Xauthority) (device_model /usr/lib64/xen/bin/qemu-dm))', 'maxmem': '512'}
[2006-09-28 08:58:49 xend.XendDomainInfo 4431] DEBUG (__init__:1072) Storing domain details: {'console/port': '2', 'name': 'PlanetLab', 'console/limit': '1048576', 'vm': '/vm/c334db3d-4d53-e46d-492c-a2f13b1b60c7', 'domid': '2', 'cpu/0/availability': 'online', 'memory/target': '524288', 'store/ring-ref': '250529', 'cpu/1/availability': 'online', 'store/port': '1'}
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hvm_shutdown fired, shutdown reason=None
[2006-09-28 08:58:49 xend.XendDomainInfo 4431] DEBUG (__init__:1072) XendDomainInfo.handleShutdownWatch
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for devices vif.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for 0.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback 1.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for devices usb.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for devices vbd.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for 768.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback 1.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) Waiting for 832.
[2006-09-28 08:58:49 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback /local/domain/0/backend/vbd/2/832/hotplug-status.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback /local/domain/0/backend/vbd/2/832/hotplug-status.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) hotplugStatusCallback 1.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) Waiting for devices irq.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) Waiting for devices pci.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) Waiting for devices ioports.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) Waiting for devices tap.
[2006-09-28 08:58:50 xend 4431] DEBUG (__init__:1072) Waiting for devices vtpm.
[2006-09-28 08:58:50 xend 4431] INFO (__init__:1072) Domain PlanetLab (2) unpaused.