Attachment 1470313 Details for Bug 1607760 – --debug virt-install output for issue in comment #2

--debug virt-install output for issue in comment #2

program.log (text/plain), 16.32 KB, created by Radek Vykydal on 2018-07-24 14:53:04 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radek Vykydal

Created: 2018-07-24 14:53:04 UTC

Size: 16.32 KB

patch

obsolete

>Running... mount -o loop /var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp
>mount: /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp: WARNING: device write-protected, mounted read-only.
>Return code: 0
>Running... isoinfo -d -i /var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso
>CD-ROM is in ISO 9660 format
>System id: LINUX
>Volume id: RHEL-8-0-BaseOS-x86_64
>Volume set id:
>Publisher id:
>Data preparer id:
>Application id: GENISOIMAGE ISO 9660/HFS FILESYSTEM CREATOR (C) 1993 E.YOUNGDALE (C) 1997-2006 J.PEARSON/J.SCHILLING (C) 2006-2007 CDRKIT TEAM
>Copyright File id:
>Abstract File id:
>Bibliographic File id:
>Volume set size is: 1
>Volume set sequence number is: 1
>Logical block size is: 2048
>Volume size is: 265776
>El Torito VD version 1 found, boot catalog is in sector 44
>Joliet with UCS level 3 found
>Rock Ridge signatures version 1 found
>Eltorito validation header:
>Hid 1
>Arch 0 (x86)
>ID ''
>Key 55 AA
>Eltorito defaultboot header:
>Bootid 88 (bootable)
>Boot media 0 (No Emulation Boot)
>Load segment 0
>Sys type 0
>Nsect 4
>Bootoff 400E6 262374
>Return code: 0
>Running... virt-install -n LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da -r 2048 --noautoconsole --debug --vcpus 1 --rng /dev/random --noreboot --graphics vnc --initrd-inject /home/kstest/git/kickstart-tests/bridged-bond-pre.ks --disk path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/disk-a.img,cache=unsafe --network network:default --network network:default --network network:default --disk path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso,device=cdrom,readonly=on,shareable=on --extra-args ks=file:/bridged-bond-pre.ks vnc debug=1 inst.debug ip=enp0s3:dhcp stage2=hd:CDLABEL=RHEL-8-0-BaseOS-x86_64 --location /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp --channel tcp,host=127.0.0.1:56201,mode=connect,target_type=virtio,name=org.fedoraproject.anaconda.log.0
>[Tue, 24 Jul 2018 13:59:23 virt-install 1767] DEBUG (cli:265) Launched with command line: /usr/share/virt-manager/virt-install -n LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da -r 2048 --noautoconsole --debug --vcpus 1 --rng /dev/random --noreboot --graphics vnc --initrd-inject /home/kstest/git/kickstart-tests/bridged-bond-pre.ks --disk path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/disk-a.img,cache=unsafe --network network:default --network network:default --network network:default --disk path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso,device=cdrom,readonly=on,shareable=on --extra-args ks=file:/bridged-bond-pre.ks vnc debug=1 inst.debug ip=enp0s3:dhcp stage2=hd:CDLABEL=RHEL-8-0-BaseOS-x86_64 --location /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp --channel tcp,host=127.0.0.1:56201,mode=connect,target_type=virtio,name=org.fedoraproject.anaconda.log.0
>[Tue, 24 Jul 2018 13:59:23 virt-install 1767] DEBUG (cli:279) Requesting libvirt URI default
>[Tue, 24 Jul 2018 13:59:23 virt-install 1767] DEBUG (cli:282) Received libvirt URI qemu:///system
>[Tue, 24 Jul 2018 13:59:23 virt-install 1767] DEBUG (virt-install:358) Requesting virt method 'default', hv type 'default'.
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (virt-install:583) Received virt method 'kvm'
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (virt-install:584) Hypervisor name is 'hvm'
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (virt-install:270) Distilled --network options: ['network=default', 'network=default', 'network=default']
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (virt-install:183) Distilled --disk options: ['path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/disk-a.img,cache=unsafe', 'path=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso,device=cdrom,readonly=on,shareable=on']
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (diskbackend:170) Attempting to build pool=kstest-bridged-bond-pre.7qpv9vva target=/var/tmp/kstest-bridged-bond-pre.7qpv9vva
>[Tue, 24 Jul 2018 13:59:24 virt-install 1767] DEBUG (storage:524) Creating storage pool 'kstest-bridged-bond-pre.7qpv9vva' with xml:
><pool type="dir">
><name>kstest-bridged-bond-pre.7qpv9vva</name>
><target>
><path>/var/tmp/kstest-bridged-bond-pre.7qpv9vva</path>
></target>
></pool>
>
>[Tue, 24 Jul 2018 13:59:25 virt-install 1767] DEBUG (distroinstaller:180) DistroInstaller location is a network source.
>[Tue, 24 Jul 2018 13:59:25 virt-install 1767] DEBUG (urlfetcher:56) Using scratchdir=/var/lib/libvirt/boot
>[Tue, 24 Jul 2018 13:59:25 virt-install 1767] DEBUG (urlfetcher:503) Finding distro store for location=/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:1144) No treearch found in uri, defaulting to arch=i386
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/current/images/MANIFEST) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/daily/MANIFEST) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/.disk/info) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/Fedora) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/SL) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/CentOS) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/VERSION) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/.disk/info) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:1144) No treearch found in uri, defaulting to arch=i386
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/current/images/MANIFEST) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/daily/MANIFEST) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/.disk/info) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/Server) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/Client) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/RedHat) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/images/pxeboot/vmlinuz) returning True
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/images/pxeboot/initrd.img) returning True
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/images/boot.iso) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/boot/boot.iso) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/current/images/netboot/mini.iso) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:144) hasFile(/var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/install/images/boot.iso) returning False
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (urlfetcher:543) Detected distro name=Generic osvariant=None
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (distroinstaller:283) installer.detect_distro returned=None
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (guest:251) Setting Guest.os_variant to 'None'
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] WARNING (virt-install:545) No operating system detected, VM performance may suffer. Specify an OS with --os-variant for optimal results.
>[Tue, 24 Jul 2018 14:00:11 virt-install 1767] DEBUG (virt-install:697) Guest.has_install_phase: True
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (urlfetcher:89) Fetching URI: /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/images/pxeboot/vmlinuz
>
>Starting install...
>
>Retrieving file vmlinuz...                                  | 7.4 MB  00:00
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (urlfetcher:164) Saved file to /var/lib/libvirt/boot/virtinst-vmlinuz.A4UJ0p
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (urlfetcher:89) Fetching URI: /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp/images/pxeboot/initrd.img
>
>Retrieving file initrd.img...                               |  56 MB  00:00
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (urlfetcher:164) Saved file to /var/lib/libvirt/boot/virtinst-initrd.img.MONXZy
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (initrdinject:96) Copying /home/kstest/git/kickstart-tests/bridged-bond-pre.ks to the initrd.
>[Tue, 24 Jul 2018 14:00:13 virt-install 1767] DEBUG (initrdinject:99) Appending to the initrd.
>[Tue, 24 Jul 2018 14:00:15 virt-install 1767] DEBUG (kernelupload:134) Have access to preferred scratchdir so nothing to upload
>[Tue, 24 Jul 2018 14:00:15 virt-install 1767] DEBUG (guest:384) Generated install XML:
><domain type="kvm">
><name>LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da</name>
><uuid>b496fa27-75e8-4f5c-b573-9703c29c9798</uuid>
><memory>2097152</memory>
><currentMemory>2097152</currentMemory>
><vcpu>1</vcpu>
><os>
><type arch="x86_64">hvm</type>
><kernel>/var/lib/libvirt/boot/virtinst-vmlinuz.A4UJ0p</kernel>
><initrd>/var/lib/libvirt/boot/virtinst-initrd.img.MONXZy</initrd>
><cmdline>ks=file:/bridged-bond-pre.ks vnc debug=1 inst.debug ip=enp0s3:dhcp stage2=hd:CDLABEL=RHEL-8-0-BaseOS-x86_64</cmdline>
></os>
><features>
><acpi/>
><apic/>
></features>
><cpu mode="custom" match="exact">
><model>Haswell-noTSX-IBRS</model>
></cpu>
><clock offset="utc">
><timer name="rtc" tickpolicy="catchup"/>
><timer name="pit" tickpolicy="delay"/>
><timer name="hpet" present="no"/>
></clock>
><on_reboot>destroy</on_reboot>
><pm>
><suspend-to-mem enabled="no"/>
><suspend-to-disk enabled="no"/>
></pm>
><devices>
><emulator>/usr/bin/qemu-kvm</emulator>
><disk type="file" device="disk">
><driver name="qemu" type="qcow2" cache="unsafe"/>
><source file="/var/tmp/kstest-bridged-bond-pre.7qpv9vva/disk-a.img"/>
><target dev="hda" bus="ide"/>
></disk>
><disk type="file" device="cdrom">
><driver name="qemu" type="raw"/>
><source file="/var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso"/>
><target dev="hdb" bus="ide"/>
><shareable/>
><readonly/>
></disk>
><controller type="usb" index="0" model="ich9-ehci1"/>
><controller type="usb" index="0" model="ich9-uhci1">
><master startport="0"/>
></controller>
><controller type="usb" index="0" model="ich9-uhci2">
><master startport="2"/>
></controller>
><controller type="usb" index="0" model="ich9-uhci3">
><master startport="4"/>
></controller>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:09:7e:ca"/>
></interface>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:f3:80:02"/>
></interface>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:ed:e3:2f"/>
></interface>
><graphics type="vnc" port="-1"/>
><console type="pty"/>
><channel type="tcp">
><source mode="connect" host="127.0.0.1" service="56201"/>
><target type="virtio" name="org.fedoraproject.anaconda.log.0"/>
><protocol type="raw"/>
></channel>
><rng model="virtio">
><backend model="random">/dev/random</backend>
></rng>
></devices>
></domain>
>
>[Tue, 24 Jul 2018 14:00:15 virt-install 1767] DEBUG (guest:385) Generated boot XML:
><domain type="kvm">
><name>LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da</name>
><uuid>b496fa27-75e8-4f5c-b573-9703c29c9798</uuid>
><memory>2097152</memory>
><currentMemory>2097152</currentMemory>
><vcpu>1</vcpu>
><os>
><type arch="x86_64">hvm</type>
><boot dev="hd"/>
></os>
><features>
><acpi/>
><apic/>
></features>
><cpu mode="custom" match="exact">
><model>Haswell-noTSX-IBRS</model>
></cpu>
><clock offset="utc">
><timer name="rtc" tickpolicy="catchup"/>
><timer name="pit" tickpolicy="delay"/>
><timer name="hpet" present="no"/>
></clock>
><pm>
><suspend-to-mem enabled="no"/>
><suspend-to-disk enabled="no"/>
></pm>
><devices>
><emulator>/usr/bin/qemu-kvm</emulator>
><disk type="file" device="disk">
><driver name="qemu" type="qcow2" cache="unsafe"/>
><source file="/var/tmp/kstest-bridged-bond-pre.7qpv9vva/disk-a.img"/>
><target dev="hda" bus="ide"/>
></disk>
><disk type="file" device="cdrom">
><driver name="qemu" type="raw"/>
><source file="/var/tmp/kstest-bridged-bond-pre.7qpv9vva/boot.master.iso"/>
><target dev="hdb" bus="ide"/>
><shareable/>
><readonly/>
></disk>
><controller type="usb" index="0" model="ich9-ehci1"/>
><controller type="usb" index="0" model="ich9-uhci1">
><master startport="0"/>
></controller>
><controller type="usb" index="0" model="ich9-uhci2">
><master startport="2"/>
></controller>
><controller type="usb" index="0" model="ich9-uhci3">
><master startport="4"/>
></controller>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:09:7e:ca"/>
></interface>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:f3:80:02"/>
></interface>
><interface type="network">
><source network="default"/>
><mac address="52:54:00:ed:e3:2f"/>
></interface>
><graphics type="vnc" port="-1"/>
><console type="pty"/>
><channel type="tcp">
><source mode="connect" host="127.0.0.1" service="56201"/>
><target type="virtio" name="org.fedoraproject.anaconda.log.0"/>
><protocol type="raw"/>
></channel>
><rng model="virtio">
><backend model="random">/dev/random</backend>
></rng>
></devices>
></domain>
>
>[Tue, 24 Jul 2018 14:00:49 virt-install 1767] DEBUG (installer:187) Removing /var/lib/libvirt/boot/virtinst-vmlinuz.A4UJ0p
>[Tue, 24 Jul 2018 14:00:49 virt-install 1767] DEBUG (installer:187) Removing /var/lib/libvirt/boot/virtinst-initrd.img.MONXZy
>[Tue, 24 Jul 2018 14:00:50 virt-install 1767] DEBUG (cli:317)   File "/usr/share/virt-manager/virt-install", line 1008, in <module>
>sys.exit(main())
>File "/usr/share/virt-manager/virt-install", line 1002, in main
>start_install(guest, options)
>File "/usr/share/virt-manager/virt-install", line 728, in start_install
>fail(e, do_exit=False)
>File "/usr/share/virt-manager/virtinst/cli.py", line 317, in fail
>logging.debug("".join(traceback.format_stack()))
>
>[Tue, 24 Jul 2018 14:00:50 virt-install 1767] ERROR (cli:318) monitor socket did not show up: No such file or directory
>[Tue, 24 Jul 2018 14:00:50 virt-install 1767] DEBUG (cli:320)
>Traceback (most recent call last):
>File "/usr/share/virt-manager/virt-install", line 707, in start_install
>transient=options.transient)
>File "/usr/share/virt-manager/virtinst/guest.py", line 498, in start_install
>doboot, transient)
>File "/usr/share/virt-manager/virtinst/guest.py", line 434, in _create_guest
>domain = self.conn.createXML(install_xml or final_xml, 0)
>File "/usr/lib64/python2.7/site-packages/libvirt.py", line 3659, in createXML
>if ret is None:raise libvirtError('virDomainCreateXML() failed', conn=self)
>libvirtError: monitor socket did not show up: No such file or directory
>[Tue, 24 Jul 2018 14:00:50 virt-install 1767] DEBUG (cli:331) Domain installation does not appear to have been successful.
>If it was, you can restart your domain by running:
>virsh --connect qemu:///system start LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da
>otherwise, please restart your installation.
>Domain installation does not appear to have been successful.
>If it was, you can restart your domain by running:
>virsh --connect qemu:///system start LiveOS-66a132a2-91d5-4ecd-9816-313f3c7a26da
>otherwise, please restart your installation.
>Return code: 1
>Running... umount /var/tmp/kstest-bridged-bond-pre.7qpv9vva/lorax.imgutils.j_jduohp
>Return code: 0

Actions: View

Attachments on bug 1607760: 1470313