Attachment 910204 Details for Bug 1110707 – libvrt.log on the destination node: su03

libvrt.log on the destination node: su03

libvirtd.log (text/plain), 83.07 KB, created by lily on 2014-06-19 01:37:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: lily

Created: 2014-06-19 01:37:44 UTC

Size: 83.07 KB

patch

obsolete

>2014-06-18 08:53:01.181+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:01.181+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4662 sys=2645 cpu=1 rss=186949
>2014-06-18 08:53:01.181+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:03.495+0000: 1919: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:03.496+0000: 1914: debug : virDomainGetCPUStats:20055 : dom=0x7fcac00594f0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=(nil), nparams=0, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:03.497+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac00594f0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:03.498+0000: 1912: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:03.499+0000: 1911: debug : virDomainGetCPUStats:20055 : dom=0x7fcac409f5e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=0x7fcac409e470, nparams=3, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:03.499+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac409f5e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:03.500+0000: 1910: debug : virDomainInterfaceStats:7361 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcad2cd4ac0, size=64
>2014-06-18 08:53:03.500+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:03.514+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:53:03.515+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4663 sys=2646 cpu=0 rss=186949
>2014-06-18 08:53:03.515+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:03.516+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:03.516+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4663 sys=2646 cpu=0 rss=186949
>2014-06-18 08:53:03.516+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:08.502+0000: 1916: debug : virDomainInterfaceStats:7361 : dom=0x7fcaa8003af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacf0ceac0, size=64
>2014-06-18 08:53:08.502+0000: 1916: debug : virDomainFree:2281 : dom=0x7fcaa8003af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:08.522+0000: 1911: debug : virDomainGetInfo:4208 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad22d3ad0
>2014-06-18 08:53:08.523+0000: 1911: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4665 sys=2649 cpu=1 rss=186949
>2014-06-18 08:53:08.523+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:08.524+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:08.524+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4665 sys=2649 cpu=2 rss=186949
>2014-06-18 08:53:08.524+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:13.504+0000: 1917: debug : virDomainInterfaceStats:7361 : dom=0x7fca9c000af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcace6cdac0, size=64
>2014-06-18 08:53:13.504+0000: 1917: debug : virDomainFree:2281 : dom=0x7fca9c000af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:13.530+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:13.530+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4667 sys=2652 cpu=1 rss=186949
>2014-06-18 08:53:13.530+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:13.531+0000: 1912: debug : virDomainGetInfo:4208 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad18d2ad0
>2014-06-18 08:53:13.531+0000: 1912: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4667 sys=2652 cpu=1 rss=186949
>2014-06-18 08:53:13.531+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:16.339+0000: 1911: debug : virDomainGetInfo:4208 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad22d3ad0
>2014-06-18 08:53:16.339+0000: 1911: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4669 sys=2653 cpu=0 rss=186949
>2014-06-18 08:53:16.339+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:18.506+0000: 1910: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:18.507+0000: 1913: debug : virDomainGetCPUStats:20055 : dom=0x7fcaa40c8760, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=(nil), nparams=0, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:18.507+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40c8760, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:18.508+0000: 1915: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:18.508+0000: 1912: debug : virDomainGetCPUStats:20055 : dom=0x7fcab40c7c40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=0x7fcab40cc0f0, nparams=3, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:18.509+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c7c40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:18.510+0000: 1911: debug : virDomainInterfaceStats:7361 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcad22d3ac0, size=64
>2014-06-18 08:53:18.510+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:18.538+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:18.538+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4670 sys=2654 cpu=2 rss=186949
>2014-06-18 08:53:18.538+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:18.539+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:53:18.539+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4670 sys=2654 cpu=2 rss=186949
>2014-06-18 08:53:18.539+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:23.512+0000: 1916: debug : virDomainInterfaceStats:7361 : dom=0x7fcaa8003af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacf0ceac0, size=64
>2014-06-18 08:53:23.512+0000: 1916: debug : virDomainFree:2281 : dom=0x7fcaa8003af0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:23.544+0000: 1912: debug : virDomainGetInfo:4208 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad18d2ad0
>2014-06-18 08:53:23.544+0000: 1912: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4671 sys=2657 cpu=1 rss=186949
>2014-06-18 08:53:23.544+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:23.545+0000: 1911: debug : virDomainGetInfo:4208 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad22d3ad0
>2014-06-18 08:53:23.545+0000: 1911: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4671 sys=2657 cpu=0 rss=186949
>2014-06-18 08:53:23.545+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:28.514+0000: 1910: debug : virDomainInterfaceStats:7361 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcad2cd4ac0, size=64
>2014-06-18 08:53:28.514+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:28.552+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:53:28.552+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4673 sys=2660 cpu=0 rss=186949
>2014-06-18 08:53:28.552+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40c0590, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:28.553+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:28.553+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4673 sys=2660 cpu=0 rss=186949
>2014-06-18 08:53:28.553+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:31.499+0000: 1912: debug : virDomainGetInfo:4208 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad18d2ad0
>2014-06-18 08:53:31.499+0000: 1912: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4674 sys=2662 cpu=1 rss=186949
>2014-06-18 08:53:31.499+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.524+0000: 1919: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.525+0000: 1910: debug : virDomainGetCPUStats:20055 : dom=0x7fcab0005800, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=(nil), nparams=0, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:33.525+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab0005800, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.526+0000: 1913: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.526+0000: 1914: debug : virDomainGetCPUStats:20055 : dom=0x7fcac0006fc0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=0x7fcac005c220, nparams=3, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:33.527+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac0006fc0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.528+0000: 1912: debug : virDomainBlockStats:7216 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=hdc, stats=0x7fcad18d2ad0, size=40
>2014-06-18 08:53:33.528+0000: 1912: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.528+0000: 1912: debug : qemuMonitorGetBlockStatsInfo:1478 : mon=0x7fcab000cc80 dev=ide0-1-0
>2014-06-18 08:53:33.528+0000: 1912: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-585"}
> fd=-1
>2014-06-18 08:53:33.528+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-585"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.529+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-585"}
> len=790
>2014-06-18 08:53:33.530+0000: 1912: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.530+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.531+0000: 1911: debug : virDomainBlockStats:7216 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=vda, stats=0x7fcad22d3ad0, size=40
>2014-06-18 08:53:33.531+0000: 1911: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.531+0000: 1911: debug : qemuMonitorGetBlockStatsInfo:1478 : mon=0x7fcab000cc80 dev=virtio-disk0
>2014-06-18 08:53:33.531+0000: 1911: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-586"}
> fd=-1
>2014-06-18 08:53:33.531+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-586"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.533+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-586"}
> len=790
>2014-06-18 08:53:33.533+0000: 1911: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.533+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.534+0000: 1918: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.534+0000: 1913: debug : virDomainBlockStatsFlags:7296 : dom=0x7fcaa40c5db0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=hdc, params=(nil), nparams=0, flags=4
>2014-06-18 08:53:33.534+0000: 1913: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.534+0000: 1913: debug : qemuDomainBlockStatsFlags:9135 : priv=0x7fcab00130d0, params=(nil), flags=0
>2014-06-18 08:53:33.534+0000: 1913: debug : qemuMonitorGetBlockStatsParamsNumber:1514 : mon=0x7fcab000cc80 nparams=0x7fcad0ed1aec
>2014-06-18 08:53:33.534+0000: 1913: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-587"}
> fd=-1
>2014-06-18 08:53:33.535+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-587"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.536+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-587"}
> len=790
>2014-06-18 08:53:33.536+0000: 1913: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.536+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40c5db0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.537+0000: 1916: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.538+0000: 1912: debug : virDomainBlockStatsFlags:7296 : dom=0x7fcab40d4cd0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=hdc, params=0x7fcab40c7940, nparams=8, flags=4
>2014-06-18 08:53:33.538+0000: 1912: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.538+0000: 1912: debug : qemuDomainBlockStatsFlags:9135 : priv=0x7fcab00130d0, params=0x7fcab40c7940, flags=0
>2014-06-18 08:53:33.538+0000: 1912: debug : qemuMonitorGetBlockStatsParamsNumber:1514 : mon=0x7fcab000cc80 nparams=0x7fcad18d2aec
>2014-06-18 08:53:33.538+0000: 1912: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-588"}
> fd=-1
>2014-06-18 08:53:33.538+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-588"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.540+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-588"}
> len=790
>2014-06-18 08:53:33.540+0000: 1912: debug : qemuMonitorGetBlockStatsInfo:1478 : mon=0x7fcab000cc80 dev=ide0-1-0
>2014-06-18 08:53:33.540+0000: 1912: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-589"}
> fd=-1
>2014-06-18 08:53:33.540+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-589"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.542+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-589"}
> len=790
>2014-06-18 08:53:33.542+0000: 1912: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.542+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40d4cd0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.543+0000: 1917: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.544+0000: 1910: debug : virDomainBlockStatsFlags:7296 : dom=0x7fcab0005800, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=vda, params=(nil), nparams=0, flags=4
>2014-06-18 08:53:33.544+0000: 1910: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.544+0000: 1910: debug : qemuDomainBlockStatsFlags:9135 : priv=0x7fcab00130d0, params=(nil), flags=0
>2014-06-18 08:53:33.544+0000: 1910: debug : qemuMonitorGetBlockStatsParamsNumber:1514 : mon=0x7fcab000cc80 nparams=0x7fcad2cd4aec
>2014-06-18 08:53:33.544+0000: 1910: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-590"}
> fd=-1
>2014-06-18 08:53:33.544+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-590"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.546+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-590"}
> len=790
>2014-06-18 08:53:33.546+0000: 1910: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.546+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab0005800, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.547+0000: 1913: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:33.547+0000: 1914: debug : virDomainBlockStatsFlags:7296 : dom=0x7fcac0006fc0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), disk=vda, params=0x7fcac005a630, nparams=8, flags=4
>2014-06-18 08:53:33.548+0000: 1914: debug : qemuDomainObjBeginJobInternal:808 : Starting job: query (async=none)
>2014-06-18 08:53:33.548+0000: 1914: debug : qemuDomainBlockStatsFlags:9135 : priv=0x7fcab00130d0, params=0x7fcac005a630, flags=0
>2014-06-18 08:53:33.548+0000: 1914: debug : qemuMonitorGetBlockStatsParamsNumber:1514 : mon=0x7fcab000cc80 nparams=0x7fcad04d0aec
>2014-06-18 08:53:33.548+0000: 1914: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-591"}
> fd=-1
>2014-06-18 08:53:33.548+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-591"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.549+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-591"}
> len=790
>2014-06-18 08:53:33.550+0000: 1914: debug : qemuMonitorGetBlockStatsInfo:1478 : mon=0x7fcab000cc80 dev=virtio-disk0
>2014-06-18 08:53:33.550+0000: 1914: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcab000cc80 msg={"execute":"query-blockstats","id":"libvirt-592"}
> fd=-1
>2014-06-18 08:53:33.550+0000: 1906: debug : qemuMonitorIOWrite:462 : QEMU_MONITOR_IO_WRITE: mon=0x7fcab000cc80 buf={"execute":"query-blockstats","id":"libvirt-592"}
> len=51 ret=51 errno=11
>2014-06-18 08:53:33.551+0000: 1906: debug : qemuMonitorIOProcess:354 : QEMU_MONITOR_IO_PROCESS: mon=0x7fcab000cc80 buf={"return": [{"device": "drive-ide0-1-0", "stats": {"flush_total_time_ns": 0, "wr_highest_offset": 0, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 38149, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 204, "rd_operations": 7}}, {"device": "drive-virtio-disk0", "parent": {"stats": {"flush_total_time_ns": 0, "wr_highest_offset": 30656171520, "wr_total_time_ns": 0, "wr_bytes": 0, "rd_total_time_ns": 0, "flush_operations": 0, "wr_operations": 0, "rd_bytes": 0, "rd_operations": 0}}, "stats": {"flush_total_time_ns": 7882946581, "wr_highest_offset": 30656171520, "wr_total_time_ns": 22160595460, "wr_bytes": 17495040, "rd_total_time_ns": 41622128658, "flush_operations": 180, "wr_operations": 1250, "rd_bytes": 286577152, "rd_operations": 10987}}], "id": "libvirt-592"}
> len=790
>2014-06-18 08:53:33.552+0000: 1914: debug : qemuDomainObjEndJob:937 : Stopping job: query (async=none)
>2014-06-18 08:53:33.552+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac0006fc0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.552+0000: 1915: debug : virDomainInterfaceStats:7361 : dom=0x7fcaac0929e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacfacfac0, size=64
>2014-06-18 08:53:33.553+0000: 1915: debug : virDomainFree:2281 : dom=0x7fcaac0929e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.560+0000: 1911: debug : virDomainGetInfo:4208 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad22d3ad0
>2014-06-18 08:53:33.560+0000: 1911: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4676 sys=2663 cpu=0 rss=186949
>2014-06-18 08:53:33.560+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:33.561+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:33.561+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4676 sys=2663 cpu=0 rss=186949
>2014-06-18 08:53:33.561+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab00055a0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:38.555+0000: 1918: debug : virDomainInterfaceStats:7361 : dom=0x7fcabc000980, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacdcccac0, size=64
>2014-06-18 08:53:38.555+0000: 1918: debug : virDomainFree:2281 : dom=0x7fcabc000980, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:38.568+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:38.568+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4678 sys=2665 cpu=1 rss=186949
>2014-06-18 08:53:38.568+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac005c430, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:38.569+0000: 1912: debug : virDomainGetInfo:4208 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad18d2ad0
>2014-06-18 08:53:38.569+0000: 1912: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4678 sys=2665 cpu=1 rss=186949
>2014-06-18 08:53:38.569+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:38.909+0000: 1916: debug : virConnectListAllNetworks:10015 : conn=0x7fcaa4000ca0, nets=0x7fcacf0ceaf8, flags=0
>2014-06-18 08:53:38.909+0000: 1916: debug : virNetworkFree:10622 : network=0x7fcaa8045e20
>2014-06-18 08:53:38.909+0000: 1916: debug : virNetworkFree:10622 : network=0x7fcaa80465a0
>2014-06-18 08:53:38.909+0000: 1916: debug : virNetworkFree:10622 : network=0x7fcaa8046030
>2014-06-18 08:53:38.909+0000: 1910: debug : virNetworkGetXMLDesc:10777 : network=0x7fcab00094f0, flags=0
>2014-06-18 08:53:38.909+0000: 1910: debug : virNetworkFree:10622 : network=0x7fcab00094f0
>2014-06-18 08:53:39.178+0000: 1918: debug : virConnectOpen:1331 : name=qemu:///system
>2014-06-18 08:53:39.178+0000: 1918: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1127 : name "qemu:///system" to URI components:
>  scheme qemu
>  server (null)
>  user (null)
>  port 0
>  path /system
>
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1174 : trying driver 0 (Test) ...
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1180 : driver 0 Test returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1174 : trying driver 1 (ESX) ...
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1174 : trying driver 2 (remote) ...
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1180 : driver 2 remote returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.178+0000: 1918: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2014-06-18 08:53:39.184+0000: 1918: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2014-06-18 08:53:39.190+0000: 1916: debug : virDrvSupportsFeature:1505 : conn=0x7fcabc000c80, feature=4
>2014-06-18 08:53:39.192+0000: 1910: debug : virDrvSupportsFeature:1505 : conn=0x7fcabc000c80, feature=6
>2014-06-18 08:53:39.235+0000: 1913: debug : virDomainMigratePrepare3:6130 : dconn=0x7fcabc000c80, cookiein=0x7fcaa40c43a0, cookieinlen=228, cookieout=0x7fcad0ed1ae0, cookieoutlen=0x7fcad0ed1aec, uri_in=tcp://10.1.110.173, uri_out=0x7fcaa4081840, flags=3, dname=(null), bandwidth=32, dom_xml=<domain type='kvm' id='4'>
>  <name>secvm1</name>
>  <uuid>66e4f53b-682a-4182-bfa8-2121cf36291a</uuid>
>  <memory unit='KiB'>1048576</memory>
>  <currentMemory unit='KiB'>1048576</currentMemory>
>  <memtune>
>    <min_guarantee unit='KiB'>698368</min_guarantee>
>  </memtune>
>  <vcpu placement='static'>1</vcpu>
>  <cputune>
>    <shares>1020</shares>
>  </cputune>
>  <sysinfo type='smbios'>
>    <system>
>      <entry name='manufacturer'>oVirt</entry>
>      <entry name='product'>oVirt Node</entry>
>      <entry name='version'>6Server-6.5.0.1.el6.ns6.02.Adv</entry>
>      <entry name='serial'>40307CB0-CA43-11DF-81EA-E41F136C4750</entry>
>      <entry name='uuid'>66e4f53b-682a-4182-bfa8-2121cf36291a</entry>
>    </system>
>  </sysinfo>
>  <os>
>    <type arch='x86_64' machine='rhel6.4.0'>hvm</type>
>    <smbios mode='sysinfo'/>
>  </os>
>  <features>
>    <acpi/>
>  </features>
>  <cpu mode='custom' match='exact'>
>    <model fallback='allow'>Conroe</model>
>    <topology sockets='1' cores='1' threads='1'/>
>  </cpu>
>  <clock offset='variable' adjustment='0' basis='utc'>
>    <timer name='rtc' tickpolicy='catchup'/>
>  </clock>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>destroy</on_crash>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='cdrom'>
>      <driver name='qemu' type='raw'/>
>      <source startupPolicy='optional'/>
>      <target dev='hdc' bus='ide'/>
>      <readonly/>
>      <serial></serial>
>      <alias name='ide0-1-0'/>
>      <address type='drive' controller='0' bus='1' target='0' unit='0'/>
>    </disk>
>    <disk type='file' device='disk' snapshot='no'>
>      <driver name='qemu' type='qcow2' cache='none' error_policy='stop' io='threads'/>
>      <source file='/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f'/>
>      <target dev='vda' bus='virtio'/>
>      <serial>a4b98453-de36-4591-a6bd-3bffc4e49d27</serial>
>      <boot order='1'/>
>      <alias name='virtio-disk0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
>    </disk>
>    <controller type='scsi' index='0' model='virtio-scsi'>
>      <alias name='scsi0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </controller>
>    <controller type='ide' index='0'>
>      <alias name='ide0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <alias name='virtio-serial0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </controller>
>    <interface type='bridge'>
>      <mac address='00:1a:4a:8b:a4:71'/>
>      <source bridge='ovirtmgmt'/>
>      <target dev='vnet1'/>
>      <model type='virtio'/>
>      <filterref filter='vdsm-no-mac-spoofing'/>
>      <link state='up'/>
>      <alias name='net0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </interface>
>    <channel type='unix'>
>      <source mode='bind' path='/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.com.redhat.rhevm.vdsm'/>
>      <target type='virtio' name='com.redhat.rhevm.vdsm'/>
>      <alias name='channel0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='1'/>
>    </channel>
>    <channel type='unix'>
>      <source mode='bind' path='/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.org.qemu.guest_agent.0'/>
>      <target type='virtio' name='org.qemu.guest_agent.0'/>
>      <alias name='channel1'/>
>      <address type='virtio-serial' controller='0' bus='0' port='2'/>
>    </channel>
>    <channel type='spicevmc'>
>      <target type='virtio' name='com.redhat.spice.0'/>
>      <alias name='channel2'/>
>      <address type='virtio-serial' controller='0' bus='0' port='3'/>
>    </channel>
>    <input type='mouse' bus='ps2'/>
>    <graphics type='spice' port='5902' tlsPort='5903' autoport='yes' listen='0' keymap='en-us' passwd='*****' passwdValidTo='1970-01-01T00:00:01'>
>      <listen type='address' address='0'/>
>      <channel name='main' mode='secure'/>
>      <channel name='display' mode='secure'/>
>      <channel name='inputs' mode='secure'/>
>      <channel name='cursor' mode='secure'/>
>      <channel name='playback' mode='secure'/>
>      <channel name='record' mode='secure'/>
>      <channel name='smartcard' mode='secure'/>
>      <channel name='usbredir' mode='secure'/>
>    </graphics>
>    <video>
>      <model type='qxl' ram='65536' vram='32768' heads='1'/>
>      <alias name='video0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
>    </video>
>    <memballoon model='virtio'>
>      <alias name='balloon0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0'/>
>    </memballoon>
>  </devices>
>  <seclabel type='none'/>
></domain>
>
>2014-06-18 08:53:39.235+0000: 1913: debug : qemuMigrationPrepareDirect:1627 : driver=0x7fcac401b8d0, dconn=0x7fcabc000c80, cookiein=<qemu-migration>
>  <name>secvm1</name>
>  <uuid>66e4f53b-682a-4182-bfa8-2121cf36291a</uuid>
>  <hostname>su04</hostname>
>  <hostuuid>c62706e2-aef4-44bb-b4fa-6dc9199178b9</hostuuid>
>  <feature name='lockstate'/>
></qemu-migration>
>, cookieinlen=228, cookieout=0x7fcad0ed1ae0, cookieoutlen=0x7fcad0ed1aec, uri_in=tcp://10.1.110.173, uri_out=0x7fcaa4081840, dname=(null), dom_xml=<domain type='kvm' id='4'>
>  <name>secvm1</name>
>  <uuid>66e4f53b-682a-4182-bfa8-2121cf36291a</uuid>
>  <memory unit='KiB'>1048576</memory>
>  <currentMemory unit='KiB'>1048576</currentMemory>
>  <memtune>
>    <min_guarantee unit='KiB'>698368</min_guarantee>
>  </memtune>
>  <vcpu placement='static'>1</vcpu>
>  <cputune>
>    <shares>1020</shares>
>  </cputune>
>  <sysinfo type='smbios'>
>    <system>
>      <entry name='manufacturer'>oVirt</entry>
>      <entry name='product'>oVirt Node</entry>
>      <entry name='version'>6Server-6.5.0.1.el6.ns6.02.Adv</entry>
>      <entry name='serial'>40307CB0-CA43-11DF-81EA-E41F136C4750</entry>
>      <entry name='uuid'>66e4f53b-682a-4182-bfa8-2121cf36291a</entry>
>    </system>
>  </sysinfo>
>  <os>
>    <type arch='x86_64' machine='rhel6.4.0'>hvm</type>
>    <smbios mode='sysinfo'/>
>  </os>
>  <features>
>    <acpi/>
>  </features>
>  <cpu mode='custom' match='exact'>
>    <model fallback='allow'>Conroe</model>
>    <topology sockets='1' cores='1' threads='1'/>
>  </cpu>
>  <clock offset='variable' adjustment='0' basis='utc'>
>    <timer name='rtc' tickpolicy='catchup'/>
>  </clock>
>  <on_poweroff>destroy</on_poweroff>
>  <on_reboot>restart</on_reboot>
>  <on_crash>destroy</on_crash>
>  <devices>
>    <emulator>/usr/libexec/qemu-kvm</emulator>
>    <disk type='file' device='cdrom'>
>      <driver name='qemu' type='raw'/>
>      <source startupPolicy='optional'/>
>      <target dev='hdc' bus='ide'/>
>      <readonly/>
>      <serial></serial>
>      <alias name='ide0-1-0'/>
>      <address type='drive' controller='0' bus='1' target='0' unit='0'/>
>    </disk>
>    <disk type='file' device='disk' snapshot='no'>
>      <driver name='qemu' type='qcow2' cache='none' error_policy='stop' io='threads'/>
>      <source file='/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f'/>
>      <target dev='vda' bus='virtio'/>
>      <serial>a4b98453-de36-4591-a6bd-3bffc4e49d27</serial>
>      <boot order='1'/>
>      <alias name='virtio-disk0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
>    </disk>
>    <controller type='scsi' index='0' model='virtio-scsi'>
>      <alias name='scsi0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/>
>    </controller>
>    <controller type='ide' index='0'>
>      <alias name='ide0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
>    </controller>
>    <controller type='virtio-serial' index='0'>
>      <alias name='virtio-serial0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
>    </controller>
>    <interface type='bridge'>
>      <mac address='00:1a:4a:8b:a4:71'/>
>      <source bridge='ovirtmgmt'/>
>      <target dev='vnet1'/>
>      <model type='virtio'/>
>      <filterref filter='vdsm-no-mac-spoofing'/>
>      <link state='up'/>
>      <alias name='net0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/>
>    </interface>
>    <channel type='unix'>
>      <source mode='bind' path='/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.com.redhat.rhevm.vdsm'/>
>      <target type='virtio' name='com.redhat.rhevm.vdsm'/>
>      <alias name='channel0'/>
>      <address type='virtio-serial' controller='0' bus='0' port='1'/>
>    </channel>
>    <channel type='unix'>
>      <source mode='bind' path='/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.org.qemu.guest_agent.0'/>
>      <target type='virtio' name='org.qemu.guest_agent.0'/>
>      <alias name='channel1'/>
>      <address type='virtio-serial' controller='0' bus='0' port='2'/>
>    </channel>
>    <channel type='spicevmc'>
>      <target type='virtio' name='com.redhat.spice.0'/>
>      <alias name='channel2'/>
>      <address type='virtio-serial' controller='0' bus='0' port='3'/>
>    </channel>
>    <input type='mouse' bus='ps2'/>
>    <graphics type='spice' port='5902' tlsPort='5903' autoport='yes' listen='0' keymap='en-us' passwd='*****' passwdValidTo='1970-01-01T00:00:01'>
>      <listen type='address' address='0'/>
>      <channel name='main' mode='secure'/>
>      <channel name='display' mode='secure'/>
>      <channel name='inputs' mode='secure'/>
>      <channel name='cursor' mode='secure'/>
>      <channel name='playback' mode='secure'/>
>      <channel name='record' mode='secure'/>
>      <channel name='smartcard' mode='secure'/>
>      <channel name='usbredir' mode='secure'/>
>    </graphics>
>    <video>
>      <model type='qxl' ram='65536' vram='32768' heads='1'/>
>      <alias name='video0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/>
>    </video>
>    <memballoon model='virtio'>
>      <alias name='balloon0'/>
>      <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0'/>
>    </memballoon>
>  </devices>
>  <seclabel type='none'/>
></domain>
>
>2014-06-18 08:53:39.235+0000: 1913: debug : qemuMigrationPrepareDirect:1717 : Generated uri_out=tcp://10.1.110.173:49155
>2014-06-18 08:53:39.236+0000: 1913: debug : qemuCapsCacheLookup:2151 : Returning caps 0x7fcac41033c0 for /usr/libexec/qemu-kvm
>2014-06-18 08:53:39.236+0000: 1913: debug : qemuMigrationEatCookie:758 : cookielen=228 cookie='<qemu-migration>
>  <name>secvm1</name>
>  <uuid>66e4f53b-682a-4182-bfa8-2121cf36291a</uuid>
>  <hostname>su04</hostname>
>  <hostuuid>c62706e2-aef4-44bb-b4fa-6dc9199178b9</hostuuid>
>  <feature name='lockstate'/>
></qemu-migration>
>'
>2014-06-18 08:53:39.236+0000: 1913: debug : qemuMigrationCookieXMLParseStr:690 : xml=<qemu-migration>
>  <name>secvm1</name>
>  <uuid>66e4f53b-682a-4182-bfa8-2121cf36291a</uuid>
>  <hostname>su04</hostname>
>  <hostuuid>c62706e2-aef4-44bb-b4fa-6dc9199178b9</hostuuid>
>  <feature name='lockstate'/>
></qemu-migration>
>
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuDomainObjBeginJobInternal:813 : Starting async job: migration in
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuDomainObjSetJobPhase:689 : Setting 'migration in' phase to 'prepare'
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3597 : Beginning VM startup process
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3609 : Setting current domain def as transient
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3635 : Preparing host devices
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3639 : Preparing chr devices
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3648 : Generating domain security label (if required)
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3657 : Ensuring no historical cgroup is lying around
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3735 : Creating domain log file
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3740 : Checking for KVM availability
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3750 : Determining emulator version
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuCapsCacheLookup:2151 : Returning caps 0x7fcac41033c0 for /usr/libexec/qemu-kvm
>2014-06-18 08:53:39.237+0000: 1913: debug : qemuProcessStart:3759 : Checking for CDROM and floppy presence
>2014-06-18 08:53:39.237+0000: 1913: debug : virStorageFileGetMetadata:993 : path=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f format=9 uid=107 gid=107 probe=0
>2014-06-18 08:53:39.237+0000: 1913: debug : virStorageFileGetMetadataRecurse:925 : path=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f format=9 uid=107 gid=107 probe=0
>2014-06-18 08:53:39.238+0000: 1913: debug : virStorageFileGetMetadataInternal:673 : path=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f, fd=32, format=9
>2014-06-18 08:53:39.239+0000: 1913: debug : virStorageFileGetMetadataRecurse:925 : path=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/95228a3b-b032-4d3b-b61b-15bfd7b880d1 format=1 uid=107 gid=107 probe=0
>2014-06-18 08:53:39.240+0000: 1913: debug : virStorageFileGetMetadataInternal:673 : path=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/95228a3b-b032-4d3b-b61b-15bfd7b880d1, fd=32, format=1
>2014-06-18 08:53:39.241+0000: 1913: debug : qemuProcessStart:3789 : Setting up domain cgroup (if required)
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuProcessStart:3798 : Preparing monitor state
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuProcessStart:3834 : Assigning domain PCI addresses
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.0
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.243+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:5.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:7.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:7.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:2.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:2.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:1.1
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:6.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.1 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.2 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.3 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.4 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.5 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.6 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuCollectPCIAddress:1045 : Remembering PCI addr 0:0:6.7 (multifunction=off for function 0)
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.1
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.2
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.3
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.4
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.5
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.6
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:0.7
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.0
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.2
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.3
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.4
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.5
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.6
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuDomainPCIAddressReserveAddr:1178 : Reserving PCI addr 0:0:1.7
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuProcessStart:3839 : Building emulator command line
>2014-06-18 08:53:39.244+0000: 1913: debug : qemuBuildCommandLine:5017 : conn=0x7fcabc000c80 driver=0x7fcac401b8d0 def=0x7fcaa40d9230 mon=0x7fcaa40d9970 json=1 caps=0x7fcaa40d5e70 migrateFrom=tcp:[::]:49155 migrateFD=-1 snapshot=(nil) vmop=5
>2014-06-18 08:53:39.254+0000: 1913: info : virNetDevProbeVnetHdr:94 : Enabling IFF_VNET_HDR
>2014-06-18 08:53:39.256+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'EBT="/sbin/ebtables"
>$EBT -t nat -D PREROUTING -i vnet1 -j libvirt-J-vnet1
>$EBT -t nat -D POSTROUTING -o vnet1 -j libvirt-P-vnet1
>EBT="/sbin/ebtables"
>collect_chains()
>{
>  for tmp2 in $*; do
>    for tmp in $($EBT -t nat -L $tmp2 | \
>      sed -n "/Bridge chain/,\$ s/.*-j \$[JP]-.*\$/\\1/p");
>    do
>      echo $tmp
>      collect_chains $tmp
>    done
>  done
>}
>rm_chains()
>{
>  for tmp in $*; do $EBT -t nat -F $tmp; done
>  for tmp in $*; do $EBT -t nat -X $tmp; done
>}
>tmp='\''
>'\''
>IFS='\'' '\'''\''	'\''$tmp
>chains="$(collect_chains libvirt-J-vnet1 libvirt-P-vnet1)"
>$EBT -t nat -F libvirt-J-vnet1
>$EBT -t nat -F libvirt-P-vnet1
>rm_chains $chains
>$EBT -t nat -F libvirt-J-vnet1
>$EBT -t nat -X libvirt-J-vnet1
>$EBT -t nat -F libvirt-P-vnet1
>$EBT -t nat -X libvirt-P-vnet1
>'
>2014-06-18 08:53:39.257+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13668
>2014-06-18 08:53:39.272+0000: 1913: debug : virCommandRun:2045 : Result exit status 255, stdout: '' stderr: 'Illegal target name 'libvirt-J-vnet1'.
>Illegal target name 'libvirt-P-vnet1'.
>Chain 'libvirt-J-vnet1' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>Chain 'libvirt-J-vnet1' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>Chain 'libvirt-J-vnet1' doesn't exist.
>Chain 'libvirt-J-vnet1' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>'
>2014-06-18 08:53:39.272+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'EBT="/sbin/ebtables"
>cmd='\''$EBT -t nat -N libvirt-J-vnet1'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>'
>2014-06-18 08:53:39.273+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13692
>2014-06-18 08:53:39.277+0000: 1913: debug : virCommandRun:2045 : Result status 0, stdout: '' stderr: ''
>2014-06-18 08:53:39.277+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'EBT="/sbin/ebtables"
>cmd='\''$EBT -t nat -F J-vnet1-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>cmd='\''$EBT -t nat -X J-vnet1-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>cmd='\''$EBT -t nat -N J-vnet1-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A libvirt-J-vnet1  -j J-vnet1-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -F J-vnet1-arp-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>cmd='\''$EBT -t nat -X J-vnet1-arp-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>cmd='\''$EBT -t nat -N J-vnet1-arp-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A libvirt-J-vnet1  -p 0x0806 -j J-vnet1-arp-mac'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A J-vnet1-arp-mac  -p 0x806 --arp-mac-src  00:1A:4A:8B:A4:71 -j RETURN'\''
>eval res=\$$"${cmd} 2>&1"$
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A J-vnet1-mac  -s  00:1A:4A:8B:A4:71 -j RETURN'\''
>eval res=\$$"${cmd} 2>&1"$
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A J-vnet1-mac  -j DROP'\''
>eval res=\$$"${cmd} 2>&1"$
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>cmd='\''$EBT -t nat -A J-vnet1-arp-mac  -j DROP'\''
>eval res=\$$"${cmd} 2>&1"$
>
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>'
>2014-06-18 08:53:39.277+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13695
>2014-06-18 08:53:39.302+0000: 1913: debug : virCommandRun:2045 : Result status 0, stdout: '' stderr: ''
>2014-06-18 08:53:39.302+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'EBT="/sbin/ebtables"
>cmd='\''$EBT -t nat -A PREROUTING -i vnet1 -j libvirt-J-vnet1'\''
>eval res=\$$"${cmd} 2>&1"$
>if [ $? -ne 0 ]; then  echo "Failure to execute command '\''${cmd}'\'' : '\''${res}'\''.";  exit 1;fi
>'
>2014-06-18 08:53:39.303+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13731
>2014-06-18 08:53:39.307+0000: 1913: debug : virCommandRun:2045 : Result status 0, stdout: '' stderr: ''
>2014-06-18 08:53:39.307+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'IPT="/sbin/iptables"
>$IPT -D libvirt-out -m physdev --physdev-out vnet1 -g FO-vnet1
>$IPT -D libvirt-in -m physdev --physdev-in vnet1 -g FI-vnet1
>$IPT -D libvirt-host-in -m physdev --physdev-in vnet1 -g HI-vnet1
>$IPT -F FO-vnet1
>$IPT -X FO-vnet1
>$IPT -F FI-vnet1
>$IPT -X FI-vnet1
>$IPT -F HI-vnet1
>$IPT -X HI-vnet1
>$IPT -E FP-vnet1 FO-vnet1
>$IPT -E FJ-vnet1 FI-vnet1
>$IPT -E HJ-vnet1 HI-vnet1
>'
>2014-06-18 08:53:39.307+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13734
>2014-06-18 08:53:39.321+0000: 1913: debug : virCommandRun:2045 : Result exit status 1, stdout: '' stderr: 'iptables v1.4.7: goto 'FO-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables v1.4.7: goto 'FI-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables v1.4.7: goto 'HI-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>'
>2014-06-18 08:53:39.321+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'IPT="/sbin/ip6tables"
>$IPT -D libvirt-out -m physdev --physdev-out vnet1 -g FO-vnet1
>$IPT -D libvirt-in -m physdev --physdev-in vnet1 -g FI-vnet1
>$IPT -D libvirt-host-in -m physdev --physdev-in vnet1 -g HI-vnet1
>$IPT -F FO-vnet1
>$IPT -X FO-vnet1
>$IPT -F FI-vnet1
>$IPT -X FI-vnet1
>$IPT -F HI-vnet1
>$IPT -X HI-vnet1
>$IPT -E FP-vnet1 FO-vnet1
>$IPT -E FJ-vnet1 FI-vnet1
>$IPT -E HJ-vnet1 HI-vnet1
>'
>2014-06-18 08:53:39.322+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13746
>2014-06-18 08:53:39.335+0000: 1913: debug : virCommandRun:2045 : Result exit status 1, stdout: '' stderr: 'ip6tables v1.4.7: goto 'FO-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables v1.4.7: goto 'FI-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables v1.4.7: goto 'HI-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>'
>2014-06-18 08:53:39.335+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'EBT="/sbin/ebtables"
>$EBT -t nat -D PREROUTING -i vnet1 -j libvirt-I-vnet1
>$EBT -t nat -D POSTROUTING -o vnet1 -j libvirt-O-vnet1
>EBT="/sbin/ebtables"
>collect_chains()
>{
>  for tmp2 in $*; do
>    for tmp in $($EBT -t nat -L $tmp2 | \
>      sed -n "/Bridge chain/,\$ s/.*-j \$[IO]-.*\$/\\1/p");
>    do
>      echo $tmp
>      collect_chains $tmp
>    done
>  done
>}
>rm_chains()
>{
>  for tmp in $*; do $EBT -t nat -F $tmp; done
>  for tmp in $*; do $EBT -t nat -X $tmp; done
>}
>tmp='\''
>'\''
>IFS='\'' '\'''\''	'\''$tmp
>chains="$(collect_chains libvirt-I-vnet1 libvirt-O-vnet1)"
>$EBT -t nat -F libvirt-I-vnet1
>$EBT -t nat -F libvirt-O-vnet1
>rm_chains $chains
>$EBT -t nat -F libvirt-I-vnet1
>$EBT -t nat -X libvirt-I-vnet1
>$EBT -t nat -F libvirt-O-vnet1
>$EBT -t nat -X libvirt-O-vnet1
>EBT="/sbin/ebtables"
>collect_chains()
>{
>  for tmp2 in $*; do
>    for tmp in $($EBT -t nat -L $tmp2 | \
>      sed -n "/Bridge chain/,\$ s/.*-j \$[JP]-.*\$/\\1/p");
>    do
>      echo $tmp
>      collect_chains $tmp
>    done
>  done
>}
>rename_chain()
>{
>  $EBT -t nat -F $2
>  $EBT -t nat -X $2
>  $EBT -t nat -E $1 $2
>}
>rename_chains()
>{
>  for tmp in $*; do
>    case $tmp in
>      J*) rename_chain $tmp I${tmp#?} ;;
>      P*) rename_chain $tmp O${tmp#?} ;;
>    esac
>  done
>}
>tmp='\''
>'\''
>IFS='\'' '\'''\''	'\''$tmp
>chains="$(collect_chains libvirt-J-vnet1 libvirt-P-vnet1)"
>rename_chains $chains
>$EBT -t nat -E libvirt-J-vnet1 libvirt-I-vnet1
>$EBT -t nat -E libvirt-P-vnet1 libvirt-O-vnet1
>'
>2014-06-18 08:53:39.335+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13758
>2014-06-18 08:53:39.371+0000: 1913: debug : virCommandRun:2045 : Result exit status 255, stdout: '' stderr: 'Illegal target name 'libvirt-I-vnet1'.
>Illegal target name 'libvirt-O-vnet1'.
>Chain 'libvirt-I-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-I-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-I-vnet1' doesn't exist.
>Chain 'libvirt-I-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>Chain 'I-vnet1-mac' doesn't exist.
>Chain 'I-vnet1-mac' doesn't exist.
>Chain 'I-vnet1-arp-mac' doesn't exist.
>Chain 'I-vnet1-arp-mac' doesn't exist.
>Chain 'libvirt-P-vnet1' doesn't exist.
>'
>2014-06-18 08:53:39.372+0000: 1913: debug : qemuProcessStart:3884 : Clear emulator capabilities: 1
>2014-06-18 08:53:39.372+0000: 1913: debug : virCommandRequireHandshake:2394 : Transfer handshake wait=35 notify=36, keep handshake wait=34 notify=37
>2014-06-18 08:53:39.372+0000: 1913: debug : virCommandRunAsync:2229 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/bin QEMU_AUDIO_DRV=spice /usr/libexec/qemu-kvm -name secvm1 -S -M rhel6.4.0 -cpu Conroe -enable-kvm -m 1024 -realtime mlock=off -smp 1,sockets=1,cores=1,threads=1 -uuid 66e4f53b-682a-4182-bfa8-2121cf36291a -smbios 'type=1,manufacturer=oVirt,product=oVirt Node,version=6Server-6.5.0.1.el6.ns6.02.Adv,serial=40307CB0-CA43-11DF-81EA-E41F136C4750,uuid=66e4f53b-682a-4182-bfa8-2121cf36291a' -nodefconfig -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/secvm1.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2014-06-18T08:53:39,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x3 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x4 -drive if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-drive,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/glusterSD/su04:neofs-images/c13bf545-2d42-4ee8-8ca1-a1413e01595e/images/a4b98453-de36-4591-a6bd-3bffc4e49d27/58808ea3-afe9-43d0-8383-ab6bcb472d5f,if=none,id=drive-virtio-disk0,format=qcow2,serial=a4b98453-de36-4591-a6bd-3bffc4e49d27,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x5,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -netdev tap,fd=32,id=hostnet0,vhost=on,vhostfd=33 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:1a:4a:8b:a4:71,bus=pci.0,addr=0x7 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/66e4f53b-682a-4182-bfa8-2121cf36291a.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice port=5902,tls-port=5903,addr=0,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -vga qxl -global qxl-vga.ram_size=67108864 -global qxl-vga.vram_size=33554432 -incoming 'tcp:[::]:49155' -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x6
>2014-06-18 08:53:39.373+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13794
>2014-06-18 08:53:39.374+0000: 1913: debug : virCommandRun:2045 : Result status 0, stdout: '(null)' stderr: '(null)'
>2014-06-18 08:53:39.374+0000: 1913: debug : qemuProcessStart:3942 : Writing early domain status to disk
>2014-06-18 08:53:39.500+0000: 1913: debug : qemuProcessStart:3947 : Waiting for handshake from child
>2014-06-18 08:53:39.500+0000: 1913: debug : virCommandHandshakeWait:2427 : Wait for handshake on 34
>2014-06-18 08:53:39.500+0000: 1913: debug : qemuProcessStart:3952 : Setting domain security labels
>2014-06-18 08:53:39.500+0000: 1913: debug : qemuProcessStart:3981 : Labelling done, completing handshake to child
>2014-06-18 08:53:39.501+0000: 1913: debug : virCommandHandshakeNotify:2494 : Notify handshake on 37
>2014-06-18 08:53:39.501+0000: 1913: debug : qemuProcessStart:3985 : Handshake complete, child running
>2014-06-18 08:53:39.501+0000: 1913: debug : qemuProcessStart:3996 : Waiting for monitor to show up
>2014-06-18 08:53:39.501+0000: 1913: debug : qemuProcessWaitForMonitor:1768 : Connect monitor to 0x7fcaa40c6f20 'secvm1'
>2014-06-18 08:53:39.701+0000: 1913: debug : qemuMonitorOpenInternal:765 : QEMU_MONITOR_NEW: mon=0x7fcaa40d7a50 refs=2 fd=33
>2014-06-18 08:53:39.701+0000: 1913: warning : qemuDomainObjEnterMonitorInternal:1005 : This thread seems to be the async job owner; entering monitor without asking for a nested job is dangerous
>2014-06-18 08:53:39.701+0000: 1913: debug : qemuMonitorSetCapabilities:1171 : mon=0x7fcaa40d7a50
>2014-06-18 08:53:39.701+0000: 1913: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7fcaa40d7a50 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2014-06-18 08:53:39.706+0000: 1906: error : qemuMonitorIORead:514 : Unable to read from monitor: Connection reset by peer
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuMonitorIO:681 : Triggering error callback
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuProcessHandleMonitorError:358 : Received error on 0x7fcaa40c6f20 'secvm1'
>2014-06-18 08:53:39.707+0000: 1913: debug : qemuMonitorSend:916 : Send command resulted in error Unable to read from monitor: Connection reset by peer
>2014-06-18 08:53:39.707+0000: 1913: debug : qemuProcessStop:4244 : Shutting down VM 'secvm1' pid=13795 flags=1
>2014-06-18 08:53:39.707+0000: 1913: error : virNWFilterDHCPSnoopEnd:2131 : internal error ifname "vnet1" not in key map
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuMonitorIO:670 : Triggering EOF callback
>2014-06-18 08:53:39.707+0000: 1906: debug : qemuProcessHandleMonitorEOF:299 : Received EOF on 0x7fcaa40c6f20 'secvm1'
>2014-06-18 08:53:39.709+0000: 1913: error : virNetDevGetIndex:653 : Unable to get index for interface vnet1: No such device
>2014-06-18 08:53:39.709+0000: 1913: debug : virCommandRunAsync:2229 : About to run /bin/sh -c 'IPT="/sbin/iptables"
>$IPT -D libvirt-out -m physdev --physdev-out vnet1 -g FO-vnet1
>$IPT -D libvirt-in -m physdev --physdev-in vnet1 -g FI-vnet1
>$IPT -D libvirt-host-in -m physdev --physdev-in vnet1 -g HI-vnet1
>$IPT -D libvirt-in-post -m physdev --physdev-in vnet1 -j ACCEPT
>$IPT -F FO-vnet1
>$IPT -X FO-vnet1
>$IPT -F FI-vnet1
>$IPT -X FI-vnet1
>$IPT -F HI-vnet1
>$IPT -X HI-vnet1
>IPT="/sbin/ip6tables"
>$IPT -D libvirt-out -m physdev --physdev-out vnet1 -g FO-vnet1
>$IPT -D libvirt-in -m physdev --physdev-in vnet1 -g FI-vnet1
>$IPT -D libvirt-host-in -m physdev --physdev-in vnet1 -g HI-vnet1
>$IPT -D libvirt-in-post -m physdev --physdev-in vnet1 -j ACCEPT
>$IPT -F FO-vnet1
>$IPT -X FO-vnet1
>$IPT -F FI-vnet1
>$IPT -X FI-vnet1
>$IPT -F HI-vnet1
>$IPT -X HI-vnet1
>EBT="/sbin/ebtables"
>$EBT -t nat -D PREROUTING -i vnet1 -j libvirt-I-vnet1
>$EBT -t nat -D POSTROUTING -o vnet1 -j libvirt-O-vnet1
>EBT="/sbin/ebtables"
>collect_chains()
>{
>  for tmp2 in $*; do
>    for tmp in $($EBT -t nat -L $tmp2 | \
>      sed -n "/Bridge chain/,\$ s/.*-j \$[IO]-.*\$/\\1/p");
>    do
>      echo $tmp
>      collect_chains $tmp
>    done
>  done
>}
>rm_chains()
>{
>  for tmp in $*; do $EBT -t nat -F $tmp; done
>  for tmp in $*; do $EBT -t nat -X $tmp; done
>}
>tmp='\''
>'\''
>IFS='\'' '\'''\''	'\''$tmp
>chains="$(collect_chains libvirt-I-vnet1 libvirt-O-vnet1)"
>$EBT -t nat -F libvirt-I-vnet1
>$EBT -t nat -F libvirt-O-vnet1
>rm_chains $chains
>$EBT -t nat -F libvirt-I-vnet1
>$EBT -t nat -X libvirt-I-vnet1
>$EBT -t nat -F libvirt-O-vnet1
>$EBT -t nat -X libvirt-O-vnet1
>'
>2014-06-18 08:53:39.710+0000: 1913: debug : virCommandRunAsync:2250 : Command result 0, with PID 13821
>2014-06-18 08:53:39.753+0000: 1913: debug : virCommandRun:2045 : Result exit status 255, stdout: '' stderr: 'iptables v1.4.7: goto 'FO-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables v1.4.7: goto 'FI-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables v1.4.7: goto 'HI-vnet1' is not a chain
>
>Try `iptables -h' or 'iptables --help' for more information.
>iptables: Bad rule (does a matching rule exist in that chain?).
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>iptables: No chain/target/match by that name.
>ip6tables v1.4.7: goto 'FO-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables v1.4.7: goto 'FI-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables v1.4.7: goto 'HI-vnet1' is not a chain
>
>Try `ip6tables -h' or 'ip6tables --help' for more information.
>ip6tables: Bad rule (does a matching rule exist in that chain?).
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>ip6tables: No chain/target/match by that name.
>Illegal target name 'libvirt-O-vnet1'.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>Chain 'libvirt-O-vnet1' doesn't exist.
>'
>2014-06-18 08:53:39.753+0000: 1913: debug : qemuMonitorClose:838 : QEMU_MONITOR_CLOSE: mon=0x7fcaa40d7a50 refs=2
>2014-06-18 08:53:39.753+0000: 1913: debug : qemuProcessKill:4142 : vm=secvm1 pid=13795 flags=5
>2014-06-18 08:53:39.753+0000: 1913: debug : qemuDomainCleanupRun:1995 : driver=0x7fcac401b8d0, vm=secvm1
>2014-06-18 08:53:39.753+0000: 1913: debug : qemuProcessAutoDestroyRemove:4725 : vm=secvm1
>2014-06-18 08:53:39.753+0000: 1913: debug : qemuDriverCloseCallbackUnset:661 : vm=secvm1, uuid=66e4f53b-682a-4182-bfa8-2121cf36291a, cb=0x475ce0
>2014-06-18 08:53:39.808+0000: 1913: debug : qemuDomainObjEndAsyncJob:955 : Stopping async job: migration in
>2014-06-18 08:53:39.808+0000: 1906: debug : qemuProcessHandleMonitorEOF:312 : Domain 0x7fcaa40c6f20 is not active, ignoring EOF
>2014-06-18 08:53:39.808+0000: 1906: debug : qemuMonitorDispose:247 : mon=0x7fcaa40d7a50
>2014-06-18 08:53:39.811+0000: 1906: debug : virConnectClose:1449 : conn=0x7fcabc000c80
>2014-06-18 08:53:39.811+0000: 1906: debug : qemuDriverCloseCallbackRunAll:749 : conn=0x7fcabc000c80
>2014-06-18 08:53:39.909+0000: 1912: debug : virDomainLookupByUUID:2039 : conn=0x7fcab40019a0, uuid=66e4f53b-682a-4182-bfa8-2121cf36291a
>2014-06-18 08:53:39.909+0000: 1912: debug : qemudDomainLookupByUUID:1382 : Domain not found: no domain with matching uuid '66e4f53b-682a-4182-bfa8-2121cf36291a'
>2014-06-18 08:53:43.557+0000: 1911: debug : virDomainInterfaceStats:7361 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcad22d3ac0, size=64
>2014-06-18 08:53:43.557+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:43.576+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:43.576+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4680 sys=2668 cpu=0 rss=186949
>2014-06-18 08:53:43.576+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:43.577+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:53:43.577+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4680 sys=2668 cpu=0 rss=186949
>2014-06-18 08:53:43.577+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:46.697+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac000ad00, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:46.697+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4682 sys=2669 cpu=1 rss=186949
>2014-06-18 08:53:46.698+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac000ad00, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:48.559+0000: 1916: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:48.560+0000: 1911: debug : virDomainGetCPUStats:20055 : dom=0x7fcac4095ba0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=(nil), nparams=0, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:48.636+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:48.636+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4095ba0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:48.636+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4683 sys=2670 cpu=2 rss=186949
>2014-06-18 08:53:48.636+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:48.637+0000: 1913: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9
>2014-06-18 08:53:48.637+0000: 1912: debug : virDomainGetCPUStats:20055 : dom=0x7fcab4006ac0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), params=0x7fcab40c77f0, nparams=3, start_cpu=-1, ncpus=1, flags=4
>2014-06-18 08:53:48.638+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab4006ac0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:48.638+0000: 1914: debug : virDomainGetInfo:4208 : dom=0x7fcac000ad00, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad04d0ad0
>2014-06-18 08:53:48.638+0000: 1914: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4683 sys=2670 cpu=2 rss=186949
>2014-06-18 08:53:48.638+0000: 1914: debug : virDomainFree:2281 : dom=0x7fcac000ad00, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:48.639+0000: 1911: debug : virDomainInterfaceStats:7361 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcad22d3ac0, size=64
>2014-06-18 08:53:48.639+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:53.640+0000: 1915: debug : virDomainInterfaceStats:7361 : dom=0x7fcaac0929e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacfacfac0, size=64
>2014-06-18 08:53:53.641+0000: 1915: debug : virDomainFree:2281 : dom=0x7fcaac0929e0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:53.644+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:53:53.644+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4685 sys=2673 cpu=0 rss=186949
>2014-06-18 08:53:53.644+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:53.645+0000: 1912: debug : virDomainGetInfo:4208 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad18d2ad0
>2014-06-18 08:53:53.645+0000: 1912: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4685 sys=2673 cpu=0 rss=186949
>2014-06-18 08:53:53.645+0000: 1912: debug : virDomainFree:2281 : dom=0x7fcab40c4710, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:58.643+0000: 1918: debug : virDomainInterfaceStats:7361 : dom=0x7fcabc001180, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), path=vnet0, stats=0x7fcacdcccac0, size=64
>2014-06-18 08:53:58.643+0000: 1918: debug : virDomainFree:2281 : dom=0x7fcabc001180, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:58.652+0000: 1911: debug : virDomainGetInfo:4208 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad22d3ad0
>2014-06-18 08:53:58.652+0000: 1911: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4687 sys=2676 cpu=2 rss=186949
>2014-06-18 08:53:58.652+0000: 1911: debug : virDomainFree:2281 : dom=0x7fcac4103d40, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:53:58.653+0000: 1910: debug : virDomainGetInfo:4208 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad2cd4ad0
>2014-06-18 08:53:58.653+0000: 1910: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4687 sys=2676 cpu=2 rss=186949
>2014-06-18 08:53:58.653+0000: 1910: debug : virDomainFree:2281 : dom=0x7fcab000b4d0, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:54:01.876+0000: 1913: debug : virDomainGetInfo:4208 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b), info=0x7fcad0ed1ad0
>2014-06-18 08:54:01.876+0000: 1913: debug : qemudGetProcessInfo:1335 : Got status for 4339/0 user=4688 sys=2678 cpu=2 rss=186949
>2014-06-18 08:54:01.876+0000: 1913: debug : virDomainFree:2281 : dom=0x7fcaa40d5670, (VM: name=nsserver1, uuid=f79654b1-d9f9-48d4-83ab-59e14c2d783b)
>2014-06-18 08:54:03.645+0000: 1916: debug : virDrvSupportsFeature:1505 : conn=0x7fcab40019a0, feature=9

Actions: View

Attachments on bug 1110707: 909918 | 909919 | 910203 | 910204