From mboxrd@z Thu Jan 1 00:00:00 1970 From: =?ISO-8859-1?Q?Oriol_Mart=ED?= Subject: Re: Virtual machine freezed after a save Date: Tue, 10 Jan 2012 11:04:44 +0100 Message-ID: <4F0C0D3C.5040404@cesca.cat> References: <4F0AC63B.5000202@cesca.cat> Mime-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: QUOTED-PRINTABLE To: kvm@vger.kernel.org Return-path: Received: from gabarro.xs.cesca.cat ([84.88.0.6]:20770 "EHLO gabarro.xs.cesca.cat" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751379Ab2AJKEr (ORCPT ); Tue, 10 Jan 2012 05:04:47 -0500 In-Reply-To: <4F0AC63B.5000202@cesca.cat> Sender: kvm-owner@vger.kernel.org List-ID: Anybody? On 09/01/12 11:49, Oriol Mart=ED wrote: > Hello, I have an Ubuntu Server with KVM, the extensions in the BIOS=20 > are enabled: > root@cluster03:~# kvm-ok > INFO: Your CPU supports KVM extensions > INFO: /dev/kvm exists > KVM acceleration can be used > > The problem is that when i do a save of a virtual machine, the screen= =20 > freezes and the ping does not respond when I restore. Does sombody=20 > know why the vm does not restore normally? > > Thx. > > PD: the commands I use--> > > virsh --connect qemu+ssh:///system save one-309 one-309 > virsh --connect qemu+ssh:///system restore one-309 > > I've saved the debug logs with export=20 > LIBVIRT_LOG_OUTPUTS=3D"1:file:virsh.log" and this is: > > 10:49:31.794: debug : virInitialize:337 : register drivers > 10:49:31.794: debug : virRegisterDriver:838 : registering Test as=20 > driver 0 > 10:49:31.794: debug : virRegisterNetworkDriver:676 : registering Test= =20 > as network driver 0 > 10:49:31.794: debug : virRegisterInterfaceDriver:707 : registering=20 > Test as interface driver 0 > 10:49:31.794: debug : virRegisterStorageDriver:738 : registering Test= =20 > as storage driver 0 > 10:49:31.794: debug : virRegisterDeviceMonitor:769 : registering Test= =20 > as device driver 0 > 10:49:31.794: debug : virRegisterSecretDriver:800 : registering Test=20 > as secret driver 0 > 10:49:31.794: debug : virRegisterDriver:838 : registering Xen as driv= er 1 > 10:49:31.794: debug : virRegisterDriver:838 : registering OPENVZ as=20 > driver 2 > 10:49:31.794: debug : vboxRegister:109 : VBoxCGlueInit failed, using=20 > dummy driver > 10:49:31.794: debug : virRegisterDriver:838 : registering VBOX as=20 > driver 3 > 10:49:31.794: debug : virRegisterNetworkDriver:676 : registering VBOX= =20 > as network driver 1 > 10:49:31.794: debug : virRegisterStorageDriver:738 : registering VBOX= =20 > as storage driver 1 > 10:49:31.794: debug : virRegisterDriver:838 : registering remote as=20 > driver 4 > 10:49:31.794: debug : virRegisterNetworkDriver:676 : registering=20 > remote as network driver 2 > 10:49:31.794: debug : virRegisterInterfaceDriver:707 : registering=20 > remote as interface driver 1 > 10:49:31.794: debug : virRegisterStorageDriver:738 : registering=20 > remote as storage driver 2 > 10:49:31.794: debug : virRegisterDeviceMonitor:769 : registering=20 > remote as device driver 1 > 10:49:31.794: debug : virRegisterSecretDriver:800 : registering remot= e=20 > as secret driver 1 > 10:49:31.794: debug : virConnectOpenAuth:1338 :=20 > name=3Dqemu+ssh:///system, auth=3D0x7f282a1b3b80, flags=3D0 > 10:49:31.794: debug : do_open:1107 : name "qemu+ssh:///system" to URI= =20 > components: > scheme qemu+ssh > opaque (null) > authority (null) > server (null) > user (null) > port 0 > path /system > > 10:49:31.794: debug : do_open:1117 : trying driver 0 (Test) ... > 10:49:31.794: debug : do_open:1123 : driver 0 Test returned DECLINED > 10:49:31.794: debug : do_open:1117 : trying driver 1 (Xen) ... > 10:49:31.794: debug : do_open:1123 : driver 1 Xen returned DECLINED > 10:49:31.794: debug : do_open:1117 : trying driver 2 (OPENVZ) ... > 10:49:31.794: debug : do_open:1123 : driver 2 OPENVZ returned DECLINE= D > 10:49:31.794: debug : do_open:1117 : trying driver 3 (VBOX) ... > 10:49:31.794: debug : do_open:1123 : driver 3 VBOX returned DECLINED > 10:49:31.794: debug : do_open:1117 : trying driver 4 (remote) ... > 10:49:31.794: debug : doRemoteOpen:565 : proceeding with name =3D=20 > qemu:///system > 10:49:31.794: debug : virExecWithHook:620 : ssh localhost nc -q 2>&1 = |=20 > grep -q 'requires an argument';if [ $? -eq 0 ] ; then CMD=3D'-q=20 > 0';else CMD=3D'';fi;nc $CMD -U /var/run/libvirt/libvirt-sock > 10:49:31.794: debug : remoteIO:8457 : Do proc=3D66 serial=3D0 length=3D= 28=20 > wait=3D(nil) > 10:49:31.794: debug : remoteIO:8519 : We have the buck 66=20 > 0x7f282a1ea010 0x7f282a1ea010 > 10:49:35.755: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 64 total (60 more) > 10:49:35.755: debug : remoteIOEventLoop:8383 : Giving up the buck 66=20 > 0x7f282a1ea010 (nil) > 10:49:35.755: debug : remoteIO:8550 : All done with our call 66 (nil)= =20 > 0x7f282a1ea010 > 10:49:35.755: debug : remoteIO:8457 : Do proc=3D1 serial=3D1 length=3D= 56=20 > wait=3D(nil) > 10:49:35.755: debug : remoteIO:8519 : We have the buck 1 0x67a930=20 > 0x67a930 > 10:49:35.755: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 10:49:35.755: debug : remoteIOEventLoop:8383 : Giving up the buck 1=20 > 0x67a930 (nil) > 10:49:35.755: debug : remoteIO:8550 : All done with our call 1 (nil)=20 > 0x67a930 > 10:49:35.755: debug : doRemoteOpen:918 : Adding Handler for remote ev= ents > 10:49:35.755: debug : doRemoteOpen:925 : virEventAddHandle failed: No= =20 > addHandleImpl defined. continuing without events. > 10:49:35.755: debug : do_open:1123 : driver 4 remote returned SUCCESS > 10:49:35.755: debug : do_open:1143 : network driver 0 Test returned=20 > DECLINED > 10:49:35.755: debug : do_open:1143 : network driver 1 VBOX returned=20 > DECLINED > 10:49:35.755: debug : do_open:1143 : network driver 2 remote returned= =20 > SUCCESS > 10:49:35.755: debug : do_open:1162 : interface driver 0 Test returned= =20 > DECLINED > 10:49:35.755: debug : do_open:1162 : interface driver 1 remote=20 > returned SUCCESS > 10:49:35.755: debug : do_open:1182 : storage driver 0 Test returned=20 > DECLINED > 10:49:35.755: debug : do_open:1182 : storage driver 1 VBOX returned=20 > DECLINED > 10:49:35.755: debug : do_open:1182 : storage driver 2 remote returned= =20 > SUCCESS > 10:49:35.755: debug : do_open:1202 : node driver 0 Test returned DECL= INED > 10:49:35.755: debug : do_open:1202 : node driver 1 remote returned=20 > SUCCESS > 10:49:35.755: debug : do_open:1229 : secret driver 0 Test returned=20 > DECLINED > 10:49:35.755: debug : do_open:1229 : secret driver 1 remote returned=20 > SUCCESS > 10:49:35.755: debug : virDomainLookupByName:1975 : conn=3D0x674c50,=20 > name=3Done-309 > 10:49:35.756: debug : remoteIO:8457 : Do proc=3D23 serial=3D2 length=3D= 40=20 > wait=3D(nil) > 10:49:35.756: debug : remoteIO:8519 : We have the buck 23 0x67a4d0=20 > 0x67a4d0 > 10:49:35.756: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 88 total (84 more) > 10:49:35.756: debug : remoteIOEventLoop:8383 : Giving up the buck 23=20 > 0x67a4d0 (nil) > 10:49:35.756: debug : remoteIO:8550 : All done with our call 23 (nil)= =20 > 0x67a4d0 > 10:49:35.756: debug : virGetDomain:345 : New hash entry 0x6749b0 > 10:49:35.756: debug : virDomainSave:2217 : domain=3D0x6749b0, to=3Don= e-309 > 10:49:35.756: debug : remoteIO:8457 : Do proc=3D55 serial=3D3 length=3D= 80=20 > wait=3D(nil) > 10:49:35.756: debug : remoteIO:8519 : We have the buck 55 0x67a4d0=20 > 0x67a4d0 > 10:52:23.598: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 10:52:23.598: debug : remoteIOEventLoop:8383 : Giving up the buck 55=20 > 0x67a4d0 (nil) > 10:52:23.598: debug : remoteIO:8550 : All done with our call 55 (nil)= =20 > 0x67a4d0 > 10:52:23.598: debug : virDomainFree:2063 : domain=3D0x6749b0 > 10:52:23.598: debug : virUnrefDomain:422 : unref domain 0x6749b0=20 > one-309 1 > 10:52:23.598: debug : virReleaseDomain:376 : release domain 0x6749b0=20 > one-309 > 10:52:23.598: debug : virReleaseDomain:392 : unref connection 0x674c5= 0 2 > 10:52:23.598: debug : virConnectClose:1356 : conn=3D0x674c50 > 10:52:23.598: debug : virUnrefConnect:259 : unref connection 0x674c50= 1 > 10:52:23.598: debug : remoteIO:8457 : Do proc=3D2 serial=3D4 length=3D= 28=20 > wait=3D(nil) > 10:52:23.598: debug : remoteIO:8519 : We have the buck 2 0x67a4d0=20 > 0x67a4d0 > 10:52:23.598: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 10:52:23.598: debug : remoteIOEventLoop:8383 : Giving up the buck 2=20 > 0x67a4d0 (nil) > 10:52:23.598: debug : remoteIO:8550 : All done with our call 2 (nil)=20 > 0x67a4d0 > 10:52:23.599: debug : virReleaseConnect:216 : release connection 0x67= 4c50 > 11:01:19.623: debug : virInitialize:337 : register drivers > 11:01:19.624: debug : virRegisterDriver:838 : registering Test as=20 > driver 0 > 11:01:19.624: debug : virRegisterNetworkDriver:676 : registering Test= =20 > as network driver 0 > 11:01:19.624: debug : virRegisterInterfaceDriver:707 : registering=20 > Test as interface driver 0 > 11:01:19.624: debug : virRegisterStorageDriver:738 : registering Test= =20 > as storage driver 0 > 11:01:19.624: debug : virRegisterDeviceMonitor:769 : registering Test= =20 > as device driver 0 > 11:01:19.624: debug : virRegisterSecretDriver:800 : registering Test=20 > as secret driver 0 > 11:01:19.624: debug : virRegisterDriver:838 : registering Xen as driv= er 1 > 11:01:19.624: debug : virRegisterDriver:838 : registering OPENVZ as=20 > driver 2 > 11:01:19.624: debug : vboxRegister:109 : VBoxCGlueInit failed, using=20 > dummy driver > 11:01:19.624: debug : virRegisterDriver:838 : registering VBOX as=20 > driver 3 > 11:01:19.624: debug : virRegisterNetworkDriver:676 : registering VBOX= =20 > as network driver 1 > 11:01:19.624: debug : virRegisterStorageDriver:738 : registering VBOX= =20 > as storage driver 1 > 11:01:19.624: debug : virRegisterDriver:838 : registering remote as=20 > driver 4 > 11:01:19.624: debug : virRegisterNetworkDriver:676 : registering=20 > remote as network driver 2 > 11:01:19.624: debug : virRegisterInterfaceDriver:707 : registering=20 > remote as interface driver 1 > 11:01:19.624: debug : virRegisterStorageDriver:738 : registering=20 > remote as storage driver 2 > 11:01:19.624: debug : virRegisterDeviceMonitor:769 : registering=20 > remote as device driver 1 > 11:01:19.624: debug : virRegisterSecretDriver:800 : registering remot= e=20 > as secret driver 1 > 11:01:19.624: debug : virConnectOpenAuth:1338 :=20 > name=3Dqemu+ssh:///system, auth=3D0x7fcfdd6b4b80, flags=3D0 > 11:01:19.624: debug : do_open:1107 : name "qemu+ssh:///system" to URI= =20 > components: > scheme qemu+ssh > opaque (null) > authority (null) > server (null) > user (null) > port 0 > path /system > > 11:01:19.624: debug : do_open:1117 : trying driver 0 (Test) ... > 11:01:19.624: debug : do_open:1123 : driver 0 Test returned DECLINED > 11:01:19.624: debug : do_open:1117 : trying driver 1 (Xen) ... > 11:01:19.624: debug : do_open:1123 : driver 1 Xen returned DECLINED > 11:01:19.624: debug : do_open:1117 : trying driver 2 (OPENVZ) ... > 11:01:19.624: debug : do_open:1123 : driver 2 OPENVZ returned DECLINE= D > 11:01:19.624: debug : do_open:1117 : trying driver 3 (VBOX) ... > 11:01:19.624: debug : do_open:1123 : driver 3 VBOX returned DECLINED > 11:01:19.624: debug : do_open:1117 : trying driver 4 (remote) ... > 11:01:19.624: debug : doRemoteOpen:565 : proceeding with name =3D=20 > qemu:///system > 11:01:19.624: debug : virExecWithHook:620 : ssh localhost nc -q 2>&1 = |=20 > grep -q 'requires an argument';if [ $? -eq 0 ] ; then CMD=3D'-q=20 > 0';else CMD=3D'';fi;nc $CMD -U /var/run/libvirt/libvirt-sock > 11:01:19.624: debug : remoteIO:8457 : Do proc=3D66 serial=3D0 length=3D= 28=20 > wait=3D(nil) > 11:01:19.624: debug : remoteIO:8519 : We have the buck 66=20 > 0x7fcfdd6eb010 0x7fcfdd6eb010 > 11:01:22.993: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 64 total (60 more) > 11:01:22.993: debug : remoteIOEventLoop:8383 : Giving up the buck 66=20 > 0x7fcfdd6eb010 (nil) > 11:01:22.993: debug : remoteIO:8550 : All done with our call 66 (nil)= =20 > 0x7fcfdd6eb010 > 11:01:22.993: debug : remoteIO:8457 : Do proc=3D1 serial=3D1 length=3D= 56=20 > wait=3D(nil) > 11:01:22.993: debug : remoteIO:8519 : We have the buck 1 0x6c68f0=20 > 0x6c68f0 > 11:01:22.994: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 11:01:22.994: debug : remoteIOEventLoop:8383 : Giving up the buck 1=20 > 0x6c68f0 (nil) > 11:01:22.994: debug : remoteIO:8550 : All done with our call 1 (nil)=20 > 0x6c68f0 > 11:01:22.994: debug : doRemoteOpen:918 : Adding Handler for remote ev= ents > 11:01:22.994: debug : doRemoteOpen:925 : virEventAddHandle failed: No= =20 > addHandleImpl defined. continuing without events. > 11:01:22.994: debug : do_open:1123 : driver 4 remote returned SUCCESS > 11:01:22.994: debug : do_open:1143 : network driver 0 Test returned=20 > DECLINED > 11:01:22.994: debug : do_open:1143 : network driver 1 VBOX returned=20 > DECLINED > 11:01:22.994: debug : do_open:1143 : network driver 2 remote returned= =20 > SUCCESS > 11:01:22.994: debug : do_open:1162 : interface driver 0 Test returned= =20 > DECLINED > 11:01:22.994: debug : do_open:1162 : interface driver 1 remote=20 > returned SUCCESS > 11:01:22.994: debug : do_open:1182 : storage driver 0 Test returned=20 > DECLINED > 11:01:22.994: debug : do_open:1182 : storage driver 1 VBOX returned=20 > DECLINED > 11:01:22.994: debug : do_open:1182 : storage driver 2 remote returned= =20 > SUCCESS > 11:01:22.994: debug : do_open:1202 : node driver 0 Test returned DECL= INED > 11:01:22.994: debug : do_open:1202 : node driver 1 remote returned=20 > SUCCESS > 11:01:22.994: debug : do_open:1229 : secret driver 0 Test returned=20 > DECLINED > 11:01:22.994: debug : do_open:1229 : secret driver 1 remote returned=20 > SUCCESS > 11:01:22.994: debug : virDomainRestore:2284 : conn=3D0x6c0c10, from=3D= one-309 > 11:01:22.994: debug : remoteIO:8457 : Do proc=3D54 serial=3D2 length=3D= 48=20 > wait=3D(nil) > 11:01:22.994: debug : remoteIO:8519 : We have the buck 54 0x6c6490=20 > 0x6c6490 > 11:01:23.620: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 11:01:23.620: debug : remoteIOEventLoop:8383 : Giving up the buck 54=20 > 0x6c6490 (nil) > 11:01:23.621: debug : remoteIO:8550 : All done with our call 54 (nil)= =20 > 0x6c6490 > 11:01:23.621: debug : virConnectClose:1356 : conn=3D0x6c0c10 > 11:01:23.621: debug : virUnrefConnect:259 : unref connection 0x6c0c10= 1 > 11:01:23.621: debug : remoteIO:8457 : Do proc=3D2 serial=3D3 length=3D= 28=20 > wait=3D(nil) > 11:01:23.621: debug : remoteIO:8519 : We have the buck 2 0x6c6490=20 > 0x6c6490 > 11:01:23.621: debug : remoteIODecodeMessageLength:7941 : Got length,=20 > now need 56 total (52 more) > 11:01:23.621: debug : remoteIOEventLoop:8383 : Giving up the buck 2=20 > 0x6c6490 (nil) > 11:01:23.621: debug : remoteIO:8550 : All done with our call 2 (nil)=20 > 0x6c6490 > 11:01:23.622: debug : virReleaseConnect:216 : release connection 0x6c= 0c10 > > --=20 =2E....................................................................= =2E __ / / Oriol Mart=ED i Bonveh=ED C E / S / C A Departament de Sistemes i Xarxes /_/ Centre de Serveis Cient=EDfics i Acad=E8mics de Cat= alunya Gran Capit=E0, 2-4 (Edifici Nexus) =B7 08034 Barcelona T. 93 551 62 12 =B7 F. 93 205 6979 =B7 omarti@cesca.cat =2E....................................................................= =2E