Attachment 711109 Details for Bug 922398 – vdsm log

vdsm log

file_922398.txt (text/plain), 30.70 KB, created by David Jaša on 2013-03-16 16:45:47 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: David Jaša

Created: 2013-03-16 16:45:47 UTC

Size: 30.70 KB

patch

obsolete

>Thread-24::DEBUG::2013-03-16 17:26:28,511::task::568::TaskManager.Task::(_updateState) Task=`57905646-47cc-4f5c-aae8-4ba2464f8e2c`::moving from state init -> state preparing
>Thread-24::INFO::2013-03-16 17:26:28,512::logUtils::37::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='ac1a77db-f916-4d1d-98b4-39aa0d2b0afe', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='b995ed3d-315b-4cb1-a371-639c1b6c2564', volUUID='72efc0c7-7ef6-41d4-b99a-2e40d2529961', options=None)
>Thread-24::INFO::2013-03-16 17:26:28,513::logUtils::39::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '21474836480', 'apparentsize': '21474836480'}
>Thread-24::DEBUG::2013-03-16 17:26:28,514::task::1151::TaskManager.Task::(prepare) Task=`57905646-47cc-4f5c-aae8-4ba2464f8e2c`::finished: {'truesize': '21474836480', 'apparentsize': '21474836480'}
>Thread-24::DEBUG::2013-03-16 17:26:28,515::task::568::TaskManager.Task::(_updateState) Task=`57905646-47cc-4f5c-aae8-4ba2464f8e2c`::moving from state preparing -> state finished
>Thread-24::DEBUG::2013-03-16 17:26:28,516::task::957::TaskManager.Task::(_decref) Task=`57905646-47cc-4f5c-aae8-4ba2464f8e2c`::ref 0 aborting False
>Thread-98654::DEBUG::2013-03-16 17:26:32,003::BindingXMLRPC::913::vds::(wrapper) client [10.34.58.32]::call vmSetTicket with ('b079adf8-85e7-46a8-96e5-69ff1d50d2ff', 'XjIDi8XxGftp', '120', 'disconnect', {'userName': 'djasa', 'userId': '84ac0644-7ee7-4fed-9923-c3a9233ac26d'}) {}
>Thread-98654::DEBUG::2013-03-16 17:26:32,083::BindingXMLRPC::920::vds::(wrapper) return vmSetTicket with {'status': {'message': 'Done', 'code': 0}}
>Thread-98656::DEBUG::2013-03-16 17:26:33,450::BindingXMLRPC::161::vds::(wrapper) [10.34.58.32]
>Thread-98656::DEBUG::2013-03-16 17:26:33,451::task::568::TaskManager.Task::(_updateState) Task=`fe1c5730-3e09-4e6f-818c-0c4b434bb467`::moving from state init -> state preparing
>Thread-98656::DEBUG::2013-03-16 17:26:33,453::task::1151::TaskManager.Task::(prepare) Task=`fe1c5730-3e09-4e6f-818c-0c4b434bb467`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 9}}
>Thread-98656::DEBUG::2013-03-16 17:26:33,454::task::568::TaskManager.Task::(_updateState) Task=`fe1c5730-3e09-4e6f-818c-0c4b434bb467`::moving from state preparing -> state finished
>Thread-98656::DEBUG::2013-03-16 17:26:33,455::task::957::TaskManager.Task::(_decref) Task=`fe1c5730-3e09-4e6f-818c-0c4b434bb467`::ref 0 aborting False
>Thread-98657::DEBUG::2013-03-16 17:26:33,477::BindingXMLRPC::161::vds::(wrapper) [10.34.58.32]
>Thread-98657::DEBUG::2013-03-16 17:26:33,478::task::568::TaskManager.Task::(_updateState) Task=`2c7cab2a-e4e1-4689-b8a5-9e4432838895`::moving from state init -> state preparing
>Thread-98657::DEBUG::2013-03-16 17:26:33,483::task::957::TaskManager.Task::(_decref) Task=`2c7cab2a-e4e1-4689-b8a5-9e4432838895`::ref 1 aborting False
>Thread-98657::DEBUG::2013-03-16 17:26:33,486::task::1151::TaskManager.Task::(prepare) Task=`2c7cab2a-e4e1-4689-b8a5-9e4432838895`::finished: {'info': {'spm_id': 1, 'master_uuid': 'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe', 'name': 'Default', 'version': '3', 'domains': u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe:Active,9aa406d4-ded7-43f4-af90-7664ceb04d95:Active', 'pool_status': 'connected', 'isoprefix': u'/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/9aa406d4-ded7-43f4-af90-7664ceb04d95/images/11111111-1111-1111-1111-111111111111', 'type': 'ISCSI', 'master_ver': 1, 'lver': 9}, 'dominfo': {u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe': {'status': u'Active', 'diskfree': '320109281280', 'alerts': [], 'disktotal': '549621596160'}, u'9aa406d4-ded7-43f4-af90-7664ceb04d95': {'status': u'Active', 'diskfree': '9872343040', 'alerts': [], 'disktotal': '63413682176'}}}
>Thread-98657::DEBUG::2013-03-16 17:26:33,487::task::568::TaskManager.Task::(_updateState) Task=`2c7cab2a-e4e1-4689-b8a5-9e4432838895`::moving from state preparing -> state finished
>Thread-98657::DEBUG::2013-03-16 17:26:33,491::task::957::TaskManager.Task::(_decref) Task=`2c7cab2a-e4e1-4689-b8a5-9e4432838895`::ref 0 aborting False
>Thread-97806::DEBUG::2013-03-16 17:26:33,865::task::568::TaskManager.Task::(_updateState) Task=`b4bfe44e-6bbc-46d8-a19a-80a1200e9b6c`::moving from state init -> state preparing
>Thread-97806::INFO::2013-03-16 17:26:33,865::logUtils::37::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='ac1a77db-f916-4d1d-98b4-39aa0d2b0afe', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='40c6e5e0-cc13-49ab-ae1e-215a67e68dcb', volUUID='c528f76b-c098-46ff-b87a-94f6bd67f581', options=None)
>Thread-97806::INFO::2013-03-16 17:26:33,867::logUtils::39::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '3221225472', 'apparentsize': '3221225472'}
>Thread-97806::DEBUG::2013-03-16 17:26:33,867::task::1151::TaskManager.Task::(prepare) Task=`b4bfe44e-6bbc-46d8-a19a-80a1200e9b6c`::finished: {'truesize': '3221225472', 'apparentsize': '3221225472'}
>Thread-97806::DEBUG::2013-03-16 17:26:33,867::task::568::TaskManager.Task::(_updateState) Task=`b4bfe44e-6bbc-46d8-a19a-80a1200e9b6c`::moving from state preparing -> state finished
>Thread-97806::DEBUG::2013-03-16 17:26:33,869::task::957::TaskManager.Task::(_decref) Task=`b4bfe44e-6bbc-46d8-a19a-80a1200e9b6c`::ref 0 aborting False
>Thread-35::DEBUG::2013-03-16 17:26:35,030::lvm::488::OperationMutex::(_invalidatevgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-35::DEBUG::2013-03-16 17:26:35,031::lvm::490::OperationMutex::(_invalidatevgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-35::DEBUG::2013-03-16 17:26:35,032::lvm::498::OperationMutex::(_invalidatelvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-35::DEBUG::2013-03-16 17:26:35,033::lvm::510::OperationMutex::(_invalidatelvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-35::DEBUG::2013-03-16 17:26:35,033::lvm::373::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>libvirtEventLoop::DEBUG::2013-03-16 17:26:35,443::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:35,445::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:35,445::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:35,446::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>Thread-35::DEBUG::2013-03-16 17:26:35,598::lvm::402::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>libvirtEventLoop::DEBUG::2013-03-16 17:26:35,750::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,004::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,883::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,889::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,890::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:37,891::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,902::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,912::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,913::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:37,913::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,915::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,916::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,916::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:37,917::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,919::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,920::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:37,920::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:37,921::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,049::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>Thread-97349::DEBUG::2013-03-16 17:26:38,086::task::568::TaskManager.Task::(_updateState) Task=`61575c50-87f9-4061-aad2-f440a38758fc`::moving from state init -> state preparing
>Thread-97349::INFO::2013-03-16 17:26:38,087::logUtils::37::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='ac1a77db-f916-4d1d-98b4-39aa0d2b0afe', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='e63762ee-356c-4c37-b293-79fb49a621f3', volUUID='4f991ad9-8bbe-4731-accb-5fd656a1ff2e', options=None)
>Thread-97349::INFO::2013-03-16 17:26:38,089::logUtils::39::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '10737418240', 'apparentsize': '10737418240'}
>Thread-97349::DEBUG::2013-03-16 17:26:38,089::task::1151::TaskManager.Task::(prepare) Task=`61575c50-87f9-4061-aad2-f440a38758fc`::finished: {'truesize': '10737418240', 'apparentsize': '10737418240'}
>Thread-97349::DEBUG::2013-03-16 17:26:38,090::task::568::TaskManager.Task::(_updateState) Task=`61575c50-87f9-4061-aad2-f440a38758fc`::moving from state preparing -> state finished
>Thread-97349::DEBUG::2013-03-16 17:26:38,091::task::957::TaskManager.Task::(_decref) Task=`61575c50-87f9-4061-aad2-f440a38758fc`::ref 0 aborting False
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,116::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,120::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,130::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,528::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,595::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,613::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>Thread-98659::DEBUG::2013-03-16 17:26:38,687::task::568::TaskManager.Task::(_updateState) Task=`1d54c6de-ed48-40f6-97da-33ad95d7a814`::moving from state init -> state preparing
>Thread-98659::INFO::2013-03-16 17:26:38,688::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-98659::INFO::2013-03-16 17:26:38,689::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe': {'delay': '0.0284030437469', 'lastCheck': '2.7', 'code': 0, 'valid': True}, u'9aa406d4-ded7-43f4-af90-7664ceb04d95': {'delay': '0.00850510597229', 'lastCheck': '0.2', 'code': 0, 'valid': True}}
>Thread-98659::DEBUG::2013-03-16 17:26:38,690::task::1151::TaskManager.Task::(prepare) Task=`1d54c6de-ed48-40f6-97da-33ad95d7a814`::finished: {u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe': {'delay': '0.0284030437469', 'lastCheck': '2.7', 'code': 0, 'valid': True}, u'9aa406d4-ded7-43f4-af90-7664ceb04d95': {'delay': '0.00850510597229', 'lastCheck': '0.2', 'code': 0, 'valid': True}}
>Thread-98659::DEBUG::2013-03-16 17:26:38,690::task::568::TaskManager.Task::(_updateState) Task=`1d54c6de-ed48-40f6-97da-33ad95d7a814`::moving from state preparing -> state finished
>Thread-98659::DEBUG::2013-03-16 17:26:38,692::task::957::TaskManager.Task::(_decref) Task=`1d54c6de-ed48-40f6-97da-33ad95d7a814`::ref 0 aborting False
>libvirtEventLoop::DEBUG::2013-03-16 17:26:38,703::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:39,103::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:39,282::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:39,289::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:39,487::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,932::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,933::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:40,933::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,936::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,936::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:40,937::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,938::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,939::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:40,940::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,941::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:40,941::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:40,942::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,070::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,089::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,098::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,099::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,914::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,915::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:41,917::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,002::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,502::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,504::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,504::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:42,505::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,508::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,509::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 2 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,510::guestIF::240::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock called
>libvirtEventLoop::ERROR::2013-03-16 17:26:42,510::guestIF::243::vm.Vm::(desktopLock) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::desktopLock failed
>Traceback (most recent call last):
>  File "/usr/share/vdsm/guestIF.py", line 241, in desktopLock
>    self._forward("lock-screen")
>  File "/usr/share/vdsm/guestIF.py", line 135, in _forward
>    self._sock.send(message)
>error: [Errno 107] Transport endpoint is not connected
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,664::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:42,677::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 0 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme  subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:43,219::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>libvirtEventLoop::DEBUG::2013-03-16 17:26:43,313::libvirtconnection::80::vm.Vm::(__eventCallback) vmId=`b079adf8-85e7-46a8-96e5-69ff1d50d2ff`::graphics event phase 1 localAddr {'node': '10.34.58.2', 'service': '', 'family': 0} remoteAddr {'node': '10.36.116.49', 'service': '', 'family': 0}authScheme spice subject []
>Thread-98662::DEBUG::2013-03-16 17:26:43,534::BindingXMLRPC::161::vds::(wrapper) [10.34.58.32]
>Thread-98662::DEBUG::2013-03-16 17:26:43,535::task::568::TaskManager.Task::(_updateState) Task=`d8ab3798-ea4b-47c3-aa7f-04bfc34188cf`::moving from state init -> state preparing
>Thread-98662::DEBUG::2013-03-16 17:26:43,538::task::1151::TaskManager.Task::(prepare) Task=`d8ab3798-ea4b-47c3-aa7f-04bfc34188cf`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 9}}
>Thread-98662::DEBUG::2013-03-16 17:26:43,538::task::568::TaskManager.Task::(_updateState) Task=`d8ab3798-ea4b-47c3-aa7f-04bfc34188cf`::moving from state preparing -> state finished
>Thread-98662::DEBUG::2013-03-16 17:26:43,539::task::957::TaskManager.Task::(_decref) Task=`d8ab3798-ea4b-47c3-aa7f-04bfc34188cf`::ref 0 aborting False
>Thread-98663::DEBUG::2013-03-16 17:26:43,561::BindingXMLRPC::161::vds::(wrapper) [10.34.58.32]
>Thread-98663::DEBUG::2013-03-16 17:26:43,562::task::568::TaskManager.Task::(_updateState) Task=`a8122e93-1d31-4bfe-90ac-ec6b6200529f`::moving from state init -> state preparing
>Thread-98663::DEBUG::2013-03-16 17:26:43,567::task::957::TaskManager.Task::(_decref) Task=`a8122e93-1d31-4bfe-90ac-ec6b6200529f`::ref 1 aborting False
>Thread-98663::DEBUG::2013-03-16 17:26:43,571::task::1151::TaskManager.Task::(prepare) Task=`a8122e93-1d31-4bfe-90ac-ec6b6200529f`::finished: {'info': {'spm_id': 1, 'master_uuid': 'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe', 'name': 'Default', 'version': '3', 'domains': u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe:Active,9aa406d4-ded7-43f4-af90-7664ceb04d95:Active', 'pool_status': 'connected', 'isoprefix': u'/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/9aa406d4-ded7-43f4-af90-7664ceb04d95/images/11111111-1111-1111-1111-111111111111', 'type': 'ISCSI', 'master_ver': 1, 'lver': 9}, 'dominfo': {u'ac1a77db-f916-4d1d-98b4-39aa0d2b0afe': {'status': u'Active', 'diskfree': '320109281280', 'alerts': [], 'disktotal': '549621596160'}, u'9aa406d4-ded7-43f4-af90-7664ceb04d95': {'status': u'Active', 'diskfree': '9872343040', 'alerts': [], 'disktotal': '63413682176'}}}
>Thread-98663::DEBUG::2013-03-16 17:26:43,572::task::568::TaskManager.Task::(_updateState) Task=`a8122e93-1d31-4bfe-90ac-ec6b6200529f`::moving from state preparing -> state finished
>Thread-98663::DEBUG::2013-03-16 17:26:43,575::task::957::TaskManager.Task::(_decref) Task=`a8122e93-1d31-4bfe-90ac-ec6b6200529f`::ref 0 aborting False

Actions: View

Attachments on bug 922398: 711109