Attachment 368611 Details for Bug 613490

xend.log

xend.log (text/x-log), 15.99 KB, created by Henry Laurent on 2010-06-11 09:42:46 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Henry Laurent

Created: 2010-06-11 09:42:46 UTC

Size: 15.99 KB

patch

obsolete

>[2010-06-04 11:01:44 28892] DEBUG (SrvDomain:76) Starting domain cas-11.2 False
>[2010-06-04 11:01:44 28892] DEBUG (XendDomainInfo:2324) XendDomainInfo.constructDomain
>[2010-06-04 11:01:44 28892] DEBUG (balloon:185) Balloon: 1649172 KiB free; need 4096; done.
>[2010-06-04 11:01:44 28892] DEBUG (XendDomain:453) Adding Domain: 122
>[2010-06-04 11:01:44 28892] DEBUG (XendDomainInfo:2525) XendDomainInfo.initDomain: 122 256
>[2010-06-04 11:01:44 14878] DEBUG (XendBootloader:130) Launching bootloader as ['/usr/lib/xen/boot/domUloader.py', '--args= ', '--output=/var/run/xend/boot/xenbl.26554', '--entry=xvda1:/boot/vmlinuz-xen,/boot/initrd-xen', '/var/lib/xen/images/cas-11.2/disk0-slash'].
>[2010-06-04 11:01:50 28892] DEBUG (image:322) No VNC passwd configured for vfb access
>[2010-06-04 11:01:50 28892] DEBUG (XendDomainInfo:2549) _initDomain:shadow_memory=0x0, memory_static_max=0x32700000, memory_static_min=0x0.
>[2010-06-04 11:01:50 28892] DEBUG (balloon:185) Balloon: 1649060 KiB free; need 397824; done.
>[2010-06-04 11:01:50 28892] INFO (image:173) buildDomain os=linux dom=122 vcpus=1
>[2010-06-04 11:01:50 28892] DEBUG (image:670) domid          = 122
>[2010-06-04 11:01:50 28892] DEBUG (image:671) memsize        = 384
>[2010-06-04 11:01:50 28892] DEBUG (image:672) image          = /var/lib/xen/tmp/kernel.3GbmY0
>[2010-06-04 11:01:50 28892] DEBUG (image:673) store_evtchn   = 1
>[2010-06-04 11:01:50 28892] DEBUG (image:674) console_evtchn = 2
>[2010-06-04 11:01:50 28892] DEBUG (image:675) cmdline        =  
>[2010-06-04 11:01:50 28892] DEBUG (image:676) ramdisk        = /var/lib/xen/tmp/ramdisk.wd4nm0
>[2010-06-04 11:01:50 28892] DEBUG (image:677) vcpus          = 1
>[2010-06-04 11:01:50 28892] DEBUG (image:678) features       = 
>[2010-06-04 11:01:50 28892] DEBUG (image:679) flags          = 0
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: vkbd : {'uuid': '3b693ad1-ca11-6e97-1e38-b671c56a344d', 'backend': 0}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'protocol': 'x86_64-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/122/0'} to /local/domain/122/device/vkbd/0.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'frontend-id': '122', 'domain': 'cas-11.2', 'frontend': '/local/domain/122/device/vkbd/0', 'state': '1', 'online': '1'} to /local/domain/0/backend/vkbd/122/0.
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: console : {'protocol': 'vt100', 'location': '2', 'uuid': '9bea138b-bb52-f24d-7bd0-9daf45ccdf10'}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'protocol': 'x86_64-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/122/0'} to /local/domain/122/device/console/0.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'domain': 'cas-11.2', 'frontend': '/local/domain/122/device/console/0', 'uuid': '9bea138b-bb52-f24d-7bd0-9daf45ccdf10', 'frontend-id': '122', 'state': '1', 'location': '2', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/122/0.
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: vfb : {'vncunused': '1', 'vnc': '1', 'xauthority': '/root/.Xauthority', 'other_config': {'vncunused': '1', 'vnc': '1', 'xauthority': '/root/.Xauthority'}, 'location': '127.0.0.1:5901', 'uuid': 'e73d2bed-fade-f091-5c8c-d03823b7b392'}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'protocol': 'x86_64-abi', 'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/122/0'} to /local/domain/122/device/vfb/0.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'cas-11.2', 'frontend': '/local/domain/122/device/vfb/0', 'xauthority': '/root/.Xauthority', 'frontend-id': '122', 'state': '1', 'location': '127.0.0.1:5901', 'online': '1', 'vnc': '1', 'uuid': 'e73d2bed-fade-f091-5c8c-d03823b7b392'} to /local/domain/0/backend/vfb/122/0.
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: tap : {'protocol': 'x86_64-abi', 'uuid': '37f92a6d-9f4c-93dc-b89f-beaef405637a', 'bootable': 1, 'dev': 'xvda:disk', 'uname': 'tap:aio:/var/lib/xen/images/cas-11.2/disk0-slash', 'mode': 'w', 'backend': 0}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'virtual-device': '51712', 'protocol': 'x86_64-abi', 'device-type': 'disk', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/122/51712'} to /local/domain/122/device/vbd/51712.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'domain': 'cas-11.2', 'frontend': '/local/domain/122/device/vbd/51712', 'uuid': '37f92a6d-9f4c-93dc-b89f-beaef405637a', 'bootable': '1', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/cas-11.2/disk0-slash', 'mode': 'w', 'online': '1', 'frontend-id': '122', 'type': 'tap'} to /local/domain/0/backend/tap/122/51712.
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: tap : {'protocol': 'x86_64-abi', 'uuid': '62821478-812f-e24e-bd12-7da1d2ac790c', 'bootable': 0, 'dev': 'xvdb:disk', 'uname': 'tap:aio:/var/lib/xen/images/cas-11.2/disk1-swap', 'mode': 'w', 'backend': 0}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'virtual-device': '51728', 'protocol': 'x86_64-abi', 'device-type': 'disk', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/122/51728'} to /local/domain/122/device/vbd/51728.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'domain': 'cas-11.2', 'frontend': '/local/domain/122/device/vbd/51728', 'uuid': '62821478-812f-e24e-bd12-7da1d2ac790c', 'bootable': '0', 'dev': 'xvdb', 'state': '1', 'params': 'aio:/var/lib/xen/images/cas-11.2/disk1-swap', 'mode': 'w', 'online': '1', 'frontend-id': '122', 'type': 'tap'} to /local/domain/0/backend/tap/122/51728.
>[2010-06-04 11:01:50 28892] INFO (XendDomainInfo:2188) createDevice: vif : {'bridge': 'br0', 'mac': '00:16:3e:28:36:4b', 'script': '/etc/xen/scripts/vif-bridge', 'uuid': 'f0d75c76-0706-74c7-db23-a9b3b51a241f', 'backend': 0}
>[2010-06-04 11:01:50 28892] DEBUG (DevController:95) DevController: writing {'mac': '00:16:3e:28:36:4b', 'handle': '0', 'protocol': 'x86_64-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/122/0'} to /local/domain/122/device/vif/0.
>[2010-06-04 11:01:50 28892] DEBUG (DevController:97) DevController: writing {'bridge': 'br0', 'domain': 'cas-11.2', 'handle': '0', 'uuid': 'f0d75c76-0706-74c7-db23-a9b3b51a241f', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:28:36:4b', 'frontend-id': '122', 'state': '1', 'online': '1', 'frontend': '/local/domain/122/device/vif/0'} to /local/domain/0/backend/vif/122/0.
>[2010-06-04 11:01:51 28892] INFO (image:394) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '122', '-domain-name', 'cas-11.2', '-videoram', '4', '-vnc', '127.0.0.1:0', '-vncunused', '-serial', 'pty', '-M', 'xenpv']
>[2010-06-04 11:01:51 28892] INFO (image:443) device model pid: 15328
>[2010-06-04 11:01:51 28892] INFO (image:542) waiting for sentinel_fifo
>[2010-06-04 11:01:51 28892] DEBUG (XendDomainInfo:3099) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid': '863c2f23-6728-2298-8fe3-a812988f6f97', 'on_reboot': 'restart', 'start_time': '1275642111.03', 'on_poweroff': 'destroy', 'bootloader_args': '--entry=xvda1:/boot/vmlinuz-xen,/boot/initrd-xen', 'on_xend_start': 'ignore', 'on_crash': 'destroy', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '384', 'bootloader': '/usr/lib/xen/boot/domUloader.py', 'image': "(linux (kernel ) (args ' ') (videoram 4) (device_model /usr/lib64/xen/bin/qemu-dm) (notes (FEATURES 'writable_page_tables|writable_descriptor_tables|auto_translated_physmap|pae_pgdir_above_4gb|supervisor_mode_kernel') (VIRT_BASE 18446744071562067968) (GUEST_VERSION 2.6) (PADDR_OFFSET 0) (GUEST_OS linux) (HYPERCALL_PAGE 18446744071562100736) (LOADER generic) (INIT_P2M 18446719884453740544) (SUSPEND_CANCEL 1) (ENTRY 18446744071562076160) (XEN_VERSION xen-3.0)))", 'name': 'cas-11.2'}
>[2010-06-04 11:01:51 28892] DEBUG (XendDomainInfo:1646) Storing domain details: {'console/ring-ref': '5714662', 'image/entry': '18446744071562076160', 'console/port': '2', 'store/ring-ref': '5714663', 'image/loader': 'generic', 'vm': '/vm/863c2f23-6728-2298-8fe3-a812988f6f97', 'control/platform-feature-multiprocessor-suspend': '1', 'image/guest-os': 'linux', 'image/features/writable-descriptor-tables': '1', 'image/virt-base': '18446744071562067968', 'memory/target': '393216', 'image/guest-version': '2.6', 'image/features/supervisor-mode-kernel': '1', 'console/limit': '1048576', 'image/paddr-offset': '0', 'image/hypercall-page': '18446744071562100736', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'image/features/pae-pgdir-above-4gb': '1', 'image/features/writable-page-tables': '1', 'console/type': 'ioemu', 'image/features/auto-translated-physmap': '1', 'name': 'cas-11.2', 'domid': '122', 'image/xen-version': 'xen-3.0', 'image/init-p2m': '18446719884453740544', 'store/port': '1'}
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vif.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:144) Waiting for 0.
>[2010-06-04 11:01:51 28892] DEBUG (XendDomainInfo:1733) XendDomainInfo.handleShutdownWatch
>[2010-06-04 11:01:51 28892] DEBUG (DevController:654) hotplugStatusCallback /local/domain/0/backend/vif/122/0/hotplug-status.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:668) hotplugStatusCallback 1.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vkbd.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices ioports.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices tap.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:144) Waiting for 51712.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:654) hotplugStatusCallback /local/domain/0/backend/tap/122/51712/hotplug-status.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:668) hotplugStatusCallback 1.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:144) Waiting for 51728.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:654) hotplugStatusCallback /local/domain/0/backend/tap/122/51728/hotplug-status.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:668) hotplugStatusCallback 1.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices console.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:144) Waiting for 0.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vscsi.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vbd.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices irq.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vfb.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices pci.
>[2010-06-04 11:01:51 28892] DEBUG (DevController:139) Waiting for devices vtpm.
>[2010-06-04 11:04:42 28892] INFO (XendDomainInfo:1919) Domain has shutdown: name=smtp3a id=121 reason=reboot.
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2757) XendDomainInfo.destroy: domid=121
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2227) Destroying device model
>[2010-06-04 11:04:42 28892] INFO (image:567) smtp3a device model terminated
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2234) Releasing devices
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vif/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vkbd/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vkbd, device = vkbd/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing console/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vbd/51712
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51712
>[2010-06-04 11:04:42 28892] ERROR (XendConfig:1115) dumping sxp from device controllers
>Traceback (most recent call last):
>  File "/usr/lib64/python2.6/site-packages/xen/xend/XendConfig.py", line 1100, in to_sxp
>    configs = controller.configurations(txn)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in configurations
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in <lambda>
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 249, in configuration
>    configDict = self.getDeviceConfiguration(devid, transaction)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/ConsoleController.py", line 27, in getDeviceConfiguration
>    devinfo = self.readBackendTxn(transaction, devid, *self.valid_cfg)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 447, in readBackendTxn
>    raise VmError("Device %s not connected" % devid)
>VmError: Device 0 not connected
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vbd/51728
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51728
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vbd/51744
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/51744
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:2247) Removing vfb/0
>[2010-06-04 11:04:42 28892] DEBUG (XendDomainInfo:1137) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
>[2010-06-04 11:04:42 28892] ERROR (XendConfig:1115) dumping sxp from device controllers
>Traceback (most recent call last):
>  File "/usr/lib64/python2.6/site-packages/xen/xend/XendConfig.py", line 1100, in to_sxp
>    configs = controller.configurations(txn)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in configurations
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in <lambda>
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 249, in configuration
>    configDict = self.getDeviceConfiguration(devid, transaction)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/netif.py", line 194, in getDeviceConfiguration
>    y = self.readBackendTxn(transaction, devid, x)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 447, in readBackendTxn
>    raise VmError("Device %s not connected" % devid)
>VmError: Device 0 not connected
>[2010-06-04 11:04:42 28892] ERROR (XendConfig:1115) dumping sxp from device controllers
>Traceback (most recent call last):
>  File "/usr/lib64/python2.6/site-packages/xen/xend/XendConfig.py", line 1100, in to_sxp
>    configs = controller.configurations(txn)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in configurations
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 242, in <lambda>
>    return map(lambda x: self.configuration(x, transaction), self.deviceIDs(transaction))
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 249, in configuration
>    configDict = self.getDeviceConfiguration(devid, transaction)
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/blkif.py", line 163, in getDeviceConfiguration
>    'bootable')
>  File "/usr/lib64/python2.6/site-packages/xen/xend/server/DevController.py", line 447, in readBackendTxn
>    raise VmError("Device %s not connected" % devid)
>VmError: Device 51744 not connected

Actions: View

Attachments on bug 613490: 368610 | 368611 | 368612