Attachment 260681 Details for Bug 386151 – xend log of failed installation due to network problem

xend log of failed installation due to network problem

xend.log (text/plain), 14.18 KB, created by Adam Greenberg on 2007-11-16 02:40:55 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Adam Greenberg

Created: 2007-11-16 02:40:55 UTC

Size: 14.18 KB

patch

obsolete

>[2007-11-15 21:11:51 2814] DEBUG (XendDomainInfo:875) Storing domain details: {'console/ring-ref': '2777647', 'image/entry': '3238002688', 'control/platform-feature-multiprocessor-suspend': '1', 'image/loader': 'generic', 'vm': '/vm/74b597cf-7f6b-b23f-beb5-036b7b503062', 'store/ring-ref': '2777648', 'image/hv-start-low': '4118806528', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '3221225472', 'memory/target': '1024000', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'image/pae-mode': 'yes', 'console/limit': '1048576', 'image/paddr-offset': '3221225472', 'image/hypercall-page': '3238006784', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'image/features/auto-translated-physmap': '1', 'name': 'fed8p', 'domid': '3', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
>[2007-11-15 21:11:51 2814] DEBUG (XendDomainInfo:1713) XendDomainInfo.destroy: domid=3
>[2007-11-15 21:11:51 2814] DEBUG (XendDomainInfo:1730) XendDomainInfo.destroyDomain(3)
>[2007-11-15 21:11:51 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:11:51 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:11:51 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-11-15 21:11:52 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-11-15 21:11:52 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:11:52 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-11-15 21:11:52 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-11-15 21:11:52 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:13:11 2814] DEBUG (XendDomainInfo:78) XendDomainInfo.create(['vm', ['name', 'fed8p'], ['memory', '1000'], ['maxmem', '1000'], ['vcpus', '1'], ['uuid', '1250bdf6-27c1-55b0-df8c-af8db590084f'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.AH_5bk'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.t-hzzE'], ['args', ' method=http://download.fedora.redhat.com/pub/fedora/linux/releases/8/Fedora/i386/os/']]], ['device', ['vbd', ['dev', 'xvda'], ['uname', 'file:/var/lib/xen/images/fed8p'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:0b:5a:23'], ['bridge', 'eth0']]], ['device', ['vkbd']], ['device', ['vfb', ['type', 'vnc'], ['vncunused', '1']]]])
>[2007-11-15 21:13:11 2814] DEBUG (XendDomainInfo:1465) XendDomainInfo.constructDomain
>[2007-11-15 21:13:11 2814] DEBUG (balloon:113) Balloon: 1024548 KiB free; need 2048; done.
>[2007-11-15 21:13:11 2814] DEBUG (XendDomain:443) Adding Domain: 4
>[2007-11-15 21:13:11 2814] DEBUG (XendDomainInfo:1519) XendDomainInfo.initDomain: 4 256
>[2007-11-15 21:13:11 2814] DEBUG (XendDomainInfo:1551) _initDomain:shadow_memory=0x0, memory_static_max=0x3e800000, memory_static_min=0x0.
>[2007-11-15 21:13:11 2814] DEBUG (balloon:113) Balloon: 1024548 KiB free; need 1024000; done.
>[2007-11-15 21:13:11 2814] INFO (image:129) buildDomain os=linux dom=4 vcpus=1
>[2007-11-15 21:13:11 2814] DEBUG (image:198) domid          = 4
>[2007-11-15 21:13:11 2814] DEBUG (image:199) memsize        = 1000
>[2007-11-15 21:13:11 2814] DEBUG (image:200) image          = /var/lib/xen/virtinst-vmlinuz.AH_5bk
>[2007-11-15 21:13:11 2814] DEBUG (image:201) store_evtchn   = 1
>[2007-11-15 21:13:11 2814] DEBUG (image:202) console_evtchn = 2
>[2007-11-15 21:13:11 2814] DEBUG (image:203) cmdline        =  method=http://download.fedora.redhat.com/pub/fedora/linux/releases/8/Fedora/i386/os/
>[2007-11-15 21:13:11 2814] DEBUG (image:204) ramdisk        = /var/lib/xen/virtinst-initrd.img.t-hzzE
>[2007-11-15 21:13:11 2814] DEBUG (image:205) vcpus          = 1
>[2007-11-15 21:13:11 2814] DEBUG (image:206) features       = 
>[2007-11-15 21:13:11 2814] INFO (XendDomainInfo:1367) createDevice: vkbd : {'uuid': '4f7fc690-d706-bba2-f1a9-0f3cea63a7e2'}
>[2007-11-15 21:13:11 2814] DEBUG (DevController:115) DevController: writing {'protocol': 'x86_32-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/4/0'} to /local/domain/4/device/vkbd/0.
>[2007-11-15 21:13:11 2814] DEBUG (DevController:117) DevController: writing {'frontend-id': '4', 'domain': 'fed8p', 'frontend': '/local/domain/4/device/vkbd/0', 'state': '1', 'online': '1'} to /local/domain/0/backend/vkbd/4/0.
>[2007-11-15 21:13:11 2814] INFO (XendDomainInfo:1367) createDevice: vfb : {'vncunused': '1', 'other_config': {'vncunused': '1', 'type': 'vnc'}, 'type': 'vnc', 'uuid': 'ed90dca2-2b01-424e-dc93-db70493a10e2'}
>[2007-11-15 21:13:11 2814] DEBUG (DevController:115) DevController: writing {'protocol': 'x86_32-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/4/0'} to /local/domain/4/device/vfb/0.
>[2007-11-15 21:13:11 2814] DEBUG (DevController:117) DevController: writing {'vncunused': '1', 'domain': 'fed8p', 'frontend': '/local/domain/4/device/vfb/0', 'uuid': 'ed90dca2-2b01-424e-dc93-db70493a10e2', 'state': '1', 'online': '1', 'frontend-id': '4', 'type': 'vnc'} to /local/domain/0/backend/vfb/4/0.
>[2007-11-15 21:13:11 2814] DEBUG (vfbif:72) No VNC passwd configured for vfb access
>[2007-11-15 21:13:11 2814] INFO (XendDomainInfo:1367) createDevice: vbd : {'uuid': '2a8b90df-b7bb-2ca8-adf5-aa62affcf7f3', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'xvda', 'uname': 'file:/var/lib/xen/images/fed8p', 'mode': 'w'}
>[2007-11-15 21:13:11 2814] DEBUG (DevController:115) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vbd/4/51712'} to /local/domain/4/device/vbd/51712.
>[2007-11-15 21:13:11 2814] DEBUG (DevController:117) DevController: writing {'domain': 'fed8p', 'frontend': '/local/domain/4/device/vbd/51712', 'uuid': '2a8b90df-b7bb-2ca8-adf5-aa62affcf7f3', 'dev': 'xvda', 'state': '1', 'params': '/var/lib/xen/images/fed8p', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'file'} to /local/domain/0/backend/vbd/4/51712.
>[2007-11-15 21:13:11 2814] INFO (XendDomainInfo:1367) createDevice: vif : {'bridge': 'eth0', 'mac': '00:16:3e:0b:5a:23', 'uuid': '32273c06-ac49-983a-393e-d020d5da283f'}
>[2007-11-15 21:13:11 2814] DEBUG (DevController:115) DevController: writing {'mac': '00:16:3e:0b:5a:23', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/4/0'} to /local/domain/4/device/vif/0.
>[2007-11-15 21:13:11 2814] DEBUG (DevController:117) DevController: writing {'bridge': 'eth0', 'domain': 'fed8p', 'handle': '0', 'uuid': '32273c06-ac49-983a-393e-d020d5da283f', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/4/device/vif/0', 'mac': '00:16:3e:0b:5a:23', 'online': '1', 'frontend-id': '4'} to /local/domain/0/backend/vif/4/0.
>[2007-11-15 21:13:11 2814] DEBUG (XendDomainInfo:2026) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid': '1250bdf6-27c1-55b0-df8c-af8db590084f', 'on_reboot': 'destroy', 'start_time': '1195179191.98', 'on_poweroff': 'destroy', 'on_xend_start': 'ignore', 'on_crash': 'destroy', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'image': "(linux (kernel /var/lib/xen/virtinst-vmlinuz.AH_5bk) (ramdisk /var/lib/xen/virtinst-initrd.img.t-hzzE) (args ' method=http://download.fedora.redhat.com/pub/fedora/linux/releases/8/Fedora/i386/os/') (notes (HV_START_LOW 4118806528) (FEATURES 'writable_page_tables|writable_descriptor_tables|auto_translated_physmap|pae_pgdir_above_4gb|supervisor_mode_kernel') (VIRT_BASE 3221225472) (GUEST_VERSION 2.6) (PADDR_OFFSET 3221225472) (GUEST_OS linux) (HYPERCALL_PAGE 3238006784) (LOADER generic) (SUSPEND_CANCEL 1) (PAE_MODE yes) (ENTRY 3238002688) (XEN_VERSION xen-3.0)))", 'name': 'fed8p'}
>[2007-11-15 21:13:12 2814] DEBUG (XendDomainInfo:875) Storing domain details: {'console/ring-ref': '2777647', 'image/entry': '3238002688', 'console/port': '2', 'store/ring-ref': '2777648', 'image/loader': 'generic', 'vm': '/vm/1250bdf6-27c1-55b0-df8c-af8db590084f', 'control/platform-feature-multiprocessor-suspend': '1', 'image/hv-start-low': '4118806528', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '3221225472', 'memory/target': '1024000', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'image/pae-mode': 'yes', 'console/limit': '1048576', 'image/paddr-offset': '3221225472', 'image/hypercall-page': '3238006784', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'image/features/auto-translated-physmap': '1', 'name': 'fed8p', 'domid': '4', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
>[2007-11-15 21:13:12 2814] DEBUG (XendDomainInfo:875) Storing domain details: {'console/ring-ref': '2777647', 'image/entry': '3238002688', 'control/platform-feature-multiprocessor-suspend': '1', 'image/loader': 'generic', 'vm': '/vm/1250bdf6-27c1-55b0-df8c-af8db590084f', 'store/ring-ref': '2777648', 'image/hv-start-low': '4118806528', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '3221225472', 'memory/target': '1024000', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'image/pae-mode': 'yes', 'console/limit': '1048576', 'image/paddr-offset': '3221225472', 'image/hypercall-page': '3238006784', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'image/features/auto-translated-physmap': '1', 'name': 'fed8p', 'domid': '4', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
>[2007-11-15 21:13:12 2814] DEBUG (XendDomainInfo:959) XendDomainInfo.handleShutdownWatch
>[2007-11-15 21:13:12 2814] DEBUG (DevController:148) Waiting for devices vif.
>[2007-11-15 21:13:12 2814] DEBUG (DevController:153) Waiting for 0.
>[2007-11-15 21:13:12 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-11-15 21:13:18 2814] INFO (XendDomain:1123) Domain fed8p (4) unpaused.
>[2007-11-15 21:14:41 2814] DEBUG (XendDomainInfo:445) XendDomainInfo.shutdown(halt)
>[2007-11-15 21:14:41 2814] DEBUG (XendDomainInfo:959) XendDomainInfo.handleShutdownWatch
>[2007-11-15 21:14:41 2814] DEBUG (XendDomainInfo:959) XendDomainInfo.handleShutdownWatch
>[2007-11-15 21:14:46 2814] INFO (XendDomainInfo:1141) Domain has shutdown: name=fed8p id=4 reason=poweroff.
>[2007-11-15 21:14:46 2814] DEBUG (XendDomainInfo:875) Storing domain details: {'console/ring-ref': '2777647', 'image/entry': '3238002688', 'control/platform-feature-multiprocessor-suspend': '1', 'image/loader': 'generic', 'vm': '/vm/1250bdf6-27c1-55b0-df8c-af8db590084f', 'store/ring-ref': '2777648', 'image/hv-start-low': '4118806528', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '3221225472', 'memory/target': '1024000', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'image/pae-mode': 'yes', 'console/limit': '1048576', 'image/paddr-offset': '3221225472', 'image/hypercall-page': '3238006784', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'image/features/auto-translated-physmap': '1', 'name': 'fed8p', 'domid': '4', 'image/xen-version': 'xen-3.0', 'store/port': '1'}
>[2007-11-15 21:14:46 2814] DEBUG (XendDomainInfo:1713) XendDomainInfo.destroy: domid=4
>[2007-11-15 21:14:46 2814] DEBUG (XendDomainInfo:1730) XendDomainInfo.destroyDomain(4)
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-11-15 21:14:47 2814] DEBUG (DevController:558) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-11-15 21:14:52 2814] ERROR (SrvBase:88) Request wait_for_devices failed.
>Traceback (most recent call last):
>  File "/usr/lib/python2.5/site-packages/xen/web/SrvBase.py", line 85, in perform
>    return op_method(op, req)
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/SrvDomain.py", line 85, in op_wait_for_devices
>    return self.dom.waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/XendDomainInfo.py", line 546, in waitForDevices
>    self.getDeviceController(devclass).waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 149, in waitForDevices
>    return map(self.waitForDevice, self.deviceIDs())
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 161, in waitForDevice
>    self.destroyDevice(devid, False)
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 216, in destroyDevice
>    self.writeBackend(devid, 'online', "0")
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 434, in writeBackend
>    raise VmError("Device %s not connected" % devid)
>VmError: Device 0 not connected

Actions: View

Attachments on bug 386151: 260681 | 260701 | 262081