2015-03-24 20:42:10.817+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:10.825+0000: 11451: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:10.827+0000: 11448: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0871c00
2015-03-24 20:42:10.828+0000: 11446: debug : virNodeGetInfo:7152 : conn=0x7ff990000aa0, info=0x7ff9b1873bc0
2015-03-24 20:42:10.828+0000: 11446: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:10.832+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:10.834+0000: 11449: debug : virConnectListAllDomains:8950 : conn=0x7ff990000aa0, domains=0x7ff9b0070bf0, flags=1
2015-03-24 20:42:10.834+0000: 11449: debug : virDomainFree:2287 : dom=0x7ff99800de40, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3)
2015-03-24 20:42:10.834+0000: 11449: debug : virDomainFree:2287 : dom=0x7ff998010610, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8)
2015-03-24 20:42:10.834+0000: 11449: debug : virDomainFree:2287 : dom=0x7ff998000970, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae)
2015-03-24 20:42:10.835+0000: 11445: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=(nil), online=(nil), flags=0
2015-03-24 20:42:10.835+0000: 11447: debug : virDomainGetInfo:4224 : dom=0x7ff980002f90, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3), info=0x7ff9b1072bd0
2015-03-24 20:42:10.835+0000: 11447: debug : qemuGetProcessInfo:1375 : Got status for 23953/0 user=3519 sys=257 cpu=2 rss=72691
2015-03-24 20:42:10.835+0000: 11447: debug : virDomainFree:2287 : dom=0x7ff980002f90, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3)
2015-03-24 20:42:10.836+0000: 11446: debug : virDomainGetVcpus:9980 : dom=0x7ff98c0081f0, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3), info=0x7ff98c010800, maxinfo=1, cpumaps=0x7ff98c008d00, maplen=1
2015-03-24 20:42:10.836+0000: 11446: debug : qemuGetProcessInfo:1375 : Got status for 23953/23960 user=3274 sys=115 cpu=2 rss=72691
2015-03-24 20:42:10.836+0000: 11446: debug : virDomainFree:2287 : dom=0x7ff98c0081f0, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3)
2015-03-24 20:42:10.838+0000: 11443: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=(nil), online=(nil), flags=0
2015-03-24 20:42:10.838+0000: 11445: debug : virDomainGetInfo:4224 : dom=0x7ff9880049e0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8), info=0x7ff9b2074bd0
2015-03-24 20:42:10.838+0000: 11445: debug : qemuGetProcessInfo:1375 : Got status for 27325/0 user=3121 sys=259 cpu=0 rss=71438
2015-03-24 20:42:10.838+0000: 11445: debug : virDomainFree:2287 : dom=0x7ff9880049e0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8)
2015-03-24 20:42:10.839+0000: 11444: debug : virDomainGetVcpus:9980 : dom=0x7ff9780058f0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8), info=0x7ff97800ea50, maxinfo=1, cpumaps=0x7ff978017410, maplen=1
2015-03-24 20:42:10.839+0000: 11444: debug : qemuGetProcessInfo:1375 : Got status for 27325/27327 user=3007 sys=144 cpu=0 rss=71438
2015-03-24 20:42:10.840+0000: 11444: debug : virDomainFree:2287 : dom=0x7ff9780058f0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8)
2015-03-24 20:42:10.840+0000: 11447: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=(nil), online=(nil), flags=0
2015-03-24 20:42:10.843+0000: 11446: debug : virDomainGetInfo:4224 : dom=0x7ff98c0081f0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae), info=0x7ff9b1873bd0
2015-03-24 20:42:10.843+0000: 11446: debug : qemuGetProcessInfo:1375 : Got status for 25691/0 user=3998 sys=274 cpu=0 rss=71728
2015-03-24 20:42:10.843+0000: 11446: debug : virDomainFree:2287 : dom=0x7ff98c0081f0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae)
2015-03-24 20:42:10.843+0000: 11443: debug : virDomainGetVcpus:9980 : dom=0x7ff984002cc0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae), info=0x7ff984002fd0, maxinfo=1, cpumaps=0x7ff984003680, maplen=1
2015-03-24 20:42:10.843+0000: 11443: debug : qemuGetProcessInfo:1375 : Got status for 25691/25693 user=3791 sys=137 cpu=0 rss=71728
2015-03-24 20:42:10.844+0000: 11443: debug : virDomainFree:2287 : dom=0x7ff984002cc0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae)
2015-03-24 20:42:10.845+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:10.847+0000: 11449: debug : virNodeGetInfo:7152 : conn=0x7ff990000aa0, info=0x7ff9b0070bc0
2015-03-24 20:42:10.847+0000: 11449: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:10.850+0000: 11447: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1072c00
2015-03-24 20:42:10.852+0000: 11448: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0871c00
2015-03-24 20:42:10.853+0000: 11450: debug : virConnectGetVersion:1552 : conn=0x7ff990000aa0, hvVer=0x7ff9af86fc00
2015-03-24 20:42:10.853+0000: 11450: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:10.854+0000: 11450: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:10.855+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:10.856+0000: 11444: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:10.858+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:10.859+0000: 11448: debug : virConnectListAllDomains:8950 : conn=0x7ff990000aa0, domains=0x7ff9b0871bf0, flags=1
2015-03-24 20:42:10.859+0000: 11448: debug : virDomainFree:2287 : dom=0x7ff9a022bbe0, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3)
2015-03-24 20:42:10.859+0000: 11448: debug : virDomainFree:2287 : dom=0x7ff9a0000ab0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8)
2015-03-24 20:42:10.859+0000: 11448: debug : virDomainFree:2287 : dom=0x7ff9a0015f40, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae)
2015-03-24 20:42:10.860+0000: 11443: debug : virDomainGetXMLDesc:4365 : dom=0x7ff9840008c0, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3), flags=0
2015-03-24 20:42:10.860+0000: 11443: debug : virDomainFree:2287 : dom=0x7ff9840008c0, (VM: name=instance-0000000d, uuid=e9d1ca4b-11cb-48d6-97a8-1e83c8370ca3)
2015-03-24 20:42:11.011+0000: 11445: debug : virDomainGetXMLDesc:4365 : dom=0x7ff9880049e0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8), flags=0
2015-03-24 20:42:11.011+0000: 11445: debug : virDomainFree:2287 : dom=0x7ff9880049e0, (VM: name=instance-0000000f, uuid=ecf202c9-3ba4-4695-b016-9d7c960080e8)
2015-03-24 20:42:11.157+0000: 11444: debug : virDomainGetXMLDesc:4365 : dom=0x7ff9780058f0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae), flags=0
2015-03-24 20:42:11.157+0000: 11444: debug : virDomainFree:2287 : dom=0x7ff9780058f0, (VM: name=instance-0000000e, uuid=13a25bb7-da26-450b-a333-e226c35339ae)
2015-03-24 20:42:11.320+0000: 11450: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af86fc00
2015-03-24 20:42:11.321+0000: 11452: debug : virNodeNumOfDevices:14406 : conn=0x7ff990000aa0, cap=pci, flags=0
2015-03-24 20:42:11.322+0000: 11449: debug : virNodeListDevices:14522 : conn=0x7ff990000aa0, cap=pci, names=0x7ff998001b30, maxnames=9, flags=0
2015-03-24 20:42:11.323+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:11.324+0000: 11444: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff9780092e0
2015-03-24 20:42:11.324+0000: 11444: debug : virNodeDeviceFree:14804 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0
2015-03-24 20:42:11.325+0000: 11447: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff98000efc0
2015-03-24 20:42:11.325+0000: 11447: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff980002ee0, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.325+0000: 11447: debug : virNodeDeviceFree:14804 : dev=0x7ff980002ee0, conn=0x7ff990000aa0
2015-03-24 20:42:11.326+0000: 11446: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1873c00
2015-03-24 20:42:11.328+0000: 11443: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff984002fd0
2015-03-24 20:42:11.328+0000: 11443: debug : virNodeDeviceFree:14804 : dev=0x7ff984001670, conn=0x7ff990000aa0
2015-03-24 20:42:11.329+0000: 11445: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff988002fa0
2015-03-24 20:42:11.329+0000: 11445: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff988008a90, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.329+0000: 11445: debug : virNodeDeviceFree:14804 : dev=0x7ff988008a90, conn=0x7ff990000aa0
2015-03-24 20:42:11.330+0000: 11444: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2875c00
2015-03-24 20:42:11.331+0000: 11451: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff99000f410
2015-03-24 20:42:11.331+0000: 11451: debug : virNodeDeviceFree:14804 : dev=0x7ff9900009e0, conn=0x7ff990000aa0
2015-03-24 20:42:11.332+0000: 11446: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff98c00d650
2015-03-24 20:42:11.332+0000: 11446: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff98c007180, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.332+0000: 11446: debug : virNodeDeviceFree:14804 : dev=0x7ff98c007180, conn=0x7ff990000aa0
2015-03-24 20:42:11.334+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:11.335+0000: 11450: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff99c002bc0
2015-03-24 20:42:11.335+0000: 11450: debug : virNodeDeviceFree:14804 : dev=0x7ff99c001390, conn=0x7ff990000aa0
2015-03-24 20:42:11.336+0000: 11444: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff978000a00
2015-03-24 20:42:11.336+0000: 11444: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.336+0000: 11444: debug : virNodeDeviceFree:14804 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0
2015-03-24 20:42:11.337+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:11.338+0000: 11449: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff998000970
2015-03-24 20:42:11.338+0000: 11449: debug : virNodeDeviceFree:14804 : dev=0x7ff998010360, conn=0x7ff990000aa0
2015-03-24 20:42:11.339+0000: 11443: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff984002fd0
2015-03-24 20:42:11.339+0000: 11443: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff9840036a0, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.339+0000: 11443: debug : virNodeDeviceFree:14804 : dev=0x7ff9840036a0, conn=0x7ff990000aa0
2015-03-24 20:42:11.340+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:11.341+0000: 11444: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff9780089d0
2015-03-24 20:42:11.341+0000: 11444: debug : virNodeDeviceFree:14804 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0
2015-03-24 20:42:11.342+0000: 11447: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff98000efc0
2015-03-24 20:42:11.342+0000: 11447: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff980002ee0, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.342+0000: 11447: debug : virNodeDeviceFree:14804 : dev=0x7ff980002ee0, conn=0x7ff990000aa0
2015-03-24 20:42:11.343+0000: 11446: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1873c00
2015-03-24 20:42:11.344+0000: 11443: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff984002fd0
2015-03-24 20:42:11.344+0000: 11443: debug : virNodeDeviceFree:14804 : dev=0x7ff984001ac0, conn=0x7ff990000aa0
2015-03-24 20:42:11.345+0000: 11445: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff988013870
2015-03-24 20:42:11.345+0000: 11445: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff988008a90, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.345+0000: 11445: debug : virNodeDeviceFree:14804 : dev=0x7ff988008a90, conn=0x7ff990000aa0
2015-03-24 20:42:11.347+0000: 11449: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0070c00
2015-03-24 20:42:11.348+0000: 11447: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff98000b800
2015-03-24 20:42:11.348+0000: 11447: debug : virNodeDeviceFree:14804 : dev=0x7ff980002ee0, conn=0x7ff990000aa0
2015-03-24 20:42:11.349+0000: 11446: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff98c010a40
2015-03-24 20:42:11.349+0000: 11446: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff98c007180, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.349+0000: 11446: debug : virNodeDeviceFree:14804 : dev=0x7ff98c007180, conn=0x7ff990000aa0
2015-03-24 20:42:11.350+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:11.351+0000: 11450: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff99c001db0
2015-03-24 20:42:11.351+0000: 11450: debug : virNodeDeviceFree:14804 : dev=0x7ff99c001390, conn=0x7ff990000aa0
2015-03-24 20:42:11.352+0000: 11444: debug : virNodeDeviceLookupByName:14561 : conn=0x7ff990000aa0, name=0x7ff9780092e0
2015-03-24 20:42:11.352+0000: 11444: debug : virNodeDeviceGetXMLDesc:14643 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0, flags=0
2015-03-24 20:42:11.352+0000: 11444: debug : virNodeDeviceFree:14804 : dev=0x7ff97800d4f0, conn=0x7ff990000aa0
2015-03-24 20:42:11.354+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:11.355+0000: 11449: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0070c00
2015-03-24 20:42:11.357+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:11.360+0000: 11445: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=0x7ff9b2074bf0, online=0x7ff9b2074bec, flags=0
2015-03-24 20:42:12.405+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:12.405+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:12.724+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:12.786+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:12.786+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:13.111+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:13.177+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:13.177+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:15.293+0000: 11448: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0871c00
2015-03-24 20:42:15.293+0000: 11450: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:16.253+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:16.253+0000: 11449: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:16.428+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:16.429+0000: 11444: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:16.489+0000: 11447: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1072c00
2015-03-24 20:42:16.490+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:16.491+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:16.492+0000: 11445: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=0x7ff9b2074bf0, online=0x7ff9b2074bec, flags=0
2015-03-24 20:42:16.493+0000: 11451: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af06ec00
2015-03-24 20:42:16.493+0000: 11447: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1072c00
2015-03-24 20:42:16.494+0000: 11450: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af86fc00
2015-03-24 20:42:16.494+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:16.495+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:16.495+0000: 11444: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=0x7ff9b2875bf0, online=0x7ff9b2875bec, flags=0
2015-03-24 20:42:16.525+0000: 11447: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1072c00
2015-03-24 20:42:16.525+0000: 11448: debug : virConnectGetVersion:1552 : conn=0x7ff990000aa0, hvVer=0x7ff9b0871c00
2015-03-24 20:42:16.525+0000: 11448: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:16.525+0000: 11448: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:16.577+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:16.578+0000: 11445: debug : virConnectGetVersion:1552 : conn=0x7ff990000aa0, hvVer=0x7ff9b2074c00
2015-03-24 20:42:16.578+0000: 11445: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:16.578+0000: 11445: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:16.727+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.280+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.281+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.497+0000: 11449: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0070c00
2015-03-24 20:42:17.498+0000: 11447: debug : virConnectGetHostname:1628 : conn=0x7ff990000aa0
2015-03-24 20:42:17.706+0000: 11446: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1873c00
2015-03-24 20:42:17.706+0000: 11450: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9af86fc00
2015-03-24 20:42:17.707+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:17.708+0000: 11444: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=0x7ff9b2875bf0, online=0x7ff9b2875bec, flags=0
2015-03-24 20:42:17.709+0000: 11447: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1072c00
2015-03-24 20:42:17.710+0000: 11446: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b1873c00
2015-03-24 20:42:17.710+0000: 11443: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b3076c00
2015-03-24 20:42:17.711+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:17.712+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:17.712+0000: 11447: debug : virNodeGetCPUMap:21042 : conn=0x7ff990000aa0, cpumap=0x7ff9b1072bf0, online=0x7ff9b1072bec, flags=0
2015-03-24 20:42:17.756+0000: 11449: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b0070c00
2015-03-24 20:42:17.757+0000: 11443: debug : virConnectGetVersion:1552 : conn=0x7ff990000aa0, hvVer=0x7ff9b3076c00
2015-03-24 20:42:17.757+0000: 11443: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:17.757+0000: 11443: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:17.822+0000: 11445: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9b2074c00
2015-03-24 20:42:17.823+0000: 11450: debug : virConnectGetVersion:1552 : conn=0x7ff990000aa0, hvVer=0x7ff9af86fc00
2015-03-24 20:42:17.823+0000: 11450: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:17.823+0000: 11450: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:17.933+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.248+0000: 11452: debug : virConnectGetLibVersion:1589 : conn=0x7ff990000aa0, libVir=0x7ff9ae86dc00
2015-03-24 20:42:18.250+0000: 11449: debug : virDomainDefineXML:8673 : conn=0x7ff990000aa0, xml=
c69baac5-21ad-403a-811a-94c260896365
instance-00000010
65536
1
ServerRescueNegativeTestJSON-instance-202624937
2015-03-24 20:42:16
64
0
0
0
1
ServerRescueNegativeTestJSON-831355339
ServerRescueNegativeTestJSON-870932700
OpenStack Foundation
OpenStack Nova
2015.1
fe39ba83-b924-4251-b170-4fc655fbff2f
c69baac5-21ad-403a-811a-94c260896365
hvm
/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/kernel
/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/ramdisk
root=/dev/vda console=tty0 console=ttyS0 no_timer_check
1024
2015-03-24 20:42:18.252+0000: 11449: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:01.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:01 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:02
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:02.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:03
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:03.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:04
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:04.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:01 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:02 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:03 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:04 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:05
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:05.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:01.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:01 already in use
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:02
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:02.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:03
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:03.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:04
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:04.0 (multifunction='off')
2015-03-24 20:42:18.252+0000: 11449: debug : virDomainObjNew:2231 : obj=0x7ff998002730
2015-03-24 20:42:18.255+0000: 11449: debug : virObjectEventNew:647 : obj=0x7ff9980019e0
2015-03-24 20:42:18.255+0000: 11449: info : qemuDomainDefineXML:6537 : Creating domain 'instance-00000010'
2015-03-24 20:42:18.255+0000: 11449: debug : virDomainFree:2287 : dom=0x7ff9980090d0, (VM: name=instance-00000010, uuid=c69baac5-21ad-403a-811a-94c260896365)
2015-03-24 20:42:18.255+0000: 11442: debug : virDomainFree:2287 : dom=0x7ff9c4dee880, (VM: name=instance-00000010, uuid=c69baac5-21ad-403a-811a-94c260896365)
2015-03-24 20:42:18.255+0000: 11442: debug : virObjectEventDispose:121 : obj=0x7ff9980019e0
2015-03-24 20:42:18.259+0000: 11443: debug : virDomainGetXMLDesc:4365 : dom=0x7ff9840008c0, (VM: name=instance-00000010, uuid=c69baac5-21ad-403a-811a-94c260896365), flags=0
2015-03-24 20:42:18.259+0000: 11443: debug : virDomainFree:2287 : dom=0x7ff9840008c0, (VM: name=instance-00000010, uuid=c69baac5-21ad-403a-811a-94c260896365)
2015-03-24 20:42:18.260+0000: 11445: debug : virDomainCreateWithFlags:9054 : dom=0x7ff988007430, (VM: name=instance-00000010, uuid=c69baac5-21ad-403a-811a-94c260896365), flags=1
2015-03-24 20:42:18.260+0000: 11445: debug : qemuDomainObjBeginJobInternal:1307 : Starting job: modify (async=none vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.260+0000: 11445: debug : qemuDomainObjBeginJobInternal:1348 : Started job: modify (async=none vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.261+0000: 11445: debug : qemuProcessStart:4023 : vm=0x7ff998002730 name=instance-00000010 id=-1 pid=0
2015-03-24 20:42:18.261+0000: 11445: debug : qemuProcessStart:4049 : Beginning VM startup process
2015-03-24 20:42:18.261+0000: 11445: debug : qemuProcessStart:4063 : Generating paths
2015-03-24 20:42:18.261+0000: 11445: debug : qemuProcessStart:4072 : Setting current domain def as transient
2015-03-24 20:42:18.262+0000: 11445: debug : qemuProcessStart:4100 : Determining emulator version
2015-03-24 20:42:18.262+0000: 11445: debug : virQEMUCapsCacheLookup:3507 : Returning caps 0x7ff9a0225bf0 for /usr/bin/qemu-system-x86_64
2015-03-24 20:42:18.262+0000: 11445: debug : qemuProcessStart:4110 : Preparing network devices
2015-03-24 20:42:18.262+0000: 11445: debug : qemuProcessStart:4115 : Preparing host devices
2015-03-24 20:42:18.262+0000: 11445: debug : qemuProcessStart:4124 : Preparing chr devices
2015-03-24 20:42:18.262+0000: 11445: debug : qemuProcessStart:4133 : Generating domain security label (if required)
2015-03-24 20:42:18.262+0000: 11445: debug : virSecuritySELinuxGenSecurityLabel:584 : label=QEMU
2015-03-24 20:42:18.262+0000: 11445: debug : virSecuritySELinuxGenSecurityLabel:606 : type=2
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxMCSFind:129 : Using sensitivity level 's0' cat min 0 max 1023 range 1024
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxMCSFind:135 : Try cat s0:c55,c946
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:329 : basecontext=system_u:system_r:svirt_tcg_t:s0 mcs=s0:c55,c946 isObjectContext=0
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:342 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:381 : Generated context 'system_u:system_r:svirt_tcg_t:s0:c55,c946'
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:329 : basecontext=system_u:object_r:svirt_image_t:s0 mcs=s0:c55,c946 isObjectContext=1
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:342 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenNewContext:381 : Generated context 'system_u:object_r:svirt_image_t:s0:c55,c946'
2015-03-24 20:42:18.264+0000: 11445: debug : virSecuritySELinuxGenSecurityLabel:717 : model=selinux label=system_u:system_r:svirt_tcg_t:s0:c55,c946 imagelabel=system_u:object_r:svirt_image_t:s0:c55,c946 baselabel=
2015-03-24 20:42:18.266+0000: 11445: debug : qemuProcessStart:4160 : Ensuring no historical cgroup is lying around
2015-03-24 20:42:18.266+0000: 11445: debug : qemuProcessStart:4237 : Creating domain log file
2015-03-24 20:42:18.267+0000: 11445: debug : qemuDomainCheckDiskPresence:2526 : Checking for disk presence
2015-03-24 20:42:18.271+0000: 11445: debug : qemuProcessStart:4296 : Preparing monitor state
2015-03-24 20:42:18.272+0000: 11445: debug : qemuProcessStart:4328 : Assigning domain PCI addresses
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:03.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:02.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:04.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:01.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:01 already in use
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:02 already in use
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:03 already in use
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressGetNextSlot:497 : PCI slot 0000:00:04 already in use
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressGetNextSlot:540 : Found free PCI slot 0000:00:05
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:05.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:03.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:02.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:04.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : virDomainPCIAddressReserveAddr:317 : Reserving PCI slot 0000:00:01.0 (multifunction='off')
2015-03-24 20:42:18.272+0000: 11445: debug : qemuProcessStart:4333 : Building emulator command line
2015-03-24 20:42:18.272+0000: 11445: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
2015-03-24 20:42:18.272+0000: 11445: debug : qemuBuildCommandLine:7699 : conn=0x7ff990000aa0 driver=0x7ff9a0160cd0 def=0x7ff998006a10 mon=0x7ff9880136c0 json=1 qemuCaps=0x7ff988007dd0 migrateFrom=(null) migrateFD=-1 snapshot=(nil) vmop=0
2015-03-24 20:42:18.272+0000: 11445: info : virNetDevProbeVnetHdr:203 : Enabling IFF_VNET_HDR
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.274+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.347+0000: 11445: debug : virSecuritySELinuxSetTapFDLabel:2385 : fd=25 points to /dev/net/tun not setting SELinux label
2015-03-24 20:42:18.348+0000: 11445: debug : qemuProcessStart:4380 : Clear emulator capabilities: 1
2015-03-24 20:42:18.348+0000: 11445: debug : qemuProcessStart:4432 : Setting up security labelling
2015-03-24 20:42:18.348+0000: 11445: debug : virSecuritySELinuxSetSecurityChildProcessLabel:2069 : label=system_u:system_r:svirt_tcg_t:s0:c55,c946
2015-03-24 20:42:18.348+0000: 11445: debug : virSecurityDACSetChildProcessLabel:1140 : Setting child to drop privileges of DEF to 107:107
2015-03-24 20:42:18.348+0000: 11445: debug : virCommandRequireHandshake:2608 : Transfer handshake wait=30 notify=31, keep handshake wait=29 notify=32
2015-03-24 20:42:18.348+0000: 11445: debug : virCommandRunAsync:2409 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=none /usr/bin/qemu-system-x86_64 -name instance-00000010 -S -machine pc-i440fx-2.1,accel=tcg,usb=off -m 64 -realtime mlock=off -smp 1,sockets=1,cores=1,threads=1 -uuid c69baac5-21ad-403a-811a-94c260896365 -smbios 'type=1,manufacturer=OpenStack Foundation,product=OpenStack Nova,version=2015.1,serial=fe39ba83-b924-4251-b170-4fc655fbff2f,uuid=c69baac5-21ad-403a-811a-94c260896365' -nographic -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/instance-00000010.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=utc -no-shutdown -boot strict=on -kernel /opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/kernel -initrd /opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/ramdisk -append 'root=/dev/vda console=tty0 console=ttyS0 no_timer_check' -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -drive file=/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk,if=none,id=drive-virtio-disk0,format=qcow2,cache=none -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x3,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -drive file=/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk.config,if=none,id=drive-ide0-1-1,readonly=on,format=raw,cache=none -device ide-cd,bus=ide.1,unit=1,drive=drive-ide0-1-1,id=ide0-1-1 -netdev tap,fd=25,id=hostnet0 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=fa:16:3e:74:c6:98,bus=pci.0,addr=0x2 -chardev file,id=charserial0,path=/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/console.log -device isa-serial,chardev=charserial0,id=serial0 -chardev pty,id=charserial1 -device isa-serial,chardev=charserial1,id=serial1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x4 -msg timestamp=on
2015-03-24 20:42:18.350+0000: 11445: debug : virCommandRunAsync:2412 : Command result 0, with PID 28906
2015-03-24 20:42:18.353+0000: 11445: debug : virCommandRun:2260 : Result status 0, stdout: '(null)' stderr: '(null)'
2015-03-24 20:42:18.353+0000: 11445: debug : qemuProcessStart:4458 : QEMU vm=0x7ff998002730 name=instance-00000010 running with pid=28907
2015-03-24 20:42:18.353+0000: 11445: debug : qemuProcessStart:4464 : Writing early domain status to disk
2015-03-24 20:42:18.354+0000: 11445: debug : qemuProcessStart:4469 : Waiting for handshake from child
2015-03-24 20:42:18.354+0000: 11445: debug : virCommandHandshakeWait:2643 : Wait for handshake on 29
2015-03-24 20:42:18.354+0000: 11445: debug : qemuProcessStart:4476 : Setting up domain cgroup (if required)
2015-03-24 20:42:18.355+0000: 11445: debug : virCgroupNewMachineSystemd:1597 : Trying to setup machine 'instance-00000010' via systemd
2015-03-24 20:42:18.355+0000: 11445: debug : virDBusMessageIterEncode:618 : rootiter=0x7ff9b2073b50 types=(null)
2015-03-24 20:42:18.355+0000: 11445: debug : virDBusIsServiceEnabled:1708 : Service org.freedesktop.machine1 is available
2015-03-24 20:42:18.355+0000: 11445: debug : virDBusMessageIterEncode:618 : rootiter=0x7ff9b2073b50 types=(null)
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusIsServiceRegistered:1723 : Service org.freedesktop.systemd1 is registered
2015-03-24 20:42:18.356+0000: 11445: debug : virSystemdCreateMachine:232 : Attempting to create machine via systemd
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:618 : rootiter=0x7ff9b2073b30 types=sayssusa(sv)
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=12 types='sayssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'qemu-instance-00000010'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=11 types='ayssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:720 : Got array non-ref
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 'y' from 'ayssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:742 : Contsig 'y' skip='1' len='1'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='ssusa(sv)' nstruct=9 narray=-1
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=16 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '198'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=15 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '155'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=14 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '170'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=13 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '197'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=12 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '33'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=11 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '173'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=10 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '64'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=9 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '58'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=8 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '129'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=7 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '26'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=6 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '148'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=5 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '194'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=4 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '96'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=3 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '137'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=2 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '99'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=1 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:670 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '101'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=0 nstruct=1 types='yssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff988006c60
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='ssusa(sv)' nstruct=9 narray=-1
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff9b2073b30
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=9 types='ssusa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'libvirt-qemu'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=8 types='susa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'vm'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=7 types='usa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:690 : Appended basic type 'dbus_uint32_t' varg 'unsigned int' sig 'u' val '28907'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=6 types='sa(sv)'
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val ''
2015-03-24 20:42:18.356+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=5 types='a(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:720 : Got array non-ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted '(sv)' from 'a(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:742 : Contsig '(sv)' skip='4' len='4'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=3 nstruct=4 types='(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 'sv)' from '(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:794 : Contsig 'sv)' skip='4' len='4'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='(sv)' nstruct=4 narray=2
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'Slice'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=1 types='s'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'machine.slice'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=0 types=''
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff98800bf60
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=0 types=')'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='(sv)' nstruct=4 narray=2
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff98800bef0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=2 nstruct=4 types='(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 'sv)' from '(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:794 : Contsig 'sv)' skip='4' len='4'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='(sv)' nstruct=4 narray=1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'After'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=2 types='as'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:720 : Got array non-ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 's' from 'as'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:742 : Contsig 's' skip='1' len='1'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=4 narray=1 nstruct=1 types='s'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'libvirtd.service'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=4 narray=0 nstruct=1 types='s'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff98800ec70
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff988009ed0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=0 types=''
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff988009ed0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=0 types=')'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='(sv)' nstruct=4 narray=1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff98800bef0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=1 nstruct=4 types='(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 'sv)' from '(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:794 : Contsig 'sv)' skip='4' len='4'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='(sv)' nstruct=4 narray=0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'Before'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=2 types='as'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:720 : Got array non-ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusCopyContainerSignature:471 : Extracted 's' from 'as'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:742 : Contsig 's' skip='1' len='1'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPush:512 : Pushed types='' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=4 narray=1 nstruct=1 types='s'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:708 : Appended basic type 'char *' varg 'char *' sig 's' val 'libvirt-guests.service'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=4 narray=0 nstruct=1 types='s'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff98800d180
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff98800cd50
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=3 narray=-1 nstruct=0 types=''
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff98800cd50
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types=')' nstruct=0 narray=-1
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=2 narray=-1 nstruct=0 types=')'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff988002540
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='(sv)' nstruct=4 narray=0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff98800bef0
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=1 narray=0 nstruct=4 types='(sv)'
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.357+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff98800bef0
2015-03-24 20:42:18.358+0000: 11445: debug : virDBusTypeStackPop:535 : Popped types='' nstruct=0 narray=-1
2015-03-24 20:42:18.358+0000: 11445: debug : virDBusMessageIterEncode:646 : Popped iter=0x7ff9b2073b30
2015-03-24 20:42:18.358+0000: 11445: debug : virDBusMessageIterEncode:630 : Loop nstack=0 narray=-1 nstruct=0 types=''
2015-03-24 20:42:18.358+0000: 11445: debug : virDBusMessageIterEncode:636 : Reset array ref
2015-03-24 20:42:18.358+0000: 11445: debug : virDBusMessageIterEncode:640 : Popping iter=0x7ff9b2073b30
2015-03-24 20:42:18.368+0000: 11445: debug : virCgroupNewMachineSystemd:1611 : Detecting systemd placement
2015-03-24 20:42:18.368+0000: 11445: debug : virCgroupNew:1046 : parent=(nil) path= controllers=-1
2015-03-24 20:42:18.368+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800cff0 controllers=-1 path= parent=(nil)
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:651 : Auto-detecting controllers
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpu' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuacct' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuset' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'memory' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'devices' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'freezer' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'blkio' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'net_cls' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'perf_event' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'name=systemd' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid 28907 path
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupNew:1046 : parent=(nil) path= controllers=-1
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800cff0 controllers=-1 path= parent=(nil)
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:651 : Auto-detecting controllers
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpu' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuacct' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuset' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'memory' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'devices' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'freezer' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'blkio' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'net_cls' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'perf_event' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'name=systemd' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid 28907 path
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in / for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupNew:1046 : parent=0x7ff98800cff0 path=/machine.slice controllers=-1
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800d470 controllers=-1 path=/machine.slice parent=0x7ff98800cff0
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:651 : Auto-detecting controllers
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpu' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuacct' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuset' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'memory' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'devices' present=yes
2015-03-24 20:42:18.369+0000: 11445: debug : virCgroupDetect:656 : Controller 'freezer' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'blkio' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'net_cls' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'perf_event' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'name=systemd' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid 28907 path /machine.slice
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in /machine.slice for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:937 : Make group /machine.slice
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpuset/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/memory/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/devices/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/freezer/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/blkio/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/net_cls,net_prio/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/perf_event/machine.slice/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:943 : Not creating systemd controller group
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:1014 : Done making controllers for group
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupNew:1046 : parent=0x7ff98800d470 path=/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope controllers=-1
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800cff0 controllers=-1 path=/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope parent=0x7ff98800d470
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:651 : Auto-detecting controllers
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpu' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuacct' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'cpuset' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'memory' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'devices' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'freezer' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'blkio' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'net_cls' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'perf_event' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:656 : Controller 'name=systemd' present=yes
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid 28907 path /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope for pid 28907
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:937 : Make group /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupCpuSetInherit:877 : Setting up inheritance /machine.slice -> /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/cpuset.cpus
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.cpus = 0-3
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/cpuset.mems
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.mems = 0
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.mems' to '0'
2015-03-24 20:42:18.370+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/memory/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/blkio/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:943 : Not creating systemd controller group
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupMakeGroup:1014 : Done making controllers for group
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/memory/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/blkio/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks' to '28907'
2015-03-24 20:42:18.371+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.deny' to 'a'
2015-03-24 20:42:18.371+0000: 11445: debug : qemuSetImageCgroupInternal:84 : Allow path /opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk, perms: rw
2015-03-24 20:42:18.371+0000: 11445: debug : qemuSetImageCgroupInternal:84 : Allow path /opt/stack/data/nova/instances/_base/69416c1d558208b00e2e408d37453f1ce8a871fd, perms: r
2015-03-24 20:42:18.371+0000: 11445: debug : qemuSetImageCgroupInternal:84 : Allow path /opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk.config, perms: r
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 136:* rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 1:3 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 1:7 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 1:5 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 1:8 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 1:9 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 5:2 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : qemuSetupDevicesCgroup:559 : Ignoring non-existent device /dev/kvm
2015-03-24 20:42:18.372+0000: 11445: debug : qemuSetupDevicesCgroup:559 : Ignoring non-existent device /dev/kqemu
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 254:0 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 10:228 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/devices.allow' to 'c 10:200 rw'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpu.shares' to '1024'
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpu.shares
2015-03-24 20:42:18.372+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.372+0000: 11445: debug : qemuProcessInitCpuAffinity:2242 : Set CPU affinity with specified cpuset
2015-03-24 20:42:18.372+0000: 11445: debug : qemuProcessStart:4486 : Setting domain security labels
2015-03-24 20:42:18.372+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk' to 'system_u:object_r:svirt_image_t:s0:c55,c946'
2015-03-24 20:42:18.373+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/_base/69416c1d558208b00e2e408d37453f1ce8a871fd' to 'system_u:object_r:virt_content_t:s0'
2015-03-24 20:42:18.373+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk.config' to 'system_u:object_r:virt_content_t:s0'
2015-03-24 20:42:18.373+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/console.log' to 'system_u:object_r:svirt_image_t:s0:c55,c946'
2015-03-24 20:42:18.373+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/kernel' to 'system_u:object_r:virt_content_t:s0'
2015-03-24 20:42:18.373+0000: 11445: info : virSecuritySELinuxSetFileconHelper:884 : Setting SELinux context on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/ramdisk' to 'system_u:object_r:virt_content_t:s0'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/_base/69416c1d558208b00e2e408d37453f1ce8a871fd' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/disk.config' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/console.log' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/kernel' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: info : virSecurityDACSetOwnershipInternal:243 : Setting DAC user and group on '/opt/stack/data/nova/instances/c69baac5-21ad-403a-811a-94c260896365/ramdisk' to '107:107'
2015-03-24 20:42:18.373+0000: 11445: debug : qemuProcessStart:4515 : Labelling done, completing handshake to child
2015-03-24 20:42:18.373+0000: 11445: debug : virCommandHandshakeNotify:2709 : Notify handshake on 32
2015-03-24 20:42:18.373+0000: 11445: debug : qemuProcessStart:4519 : Handshake complete, child running
2015-03-24 20:42:18.374+0000: 11445: debug : qemuProcessStart:4530 : Waiting for monitor to show up
2015-03-24 20:42:18.374+0000: 11445: debug : qemuProcessWaitForMonitor:2010 : Connect monitor to 0x7ff998002730 'instance-00000010'
2015-03-24 20:42:18.374+0000: 11445: debug : virSecuritySELinuxSetSecurityDaemonSocketLabel:2118 : Setting VM instance-00000010 socket context system_u:system_r:virtd_t:s0:c55,c946
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:350 : dispatching to max 0 clients, called from event watch 6
2015-03-24 20:42:18.418+0000: 11442: debug : virNetlinkEventCallback:363 : event not handled.
2015-03-24 20:42:18.575+0000: 11445: debug : qemuMonitorOpenInternal:831 : QEMU_MONITOR_NEW: mon=0x7ff98800f8d0 refs=2 fd=29
2015-03-24 20:42:18.576+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.576+0000: 11445: debug : qemuMonitorSetCapabilities:1393 : mon=0x7ff98800f8d0
2015-03-24 20:42:18.576+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
2015-03-24 20:42:18.576+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
fd=-1
2015-03-24 20:42:18.576+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"QMP": {"version": {"qemu": {"micro": 3, "minor": 1, "major": 2}, "package": " (qemu-2.1.3-3.fc21)"}, "capabilities": []}}
len=125
2015-03-24 20:42:18.576+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"QMP": {"version": {"qemu": {"micro": 3, "minor": 1, "major": 2}, "package": " (qemu-2.1.3-3.fc21)"}, "capabilities": []}}]
2015-03-24 20:42:18.576+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 125 bytes out of 125 available in buffer
2015-03-24 20:42:18.576+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qmp_capabilities","id":"libvirt-1"}
len=49 ret=49 errno=11
2015-03-24 20:42:18.577+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": {}, "id": "libvirt-1"}
len=35
2015-03-24 20:42:18.577+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": {}, "id": "libvirt-1"}]
2015-03-24 20:42:18.577+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": {}, "id": "libvirt-1"}
2015-03-24 20:42:18.577+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 35 bytes out of 35 available in buffer
2015-03-24 20:42:18.577+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4def7f0
2015-03-24 20:42:18.577+0000: 11445: debug : virQEMUCapsProbeQMP:2516 : qemuCaps=0x7ff988007dd0 mon=0x7ff98800f8d0
2015-03-24 20:42:18.577+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.577+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.577+0000: 11445: debug : qemuMonitorGetPtyPaths:2920 : mon=0x7ff98800f8d0
2015-03-24 20:42:18.577+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"query-chardev","id":"libvirt-2"}' for write with FD -1
2015-03-24 20:42:18.577+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"query-chardev","id":"libvirt-2"}
fd=-1
2015-03-24 20:42:18.577+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"query-chardev","id":"libvirt-2"}
len=46 ret=46 errno=11
2015-03-24 20:42:18.578+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"frontend-open": true, "filename": "pty:/dev/pts/49", "label": "charserial1"}, {"frontend-open": true, "filename": "file", "label": "charserial0"}, {"frontend-open": true, "filename": "unix:/var/lib/libvirt/qemu/instance-00000010.monitor,server", "label": "charmonitor"}], "id": "libvirt-2"}
len=306
2015-03-24 20:42:18.578+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"frontend-open": true, "filename": "pty:/dev/pts/49", "label": "charserial1"}, {"frontend-open": true, "filename": "file", "label": "charserial0"}, {"frontend-open": true, "filename": "unix:/var/lib/libvirt/qemu/instance-00000010.monitor,server", "label": "charmonitor"}], "id": "libvirt-2"}]
2015-03-24 20:42:18.578+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"frontend-open": true, "filename": "pty:/dev/pts/49", "label": "charserial1"}, {"frontend-open": true, "filename": "file", "label": "charserial0"}, {"frontend-open": true, "filename": "unix:/var/lib/libvirt/qemu/instance-00000010.monitor,server", "label": "charmonitor"}], "id": "libvirt-2"}
2015-03-24 20:42:18.578+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 306 bytes out of 306 available in buffer
2015-03-24 20:42:18.578+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc54e0
2015-03-24 20:42:18.578+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.578+0000: 11445: debug : qemuProcessWaitForMonitor:2028 : qemuMonitorGetPtyPaths returned 0
2015-03-24 20:42:18.578+0000: 11445: debug : qemuProcessStart:4545 : Detecting if required emulator features are present
2015-03-24 20:42:18.578+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.578+0000: 11445: debug : qemuMonitorGetGuestCPU:4055 : mon=0x7ff98800f8d0, arch='x86_64' data='0x7ff9b20740a0'
2015-03-24 20:42:18.578+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"/machine/unattached/device[0]"},"id":"libvirt-3"}' for write with FD -1
2015-03-24 20:42:18.578+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"/machine/unattached/device[0]"},"id":"libvirt-3"}
fd=-1
2015-03-24 20:42:18.579+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"/machine/unattached/device[0]"},"id":"libvirt-3"}
len=94 ret=94 errno=11
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "apic", "type": "child"}, {"name": "filtered-features", "type": "X86CPUFeatureWordInfo"}, {"name": "feature-words", "type": "X86CPUFeatureWordInfo"}, {"name": "apic-id", "type": "int"}, {"name": "tsc-frequency", "type": "int"}, {"name": "model-id", "type": "string"}, {"name": "vendor", "type": "string"}, {"name": "xlevel", "type": "int"}, {"name": "level", "type": "int"}, {"name": "stepping", "type": "int"}, {"name": "model", "type": "int"}, {"name": "family", "type": "int"}, {"name": "parent_bus", "type": "link"}, {"name": "kvm", "type": "bool"}, {"name": "enforce", "type": "bool"}, {"name": "check", "type": "bool"}, {"name": "hv-time", "type": "bool"}, {"name": "hv-vapic", "type": "bool"}, {"name": "hv-relaxed", "type": "bool"}, {"name": "hv-spinlocks", "type": "int"}, {"name": "pmu", "type": "bool"}, {"name": "hotplugged", "type": "bool"}, {"name": "hotpluggable", "type": "bool"}, {"name": "realized", "type": "bool"}, {"name": "type", "type": "string"}], "id": "libvirt-3"}
len=1023
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 0 bytes out of 1023 available in buffer
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "apic", "type": "child"}, {"name": "filtered-features", "type": "X86CPUFeatureWordInfo"}, {"name": "feature-words", "type": "X86CPUFeatureWordInfo"}, {"name": "apic-id", "type": "int"}, {"name": "tsc-frequency", "type": "int"}, {"name": "model-id", "type": "string"}, {"name": "vendor", "type": "string"}, {"name": "xlevel", "type": "int"}, {"name": "level", "type": "int"}, {"name": "stepping", "type": "int"}, {"name": "model", "type": "int"}, {"name": "family", "type": "int"}, {"name": "parent_bus", "type": "link"}, {"name": "kvm", "type": "bool"}, {"name": "enforce", "type": "bool"}, {"name": "check", "type": "bool"}, {"name": "hv-time", "type": "bool"}, {"name": "hv-vapic", "type": "bool"}, {"name": "hv-relaxed", "type": "bool"}, {"name": "hv-spinlocks", "type": "int"}, {"name": "pmu", "type": "bool"}, {"name": "hotplugged", "type": "bool"}, {"name": "hotpluggable", "type": "bool"}, {"name": "realized", "type": "bool"}, {"name": "type", "type": "string"}], "id": "libvirt-3"}
len=1024
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "apic", "type": "child"}, {"name": "filtered-features", "type": "X86CPUFeatureWordInfo"}, {"name": "feature-words", "type": "X86CPUFeatureWordInfo"}, {"name": "apic-id", "type": "int"}, {"name": "tsc-frequency", "type": "int"}, {"name": "model-id", "type": "string"}, {"name": "vendor", "type": "string"}, {"name": "xlevel", "type": "int"}, {"name": "level", "type": "int"}, {"name": "stepping", "type": "int"}, {"name": "model", "type": "int"}, {"name": "family", "type": "int"}, {"name": "parent_bus", "type": "link"}, {"name": "kvm", "type": "bool"}, {"name": "enforce", "type": "bool"}, {"name": "check", "type": "bool"}, {"name": "hv-time", "type": "bool"}, {"name": "hv-vapic", "type": "bool"}, {"name": "hv-relaxed", "type": "bool"}, {"name": "hv-spinlocks", "type": "int"}, {"name": "pmu", "type": "bool"}, {"name": "hotplugged", "type": "bool"}, {"name": "hotpluggable", "type": "bool"}, {"name": "realized", "type": "bool"}, {"name": "type", "type": "string"}], "id": "libvirt-3"}]
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "apic", "type": "child"}, {"name": "filtered-features", "type": "X86CPUFeatureWordInfo"}, {"name": "feature-words", "type": "X86CPUFeatureWordInfo"}, {"name": "apic-id", "type": "int"}, {"name": "tsc-frequency", "type": "int"}, {"name": "model-id", "type": "string"}, {"name": "vendor", "type": "string"}, {"name": "xlevel", "type": "int"}, {"name": "level", "type": "int"}, {"name": "stepping", "type": "int"}, {"name": "model", "type": "int"}, {"name": "family", "type": "int"}, {"name": "parent_bus", "type": "link"}, {"name": "kvm", "type": "bool"}, {"name": "enforce", "type": "bool"}, {"name": "check", "type": "bool"}, {"name": "hv-time", "type": "bool"}, {"name": "hv-vapic", "type": "bool"}, {"name": "hv-relaxed", "type": "bool"}, {"name": "hv-spinlocks", "type": "int"}, {"name": "pmu", "type": "bool"}, {"name": "hotplugged", "type": "bool"}, {"name": "hotpluggable", "type": "bool"}, {"name": "realized", "type": "bool"}, {"name": "type", "type": "string"}], "id": "libvirt-3"}
2015-03-24 20:42:18.580+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 1024 bytes out of 1024 available in buffer
2015-03-24 20:42:18.580+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc54e0
2015-03-24 20:42:18.580+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-get","arguments":{"path":"/machine/unattached/device[0]","property":"feature-words"},"id":"libvirt-4"}' for write with FD -1
2015-03-24 20:42:18.581+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-get","arguments":{"path":"/machine/unattached/device[0]","property":"feature-words"},"id":"libvirt-4"}
fd=-1
2015-03-24 20:42:18.581+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-get","arguments":{"path":"/machine/unattached/device[0]","property":"feature-words"},"id":"libvirt-4"}
len=120 ret=120 errno=11
2015-03-24 20:42:18.582+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"cpuid-register": "EDX", "cpuid-input-eax": 2147483658, "features": 0}, {"cpuid-register": "EAX", "cpuid-input-eax": 1073741825, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 3221225473, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483655, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 2147483649, "features": 101}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483649, "features": 563346425}, {"cpuid-register": "EBX", "cpuid-input-ecx": 0, "cpuid-input-eax": 7, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 1, "features": 2155880449}, {"cpuid-register": "EDX", "cpuid-input-eax": 1, "features": 126614521}], "id": "libvirt-4"}
len=712
2015-03-24 20:42:18.582+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"cpuid-register": "EDX", "cpuid-input-eax": 2147483658, "features": 0}, {"cpuid-register": "EAX", "cpuid-input-eax": 1073741825, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 3221225473, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483655, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 2147483649, "features": 101}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483649, "features": 563346425}, {"cpuid-register": "EBX", "cpuid-input-ecx": 0, "cpuid-input-eax": 7, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 1, "features": 2155880449}, {"cpuid-register": "EDX", "cpuid-input-eax": 1, "features": 126614521}], "id": "libvirt-4"}]
2015-03-24 20:42:18.582+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"cpuid-register": "EDX", "cpuid-input-eax": 2147483658, "features": 0}, {"cpuid-register": "EAX", "cpuid-input-eax": 1073741825, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 3221225473, "features": 0}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483655, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 2147483649, "features": 101}, {"cpuid-register": "EDX", "cpuid-input-eax": 2147483649, "features": 563346425}, {"cpuid-register": "EBX", "cpuid-input-ecx": 0, "cpuid-input-eax": 7, "features": 0}, {"cpuid-register": "ECX", "cpuid-input-eax": 1, "features": 2155880449}, {"cpuid-register": "EDX", "cpuid-input-eax": 1, "features": 126614521}], "id": "libvirt-4"}
2015-03-24 20:42:18.582+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 712 bytes out of 712 available in buffer
2015-03-24 20:42:18.582+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dce8e0
2015-03-24 20:42:18.582+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.582+0000: 11445: debug : qemuProcessStart:4549 : Setting up post-init cgroup restrictions
2015-03-24 20:42:18.582+0000: 11445: debug : qemuProcessStart:4553 : Detecting VCPU PIDs
2015-03-24 20:42:18.582+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.582+0000: 11445: debug : qemuMonitorGetCPUInfo:1519 : mon=0x7ff98800f8d0
2015-03-24 20:42:18.582+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"query-cpus","id":"libvirt-5"}' for write with FD -1
2015-03-24 20:42:18.583+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"query-cpus","id":"libvirt-5"}
fd=-1
2015-03-24 20:42:18.583+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"query-cpus","id":"libvirt-5"}
len=43 ret=43 errno=11
2015-03-24 20:42:18.583+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 28918}], "id": "libvirt-5"}
len=117
2015-03-24 20:42:18.583+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 28918}], "id": "libvirt-5"}]
2015-03-24 20:42:18.583+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"current": true, "CPU": 0, "pc": 4294967280, "halted": false, "thread_id": 28918}], "id": "libvirt-5"}
2015-03-24 20:42:18.583+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 117 bytes out of 117 available in buffer
2015-03-24 20:42:18.583+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc2020
2015-03-24 20:42:18.583+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.583+0000: 11445: debug : qemuProcessStart:4557 : Detecting IOThread PIDs
2015-03-24 20:42:18.583+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.583+0000: 11445: debug : qemuMonitorGetIOThreads:4123 : mon=0x7ff98800f8d0 iothreads=0x7ff9b2073fc0
2015-03-24 20:42:18.584+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"query-iothreads","id":"libvirt-6"}' for write with FD -1
2015-03-24 20:42:18.584+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"query-iothreads","id":"libvirt-6"}
fd=-1
2015-03-24 20:42:18.584+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"query-iothreads","id":"libvirt-6"}
len=48 ret=48 errno=11
2015-03-24 20:42:18.584+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [], "id": "libvirt-6"}
len=35
2015-03-24 20:42:18.584+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [], "id": "libvirt-6"}]
2015-03-24 20:42:18.584+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [], "id": "libvirt-6"}
2015-03-24 20:42:18.584+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 35 bytes out of 35 available in buffer
2015-03-24 20:42:18.584+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc2020
2015-03-24 20:42:18.584+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.584+0000: 11445: debug : qemuProcessStart:4561 : Setting cgroup for each VCPU (if required)
2015-03-24 20:42:18.584+0000: 11445: debug : virCgroupNew:1046 : parent=0x7ff98800cff0 path=vcpu0 controllers=7
2015-03-24 20:42:18.584+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800e6a0 controllers=7 path=vcpu0 parent=0x7ff98800cff0
2015-03-24 20:42:18.584+0000: 11445: debug : virCgroupDetect:616 : Filtering controllers 7
2015-03-24 20:42:18.584+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpu' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpuacct' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpuset' wanted=yes, mount='/sys/fs/cgroup/cpuset'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'memory' wanted=no, mount='/sys/fs/cgroup/memory'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'devices' wanted=no, mount='/sys/fs/cgroup/devices'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'freezer' wanted=no, mount='/sys/fs/cgroup/freezer'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'blkio' wanted=no, mount='/sys/fs/cgroup/blkio'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'net_cls' wanted=no, mount='/sys/fs/cgroup/net_cls,net_prio'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'perf_event' wanted=no, mount='/sys/fs/cgroup/perf_event'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:621 : Controller 'name=systemd' wanted=no, mount='/sys/fs/cgroup/systemd'
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid -1 path vcpu0
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0 for pid 18446744073709551615
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0 for pid 18446744073709551615
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0 for pid 18446744073709551615
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupMakeGroup:937 : Make group /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/
2015-03-24 20:42:18.585+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupCpuSetInherit:877 : Setting up inheritance /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope -> /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.cpus
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.cpus = 0-3
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.mems
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.mems = 0
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/cpuset.mems' to '0'
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller memory
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller devices
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller freezer
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller blkio
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller net_cls
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller perf_event
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:943 : Not creating systemd controller group
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupMakeGroup:1014 : Done making controllers for group
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/tasks' to '28918'
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/tasks' to '28918'
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/tasks' to '28918'
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/vcpu0/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.586+0000: 11445: debug : qemuProcessStart:4565 : Setting cgroup for emulator (if required)
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupNew:1046 : parent=0x7ff98800cff0 path=emulator controllers=7
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupDetect:605 : group=0x7ff98800e6a0 controllers=7 path=emulator parent=0x7ff98800cff0
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupDetect:616 : Filtering controllers 7
2015-03-24 20:42:18.586+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpu' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpuacct' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'cpuset' wanted=yes, mount='/sys/fs/cgroup/cpuset'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'memory' wanted=no, mount='/sys/fs/cgroup/memory'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'devices' wanted=no, mount='/sys/fs/cgroup/devices'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'freezer' wanted=no, mount='/sys/fs/cgroup/freezer'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'blkio' wanted=no, mount='/sys/fs/cgroup/blkio'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'net_cls' wanted=no, mount='/sys/fs/cgroup/net_cls,net_prio'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'perf_event' wanted=no, mount='/sys/fs/cgroup/perf_event'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:621 : Controller 'name=systemd' wanted=no, mount='/sys/fs/cgroup/systemd'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetectPlacement:510 : Detecting placement for pid -1 path emulator
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator for pid 18446744073709551615
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator for pid 18446744073709551615
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupDetect:698 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator for pid 18446744073709551615
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupMakeGroup:937 : Make group /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupMakeGroup:961 : Make controller /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupCpuSetInherit:877 : Setting up inheritance /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope -> /machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.cpus
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.cpus = 0-3
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.587+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/cpuset.mems
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupCpuSetInherit:887 : Inherit cpuset.mems = 0
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/cpuset.mems' to '0'
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller memory
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller devices
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller freezer
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller blkio
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller net_cls
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:950 : Skipping unmounted controller perf_event
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:943 : Not creating systemd controller group
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupMakeGroup:1014 : Done making controllers for group
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/tasks' to '28907'
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/tasks' to '28917'
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/tasks' to '28907'
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/tasks' to '28917'
2015-03-24 20:42:18.588+0000: 11445: debug : virCgroupGetValueStr:754 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/tasks
2015-03-24 20:42:18.589+0000: 11445: debug : virCgroupSetValueStr:718 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2dinstance\x2d00000010.scope/emulator/cpuset.cpus' to '0-3'
2015-03-24 20:42:18.589+0000: 11445: debug : qemuProcessStart:4569 : Setting cgroup for each IOThread (if required)
2015-03-24 20:42:18.589+0000: 11445: debug : qemuProcessStart:4573 : Setting VCPU affinities
2015-03-24 20:42:18.590+0000: 11445: debug : qemuProcessStart:4577 : Setting affinity of emulator threads
2015-03-24 20:42:18.590+0000: 11445: debug : qemuProcessStart:4581 : Setting affinity of IOThread threads
2015-03-24 20:42:18.590+0000: 11445: debug : qemuProcessStart:4585 : Setting any required VM passwords
2015-03-24 20:42:18.590+0000: 11445: debug : qemuProcessStart:4600 : Setting network link states
2015-03-24 20:42:18.590+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.590+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.590+0000: 11445: debug : qemuProcessStart:4610 : Fetching list of active devices
2015-03-24 20:42:18.590+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.590+0000: 11445: debug : qemuMonitorGetDeviceAliases:3999 : mon=0x7ff98800f8d0, aliases=0x7ff9b2073fc0
2015-03-24 20:42:18.590+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"/machine/peripheral"},"id":"libvirt-7"}' for write with FD -1
2015-03-24 20:42:18.591+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"/machine/peripheral"},"id":"libvirt-7"}
fd=-1
2015-03-24 20:42:18.593+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"/machine/peripheral"},"id":"libvirt-7"}
len=84 ret=84 errno=11
2015-03-24 20:42:18.593+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "balloon0", "type": "child"}, {"name": "serial1", "type": "child"}, {"name": "serial0", "type": "child"}, {"name": "net0", "type": "child"}, {"name": "ide0-1-1", "type": "child"}, {"name": "virtio-disk0", "type": "child"}, {"name": "usb", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-7"}
len=435
2015-03-24 20:42:18.593+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "balloon0", "type": "child"}, {"name": "serial1", "type": "child"}, {"name": "serial0", "type": "child"}, {"name": "net0", "type": "child"}, {"name": "ide0-1-1", "type": "child"}, {"name": "virtio-disk0", "type": "child"}, {"name": "usb", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-7"}]
2015-03-24 20:42:18.593+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "balloon0", "type": "child"}, {"name": "serial1", "type": "child"}, {"name": "serial0", "type": "child"}, {"name": "net0", "type": "child"}, {"name": "ide0-1-1", "type": "child"}, {"name": "virtio-disk0", "type": "child"}, {"name": "usb", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-7"}
2015-03-24 20:42:18.593+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 435 bytes out of 435 available in buffer
2015-03-24 20:42:18.593+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1b40
2015-03-24 20:42:18.593+0000: 11445: debug : qemuDomainObjExitMonitorInternal:1572 : Exited monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.593+0000: 11445: debug : qemuProcessStart:4618 : Setting initial memory amount
2015-03-24 20:42:18.593+0000: 11445: debug : qemuDomainObjEnterMonitorInternal:1549 : Entering monitor (mon=0x7ff98800f8d0 vm=0x7ff998002730 name=instance-00000010)
2015-03-24 20:42:18.593+0000: 11445: debug : qemuMonitorSetMemoryStatsPeriod:1622 : mon=0x7ff98800f8d0 period=10
2015-03-24 20:42:18.593+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at /
2015-03-24 20:42:18.593+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-8"}' for write with FD -1
2015-03-24 20:42:18.593+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-8"}
fd=-1
2015-03-24 20:42:18.594+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"/"},"id":"libvirt-8"}
len=66 ret=66 errno=11
2015-03-24 20:42:18.594+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "objects", "type": "child"}, {"name": "machine", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-8"}
len=179
2015-03-24 20:42:18.594+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "objects", "type": "child"}, {"name": "machine", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-8"}]
2015-03-24 20:42:18.595+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "objects", "type": "child"}, {"name": "machine", "type": "child"}, {"name": "type", "type": "string"}], "id": "libvirt-8"}
2015-03-24 20:42:18.595+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 179 bytes out of 179 available in buffer
2015-03-24 20:42:18.595+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dfafa0
2015-03-24 20:42:18.595+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //objects
2015-03-24 20:42:18.595+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//objects"},"id":"libvirt-9"}' for write with FD -1
2015-03-24 20:42:18.595+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//objects"},"id":"libvirt-9"}
fd=-1
2015-03-24 20:42:18.595+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//objects"},"id":"libvirt-9"}
len=74 ret=74 errno=11
2015-03-24 20:42:18.597+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "type", "type": "string"}], "id": "libvirt-9"}
len=69
2015-03-24 20:42:18.597+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "type", "type": "string"}], "id": "libvirt-9"}]
2015-03-24 20:42:18.597+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "type", "type": "string"}], "id": "libvirt-9"}
2015-03-24 20:42:18.597+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 69 bytes out of 69 available in buffer
2015-03-24 20:42:18.597+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc5340
2015-03-24 20:42:18.597+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine
2015-03-24 20:42:18.597+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine"},"id":"libvirt-10"}' for write with FD -1
2015-03-24 20:42:18.597+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine"},"id":"libvirt-10"}
fd=-1
2015-03-24 20:42:18.597+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine"},"id":"libvirt-10"}
len=75 ret=75 errno=11
2015-03-24 20:42:18.598+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "acpi-device", "type": "link"}, {"name": "acpi-pci-hotplug[0]", "type": "child"}, {"name": "ide[3]", "type": "child"}, {"name": "ide[2]", "type": "child"}, {"name": "ide[1]", "type": "child"}, {"name": "ide[0]", "type": "child"}, {"name": "dma-cont[1]", "type": "child"}, {"name": "dma-page[3]", "type": "child"}, {"name": "dma-page[2]", "type": "child"}, {"name": "dma-chan[1]", "type": "child"}, {"name": "dma-cont[0]", "type": "child"}, {"name": "dma-page[1]", "type": "child"}, {"name": "dma-page[0]", "type": "child"}, {"name": "dma-chan[0]", "type": "child"}, {"name": "rtc-time", "type": "struct tm"}, {"name": "ioportF0[0]", "type": "child"}, {"name": "ioport80[0]", "type": "child"}, {"name": "acpi-pci-hotplug[0]", "type": "child"}, {"name": "ide[3]", "type": "child"}, {"name": "ide[2]", "type": "child"}, {"name": "ide[1]", "type": "child"}, {"name": "ide[0]", "type": "child"}, {"name": "dma-cont[1]", "type": "child"}, {"name": "dma-page[3]", "type": "child"}, {"name": "dma-page[2]", "type": "child"}, {"name": "dma-chan[1]", "type": "child"}, {"name": "dma-cont[0]", "type": "child"}, {"name": "dma-page[1]", "type": "child"}, {"name": "dma-page[0]", "type": "child"}, {"name": "dma-chan[0]", "type": "child"}, {"name": "rtc-time", "type": "struct tm"}, {"name": "ioportF0[0]", "type": "child"}, {"name": "ioport80[0]", "type": "child"}, {"name": "i440fx", "type": "child"}, {"name": "fw_cfg", "type": "child"}, {"name": "pc.rom[0]", "type": "child"}, {"name": "isa-bios[0]", "type": "child"}, {"name": "pc.bios[0]", "type": "child"}, {"name": "ram-below-4g[0]", "type": "child"}, {"name": "pc.ram[0]", "type": "child"}, {"name": "pci[0]", "type": "child"}, {"name": "icc-bridge", "type": "child"}, {"name": "unattached", "type": "child"}, {"name": "peripheral", "type": "child"}, {"name": "peripheral-anon", "type": "child"}, {"name": "io[0]", "type": "child"}, {"name": "system[0]", "type": "child"}, {"name": "max-ram-below-4g", "type": "size"}, {"name": "hotplug-memory-region-size", "type": "int"}, {"name": "firmware", "type": "string"}, {"name": "usb", "type": "bool"}, {"name": "mem-merge", "type": "bool"}, {"name": len=2047
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 0 bytes out of 2047 available in buffer
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "acpi-device", "type": "link"}, {"name": "acpi-pci-hotplug[0]", "type": "child"}, {"name": "ide[3]", "type": "child"}, {"name": "ide[2]", "type": "child"}, {"name": "ide[1]", "type": "child"}, {"name": "ide[0]", "type": "child"}, {"name": "dma-cont[1]", "type": "child"}, {"name": "dma-page[3]", "type": "child"}, {"name": "dma-page[2]", "type": "child"}, {"name": "dma-chan[1]", "type": "child"}, {"name": "dma-cont[0]", "type": "child"}, {"name": "dma-page[1]", "type": "child"}, {"name": "dma-page[0]", "type": "child"}, {"name": "dma-chan[0]", "type": "child"}, {"name": "rtc-time", "type": "struct tm"}, {"name": "ioportF0[0]", "type": "child"}, {"name": "ioport80[0]", "type": "child"}, {"name": "i440fx", "type": "child"}, {"name": "fw_cfg", "type": "child"}, {"name": "pc.rom[0]", "type": "child"}, {"name": "isa-bios[0]", "type": "child"}, {"name": "pc.bios[0]", "type": "child"}, {"name": "ram-below-4g[0]", "type": "child"}, {"name": "pc.ram[0]", "type": "child"}, {"name": "pci[0]", "type": "child"}, {"name": "icc-bridge", "type": "child"}, {"name": "unattached", "type": "child"}, {"name": "peripheral", "type": "child"}, {"name": "peripheral-anon", "type": "child"}, {"name": "io[0]", "type": "child"}, {"name": "system[0]", "type": "child"}, {"name": "max-ram-below-4g", "type": "size"}, {"name": "hotplug-memory-region-size", "type": "int"}, {"name": "firmware", "type": "string"}, {"name": "usb", "type": "bool"}, {"name": "mem-merge", "type": "bool"}, {"name": "dump-guest-core", "type": "bool"}, {"name": "dt-compatible", "type": "string"}, {"name": "phandle-start", "type": "int"}, {"name": "dumpdtb", "type": "string"}, {"name": "dtb", "type": "string"}, {"name": "append", "type": "string"}, {"name": "initrd", "type": "string"}, {"name": "kernel", "type": "string"}, {"name": "kvm-shadow-mem", "type": "int"}, {"name": "kernel-irqchip", "type": "bool"}, {"name": "accel", "type": "string"}, {"name": "type", "type": "string"}], "id": "libvirt-10"}
len=2540
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "acpi-device", "type": "link"}, {"name": "acpi-pci-hotplug[0]", "type": "child"}, {"name": "ide[3]", "type": "child"}, {"name": "ide[2]", "type": "child"}, {"name": "ide[1]", "type": "child"}, {"name": "ide[0]", "type": "child"}, {"name": "dma-cont[1]", "type": "child"}, {"name": "dma-page[3]", "type": "child"}, {"name": "dma-page[2]", "type": "child"}, {"name": "dma-chan[1]", "type": "child"}, {"name": "dma-cont[0]", "type": "child"}, {"name": "dma-page[1]", "type": "child"}, {"name": "dma-page[0]", "type": "child"}, {"name": "dma-chan[0]", "type": "child"}, {"name": "rtc-time", "type": "struct tm"}, {"name": "ioportF0[0]", "type": "child"}, {"name": "ioport80[0]", "type": "child"}, {"name": "i440fx", "type": "child"}, {"name": "fw_cfg", "type": "child"}, {"name": "pc.rom[0]", "type": "child"}, {"name": "isa-bios[0]", "type": "child"}, {"name": "pc.bios[0]", "type": "child"}, {"name": "ram-below-4g[0]", "type": "child"}, {"name": "pc.ram[0]", "type": "child"}, {"name": "pci[0]", "type": "child"}, {"name": "icc-bridge", "type": "child"}, {"name": "unattached", "type": "child"}, {"name": "peripheral", "type": "child"}, {"name": "peripheral-anon", "type": "child"}, {"name": "io[0]", "type": "child"}, {"name": "system[0]", "type": "child"}, {"name": "max-ram-below-4g", "type": "size"}, {"name": "hotplug-memory-region-size", "type": "int"}, {"name": "firmware", "type": "string"}, {"name": "usb", "type": "bool"}, {"name": "mem-merge", "type": "bool"}, {"name": "dump-guest-core", "type": "bool"}, {"name": "dt-compatible", "type": "string"}, {"name": "phandle-start", "type": "int"}, {"name": "dumpdtb", "type": "string"}, {"name": "dtb", "type": "string"}, {"name": "append", "type": "string"}, {"name": "initrd", "type": "string"}, {"name": "kernel", "type": "string"}, {"name": "kvm-shadow-mem", "type": "int"}, {"name": "kernel-irqchip", "type": "bool"}, {"name": "accel", "type": "string"}, {"name": "type", "type": "string"}], "id": "libvirt-10"}]
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "acpi-device", "type": "link"}, {"name": "acpi-pci-hotplug[0]", "type": "child"}, {"name": "ide[3]", "type": "child"}, {"name": "ide[2]", "type": "child"}, {"name": "ide[1]", "type": "child"}, {"name": "ide[0]", "type": "child"}, {"name": "dma-cont[1]", "type": "child"}, {"name": "dma-page[3]", "type": "child"}, {"name": "dma-page[2]", "type": "child"}, {"name": "dma-chan[1]", "type": "child"}, {"name": "dma-cont[0]", "type": "child"}, {"name": "dma-page[1]", "type": "child"}, {"name": "dma-page[0]", "type": "child"}, {"name": "dma-chan[0]", "type": "child"}, {"name": "rtc-time", "type": "struct tm"}, {"name": "ioportF0[0]", "type": "child"}, {"name": "ioport80[0]", "type": "child"}, {"name": "i440fx", "type": "child"}, {"name": "fw_cfg", "type": "child"}, {"name": "pc.rom[0]", "type": "child"}, {"name": "isa-bios[0]", "type": "child"}, {"name": "pc.bios[0]", "type": "child"}, {"name": "ram-below-4g[0]", "type": "child"}, {"name": "pc.ram[0]", "type": "child"}, {"name": "pci[0]", "type": "child"}, {"name": "icc-bridge", "type": "child"}, {"name": "unattached", "type": "child"}, {"name": "peripheral", "type": "child"}, {"name": "peripheral-anon", "type": "child"}, {"name": "io[0]", "type": "child"}, {"name": "system[0]", "type": "child"}, {"name": "max-ram-below-4g", "type": "size"}, {"name": "hotplug-memory-region-size", "type": "int"}, {"name": "firmware", "type": "string"}, {"name": "usb", "type": "bool"}, {"name": "mem-merge", "type": "bool"}, {"name": "dump-guest-core", "type": "bool"}, {"name": "dt-compatible", "type": "string"}, {"name": "phandle-start", "type": "int"}, {"name": "dumpdtb", "type": "string"}, {"name": "dtb", "type": "string"}, {"name": "append", "type": "string"}, {"name": "initrd", "type": "string"}, {"name": "kernel", "type": "string"}, {"name": "kvm-shadow-mem", "type": "int"}, {"name": "kernel-irqchip", "type": "bool"}, {"name": "accel", "type": "string"}, {"name": "type", "type": "string"}], "id": "libvirt-10"}
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 2540 bytes out of 2540 available in buffer
2015-03-24 20:42:18.599+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc5820
2015-03-24 20:42:18.599+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/acpi-pci-hotplug[0]
2015-03-24 20:42:18.599+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/acpi-pci-hotplug[0]"},"id":"libvirt-11"}' for write with FD -1
2015-03-24 20:42:18.599+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/acpi-pci-hotplug[0]"},"id":"libvirt-11"}
fd=-1
2015-03-24 20:42:18.599+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/acpi-pci-hotplug[0]"},"id":"libvirt-11"}
len=95 ret=95 errno=11
2015-03-24 20:42:18.600+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-11"}
len=282
2015-03-24 20:42:18.600+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-11"}]
2015-03-24 20:42:18.600+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-11"}
2015-03-24 20:42:18.601+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.601+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.601+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ide[3]
2015-03-24 20:42:18.601+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ide[3]"},"id":"libvirt-12"}' for write with FD -1
2015-03-24 20:42:18.601+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ide[3]"},"id":"libvirt-12"}
fd=-1
2015-03-24 20:42:18.601+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ide[3]"},"id":"libvirt-12"}
len=82 ret=82 errno=11
2015-03-24 20:42:18.602+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-12"}
len=282
2015-03-24 20:42:18.602+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-12"}]
2015-03-24 20:42:18.602+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-12"}
2015-03-24 20:42:18.602+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.602+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.602+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ide[2]
2015-03-24 20:42:18.602+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ide[2]"},"id":"libvirt-13"}' for write with FD -1
2015-03-24 20:42:18.602+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ide[2]"},"id":"libvirt-13"}
fd=-1
2015-03-24 20:42:18.602+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ide[2]"},"id":"libvirt-13"}
len=82 ret=82 errno=11
2015-03-24 20:42:18.603+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-13"}
len=282
2015-03-24 20:42:18.603+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-13"}]
2015-03-24 20:42:18.603+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-13"}
2015-03-24 20:42:18.603+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.604+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.604+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ide[1]
2015-03-24 20:42:18.604+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ide[1]"},"id":"libvirt-14"}' for write with FD -1
2015-03-24 20:42:18.604+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ide[1]"},"id":"libvirt-14"}
fd=-1
2015-03-24 20:42:18.604+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ide[1]"},"id":"libvirt-14"}
len=82 ret=82 errno=11
2015-03-24 20:42:18.605+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-14"}
len=282
2015-03-24 20:42:18.605+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-14"}]
2015-03-24 20:42:18.605+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-14"}
2015-03-24 20:42:18.605+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.605+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.605+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ide[0]
2015-03-24 20:42:18.605+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ide[0]"},"id":"libvirt-15"}' for write with FD -1
2015-03-24 20:42:18.605+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ide[0]"},"id":"libvirt-15"}
fd=-1
2015-03-24 20:42:18.605+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ide[0]"},"id":"libvirt-15"}
len=82 ret=82 errno=11
2015-03-24 20:42:18.606+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-15"}
len=282
2015-03-24 20:42:18.606+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-15"}]
2015-03-24 20:42:18.606+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-15"}
2015-03-24 20:42:18.606+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.607+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.607+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-cont[1]
2015-03-24 20:42:18.607+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-cont[1]"},"id":"libvirt-16"}' for write with FD -1
2015-03-24 20:42:18.607+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-cont[1]"},"id":"libvirt-16"}
fd=-1
2015-03-24 20:42:18.607+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-cont[1]"},"id":"libvirt-16"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.608+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-16"}
len=282
2015-03-24 20:42:18.608+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-16"}]
2015-03-24 20:42:18.608+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-16"}
2015-03-24 20:42:18.608+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.608+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.608+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-page[3]
2015-03-24 20:42:18.608+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-page[3]"},"id":"libvirt-17"}' for write with FD -1
2015-03-24 20:42:18.608+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-page[3]"},"id":"libvirt-17"}
fd=-1
2015-03-24 20:42:18.608+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-page[3]"},"id":"libvirt-17"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.609+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-17"}
len=282
2015-03-24 20:42:18.609+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-17"}]
2015-03-24 20:42:18.609+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-17"}
2015-03-24 20:42:18.609+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.609+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.610+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-page[2]
2015-03-24 20:42:18.610+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-page[2]"},"id":"libvirt-18"}' for write with FD -1
2015-03-24 20:42:18.610+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-page[2]"},"id":"libvirt-18"}
fd=-1
2015-03-24 20:42:18.610+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-page[2]"},"id":"libvirt-18"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.611+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-18"}
len=282
2015-03-24 20:42:18.611+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-18"}]
2015-03-24 20:42:18.611+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-18"}
2015-03-24 20:42:18.611+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.611+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.611+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-chan[1]
2015-03-24 20:42:18.611+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-chan[1]"},"id":"libvirt-19"}' for write with FD -1
2015-03-24 20:42:18.611+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-chan[1]"},"id":"libvirt-19"}
fd=-1
2015-03-24 20:42:18.611+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-chan[1]"},"id":"libvirt-19"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.612+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-19"}
len=282
2015-03-24 20:42:18.612+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-19"}]
2015-03-24 20:42:18.612+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-19"}
2015-03-24 20:42:18.612+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.613+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.613+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-cont[0]
2015-03-24 20:42:18.613+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-cont[0]"},"id":"libvirt-20"}' for write with FD -1
2015-03-24 20:42:18.613+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-cont[0]"},"id":"libvirt-20"}
fd=-1
2015-03-24 20:42:18.613+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-cont[0]"},"id":"libvirt-20"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.614+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-20"}
len=282
2015-03-24 20:42:18.614+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-20"}]
2015-03-24 20:42:18.614+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-20"}
2015-03-24 20:42:18.614+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.614+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.614+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-page[1]
2015-03-24 20:42:18.614+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-page[1]"},"id":"libvirt-21"}' for write with FD -1
2015-03-24 20:42:18.614+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-page[1]"},"id":"libvirt-21"}
fd=-1
2015-03-24 20:42:18.614+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-page[1]"},"id":"libvirt-21"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.615+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-21"}
len=282
2015-03-24 20:42:18.615+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-21"}]
2015-03-24 20:42:18.615+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-21"}
2015-03-24 20:42:18.615+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.616+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.616+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-page[0]
2015-03-24 20:42:18.616+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-page[0]"},"id":"libvirt-22"}' for write with FD -1
2015-03-24 20:42:18.616+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-page[0]"},"id":"libvirt-22"}
fd=-1
2015-03-24 20:42:18.616+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-page[0]"},"id":"libvirt-22"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.617+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-22"}
len=282
2015-03-24 20:42:18.617+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-22"}]
2015-03-24 20:42:18.617+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-22"}
2015-03-24 20:42:18.617+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.617+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.617+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/dma-chan[0]
2015-03-24 20:42:18.617+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/dma-chan[0]"},"id":"libvirt-23"}' for write with FD -1
2015-03-24 20:42:18.617+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/dma-chan[0]"},"id":"libvirt-23"}
fd=-1
2015-03-24 20:42:18.617+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/dma-chan[0]"},"id":"libvirt-23"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.619+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-23"}
len=282
2015-03-24 20:42:18.619+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-23"}]
2015-03-24 20:42:18.619+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-23"}
2015-03-24 20:42:18.619+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.619+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.619+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ioportF0[0]
2015-03-24 20:42:18.619+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ioportF0[0]"},"id":"libvirt-24"}' for write with FD -1
2015-03-24 20:42:18.620+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ioportF0[0]"},"id":"libvirt-24"}
fd=-1
2015-03-24 20:42:18.620+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ioportF0[0]"},"id":"libvirt-24"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.621+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-24"}
len=282
2015-03-24 20:42:18.621+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-24"}]
2015-03-24 20:42:18.621+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-24"}
2015-03-24 20:42:18.621+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.621+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.621+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/ioport80[0]
2015-03-24 20:42:18.621+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/ioport80[0]"},"id":"libvirt-25"}' for write with FD -1
2015-03-24 20:42:18.621+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/ioport80[0]"},"id":"libvirt-25"}
fd=-1
2015-03-24 20:42:18.621+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/ioport80[0]"},"id":"libvirt-25"}
len=87 ret=87 errno=11
2015-03-24 20:42:18.622+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-25"}
len=282
2015-03-24 20:42:18.622+0000: 11442: debug : qemuMonitorJSONIOProcessLine:179 : Line [{"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-25"}]
2015-03-24 20:42:18.622+0000: 11442: debug : qemuMonitorJSONIOProcessLine:199 : QEMU_MONITOR_RECV_REPLY: mon=0x7ff98800f8d0 reply={"return": [{"name": "size", "type": "uint64"}, {"name": "may-overlap", "type": "bool"}, {"name": "priority", "type": "uint32"}, {"name": "addr", "type": "uint64"}, {"name": "container", "type": "link"}, {"name": "type", "type": "string"}], "id": "libvirt-25"}
2015-03-24 20:42:18.622+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 282 bytes out of 282 available in buffer
2015-03-24 20:42:18.622+0000: 11445: debug : qemuMonitorJSONCommandWithFd:291 : Receive command reply ret=0 rxObject=0x7ff9c4dc1620
2015-03-24 20:42:18.622+0000: 11445: debug : qemuMonitorFindBalloonObjectPath:1058 : Searching for Balloon Object Path starting at //machine/i440fx
2015-03-24 20:42:18.622+0000: 11445: debug : qemuMonitorJSONCommandWithFd:286 : Send command '{"execute":"qom-list","arguments":{"path":"//machine/i440fx"},"id":"libvirt-26"}' for write with FD -1
2015-03-24 20:42:18.623+0000: 11445: debug : qemuMonitorSend:972 : QEMU_MONITOR_SEND_MSG: mon=0x7ff98800f8d0 msg={"execute":"qom-list","arguments":{"path":"//machine/i440fx"},"id":"libvirt-26"}
fd=-1
2015-03-24 20:42:18.623+0000: 11442: debug : qemuMonitorIOWrite:503 : QEMU_MONITOR_IO_WRITE: mon=0x7ff98800f8d0 buf={"execute":"qom-list","arguments":{"path":"//machine/i440fx"},"id":"libvirt-26"}
len=82 ret=82 errno=11
2015-03-24 20:42:18.624+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "ioapic", "type": "child"}, {"name": "pam-pci[25]", "type": "child"}, {"name": "pam-pci[24]", "type": "child"}, {"name": "pam-rom[12]", "type": "child"}, {"name": "pam-ram[12]", "type": "child"}, {"name": "pam-pci[23]", "type": "child"}, {"name": "pam-pci[22]", "type": "child"}, {"name": "pam-rom[11]", "type": "child"}, {"name": "pam-ram[11]", "type": "child"}, {"name": "pam-pci[21]", "type": "child"}, {"name": "pam-pci[20]", "type": "child"}, {"name": "pam-rom[10]", "type": "child"}, {"name": "pam-ram[10]", "type": "child"}, {"name": "pam-pci[19]", "type": "child"}, {"name": "pam-pci[18]", "type": "child"}, {"name": "pam-rom[9]", "type": "child"}, {"name": "pam-ram[9]", "type": "child len=1023
2015-03-24 20:42:18.624+0000: 11442: debug : qemuMonitorJSONIOProcess:248 : Total used 0 bytes out of 1023 available in buffer
2015-03-24 20:42:18.624+0000: 11442: debug : qemuMonitorIOProcess:399 : QEMU_MONITOR_IO_PROCESS: mon=0x7ff98800f8d0 buf={"return": [{"name": "ioapic", "type": "child"}, {"name": "pam-pci[25]", "type": "child"}, {"name": "pam-pci[24]", "type": "child"}, {"name": "pam-rom[12]", "type": "child"}, {"name": "pam-ram[12]", "type": "child"}, {"name": "pam-pci[23]", "type": "child"}, {"name": "pam-pci[22]", "type": "child"}, {"name": "pam-rom[11]", "type": "child"}, {"name": "pam-ram[11]", "type": "child"}, {"name": "pam-pci[21]", "type": "child"}, {"name": "pam-pci[20]", "type": "child"}, {"name": "pam-rom[10]", "type": "child"}, {"name": "pam-ram[10]", "type": "child"}, {"name": "pam-pci[19]", "type": "child"}, {"name": "pam-pci[18]", "type": "child"}, {"name": "pam-rom[9]", "type": "child"}, {"name": "pam-ram[9]", "type": "child"}, {"name": "pam-pci[17]", "type": "child"}, {"name": "pam-pci[16]", "type": "child"}, {"name": "pam-rom[8]", "type": "child"}, {"name": "pam-ram[8]", "type": "child"}, {"name": "pam-pci[15]", "type": "child"}, {"name": "pam-pci[14]", "type": "child