[fedora-virt] virsh Segfault after creation of guest [with virt-preview repo; F17 guest on F17 host]
Kashyap Chamarthy
kashyapc at fedoraproject.org
Sat Jul 28 14:52:24 UTC 2012
On 07/28/2012 07:40 PM, Kashyap Chamarthy wrote:
> Heya,
>
> I'm just trying to create a minimal(@core) Fedora 17 guest on F17 host, using Virt- preview.
>
> So, once I create the minimal guest (using the attached script), the guest attempts to
> reboot, and it's just hung there, I could see that on the serial console. I tried to do a
> 'virsh list', it's just hung there too, When I tried to look into logs, I see a virsh
> segfault:
More specifically, 'virsh list' hangs when I shutdown the guest (I just ran 'init 0'
inside the guest)
----
Jul 28 19:45:29 moon libvirtd[22181]: 2012-07-28 14:15:29.176+0000: 22181: error :
daemonStreamEvent:237 : stream had unexpected termination
----
Then I try to stop the service, it still hangs..
#--------------------------------------------------#
[root at moon qemu]# service libvirtd stop
Stopping libvirtd (via systemctl):
#--------------------------------------------------#
[root at moon ~]# systemctl status libvirtd.service
libvirtd.service - Virtualization daemon
Loaded: loaded (/usr/lib/systemd/system/libvirtd.service; disabled)
Active: deactivating (stop-sigterm) since Sat, 28 Jul 2012 19:55:17 +0530; 29s ago
Main PID: 22181 (libvirtd)
.
.
.
#--------------------------------------------------#
[root at moon ~]# systemctl start libvirtd.service
[root at moon ~]# systemctl status libvirtd.service
libvirtd.service - Virtualization daemon
Loaded: loaded (/usr/lib/systemd/system/libvirtd.service; disabled)
Active: active (running) since Sat, 28 Jul 2012 19:56:47 +0530; 30s ago
Main PID: 25081 (libvirtd)
.
.
.
#--------------------------------------------------#
Nothing specific I see in '/var/log/libvirt/qemu/regular-guest-f17.log'
Attached stdout of 'virsh list' after enabling LIBVIRT_DEBUG=1.
Anyone else notice this?
>
>
> => Version Info: <=
> I'm using the packages from 'virt-preview' repo
> #-----------------------------------------------------------#
> [root at moon ~]# uname -r ; arch ; rpm -q qemu-kvm libvirt
> 3.4.6-2.fc17.x86_64
> x86_64
> qemu-kvm-1.1.0-9.fc17.x86_64
> libvirt-0.9.13-3.fc17.x86_64
> [root at moon ~]#
> [root at moon qemu]# cat /etc/fedora-release
> Fedora release 17 (Beefy Miracle)
> [root at moon qemu]#
>
> #-----------------------------------------------------------#
>
>
>
> => From /var/log/messages <=
> #-----------------------------------------------------------#
>
>
>
> .
> .
> .
> Jul 28 18:26:08 moon libvirtd[18901]: 2012-07-28 12:56:08.066+0000: 18901: error :
> virNetSocketReadWire:1006 : End of file while reading data: Input/output error
> Jul 28 18:26:15 moon kernel: [296929.644578] virsh[20063]: segfault at 96 ip
> 00007f4d5100ff31 sp 00007fffd7978688 error 4 in libc-2.15.so[7f4d50f8a000+1ac000]
> Jul 28 18:26:15 moon libvirtd[18901]: 2012-07-28 12:56:15.176+0000: 18901: error :
> virNetSocketReadWire:1006 : End of file while reading data: Input/output error
> .
> .
> .
> .
>
> Jul 28 18:53:09 moon libvirtd[21293]: 2012-07-28 13:23:09.037+0000: 21293: warning :
> virDriverLoadModule:72 : Module /usr/lib64/libvirt/connection-d
> river/libvirt_driver_libxl.so not accessible
> Jul 28 18:53:09 moon libvirtd[21293]: 2012-07-28 13:23:09.656+0000: 21379: error :
> qemuMonitorOpenUnix:308 : failed to connect to monitor socket: No such process
> .
> .
> .
> Jul 28 18:54:36 moon libvirtd[21293]: 2012-07-28 13:24:36.640+0000: 21293: error :
> daemonStreamHandleAbort:622 : stream aborted at client request
> #-----------------------------------------------------------#
>
> To avoid this, I have to stop libvirtd service (here, it takes a little while to stop the
> service. Then, again start the libvirtd service). Now, I can use virsh, and start the
> guest and see the boot progress via serial console.
>
> Just wondering if it's a known issue, before I file a bug.
>
> If needed, I can provide further debugging info by enabling LIBVIRT_DEBUG=1 on the shell
> when it hangs. Just let me know.
>
>
>
> /kashyap
>
-------------- next part --------------
[root at moon ~]# virsh list
2012-07-28 14:20:00.047+0000: 24935: info : libvirt version: 0.9.13, package: 3.fc17 (Fedora Project, 2012-07-24-04:10:06, buildvm-03.phx2.fedoraproject.org)
2012-07-28 14:20:00.047+0000: 24935: debug : virInitialize:409 : register drivers
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d72980 name=Test
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering Test as driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterNetworkDriver:546 : registering Test as network driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterInterfaceDriver:576 : registering Test as interface driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterStorageDriver:606 : registering Test as storage driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDeviceMonitor:636 : registering Test as device driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterSecretDriver:666 : registering Test as secret driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterNWFilterDriver:696 : registering Test as network filter driver 0
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d74500 name=OPENVZ
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering OPENVZ as driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d74a40 name=VMWARE
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering VMWARE as driver 2
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d73dc0 name=PHYP
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering PHYP as driver 3
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterStorageDriver:606 : registering PHYP as storage driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterInterfaceDriver:576 : registering PHYP as interface driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : vboxRegister:137 : VBoxCGlueInit failed, using dummy driver
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d74f80 name=VBOX
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering VBOX as driver 4
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterNetworkDriver:546 : registering VBOX as network driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterStorageDriver:606 : registering VBOX as storage driver 2
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d78040 name=ESX
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering ESX as driver 5
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterInterfaceDriver:576 : registering ESX as interface driver 2
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterNetworkDriver:546 : registering ESX as network driver 2
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterStorageDriver:606 : registering ESX as storage driver 3
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDeviceMonitor:636 : registering ESX as device driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterSecretDriver:666 : registering ESX as secret driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterNWFilterDriver:696 : registering ESX as network filter driver 1
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d78940 name=Hyper-V
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterDriver:729 : registering Hyper-V as driver 6
2012-07-28 14:20:00.047+0000: 24935: debug : virRegisterInterfaceDriver:576 : registering Hyper-V as interface driver 3
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterNetworkDriver:546 : registering Hyper-V as network driver 3
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterStorageDriver:606 : registering Hyper-V as storage driver 4
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterDeviceMonitor:636 : registering Hyper-V as device driver 2
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterSecretDriver:666 : registering Hyper-V as secret driver 2
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterNWFilterDriver:696 : registering Hyper-V as network filter driver 2
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterDriver:714 : driver=0x7ff237d73280 name=remote
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterDriver:729 : registering remote as driver 7
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterNetworkDriver:546 : registering remote as network driver 4
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterInterfaceDriver:576 : registering remote as interface driver 4
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterStorageDriver:606 : registering remote as storage driver 5
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterDeviceMonitor:636 : registering remote as device driver 3
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterSecretDriver:666 : registering remote as secret driver 3
2012-07-28 14:20:00.048+0000: 24935: debug : virRegisterNWFilterDriver:696 : registering remote as network filter driver 3
2012-07-28 14:20:00.048+0000: 24935: debug : virEventRegisterDefaultImpl:204 : registering default event implementation
2012-07-28 14:20:00.048+0000: 24935: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more
2012-07-28 14:20:00.048+0000: 24935: debug : virEventPollInterruptLocked:701 : Skip interrupt, 0 0
2012-07-28 14:20:00.048+0000: 24935: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7ff237906800 opaque=(nil) ff=(nil)
2012-07-28 14:20:00.048+0000: 24935: debug : virEventRegisterImpl:177 : addHandle=0x7ff237907310 updateHandle=0x7ff237907200 removeHandle=0x7ff237906c20 addTimeout=0x7ff237906d80 updateTimeout=0x7ff237906f50 removeTimeout=0x7ff237907080
2012-07-28 14:20:00.048+0000: 24935: debug : virConnectOpenAuth:1385 : name=(null), auth=0x7ff237d72900, flags=0
2012-07-28 14:20:00.048+0000: 24935: debug : virConnectGetConfigFile:938 : Loading config file '/etc/libvirt/libvirt.conf'
2012-07-28 14:20:00.048+0000: 24936: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-28 14:20:00.048+0000: 24935: debug : virFileClose:70 : Closed fd 6
2012-07-28 14:20:00.048+0000: 24936: debug : virEventPollCleanupTimeouts:505 : Cleanup 0
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1116 : no name, allowing driver auto-select
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 0 (Test) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 0 Test returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 1 (OPENVZ) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 1 OPENVZ returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 2 (VMWARE) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 2 VMWARE returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 3 (PHYP) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 3 PHYP returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 4 (VBOX) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 4 VBOX returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 5 (ESX) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 5 ESX returned DECLINED
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1156 : trying driver 6 (Hyper-V) ...
2012-07-28 14:20:00.048+0000: 24935: debug : do_open:1162 : driver 6 Hyper-V returned DECLINED
2012-07-28 14:20:00.049+0000: 24935: debug : do_open:1156 : trying driver 7 (remote) ...
2012-07-28 14:20:00.049+0000: 24935: debug : remoteOpen:865 : Auto-probe remote URI
2012-07-28 14:20:00.049+0000: 24935: debug : doRemoteOpen:542 : proceeding with name =
2012-07-28 14:20:00.049+0000: 24935: debug : doRemoteOpen:552 : Connecting with transport 1
2012-07-28 14:20:00.049+0000: 24935: debug : doRemoteOpen:599 : Proceeding with sockname /var/run/libvirt/libvirt-sock
2012-07-28 14:20:00.049+0000: 24935: debug : virNetSocketNew:122 : localAddr=0x7fff71145750 remoteAddr=0x7fff711457e0 fd=6 errfd=-1 pid=0
2012-07-28 14:20:00.049+0000: 24935: debug : virNetSocketNew:182 : RPC_SOCKET_NEW: sock=0x1abdfa0 refs=1 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2012-07-28 14:20:00.049+0000: 24935: debug : virNetSocketRef:716 : RPC_SOCKET_REF: sock=0x1abdfa0 refs=2
2012-07-28 14:20:00.048+0000: 24936: debug : virEventPollCleanupTimeouts:541 : Found 0 out of 0 timeout slots used, releasing 0
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollCleanupHandles:553 : Cleanup 1
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=4 e=1 d=0
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 0 timers
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollCalculateTimeout:350 : Timeout at 0 due in -1 ms
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2012-07-28 14:20:00.049+0000: 24935: debug : virEventPollInterruptLocked:705 : Interrupting
2012-07-28 14:20:00.049+0000: 24935: debug : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7ff2379eda90 opaque=0x1abdfa0 ff=0x7ff2379ee0e0
2012-07-28 14:20:00.049+0000: 24935: debug : virKeepAliveNew:206 : client=0x1abe2a0, interval=-1, count=0
2012-07-28 14:20:00.049+0000: 24935: debug : virKeepAliveNew:230 : RPC_KEEPALIVE_NEW: ka=0x1abe470 client=0x1abe2a0 refs=1
2012-07-28 14:20:00.049+0000: 24935: debug : virNetClientNew:337 : RPC_CLIENT_NEW: client=0x1abe2a0 refs=3 sock=0x1abdfa0
2012-07-28 14:20:00.049+0000: 24935: debug : doRemoteOpen:684 : Trying authentication
2012-07-28 14:20:00.049+0000: 24935: debug : virNetMessageNew:48 : msg=0x1abde50 tracked=0
2012-07-28 14:20:00.049+0000: 24935: debug : virNetMessageEncodePayload:361 : Encode length as 28
2012-07-28 14:20:00.049+0000: 24935: debug : virNetClientSendInternal:1757 : RPC_CLIENT_MSG_TX_QUEUE: client=0x1abe2a0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2012-07-28 14:20:00.049+0000: 24935: debug : virNetClientCallNew:1710 : New call 0x1abdbb0: msg=0x1abde50, expectReply=1, nonBlock=0
2012-07-28 14:20:00.049+0000: 24935: debug : virNetClientIO:1527 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
2012-07-28 14:20:00.049+0000: 24935: debug : virNetClientIO:1586 : We have the buck head=0x1abdbb0 call=0x1abdbb0
2012-07-28 14:20:00.049+0000: 24935: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2012-07-28 14:20:00.049+0000: 24935: debug : virEventPollInterruptLocked:705 : Interrupting
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollDispatchTimeouts:414 : Dispatch 0
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollDispatchHandles:459 : Dispatch 1
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-07-28 14:20:00.049+0000: 24936: debug : virEventPollCleanupTimeouts:505 : Cleanup 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:541 : Found 0 out of 0 timeout slots used, releasing 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupHandles:553 : Cleanup 2
2012-07-28 14:20:00.050+0000: 24936: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:505 : Cleanup 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:541 : Found 0 out of 0 timeout slots used, releasing 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupHandles:553 : Cleanup 2
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=4 e=1 d=0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=6 e=0 d=0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 0 timers
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCalculateTimeout:350 : Timeout at 0 due in -1 ms
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollDispatchTimeouts:414 : Dispatch 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollDispatchHandles:459 : Dispatch 1
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:505 : Cleanup 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:541 : Found 0 out of 0 timeout slots used, releasing 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupHandles:553 : Cleanup 2
2012-07-28 14:20:00.050+0000: 24936: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:505 : Cleanup 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupTimeouts:541 : Found 0 out of 0 timeout slots used, releasing 0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCleanupHandles:553 : Cleanup 2
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=4 e=1 d=0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=6 e=0 d=0
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 0 timers
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollCalculateTimeout:350 : Timeout at 0 due in -1 ms
2012-07-28 14:20:00.050+0000: 24936: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=1 timeout=-1
More information about the virt
mailing list