From mboxrd@z Thu Jan 1 00:00:00 1970 From: Stefan Bader Subject: Xen-4.4-rc6 problems launching a PV guest Date: Fri, 07 Mar 2014 14:34:52 +0100 Message-ID: <5319CAFC.7050304@canonical.com> Mime-Version: 1.0 Content-Type: multipart/mixed; boundary="===============1474902328664675999==" Return-path: List-Unsubscribe: , List-Post: List-Help: List-Subscribe: , Sender: xen-devel-bounces@lists.xen.org Errors-To: xen-devel-bounces@lists.xen.org To: "xen-devel@lists.xensource.com" Cc: Ian Campbell List-Id: xen-devel@lists.xenproject.org This is an OpenPGP/MIME signed message (RFC 4880 and 3156) --===============1474902328664675999== Content-Type: multipart/signed; micalg=pgp-sha512; protocol="application/pgp-signature"; boundary="N4Q8OOM4Kc5cX3dHNiuXagN21B3s6aB2R" This is an OpenPGP/MIME signed message (RFC 4880 and 3156) --N4Q8OOM4Kc5cX3dHNiuXagN21B3s6aB2R Content-Type: text/plain; charset=ISO-8859-15 Content-Transfer-Encoding: quoted-printable Playing around with the release candidate of Xen-4.4 I seems to have prob= lems launching a PV guest with a config that used to work with Xen-4.3. The no= rmal logging would hint problems with the NIC setup (witch the slightly confus= ing origin of vtpm). Comparing the output to one produced by xen-4.3 I found a weird deregiste= r of a watch never registered (see below) instead of a call to the hotplug scrip= t. Am I doing something wrong? -Stefan --- cfg --- name =3D "arg-saucy6401-pv" kernel =3D "/root/boot/pv-grub-hd0--x86_64.gz" memory =3D 4096 vcpus =3D 4 disk =3D [ 'file:/home/ec2/amis/saucy-server-uec/arg-saucy6401-pv.img,xvda1,w' ] vif =3D ['mac=3D02:e1:9c:43:ef:af,bridge=3Dbr0'] on_crash =3D "coredump-destroy" #device_model_version =3D "qemu-xen-traditional" --- log --- libxl: debug: libxl_create.c:1342:do_domain_create: ao 0x9cba30: create: how=3D(nil) callback=3D(nil) poller=3D0x9c0fa0 libxl: debug: libxl_device.c:251:libxl__device_disk_set_backend: Disk vde= v=3Dxvda1 spec.backend=3Dunknown libxl: debug: libxl_device.c:197:disk_try_backend: Disk vdev=3Dxvda1, bac= kend phy unsuitable as phys path not a block device libxl: debug: libxl_device.c:286:libxl__device_disk_set_backend: Disk vdev=3Dxvda1, using backend qdisk libxl: debug: libxl_create.c:797:initiate_domain_create: running bootload= er libxl: debug: libxl_bootloader.c:327:libxl__bootloader_run: no bootloader= configured, using user supplied kernel libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c13d8: deregister unregistered libxl: debug: libxl_numa.c:475:libxl__get_numa_candidate: New best NUMA placement candidate found: nr_nodes=3D1, nr_cpus=3D4, nr_vcpus=3D12, free= _memkb=3D15612 libxl: debug: libxl_numa.c:475:libxl__get_numa_candidate: New best NUMA placement candidate found: nr_nodes=3D1, nr_cpus=3D4, nr_vcpus=3D12, free= _memkb=3D15769 libxl: detail: libxl_dom.c:195:numa_place_domain: NUMA placement candidat= e with 1 nodes, 4 cpus and 15769 KB free selected domainbuilder: detail: xc_dom_allocate: cmdline=3D"", features=3D"(null)"= libxl: debug: libxl_dom.c:357:libxl__build_pv: pv kernel mapped 0 path /root/boot/pv-grub-hd0--x86_64.gz domainbuilder: detail: xc_dom_kernel_file: filename=3D"/root/boot/pv-grub-hd0--x86_64.gz" domainbuilder: detail: xc_dom_malloc_filemap : 769 kB domainbuilder: detail: xc_dom_malloc : 4384 kB domainbuilder: detail: xc_dom_do_gunzip: unzip ok, 0xc050e -> 0x448085 domainbuilder: detail: xc_dom_boot_xen_init: ver 4.4, caps xen-3.0-x86_64= xen-3.0-x86_32p hvm-3.0-x86_32 hvm-3.0-x86_32p hvm-3.0-x86_64 domainbuilder: detail: xc_dom_parse_image: called domainbuilder: detail: xc_dom_find_loader: trying multiboot-binary loader= ... domainbuilder: detail: loader probe failed domainbuilder: detail: xc_dom_find_loader: trying Linux bzImage loader ..= =2E domainbuilder: detail: xc_dom_probe_bzimage_kernel: kernel is not a bzIma= ge domainbuilder: detail: loader probe failed domainbuilder: detail: xc_dom_find_loader: trying ELF-generic loader ... domainbuilder: detail: loader probe OK xc: detail: elf_parse_binary: phdr: paddr=3D0x0 memsz=3D0x98fe68 xc: detail: elf_parse_binary: memory: 0x0 -> 0x98fe68 xc: detail: elf_xen_parse: __xen_guest: "GUEST_OS=3DMini-OS,XEN_VER=3Dxen-3.0,VIRT_BASE=3D0x0,ELF_PADDR_OFFSET=3D= 0x0,HYPERCALL_PAGE=3D0x2,LOADER=3Dgeneric" xc: detail: elf_xen_parse_guest_info: GUEST_OS=3D"Mini-OS" xc: detail: elf_xen_parse_guest_info: XEN_VER=3D"xen-3.0" xc: detail: elf_xen_parse_guest_info: VIRT_BASE=3D"0x0" xc: detail: elf_xen_parse_guest_info: ELF_PADDR_OFFSET=3D"0x0" xc: detail: elf_xen_parse_guest_info: HYPERCALL_PAGE=3D"0x2" xc: detail: elf_xen_parse_guest_info: LOADER=3D"generic" xc: detail: elf_xen_addr_calc_check: addresses: xc: detail: virt_base =3D 0x0 xc: detail: elf_paddr_offset =3D 0x0 xc: detail: virt_offset =3D 0x0 xc: detail: virt_kstart =3D 0x0 xc: detail: virt_kend =3D 0x98fe68 xc: detail: virt_entry =3D 0x0 xc: detail: p2m_base =3D 0xffffffffffffffff domainbuilder: detail: xc_dom_parse_elf_kernel: xen-3.0-x86_64: 0x0 -> 0x= 98fe68 domainbuilder: detail: xc_dom_mem_init: mem 4096 MB, pages 0x100000 pages= , 4k each domainbuilder: detail: xc_dom_mem_init: 0x100000 pages domainbuilder: detail: xc_dom_boot_mem_init: called domainbuilder: detail: x86_compat: guest xen-3.0-x86_64, address size 64 domainbuilder: detail: xc_dom_malloc : 8192 kB domainbuilder: detail: xc_dom_build_image: called domainbuilder: detail: xc_dom_alloc_segment: kernel : 0x0 -> 0x99= 0000 (pfn 0x0 + 0x990 pages) domainbuilder: detail: xc_dom_pfn_to_ptr_retcount: domU mapping: pfn 0x0+= 0x990 at 0x7f2f34eca000 xc: detail: elf_load_binary: phdr 0 at 0x7f2f34eca000 -> 0x7f2f34f4ab00 domainbuilder: detail: xc_dom_alloc_segment: phys2mach : 0x990000 ->= 0x1190000 (pfn 0x990 + 0x800 pages) domainbuilder: detail: xc_dom_pfn_to_ptr_retcount: domU mapping: pfn 0x99= 0+0x800 at 0x7f2f346ca000 domainbuilder: detail: xc_dom_alloc_page : start info : 0x1190000 (= pfn 0x1190) domainbuilder: detail: xc_dom_alloc_page : xenstore : 0x1191000 (= pfn 0x1191) domainbuilder: detail: xc_dom_alloc_page : console : 0x1192000 (= pfn 0x1192) domainbuilder: detail: nr_page_tables: 0x0000ffffffffffff/48: 0x000000000= 0000000 -> 0x0000ffffffffffff, 1 table(s) domainbuilder: detail: nr_page_tables: 0x0000007fffffffff/39: 0x000000000= 0000000 -> 0x0000007fffffffff, 1 table(s) domainbuilder: detail: nr_page_tables: 0x000000003fffffff/30: 0x000000000= 0000000 -> 0x000000003fffffff, 1 table(s) domainbuilder: detail: nr_page_tables: 0x00000000001fffff/21: 0x000000000= 0000000 -> 0x00000000013fffff, 10 table(s) domainbuilder: detail: xc_dom_alloc_segment: page tables : 0x1193000 -= > 0x11a0000 (pfn 0x1193 + 0xd pages) domainbuilder: detail: xc_dom_pfn_to_ptr_retcount: domU mapping: pfn 0x11= 93+0xd at 0x7f2f382ea000 domainbuilder: detail: xc_dom_alloc_page : boot stack : 0x11a0000 (= pfn 0x11a0) domainbuilder: detail: xc_dom_build_image : virt_alloc_end : 0x11a1000 domainbuilder: detail: xc_dom_build_image : virt_pgtab_end : 0x1400000 domainbuilder: detail: xc_dom_boot_image: called domainbuilder: detail: arch_setup_bootearly: doing nothing domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0= -x86_64 <=3D matches domainbuilder: detail: xc_dom_compat_check: supported guest type: xen-3.0= -x86_32p domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0= -x86_32 domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0= -x86_32p domainbuilder: detail: xc_dom_compat_check: supported guest type: hvm-3.0= -x86_64 domainbuilder: detail: xc_dom_update_guest_p2m: dst 64bit, pages 0x100000= domainbuilder: detail: clear_page: pfn 0x1192, mfn 0x81e765 domainbuilder: detail: clear_page: pfn 0x1191, mfn 0x81e766 domainbuilder: detail: xc_dom_pfn_to_ptr_retcount: domU mapping: pfn 0x11= 90+0x1 at 0x7f2f382e9000 domainbuilder: detail: start_info_x86_64: called domainbuilder: detail: setup_hypercall_page: vaddr=3D0x2000 pfn=3D0x2 domainbuilder: detail: domain builder memory footprint domainbuilder: detail: allocated domainbuilder: detail: malloc : 12684 kB domainbuilder: detail: anon mmap : 0 bytes domainbuilder: detail: mapped domainbuilder: detail: file mmap : 769 kB domainbuilder: detail: domU mmap : 18040 kB domainbuilder: detail: arch_setup_bootlate: shared_info: pfn 0x0, mfn 0xd= faf5 domainbuilder: detail: shared_info_x86_64: called domainbuilder: detail: vcpu_x86_64: called domainbuilder: detail: vcpu_x86_64: cr3: pfn 0x1193 mfn 0x81e764 domainbuilder: detail: launch_vm: called, ctxt=3D0x7f2f383c0004 domainbuilder: detail: xc_dom_release: called libxl: debug: libxl_device.c:251:libxl__device_disk_set_backend: Disk vde= v=3Dxvda1 spec.backend=3Dqdisk libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c27a0: deregister unregistered libxl: debug: libxl_dm.c:1303:libxl__spawn_local_dm: Spawning device-mode= l /usr/bin/qemu-system-i386 with arguments: libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: /usr/bin/qemu-syst= em-i386 libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -xen-domid libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: 7 libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -chardev libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: socket,id=3Dlibxl-cmd,path=3D/var/run/xen/qmp-libxl-7,server,nowait libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -mon libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: chardev=3Dlibxl-cmd,mode=3Dcontrol libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -nodefaults libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -xen-attach libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -name libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: arg-saucy6401-pv libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -nographic libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -machine libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: xenpv libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: -m libxl: debug: libxl_dm.c:1305:libxl__spawn_local_dm: 4097 libxl: debug: libxl_event.c:570:libxl__ev_xswatch_register: watch w=3D0x9= c1610 wpath=3D/local/domain/0/device-model/7/state token=3D3/0: register slotnu= m=3D3 libxl: debug: libxl_create.c:1356:do_domain_create: ao 0x9cba30: inprogre= ss: poller=3D0x9c0fa0, flags=3Di libxl: debug: libxl_event.c:514:watchfd_callback: watch w=3D0x9c1610 wpath=3D/local/domain/0/device-model/7/state token=3D3/0: event epath=3D/local/domain/0/device-model/7/state libxl: debug: libxl_event.c:514:watchfd_callback: watch w=3D0x9c1610 wpath=3D/local/domain/0/device-model/7/state token=3D3/0: event epath=3D/local/domain/0/device-model/7/state libxl: debug: libxl_event.c:606:libxl__ev_xswatch_deregister: watch w=3D0= x9c1610 wpath=3D/local/domain/0/device-model/7/state token=3D3/0: deregister slot= num=3D3 libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c1610: deregister unregistered libxl: debug: libxl_qmp.c:696:libxl__qmp_initialize: connected to /var/run/xen/qmp-libxl-7 libxl: debug: libxl_qmp.c:296:qmp_handle_response: message type: qmp libxl: debug: libxl_qmp.c:546:qmp_send_prepare: next qmp command: '{ "execute": "qmp_capabilities", "id": 1 } ' libxl: debug: libxl_qmp.c:296:qmp_handle_response: message type: return libxl: debug: libxl_qmp.c:546:qmp_send_prepare: next qmp command: '{ "execute": "query-chardev", "id": 2 } ' libxl: debug: libxl_qmp.c:296:qmp_handle_response: message type: return libxl: debug: libxl_qmp.c:546:qmp_send_prepare: next qmp command: '{ "execute": "query-vnc", "id": 3 } ' libxl: debug: libxl_qmp.c:296:qmp_handle_response: message type: return libxl: debug: libxl_event.c:570:libxl__ev_xswatch_register: watch w=3D0x9= c4bd8 wpath=3D/local/domain/0/backend/vif/7/0/state token=3D3/1: register slotn= um=3D3 libxl: debug: libxl_event.c:514:watchfd_callback: watch w=3D0x9c4bd8 wpath=3D/local/domain/0/backend/vif/7/0/state token=3D3/1: event epath=3D/local/domain/0/backend/vif/7/0/state libxl: debug: libxl_event.c:657:devstate_watch_callback: backend /local/domain/0/backend/vif/7/0/state wanted state 2 still waiting state = 1 libxl: debug: libxl_event.c:514:watchfd_callback: watch w=3D0x9c4bd8 wpath=3D/local/domain/0/backend/vif/7/0/state token=3D3/1: event epath=3D/local/domain/0/backend/vif/7/0/state libxl: debug: libxl_event.c:653:devstate_watch_callback: backend /local/domain/0/backend/vif/7/0/state wanted state 2 ok libxl: debug: libxl_event.c:606:libxl__ev_xswatch_deregister: watch w=3D0= x9c4bd8 wpath=3D/local/domain/0/backend/vif/7/0/state token=3D3/1: deregister slo= tnum=3D3 libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c4bd8: deregister unregistered libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c4c60: deregister unregistered --- This second watch getting unregistered, I do not get in xen-4.3. Also the= re never seems to be a register with that handle. In 4.3 at this point the h= otplug script gets called. --- libxl: error: libxl_create.c:1226:domcreate_attach_vtpms: unable to add n= ic devices libxl: debug: libxl_dm.c:1478:kill_device_model: Device Model signaled libxl: error: libxl_device.c:780:libxl__initiate_device_remove: unable to= get my domid libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c5700: deregister unregistered libxl: error: libxl_device.c:780:libxl__initiate_device_remove: unable to= get my domid libxl: debug: libxl_event.c:618:libxl__ev_xswatch_deregister: watch w=3D0= x9c5a10: deregister unregistered libxl: error: libxl.c:1457:devices_destroy_cb: libxl__devices_destroy fai= led for 7 libxl: debug: libxl_event.c:1591:libxl__ao_complete: ao 0x9cba30: complet= e, rc=3D-3 libxl: debug: libxl_event.c:1563:libxl__ao__destroy: ao 0x9cba30: destroy= xc: debug: hypercall buffer: total allocations:782 total releases:782 xc: debug: hypercall buffer: current allocations:0 maximum allocations:4 xc: debug: hypercall buffer: cache current size:4 xc: debug: hypercall buffer: cache hits:771 misses:4 toobig:7 Parsing config from arg-saucy6401-pv.cfg --N4Q8OOM4Kc5cX3dHNiuXagN21B3s6aB2R Content-Type: application/pgp-signature; name="signature.asc" Content-Description: OpenPGP digital signature Content-Disposition: attachment; filename="signature.asc" -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.11 (GNU/Linux) Comment: Using GnuPG with Thunderbird - http://www.enigmail.net/ iQIcBAEBCgAGBQJTGcsOAAoJEOhnXe7L7s6jy/wQAMkseI7tpQNyB6+6PAnEke+O FGaQeMOmIRXla/q9FRvnAO40Y6RBB9mDYs46Ckstf4vNUlKO3fuKCKwMWxbaNm/k zg169mts4a2hHp6B2FgIYPhNLWoCiJ9LUcwtyfIv9FRtZ2D0Nb/7oODw6QWUGRhK uoTvHyjtOZql05IeNylXPF+Oel/kqMOu72yUpTWEVmlpCdvWrskHthafI0qL+Iba ZoJCyfcm76LgGKO92ClsvoyMqvaVTMiHDUXCVI9DIfWa638hCqwDFsPEw01z91iv NbSVaErMzFtBZ+nekTLQQVcy2VzMYtlTXFXi+P3clG409Xk7m52MATqwYTIP9ajF onS8jc/eMRtt2b2jvI78DdiBQhKFs2QTqlS6LqXrYAQc3rJC/pxbBhfDyonrM3Ag uHdsyKXAau2WUYjIkw72sotebbv1mxrW6VaHm5YAz+imUJeBA7D6TyBDCsut97f1 mw69DetDg5ghWFJwB+e7n7XaXNYvHaMTk2UrmuiOvHUA0HcWlU5mXd4xWmvd/diO p1XHHFbbHwMH1OIuP3e0qrcBXdSs5bLHRfdJsoxfVTZu7jw0cLjNYXONYdnZU9L6 4NdLccrLUdjd5iSnLoqzyJXaBY23B+i69lABUKKETRkzNJqWEZRwH5u1TDPt1OWH vm3LQViC6YnueS8JWIdo =cJdK -----END PGP SIGNATURE----- --N4Q8OOM4Kc5cX3dHNiuXagN21B3s6aB2R-- --===============1474902328664675999== Content-Type: text/plain; charset="us-ascii" MIME-Version: 1.0 Content-Transfer-Encoding: 7bit Content-Disposition: inline _______________________________________________ Xen-devel mailing list Xen-devel@lists.xen.org http://lists.xen.org/xen-devel --===============1474902328664675999==--