Attachment 832645 Details for Bug 1038121 – vdsm.log (excerpt)

vdsm.log (excerpt)

cloudinit-vdsm.log (text/plain), 6.02 KB, created by Sven Kieske on 2013-12-04 13:20:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Sven Kieske

Created: 2013-12-04 13:20:07 UTC

Size: 6.02 KB

patch

obsolete

>Thread-492561::DEBUG::2013-12-04 14:54:05,935::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-492561::DEBUG::2013-12-04 14:54:05,935::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource 'Storage.a555f4aa-d962-425c-91c1-6ee5c4da4a23'
>Thread-492561::DEBUG::2013-12-04 14:54:05,935::resourceManager::634::ResourceManager::(releaseResource) Released resource 'Storage.a555f4aa-d962-425c-91c1-6ee5c4da4a23' (0 active users)
>Thread-492561::DEBUG::2013-12-04 14:54:05,935::resourceManager::640::ResourceManager::(releaseResource) Resource 'Storage.a555f4aa-d962-425c-91c1-6ee5c4da4a23' is free, finding out if anyone is waiting for it.
>Thread-492561::DEBUG::2013-12-04 14:54:05,935::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.a555f4aa-d962-425c-91c1-6ee5c4da4a23', Clearing records.
>Thread-492561::DEBUG::2013-12-04 14:54:05,935::task::974::TaskManager.Task::(_decref) Task=`25f8eea9-89dc-4eb8-8a3d-5e0adeda432d`::ref 0 aborting False
>Thread-25::DEBUG::2013-12-04 14:54:06,879::fileSD::238::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/management.test:_var_lib_exports_export/1d78d7e2-dd74-48d9-86c2-dd
>e2e04d7fae/dom_md/metadata bs=4096 count=1' (cwd None)
>Thread-25::DEBUG::2013-12-04 14:54:06,884::fileSD::238::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n366 bytes (366 B) copied, 0.000255469 s, 1.4 MB/s\n'; <rc> = 0
>VM Channels Listener::DEBUG::2013-12-04 14:54:08,395::vmChannels::91::vds::(_handle_timeouts) Timeout on fileno 111.
>Thread-492563::DEBUG::2013-12-04 14:54:09,827::task::579::TaskManager.Task::(_updateState) Task=`1f4812c4-e568-4b49-9c25-d20433f0076a`::moving from state init -> state preparing
>Thread-492563::INFO::2013-12-04 14:54:09,828::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-492563::INFO::2013-12-04 14:54:09,828::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'cd9f7816-e107-4ada-a930-75a52f852ab1': {'delay': '0.000204978', 'lastCheck': '7.6'
>, 'code': 0, 'valid': True, 'version': 3}, '27945043-e2a8-4c1f-bd6b-bf39ba2a6d97': {'delay': '0.000269259', 'lastCheck': '5.1', 'code': 0, 'valid': True, 'version': 0}, '1d78d7e2-dd74-48d9-86c2-dde2e04d7fae': {'
>delay': '0.000255469', 'lastCheck': '2.9', 'code': 0, 'valid': True, 'version': 0}}
>Thread-492563::DEBUG::2013-12-04 14:54:09,828::task::1168::TaskManager.Task::(prepare) Task=`1f4812c4-e568-4b49-9c25-d20433f0076a`::finished: {'cd9f7816-e107-4ada-a930-75a52f852ab1': {'delay': '0.000204978', 'la
>stCheck': '7.6', 'code': 0, 'valid': True, 'version': 3}, '27945043-e2a8-4c1f-bd6b-bf39ba2a6d97': {'delay': '0.000269259', 'lastCheck': '5.1', 'code': 0, 'valid': True, 'version': 0}, '1d78d7e2-dd74-48d9-86c2-dd
>e2e04d7fae': {'delay': '0.000255469', 'lastCheck': '2.9', 'code': 0, 'valid': True, 'version': 0}}
>Thread-492563::DEBUG::2013-12-04 14:54:09,828::task::579::TaskManager.Task::(_updateState) Task=`1f4812c4-e568-4b49-9c25-d20433f0076a`::moving from state preparing -> state finished
>Thread-492563::DEBUG::2013-12-04 14:54:09,828::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-492563::DEBUG::2013-12-04 14:54:09,828::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-492563::DEBUG::2013-12-04 14:54:09,829::task::974::TaskManager.Task::(_decref) Task=`1f4812c4-e568-4b49-9c25-d20433f0076a`::ref 0 aborting False
>VM Channels Listener::DEBUG::2013-12-04 14:54:11,399::vmChannels::91::vds::(_handle_timeouts) Timeout on fileno 120.
>Thread-23::DEBUG::2013-12-04 14:54:12,267::fileSD::238::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/_home_DATA/cd9f7816-e107-4ada-a930-75a52f852ab1/dom_md/metadata bs=4096 
>count=1' (cwd None)
>Thread-23::DEBUG::2013-12-04 14:54:12,272::fileSD::238::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n546 bytes (546 B) copied, 0.000201215 s, 2.7 MB/s\n'; <rc> = 0
>Thread-24::DEBUG::2013-12-04 14:54:14,725::fileSD::238::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/rhev/data-center/mnt/management.test:_var_lib_exports_iso/27945043-e2a8-4c1f-bd6b-bf39b
>a2a6d97/dom_md/metadata bs=4096 count=1' (cwd None)
>Thread-24::DEBUG::2013-12-04 14:54:14,731::fileSD::238::Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '0+1 records in\n0+1 records out\n435 bytes (435 B) copied, 0.000219861 s, 2.0 MB/s\n'; <rc> = 0
>Thread-492567::DEBUG::2013-12-04 14:54:15,986::BindingXMLRPC::177::vds::(wrapper) client [10.0.1.11]
>Thread-492567::DEBUG::2013-12-04 14:54:15,987::task::579::TaskManager.Task::(_updateState) Task=`655e7491-441d-4aea-97bd-c68f7083399f`::moving from state init -> state preparing
>Thread-492567::INFO::2013-12-04 14:54:15,987::logUtils::44::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='a555f4aa-d962-425c-91c1-6ee5c4da4a23', options=None)
>Thread-492567::INFO::2013-12-04 14:54:15,987::logUtils::47::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 5}}
>Thread-492567::DEBUG::2013-12-04 14:54:15,987::task::1168::TaskManager.Task::(prepare) Task=`655e7491-441d-4aea-97bd-c68f7083399f`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 5}}
>Thread-492567::DEBUG::2013-12-04 14:54:15,987::task::579::TaskManager.Task::(_updateState) Task=`655e7491-441d-4aea-97bd-c68f7083399f`::moving from state preparing -> state finished
>Thread-492567::DEBUG::2013-12-04 14:54:15,987::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-492567::DEBUG::2013-12-04 14:54:15,988::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-492567::DEBUG::2013-12-04 14:54:15,988::task::974::TaskManager.Task::(_decref) Task=`655e7491-441d-4aea-97bd-c68f7083399f`::ref 0 aborting False
>Thread-492568::DEBUG::2013-12-04 14:54:15,990::BindingXMLRPC::177::vds::(wrapper) client [10.0.1.11]

Actions: View

Attachments on bug 1038121: 832644 | 832645 | 832647 | 832694 | 832729 | 833027 | 833042