Attachment 2774 Details for Bug 20481 – /var/log/xen/xend.log

/var/log/xen/xend.log

xend.log (text/plain), 31.90 KB, created by Arvid Requate on 2010-10-22 10:01:20 CEST

(hide)

Description:

Filename:

MIME Type:

Creator: Arvid Requate

Created: 2010-10-22 10:01:20 CEST

Size: 31.90 KB

patch

obsolete

>[2010-10-21 09:18:10 5016] INFO (SrvDaemon:332) Xend Daemon started
>[2010-10-21 09:18:10 5016] INFO (SrvDaemon:336) Xend changeset: unavailable.
>[2010-10-21 09:18:11 5016] DEBUG (XendDomainInfo:140) XendDomainInfo.recreate({'max_vcpu_id': 1, 'cpu_time': 36230377000L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'online_vcpus': 2, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'mem_kb': 3404032L, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0, 'name': 'Domain-0'})
>[2010-10-21 09:18:11 5016] INFO (XendDomainInfo:158) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
>[2010-10-21 09:18:11 5016] DEBUG (XendDomainInfo:3067) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'image': '(linux (kernel ))', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'bootloader': '', 'name': 'Domain-0'}
>[2010-10-21 09:18:11 5016] DEBUG (XendDomainInfo:1629) Storing domain details: {'name': 'Domain-0', 'console/limit': '1048576', 'memory/target': '3404032', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0', 'cpu/0/availability': 'online', 'cpu/1/availability': 'online', 'control/platform-feature-multiprocessor-suspend': '1', 'console/type': 'xenconsoled'}
>[2010-10-21 09:18:11 5016] DEBUG (XendDomain:453) Adding Domain: 0
>[2010-10-21 09:18:11 5016] DEBUG (XendDomain:387) number of vcpus to use is 0
>[2010-10-21 09:18:11 5016] DEBUG (XendDomainInfo:1716) XendDomainInfo.handleShutdownWatch
>[2010-10-21 09:18:11 5016] INFO (SrvServer:177) unix path=/var/lib/xend/xend-socket
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VBD.set_device not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VBD.set_type not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: session.get_all_records not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: event.get_record not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: event.get_all not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VIF.get_network not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VIF.set_device not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VIF.set_MAC not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: VIF.set_MTU not found
>[2010-10-21 09:18:11 5016] WARNING (XendAPI:701) API call: debug.get_all not found
>[2010-10-21 09:18:11 5016] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xen-api.sock; authentication has been disabled for this server.
>[2010-10-21 09:18:11 5016] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
>[2010-10-19 04:19:08 5016] DEBUG (SrvServer:76) SrvServer.cleanup()
>[2010-10-19 04:19:08 5016] DEBUG (XMLRPCServer:236) XMLRPCServer.cleanup()
>[2010-10-19 04:19:08 5016] DEBUG (XMLRPCServer:236) XMLRPCServer.cleanup()
>[2010-10-19 04:19:08 5016] DEBUG (XendDomain:622) cleanup_domains
>[2010-10-19 04:19:08 5014] INFO (SrvDaemon:220) Xend exited with status 0.
>[2010-10-19 04:23:50 5192] INFO (SrvDaemon:332) Xend Daemon started
>[2010-10-19 04:23:50 5192] INFO (SrvDaemon:336) Xend changeset: unavailable.
>[2010-10-19 04:23:52 5192] DEBUG (XendDomainInfo:140) XendDomainInfo.recreate({'max_vcpu_id': 1, 'cpu_time': 39031418475L, 'ssidref': 0, 'hvm': 0, 'shutdown_reason': 0, 'dying': 0, 'online_vcpus': 2, 'domid': 0, 'paused': 0, 'crashed': 0, 'running': 1, 'maxmem_kb': 17179869180L, 'shutdown': 0, 'mem_kb': 3404032L, 'handle': [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0], 'blocked': 0, 'name': 'Domain-0'})
>[2010-10-19 04:23:52 5192] INFO (XendDomainInfo:158) Recreating domain 0, UUID 00000000-0000-0000-0000-000000000000. at /local/domain/0
>[2010-10-19 04:23:52 5192] DEBUG (XendDomainInfo:3067) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '0', 'uuid': '00000000-0000-0000-0000-000000000000', 'on_reboot': 'restart', 'image': '(linux (kernel ))', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '2', 'vcpu_avail': '3', 'bootloader': '', 'name': 'Domain-0'}
>[2010-10-19 04:23:52 5192] DEBUG (XendDomainInfo:1629) Storing domain details: {'name': 'Domain-0', 'console/limit': '1048576', 'memory/target': '3404032', 'vm': '/vm/00000000-0000-0000-0000-000000000000', 'domid': '0', 'cpu/0/availability': 'online', 'cpu/1/availability': 'online', 'control/platform-feature-multiprocessor-suspend': '1', 'console/type': 'xenconsoled'}
>[2010-10-19 04:23:52 5192] DEBUG (XendDomain:453) Adding Domain: 0
>[2010-10-19 04:23:52 5192] DEBUG (XendDomain:387) number of vcpus to use is 0
>[2010-10-19 04:23:52 5192] DEBUG (XendDomainInfo:1716) XendDomainInfo.handleShutdownWatch
>[2010-10-19 04:23:52 5192] INFO (SrvServer:177) unix path=/var/lib/xend/xend-socket
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VBD.set_device not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VBD.set_type not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: session.get_all_records not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: event.get_record not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: event.get_all not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VIF.get_network not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VIF.set_device not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VIF.set_MAC not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: VIF.set_MTU not found
>[2010-10-19 04:23:52 5192] WARNING (XendAPI:701) API call: debug.get_all not found
>[2010-10-19 04:23:52 5192] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xen-api.sock; authentication has been disabled for this server.
>[2010-10-19 04:23:52 5192] INFO (XMLRPCServer:156) Opening Unix domain socket XML-RPC server on /var/run/xend/xmlrpc.sock.
>[2010-10-19 04:41:47 5192] DEBUG (XendDomainInfo:249) XendDomainInfo.createDormant({'vcpus_params': {'cap': 0, 'weight': 256}, 'PV_args': '', 'features': '', 'cpus': [[]], 'devices': {'55f17ee8-60d3-0826-1d6b-947bd0b61424': ('vfb', {'vncunused': '1', 'other_config': {'vncunused': '1', 'vnclisten': '0.0.0.0', 'keymap': 'de'}, 'vnc': 1, 'uuid': '55f17ee8-60d3-0826-1d6b-947bd0b61424', 'vnclisten': '0.0.0.0', 'keymap': 'de'}), '681c9320-fe4c-bc36-96ec-737883bcf842': ('vbd', {'uuid': '681c9320-fe4c-bc36-96ec-737883bcf842', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname': 'file:/var/lib/libvirt/images/hdimage_xen_3.4.3_neu_aktiviert_gplpv_maerz', 'mode': 'w'}), '118cc14b-fe69-018b-fdc8-d9308e3f9a7a': ('vkbd', {'uuid': '118cc14b-fe69-018b-fdc8-d9308e3f9a7a'}), 'e7cce477-abd7-e18a-2ec9-462e66ff4c64': ('vif', {'bridge': 'eth0', 'mac': '00:16:3e:27:d1:29', 'uuid': 'e7cce477-abd7-e18a-2ec9-462e66ff4c64', 'script': 'vif-bridge'})}, 'vcpu_avail': 1, 'VCPUs_live': 1, 'PV_bootloader': '', 'actions_after_crash': 'destroy', 'vbd_refs': ['681c9320-fe4c-bc36-96ec-737883bcf842'], 'PV_ramdisk': '', 'memory_dynamic_min': 536870912, 'name_label': 'winxp-test001', 'VCPUs_at_startup': 1, 'HVM_boot_params': {'order': 'dc'}, 'platform': {'timer_mode': 1, 'usbdevice': 'tablet', 'hpet': 0, 'device_model': '/usr/lib64/xen/bin/qemu-dm', 'vpt_align': 1, 'boot': 'dc', 'rtc_timeoffset': 0, 'loader': '/usr/lib/xen/boot/hvmloader', 'xen_platform_pci': 1, 'pae': '1', 'apic': '1', 'serial': 'none', 'viridian': 0, 'acpi': '1', 'usb': '1'}, 'PV_kernel': '', 'console_refs': ['55f17ee8-60d3-0826-1d6b-947bd0b61424'], 'on_xend_stop': 'ignore', 'memory_static_min': 0, 'HVM_boot_policy': 'BIOS order', 'VCPUs_max': 1, 'memory_static_max': 536870912, 'actions_after_shutdown': 'destroy', 'on_xend_start': 'ignore', 'memory_dynamic_max': 536870912, 'actions_after_suspend': '', 'is_a_template': False, 'is_control_domain': False, 'uuid': 'bdb3a479-6e16-7427-09d3-f88271d29bcd', 'shadow_memory': 0, 'target': 0, 'PV_bootloader_args': '', 'other_config': {}, 'auto_power_on': False, 'actions_after_reboot': 'restart', 'vif_refs': ['e7cce477-abd7-e18a-2ec9-462e66ff4c64'], 'vtpm_refs': []})
>[2010-10-19 04:41:47 5192] DEBUG (XendDomain:1007) Creating new managed domain: winxp-test001
>[2010-10-19 04:47:57 5192] DEBUG (SrvDomain:76) Starting domain winxp-test001 False
>[2010-10-19 04:47:57 5192] DEBUG (XendDomainInfo:2311) XendDomainInfo.constructDomain
>[2010-10-19 04:47:57 5192] DEBUG (balloon:166) Balloon: 471520 KiB free; need 4096; done.
>[2010-10-19 04:47:57 5192] DEBUG (XendDomain:453) Adding Domain: 1
>[2010-10-19 04:47:57 5192] DEBUG (XendDomainInfo:2512) XendDomainInfo.initDomain: 1 256
>[2010-10-19 04:47:57 5192] DEBUG (image:322) No VNC passwd configured for vfb access
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: boot, val: dc
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: fda, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: fdb, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: soundhw, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: localtime, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: serial, val: ['none']
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: std-vga, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: isa, val: None
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: acpi, val: 1
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: usb, val: 1
>[2010-10-19 04:47:57 5192] DEBUG (image:813) args: usbdevice, val: tablet
>[2010-10-19 04:47:57 5192] INFO (image:749) Need to create platform device.[domid:1]
>[2010-10-19 04:47:57 5192] DEBUG (XendDomainInfo:2536) _initDomain:shadow_memory=0x0, memory_static_max=0x20000000, memory_static_min=0x0.
>[2010-10-19 04:47:57 5192] DEBUG (balloon:172) Balloon: 470104 KiB free; 0 to scrub; need 535552; retries: 20.
>[2010-10-19 04:47:57 5192] DEBUG (balloon:187) Balloon: setting dom0 target to 3259 MiB.
>[2010-10-19 04:47:57 5192] DEBUG (XendDomainInfo:1303) Setting memory target of domain Domain-0 (0) to 3259 MiB.
>[2010-10-19 04:47:58 5192] DEBUG (balloon:166) Balloon: 536472 KiB free; need 535552; done.
>[2010-10-19 04:47:58 5192] INFO (image:173) buildDomain os=hvm dom=1 vcpus=1
>[2010-10-19 04:47:58 5192] DEBUG (image:866) domid          = 1
>[2010-10-19 04:47:58 5192] DEBUG (image:867) image          = /usr/lib/xen/boot/hvmloader
>[2010-10-19 04:47:58 5192] DEBUG (image:868) store_evtchn   = 2
>[2010-10-19 04:47:58 5192] DEBUG (image:869) memsize        = 512
>[2010-10-19 04:47:58 5192] DEBUG (image:870) target         = 512
>[2010-10-19 04:47:58 5192] DEBUG (image:871) vcpus          = 1
>[2010-10-19 04:47:58 5192] DEBUG (image:872) acpi           = 1
>[2010-10-19 04:47:58 5192] DEBUG (image:873) apic           = 1
>[2010-10-19 04:47:58 5192] INFO (XendDomainInfo:2175) createDevice: vkbd : {'uuid': '118cc14b-fe69-018b-fdc8-d9308e3f9a7a'}
>[2010-10-19 04:47:58 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/1/0'} to /local/domain/1/device/vkbd/0.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:97) DevController: writing {'frontend-id': '1', 'domain': 'winxp-test001', 'frontend': '/local/domain/1/device/vkbd/0', 'state': '1', 'online': '1'} to /local/domain/0/backend/vkbd/1/0.
>[2010-10-19 04:47:58 5192] INFO (XendDomainInfo:2175) createDevice: vfb : {'vncunused': '1', 'other_config': {'vncunused': '1', 'vnclisten': '0.0.0.0', 'keymap': 'de'}, 'vnc': 1, 'uuid': '55f17ee8-60d3-0826-1d6b-947bd0b61424', 'vnclisten': '0.0.0.0', 'keymap': 'de'}
>[2010-10-19 04:47:58 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/1/0'} to /local/domain/1/device/vfb/0.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'winxp-test001', 'frontend': '/local/domain/1/device/vfb/0', 'uuid': '55f17ee8-60d3-0826-1d6b-947bd0b61424', 'frontend-id': '1', 'vnclisten': '0.0.0.0', 'state': '1', 'keymap': 'de', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/1/0.
>[2010-10-19 04:47:58 5192] INFO (XendDomainInfo:2175) createDevice: vbd : {'uuid': '681c9320-fe4c-bc36-96ec-737883bcf842', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname': 'file:/var/lib/libvirt/images/hdimage_xen_3.4.3_neu_aktiviert_gplpv_maerz', 'mode': 'w'}
>[2010-10-19 04:47:58 5192] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/1/768'} to /local/domain/1/device/vbd/768.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:97) DevController: writing {'domain': 'winxp-test001', 'frontend': '/local/domain/1/device/vbd/768', 'uuid': '681c9320-fe4c-bc36-96ec-737883bcf842', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/hdimage_xen_3.4.3_neu_aktiviert_gplpv_maerz', 'mode': 'w', 'online': '1', 'frontend-id': '1', 'type': 'file'} to /local/domain/0/backend/vbd/1/768.
>[2010-10-19 04:47:58 5192] INFO (XendDomainInfo:2175) createDevice: vif : {'bridge': 'eth0', 'mac': '00:16:3e:27:d1:29', 'uuid': 'e7cce477-abd7-e18a-2ec9-462e66ff4c64', 'script': 'vif-bridge'}
>[2010-10-19 04:47:58 5192] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:27:d1:29', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/1/0'} to /local/domain/1/device/vif/0.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:97) DevController: writing {'bridge': 'eth0', 'domain': 'winxp-test001', 'handle': '0', 'uuid': 'e7cce477-abd7-e18a-2ec9-462e66ff4c64', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:27:d1:29', 'frontend-id': '1', 'state': '1', 'online': '1', 'frontend': '/local/domain/1/device/vif/0'} to /local/domain/0/backend/vif/1/0.
>[2010-10-19 04:47:58 5192] INFO (image:394) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '1', '-domain-name', 'winxp-test001', '-videoram', '4', '-k', 'de', '-vnc', '0.0.0.0:0', '-vncunused', '-vcpus', '1', '-boot', 'dc', '-serial', 'none', '-acpi', '-usb', '-usbdevice', 'tablet', '-net', 'nic,vlan=1,macaddr=00:16:3e:27:d1:29,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap1.0,bridge=eth0', '-M', 'xenfv']
>[2010-10-19 04:47:58 5192] INFO (image:443) device model pid: 7236
>[2010-10-19 04:47:58 5192] INFO (image:531) waiting for sentinel_fifo
>[2010-10-19 04:47:58 5192] DEBUG (XendDomainInfo:3067) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': 'bdb3a479-6e16-7427-09d3-f88271d29bcd', 'on_reboot': 'restart', 'start_time': '1287456478.32', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'destroy', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': '(hvm (kernel ) (hpet 0) (loader /usr/lib/xen/boot/hvmloader) (serial none) (xen_platform_pci 1) (boot dc) (rtc_timeoffset 0) (pae 1) (vpt_align 1) (viridian 0) (acpi 1) (timer_mode 1) (apic 1) (usbdevice tablet) (device_model /usr/lib64/xen/bin/qemu-dm) (usb 1) (notes (SUSPEND_CANCEL 1)))', 'name': 'winxp-test001'}
>[2010-10-19 04:47:58 5192] DEBUG (XendDomainInfo:1629) Storing domain details: {'console/port': '3', 'name': 'winxp-test001', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/bdb3a479-6e16-7427-09d3-f88271d29bcd', 'domid': '1', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu'}
>[2010-10-19 04:47:58 5192] DEBUG (DevController:139) Waiting for devices vif.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/1/0'} to /local/domain/1/device/console/0.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:97) DevController: writing {'domain': 'winxp-test001', 'frontend': '/local/domain/1/device/console/0', 'uuid': '69751c68-8525-51fc-dcfe-f76a2d96286c', 'frontend-id': '1', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/1/0.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:144) Waiting for 0.
>[2010-10-19 04:47:58 5192] DEBUG (XendDomainInfo:1716) XendDomainInfo.handleShutdownWatch
>[2010-10-19 04:47:58 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vif/1/0/hotplug-status.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:643) hotplugStatusCallback 1.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:139) Waiting for devices vscsi.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:139) Waiting for devices vbd.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:144) Waiting for 768.
>[2010-10-19 04:47:58 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:49:38 5192] WARNING (XendDomain:1048) Failed to setup devices for <domain id=1 name=winxp-test001 memory=536870912 state=paused>: Device 768 (vbd) could not be connected. Hotplug scripts not working.
>[2010-10-19 04:49:38 5192] DEBUG (XendDomainInfo:2739) XendDomainInfo.destroy: domid=1
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2214) Destroying device model
>[2010-10-19 04:49:39 5192] INFO (image:556) winxp-test001 device model terminated
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2221) Releasing devices
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2234) Removing vif/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2234) Removing vbd/768
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2234) Removing vkbd/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vkbd, device = vkbd/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2234) Removing vfb/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2234) Removing console/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2219) No device model
>[2010-10-19 04:49:39 5192] DEBUG (XendDomainInfo:2221) Releasing devices
>[2010-10-19 04:49:39 5192] ERROR (SrvBase:88) Request start 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 77, in op_start
>    return self.xd.domain_start(self.dom.getName(), paused)
>  File "/usr/lib/python2.5/site-packages/xen/xend/XendDomain.py", line 1046, in domain_start
>    dominfo.waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/XendDomainInfo.py", line 1068, in waitForDevices
>    self.getDeviceController(devclass).waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 140, in waitForDevices
>    return map(self.waitForDevice, self.deviceIDs())
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 155, in waitForDevice
>    (devid, self.deviceClass))
>VmError: Device 768 (vbd) could not be connected. Hotplug scripts not working.
>[2010-10-19 04:49:39 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:49:39 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:49:39 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:49:39 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:53:32 5192] DEBUG (SrvDomain:76) Starting domain winxp-test001 False
>[2010-10-19 04:53:32 5192] DEBUG (XendDomainInfo:2311) XendDomainInfo.constructDomain
>[2010-10-19 04:53:32 5192] DEBUG (balloon:166) Balloon: 537888 KiB free; need 4096; done.
>[2010-10-19 04:53:32 5192] DEBUG (XendDomain:453) Adding Domain: 2
>[2010-10-19 04:53:32 5192] DEBUG (XendDomainInfo:2512) XendDomainInfo.initDomain: 2 256
>[2010-10-19 04:53:33 5192] DEBUG (image:322) No VNC passwd configured for vfb access
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: boot, val: dc
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: fda, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: fdb, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: soundhw, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: localtime, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: serial, val: ['none']
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: std-vga, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: isa, val: None
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: acpi, val: 1
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: usb, val: 1
>[2010-10-19 04:53:33 5192] DEBUG (image:813) args: usbdevice, val: tablet
>[2010-10-19 04:53:33 5192] INFO (image:749) Need to create platform device.[domid:2]
>[2010-10-19 04:53:33 5192] DEBUG (XendDomainInfo:2536) _initDomain:shadow_memory=0x5, memory_static_max=0x20000000, memory_static_min=0x0.
>[2010-10-19 04:53:33 5192] DEBUG (balloon:166) Balloon: 536472 KiB free; need 535552; done.
>[2010-10-19 04:53:33 5192] INFO (image:173) buildDomain os=hvm dom=2 vcpus=1
>[2010-10-19 04:53:33 5192] DEBUG (image:866) domid          = 2
>[2010-10-19 04:53:33 5192] DEBUG (image:867) image          = /usr/lib/xen/boot/hvmloader
>[2010-10-19 04:53:33 5192] DEBUG (image:868) store_evtchn   = 2
>[2010-10-19 04:53:33 5192] DEBUG (image:869) memsize        = 512
>[2010-10-19 04:53:33 5192] DEBUG (image:870) target         = 512
>[2010-10-19 04:53:33 5192] DEBUG (image:871) vcpus          = 1
>[2010-10-19 04:53:33 5192] DEBUG (image:872) acpi           = 1
>[2010-10-19 04:53:33 5192] DEBUG (image:873) apic           = 1
>[2010-10-19 04:53:33 5192] INFO (XendDomainInfo:2175) createDevice: vkbd : {'devid': 0, 'uuid': '118cc14b-fe69-018b-fdc8-d9308e3f9a7a'}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vkbd/2/0'} to /local/domain/2/device/vkbd/0.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:97) DevController: writing {'frontend-id': '2', 'domain': 'winxp-test001', 'frontend': '/local/domain/2/device/vkbd/0', 'state': '1', 'online': '1'} to /local/domain/0/backend/vkbd/2/0.
>[2010-10-19 04:53:33 5192] INFO (XendDomainInfo:2175) createDevice: vfb : {'vncunused': '1', 'other_config': {'vncunused': '1', 'vnclisten': '0.0.0.0', 'keymap': 'de'}, 'vnc': 1, 'uuid': '55f17ee8-60d3-0826-1d6b-947bd0b61424', 'vnclisten': '0.0.0.0', 'keymap': 'de', 'location': '0.0.0.0:5900', 'devid': 0}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/2/0'} to /local/domain/2/device/vfb/0.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'winxp-test001', 'frontend': '/local/domain/2/device/vfb/0', 'uuid': '55f17ee8-60d3-0826-1d6b-947bd0b61424', 'frontend-id': '2', 'vnclisten': '0.0.0.0', 'state': '1', 'keymap': 'de', 'location': '0.0.0.0:5900', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/2/0.
>[2010-10-19 04:53:33 5192] INFO (XendDomainInfo:2175) createDevice: console : {'other_config': {}, 'protocol': 'vt100', 'uuid': '69751c68-8525-51fc-dcfe-f76a2d96286c', 'location': '3'}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/2/0'} to /local/domain/2/device/console/0.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:97) DevController: writing {'domain': 'winxp-test001', 'frontend': '/local/domain/2/device/console/0', 'uuid': '69751c68-8525-51fc-dcfe-f76a2d96286c', 'frontend-id': '2', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/2/0.
>[2010-10-19 04:53:33 5192] INFO (XendDomainInfo:2175) createDevice: vbd : {'uuid': '681c9320-fe4c-bc36-96ec-737883bcf842', 'bootable': 1, 'devid': 768, 'driver': 'paravirtualised', 'dev': 'hda:disk', 'uname': 'file:/var/lib/libvirt/images/hdimage_xen_3.4.3_neu_aktiviert_gplpv_maerz', 'mode': 'w'}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/2/768'} to /local/domain/2/device/vbd/768.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:97) DevController: writing {'domain': 'winxp-test001', 'frontend': '/local/domain/2/device/vbd/768', 'uuid': '681c9320-fe4c-bc36-96ec-737883bcf842', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/hdimage_xen_3.4.3_neu_aktiviert_gplpv_maerz', 'mode': 'w', 'online': '1', 'frontend-id': '2', 'type': 'file'} to /local/domain/0/backend/vbd/2/768.
>[2010-10-19 04:53:33 5192] INFO (XendDomainInfo:2175) createDevice: vif : {'bridge': 'eth0', 'mac': '00:16:3e:27:d1:29', 'devid': 0, 'uuid': 'e7cce477-abd7-e18a-2ec9-462e66ff4c64', 'script': 'vif-bridge'}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'mac': '00:16:3e:27:d1:29', 'handle': '0', 'state': '1', 'backend': '/local/domain/0/backend/vif/2/0'} to /local/domain/2/device/vif/0.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:97) DevController: writing {'bridge': 'eth0', 'domain': 'winxp-test001', 'handle': '0', 'uuid': 'e7cce477-abd7-e18a-2ec9-462e66ff4c64', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:27:d1:29', 'frontend-id': '2', 'state': '1', 'online': '1', 'frontend': '/local/domain/2/device/vif/0'} to /local/domain/0/backend/vif/2/0.
>[2010-10-19 04:53:33 5192] INFO (image:394) spawning device models: /usr/lib64/xen/bin/qemu-dm ['/usr/lib64/xen/bin/qemu-dm', '-d', '2', '-domain-name', 'winxp-test001', '-videoram', '4', '-k', 'de', '-vnc', '0.0.0.0:0', '-vncunused', '-vcpus', '1', '-boot', 'dc', '-serial', 'none', '-acpi', '-usb', '-usbdevice', 'tablet', '-net', 'nic,vlan=1,macaddr=00:16:3e:27:d1:29,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap2.0,bridge=eth0', '-M', 'xenfv']
>[2010-10-19 04:53:33 5192] INFO (image:443) device model pid: 7967
>[2010-10-19 04:53:33 5192] INFO (image:531) waiting for sentinel_fifo
>[2010-10-19 04:53:33 5192] DEBUG (XendDomainInfo:3067) Storing VM details: {'on_xend_stop': 'ignore', 'shadow_memory': '5', 'uuid': 'bdb3a479-6e16-7427-09d3-f88271d29bcd', 'on_reboot': 'restart', 'start_time': '1287456813.17', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'destroy', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': '(hvm (kernel ) (hpet 0) (loader /usr/lib/xen/boot/hvmloader) (serial none) (xen_platform_pci 1) (boot dc) (rtc_timeoffset 0) (pae 1) (vpt_align 1) (viridian 0) (acpi 1) (timer_mode 1) (apic 1) (usbdevice tablet) (device_model /usr/lib64/xen/bin/qemu-dm) (usb 1) (notes (SUSPEND_CANCEL 1)))', 'name': 'winxp-test001'}
>[2010-10-19 04:53:33 5192] DEBUG (XendDomainInfo:1629) Storing domain details: {'console/port': '3', 'name': 'winxp-test001', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/bdb3a479-6e16-7427-09d3-f88271d29bcd', 'domid': '2', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '524288', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu'}
>[2010-10-19 04:53:33 5192] DEBUG (DevController:139) Waiting for devices vif.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:144) Waiting for 0.
>[2010-10-19 04:53:33 5192] DEBUG (XendDomainInfo:1716) XendDomainInfo.handleShutdownWatch
>[2010-10-19 04:53:33 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vif/2/0/hotplug-status.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:643) hotplugStatusCallback 1.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:139) Waiting for devices vscsi.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:139) Waiting for devices vbd.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:144) Waiting for 768.
>[2010-10-19 04:53:33 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.
>[2010-10-19 04:55:13 5192] WARNING (XendDomain:1048) Failed to setup devices for <domain id=2 name=winxp-test001 memory=536870912 state=paused>: Device 768 (vbd) could not be connected. Hotplug scripts not working.
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2739) XendDomainInfo.destroy: domid=2
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2214) Destroying device model
>[2010-10-19 04:55:13 5192] INFO (image:556) winxp-test001 device model terminated
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2221) Releasing devices
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2234) Removing vif/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2234) Removing vbd/768
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2234) Removing vkbd/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vkbd, device = vkbd/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2234) Removing vfb/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2234) Removing console/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:1134) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2219) No device model
>[2010-10-19 04:55:13 5192] DEBUG (XendDomainInfo:2221) Releasing devices
>[2010-10-19 04:55:13 5192] ERROR (SrvBase:88) Request start 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 77, in op_start
>    return self.xd.domain_start(self.dom.getName(), paused)
>  File "/usr/lib/python2.5/site-packages/xen/xend/XendDomain.py", line 1046, in domain_start
>    dominfo.waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/XendDomainInfo.py", line 1068, in waitForDevices
>    self.getDeviceController(devclass).waitForDevices()
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 140, in waitForDevices
>    return map(self.waitForDevice, self.deviceIDs())
>  File "/usr/lib/python2.5/site-packages/xen/xend/server/DevController.py", line 155, in waitForDevice
>    (devid, self.deviceClass))
>VmError: Device 768 (vbd) could not be connected. Hotplug scripts not working.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/1/768/hotplug-status.
>[2010-10-19 04:55:14 5192] DEBUG (DevController:629) hotplugStatusCallback /local/domain/0/backend/vbd/2/768/hotplug-status.

Actions: View

Attachments on bug 20481: 2772 | 2773 | 2774 | 2775