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@moon qemu]# service libvirtd stop
Stopping libvirtd (via systemctl):
#--------------------------------------------------#
[root@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@moon ~]# systemctl start libvirtd.service
[root@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@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@moon ~]#
> [root@moon qemu]# cat /etc/fedora-release
> Fedora release 17 (Beefy Miracle)
> [root@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
>
[root@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
_______________________________________________
virt mailing list
[email protected]
https://admin.fedoraproject.org/mailman/listinfo/virt
