Attachment 312866 Details for Bug 456926 – nex daemon log

nex daemon log

xend.log (text/plain), 10.92 KB, created by Barry Donahue on 2008-07-29 13:05:35 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Barry Donahue

Created: 2008-07-29 13:05:35 UTC

Size: 10.92 KB

patch

obsolete

>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'w2k3_large'], ['memory', '8192'], ['maxmem', '8192'], ['vcpus', '4'], ['uuid', '306828d3-b7a5-f469-b2ee-554f91a7a853'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['vcpus', '4'], ['boot', 'd'], ['acpi', '1'], ['apic', '1'], ['pae', '1'], ['usb', '1'], ['serial', 'pty'], ['vnc', '1'], ['vncunused', '1']]], ['device', ['vbd', ['dev', 'hda:disk'], ['uname', 'file:/var/lib/xen/images/w2k3_large.img'], ['mode', 'w']]], ['device', ['vbd', ['dev', 'hdc:cdrom'], ['uname', 'file:/var/lib/xen/images/Win2003R2-64-disk1.iso'], ['mode', 'r']]], ['device', ['vif', ['mac', '00:16:3e:59:6b:14'], ['bridge', 'xenbr0']]]])
>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'w2k3_large'], ['memory', '8192'], ['maxmem', '8192'], ['vcpus', '4'], ['uuid', '306828d3-b7a5-f469-b2ee-554f91a7a853'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['vcpus', '4'], ['boot', 'd'], ['acpi', '1'], ['apic', '1'], ['pae', '1'], ['usb', '1'], ['serial', 'pty'], ['vnc', '1'], ['vncunused', '1']]], ['device', ['vbd', ['dev', 'hda:disk'], ['uname', 'file:/var/lib/xen/images/w2k3_large.img'], ['mode', 'w']]], ['device', ['vbd', ['dev', 'hdc:cdrom'], ['uname', 'file:/var/lib/xen/images/Win2003R2-64-disk1.iso'], ['mode', 'r']]], ['device', ['vif', ['mac', '00:16:3e:59:6b:14'], ['bridge', 'xenbr0']]]]
>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '306828d3-b7a5-f469-b2ee-554f91a7a853', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['hvm', ['kernel', '/usr/lib/xen/boot/hvmloader'], ['device_model', '/usr/lib64/xen/bin/qemu-dm'], ['vcpus', '4'], ['boot', 'd'], ['acpi', '1'], ['apic', '1'], ['pae', '1'], ['usb', '1'], ['serial', 'pty'], ['vnc', '1'], ['vncunused', '1']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'w2k3_large', 'backend': [], 'vcpus': 4, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 8192, 'device': [('vbd', ['vbd', ['dev', 'hda:disk'], ['uname', 'file:/var/lib/xen/images/w2k3_large.img'], ['mode', 'w']]), ('vbd', ['vbd', ['dev', 'hdc:cdrom'], ['uname', 'file:/var/lib/xen/images/Win2003R2-64-disk1.iso'], ['mode', 'r']]), ('vif', ['vif', ['mac', '00:16:3e:59:6b:14'], ['bridge', 'xenbr0']])], 'bootloader': None, 'cpu': None, 'maxmem': 8192}
>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:1350) XendDomainInfo.construct: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (balloon:127) Balloon: 8470024 KiB free; need 2048; done.
>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:1398) XendDomainInfo.initDomain: 15 1.0
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: boot, val: d
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: fda, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: fdb, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: soundhw, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: localtime, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: serial, val: pty
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: std-vga, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: isa, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: vcpus, val: 4
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: acpi, val: 1
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: usb, val: 1
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: usbdevice, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:316) args: k, val: None
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:376) No VNC passwd configured for vfb access
>[2008-06-20 16:16:06 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:1437) _initDomain:shadow_memory=0x0, maxmem=0x2000, memory=0x2000.
>[2008-06-20 16:16:06 xend 6144] DEBUG (balloon:127) Balloon: 8468976 KiB free; need 8466432; done.
>[2008-06-20 16:16:06 xend 6144] INFO (image:137) buildDomain os=None dom=15 vcpus=4
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:269) domid          = 15
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:270) image          = /usr/lib/xen/boot/hvmloader
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:271) store_evtchn   = 5
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:272) memsize        = 8192
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:273) vcpus          = 4
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:274) pae            = 1
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:275) acpi           = 1
>[2008-06-20 16:16:06 xend 6144] DEBUG (image:276) apic           = 1
>[2008-06-20 16:16:07 xend 6144] DEBUG (blkif:24) exception looking up device number for hda: [Errno 2] No such file or directory: '/dev/hda'
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/15/768'} to /local/domain/15/device/vbd/768.
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:112) DevController: writing {'domain': 'w2k3_large', 'frontend': '/local/domain/15/device/vbd/768', 'format': 'raw', 'dev': 'hda', 'state': '1', 'params': '/var/lib/xen/images/w2k3_large.img', 'mode': 'w', 'online': '1', 'frontend-id': '15', 'type': 'file'} to /local/domain/0/backend/vbd/15/768.
>[2008-06-20 16:16:07 xend 6144] DEBUG (blkif:24) exception looking up device number for hdc: [Errno 2] No such file or directory: '/dev/hdc'
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/15/5632'} to /local/domain/15/device/vbd/5632.
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:112) DevController: writing {'domain': 'w2k3_large', 'frontend': '/local/domain/15/device/vbd/5632', 'format': 'raw', 'dev': 'hdc', 'state': '1', 'params': '/var/lib/xen/images/Win2003R2-64-disk1.iso', 'mode': 'r', 'online': '1', 'frontend-id': '15', 'type': 'file'} to /local/domain/0/backend/vbd/15/5632.
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:59:6b:14', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/15/0'} to /local/domain/15/device/vif/0.
>[2008-06-20 16:16:07 xend 6144] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'w2k3_large', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/15/device/vif/0', 'mac': '00:16:3e:59:6b:14', 'online': '1', 'frontend-id': '15'} to /local/domain/0/backend/vif/15/0.
>[2008-06-20 16:16:07 xend 6144] INFO (image:420) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '15', '-m', '8192', '-boot', 'd', '-serial', 'pty', '-vcpus', '4', '-acpi', '-usb', '-domain-name', 'w2k3_large', '-net', 'nic,vlan=1,macaddr=00:16:3e:59:6b:14,model=rtl8139', '-net', 'tap,vlan=1,bridge=xenbr0', '-vnc', '127.0.0.1:15', '-vncunused']
>[2008-06-20 16:16:07 xend 6144] INFO (image:422) device model pid: 27769
>[2008-06-20 16:16:07 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '68', 'uuid': '306828d3-b7a5-f469-b2ee-554f91a7a853', 'on_reboot': 'destroy', 'start_time': '1213992967.96', 'on_poweroff': 'destroy', 'name': 'w2k3_large', 'xend/restart_count': '0', 'vcpus': '4', 'vcpu_avail': '15', 'memory': '8192', 'on_crash': 'destroy', 'image': '(hvm (kernel /usr/lib/xen/boot/hvmloader) (device_model /usr/lib64/xen/bin/qemu-dm) (vcpus 4) (boot d) (acpi 1) (apic 1) (pae 1) (usb 1) (serial pty) (vnc 1) (vncunused 1))', 'maxmem': '8192'}
>[2008-06-20 16:16:07 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:791) Storing domain details: {'console/port': '6', 'cpu/3/availability': 'online', 'name': 'w2k3_large', 'console/limit': '1048576', 'cpu/2/availability': 'online', 'vm': '/vm/306828d3-b7a5-f469-b2ee-554f91a7a853', 'domid': '15', 'cpu/0/availability': 'online', 'memory/target': '8388608', 'store/ring-ref': '983038', 'cpu/1/availability': 'online', 'store/port': '5'}
>[2008-06-20 16:16:08 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:989) XendDomainInfo.handleShutdownWatch
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:143) Waiting for devices vif.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:149) Waiting for 0.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/15/0/hotplug-status.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/15/0/hotplug-status.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:143) Waiting for devices usb.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:143) Waiting for devices vbd.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:149) Waiting for 768.
>[2008-06-20 16:16:08 xend 6144] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vbd/15/768/hotplug-status.
>[2008-06-20 16:17:48 xend 6144] ERROR (SrvBase:88) Request wait_for_devices failed.
>Traceback (most recent call last):
>  File "/usr/lib64/python2.4/site-packages/xen/web/SrvBase.py", line 85, in perform
>    return op_method(op, req)
>  File "/usr/lib64/python2.4/site-packages/xen/xend/server/SrvDomain.py", line 72, in op_wait_for_devices
>    return self.dom.waitForDevices()
>  File "/usr/lib64/python2.4/site-packages/xen/xend/XendDomainInfo.py", line 1763, in waitForDevices
>    self.waitForDevices_(c)
>  File "/usr/lib64/python2.4/site-packages/xen/xend/XendDomainInfo.py", line 1159, in waitForDevices_
>    return self.getDeviceController(deviceClass).waitForDevices()
>  File "/usr/lib64/python2.4/site-packages/xen/xend/server/DevController.py", line 145, in waitForDevices
>    return map(self.waitForDevice, self.deviceIDs())
>  File "/usr/lib64/python2.4/site-packages/xen/xend/server/DevController.py", line 155, in waitForDevice
>    raise VmError("Device %s (%s) could not be connected. "
>VmError: Device 768 (vbd) could not be connected. Hotplug scripts not working.
>[2008-06-20 16:17:50 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:1560) XendDomainInfo.destroy: domid=15
>[2008-06-20 16:17:50 xend.XendDomainInfo 6144] DEBUG (XendDomainInfo:1568) XendDomainInfo.destroyDomain(15)
>[2008-06-20 16:19:01 xend 6144] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vbd/15/768/hotplug-status.
>[2008-06-20 16:19:01 xend 6144] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2008-06-20 16:19:02 xend 6144] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/8/0/hotplug-status.

Actions: View

Attachments on bug 456926: 312866 | 312868 | 320467 | 320469 | 320470 | 340523 | 344923 | 344924 | 344926