Facing problems while connecting virsh to XenServer 6.5 to import VMs to RHEV / oVirt

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 

Hi,

I am trying to import couple of important Virtual Machines from XenServer 6.5 (from downloaded from xenserver.org) into RHEV / oVirt environment. When I used the RHEV / oVirt Import Wizard, I was facing the issues. In order to check further, I attempted to use virsh -c ssh://root@<xenhost> command to connect and check but received the same set of errors as in RHEV / oVirt Wizard.

I am able to connect to xen server 6.5 using ssh command without password and even "sudo -u vdsm ssh root@<xenhost>" also works without any issues. To dig further I, on xen server, disabled the firewall and set SELinux to disabled and I am finding that libvirt is unable to connect to hypervisor.

I have attached the libvirt client logs for your reference.

Can someone help me to debug this and import the VMs from Xen Server 6.5 to RHEV / oVirt? We cannot recreate these Virtual Machines as these are critical ones and rebuilding will be a herculean task.

Thanks in advance.

--

Thanks & Regards,

Anantha Raghava

Do not print this e-mail unless required. Save Paper & trees. 
------ from here you can see the debug log of command virsh -c ssh://root@192.168.1.40 ---------

2016-08-11 12:15:24.011+0000: 43283: info : libvirt version: 1.2.17, package: 13.el7_2.5 (CentOS BuildSystem <http://bugs.centos.org>, 2016-06-23-14:23:27, worker1.bsys.centos.org)
2016-08-11 12:15:24.011+0000: 43283: debug : virGlobalInit:389 : register drivers
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:691 : driver=0x7f975826a640 name=Test
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:702 : registering Test as driver 0
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:691 : driver=0x7f975826c260 name=ESX
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:702 : registering ESX as driver 1
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:691 : driver=0x7f975826b0c0 name=remote
2016-08-11 12:15:24.011+0000: 43283: debug : virRegisterConnectDriver:702 : registering remote as driver 2
2016-08-11 12:15:24.011+0000: 43283: debug : virEventRegisterDefaultImpl:261 : registering default event implementation
2016-08-11 12:15:24.011+0000: 43283: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more
2016-08-11 12:15:24.011+0000: 43283: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0
2016-08-11 12:15:24.011+0000: 43283: info : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=5 events=1 cb=0x7f9757da9920 opaque=(nil) ff=(nil)
2016-08-11 12:15:24.011+0000: 43283: debug : virEventRegisterImpl:231 : addHandle=0x7f9757daa080 updateHandle=0x7f9757daa3e0 removeHandle=0x7f9757da9980 addTimeout=0x7f9757da9b00 updateTimeout=0x7f9757da9d20 removeTimeout=0x7f9757da9ef0
2016-08-11 12:15:24.011+0000: 43283: debug : virEventPollAddTimeout:230 : Used 0 timeout slots, adding at least 10 more
2016-08-11 12:15:24.011+0000: 43283: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0
2016-08-11 12:15:24.011+0000: 43283: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7f97584b7c10 opaque=0x7ffe0aa3bc30 ff=(nil)
2016-08-11 12:15:24.011+0000: 43283: debug : virConnectOpenAuth:1339 : name=ssh://root@192.168.1.40, auth=0x7f975826a400, flags=0
2016-08-11 12:15:24.011+0000: 43283: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f97587985e0 classname=virConnect
2016-08-11 12:15:24.011+0000: 43283: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9758797f20 classname=virConnectCloseCallbackData
2016-08-11 12:15:24.011+0000: 43283: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
2016-08-11 12:15:24.011+0000: 43283: debug : virConfReadFile:769 : filename=/etc/libvirt/libvirt.conf
2016-08-11 12:15:24.011+0000: 43284: debug : virThreadJobSet:99 : Thread 43284 is now running job vshEventLoop
2016-08-11 12:15:24.011+0000: 43283: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:15:24.011+0000: 43284: debug : virEventRunDefaultImpl:305 : running default event implementation
2016-08-11 12:15:24.011+0000: 43284: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:15:24.011+0000: 43284: debug : virEventPollCleanupHandles:575 : Cleanup 1
2016-08-11 12:15:24.011+0000: 43284: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=5 e=1 d=0
2016-08-11 12:15:24.011+0000: 43284: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers
2016-08-11 12:15:24.011+0000: 43284: debug : virEventPollCalculateTimeout:371 : No timeout is pending
2016-08-11 12:15:24.011+0000: 43284: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1132 : name "ssh://root@192.168.1.40" to URI components:
  scheme ssh
  server 192.168.1.40
  user root
  port 0
  path <null>

2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1179 : trying driver 0 (Test) ...
2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1194 : driver 0 Test returned DECLINED
2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1179 : trying driver 1 (ESX) ...
2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1194 : driver 1 ESX returned DECLINED
2016-08-11 12:15:24.011+0000: 43283: debug : do_open:1179 : trying driver 2 (remote) ...
2016-08-11 12:15:24.011+0000: 43283: debug : doRemoteOpen:792 : proceeding with name = ssh://
2016-08-11 12:15:24.011+0000: 43283: debug : doRemoteOpen:801 : Connecting with transport 0
2016-08-11 12:15:24.011+0000: 43283: debug : virNetTLSContextLocateCredentials:813 : pkipath=(null) isServer=0 tryUserPkiPath=0
2016-08-11 12:15:24.011+0000: 43283: debug : virNetTLSContextLocateCredentials:885 : Using default TLS CA certificate path
2016-08-11 12:15:24.011+0000: 43283: debug : virNetTLSContextLocateCredentials:891 : Using default TLS CA revocation list path
2016-08-11 12:15:24.011+0000: 43283: debug : virNetTLSContextLocateCredentials:897 : Using default TLS key/certificate path
2016-08-11 12:15:24.011+0000: 43283: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f975879a9c0 classname=virNetTLSContext
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextLoadCertFromFile:516 : isServer 0 certFile /etc/pki/libvirt/clientcert.pem
2016-08-11 12:15:24.012+0000: 43283: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextLoadCACertListFromFile:560 : certFile /etc/pki/CA/cacert.pem
2016-08-11 12:15:24.012+0000: 43283: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertBasicConstraints:189 : Cert /etc/pki/libvirt/clientcert.pem basic constraints 0
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertKeyUsage:235 : Cert /etc/pki/libvirt/clientcert.pem key usage status 0 usage 160 critical 1
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertKeyPurpose:335 : Key purpose 0 1.3.6.1.5.5.7.3.1 critical 1
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertKeyPurpose:335 : Key purpose 0 1.3.6.1.5.5.7.3.2 critical 1
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertKeyPurpose:307 : No key purpose data available at slot 2
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertBasicConstraints:189 : Cert /etc/pki/CA/cacert.pem basic constraints 1
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextCheckCertKeyUsage:235 : Cert /etc/pki/CA/cacert.pem key usage status 0 usage 6 critical 1
2016-08-11 12:15:24.012+0000: 43283: debug : virNetTLSContextLoadCredentials:642 : loading CA cert from /etc/pki/CA/cacert.pem
2016-08-11 12:15:24.013+0000: 43283: debug : virNetTLSContextLoadCredentials:671 : Skipping non-existent CA CRL /etc/pki/CA/cacrl.pem
2016-08-11 12:15:24.013+0000: 43283: debug : virNetTLSContextLoadCredentials:684 : loading cert and key from /etc/pki/libvirt/clientcert.pem and /etc/pki/libvirt/private/clientkey.pem
2016-08-11 12:15:24.014+0000: 43283: info : virNetTLSContextNew:783 : RPC_TLS_CONTEXT_NEW: ctxt=0x7f975879a9c0 cacert=/etc/pki/CA/cacert.pem cacrl=/etc/pki/CA/cacrl.pem cert=/etc/pki/libvirt/clientcert.pem key=/etc/pki/libvirt/private/clientkey.pem sanityCheckCert=1 requireValidCert=1 isServer=0
2016-08-11 12:15:24.016+0000: 43283: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:15:24.016+0000: 43283: error : virNetSocketNewConnectTCP:577 : unable to connect to server at '192.168.1.40:16514': Connection refused
2016-08-11 12:15:24.016+0000: 43283: debug : virNetClientCloseInternal:695 : client=(nil) wantclose=0
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f975879a9c0
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f975879a9c0
2016-08-11 12:15:24.016+0000: 43283: info : virNetTLSContextDispose:1169 : RPC_TLS_CONTEXT_DISPOSE: ctxt=0x7f975879a9c0
2016-08-11 12:15:24.016+0000: 43283: debug : do_open:1194 : driver 2 remote returned ERROR
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f97587985e0
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f97587985e0
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9758797f20
2016-08-11 12:15:24.016+0000: 43283: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9758797f20
2016-08-11 12:15:24.016+0000: 43283: debug : virEventPollInterruptLocked:727 : Interrupting
2016-08-11 12:15:24.016+0000: 43283: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=0 cb=0x7f97584b7ae0 opaque=(nil) ff=(nil)
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollRunOnce:651 : Poll got 1 event(s)
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollDispatchTimeouts:433 : Dispatch 2
2016-08-11 12:15:24.016+0000: 43284: info : virEventPollDispatchTimeouts:456 : EVENT_POLL_DISPATCH_TIMEOUT: timer=2
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollDispatchHandles:479 : Dispatch 1
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollDispatchHandles:493 : i=0 w=1
2016-08-11 12:15:24.016+0000: 43284: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollCleanupTimeouts:526 : Cleanup 2
2016-08-11 12:15:24.016+0000: 43284: debug : virEventPollCleanupHandles:575 : Cleanup 1
2016-08-11 12:15:24.016+0000: 43284: debug : virThreadJobClear:124 : Thread 43284 finished job vshEventLoop with ret=0
2016-08-11 12:15:24.016+0000: 43283: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=2
2016-08-11 12:15:24.016+0000: 43283: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 140287726221056
2016-08-11 12:15:24.016+0000: 43283: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
2016-08-11 12:15:24.016+0000: 43283: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 140287726221056

-------- from here you can see debug log of command virsh -c xen+ssh://root@192.168.1.40 -----------

2016-08-11 12:17:34.366+0000: 43669: info : libvirt version: 1.2.17, package: 13.el7_2.5 (CentOS BuildSystem <http://bugs.centos.org>, 2016-06-23-14:23:27, worker1.bsys.centos.org)
2016-08-11 12:17:34.366+0000: 43669: debug : virGlobalInit:389 : register drivers
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:691 : driver=0x7f9661e6a640 name=Test
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:702 : registering Test as driver 0
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:691 : driver=0x7f9661e6c260 name=ESX
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:702 : registering ESX as driver 1
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:691 : driver=0x7f9661e6b0c0 name=remote
2016-08-11 12:17:34.367+0000: 43669: debug : virRegisterConnectDriver:702 : registering remote as driver 2
2016-08-11 12:17:34.367+0000: 43669: debug : virEventRegisterDefaultImpl:261 : registering default event implementation
2016-08-11 12:17:34.367+0000: 43669: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more
2016-08-11 12:17:34.367+0000: 43669: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0
2016-08-11 12:17:34.367+0000: 43669: info : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=5 events=1 cb=0x7f96619a9920 opaque=(nil) ff=(nil)
2016-08-11 12:17:34.367+0000: 43669: debug : virEventRegisterImpl:231 : addHandle=0x7f96619aa080 updateHandle=0x7f96619aa3e0 removeHandle=0x7f96619a9980 addTimeout=0x7f96619a9b00 updateTimeout=0x7f96619a9d20 removeTimeout=0x7f96619a9ef0
2016-08-11 12:17:34.367+0000: 43669: debug : virEventPollAddTimeout:230 : Used 0 timeout slots, adding at least 10 more
2016-08-11 12:17:34.367+0000: 43669: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0
2016-08-11 12:17:34.367+0000: 43669: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7f96620b7c10 opaque=0x7ffef5e35180 ff=(nil)
2016-08-11 12:17:34.367+0000: 43669: debug : virConnectOpenAuth:1339 : name=xen+ssh://root@192.168.1.40, auth=0x7f9661e6a400, flags=0
2016-08-11 12:17:34.367+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663423ec0 classname=virConnect
2016-08-11 12:17:34.367+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663421f20 classname=virConnectCloseCallbackData
2016-08-11 12:17:34.367+0000: 43670: debug : virThreadJobSet:99 : Thread 43670 is now running job vshEventLoop
2016-08-11 12:17:34.367+0000: 43670: debug : virEventRunDefaultImpl:305 : running default event implementation
2016-08-11 12:17:34.367+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.367+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 1
2016-08-11 12:17:34.367+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=5 e=1 d=0
2016-08-11 12:17:34.367+0000: 43669: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
2016-08-11 12:17:34.367+0000: 43670: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers
2016-08-11 12:17:34.367+0000: 43670: debug : virEventPollCalculateTimeout:371 : No timeout is pending
2016-08-11 12:17:34.367+0000: 43669: debug : virConfReadFile:769 : filename=/etc/libvirt/libvirt.conf
2016-08-11 12:17:34.367+0000: 43670: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2016-08-11 12:17:34.367+0000: 43669: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1132 : name "xen+ssh://root@192.168.1.40" to URI components:
  scheme xen+ssh
  server 192.168.1.40
  user root
  port 0
  path <null>

2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1179 : trying driver 0 (Test) ...
2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1194 : driver 0 Test returned DECLINED
2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1179 : trying driver 1 (ESX) ...
2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1194 : driver 1 ESX returned DECLINED
2016-08-11 12:17:34.367+0000: 43669: debug : do_open:1179 : trying driver 2 (remote) ...
2016-08-11 12:17:34.367+0000: 43669: debug : doRemoteOpen:792 : proceeding with name = xen://
2016-08-11 12:17:34.367+0000: 43669: debug : doRemoteOpen:801 : Connecting with transport 2
2016-08-11 12:17:34.368+0000: 43669: debug : virCommandRunAsync:2428 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/root/bin HOME=/root USER=root LOGNAME=root ssh -l root 192.168.1.40 sh -c ''\''if '\''nc'\'' -q 2>&1 | grep "requires an argument" >/dev/null 2>&1; then ARG=-q0;else ARG=;fi;'\''nc'\'' $ARG -U /var/run/libvirt/libvirt-sock'\'''
2016-08-11 12:17:34.368+0000: 43669: debug : virCommandRunAsync:2431 : Command result 0, with PID 43671
2016-08-11 12:17:34.368+0000: 43669: debug : virFileClose:102 : Closed fd 8
2016-08-11 12:17:34.368+0000: 43669: debug : virFileClose:102 : Closed fd 10
2016-08-11 12:17:34.368+0000: 43669: debug : virNetSocketNew:225 : localAddr=(nil) remoteAddr=(nil) fd=7 errfd=9 pid=43671
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663424d90 classname=virNetSocket
2016-08-11 12:17:34.368+0000: 43669: info : virNetSocketNew:276 : RPC_SOCKET_NEW: sock=0x7f9663424d90 fd=7 errfd=9 pid=43671 localAddr=<null>, remoteAddr=<null>
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663424fd0 classname=virNetClient
2016-08-11 12:17:34.368+0000: 43669: info : virNetClientNew:327 : RPC_CLIENT_NEW: client=0x7f9663424fd0 sock=0x7f9663424d90
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424fd0
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424d90
2016-08-11 12:17:34.368+0000: 43669: debug : virEventPollInterruptLocked:727 : Interrupting
2016-08-11 12:17:34.368+0000: 43669: info : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=2 fd=7 events=1 cb=0x7f9661afbfb0 opaque=0x7f9663424d90 ff=0x7f9661afbf60
2016-08-11 12:17:34.368+0000: 43669: debug : virKeepAliveNew:199 : client=0x7f9663424fd0, interval=-1, count=0
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663424c10 classname=virKeepAlive
2016-08-11 12:17:34.368+0000: 43669: info : virKeepAliveNew:218 : RPC_KEEPALIVE_NEW: ka=0x7f9663424c10 client=0x7f9663424fd0
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424fd0
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663421f20
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollRunOnce:651 : Poll got 1 event(s)
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchTimeouts:433 : Dispatch 1
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f96634249f0 classname=virNetClientProgram
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchHandles:479 : Dispatch 1
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchHandles:493 : i=0 w=1
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663424ba0 classname=virNetClientProgram
2016-08-11 12:17:34.368+0000: 43669: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9663424950 classname=virNetClientProgram
2016-08-11 12:17:34.368+0000: 43670: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f96634249f0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424ba0
2016-08-11 12:17:34.368+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424950
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 2
2016-08-11 12:17:34.368+0000: 43669: debug : doRemoteOpen:1010 : Trying authentication
2016-08-11 12:17:34.368+0000: 43670: debug : virEventRunDefaultImpl:305 : running default event implementation
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.368+0000: 43669: debug : virNetMessageNew:46 : msg=0x7f96634251e0 tracked=0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 2
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=5 e=1 d=0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=1 d=0
2016-08-11 12:17:34.368+0000: 43669: debug : virNetMessageEncodePayload:376 : Encode length as 28
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers
2016-08-11 12:17:34.368+0000: 43669: info : virNetClientSendInternal:1950 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f9663424fd0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCalculateTimeout:371 : No timeout is pending
2016-08-11 12:17:34.368+0000: 43669: debug : virNetClientCallNew:1903 : New call 0x7f9663425100: msg=0x7f96634251e0, expectReply=1, nonBlock=0
2016-08-11 12:17:34.368+0000: 43670: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2016-08-11 12:17:34.368+0000: 43669: debug : virNetClientIO:1712 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
2016-08-11 12:17:34.368+0000: 43669: debug : virNetClientIO:1771 : We have the buck head=0x7f9663425100 call=0x7f9663425100
2016-08-11 12:17:34.368+0000: 43669: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2016-08-11 12:17:34.368+0000: 43669: debug : virEventPollInterruptLocked:727 : Interrupting
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollRunOnce:651 : Poll got 1 event(s)
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchTimeouts:433 : Dispatch 1
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchHandles:479 : Dispatch 2
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollDispatchHandles:493 : i=0 w=1
2016-08-11 12:17:34.368+0000: 43670: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 2
2016-08-11 12:17:34.368+0000: 43670: debug : virEventRunDefaultImpl:305 : running default event implementation
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.368+0000: 43671: info : libvirt version: 1.2.17, package: 13.el7_2.5 (CentOS BuildSystem <http://bugs.centos.org>, 2016-06-23-14:23:27, worker1.bsys.centos.org)
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 2
2016-08-11 12:17:34.368+0000: 43671: debug : virExec:730 : Setting child uid:gid to -1:-1 with caps 0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=5 e=1 d=0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=0 d=0
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers
2016-08-11 12:17:34.368+0000: 43670: debug : virEventPollCalculateTimeout:371 : No timeout is pending
2016-08-11 12:17:34.368+0000: 43670: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2016-08-11 12:17:34.632+0000: 43669: error : virNetSocketReadWire:1590 : End of file while reading data: 2016-08-11 12:17:34.368+0000: 43671: debug : virFileClose:102 : Closed fd 8
2016-08-11 12:17:34.368+0000: 43671: debug : virFileClose:102 : Closed fd 10
2016-08-11 12:17:34.368+0000: 43671: debug : virLogParseOutputs:1091 : outputs=1:file:/tmp/libvirt_client.log: Input/output error
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientMarkClose:638 : client=0x7f9663424fd0, reason=0
2016-08-11 12:17:34.632+0000: 43669: info : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2
2016-08-11 12:17:34.632+0000: 43669: debug : virEventPollRemoveHandle:199 : mark delete 1 7
2016-08-11 12:17:34.632+0000: 43669: debug : virEventPollInterruptLocked:727 : Interrupting
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientIOEventLoopPassTheBuck:1419 : Giving up the buck 0x7f9663425100
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientIOEventLoopPassTheBuck:1433 : No thread to pass the buck to
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientCloseLocked:654 : client=0x7f9663424fd0, sock=0x7f9663424d90, reason=0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424d90
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollRunOnce:651 : Poll got 1 event(s)
2016-08-11 12:17:34.632+0000: 43669: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchTimeouts:433 : Dispatch 1
2016-08-11 12:17:34.632+0000: 43669: info : virKeepAliveStop:310 : RPC_KEEPALIVE_STOP: ka=0x7f9663424c10 client=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424c10
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchHandles:479 : Dispatch 1
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663424c10
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchHandles:493 : i=0 w=1
2016-08-11 12:17:34.632+0000: 43669: info : virKeepAliveDispose:230 : RPC_KEEPALIVE_DISPOSE: ka=0x7f9663424c10
2016-08-11 12:17:34.632+0000: 43670: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 2
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientIO:1801 : All done with our call head=(nil) call=0x7f9663425100 rv=-1
2016-08-11 12:17:34.632+0000: 43670: info : virEventPollCleanupHandles:588 : EVENT_POLL_PURGE_HANDLE: watch=2
2016-08-11 12:17:34.632+0000: 43669: debug : virNetMessageFree:74 : msg=0x7f96634251e0 nfds=0 cb=(nil)
2016-08-11 12:17:34.632+0000: 43670: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f96634249f0
2016-08-11 12:17:34.632+0000: 43670: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424d90
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424ba0
2016-08-11 12:17:34.632+0000: 43670: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663424d90
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424950
2016-08-11 12:17:34.632+0000: 43670: info : virNetSocketDispose:1155 : RPC_SOCKET_DISPOSE: sock=0x7f9663424d90
2016-08-11 12:17:34.632+0000: 43669: debug : virNetClientCloseInternal:695 : client=0x7f9663424fd0 wantclose=0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43669: info : virNetClientDispose:606 : RPC_CLIENT_DISPOSE: client=0x7f9663424fd0
2016-08-11 12:17:34.632+0000: 43670: debug : virFileClose:102 : Closed fd 7
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663421f20
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f96634249f0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f96634249f0
2016-08-11 12:17:34.632+0000: 43670: debug : virFileClose:102 : Closed fd 9
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424ba0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663424ba0
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663424950
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663424950
2016-08-11 12:17:34.632+0000: 43670: debug : virProcessAbort:167 : aborting child process 43671
2016-08-11 12:17:34.632+0000: 43669: debug : virFileClose:102 : Closed fd 10
2016-08-11 12:17:34.632+0000: 43669: debug : virFileClose:102 : Closed fd 8
2016-08-11 12:17:34.632+0000: 43669: debug : virNetMessageClear:57 : msg=0x7f9663425038 nfds=0
2016-08-11 12:17:34.632+0000: 43669: debug : do_open:1194 : driver 2 remote returned ERROR
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663423ec0
2016-08-11 12:17:34.632+0000: 43670: debug : virProcessAbort:172 : process has ended: exit status 1
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663423ec0
2016-08-11 12:17:34.632+0000: 43670: debug : virEventRunDefaultImpl:305 : running default event implementation
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9663421f20
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 1
2016-08-11 12:17:34.632+0000: 43669: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9663421f20
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 1
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=5 e=1 d=0
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCalculateTimeout:371 : No timeout is pending
2016-08-11 12:17:34.632+0000: 43670: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2016-08-11 12:17:34.632+0000: 43669: debug : virEventPollInterruptLocked:727 : Interrupting
2016-08-11 12:17:34.632+0000: 43669: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=0 cb=0x7f96620b7ae0 opaque=(nil) ff=(nil)
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollRunOnce:651 : Poll got 1 event(s)
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchTimeouts:433 : Dispatch 2
2016-08-11 12:17:34.632+0000: 43670: info : virEventPollDispatchTimeouts:456 : EVENT_POLL_DISPATCH_TIMEOUT: timer=2
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchHandles:479 : Dispatch 1
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollDispatchHandles:493 : i=0 w=1
2016-08-11 12:17:34.632+0000: 43670: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupTimeouts:526 : Cleanup 2
2016-08-11 12:17:34.632+0000: 43670: debug : virEventPollCleanupHandles:575 : Cleanup 1
2016-08-11 12:17:34.632+0000: 43670: debug : virThreadJobClear:124 : Thread 43670 finished job vshEventLoop with ret=0
2016-08-11 12:17:34.632+0000: 43669: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=2
2016-08-11 12:17:34.632+0000: 43669: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 140283594831616
2016-08-11 12:17:34.632+0000: 43669: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
2016-08-11 12:17:34.632+0000: 43669: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 140283594831616

_______________________________________________
libvirt-users mailing list
libvirt-users@xxxxxxxxxx
https://www.redhat.com/mailman/listinfo/libvirt-users
[Index of Archives]     [Virt Tools]     [Lib OS Info]     [Fedora Users]     [Fedora Desktop]     [Fedora SELinux]     [Yosemite News]     [KDE Users]

  Powered by Linux