Attachment 289421 Details for Bug 425411 – Full xend.log when trying to save an i386 domain

Full xend.log when trying to save an i386 domain

xend.log (text/plain), 46.97 KB, created by Chris Lalancette on 2007-12-14 20:31:22 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chris Lalancette

Created: 2007-12-14 20:31:22 UTC

Size: 46.97 KB

patch

obsolete

>[2007-12-14 14:30:08 xend 3800] INFO (SrvDaemon:283) Xend Daemon started
>[2007-12-14 14:30:08 xend 3800] INFO (SrvDaemon:287) Xend changeset: unavailable.
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:222) XendDomainInfo.recreate({'paused': 0, 'cpu_time': 26712116967L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'mem_kb': 8120552L, 'domid': 0, 'max_vcpu_id': 7, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'online_vcpus': 8, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0})
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] INFO (XendDomainInfo:234) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000.
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] WARNING (XendDomainInfo:256) No vm path in store for existing domain 0
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) 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': '7931', 'on_crash': 'restart', 'maxmem': '7931'}
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) 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': '8121344', 'cpu/5/availability': 'online', 'cpu/6/availability': 'online', 'cpu/4/availability': 'online'}
>[2007-12-14 14:30:08 xend 3800] DEBUG (XendDomain:153) number of vcpus to use is 0
>[2007-12-14 14:30:08 xend 3800] INFO (SrvServer:116) unix path=/var/lib/xend/xend-socket
>[2007-12-14 14:30:08 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'rhel5pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '63089e43-bfb9-f2ff-132c-49112f7b0201'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.-LMcnM'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.DIzmHJ'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel5-server-i386']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']]]])
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'rhel5pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '63089e43-bfb9-f2ff-132c-49112f7b0201'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.-LMcnM'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.DIzmHJ'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel5-server-i386']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']]]]
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '63089e43-bfb9-f2ff-132c-49112f7b0201', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.-LMcnM'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.DIzmHJ'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel5-server-i386']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'rhel5pvi386', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 1024, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']])], 'bootloader': None, 'cpu': None, 'maxmem': 1024}
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1349) XendDomainInfo.construct: None
>[2007-12-14 14:56:16 xend 3800] DEBUG (balloon:127) Balloon: 130280 KiB free; need 2048; done.
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1397) XendDomainInfo.initDomain: 1 1.0
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1436) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2007-12-14 14:56:16 xend 3800] DEBUG (balloon:133) Balloon: 130272 KiB free; 0 to scrub; need 1048576; retries: 20.
>[2007-12-14 14:56:16 xend 3800] DEBUG (balloon:148) Balloon: setting dom0 target to 7034 MiB.
>[2007-12-14 14:56:16 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1078) Setting memory target of domain Domain-0 (0) to 7034 MiB.
>[2007-12-14 14:56:17 xend 3800] DEBUG (balloon:127) Balloon: 1048800 KiB free; need 1048576; done.
>[2007-12-14 14:56:17 xend 3800] INFO (image:136) buildDomain os=linux dom=1 vcpus=1
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:201) domid          = 1
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:202) memsize        = 1024
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:203) image          = /var/lib/xen/virtinst-vmlinuz.-LMcnM
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:204) store_evtchn   = 1
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:205) console_evtchn = 2
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:206) cmdline        =   method=nfs:bigpapi:/vol/engineering/redhat/rhel5-server-i386
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:207) ramdisk        = /var/lib/xen/virtinst-initrd.img.DIzmHJ
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:208) vcpus          = 1
>[2007-12-14 14:56:17 xend 3800] DEBUG (image:209) features       = 
>[2007-12-14 14:56:18 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/1/51712'} to /local/domain/1/device/vbd/51712.
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'rhel5pvi386', 'frontend': '/local/domain/1/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel5pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '1', 'type': 'tap'} to /local/domain/0/backend/tap/1/51712.
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:47:67:4e', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'rhel5pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/1/device/vif/0', 'mac': '00:16:3e:47:67:4e', 'online': '1', 'frontend-id': '1'} to /local/domain/0/backend/vif/1/0.
>[2007-12-14 14:56:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '0', 'uuid': '63089e43-bfb9-f2ff-132c-49112f7b0201', 'on_reboot': 'destroy', 'start_time': '1197662178.89', 'on_poweroff': 'destroy', 'name': 'rhel5pvi386', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '1024', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/virtinst-vmlinuz.-LMcnM) (ramdisk /var/lib/xen/virtinst-initrd.img.DIzmHJ) (args ' method=nfs:bigpapi:/vol/engineering/redhat/rhel5-server-i386'))", 'maxmem': '1024'}
>[2007-12-14 14:56:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '2211306', 'console/port': '2', 'name': 'rhel5pvi386', 'console/limit': '1048576', 'vm': '/vm/63089e43-bfb9-f2ff-132c-49112f7b0201', 'domid': '1', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '2211307', 'store/port': '1'}
>[2007-12-14 14:56:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:143) Waiting for devices vif.
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:149) Waiting for 0.
>[2007-12-14 14:56:18 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices usb.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices irq.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices pci.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices tap.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:149) Waiting for 51712.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/tap/1/51712/hotplug-status.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 14:57:17 xend 3800] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-12-14 14:57:17 xend 3800] INFO (XendDomain:370) Domain rhel5pvi386 (1) unpaused.
>[2007-12-14 15:01:17 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '0d18d0e7-e38f-6903-06e5-b4530a284081'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.UqINrD'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.fd6hCz'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:04:8d:bc'], ['bridge', 'xenbr0']]]])
>[2007-12-14 15:01:17 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '0d18d0e7-e38f-6903-06e5-b4530a284081'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.UqINrD'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.fd6hCz'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:04:8d:bc'], ['bridge', 'xenbr0']]]]
>[2007-12-14 15:01:17 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '0d18d0e7-e38f-6903-06e5-b4530a284081', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.UqINrD'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.fd6hCz'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'rhel4pvi386', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 1024, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:04:8d:bc'], ['bridge', 'xenbr0']])], 'bootloader': None, 'cpu': None, 'maxmem': 1024}
>[2007-12-14 15:01:17 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1349) XendDomainInfo.construct: None
>[2007-12-14 15:01:17 xend 3800] DEBUG (balloon:133) Balloon: 236 KiB free; 0 to scrub; need 2048; retries: 20.
>[2007-12-14 15:01:17 xend 3800] DEBUG (balloon:148) Balloon: setting dom0 target to 7032 MiB.
>[2007-12-14 15:01:17 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1078) Setting memory target of domain Domain-0 (0) to 7032 MiB.
>[2007-12-14 15:01:18 xend 3800] DEBUG (balloon:127) Balloon: 2284 KiB free; need 2048; done.
>[2007-12-14 15:01:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1397) XendDomainInfo.initDomain: 2 1.0
>[2007-12-14 15:01:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1436) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2007-12-14 15:01:18 xend 3800] DEBUG (balloon:133) Balloon: 2276 KiB free; 0 to scrub; need 1048576; retries: 20.
>[2007-12-14 15:01:18 xend 3800] DEBUG (balloon:148) Balloon: setting dom0 target to 6010 MiB.
>[2007-12-14 15:01:18 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1078) Setting memory target of domain Domain-0 (0) to 6010 MiB.
>[2007-12-14 15:01:19 xend 3800] DEBUG (balloon:127) Balloon: 1048804 KiB free; need 1048576; done.
>[2007-12-14 15:01:19 xend 3800] INFO (image:136) buildDomain os=linux dom=2 vcpus=1
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:201) domid          = 2
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:202) memsize        = 1024
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:203) image          = /var/lib/xen/virtinst-vmlinuz.UqINrD
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:204) store_evtchn   = 1
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:205) console_evtchn = 2
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:206) cmdline        =   method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:207) ramdisk        = /var/lib/xen/virtinst-initrd.img.fd6hCz
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:208) vcpus          = 1
>[2007-12-14 15:01:19 xend 3800] DEBUG (image:209) features       = 
>[2007-12-14 15:01:19 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 15:01:19 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/2/51712'} to /local/domain/2/device/vbd/51712.
>[2007-12-14 15:01:19 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'rhel4pvi386', 'frontend': '/local/domain/2/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel4pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '2', 'type': 'tap'} to /local/domain/0/backend/tap/2/51712.
>[2007-12-14 15:01:20 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:04:8d:bc', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/2/0'} to /local/domain/2/device/vif/0.
>[2007-12-14 15:01:20 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'rhel4pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/2/device/vif/0', 'mac': '00:16:3e:04:8d:bc', 'online': '1', 'frontend-id': '2'} to /local/domain/0/backend/vif/2/0.
>[2007-12-14 15:01:20 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '0', 'uuid': '0d18d0e7-e38f-6903-06e5-b4530a284081', 'on_reboot': 'destroy', 'start_time': '1197662480.69', 'on_poweroff': 'destroy', 'name': 'rhel4pvi386', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '1024', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/virtinst-vmlinuz.UqINrD) (ramdisk /var/lib/xen/virtinst-initrd.img.fd6hCz) (args ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS'))", 'maxmem': '1024'}
>[2007-12-14 15:01:21 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '656633', 'console/port': '2', 'name': 'rhel4pvi386', 'console/limit': '1048576', 'vm': '/vm/0d18d0e7-e38f-6903-06e5-b4530a284081', 'domid': '2', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '656549', 'store/port': '1'}
>[2007-12-14 15:01:22 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:01:22 xend 3800] DEBUG (DevController:143) Waiting for devices vif.
>[2007-12-14 15:01:23 xend 3800] DEBUG (DevController:149) Waiting for 0.
>[2007-12-14 15:01:23 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices usb.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices irq.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices pci.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices tap.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:149) Waiting for 51712.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/tap/2/51712/hotplug-status.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:02:16 xend 3800] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-12-14 15:02:16 xend 3800] INFO (XendDomain:370) Domain rhel4pvi386 (2) unpaused.
>[2007-12-14 15:02:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1557) XendDomainInfo.destroy: domid=2
>[2007-12-14 15:02:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1566) XendDomainInfo.destroyDomain(2)
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '3f5d743b-5cf5-f5db-0c62-231a1b547baa'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.5hu_yj'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.rUeepd'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']]]])
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '3f5d743b-5cf5-f5db-0c62-231a1b547baa'], ['on_poweroff', 'destroy'], ['on_reboot', 'destroy'], ['on_crash', 'destroy'], ['image', ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.5hu_yj'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.rUeepd'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']]], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']]]]
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'on_crash': 'destroy', 'on_reboot': 'destroy', 'localtime': None, 'image': ['linux', ['kernel', '/var/lib/xen/virtinst-vmlinuz.5hu_yj'], ['ramdisk', '/var/lib/xen/virtinst-initrd.img.rUeepd'], ['args', ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS']], 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'rhel4pvi386', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 1024, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']])], 'bootloader': None, 'cpu': None, 'maxmem': 1024}
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1349) XendDomainInfo.construct: None
>[2007-12-14 15:03:06 xend 3800] DEBUG (balloon:127) Balloon: 1049028 KiB free; need 2048; done.
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1397) XendDomainInfo.initDomain: 3 1.0
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1436) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2007-12-14 15:03:06 xend 3800] DEBUG (balloon:127) Balloon: 1049020 KiB free; need 1048576; done.
>[2007-12-14 15:03:06 xend 3800] INFO (image:136) buildDomain os=linux dom=3 vcpus=1
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:201) domid          = 3
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:202) memsize        = 1024
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:203) image          = /var/lib/xen/virtinst-vmlinuz.5hu_yj
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:204) store_evtchn   = 1
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:205) console_evtchn = 2
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:206) cmdline        =   method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:207) ramdisk        = /var/lib/xen/virtinst-initrd.img.rUeepd
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:208) vcpus          = 1
>[2007-12-14 15:03:06 xend 3800] DEBUG (image:209) features       = 
>[2007-12-14 15:03:06 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 15:03:06 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/3/51712'} to /local/domain/3/device/vbd/51712.
>[2007-12-14 15:03:06 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'rhel4pvi386', 'frontend': '/local/domain/3/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel4pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '3', 'type': 'tap'} to /local/domain/0/backend/tap/3/51712.
>[2007-12-14 15:03:06 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:6e:bf:5d', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/3/0'} to /local/domain/3/device/vif/0.
>[2007-12-14 15:03:06 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'rhel4pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/3/device/vif/0', 'mac': '00:16:3e:6e:bf:5d', 'online': '1', 'frontend-id': '3'} to /local/domain/0/backend/vif/3/0.
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '0', 'uuid': '3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'on_reboot': 'destroy', 'start_time': '1197662586.93', 'on_poweroff': 'destroy', 'name': 'rhel4pvi386', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '1024', 'on_crash': 'destroy', 'image': "(linux (kernel /var/lib/xen/virtinst-vmlinuz.5hu_yj) (ramdisk /var/lib/xen/virtinst-initrd.img.rUeepd) (args ' method=nfs:bigpapi:/vol/engineering/redhat/rhel4-u6-i386-AS'))", 'maxmem': '1024'}
>[2007-12-14 15:03:06 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '648419', 'console/port': '2', 'name': 'rhel4pvi386', 'console/limit': '1048576', 'vm': '/vm/3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'domid': '3', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '649631', 'store/port': '1'}
>[2007-12-14 15:03:07 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices vif.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:149) Waiting for 0.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices usb.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices irq.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices pci.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices tap.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:149) Waiting for 51712.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/tap/3/51712/hotplug-status.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:03:07 xend 3800] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-12-14 15:03:07 xend 3800] INFO (XendDomain:370) Domain rhel4pvi386 (3) unpaused.
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel4pvi386 id=3 reason=reboot.
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1557) XendDomainInfo.destroy: domid=3
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1566) XendDomainInfo.destroyDomain(3)
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=1 reason=reboot.
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1557) XendDomainInfo.destroy: domid=1
>[2007-12-14 15:12:54 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1566) XendDomainInfo.destroyDomain(1)
>[2007-12-14 15:12:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '3f5d743b5cf5f5db0c62231a1b547baa'], ['bootloader', '/usr/bin/pygrub'], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']]]])
>[2007-12-14 15:12:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'rhel4pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '3f5d743b5cf5f5db0c62231a1b547baa'], ['bootloader', '/usr/bin/pygrub'], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']]]]
>[2007-12-14 15:12:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'on_crash': 'restart', 'on_reboot': 'restart', 'localtime': None, 'image': None, 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'rhel4pvi386', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 1024, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel4pvi386.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:6e:bf:5d'], ['bridge', 'xenbr0']])], 'bootloader': '/usr/bin/pygrub', 'cpu': None, 'maxmem': 1024}
>[2007-12-14 15:12:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1349) XendDomainInfo.construct: None
>[2007-12-14 15:12:55 xend 3800] DEBUG (balloon:127) Balloon: 2097252 KiB free; need 2048; done.
>[2007-12-14 15:12:55 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1397) XendDomainInfo.initDomain: 4 1.0
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1436) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2007-12-14 15:12:56 xend 3800] DEBUG (balloon:127) Balloon: 2097284 KiB free; need 1048576; done.
>[2007-12-14 15:12:56 xend 3800] INFO (image:136) buildDomain os=linux dom=4 vcpus=1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:201) domid          = 4
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:202) memsize        = 1024
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:203) image          = /var/lib/xen/boot_kernel.l-1u1s
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:204) store_evtchn   = 1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:205) console_evtchn = 2
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:206) cmdline        =  ro root=/dev/VolGroup00/LogVol00 console=xvc0 rhgb quiet
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:207) ramdisk        = /var/lib/xen/boot_ramdisk.lw_jKC
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:208) vcpus          = 1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:209) features       = 
>[2007-12-14 15:12:56 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 15:12:56 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/4/51712'} to /local/domain/4/device/vbd/51712.
>[2007-12-14 15:12:56 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'rhel4pvi386', 'frontend': '/local/domain/4/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel4pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'tap'} to /local/domain/0/backend/tap/4/51712.
>[2007-12-14 15:12:56 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:6e:bf:5d', '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-12-14 15:12:56 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'rhel4pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/4/device/vif/0', 'mac': '00:16:3e:6e:bf:5d', 'online': '1', 'frontend-id': '4'} to /local/domain/0/backend/vif/4/0.
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '0', 'uuid': '3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'on_reboot': 'restart', 'start_time': '1197663176.45', 'on_poweroff': 'destroy', 'name': 'rhel4pvi386', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '1024', 'on_crash': 'restart', 'image': "(linux (kernel /var/lib/xen/boot_kernel.l-1u1s) (ramdisk /var/lib/xen/boot_ramdisk.lw_jKC) (args 'ro root=/dev/VolGroup00/LogVol00 console=xvc0 rhgb quiet'))", 'maxmem': '1024'}
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '2210669', 'console/port': '2', 'name': 'rhel4pvi386', 'console/limit': '1048576', 'vm': '/vm/3f5d743b-5cf5-f5db-0c62-231a1b547baa', 'domid': '4', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '2210670', 'store/port': '1'}
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:200) XendDomainInfo.create(['vm', ['name', 'rhel5pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '63089e43bfb9f2ff132c49112f7b0201'], ['bootloader', '/usr/bin/pygrub'], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']]]])
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:306) parseConfig: config is ['vm', ['name', 'rhel5pvi386'], ['memory', '1024'], ['maxmem', '1024'], ['vcpus', '1'], ['uuid', '63089e43bfb9f2ff132c49112f7b0201'], ['bootloader', '/usr/bin/pygrub'], ['on_poweroff', 'destroy'], ['on_reboot', 'restart'], ['on_crash', 'restart'], ['device', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]], ['device', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']]]]
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:411) parseConfig: result is {'shadow_memory': None, 'start_time': None, 'uuid': '63089e43-bfb9-f2ff-132c-49112f7b0201', 'on_crash': 'restart', 'on_reboot': 'restart', 'localtime': None, 'image': None, 'on_poweroff': 'destroy', 'bootloader_args': None, 'cpus': None, 'name': 'rhel5pvi386', 'backend': [], 'vcpus': 1, 'cpu_weight': None, 'features': None, 'vcpu_avail': None, 'memory': 1024, 'device': [('tap', ['tap', ['dev', 'xvda'], ['uname', 'tap:aio:/var/lib/xen/images/rhel5pvi386.dsk'], ['mode', 'w']]), ('vif', ['vif', ['mac', '00:16:3e:47:67:4e'], ['bridge', 'xenbr0']])], 'bootloader': '/usr/bin/pygrub', 'cpu': None, 'maxmem': 1024}
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1349) XendDomainInfo.construct: None
>[2007-12-14 15:12:56 xend 3800] DEBUG (balloon:127) Balloon: 1048688 KiB free; need 2048; done.
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1397) XendDomainInfo.initDomain: 5 1.0
>[2007-12-14 15:12:56 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1436) _initDomain:shadow_memory=0x0, maxmem=0x400, memory=0x400.
>[2007-12-14 15:12:56 xend 3800] DEBUG (balloon:127) Balloon: 1048680 KiB free; need 1048576; done.
>[2007-12-14 15:12:56 xend 3800] INFO (image:136) buildDomain os=linux dom=5 vcpus=1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:201) domid          = 5
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:202) memsize        = 1024
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:203) image          = /var/lib/xen/boot_kernel.8RIoRj
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:204) store_evtchn   = 1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:205) console_evtchn = 2
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:206) cmdline        =  ro root=/dev/VolGroup00/LogVol00 console=xvc0 rhgb quiet
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:207) ramdisk        = /var/lib/xen/boot_ramdisk.PSgUQU
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:208) vcpus          = 1
>[2007-12-14 15:12:56 xend 3800] DEBUG (image:209) features       = 
>[2007-12-14 15:12:57 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/5/51712'} to /local/domain/5/device/vbd/51712.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'rhel5pvi386', 'frontend': '/local/domain/5/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel5pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '5', 'type': 'tap'} to /local/domain/0/backend/tap/5/51712.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:47:67:4e', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/5/0'} to /local/domain/5/device/vif/0.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'rhel5pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/5/device/vif/0', 'mac': '00:16:3e:47:67:4e', 'online': '1', 'frontend-id': '5'} to /local/domain/0/backend/vif/5/0.
>[2007-12-14 15:12:57 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:756) Storing VM details: {'shadow_memory': '0', 'uuid': '63089e43-bfb9-f2ff-132c-49112f7b0201', 'on_reboot': 'restart', 'start_time': '1197663177.28', 'on_poweroff': 'destroy', 'name': 'rhel5pvi386', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'memory': '1024', 'on_crash': 'restart', 'image': "(linux (kernel /var/lib/xen/boot_kernel.8RIoRj) (ramdisk /var/lib/xen/boot_ramdisk.PSgUQU) (args 'ro root=/dev/VolGroup00/LogVol00 console=xvc0 rhgb quiet'))", 'maxmem': '1024'}
>[2007-12-14 15:12:57 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '569661', 'console/port': '2', 'name': 'rhel5pvi386', 'console/limit': '1048576', 'vm': '/vm/63089e43-bfb9-f2ff-132c-49112f7b0201', 'domid': '5', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '569662', 'store/port': '1'}
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vif.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:149) Waiting for 0.
>[2007-12-14 15:12:57 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices usb.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices irq.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices pci.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices tap.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:149) Waiting for 51712.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/tap/4/51712/hotplug-status.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-12-14 15:12:57 xend 3800] INFO (XendDomain:370) Domain rhel4pvi386 (4) unpaused.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vif.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:149) Waiting for 0.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/vif/5/0/hotplug-status.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices usb.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vbd.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices irq.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vkbd.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vfb.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices pci.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices ioports.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices tap.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:149) Waiting for 51712.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:476) hotplugStatusCallback /local/domain/0/backend/tap/5/51712/hotplug-status.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:490) hotplugStatusCallback 1.
>[2007-12-14 15:12:57 xend 3800] DEBUG (DevController:143) Waiting for devices vtpm.
>[2007-12-14 15:12:57 xend 3800] INFO (XendDomain:370) Domain rhel5pvi386 (5) unpaused.
>[2007-12-14 15:23:30 xend 3800] DEBUG (XendCheckpoint:89) [xc_save]: /usr/lib64/xen/bin/xc_save 4 5 0 0 0
>[2007-12-14 15:23:30 xend 3800] DEBUG (XendCheckpoint:322) suspend
>[2007-12-14 15:23:30 xend 3800] DEBUG (XendCheckpoint:92) In saveInputHandler suspend
>[2007-12-14 15:23:30 xend 3800] DEBUG (XendCheckpoint:94) Suspending 5 ...
>[2007-12-14 15:23:30 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:23:30 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:23:30 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=migrating-rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:23:30 xend 3800] INFO (XendCheckpoint:99) Domain 5 suspended.
>[2007-12-14 15:23:30 xend 3800] DEBUG (XendCheckpoint:108) Written done
>[2007-12-14 15:23:32 xend 3800] INFO (XendCheckpoint:351) ERROR Internal error: Timed out waiting for frame list updated.
>[2007-12-14 15:23:32 xend 3800] INFO (XendCheckpoint:351) ERROR Internal error: Failed to map/save the p2m frame list
>[2007-12-14 15:23:32 xend 3800] INFO (XendCheckpoint:351) Save exit rc=1
>[2007-12-14 15:23:32 xend 3800] ERROR (XendCheckpoint:133) Save failed on domain rhel5pvi386 (5).
>Traceback (most recent call last):
>  File "/usr/lib64/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 110, in save
>    forkHelper(cmd, fd, saveInputHandler, False)
>  File "/usr/lib64/python2.4/site-packages/xen/xend/XendCheckpoint.py", line 339, in forkHelper
>    raise XendError("%s failed" % string.join(cmd))
>XendError: /usr/lib64/xen/bin/xc_save 4 5 0 0 0 failed
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1598) XendDomainInfo.resumeDomain(5)
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] INFO (XendDomainInfo:720) Dev 0 still active, looping...
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1611) XendDomainInfo.resumeDomain: devices released
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:988) XendDomainInfo.handleShutdownWatch
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:791) Storing domain details: {'console/ring-ref': '569661', 'console/port': '2', 'name': 'migrating-rhel5pvi386', 'console/limit': '1048576', 'vm': '/vm/63089e43-bfb9-f2ff-132c-49112f7b0201', 'domid': '5', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'store/ring-ref': '569662', 'store/port': '1'}
>[2007-12-14 15:23:32 xend 3800] DEBUG (blkif:24) exception looking up device number for xvda: [Errno 2] No such file or directory: '/dev/xvda'
>[2007-12-14 15:23:32 xend 3800] DEBUG (DevController:110) DevController: writing {'virtual-device': '51712', 'device-type': 'disk', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/tap/5/51712'} to /local/domain/5/device/vbd/51712.
>[2007-12-14 15:23:32 xend 3800] DEBUG (DevController:112) DevController: writing {'domain': 'migrating-rhel5pvi386', 'frontend': '/local/domain/5/device/vbd/51712', 'dev': 'xvda', 'state': '1', 'params': 'aio:/var/lib/xen/images/rhel5pvi386.dsk', 'mode': 'w', 'online': '1', 'frontend-id': '5', 'type': 'tap'} to /local/domain/0/backend/tap/5/51712.
>[2007-12-14 15:23:32 xend 3800] DEBUG (DevController:110) DevController: writing {'mac': '00:16:3e:47:67:4e', 'handle': '0', 'protocol': 'x86_32-abi', 'backend-id': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/5/0'} to /local/domain/5/device/vif/0.
>[2007-12-14 15:23:32 xend 3800] DEBUG (DevController:112) DevController: writing {'bridge': 'xenbr0', 'domain': 'migrating-rhel5pvi386', 'handle': '0', 'script': '/etc/xen/scripts/vif-bridge', 'state': '1', 'frontend': '/local/domain/5/device/vif/0', 'mac': '00:16:3e:47:67:4e', 'online': '1', 'frontend-id': '5'} to /local/domain/0/backend/vif/5/0.
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] DEBUG (XendDomainInfo:1623) XendDomainInfo.resumeDomain: devices created
>[2007-12-14 15:23:32 xend.XendDomainInfo 3800] ERROR (XendDomainInfo:1628) XendDomainInfo.resume: xc.domain_resume failed on domain 5.
>Traceback (most recent call last):
>  File "/usr/lib64/python2.4/site-packages/xen/xend/XendDomainInfo.py", line 1625, in resumeDomain
>    xc.domain_resume(self.domid, fast)
>Error: (1, 'Internal error', "Couldn't map p2m_frame_list_list")
>[2007-12-14 15:23:32 xend 3800] DEBUG (XendCheckpoint:136) XendCheckpoint.save: resumeDomain
>[2007-12-14 15:23:39 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:23:39 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:23:39 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:23:39 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:15 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:15 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:15 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:15 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:20 xend 3800] INFO (XendDomain:370) Domain rhel5pvi386 (5) unpaused.
>[2007-12-14 15:28:21 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:21 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:21 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:21 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.
>[2007-12-14 15:28:29 xend.XendDomainInfo 3800] INFO (XendDomainInfo:947) Domain has shutdown: name=rhel5pvi386 id=5 reason=suspend.

Actions: View

Attachments on bug 425411: 289421 | 295727 | 295728 | 295729 | 329322