On Wed, Nov 12, 2014 at 4:28 PM, Jim Fehlig jfehlig@suse.com wrote:
This says the device model (which appears to be qemu-dm in your case) failed to run. /var/log/xen/qemu-dm-<vm_name>.log should have some info telling why qemu failed.
It says:
domid: 2 Boot device 'c' was given twice
Looking at the XML, I see there are two "boot" elements. So I've tried to comment one of them: <os> <type arch='x86_64' machine='xenfv'>hvm</type> <loader>/usr/lib/xen/boot/hvmloader</loader> <boot dev='hd'/> <!-- <boot dev='hd'/> --> </os> Interestingly, these two elements were generated by libvirt with "virsh -c xen:/// domxml-from-native xen-xm testvm.cfg"
Then, I've tried to start the VM with "virsh create", but still problems. Appartently, it seemed to work since I see it as "running" with "virsh list". However, (1) I'm unable to connect with vncviewer, and (2) after a while the VM stops.
Here below is the content of the logs:
/var/log/libvirt/libxl/testvm.log:
libxl: verbose: libxl_create.c:130:libxl__domain_build_info_setdefault: qemu-xen is unavailable, use qemu-xen-traditional instead: No such file or directory libxl: debug: libxl_create.c:1243:do_domain_create: ao 0x7fb678002900: create: how=(nil) callback=(nil) poller=0x7fb6780011b0 libxl: debug: libxl_device.c:257:libxl__device_disk_set_backend: Disk vdev=hda spec.backend=qdisk libxl: debug: libxl_device.c:257:libxl__device_disk_set_backend: Disk vdev=hdc spec.backend=qdisk libxl: debug: libxl_create.c:699:initiate_domain_create: running bootloader libxl: debug: libxl_bootloader.c:321:libxl__bootloader_run: not a PV domain, skipping bootloader libxl: debug: libxl_event.c:608:libxl__ev_xswatch_deregister: watch w=0x7fb678002c88: deregister unregistered libxl: debug: libxl_numa.c:478:libxl__get_numa_candidate: New best NUMA placement candidate found: nr_nodes=1, nr_cpus=8, nr_vcpus=17, free_memkb=2698 libxl: detail: libxl_dom.c:195:numa_place_domain: NUMA placement candidate with 1 nodes, 8 cpus and 2698 KB free selected xc: detail: elf_parse_binary: phdr: paddr=0x100000 memsz=0xa2304 xc: detail: elf_parse_binary: memory: 0x100000 -> 0x1a2304 xc: info: VIRTUAL MEMORY ARRANGEMENT: Loader: 0000000000100000->00000000001a2304 Modules: 0000000000000000->0000000000000000 TOTAL: 0000000000000000->00000000bf800000 ENTRY ADDRESS: 0000000000100000 xc: info: PHYSICAL MEMORY ALLOCATION: 4KB PAGES: 0x0000000000000200 2MB PAGES: 0x00000000000003fb 1GB PAGES: 0x0000000000000001 xc: detail: elf_load_binary: phdr 0 at 0x7fb6a7af8000 -> 0x7fb6a7b9118d libxl: debug: libxl_device.c:257:libxl__device_disk_set_backend: Disk vdev=hda spec.backend=qdisk libxl: debug: libxl_device.c:257:libxl__device_disk_set_backend: Disk vdev=hdc spec.backend=qdisk libxl: debug: libxl_dm.c:1211:libxl__spawn_local_dm: Spawning device-model /usr/lib/xen/bin/qemu-dm with arguments: libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: /usr/lib/xen/bin/qemu-dm libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -d libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: 9 libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -domain-name libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: testvm libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -vnc libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: 127.0.0.1:1 libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -videoram libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: 8 libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -boot libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: c libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -vcpu_avail libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: 0x01 libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -net libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: none libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: -M libxl: debug: libxl_dm.c:1213:libxl__spawn_local_dm: xenfv libxl: debug: libxl_event.c:559:libxl__ev_xswatch_register: watch w=0x7fb678002ec0 wpath=/local/domain/0/device-model/9/state token=3/0: register slotnum=3 libxl: debug: libxl_create.c:1256:do_domain_create: ao 0x7fb678002900: inprogress: poller=0x7fb6780011b0, flags=i libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb678002ec0 wpath=/local/domain/0/device-model/9/state token=3/0: event epath=/local/domain/0/device-model/9/state libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb678002ec0 wpath=/local/domain/0/device-model/9/state token=3/0: event epath=/local/domain/0/device-model/9/state libxl: debug: libxl_event.c:596:libxl__ev_xswatch_deregister: watch w=0x7fb678002ec0 wpath=/local/domain/0/device-model/9/state token=3/0: deregister slotnum=3 libxl: debug: libxl_event.c:608:libxl__ev_xswatch_deregister: watch w=0x7fb678002ec0: deregister unregistered libxl: debug: libxl_event.c:559:libxl__ev_xswatch_register: watch w=0x7fb678006b18 wpath=/local/domain/0/backend/vif/9/0/state token=3/1: register slotnum=3 libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb678006b18 wpath=/local/domain/0/backend/vif/9/0/state token=3/1: event epath=/local/domain/0/backend/vif/9/0/state libxl: debug: libxl_event.c:643:devstate_watch_callback: backend /local/domain/0/backend/vif/9/0/state wanted state 2 ok libxl: debug: libxl_event.c:596:libxl__ev_xswatch_deregister: watch w=0x7fb678006b18 wpath=/local/domain/0/backend/vif/9/0/state token=3/1: deregister slotnum=3 libxl: debug: libxl_event.c:608:libxl__ev_xswatch_deregister: watch w=0x7fb678006b18: deregister unregistered libxl: debug: libxl_device.c:959:device_hotplug: calling hotplug script: /etc/xen/scripts/vif-bridge online libxl: debug: libxl_event.c:1737:libxl__ao_progress_report: ao 0x7fb678002900: progress report: ignored libxl: debug: libxl_event.c:1569:libxl__ao_complete: ao 0x7fb678002900: complete, rc=0 libxl: debug: libxl_event.c:1541:libxl__ao__destroy: ao 0x7fb678002900: destroy libxl: debug: libxl_event.c:559:libxl__ev_xswatch_register: watch w=0x7fb678001cf0 wpath=@releaseDomain token=3/2: register slotnum=3 libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb678001cf0 wpath=@releaseDomain token=3/2: event epath=@releaseDomain libxl: debug: libxl.c:1004:domain_death_xswatch_callback: [evg=0x7fb678001810:9] from domid=9 nentries=1 rc=1 libxl: debug: libxl.c:1015:domain_death_xswatch_callback: [evg=0x7fb678001810:9] got=domaininfos[0] got->domain=9 libxl: debug: libxl.c:1042:domain_death_xswatch_callback: exists shutdown_reported=0 dominf.flags=ffff0022 libxl: debug: libxl.c:1008:domain_death_xswatch_callback: [evg=0] all reported libxl: debug: libxl.c:1072:domain_death_xswatch_callback: domain death search done libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb678001cf0 wpath=@releaseDomain token=3/2: event epath=@releaseDomain libxl: debug: libxl.c:1004:domain_death_xswatch_callback: [evg=0x7fb678001810:9] from domid=9 nentries=1 rc=1 libxl: debug: libxl.c:1015:domain_death_xswatch_callback: [evg=0x7fb678001810:9] got=domaininfos[0] got->domain=9 libxl: debug: libxl.c:1042:domain_death_xswatch_callback: exists shutdown_reported=0 dominf.flags=6 libxl: debug: libxl.c:1054:domain_death_xswatch_callback: shutdown reporting libxl: debug: libxl.c:1008:domain_death_xswatch_callback: [evg=0] all reported libxl: debug: libxl.c:1072:domain_death_xswatch_callback: domain death search done libxl: debug: libxl_event.c:1154:egc_run_callbacks: event 0x7fb6a99f1ad0 callback type=domain_shutdown libxl: debug: libxl.c:1256:libxl_domain_destroy: ao 0x7fb6a99f1b60: create: how=(nil) callback=(nil) poller=0x7fb6780011b0 libxl: debug: libxl_dm.c:1314:libxl__destroy_device_model: Device Model signaled libxl: debug: libxl_event.c:559:libxl__ev_xswatch_register: watch w=0x7fb6a99f08a8 wpath=/local/domain/0/backend/vif/9/0/state token=2/3: register slotnum=2 libxl: debug: libxl.c:1265:libxl_domain_destroy: ao 0x7fb6a99f1b60: inprogress: poller=0x7fb6780011b0, flags=i libxl: debug: libxl_event.c:503:watchfd_callback: watch w=0x7fb6a99f08a8 wpath=/local/domain/0/backend/vif/9/0/state token=2/3: event epath=/local/domain/0/backend/vif/9/0/state libxl: debug: libxl_event.c:643:devstate_watch_callback: backend /local/domain/0/backend/vif/9/0/state wanted state 6 ok libxl: debug: libxl_event.c:596:libxl__ev_xswatch_deregister: watch w=0x7fb6a99f08a8 wpath=/local/domain/0/backend/vif/9/0/state token=2/3: deregister slotnum=2 libxl: debug: libxl_event.c:608:libxl__ev_xswatch_deregister: watch w=0x7fb6a99f08a8: deregister unregistered libxl: debug: libxl_device.c:959:device_hotplug: calling hotplug script: /etc/xen/scripts/vif-bridge offline libxl: debug: libxl_event.c:472:watchfd_callback: watch epath=/local/domain/0/backend/vif/9/0/state token=2/3: empty slot libxl: debug: libxl_event.c:472:watchfd_callback: watch epath=/local/domain/0/backend/vif/9/0/state token=2/3: empty slot libxl: debug: libxl_event.c:1569:libxl__ao_complete: ao 0x7fb6a99f1b60: complete, rc=0 libxl: debug: libxl_event.c:1541:libxl__ao__destroy: ao 0x7fb6a99f1b60: destroy libxl: debug: libxl_event.c:596:libxl__ev_xswatch_deregister: watch w=0x7fb678001cf0 wpath=@releaseDomain token=3/2: deregister slotnum=3 libxl: debug: libxl_event.c:472:watchfd_callback: watch epath=@releaseDomain token=3/2: empty slot
/var/log/xen/qemu-dm-testvm.log:
domid: 9 -videoram option does not work with cirrus vga device model. Videoram set to 4M. Strip off blktap sub-type prefix to /root/images/testvm.img (drv 'aio') Using file /root/images/testvm.img in read-write mode Strip off blktap sub-type prefix to /root/iso/CentOS-7.0-1406-x86_64-Minimal.iso (drv 'aio') Using file /root/iso/CentOS-7.0-1406-x86_64-Minimal.iso in read-only mode Watching /local/domain/0/device-model/9/logdirty/cmd Watching /local/domain/0/device-model/9/command Watching /local/domain/9/cpu qemu_map_cache_init nr_buckets = 10000 size 4194304 shared page at pfn feffd buffered io page at pfn feffb Guest uuid = 93f08541-bfd5-4ac4-851d-c619a4516a13 populating video RAM at ff000000 mapping video RAM from ff000000 Register xen platform. Done register platform. platform_fixed_ioport: changed ro/rw state of ROM memory area. now is rw state. xs_read(/local/domain/0/device-model/9/xen_extended_power_mgmt): read error xs_read(): vncpasswd get error. /vm/93f08541-bfd5-4ac4-851d-c619a4516a13/vncpasswd. medium change watch on `hdc' (index: 1): aio:/root/iso/CentOS-7.0-1406-x86_64-Minimal.iso I/O request not ready: 0, ptr: 0, port: 0, data: 0, count: 0, size: 0 Log-dirty: no command yet. vcpu-set: watch node error. [xenstore_process_vcpu_set_event]: /local/domain/9/cpu has no CPU! xs_read(/local/domain/9/log-throttling): read error qemu: ignoring not-understood drive `/local/domain/9/log-throttling' medium change watch on `/local/domain/9/log-throttling' - unknown device, ignored cirrus vga map change while on lfb mode mapping vram to f0000000 - f0400000 platform_fixed_ioport: changed ro/rw state of ROM memory area. now is rw state. platform_fixed_ioport: changed ro/rw state of ROM memory area. now is ro state.
Just FYI, I've tried to start the VM directly with "xl" and it works: the VM runs and vncviewer happily connects. Here's the content of /var/log/xen/qemu-dm-testvm.log:
domid: 10 -videoram option does not work with cirrus vga device model. Videoram set to 4M. Strip off blktap sub-type prefix to /root/images/testvm.img (drv 'aio') Using file /root/images/testvm.img in read-write mode Strip off blktap sub-type prefix to /root/iso/CentOS-7.0-1406-x86_64-Minimal.iso (drv 'aio') Using file /root/iso/CentOS-7.0-1406-x86_64-Minimal.iso in read-only mode Watching /local/domain/0/device-model/10/logdirty/cmd Watching /local/domain/0/device-model/10/command Watching /local/domain/10/cpu qemu_map_cache_init nr_buckets = 10000 size 4194304 shared page at pfn feffd buffered io page at pfn feffb Guest uuid = 93f08541-bfd5-4ac4-851d-c619a4516a13 populating video RAM at ff000000 mapping video RAM from ff000000 Register xen platform. Done register platform. platform_fixed_ioport: changed ro/rw state of ROM memory area. now is rw state. xs_read(/local/domain/0/device-model/10/xen_extended_power_mgmt): read error xs_read(): vncpasswd get error. /vm/93f08541-bfd5-4ac4-851d-c619a4516a13/vncpasswd. medium change watch on `hdc' (index: 1): aio:/root/iso/CentOS-7.0-1406-x86_64-Minimal.iso I/O request not ready: 0, ptr: 0, port: 0, data: 0, count: 0, size: 0 Log-dirty: no command yet. vcpu-set: watch node error. [xenstore_process_vcpu_set_event]: /local/domain/10/cpu has no CPU! xs_read(/local/domain/10/log-throttling): read error qemu: ignoring not-understood drive `/local/domain/10/log-throttling' medium change watch on `/local/domain/10/log-throttling' - unknown device, ignored cirrus vga map change while on lfb mode mapping vram to f0000000 - f0400000 platform_fixed_ioport: changed ro/rw state of ROM memory area. now is rw state. platform_fixed_ioport: changed ro/rw state of ROM memory area. now is ro state. Unknown PV product 3 loaded in guest PV driver build 1 region type 1 at [c100,c200). region type 0 at [f3001000,f3001100). squash iomem [f3001000, f3001100). ACPI:debug: write addr=0xb044, val=0x0. ACPI:debug: write addr=0xb045, val=0x89. ACPI:debug: write addr=0xb044, val=0x1. ACPI:debug: write addr=0xb045, val=0x89. ... ACPI:debug: write addr=0xb044, val=0xff. ACPI:debug: write addr=0xb045, val=0x89.
Cheers,
-- Marco
-
Marco Guazzone