Attachment 844617 Details for Bug 1047944 – vdsm.log

vdsm.log

vdsm.log (text/x-log), 63.51 KB, created by Artyom on 2014-01-02 16:37:03 UTC

(hide)

Description:

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]

Actions: View

Attachments on bug 1047944: 844617 | 846083