On Tue, 2006-09-26 at 18:07 -0400, Lon Hohberger wrote:
xend.log
-- Lon
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:188) XendDomainInfo.create(['vm', ['name', 'futon1'], ['memory', '384'], ['maxmem', '384'], ['vcpus', '1'], ['uuid', 'af214925-a0ef-32f9-ff49-771d55c9daf7'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']]], ['device', ['vbd', ['dev', 'xvda'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:3c:0d:ec'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']]]])
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:294) parseConfig: config is ['vm', ['name', 'futon1'], ['memory', '384'], ['maxmem', '384'], ['vcpus', '1'], ['uuid', 'af214925-a0ef-32f9-ff49-771d55c9daf7'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']]], ['device', ['vbd', ['dev', 'xvda'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:3c:0d:ec'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']]]]
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:393) parseConfig: result is {'shadow_memory': None, 'uuid': 'af214925-a0ef-32f9-ff49-771d55c9daf7', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'futon1', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 384, 'device': [('vbd', ['vbd', ['dev', 'xvda'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:3c:0d:ec'], ['bridge', 'xenbr0'], ['script', '/etc/xen/scripts/vif-bridge']])], 'bootloader': None, 'cpu': None, 'maxmem': 384}
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1228) XendDomainInfo.construct: None
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1260) XendDomainInfo.initDomain: 10 1.0
[2006-09-26 14:28:11 xend 3474] INFO (image:199) configuring linux guest
[2006-09-26 14:28:11 xend 3474] DEBUG (balloon:127) Balloon: 393216 KiB free; need 393216; done.
[2006-09-26 14:28:11 xend 3474] INFO (image:138) buildDomain os=linux dom=10 vcpus=1
[2006-09-26 14:28:11 xend 3474] DEBUG (image:178) dom = 10
[2006-09-26 14:28:11 xend 3474] DEBUG (image:179) image = /var/lib/xen/vmlinuz.pPOHFq
[2006-09-26 14:28:11 xend 3474] DEBUG (image:180) store_evtchn = 1
[2006-09-26 14:28:11 xend 3474] DEBUG (image:181) console_evtchn = 2
[2006-09-26 14:28:11 xend 3474] DEBUG (image:182) cmdline = method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386
[2006-09-26 14:28:11 xend 3474] DEBUG (image:183) ramdisk = /var/lib/xen/initrd.img.zXex8_
[2006-09-26 14:28:11 xend 3474] DEBUG (image:184) vcpus = 1
[2006-09-26 14:28:11 xend 3474] DEBUG (image:185) features =
[2006-09-26 14:28:11 xend 3474] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
[2006-09-26 14:28:11 xend 3474] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '51712', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/10/51712'} to /local/domain/10/device/vbd/51712.
[2006-09-26 14:28:11 xend 3474] DEBUG (DevController:112) DevController: writing {'domain': 'futon1', 'frontend': '/local/domain/10/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': '/tmp/futon1.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '10', 'type': 'file'} to /local/domain/0/backend/vbd/10/51712.
[2006-09-26 14:28:11 xend 3474] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:3c:0d:ec', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/10/0'} to /local/domain/10/device/vif/0.
[2006-09-26 14:28:11 xend 3474] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'futon1', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/10/device/vif/0', 'mac': '00:16:3e:3c:0d:ec', 'online': '1', 'frontend-id': '10'} to /local/domain/0/backend/vif/10/0.
[2006-09-26 14:28:11 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:708) Storing VM details: {'shadow_memory': '0', 'uuid': 'af214925-a0ef-32f9-ff49-771d55c9daf7', 'on_reboot': 'destroy', 'start_time': '1159295291.96', 'on_poweroff': 'destroy', 'name': 'futon1', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '384', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/vmlinuz.pPOHFq) (ramdisk /var/lib/xen/initrd.img.zXex8_) (args ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 '))", 'maxmem': '384'}
[2006-09-26 14:28:12 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:743) Storing domain details: {'console/ring-ref': '1836963', 'console/port': '2', 'name': 'futon1', 'console/limit': '1048576', 'vm': '/vm/af214925-a0ef-32f9-ff49-771d55c9daf7', 'domid': '10', 'cpu/0/availability': 'online', 'memory/target': '393216', 'store/ring-ref': '1836964', 'store/port': '1'}
[2006-09-26 14:28:12 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:929) XendDomainInfo.handleShutdownWatch
[2006-09-26 14:28:12 xend 3474] DEBUG (DevController:143) Waiting for devices vif.
[2006-09-26 14:28:12 xend 3474] DEBUG (DevController:149) Waiting for 0.
[2006-09-26 14:28:12 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/10/0/hotplug-status.
[2006-09-26 14:28:22 xend 3474] ERROR (SrvBase:87) Request wait_for_devices failed.
Traceback (most recent call last):
File "/usr/lib/python2.4/site-packages/xen/web/SrvBase.py", line 85, in perform
return op_method(op, req)
File "/usr/lib/python2.4/site-packages/xen/xend/server/SrvDomain.py", line 72, in op_wait_for_devices
return self.dom.waitForDevices()
File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py", line 1544, in waitForDevices
self.waitForDevices_(c)
File "/usr/lib/python2.4/site-packages/xen/xend/XendDomainInfo.py", line 1075, in waitForDevices_
return self.getDeviceController(deviceClass).waitForDevices()
File "/usr/lib/python2.4/site-packages/xen/xend/server/DevController.py", line 145, in waitForDevices
return map(self.waitForDevice, self.deviceIDs())
File "/usr/lib/python2.4/site-packages/xen/xend/server/DevController.py", line 155, in waitForDevice
raise VmError("Device %s (%s) could not be connected. "
VmError: Device 0 (vif) could not be connected. Hotplug scripts not working.
[2006-09-26 14:28:22 xend 3474] INFO (XendDomain:370) Domain futon1 (10) unpaused.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/10/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/10/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/8/0/hotplug-status.
[2006-09-26 14:28:31 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/10/0/hotplug-status.
[2006-09-26 14:28:58 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1415) XendDomainInfo.destroy: domid=10
[2006-09-26 14:28:58 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1423) XendDomainInfo.destroyDomain(10)
[2006-09-26 14:28:59 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:188) XendDomainInfo.create(['vm', ['name', 'futon1'], ['memory', '384'], ['maxmem', '384'], ['vcpus', '1'], ['uuid', 'af214925a0ef32f9ff49771d55c9daf7'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']]], ['device', ['vbd', ['dev', 'xvda:disk'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']]]])
[2006-09-26 14:28:59 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:294) parseConfig: config is ['vm', ['name', 'futon1'], ['memory', '384'], ['maxmem', '384'], ['vcpus', '1'], ['uuid', 'af214925a0ef32f9ff49771d55c9daf7'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']]], ['device', ['vbd', ['dev', 'xvda:disk'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']]]]
[2006-09-26 14:28:59 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:393) parseConfig: result is {'shadow_memory': None, 'uuid': 'af214925a0ef32f9ff49771d55c9daf7', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/vmlinuz.pPOHFq'], ['ramdisk', '/var/lib/xen/initrd.img.zXex8_'], ['args', ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 ']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'futon1', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 384, 'device': [('vbd', ['vbd', ['dev', 'xvda:disk'], ['uname', 'file:/tmp/futon1.dsk'], ['mode', 'w']])], 'bootloader': None, 'cpu': None, 'maxmem': 384}
[2006-09-26 14:28:59 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1228) XendDomainInfo.construct: None
[2006-09-26 14:28:59 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:1260) XendDomainInfo.initDomain: 11 1.0
[2006-09-26 14:28:59 xend 3474] INFO (image:199) configuring linux guest
[2006-09-26 14:28:59 xend 3474] DEBUG (balloon:127) Balloon: 393216 KiB free; need 393216; done.
[2006-09-26 14:28:59 xend 3474] INFO (image:138) buildDomain os=linux dom=11 vcpus=1
[2006-09-26 14:28:59 xend 3474] DEBUG (image:178) dom = 11
[2006-09-26 14:28:59 xend 3474] DEBUG (image:179) image = /var/lib/xen/vmlinuz.pPOHFq
[2006-09-26 14:28:59 xend 3474] DEBUG (image:180) store_evtchn = 1
[2006-09-26 14:28:59 xend 3474] DEBUG (image:181) console_evtchn = 2
[2006-09-26 14:28:59 xend 3474] DEBUG (image:182) cmdline = method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386
[2006-09-26 14:28:59 xend 3474] DEBUG (image:183) ramdisk = /var/lib/xen/initrd.img.zXex8_
[2006-09-26 14:28:59 xend 3474] DEBUG (image:184) vcpus = 1
[2006-09-26 14:28:59 xend 3474] DEBUG (image:185) features =
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/8/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vif/10/0/hotplug-status.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:110) DevController: writing {'backend-id': '0', 'virtual-device': '51712', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/11/51712'} to /local/domain/11/device/vbd/51712.
[2006-09-26 14:28:59 xend 3474] DEBUG (DevController:112) DevController: writing {'domain': 'futon1', 'frontend': '/local/domain/11/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': '/tmp/futon1.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '11', 'type': 'file'} to /local/domain/0/backend/vbd/11/51712.
[2006-09-26 14:29:00 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:708) Storing VM details: {'shadow_memory': '0', 'uuid': 'af214925a0ef32f9ff49771d55c9daf7', 'on_reboot': 'destroy', 'start_time': '1159295340.05', 'on_poweroff': 'destroy', 'name': 'futon1', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '384', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/vmlinuz.pPOHFq) (ramdisk /var/lib/xen/initrd.img.zXex8_) (args ' method=nfs:bigpapi.boston.redhat.com:/vol/engineering/redhat/rhel5-i386 '))", 'maxmem': '384'}
[2006-09-26 14:29:00 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:743) Storing domain details: {'console/ring-ref': '1900963', 'console/port': '2', 'name': 'futon1', 'console/limit': '1048576', 'vm': '/vm/af214925a0ef32f9ff49771d55c9daf7', 'domid': '11', 'cpu/0/availability': 'online', 'memory/target': '393216', 'store/ring-ref': '1900964', 'store/port': '1'}
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices vif.
[2006-09-26 14:29:00 xend.XendDomainInfo 3474] DEBUG (XendDomainInfo:929) XendDomainInfo.handleShutdownWatch
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices usb.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices vbd.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:149) Waiting for 51712.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vbd/11/51712/hotplug-status.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:464) hotplugStatusCallback /local/domain/0/backend/vbd/11/51712/hotplug-status.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:478) hotplugStatusCallback 1.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices irq.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices pci.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices ioports.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices tap.
[2006-09-26 14:29:00 xend 3474] DEBUG (DevController:143) Waiting for devices vtpm.
[2006-09-26 14:29:00 xend 3474] INFO (XendDomain:370) Domain futon1 (11) unpaused.