Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 844617 Details for
Bug 1047944
Can't poweroff or destroy virtual machine via vdsClient after sysprep attached
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
vdsm.log
vdsm.log (text/x-log), 63.51 KB, created by
Artyom
on 2014-01-02 16:37:03 UTC
(
hide
)
Description:
vdsm.log
Filename:
MIME Type:
Creator:
Artyom
Created:
2014-01-02 16:37:03 UTC
Size:
63.51 KB
patch
obsolete
>Thread-42::DEBUG::2014-01-02 18:20:00,730::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:00,747::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.000304267 s, 1.9 MB/s\n'; <rc> = 0 >Thread-37::DEBUG::2014-01-02 18:20:01,744::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:01,760::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000810154 s, 5.1 MB/s\n'; <rc> = 0 >Thread-38::DEBUG::2014-01-02 18:20:01,788::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:01,803::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.00031382 s, 1.1 MB/s\n'; <rc> = 0 >Thread-302::DEBUG::2014-01-02 18:20:01,883::task::579::TaskManager.Task::(_updateState) Task=`496e93b2-5cd8-49a5-8ec6-ec47c4a63bc1`::moving from state init -> state preparing >Thread-302::INFO::2014-01-02 18:20:01,883::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None) >Thread-302::INFO::2014-01-02 18:20:01,884::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000810154', 'lastCheck': '0.1', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00031382', 'lastCheck': '0.1', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000304267', 'lastCheck': '1.1', 'valid': True}} >Thread-302::DEBUG::2014-01-02 18:20:01,884::task::1168::TaskManager.Task::(prepare) Task=`496e93b2-5cd8-49a5-8ec6-ec47c4a63bc1`::finished: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000810154', 'lastCheck': '0.1', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00031382', 'lastCheck': '0.1', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000304267', 'lastCheck': '1.1', 'valid': True}} >Thread-302::DEBUG::2014-01-02 18:20:01,884::task::579::TaskManager.Task::(_updateState) Task=`496e93b2-5cd8-49a5-8ec6-ec47c4a63bc1`::moving from state preparing -> state finished >Thread-302::DEBUG::2014-01-02 18:20:01,884::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-302::DEBUG::2014-01-02 18:20:01,885::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-302::DEBUG::2014-01-02 18:20:01,885::task::974::TaskManager.Task::(_decref) Task=`496e93b2-5cd8-49a5-8ec6-ec47c4a63bc1`::ref 0 aborting False >Thread-42::DEBUG::2014-01-02 18:20:10,763::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:10,780::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.00035849 s, 1.6 MB/s\n'; <rc> = 0 >Thread-37::DEBUG::2014-01-02 18:20:11,779::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:11,795::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.00105821 s, 3.9 MB/s\n'; <rc> = 0 >Thread-38::DEBUG::2014-01-02 18:20:11,819::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:11,835::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.000369224 s, 948 kB/s\n'; <rc> = 0 >Thread-308::DEBUG::2014-01-02 18:20:16,980::task::579::TaskManager.Task::(_updateState) Task=`ebc92a00-00f9-4993-aa52-36fef5f63e8f`::moving from state init -> state preparing >Thread-308::INFO::2014-01-02 18:20:16,981::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None) >Thread-308::INFO::2014-01-02 18:20:16,981::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.00105821', 'lastCheck': '5.2', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000369224', 'lastCheck': '5.1', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00035849', 'lastCheck': '6.2', 'valid': True}} >Thread-308::DEBUG::2014-01-02 18:20:16,981::task::1168::TaskManager.Task::(prepare) Task=`ebc92a00-00f9-4993-aa52-36fef5f63e8f`::finished: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.00105821', 'lastCheck': '5.2', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000369224', 'lastCheck': '5.1', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00035849', 'lastCheck': '6.2', 'valid': True}} >Thread-308::DEBUG::2014-01-02 18:20:16,982::task::579::TaskManager.Task::(_updateState) Task=`ebc92a00-00f9-4993-aa52-36fef5f63e8f`::moving from state preparing -> state finished >Thread-308::DEBUG::2014-01-02 18:20:16,982::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-308::DEBUG::2014-01-02 18:20:16,982::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-308::DEBUG::2014-01-02 18:20:16,982::task::974::TaskManager.Task::(_decref) Task=`ebc92a00-00f9-4993-aa52-36fef5f63e8f`::ref 0 aborting False >Thread-42::DEBUG::2014-01-02 18:20:20,794::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:20,811::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.000399218 s, 1.4 MB/s\n'; <rc> = 0 >Thread-37::DEBUG::2014-01-02 18:20:21,812::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:21,828::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000475847 s, 8.6 MB/s\n'; <rc> = 0 >Thread-38::DEBUG::2014-01-02 18:20:21,851::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:21,867::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.000949717 s, 369 kB/s\n'; <rc> = 0 >Thread-42::DEBUG::2014-01-02 18:20:30,829::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:30,845::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.000363597 s, 1.6 MB/s\n'; <rc> = 0 >Thread-38::DEBUG::2014-01-02 18:20:31,881::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:31,897::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.000810407 s, 432 kB/s\n'; <rc> = 0 >Thread-314::DEBUG::2014-01-02 18:20:32,086::task::579::TaskManager.Task::(_updateState) Task=`5704af14-9a54-4033-855d-f479bca5911a`::moving from state init -> state preparing >Thread-314::INFO::2014-01-02 18:20:32,087::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None) >Thread-314::INFO::2014-01-02 18:20:32,087::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000475847', 'lastCheck': '10.3', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000810407', 'lastCheck': '0.2', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000363597', 'lastCheck': '1.2', 'valid': True}} >Thread-314::DEBUG::2014-01-02 18:20:32,088::task::1168::TaskManager.Task::(prepare) Task=`5704af14-9a54-4033-855d-f479bca5911a`::finished: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.000475847', 'lastCheck': '10.3', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000810407', 'lastCheck': '0.2', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.000363597', 'lastCheck': '1.2', 'valid': True}} >Thread-314::DEBUG::2014-01-02 18:20:32,088::task::579::TaskManager.Task::(_updateState) Task=`5704af14-9a54-4033-855d-f479bca5911a`::moving from state preparing -> state finished >Thread-314::DEBUG::2014-01-02 18:20:32,088::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-314::DEBUG::2014-01-02 18:20:32,088::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-314::DEBUG::2014-01-02 18:20:32,089::task::974::TaskManager.Task::(_decref) Task=`5704af14-9a54-4033-855d-f479bca5911a`::ref 0 aborting False >Thread-37::DEBUG::2014-01-02 18:20:32,926::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:32,942::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000506418 s, 8.1 MB/s\n'; <rc> = 0 >Thread-42::DEBUG::2014-01-02 18:20:40,860::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:40,877::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.00035033 s, 1.6 MB/s\n'; <rc> = 0 >Thread-38::DEBUG::2014-01-02 18:20:41,914::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:41,931::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.00087151 s, 402 kB/s\n'; <rc> = 0 >Thread-37::DEBUG::2014-01-02 18:20:42,960::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:42,979::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.00244155 s, 1.7 MB/s\n'; <rc> = 0 >Thread-319::DEBUG::2014-01-02 18:20:43,254::BindingXMLRPC::984::vds::(wrapper) client [10.35.161.250]::call vmCreate with ({'custom': {}, 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'acpiEnable': 'true', 'emulatedMachine': 'rhel6.5.0', 'cpuType': 'Conroe', 'vmId': '68274f69-a66c-40c1-991e-58f5a7f2faec', 'devices': [{'index': '0', 'iface': 'fdc', 'specParams': {'vmPayload': {'file': {'sysprep.inf': 'PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0i\ndXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9\nIndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3Mt\nU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4\nNTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxu\nczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUi\nIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0K\nICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAg\nICAgICAgICAgICAgICAgICA8S2V5PjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtl\neT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAg\nICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxP\ncmdhbml6YXRpb24+c2NsLmxhYi50bHYucmVkaGF0LmNvbTwvT3JnYW5pemF0aW9uPg0KICAgICAg\nICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAg\nICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQ\nYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAg\nICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2Nv\nbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRl\ncm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNL\nZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29w\nZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29u\nZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hl\nbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAg\nICAgICA8VUlMYW5ndWFnZT5lbi1VUzwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBV\nSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPmVuLVVTPC9JbnB1dExvY2FsZT4N\nCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPmVuLVVTPC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAg\nPFN5c3RlbUxvY2FsZT5lbi1VUzwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2Nh\nbGU+ZW4tVVM8L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGlu\nZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50\nIG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1\ncmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0\ncmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNy\nb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMu\nb3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAg\nICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAg\nIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAy\nNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1\nPC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwv\nVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAg\nPENvbXB1dGVyTmFtZT53aW5kb3dzX3ZtPC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGlt\nZVpvbmU+ODU8L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBv\nbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3Nv\nckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFu\nZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9z\nY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0\ncDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElu\ncHV0TG9jYWxlPmVuLVVTPC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPmVu\nLVVTPC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT5lbi1VUzwvU3lzdGVt\ >nTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+ZW4tVVM8L1VJTGFuZ3VhZ2U+DQogICAg\nICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5k\nb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNL\nZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29w\nZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29u\nZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hl\nbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAg\nICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48L0RvbWFpbj4N\nCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAg\nICAgICAgIDxVc2VybmFtZT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlh\nbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PC9Kb2luRG9tYWluPg0KICAgICAgICAg\nICAgPC9JZGVudGlmaWNhdGlvbj4NCiAgICAgICAgPC9jb21wb25lbnQ+DQogICAgPC9zZXR0aW5n\ncz4NCiAgICA8c2V0dGluZ3MgcGFzcz0ib29iZVN5c3RlbSI+DQogICAgICAgIDxjb21wb25lbnQg\nbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtSW50ZXJuYXRpb25hbC1Db3JlIiBwcm9jZXNzb3JBcmNo\naXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdl\nPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1h\ncy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93\nd3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxJbnB1dExv\nY2FsZT5lbi1VUzwvSW5wdXRMb2NhbGU+DQogICAgICAgICAgICA8VXNlckxvY2FsZT5lbi1VUzwv\nVXNlckxvY2FsZT4NCiAgICAgICAgICAgIDxTeXN0ZW1Mb2NhbGU+ZW4tVVM8L1N5c3RlbUxvY2Fs\nZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPmVuLVVTPC9VSUxhbmd1YWdlPg0KICAgICAgICA8\nL2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1T\naGVsbC1TZXR1cCIgcHJvY2Vzc29yQXJjaGl0ZWN0dXJlPSJhbWQ2NCIgcHVibGljS2V5VG9rZW49\nIjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4\nUyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAy\nL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3Rh\nbmNlIj4NCiAgICAgICAgICAgIDxPT0JFPg0KICAgICAgICAgICAgICAgIDxQcm90ZWN0WW91clBD\nPjI8L1Byb3RlY3RZb3VyUEM+DQogICAgICAgICAgICAgICAgPE5ldHdvcmtMb2NhdGlvbj5Xb3Jr\nPC9OZXR3b3JrTG9jYXRpb24+DQogICAgICAgICAgICAgICAgPEhpZGVFVUxBUGFnZT50cnVlPC9I\naWRlRVVMQVBhZ2U+DQogICAgICAgICAgICA8L09PQkU+DQogICAgICAgICAgICA8VXNlckFjY291\nbnRzPg0KICAgICAgICAgICAgICAgIDxBZG1pbmlzdHJhdG9yUGFzc3dvcmQ+DQogICAgICAgICAg\nICAgICAgICAgIDxWYWx1ZT4xMjM0NTY8L1ZhbHVlPg0KICAgICAgICAgICAgICAgICAgICA8UGxh\naW5UZXh0PnRydWU8L1BsYWluVGV4dD4NCiAgICAgICAgICAgICAgICA8L0FkbWluaXN0cmF0b3JQ\nYXNzd29yZD4NCiAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgICAg\nICAgICAgPExvY2FsQWNjb3VudCB3Y206YWN0aW9uPSJhZGQiPg0KICAgICAgICAgICAgICAgICAg\nICAgICAgPFBhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgICAgIDxWYWx1ZT4xMjM0\nNTY8L1ZhbHVlPg0KICAgICAgICAgICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwv\nUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgICAgICAgICAgPC9QYXNzd29yZD4NCiAgICAgICAg\nICAgICAgICAgICAgICAgIDxHcm91cD5hZG1pbmlzdHJhdG9yczwvR3JvdXA+DQogICAgICAgICAg\nICAgICAgICAgICAgICA8TmFtZT51c2VyPC9OYW1lPg0KICAgICAgICAgICAgICAgICAgICAgICAg\nPERpc3BsYXlOYW1lPnVzZXI8L0Rpc3BsYXlOYW1lPg0KICAgICAgICAgICAgICAgICAgICA8L0xv\nY2FsQWNjb3VudD4NCiAgICAgICAgICAgICAgICA8L0xvY2FsQWNjb3VudHM+DQogICAgICAgICAg\nICA8L1VzZXJBY2NvdW50cz4NCiAgICAgICAgPC9jb21wb25lbnQ+DQogICAgPC9zZXR0aW5ncz4N\nCiAgICA8Y3BpOm9mZmxpbmVJbWFnZSBjcGk6c291cmNlPSJ3aW06ZDovc291cmNlcy9pbnN0YWxs\nLndpbSNXaW5kb3dzIDcgRU5URVJQUklTRSIgeG1sbnM6Y3BpPSJ1cm46c2NoZW1hcy1taWNyb3Nv\nZnQtY29tOmNwaSIvPg0KPC91bmF0dGVuZD4NCg==\n'}}}, 'readonly': 'true', 'deviceId': '70ca7bf3-e2f3-4d1b-be2c-6cf92788fd72', 'path': '', 'device': 'floppy', 'shared': 'false', 'type': 'disk'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'heads': '1'}, 'type': 'video', 'deviceId': '616b80db-06f0-45c9-8cb0-f1d8a12cf5a2', 'address': {'bus': '0x00', ' slot': '0x02', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}}, {'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': '44ba830e-fb13-43a9-9af7-a320acb1fae6', 'path': '/rhev/data-center/mnt/ >multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.3_9.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'ide', 'format': 'raw', 'bootOrder': '1', 'poolID': 'b32005e6-26e4-4a55-900c-1c05fb30cca1', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5', 'specParams': {}, 'readonly': 'false', 'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'optional': 'false', 'deviceId': '072b9067-5a8f-4dad-bdcb-0174b55cebb5', 'address': {' controller': '0', ' target': '0', 'unit': '0', ' bus': '0', ' type': 'drive'}, 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:b5:b6:c8', 'linkActive': 'true', 'network': 'rhevm', 'bootOrder': '2', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': 'ce2da697-d6ef-4424-a79d-78740ed28e86', 'address': {'bus': '0x00', ' slot': '0x03', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': 'c20b88a9-6de1-4f49-93f5-1bb10799181f'}, {'specParams': {}, 'deviceId': 'bee91147-048e-4483-a433-70932c386054', 'address': {'bus': '0x00', ' slot': '0x04', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}], 'smp': '1', 'vmType': 'kvm', 'smartcardEnable': 'false', 'timeOffset': '0', 'memSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'smpCoresPerSocket': '1', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'memGuaranteedSize': 1024, 'vmName': 'windows_vm', 'display': 'qxl', 'transparentHugePages': 'true', 'nice': '0'},) {} flowID [3e3e92bc] >Thread-319::INFO::2014-01-02 18:20:43,258::API::648::vds::(_getNetworkIp) network None: using 0 >Thread-319::INFO::2014-01-02 18:20:43,258::clientIF::393::vds::(createVm) vmContainerLock acquired by vm 68274f69-a66c-40c1-991e-58f5a7f2faec >Thread-319::DEBUG::2014-01-02 18:20:43,258::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt >Thread-320::DEBUG::2014-01-02 18:20:43,306::vm::2115::vm.Vm::(_startUnderlyingVm) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Start >Thread-319::DEBUG::2014-01-02 18:20:43,306::clientIF::406::vds::(createVm) Total desktops after creation of 68274f69-a66c-40c1-991e-58f5a7f2faec is 1 >Thread-320::DEBUG::2014-01-02 18:20:43,307::vm::2119::vm.Vm::(_startUnderlyingVm) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::_ongoingCreations acquired >Thread-319::DEBUG::2014-01-02 18:20:43,307::BindingXMLRPC::991::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'rhel6.5.0', 'vmId': '68274f69-a66c-40c1-991e-58f5a7f2faec', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Conroe', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'iface': 'fdc', 'specParams': {'vmPayload': {'file': {'sysprep.inf': 'PD94bWwgdmVyc2lvbj0iMS4wIiBlbmNvZGluZz0iVVRGLTgiPz4NCjx1bmF0dGVuZCB4bWxucz0i\ndXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTp1bmF0dGVuZCI+DQogICAgPHNldHRpbmdzIHBhc3M9\nIndpbmRvd3NQRSI+DQogICAgICAgIDxjb21wb25lbnQgbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3Mt\nU2V0dXAiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4\nNTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxu\nczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUi\nIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0K\nICAgICAgICAgICAgPFVzZXJEYXRhPg0KICAgICAgICAgICAgICAgIDxQcm9kdWN0S2V5Pg0KICAg\nICAgICAgICAgICAgICAgICA8S2V5PjwvS2V5Pg0KICAgICAgICAgICAgICAgIDwvUHJvZHVjdEtl\neT4NCiAgICAgICAgICAgICAgICA8QWNjZXB0RXVsYT50cnVlPC9BY2NlcHRFdWxhPg0KICAgICAg\nICAgICAgICAgIDxGdWxsTmFtZT4idXNlciI8L0Z1bGxOYW1lPg0KICAgICAgICAgICAgICAgIDxP\ncmdhbml6YXRpb24+c2NsLmxhYi50bHYucmVkaGF0LmNvbTwvT3JnYW5pemF0aW9uPg0KICAgICAg\nICAgICAgPC9Vc2VyRGF0YT4NCiAgICAgICAgICAgIDxJbWFnZUluc3RhbGw+DQogICAgICAgICAg\nICAgICAgPE9TSW1hZ2U+DQogICAgICAgICAgICAgICAgICAgIDxJbnN0YWxsVG9BdmFpbGFibGVQ\nYXJ0aXRpb24+dHJ1ZTwvSW5zdGFsbFRvQXZhaWxhYmxlUGFydGl0aW9uPg0KICAgICAgICAgICAg\nICAgIDwvT1NJbWFnZT4NCiAgICAgICAgICAgIDwvSW1hZ2VJbnN0YWxsPg0KICAgICAgICA8L2Nv\nbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRl\ncm5hdGlvbmFsLUNvcmUtV2luUEUiIHByb2Nlc3NvckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNL\nZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29w\nZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29u\nZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hl\nbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPFNldHVwVUlMYW5ndWFnZT4NCiAgICAgICAgICAg\nICAgICA8VUlMYW5ndWFnZT5lbi1VUzwvVUlMYW5ndWFnZT4NCiAgICAgICAgICAgIDwvU2V0dXBV\nSUxhbmd1YWdlPg0KICAgICAgICAgICAgPElucHV0TG9jYWxlPmVuLVVTPC9JbnB1dExvY2FsZT4N\nCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPmVuLVVTPC9VSUxhbmd1YWdlPg0KICAgICAgICAgICAg\nPFN5c3RlbUxvY2FsZT5lbi1VUzwvU3lzdGVtTG9jYWxlPg0KICAgICAgICAgICAgPFVzZXJMb2Nh\nbGU+ZW4tVVM8L1VzZXJMb2NhbGU+DQogICAgICAgIDwvY29tcG9uZW50Pg0KICAgIDwvc2V0dGlu\nZ3M+DQogICAgPHNldHRpbmdzIHBhc3M9InNwZWNpYWxpemUiPg0KICAgICAgICA8Y29tcG9uZW50\nIG5hbWU9Ik1pY3Jvc29mdC1XaW5kb3dzLVNoZWxsLVNldHVwIiBwcm9jZXNzb3JBcmNoaXRlY3R1\ncmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0\ncmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNy\nb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMu\nb3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxEaXNwbGF5Pg0KICAg\nICAgICAgICAgICAgIDxDb2xvckRlcHRoPjMyPC9Db2xvckRlcHRoPg0KICAgICAgICAgICAgICAg\nIDxEUEk+OTY8L0RQST4NCiAgICAgICAgICAgICAgICA8SG9yaXpvbnRhbFJlc29sdXRpb24+MTAy\nNDwvSG9yaXpvbnRhbFJlc29sdXRpb24+DQogICAgICAgICAgICAgICAgPFJlZnJlc2hSYXRlPjc1\nPC9SZWZyZXNoUmF0ZT4NCiAgICAgICAgICAgICAgICA8VmVydGljYWxSZXNvbHV0aW9uPjc2ODwv\nVmVydGljYWxSZXNvbHV0aW9uPg0KICAgICAgICAgICAgPC9EaXNwbGF5Pg0KICAgICAgICAgICAg\nPENvbXB1dGVyTmFtZT53aW5kb3dzX3ZtPC9Db21wdXRlck5hbWU+DQogICAgICAgICAgICA8VGlt\nZVpvbmU+ODU8L1RpbWVab25lPg0KICAgICAgICA8L2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBv\nbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1JbnRlcm5hdGlvbmFsLUNvcmUiIHByb2Nlc3Nv\nckFyY2hpdGVjdHVyZT0ieDg2IiBwdWJsaWNLZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFu\nZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29wZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9z\ >nY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29uZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0\ncDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hlbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElu\ncHV0TG9jYWxlPmVuLVVTPC9JbnB1dExvY2FsZT4NCiAgICAgICAgICAgIDxVc2VyTG9jYWxlPmVu\nLVVTPC9Vc2VyTG9jYWxlPg0KICAgICAgICAgICAgPFN5c3RlbUxvY2FsZT5lbi1VUzwvU3lzdGVt\nTG9jYWxlPg0KICAgICAgICAgICAgPFVJTGFuZ3VhZ2U+ZW4tVVM8L1VJTGFuZ3VhZ2U+DQogICAg\nICAgIDwvY29tcG9uZW50Pg0KICAgICAgICA8Y29tcG9uZW50IG5hbWU9Ik1pY3Jvc29mdC1XaW5k\nb3dzLVVuYXR0ZW5kZWRKb2luIiBwcm9jZXNzb3JBcmNoaXRlY3R1cmU9ImFtZDY0IiBwdWJsaWNL\nZXlUb2tlbj0iMzFiZjM4NTZhZDM2NGUzNSIgbGFuZ3VhZ2U9Im5ldXRyYWwiIHZlcnNpb25TY29w\nZT0ibm9uU3hTIiB4bWxuczp3Y209Imh0dHA6Ly9zY2hlbWFzLm1pY3Jvc29mdC5jb20vV01JQ29u\nZmlnLzIwMDIvU3RhdGUiIHhtbG5zOnhzaT0iaHR0cDovL3d3dy53My5vcmcvMjAwMS9YTUxTY2hl\nbWEtaW5zdGFuY2UiPg0KICAgICAgICAgICAgPElkZW50aWZpY2F0aW9uPg0KICAgICAgICAgICAg\nICAgIDxDcmVkZW50aWFscz4NCiAgICAgICAgICAgICAgICAgICAgPERvbWFpbj48L0RvbWFpbj4N\nCiAgICAgICAgICAgICAgICAgICAgPFBhc3N3b3JkPjwvUGFzc3dvcmQ+DQogICAgICAgICAgICAg\nICAgICAgIDxVc2VybmFtZT48L1VzZXJuYW1lPg0KICAgICAgICAgICAgICAgIDwvQ3JlZGVudGlh\nbHM+DQogICAgICAgICAgICAgICAgPEpvaW5Eb21haW4+PC9Kb2luRG9tYWluPg0KICAgICAgICAg\nICAgPC9JZGVudGlmaWNhdGlvbj4NCiAgICAgICAgPC9jb21wb25lbnQ+DQogICAgPC9zZXR0aW5n\ncz4NCiAgICA8c2V0dGluZ3MgcGFzcz0ib29iZVN5c3RlbSI+DQogICAgICAgIDxjb21wb25lbnQg\nbmFtZT0iTWljcm9zb2Z0LVdpbmRvd3MtSW50ZXJuYXRpb25hbC1Db3JlIiBwcm9jZXNzb3JBcmNo\naXRlY3R1cmU9Ing4NiIgcHVibGljS2V5VG9rZW49IjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdl\nPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4UyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1h\ncy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAyL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93\nd3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3RhbmNlIj4NCiAgICAgICAgICAgIDxJbnB1dExv\nY2FsZT5lbi1VUzwvSW5wdXRMb2NhbGU+DQogICAgICAgICAgICA8VXNlckxvY2FsZT5lbi1VUzwv\nVXNlckxvY2FsZT4NCiAgICAgICAgICAgIDxTeXN0ZW1Mb2NhbGU+ZW4tVVM8L1N5c3RlbUxvY2Fs\nZT4NCiAgICAgICAgICAgIDxVSUxhbmd1YWdlPmVuLVVTPC9VSUxhbmd1YWdlPg0KICAgICAgICA8\nL2NvbXBvbmVudD4NCiAgICAgICAgPGNvbXBvbmVudCBuYW1lPSJNaWNyb3NvZnQtV2luZG93cy1T\naGVsbC1TZXR1cCIgcHJvY2Vzc29yQXJjaGl0ZWN0dXJlPSJhbWQ2NCIgcHVibGljS2V5VG9rZW49\nIjMxYmYzODU2YWQzNjRlMzUiIGxhbmd1YWdlPSJuZXV0cmFsIiB2ZXJzaW9uU2NvcGU9Im5vblN4\nUyIgeG1sbnM6d2NtPSJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL1dNSUNvbmZpZy8yMDAy\nL1N0YXRlIiB4bWxuczp4c2k9Imh0dHA6Ly93d3cudzMub3JnLzIwMDEvWE1MU2NoZW1hLWluc3Rh\nbmNlIj4NCiAgICAgICAgICAgIDxPT0JFPg0KICAgICAgICAgICAgICAgIDxQcm90ZWN0WW91clBD\nPjI8L1Byb3RlY3RZb3VyUEM+DQogICAgICAgICAgICAgICAgPE5ldHdvcmtMb2NhdGlvbj5Xb3Jr\nPC9OZXR3b3JrTG9jYXRpb24+DQogICAgICAgICAgICAgICAgPEhpZGVFVUxBUGFnZT50cnVlPC9I\naWRlRVVMQVBhZ2U+DQogICAgICAgICAgICA8L09PQkU+DQogICAgICAgICAgICA8VXNlckFjY291\nbnRzPg0KICAgICAgICAgICAgICAgIDxBZG1pbmlzdHJhdG9yUGFzc3dvcmQ+DQogICAgICAgICAg\nICAgICAgICAgIDxWYWx1ZT4xMjM0NTY8L1ZhbHVlPg0KICAgICAgICAgICAgICAgICAgICA8UGxh\naW5UZXh0PnRydWU8L1BsYWluVGV4dD4NCiAgICAgICAgICAgICAgICA8L0FkbWluaXN0cmF0b3JQ\nYXNzd29yZD4NCiAgICAgICAgICAgICAgICA8TG9jYWxBY2NvdW50cz4NCiAgICAgICAgICAgICAg\nICAgICAgPExvY2FsQWNjb3VudCB3Y206YWN0aW9uPSJhZGQiPg0KICAgICAgICAgICAgICAgICAg\nICAgICAgPFBhc3N3b3JkPg0KICAgICAgICAgICAgICAgICAgICAgICAgICAgIDxWYWx1ZT4xMjM0\nNTY8L1ZhbHVlPg0KICAgICAgICAgICAgICAgICAgICAgICAgICAgIDxQbGFpblRleHQ+dHJ1ZTwv\nUGxhaW5UZXh0Pg0KICAgICAgICAgICAgICAgICAgICAgICAgPC9QYXNzd29yZD4NCiAgICAgICAg\nICAgICAgICAgICAgICAgIDxHcm91cD5hZG1pbmlzdHJhdG9yczwvR3JvdXA+DQogICAgICAgICAg\nICAgICAgICAgICAgICA8TmFtZT51c2VyPC9OYW1lPg0KICAgICAgICAgICAgICAgICAgICAgICAg\nPERpc3BsYXlOYW1lPnVzZXI8L0Rpc3BsYXlOYW1lPg0KICAgICAgICAgICAgICAgICAgICA8L0xv\nY2FsQWNjb3VudD4NCiAgICAgICAgICAgICAgICA8L0xvY2FsQWNjb3VudHM+DQogICAgICAgICAg\nICA8L1VzZXJBY2NvdW50cz4NCiAgICAgICAgPC9jb21wb25lbnQ+DQogICAgPC9zZXR0aW5ncz4N\nCiAgICA8Y3BpOm9mZmxpbmVJbWFnZSBjcGk6c291cmNlPSJ3aW06ZDovc291cmNlcy9pbnN0YWxs\nLndpbSNXaW5kb3dzIDcgRU5URVJQUklTRSIgeG1sbnM6Y3BpPSJ1cm46c2NoZW1hcy1taWNyb3Nv\nZnQtY29tOmNwaSIvPg0KPC91bmF0dGVuZD4NCg==\n'}}}, 'readonly': 'true', 'deviceId': '70ca7bf3-e2f3-4d1b-be2c-6cf92788fd72', 'path': '', 'device': 'floppy', 'shared': 'false', 'type': 'disk'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'heads': '1'}, 'type': 'video', ' >deviceId': '616b80db-06f0-45c9-8cb0-f1d8a12cf5a2', 'address': {'bus': '0x00', ' slot': '0x02', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}}, {'index': '2', 'iface': 'ide', 'specParams': {}, 'readonly': 'true', 'deviceId': '44ba830e-fb13-43a9-9af7-a320acb1fae6', 'path': '/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.3_9.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'ide', 'format': 'raw', 'bootOrder': '1', 'poolID': 'b32005e6-26e4-4a55-900c-1c05fb30cca1', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5', 'specParams': {}, 'readonly': 'false', 'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'optional': 'false', 'deviceId': '072b9067-5a8f-4dad-bdcb-0174b55cebb5', 'address': {' controller': '0', ' target': '0', 'unit': '0', ' bus': '0', ' type': 'drive'}, 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:b5:b6:c8', 'linkActive': 'true', 'network': 'rhevm', 'bootOrder': '2', 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': 'ce2da697-d6ef-4424-a79d-78740ed28e86', 'address': {'bus': '0x00', ' slot': '0x03', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'bridge', 'type': 'interface'}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': 'c20b88a9-6de1-4f49-93f5-1bb10799181f'}, {'specParams': {}, 'deviceId': 'bee91147-048e-4483-a433-70932c386054', 'address': {'bus': '0x00', ' slot': '0x04', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'scsi', 'model': 'virtio-scsi', 'type': 'controller'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'windows_vm', 'display': 'qxl', 'nice': '0'}} >Thread-320::INFO::2014-01-02 18:20:43,307::vm::2969::vm.Vm::(_run) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::VM wrapper has started >Thread-320::DEBUG::2014-01-02 18:20:43,310::task::579::TaskManager.Task::(_updateState) Task=`a2787b31-3c78-40fe-b2cf-292766b1226d`::moving from state init -> state preparing >Thread-320::INFO::2014-01-02 18:20:43,310::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='63c64507-20bf-4f3f-ab9b-3279401f5af6', spUUID='b32005e6-26e4-4a55-900c-1c05fb30cca1', imgUUID='072b9067-5a8f-4dad-bdcb-0174b55cebb5', volUUID='7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', options=None) >Thread-320::INFO::2014-01-02 18:20:43,313::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '24576', 'apparentsize': '21474836480'} >Thread-320::DEBUG::2014-01-02 18:20:43,314::task::1168::TaskManager.Task::(prepare) Task=`a2787b31-3c78-40fe-b2cf-292766b1226d`::finished: {'truesize': '24576', 'apparentsize': '21474836480'} >Thread-320::DEBUG::2014-01-02 18:20:43,314::task::579::TaskManager.Task::(_updateState) Task=`a2787b31-3c78-40fe-b2cf-292766b1226d`::moving from state preparing -> state finished >Thread-320::DEBUG::2014-01-02 18:20:43,314::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-320::DEBUG::2014-01-02 18:20:43,314::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-320::DEBUG::2014-01-02 18:20:43,314::task::974::TaskManager.Task::(_decref) Task=`a2787b31-3c78-40fe-b2cf-292766b1226d`::ref 0 aborting False >Thread-320::INFO::2014-01-02 18:20:43,315::clientIF::352::vds::(prepareVolumePath) prepared volume path: >Thread-320::INFO::2014-01-02 18:20:43,315::clientIF::352::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.3_9.iso >Thread-320::DEBUG::2014-01-02 18:20:43,315::task::579::TaskManager.Task::(_updateState) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::moving from state init -> state preparing >Thread-320::INFO::2014-01-02 18:20:43,315::logUtils::44::dispatcher::(wrapper) Run and protect: prepareImage(sdUUID='63c64507-20bf-4f3f-ab9b-3279401f5af6', spUUID='b32005e6-26e4-4a55-900c-1c05fb30cca1', imgUUID='072b9067-5a8f-4dad-bdcb-0174b55cebb5', leafUUID='7a794f4c-b2a0-4bc5-8631-ea661b15e2ad') >Thread-320::DEBUG::2014-01-02 18:20:43,316::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6`ReqID=`ff865539-0577-462a-978c-da3380e041b1`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '3256' at 'prepareImage' >Thread-320::DEBUG::2014-01-02 18:20:43,316::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6' for lock type 'shared' >Thread-320::DEBUG::2014-01-02 18:20:43,316::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6' is free. Now locking as 'shared' (1 active user) >Thread-320::DEBUG::2014-01-02 18:20:43,317::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6`ReqID=`ff865539-0577-462a-978c-da3380e041b1`::Granted request >Thread-320::DEBUG::2014-01-02 18:20:43,317::task::811::TaskManager.Task::(resourceAcquired) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::_resourcesAcquired: Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6 (shared) >Thread-320::DEBUG::2014-01-02 18:20:43,317::task::974::TaskManager.Task::(_decref) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::ref 1 aborting False >Thread-320::WARNING::2014-01-02 18:20:43,320::fileUtils::167::Storage.fileUtils::(createdir) Dir /var/run/vdsm/storage/63c64507-20bf-4f3f-ab9b-3279401f5af6 already exists >Thread-320::DEBUG::2014-01-02 18:20:43,322::fileVolume::528::Storage.Volume::(validateVolumePath) validate path for 7a794f4c-b2a0-4bc5-8631-ea661b15e2ad >Thread-320::INFO::2014-01-02 18:20:43,324::logUtils::47::dispatcher::(wrapper) Run and protect: prepareImage, Return response: {'info': {'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'volType': 'path', 'leaseOffset': 0, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'leasePath': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad.lease', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5'}, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'imgVolumesInfo': [{'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'volType': 'path', 'leaseOffset': 0, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'leasePath': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad.lease', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5'}]} >Thread-320::DEBUG::2014-01-02 18:20:43,324::task::1168::TaskManager.Task::(prepare) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::finished: {'info': {'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'volType': 'path', 'leaseOffset': 0, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'leasePath': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad.lease', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5'}, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'imgVolumesInfo': [{'domainID': '63c64507-20bf-4f3f-ab9b-3279401f5af6', 'volType': 'path', 'leaseOffset': 0, 'path': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'volumeID': '7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', 'leasePath': '/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad.lease', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5'}]} >Thread-320::DEBUG::2014-01-02 18:20:43,325::task::579::TaskManager.Task::(_updateState) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::moving from state preparing -> state finished >Thread-320::DEBUG::2014-01-02 18:20:43,325::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6': < ResourceRef 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6', isValid: 'True' obj: 'None'>} >Thread-320::DEBUG::2014-01-02 18:20:43,325::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-320::DEBUG::2014-01-02 18:20:43,325::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6' >Thread-320::DEBUG::2014-01-02 18:20:43,325::resourceManager::634::ResourceManager::(releaseResource) Released resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6' (0 active users) >Thread-320::DEBUG::2014-01-02 18:20:43,326::resourceManager::640::ResourceManager::(releaseResource) Resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6' is free, finding out if anyone is waiting for it. >Thread-320::DEBUG::2014-01-02 18:20:43,326::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.63c64507-20bf-4f3f-ab9b-3279401f5af6', Clearing records. >Thread-320::DEBUG::2014-01-02 18:20:43,326::task::974::TaskManager.Task::(_decref) Task=`3e98bcfb-35f4-40c5-951a-af6e96455868`::ref 0 aborting False >Thread-320::INFO::2014-01-02 18:20:43,326::clientIF::352::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad >Thread-320::DEBUG::2014-01-02 18:20:43,346::utils::489::root::(execCmd) '/usr/libexec/vdsm/hooks/before_vm_start/hooks_test' (cwd None) >Thread-320::DEBUG::2014-01-02 18:20:43,378::utils::509::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0 >Thread-320::INFO::2014-01-02 18:20:43,379::hooks::88::root::(_runHooksDir) >Thread-320::DEBUG::2014-01-02 18:20:43,379::vm::3031::vm.Vm::(_run) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::<?xml version="1.0" encoding="utf-8"?> ><domain type="kvm"> > <name>windows_vm</name> > <uuid>68274f69-a66c-40c1-991e-58f5a7f2faec</uuid> > <memory>1048576</memory> > <currentMemory>1048576</currentMemory> > <vcpu>1</vcpu> > <memtune> > <min_guarantee>1048576</min_guarantee> > </memtune> > <devices> > <channel type="unix"> > <target name="com.redhat.rhevm.vdsm" type="virtio"/> > <source mode="bind" path="/var/lib/libvirt/qemu/channels/68274f69-a66c-40c1-991e-58f5a7f2faec.com.redhat.rhevm.vdsm"/> > </channel> > <channel type="unix"> > <target name="org.qemu.guest_agent.0" type="virtio"/> > <source mode="bind" path="/var/lib/libvirt/qemu/channels/68274f69-a66c-40c1-991e-58f5a7f2faec.org.qemu.guest_agent.0"/> > </channel> > <input bus="ps2" type="mouse"/> > <channel type="spicevmc"> > <target name="com.redhat.spice.0" type="virtio"/> > </channel> > <graphics autoport="yes" keymap="en-us" listen="0" passwd="*****" passwdValidTo="1970-01-01T00:00:01" port="-1" tlsPort="-1" type="spice"> > <channel mode="secure" name="main"/> > <channel mode="secure" name="inputs"/> > <channel mode="secure" name="cursor"/> > <channel mode="secure" name="playback"/> > <channel mode="secure" name="record"/> > <channel mode="secure" name="display"/> > <channel mode="secure" name="usbredir"/> > <channel mode="secure" name="smartcard"/> > </graphics> > <controller model="virtio-scsi" type="scsi"> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> > </controller> > <video> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> > <model heads="1" type="qxl" vram="32768"/> > </video> > <interface type="bridge"> > <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> > <mac address="00:1a:4a:b5:b6:c8"/> > <model type="virtio"/> > <source bridge="rhevm"/> > <filterref filter="vdsm-no-mac-spoofing"/> > <link state="up"/> > <boot order="2"/> > </interface> > <disk device="floppy" snapshot="no" type="file"> > <source file="" startupPolicy="optional"/> > <target bus="fdc" dev="fda"/> > <readonly/> > <serial/> > </disk> > <disk device="cdrom" snapshot="no" type="file"> > <source file="/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.3_9.iso" startupPolicy="optional"/> > <target bus="ide" dev="hdc"/> > <readonly/> > <serial/> > </disk> > <disk device="disk" snapshot="no" type="file"> > <address bus="0" controller="0" target="0" type="drive" unit="0"/> > <source file="/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/images/072b9067-5a8f-4dad-bdcb-0174b55cebb5/7a794f4c-b2a0-4bc5-8631-ea661b15e2ad"/> > <target bus="ide" dev="hda"/> > <serial>072b9067-5a8f-4dad-bdcb-0174b55cebb5</serial> > <boot order="1"/> > <driver cache="none" error_policy="stop" io="threads" name="qemu" type="raw"/> > </disk> > <memballoon model="virtio"/> > </devices> > <os> > <type arch="x86_64" machine="rhel6.5.0">hvm</type> > <smbios mode="sysinfo"/> > </os> > <sysinfo type="smbios"> > <system> > <entry name="manufacturer">Red Hat</entry> > <entry name="product">RHEV Hypervisor</entry> > <entry name="version">6Server-6.5.0.1.el6</entry> > <entry name="serial">802B8DA9-3B43-B601-19FE-00145EDD0555</entry> > <entry name="uuid">68274f69-a66c-40c1-991e-58f5a7f2faec</entry> > </system> > </sysinfo> > <clock adjustment="0" offset="variable"> > <timer name="rtc" tickpolicy="catchup"/> > </clock> > <features> > <acpi/> > </features> > <cpu match="exact"> > <model>Conroe</model> > <topology cores="1" sockets="1" threads="1"/> > </cpu> ></domain> > >libvirtEventLoop::DEBUG::2014-01-02 18:20:44,236::vm::4943::vm.Vm::(_onLibvirtLifecycleEvent) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::event Started detail 0 opaque None >Thread-320::DEBUG::2014-01-02 18:20:44,382::sampling::285::vm.Vm::(start) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Start statistics collection >Thread-323::DEBUG::2014-01-02 18:20:44,383::sampling::314::vm.Vm::(run) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Stats thread started >Thread-323::DEBUG::2014-01-02 18:20:44,385::task::579::TaskManager.Task::(_updateState) Task=`6b892669-d756-4826-a049-bf89d52f8b0f`::moving from state init -> state preparing >Thread-323::INFO::2014-01-02 18:20:44,385::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='63c64507-20bf-4f3f-ab9b-3279401f5af6', spUUID='b32005e6-26e4-4a55-900c-1c05fb30cca1', imgUUID='072b9067-5a8f-4dad-bdcb-0174b55cebb5', volUUID='7a794f4c-b2a0-4bc5-8631-ea661b15e2ad', options=None) >Thread-323::INFO::2014-01-02 18:20:44,388::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '24576', 'apparentsize': '21474836480'} >Thread-323::DEBUG::2014-01-02 18:20:44,389::task::1168::TaskManager.Task::(prepare) Task=`6b892669-d756-4826-a049-bf89d52f8b0f`::finished: {'truesize': '24576', 'apparentsize': '21474836480'} >Thread-323::DEBUG::2014-01-02 18:20:44,389::task::579::TaskManager.Task::(_updateState) Task=`6b892669-d756-4826-a049-bf89d52f8b0f`::moving from state preparing -> state finished >Thread-323::DEBUG::2014-01-02 18:20:44,389::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-323::DEBUG::2014-01-02 18:20:44,389::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-323::DEBUG::2014-01-02 18:20:44,390::task::974::TaskManager.Task::(_decref) Task=`6b892669-d756-4826-a049-bf89d52f8b0f`::ref 0 aborting False >Thread-320::DEBUG::2014-01-02 18:20:44,424::vmChannels::194::vds::(register) Add fileno 7 to listener's channels. >Thread-320::WARNING::2014-01-02 18:20:44,430::vm::3536::vm.Vm::(_readPauseCode) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::_readPauseCode unsupported by libvirt vm >Thread-320::DEBUG::2014-01-02 18:20:44,510::vm::2136::vm.Vm::(_startUnderlyingVm) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::_ongoingCreations released >VM Channels Listener::DEBUG::2014-01-02 18:20:45,247::vmChannels::102::vds::(_do_add_channels) fileno 7 was added to unconnected channels. >VM Channels Listener::DEBUG::2014-01-02 18:20:45,247::vmChannels::128::vds::(_handle_unconnected) Trying to connect fileno 7. >VM Channels Listener::DEBUG::2014-01-02 18:20:45,248::guestIF::147::vm.Vm::(_connect) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Attempting connection to /var/lib/libvirt/qemu/channels/68274f69-a66c-40c1-991e-58f5a7f2faec.com.redhat.rhevm.vdsm >VM Channels Listener::DEBUG::2014-01-02 18:20:45,248::guestIF::150::vm.Vm::(_connect) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Connected to /var/lib/libvirt/qemu/channels/68274f69-a66c-40c1-991e-58f5a7f2faec.com.redhat.rhevm.vdsm >VM Channels Listener::DEBUG::2014-01-02 18:20:45,248::vmChannels::144::vds::(_handle_unconnected) Connecting to fileno 7 succeeded. >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,262::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,263::vm::629::vm.Vm::(_getNetworkStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Network stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,263::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,263::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,263::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,263::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,264::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,264::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,268::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::629::vm.Vm::(_getNetworkStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Network stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,269::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:46,270::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >Thread-324::DEBUG::2014-01-02 18:20:47,198::task::579::TaskManager.Task::(_updateState) Task=`bd85944b-fdb2-4d38-955f-929afad707c5`::moving from state init -> state preparing >Thread-324::INFO::2014-01-02 18:20:47,198::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None) >Thread-324::INFO::2014-01-02 18:20:47,198::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.00244155', 'lastCheck': '4.2', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00087151', 'lastCheck': '5.3', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00035033', 'lastCheck': '6.3', 'valid': True}} >Thread-324::DEBUG::2014-01-02 18:20:47,199::task::1168::TaskManager.Task::(prepare) Task=`bd85944b-fdb2-4d38-955f-929afad707c5`::finished: {'7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6': {'code': 0, 'version': 0, 'acquired': True, 'delay': '0.00244155', 'lastCheck': '4.2', 'valid': True}, '63c64507-20bf-4f3f-ab9b-3279401f5af6': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00087151', 'lastCheck': '5.3', 'valid': True}, '22b7153e-c257-40c7-9766-a8c1657ae170': {'code': 0, 'version': 3, 'acquired': True, 'delay': '0.00035033', 'lastCheck': '6.3', 'valid': True}} >Thread-324::DEBUG::2014-01-02 18:20:47,199::task::579::TaskManager.Task::(_updateState) Task=`bd85944b-fdb2-4d38-955f-929afad707c5`::moving from state preparing -> state finished >Thread-324::DEBUG::2014-01-02 18:20:47,199::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} >Thread-324::DEBUG::2014-01-02 18:20:47,199::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} >Thread-324::DEBUG::2014-01-02 18:20:47,199::task::974::TaskManager.Task::(_decref) Task=`bd85944b-fdb2-4d38-955f-929afad707c5`::ref 0 aborting False >Thread-325::DEBUG::2014-01-02 18:20:47,214::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >Thread-325::DEBUG::2014-01-02 18:20:47,214::vm::629::vm.Vm::(_getNetworkStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Network stats not available >Thread-325::DEBUG::2014-01-02 18:20:47,214::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >Thread-325::DEBUG::2014-01-02 18:20:47,214::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >Thread-325::DEBUG::2014-01-02 18:20:47,215::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >Thread-325::DEBUG::2014-01-02 18:20:47,215::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >Thread-325::DEBUG::2014-01-02 18:20:47,215::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >Thread-325::DEBUG::2014-01-02 18:20:47,215::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >Thread-327::DEBUG::2014-01-02 18:20:49,971::BindingXMLRPC::984::vds::(wrapper) client [10.35.161.250]::call vmDestroy with ('68274f69-a66c-40c1-991e-58f5a7f2faec',) {} flowID [7eeb89bd] >Thread-327::INFO::2014-01-02 18:20:49,971::API::324::vds::(destroy) vmContainerLock acquired by vm 68274f69-a66c-40c1-991e-58f5a7f2faec >Thread-327::DEBUG::2014-01-02 18:20:49,971::vm::4477::vm.Vm::(destroy) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::destroy Called >Thread-327::ERROR::2014-01-02 18:20:49,994::BindingXMLRPC::1003::vds::(wrapper) unexpected error >Traceback (most recent call last): > File "/usr/share/vdsm/BindingXMLRPC.py", line 989, in wrapper > res = f(*args, **kwargs) > File "/usr/share/vdsm/BindingXMLRPC.py", line 211, in vmDestroy > return vm.destroy() > File "/usr/share/vdsm/API.py", line 329, in destroy > res = v.destroy() > File "/usr/share/vdsm/vm.py", line 4485, in destroy > response = self.releaseVm() > File "/usr/share/vdsm/vm.py", line 4417, in releaseVm > supervdsm.getProxy().removeFs(drive.path) > File "/usr/share/vdsm/supervdsm.py", line 50, in __call__ > return callMethod() > File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda> > **kwargs) > File "<string>", line 2, in removeFs > File "/usr/lib64/python2.6/multiprocessing/managers.py", line 740, in _callmethod > raise convert_to_error(kind, result) >Exception: Cannot remove Fs that does not exists in: /var/run/vdsm/payload >Thread-329::DEBUG::2014-01-02 18:20:50,288::BindingXMLRPC::984::vds::(wrapper) client [10.35.161.250]::call vmGetStats with ('68274f69-a66c-40c1-991e-58f5a7f2faec',) {} flowID [60056081] >Thread-329::DEBUG::2014-01-02 18:20:50,288::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >Thread-329::DEBUG::2014-01-02 18:20:50,288::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >Thread-329::DEBUG::2014-01-02 18:20:50,289::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >Thread-329::DEBUG::2014-01-02 18:20:50,289::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >Thread-329::DEBUG::2014-01-02 18:20:50,289::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >Thread-329::DEBUG::2014-01-02 18:20:50,289::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >Thread-329::DEBUG::2014-01-02 18:20:50,289::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >Thread-329::DEBUG::2014-01-02 18:20:50,312::BindingXMLRPC::991::vds::(wrapper) return vmGetStats with {'status': {'message': 'Done', 'code': 0}, 'statsList': [{'status': 'Powering up', 'username': 'Unknown', 'memUsage': '0', 'acpiEnable': 'true', 'guestFQDN': '', 'pid': '4353', 'displayIp': '0', 'displayPort': u'5900', 'session': 'Unknown', 'displaySecurePort': u'5901', 'timeOffset': '0', 'hash': '2100307680727835830', 'balloonInfo': {'balloon_max': '1048576', 'balloon_target': '1048576', 'balloon_cur': '1048576', 'balloon_min': '1048576'}, 'pauseCode': 'NOERR', 'clientIp': '', 'kvmEnable': 'true', 'network': {u'vnet0': {'macAddr': '00:1a:4a:b5:b6:c8', 'rxDropped': '0', 'rxErrors': '0', 'txDropped': '0', 'txRate': '0.0', 'rxRate': '0.0', 'txErrors': '0', 'state': 'unknown', 'speed': '1000', 'name': u'vnet0'}}, 'vmId': '68274f69-a66c-40c1-991e-58f5a7f2faec', 'displayType': 'qxl', 'cpuUser': '0.00', 'disks': {u'fda': {'truesize': '0', 'apparentsize': '0'}, u'hdc': {'truesize': '0', 'apparentsize': '0'}, u'hda': {'truesize': '24576', 'apparentsize': '21474836480', 'imageID': '072b9067-5a8f-4dad-bdcb-0174b55cebb5'}}, 'monitorResponse': '0', 'statsAge': '0.55', 'elapsedTime': '7', 'vmType': 'kvm', 'cpuSys': '0.00', 'appsList': [], 'guestIPs': ''}]} >Thread-42::DEBUG::2014-01-02 18:20:50,894::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/lion.qa.lab.tlv.redhat.com:_export_alukiano_nfs__33/22b7153e-c257-40c7-9766-a8c1657ae170/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-42::DEBUG::2014-01-02 18:20:50,920::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n567 bytes (567 B) copied, 0.00767576 s, 73.9 kB/s\n'; <rc> = 0 >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,274::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,275::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,275::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,275::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,276::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,276::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,276::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,278::vm::595::vm.Vm::(_getCpuStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::CPU stats not available: 'NoneType' object is unsubscriptable >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,279::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,279::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,279::vm::649::vm.Vm::(_getDiskStats) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda stats not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,279::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk fda latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,280::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hdc latency not available >GuestMonitor-windows_vm::DEBUG::2014-01-02 18:20:51,280::vm::688::vm.Vm::(_getDiskLatency) vmId=`68274f69-a66c-40c1-991e-58f5a7f2faec`::Disk hda latency not available >Thread-38::DEBUG::2014-01-02 18:20:51,947::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/10.35.160.108:_RHEV_artyom__2/63c64507-20bf-4f3f-ab9b-3279401f5af6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-38::DEBUG::2014-01-02 18:20:51,980::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n350 bytes (350 B) copied, 0.000607167 s, 576 kB/s\n'; <rc> = 0 >Thread-37::DEBUG::2014-01-02 18:20:52,993::fileSD::239::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/multipass.eng.lab.tlv.redhat.com:_export_images_qa_shared__iso__domain/7233a711-98e8-4c3c-bcfa-44c4bcc4f6c6/dom_md/metadata bs=4096 count=1' (cwd None) >Thread-37::DEBUG::2014-01-02 18:20:53,041::fileSD::239::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.00116749 s, 3.5 MB/s\n'; <rc> = 0 >Thread-331::DEBUG::2014-01-02 18:20:53,334::BindingXMLRPC::984::vds::(wrapper) client [10.35.161.250]::call vmGetStats with ('68274f69-a66c-40c1-991e-58f5a7f2faec',) {} flowID [1d9b88b0]
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1047944
: 844617 |
846083