[libvirt-users] Error installing VM: "Process exited while reading console log output"

[Osier Yang]

On 2012年12月28日 05:28, Patrick 'P. J.' McDermott wrote:
> On 2012-12-26 10:28, Gao Yongwei wrote:
>>>> you could change the log level of libvirt daemon by edit
>> /etc/libvirt/libvirtd.log, and see more information
>
> On 2012-12-26 09:49, Osier Yang wrote:
>> Generally, this means qemu crashed, can you check the guest log
>> to see if there is any useful info?
>>
>> /var/log/libvirt/qemu/$guest.log
>>
>> PS: perhaps we should grab the stderr of qemu process and dump it
>> to user instead.
>
> Thanks.  I set log_level and log_outputs to log everything and write it
> to a file.  I ran the virt-install command again and saw this output:
>
>      Starting install...
>      Retrieving file MANIFEST...                  | 3.3 kB     00:00 ...
>      Retrieving file linux...                     | 4.6 MB     00:13 ...
>      Retrieving file initrd.gz...                 |  13 MB     00:37 ...
>      ERROR    internal error Process exited while reading console log output: 11:53:20.676: debug : qemudInitCpuAffinity:2423 : Setting CPU affinity
>      11:53:20.676: debug : qemuSecurityDACSetProcessLabel:547 : Dropping privileges of VM to 113:115
>      char device redirected to /dev/pts/5
>
>      Domain installation does not appear to have been
>       successful.  If it was, you can restart your domain
>       by running 'virsh start test1'; otherwise, please
>       restart your installation.
>      ERROR    internal error Process exited while reading console log output: 11:53:20.676: debug : qemudInitCpuAffinity:2423 : Setting CPU affinity
>      11:53:20.676: debug : qemuSecurityDACSetProcessLabel:547 : Dropping privileges of VM to 113:115
>      char device redirected to /dev/pts/5
>      Traceback (most recent call last):
>        File "/usr/bin/virt-install", line 1033, in<module>
>          main()
>        File "/usr/bin/virt-install", line 915, in main
>          start_time, guest.start_install)
>        File "/usr/bin/virt-install", line 957, in do_install
>          dom = install_func(conscb, progresscb, wait=(not wait))
>        File "/usr/lib/pymodules/python2.6/virtinst/Guest.py", line 973, in start_install
>          return self._do_install(consolecb, meter, removeOld, wait)
>        File "/usr/lib/pymodules/python2.6/virtinst/Guest.py", line 1038, in _do_install
>          "install")
>        File "/usr/lib/pymodules/python2.6/virtinst/Guest.py", line 1009, in _create_guest
>          dom = self.conn.createLinux(start_xml, 0)
>        File "/usr/lib/python2.6/dist-packages/libvirt.py", line 1277, in createLinux
>          if ret is None:raise libvirtError('virDomainCreateLinux() failed', conn=self)
>      libvirtError: internal error Process exited while reading console log output: 11:53:20.676: debug : qemudInitCpuAffinity:2423 : Setting CPU affinity
>      11:53:20.676: debug : qemuSecurityDACSetProcessLabel:547 : Dropping privileges of VM to 113:115
>      char device redirected to /dev/pts/5
>
> The log [1] is a bit more helpful.  I see the following warnings and
> errors:
>
>      11:52:19.564: error : ebiptablesDriverInit:3416 : internal error essential tools to support ip(6)tables firewalls could not be located
>      11:52:19.564: error : ebiptablesDriverInit:3425 : internal error firewall tools were not found or cannot be used
>      11:52:19.565: warning : qemudStartup:1832 : Unable to create cgroup for driver: No such device or address
>      11:52:19.613: warning : lxcStartup:1900 : Unable to create cgroup for driver: No such device or address
>      11:52:21.146: error : qemudDomainLookupByName:4403 : Domain not found: no domain with matching name 'test1'
>      11:52:21.163: error : storageVolumeLookupByPath:1241 : Storage volume not found: no storage vol with matching path
>      11:52:21.221: warning : qemudParsePCIDeviceStrs:1422 : Unexpected exit status '1', qemu probably failed
>      11:52:21.229: error : storageVolumeLookupByPath:1241 : Storage volume not found: no storage vol with matching path
>      11:52:21.286: error : storageVolumeLookupByPath:1241 : Storage volume not found: no storage vol with matching path
>      11:52:21.290: error : qemudDomainLookupByUUID:4378 : Domain not found: no domain with matching uuid '53c30756-0a2c-3cd1-a07d-9084da91e9b5'
>      11:53:20.570: error : qemudDomainLookupByName:4403 : Domain not found: no domain with matching name 'test1'
>      11:53:20.629: warning : qemudParsePCIDeviceStrs:1422 : Unexpected exit status '1', qemu probably failed
>      11:53:20.673: warning : qemudParsePCIDeviceStrs:1422 : Unexpected exit status '1', qemu probably failed
>      11:53:20.776: error : qemudReadLogOutput:2118 : internal error Process exited while reading console log output: 11:53:20.676: debug : qemudInitCpuAffinity:2423 : Setting CPU affinity
>
> Of particular interest in the log seems to be:
>
>      11:53:20.636: debug : virExecWithHook:709 : LC_ALL=C /usr/bin/kvm -device pci-assign,?
>      11:53:20.673: warning : qemudParsePCIDeviceStrs:1422 : Unexpected exit status '1', qemu probably failed
>
> I ran QEMU with that "-device" option:
>
>      $ LC_ALL=C /usr/bin/kvm -device pci-assign,?
>      property "pci-assign.?" not found
>      can't set property "?" to "on" for "pci-assign"

qemudParsePCIDeviceStrs should be involked when detecting the
qemu capability, and it should be the cause of the qemu crash.
However, unfortunately, I can't even find the function from
git log.

>
> So it looks like libvirtd (0.8.3) is trying to get a list of all

It's too old. :-), can you try to upgrade your libvirt?

> properties of the pci-assign driver, but QEMU 0.12.x doesn't support
> this.  But I'm not sure if that's related to why QEMU crashes later on.
>
> [1]: http://odin1.pehjota.net/~pj/servers/libvirtd.log
>