2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=0 value=root 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=1 value=0 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=2 value=root 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=3 value=0 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=4 value=26899 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=5 value=6425869 2017-04-07 07:09:57.682+0000: 1065: debug : virIdentitySetAttr:248 : ident=0x7f652c00fad0 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 2017-04-07 07:09:57.682+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchAuthList 2017-04-07 07:09:57.682+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchAuthList with ret=0 2017-04-07 07:09:57.682+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchConnectOpen 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpen:1169 : name= 2017-04-07 07:09:57.682+0000: 1067: debug : virConfLoadConfig:1604 : Loading config file '/etc/libvirt/libvirt.conf' 2017-04-07 07:09:57.682+0000: 1067: debug : virConfReadFile:778 : filename=/etc/libvirt/libvirt.conf 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfAddEntry:241 : Add entry (null) (nil) 2017-04-07 07:09:57.682+0000: 1067: debug : virConfGetValueString:932 : Get value string (nil) 0 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1040 : no name, allowing driver auto-select 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 0 (Test) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 0 Test returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 1 (OPENVZ) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 1 OPENVZ returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 2 (VMWARE) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 2 VMWARE returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 3 (PHYP) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 3 PHYP returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 4 (ESX) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 4 ESX returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 5 (Hyper-V) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 5 Hyper-V returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 6 (remote) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 6 remote returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 7 (Xen) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 7 Xen returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 8 (xenlight) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 8 xenlight returned DECLINED 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1083 : trying driver 9 (QEMU) ... 2017-04-07 07:09:57.682+0000: 1067: debug : virConnectOpenInternal:1098 : driver 9 QEMU returned SUCCESS 2017-04-07 07:09:57.682+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchConnectOpen with ret=0 2017-04-07 07:09:57.682+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchConnectGetURI 2017-04-07 07:09:57.682+0000: 1069: debug : virConnectGetURI:255 : conn=0x7f653400fed0 2017-04-07 07:09:57.682+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchConnectGetURI with ret=0 2017-04-07 07:09:57.683+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature 2017-04-07 07:09:57.683+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0 2017-04-07 07:09:57.683+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature 2017-04-07 07:09:57.683+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0 2017-04-07 07:09:57.683+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchConnectGetCapabilities 2017-04-07 07:09:57.683+0000: 1065: debug : virConnectGetCapabilities:395 : conn=0x7f653400fed0 2017-04-07 07:09:57.683+0000: 1065: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64) 2017-04-07 07:09:57.683+0000: 1065: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64) 2017-04-07 07:09:57.683+0000: 1065: debug : virCPUGetHost:403 : arch=x86_64, type=host, nodeInfo=0x7f65582b2900, models=(nil), nmodels=0 2017-04-07 07:09:57.683+0000: 1065: debug : x86DataFilterTSX:1809 : Dropping broken TSX 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model Opteron_G5 differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model Opteron_G4 differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model Opteron_G3 differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model Opteron_G2 differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model Opteron_G1 differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model phenom differs from Intel; ignoring 2017-04-07 07:09:57.683+0000: 1065: debug : x86Decode:1882 : CPU vendor AMD of model athlon differs from Intel; ignoring 2017-04-07 07:09:57.685+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65380f70c0 for /usr/bin/qemu-system-i386 2017-04-07 07:09:57.686+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.686+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f653829f520 for /usr/bin/qemu-system-x86_64 2017-04-07 07:09:57.687+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.687+0000: 1065: debug : virQEMUDriverCreateCapabilities:964 : Initialized caps for security driver "selinux" with DOI "0" 2017-04-07 07:09:57.687+0000: 1065: debug : virQEMUDriverCreateCapabilities:964 : Initialized caps for security driver "dac" with DOI "0" 2017-04-07 07:09:57.687+0000: 1065: debug : virConnectGetCapabilities:406 : conn=0x7f653400fed0 ret= f8ba0b36-0121-4b86-9fc5-78ec297bd90a x86_64 Haswell-noTSX Intel tcp rdma 8174488 2043622 0 0 selinux 0 system_u:system_r:svirt_t:s0 system_u:system_r:svirt_tcg_t:s0 dac 0 +107:+107 +107:+107 hvm 32 /usr/bin/qemu-system-i386 pc-i440fx-2.9 pc pc-0.12 pc-i440fx-2.4 pc-1.3 pc-q35-2.7 pc-q35-2.6 xenpv pc-i440fx-1.7 pc-i440fx-2.8 pc-i440fx-1.6 pc-i440fx-2.7 pc-0.11 pc-i440fx-2.3 pc-0.10 pc-1.2 pc-i440fx-2.2 isapc pc-q35-2.5 xenfv pc-0.15 pc-0.14 pc-i440fx-1.5 pc-i440fx-2.6 pc-i440fx-1.4 pc-i440fx-2.5 pc-q35-2.9 q35 pc-1.1 pc-i440fx-2.1 pc-q35-2.8 pc-1.0 pc-i440fx-2.0 pc-q35-2.4 pc-0.13 /usr/bin/qemu-kvm pc-i440fx-2.9 pc pc-0.12 pc-i440fx-2.4 pc-1.3 pc-q35-2.7 pc-q35-2.6 xenpv pc-i440fx-1.7 pc-i440fx-2.8 pc-i440fx-1.6 pc-i440fx-2.7 pc-0.11 pc-i440fx-2.3 pc-0.10 pc-1.2 pc-i440fx-2.2 isapc pc-q35-2.5 xenfv pc-0.15 pc-0.14 pc-i440fx-1.5 pc-i440fx-2.6 pc-i440fx-1.4 pc-i440fx-2.5 pc-q35-2.9 q35 pc-1.1 pc-i440fx-2.1 pc-q35-2.8 pc-1.0 pc-i440fx-2.0 pc-q35-2.4 pc-0.13 hvm 64 /usr/bin/qemu-system-x86_64 pc-i440fx-2.9 pc pc-0.12 pc-i440fx-2.4 pc-1.3 pc-q35-2.7 pc-q35-2.6 xenpv pc-i440fx-1.7 pc-i440fx-2.8 pc-i440fx-1.6 pc-i440fx-2.7 pc-0.11 pc-i440fx-2.3 pc-0.10 pc-1.2 pc-i440fx-2.2 isapc pc-q35-2.5 xenfv pc-0.15 pc-0.14 pc-i440fx-1.5 pc-i440fx-2.6 pc-i440fx-1.4 pc-i440fx-2.5 pc-q35-2.9 q35 pc-1.1 pc-i440fx-2.1 pc-q35-2.8 pc-1.0 pc-i440fx-2.0 pc-q35-2.4 pc-0.13 /usr/bin/qemu-kvm pc-i440fx-2.9 pc pc-0.12 pc-i440fx-2.4 pc-1.3 pc-q35-2.7 pc-q35-2.6 xenpv pc-i440fx-1.7 pc-i440fx-2.8 pc-i440fx-1.6 pc-i440fx-2.7 pc-0.11 pc-i440fx-2.3 pc-0.10 pc-1.2 pc-i440fx-2.2 isapc pc-q35-2.5 xenfv pc-0.15 pc-0.14 pc-i440fx-1.5 pc-i440fx-2.6 pc-i440fx-1.4 pc-i440fx-2.5 pc-q35-2.9 q35 pc-1.1 pc-i440fx-2.1 pc-q35-2.8 pc-1.0 pc-i440fx-2.0 pc-q35-2.4 pc-0.13 2017-04-07 07:09:57.687+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchConnectGetCapabilities with ret=0 2017-04-07 07:09:57.697+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchDomainLookupByName 2017-04-07 07:09:57.697+0000: 1067: debug : virDomainLookupByName:416 : conn=0x7f653400fed0, name=f25-l2 2017-04-07 07:09:57.697+0000: 1067: debug : qemuDomainLookupByName:1585 : Domain not found: no domain with matching name 'f25-l2' 2017-04-07 07:09:57.697+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchDomainLookupByName with ret=-1 2017-04-07 07:09:57.702+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchConnectNumOfStoragePools 2017-04-07 07:09:57.702+0000: 1064: debug : virConnectNumOfStoragePools:150 : conn=0x7f653400fed0 2017-04-07 07:09:57.702+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchConnectNumOfStoragePools with ret=0 2017-04-07 07:09:57.702+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchConnectListStoragePools 2017-04-07 07:09:57.702+0000: 1066: debug : virConnectListStoragePools:195 : conn=0x7f653400fed0, names=0x7f6528013e60, maxnames=3 2017-04-07 07:09:57.702+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchConnectListStoragePools with ret=0 2017-04-07 07:09:57.702+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolLookupByPath 2017-04-07 07:09:57.702+0000: 1063: debug : virStorageVolLookupByPath:1373 : conn=0x7f653400fed0, path=/var/lib/libvirt/images/f25-l2.qcow2 2017-04-07 07:09:57.702+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolLookupByPath with ret=0 2017-04-07 07:09:57.702+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetInfo 2017-04-07 07:09:57.702+0000: 1071: debug : virStorageVolGetInfo:1888 : vol=0x7f6540000b20, info=0x7f65552acae0 2017-04-07 07:09:57.702+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetInfo with ret=0 2017-04-07 07:09:57.702+0000: 1068: debug : virThreadJobSet:96 : Thread 1068 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolLookupByVolume 2017-04-07 07:09:57.702+0000: 1068: debug : virStoragePoolLookupByVolume:482 : vol=0x7f65382a1470 2017-04-07 07:09:57.702+0000: 1068: debug : virThreadJobClear:121 : Thread 1068 (virNetServerHandleJob) finished job remoteDispatchStoragePoolLookupByVolume with ret=0 2017-04-07 07:09:57.702+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.702+0000: 1070: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65480014b0, flags=0 2017-04-07 07:09:57.702+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.703+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetXMLDesc 2017-04-07 07:09:57.703+0000: 1069: debug : virStoragePoolGetXMLDesc:1026 : pool=0x7f6550000970, flags=0 2017-04-07 07:09:57.703+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetXMLDesc with ret=0 2017-04-07 07:09:57.703+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchConnectGetVersion 2017-04-07 07:09:57.703+0000: 1072: debug : virConnectGetVersion:139 : conn=0x7f653400fed0, hvVer=0x7f6554aabaf0 2017-04-07 07:09:57.703+0000: 1072: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64) 2017-04-07 07:09:57.704+0000: 1072: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f653829f520 for /usr/bin/qemu-system-x86_64 2017-04-07 07:09:57.704+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchConnectGetVersion with ret=0 2017-04-07 07:09:57.704+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchConnectGetVersion 2017-04-07 07:09:57.704+0000: 1065: debug : virConnectGetVersion:139 : conn=0x7f653400fed0, hvVer=0x7f65582b2af0 2017-04-07 07:09:57.704+0000: 1065: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64) 2017-04-07 07:09:57.705+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f653829f520 for /usr/bin/qemu-system-x86_64 2017-04-07 07:09:57.705+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchConnectGetVersion with ret=0 2017-04-07 07:09:57.708+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchConnectListAllDomains 2017-04-07 07:09:57.708+0000: 1067: debug : virConnectListAllDomains:6480 : conn=0x7f653400fed0, domains=0x7f65572b0af0, flags=0 2017-04-07 07:09:57.708+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchConnectListAllDomains with ret=0 2017-04-07 07:09:57.708+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchConnectListAllDomains 2017-04-07 07:09:57.708+0000: 1064: debug : virConnectListAllDomains:6480 : conn=0x7f653400fed0, domains=0x7f6558ab3af0, flags=0 2017-04-07 07:09:57.708+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchConnectListAllDomains with ret=0 2017-04-07 07:09:57.708+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchDomainGetXMLDesc 2017-04-07 07:09:57.708+0000: 1066: debug : virDomainGetXMLDesc:2576 : dom=0x7f6528028270, (VM: name=l2-f25, uuid=a6259ba6-1221-42f6-9737-0a90d1299c3f), flags=0 2017-04-07 07:09:57.708+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchDomainGetXMLDesc with ret=0 2017-04-07 07:09:57.712+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchConnectListAllStoragePools 2017-04-07 07:09:57.712+0000: 1063: debug : virConnectListAllStoragePools:113 : conn=0x7f653400fed0, pools=0x7f65592b4af0, flags=0 2017-04-07 07:09:57.712+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchConnectListAllStoragePools with ret=0 2017-04-07 07:09:57.712+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchConnectListAllStoragePools 2017-04-07 07:09:57.712+0000: 1072: debug : virConnectListAllStoragePools:113 : conn=0x7f653400fed0, pools=0x7f6554aabaf0, flags=0 2017-04-07 07:09:57.712+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchConnectListAllStoragePools with ret=0 2017-04-07 07:09:57.712+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetXMLDesc 2017-04-07 07:09:57.712+0000: 1071: debug : virStoragePoolGetXMLDesc:1026 : pool=0x7f6540000b20, flags=0 2017-04-07 07:09:57.712+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetXMLDesc with ret=0 2017-04-07 07:09:57.713+0000: 1068: debug : virThreadJobSet:96 : Thread 1068 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetXMLDesc 2017-04-07 07:09:57.713+0000: 1068: debug : virStoragePoolGetXMLDesc:1026 : pool=0x7f65382a1820, flags=0 2017-04-07 07:09:57.713+0000: 1068: debug : virThreadJobClear:121 : Thread 1068 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetXMLDesc with ret=0 2017-04-07 07:09:57.713+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetXMLDesc 2017-04-07 07:09:57.713+0000: 1070: debug : virStoragePoolGetXMLDesc:1026 : pool=0x7f65480014b0, flags=0 2017-04-07 07:09:57.713+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetXMLDesc with ret=0 2017-04-07 07:09:57.713+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolLookupByName 2017-04-07 07:09:57.713+0000: 1069: debug : virStoragePoolLookupByName:367 : conn=0x7f653400fed0, name=images 2017-04-07 07:09:57.713+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchStoragePoolLookupByName with ret=0 2017-04-07 07:09:57.713+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetInfo 2017-04-07 07:09:57.713+0000: 1072: debug : virStoragePoolGetInfo:982 : pool=0x7f6530001790, info=0x7f6554aabac0 2017-04-07 07:09:57.713+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetInfo with ret=0 2017-04-07 07:09:57.713+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolListAllVolumes 2017-04-07 07:09:57.713+0000: 1071: debug : virStoragePoolListAllVolumes:1151 : pool=0x7f6540000920, vols=0x7f65552acad8, flags=0 2017-04-07 07:09:57.713+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStoragePoolListAllVolumes with ret=0 2017-04-07 07:09:57.713+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolListAllVolumes 2017-04-07 07:09:57.713+0000: 1064: debug : virStoragePoolListAllVolumes:1151 : pool=0x55bba303c2c0, vols=0x7f6558ab3ad8, flags=0 2017-04-07 07:09:57.713+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStoragePoolListAllVolumes with ret=0 2017-04-07 07:09:57.713+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.713+0000: 1070: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6548000b20, flags=0 2017-04-07 07:09:57.714+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.714+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.714+0000: 1069: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6550000970, flags=0 2017-04-07 07:09:57.714+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.714+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.714+0000: 1065: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.714+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.714+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.714+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000990, flags=0 2017-04-07 07:09:57.714+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.714+0000: 1068: debug : virThreadJobSet:96 : Thread 1068 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.714+0000: 1068: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65382a1820, flags=0 2017-04-07 07:09:57.714+0000: 1068: debug : virThreadJobClear:121 : Thread 1068 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.715+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolLookupByName 2017-04-07 07:09:57.715+0000: 1070: debug : virStoragePoolLookupByName:367 : conn=0x7f653400fed0, name=root 2017-04-07 07:09:57.715+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStoragePoolLookupByName with ret=0 2017-04-07 07:09:57.715+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetInfo 2017-04-07 07:09:57.715+0000: 1063: debug : virStoragePoolGetInfo:982 : pool=0x7f652c01d1b0, info=0x7f65592b4ac0 2017-04-07 07:09:57.715+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetInfo with ret=0 2017-04-07 07:09:57.715+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolListAllVolumes 2017-04-07 07:09:57.715+0000: 1072: debug : virStoragePoolListAllVolumes:1151 : pool=0x7f6530001790, vols=0x7f6554aabad8, flags=0 2017-04-07 07:09:57.715+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchStoragePoolListAllVolumes with ret=0 2017-04-07 07:09:57.715+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.715+0000: 1071: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6540001260, flags=0 2017-04-07 07:09:57.715+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.715+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.715+0000: 1064: debug : virStorageVolGetXMLDesc:1982 : vol=0x55bba303c2c0, flags=0 2017-04-07 07:09:57.715+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.715+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.715+0000: 1066: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65280163d0, flags=0 2017-04-07 07:09:57.715+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.715+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.715+0000: 1063: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.716+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.716+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.716+0000: 1072: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6530000b60, flags=0 2017-04-07 07:09:57.716+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.716+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.716+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000a30, flags=0 2017-04-07 07:09:57.716+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.716+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.716+0000: 1064: debug : virStorageVolGetXMLDesc:1982 : vol=0x55bba303c2c0, flags=0 2017-04-07 07:09:57.716+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.716+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.716+0000: 1070: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65480014b0, flags=0 2017-04-07 07:09:57.716+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.716+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.716+0000: 1069: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6550000970, flags=0 2017-04-07 07:09:57.716+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.717+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.717+0000: 1065: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.717+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.717+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.717+0000: 1071: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6540000a70, flags=0 2017-04-07 07:09:57.717+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.717+0000: 1068: debug : virThreadJobSet:96 : Thread 1068 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.717+0000: 1068: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65382a1820, flags=0 2017-04-07 07:09:57.717+0000: 1068: debug : virThreadJobClear:121 : Thread 1068 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.717+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.717+0000: 1066: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65280163d0, flags=0 2017-04-07 07:09:57.717+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.717+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.717+0000: 1063: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.717+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.718+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.718+0000: 1072: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6530001760, flags=0 2017-04-07 07:09:57.718+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.718+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.718+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000a30, flags=0 2017-04-07 07:09:57.718+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.718+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolLookupByName 2017-04-07 07:09:57.718+0000: 1064: debug : virStoragePoolLookupByName:367 : conn=0x7f653400fed0, name=stack 2017-04-07 07:09:57.718+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStoragePoolLookupByName with ret=0 2017-04-07 07:09:57.718+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolGetInfo 2017-04-07 07:09:57.718+0000: 1070: debug : virStoragePoolGetInfo:982 : pool=0x7f65480014b0, info=0x7f6555aadac0 2017-04-07 07:09:57.718+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStoragePoolGetInfo with ret=0 2017-04-07 07:09:57.718+0000: 1069: debug : virThreadJobSet:96 : Thread 1069 (virNetServerHandleJob) is now running job remoteDispatchStoragePoolListAllVolumes 2017-04-07 07:09:57.718+0000: 1069: debug : virStoragePoolListAllVolumes:1151 : pool=0x7f6550000970, vols=0x7f65562aead8, flags=0 2017-04-07 07:09:57.718+0000: 1069: debug : virThreadJobClear:121 : Thread 1069 (virNetServerHandleJob) finished job remoteDispatchStoragePoolListAllVolumes with ret=0 2017-04-07 07:09:57.718+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.718+0000: 1065: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.718+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.719+0000: 1071: debug : virThreadJobSet:96 : Thread 1071 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.719+0000: 1071: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6540000a70, flags=0 2017-04-07 07:09:57.719+0000: 1071: debug : virThreadJobClear:121 : Thread 1071 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.719+0000: 1068: debug : virThreadJobSet:96 : Thread 1068 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.719+0000: 1068: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65382a1820, flags=0 2017-04-07 07:09:57.719+0000: 1068: debug : virThreadJobClear:121 : Thread 1068 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.719+0000: 1070: debug : virThreadJobSet:96 : Thread 1070 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.719+0000: 1070: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65480014b0, flags=0 2017-04-07 07:09:57.719+0000: 1070: debug : virThreadJobClear:121 : Thread 1070 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.719+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.719+0000: 1063: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.719+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.719+0000: 1072: debug : virThreadJobSet:96 : Thread 1072 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.719+0000: 1072: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6530001760, flags=0 2017-04-07 07:09:57.719+0000: 1072: debug : virThreadJobClear:121 : Thread 1072 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.720+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.720+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000a30, flags=0 2017-04-07 07:09:57.720+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.720+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.720+0000: 1064: debug : virStorageVolGetXMLDesc:1982 : vol=0x55bba301e260, flags=0 2017-04-07 07:09:57.720+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.720+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.720+0000: 1066: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65280163d0, flags=0 2017-04-07 07:09:57.720+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.720+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.720+0000: 1063: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.720+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.720+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.720+0000: 1065: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d350, flags=0 2017-04-07 07:09:57.720+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.721+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.721+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000a30, flags=0 2017-04-07 07:09:57.721+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.721+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.721+0000: 1064: debug : virStorageVolGetXMLDesc:1982 : vol=0x55bba303c2c0, flags=0 2017-04-07 07:09:57.721+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.721+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.721+0000: 1066: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f65280163d0, flags=0 2017-04-07 07:09:57.721+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.721+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.721+0000: 1063: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.721+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.721+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.721+0000: 1065: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f652c01d1b0, flags=0 2017-04-07 07:09:57.721+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.722+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchStorageVolGetXMLDesc 2017-04-07 07:09:57.722+0000: 1067: debug : virStorageVolGetXMLDesc:1982 : vol=0x7f6534000a30, flags=0 2017-04-07 07:09:57.722+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchStorageVolGetXMLDesc with ret=0 2017-04-07 07:09:57.942+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchDomainLookupByUUID 2017-04-07 07:09:57.942+0000: 1064: debug : virDomainLookupByUUID:340 : conn=0x7f653400fed0, uuid=d397f20a-1c29-48b1-84c4-e2c354dc4eae 2017-04-07 07:09:57.942+0000: 1064: debug : qemuDomainLookupByUUID:1559 : Domain not found: no domain with matching uuid 'd397f20a-1c29-48b1-84c4-e2c354dc4eae' 2017-04-07 07:09:57.942+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchDomainLookupByUUID with ret=-1 2017-04-07 07:09:57.954+0000: 1066: debug : virThreadJobSet:96 : Thread 1066 (virNetServerHandleJob) is now running job remoteDispatchDomainLookupByName 2017-04-07 07:09:57.954+0000: 1066: debug : virDomainLookupByName:416 : conn=0x7f653400fed0, name=f25-l2 2017-04-07 07:09:57.954+0000: 1066: debug : qemuDomainLookupByName:1585 : Domain not found: no domain with matching name 'f25-l2' 2017-04-07 07:09:57.954+0000: 1066: debug : virThreadJobClear:121 : Thread 1066 (virNetServerHandleJob) finished job remoteDispatchDomainLookupByName with ret=-1 2017-04-07 07:09:57.954+0000: 1063: debug : virThreadJobSet:96 : Thread 1063 (virNetServerHandleJob) is now running job remoteDispatchDomainDefineXML 2017-04-07 07:09:57.954+0000: 1063: debug : virDomainDefineXML:6151 : conn=0x7f653400fed0, xml= f25-l2 d397f20a-1c29-48b1-84c4-e2c354dc4eae 1048576 1048576 1 hvm Haswell-noTSX /usr/bin/qemu-kvm /dev/urandom 2017-04-07 07:09:57.955+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.956+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.957+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.958+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.958+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.959+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.960+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.961+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.961+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.962+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.963+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.963+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.964+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.965+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.966+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.966+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainVirtioSerialAddrSetAddController:1185 : Adding virtio serial controller index 0 with 31 ports to the address set 2017-04-07 07:09:57.966+0000: 1063: debug : qemuDomainAssignVirtioSerialAddresses:118 : Finished reserving existing ports 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainVirtioSerialAddrNext:1345 : Found free virtio serial controller 0 port 1 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainVirtioSerialAddrReserve:1489 : Reserving virtio serial 0 1 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:01 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:02 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:02 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:03 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:03 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:04 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.7 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:04 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:05 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:05 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:06 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.1 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:06 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:07 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.2 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:07 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:08 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:08.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:08 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:09 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:09.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:09 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:0a 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:0a.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:0a already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:0b 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:0b.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:01 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:02 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:02 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:03 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.7 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.1 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.2 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:03 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:04 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:04 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:05 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:05 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:06 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:06 already in use 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:07 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.0 (aggregate='false') 2017-04-07 07:09:57.966+0000: 1063: debug : qemuDomainUSBAddressAddHubs:2332 : Found 1 USB devices and 6 provided USB ports; adding 0 hubs 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-ehci1 with 6 ports 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci1 with 0 ports 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci2 with 0 ports 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci3 with 0 ports 2017-04-07 07:09:57.966+0000: 1063: debug : qemuDomainAssignUSBAddresses:2471 : Existing USB addresses have been reserved 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressAssign:2022 : Looking for a free USB port on all the buses 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressFindFreePort:1914 : Found a free port 1 at level 0 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressAssignFromBus:1992 : Assigning USB addr bus=0 port=1 2017-04-07 07:09:57.966+0000: 1063: debug : virDomainUSBAddressReserve:2066 : Reserving USB address bus=0 port=1 2017-04-07 07:09:57.966+0000: 1063: debug : qemuDomainAssignUSBAddresses:2479 : Finished assigning USB ports 2017-04-07 07:09:57.967+0000: 1063: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:57.967+0000: 1063: debug : virDomainObjNew:2888 : obj=0x7f652c019bf0 2017-04-07 07:09:58.359+0000: 1063: debug : virObjectEventNew:640 : obj=0x7f652c001190 2017-04-07 07:09:58.359+0000: 1063: info : qemuDomainDefineXMLFlags:7103 : Creating domain 'f25-l2' 2017-04-07 07:09:58.359+0000: 1063: debug : virObjectEventDispose:134 : obj=0x7f652c001190 2017-04-07 07:09:58.359+0000: 1063: debug : virThreadJobClear:121 : Thread 1063 (virNetServerHandleJob) finished job remoteDispatchDomainDefineXML with ret=0 2017-04-07 07:09:58.359+0000: 1065: debug : virThreadJobSet:96 : Thread 1065 (virNetServerHandleJob) is now running job remoteDispatchDomainCreate 2017-04-07 07:09:58.359+0000: 1065: debug : virDomainCreate:6521 : dom=0x7f652c000990, (VM: name=f25-l2, uuid=d397f20a-1c29-48b1-84c4-e2c354dc4eae) 2017-04-07 07:09:58.359+0000: 1065: debug : qemuDomainObjBeginJobInternal:3504 : Starting async job: start (vm=0x7f652c019bf0 name=f25-l2, current job=none async=none) 2017-04-07 07:09:58.359+0000: 1065: debug : qemuDomainObjBeginJobInternal:3553 : Started async job: start (vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.359+0000: 1065: debug : qemuProcessStart:5954 : conn=0x7f653400fed0 driver=0x7f6538170720 vm=0x7f652c019bf0 name=f25-l2 id=-1 asyncJob=start migrateFrom= migrateFd=-1 migratePath= snapshot=(nil) vmop=0 flags=0x1 2017-04-07 07:09:58.359+0000: 1065: debug : qemuProcessInit:4847 : vm=0x7f652c019bf0 name=f25-l2 id=-1 migration=0 2017-04-07 07:09:58.359+0000: 1065: debug : qemuProcessInit:4849 : Beginning VM startup process 2017-04-07 07:09:58.359+0000: 1065: debug : qemuProcessInit:4860 : Determining emulator version 2017-04-07 07:09:58.361+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.361+0000: 1065: debug : qemuProcessStartValidate:4781 : Checking for KVM availability 2017-04-07 07:09:58.361+0000: 1065: debug : qemuProcessStartValidate:4792 : Checking domain and device security labels 2017-04-07 07:09:58.363+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.363+0000: 1065: debug : qemuProcessStartValidate:4813 : Checking for any possible (non-fatal) issues 2017-04-07 07:09:58.363+0000: 1065: debug : qemuProcessInit:4875 : Setting current domain def as transient 2017-04-07 07:09:58.365+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.366+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.368+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.369+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.370+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.372+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.373+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.374+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.375+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.377+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.378+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.379+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.380+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.382+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.383+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.384+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.385+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.386+0000: 1065: debug : virQEMUCapsCacheLookup:5190 : Returning caps 0x7f65382a0c30 for /usr/bin/qemu-kvm 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainVirtioSerialAddrSetAddController:1185 : Adding virtio serial controller index 0 with 31 ports to the address set 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainVirtioSerialAddrReserve:1489 : Reserving virtio serial 0 1 2017-04-07 07:09:58.386+0000: 1065: debug : qemuDomainAssignVirtioSerialAddresses:118 : Finished reserving existing ports 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.7 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.1 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.2 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:01 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:02 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:03 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:04 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:05 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:06 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:07 already in use 2017-04-07 07:09:58.386+0000: 1065: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:08 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:08.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.7 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.1 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.2 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:58.387+0000: 1065: debug : qemuDomainUSBAddressAddHubs:2332 : Found 1 USB devices and 6 provided USB ports; adding 0 hubs 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-ehci1 with 6 ports 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci1 with 0 ports 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci2 with 0 ports 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci3 with 0 ports 2017-04-07 07:09:58.387+0000: 1065: debug : virDomainUSBAddressReserve:2066 : Reserving USB address bus=0 port=1 2017-04-07 07:09:58.387+0000: 1065: debug : qemuDomainAssignUSBAddresses:2471 : Existing USB addresses have been reserved 2017-04-07 07:09:58.387+0000: 1065: debug : qemuDomainAssignUSBAddresses:2479 : Finished assigning USB ports 2017-04-07 07:09:58.387+0000: 1065: debug : qemuProcessPrepareDomain:5356 : Generating domain security label (if required) 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenLabel:742 : label=QEMU 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenLabel:764 : type=2 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxMCSFind:281 : Using sensitivity level 's0' cat min 0 max 1023 range 1024 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxMCSFind:287 : Try cat s0:c998,c567 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:481 : basecontext=system_u:system_r:svirt_t:s0 mcs=s0:c567,c998 isObjectContext=0 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:494 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:533 : Generated context 'system_u:system_r:svirt_t:s0:c567,c998' 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:481 : basecontext=system_u:object_r:svirt_image_t:s0 mcs=s0:c567,c998 isObjectContext=1 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:494 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenNewContext:533 : Generated context 'system_u:object_r:svirt_image_t:s0:c567,c998' 2017-04-07 07:09:58.387+0000: 1065: debug : virSecuritySELinuxGenLabel:875 : model=selinux label=system_u:system_r:svirt_t:s0:c567,c998 imagelabel=system_u:object_r:svirt_image_t:s0:c567,c998 baselabel= 2017-04-07 07:09:58.388+0000: 1065: debug : qemuProcessPrepareDomain:5391 : Assigning domain PCI addresses 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainVirtioSerialAddrSetAddController:1185 : Adding virtio serial controller index 0 with 31 ports to the address set 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainVirtioSerialAddrReserve:1489 : Reserving virtio serial 0 1 2017-04-07 07:09:58.388+0000: 1065: debug : qemuDomainAssignVirtioSerialAddresses:118 : Finished reserving existing ports 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.7 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.1 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.2 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:01 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:02 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:03 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:04 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:05 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:06 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressFindUnusedFunctionOnBus:725 : PCI slot 0000:00:07 already in use 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressGetNextAddr:817 : Found free PCI slot 0000:00:08 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:08.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:05.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:02.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.7 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.1 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:03.2 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:04.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:06.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:07.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainPCIAddressReserveAddrInternal:571 : Reserving PCI address 0000:00:01.0 (aggregate='false') 2017-04-07 07:09:58.388+0000: 1065: debug : qemuDomainUSBAddressAddHubs:2332 : Found 1 USB devices and 6 provided USB ports; adding 0 hubs 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-ehci1 with 6 ports 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci1 with 0 ports 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci2 with 0 ports 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainUSBAddressSetAddController:1727 : Adding a USB controller model=ich9-uhci3 with 0 ports 2017-04-07 07:09:58.388+0000: 1065: debug : virDomainUSBAddressReserve:2066 : Reserving USB address bus=0 port=1 2017-04-07 07:09:58.388+0000: 1065: debug : qemuDomainAssignUSBAddresses:2471 : Existing USB addresses have been reserved 2017-04-07 07:09:58.388+0000: 1065: debug : qemuDomainAssignUSBAddresses:2479 : Finished assigning USB ports 2017-04-07 07:09:58.388+0000: 1065: debug : qemuProcessPrepareDomain:5400 : Setting graphics devices 2017-04-07 07:09:58.388+0000: 1065: debug : qemuDomainCheckDiskPresence:4975 : Checking for disk presence 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessPrepareDomain:5410 : Create domain masterKey 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessPrepareDomain:5414 : Prepare chardev source backends for TLS 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessPrepareDomain:5417 : Add secrets to disks, hostdevs, and chardevs 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessPrepareDomain:5430 : Preparing monitor state 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessPrepareDomain:5439 : Updating guest CPU definition 2017-04-07 07:09:58.389+0000: 1065: debug : virCPUConvertLegacy:1069 : arch=x86_64, cpu=0x7f652c016ad0, model=Haswell-noTSX 2017-04-07 07:09:58.389+0000: 1065: debug : virCPUCompare:173 : arch=x86_64, host=0x7f652c019e70, cpu=0x7f652c016ad0 2017-04-07 07:09:58.389+0000: 1065: debug : x86Compute:1587 : Host CPU does not provide required features: invpcid 2017-04-07 07:09:58.389+0000: 1065: error : virCPUx86Compare:1707 : the CPU is incompatible with host CPU: Host CPU does not provide required features: invpcid 2017-04-07 07:09:58.389+0000: 1065: debug : qemuProcessStop:6161 : Shutting down vm=0x7f652c019bf0 name=f25-l2 id=15 pid=0, reason=failed, asyncJob=start, flags=2 2017-04-07 07:09:58.389+0000: 1065: debug : qemuDomainObjBeginJobInternal:3504 : Starting job: async nested (vm=0x7f652c019bf0 name=f25-l2, current job=none async=start) 2017-04-07 07:09:58.389+0000: 1065: debug : qemuDomainObjBeginJobInternal:3545 : Started job: async nested (async=start vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.389+0000: 1065: debug : qemuDomainLogAppendMessage:4477 : Append log message (vm='f25-l2' message='2017-04-07 07:09:58.389+0000: shutting down, reason=failed ) stdioLogD=1 2017-04-07 07:09:58.390+0000: 1065: debug : qemuProcessKill:6077 : vm=0x7f652c019bf0 name=f25-l2 pid=0 flags=5 2017-04-07 07:09:58.390+0000: 1065: debug : virProcessKillPainfully:355 : vpid=0 force=1 2017-04-07 07:09:58.390+0000: 1065: debug : qemuDomainCleanupRun:5069 : driver=0x7f6538170720, vm=f25-l2 2017-04-07 07:09:58.390+0000: 1065: debug : qemuProcessAutoDestroyRemove:6762 : vm=f25-l2 2017-04-07 07:09:58.390+0000: 1065: debug : virCloseCallbacksUnset:162 : vm=f25-l2, uuid=d397f20a-1c29-48b1-84c4-e2c354dc4eae, cb=0x7f65469a3610 2017-04-07 07:09:58.409+0000: 1065: debug : qemuDomainObjEndJob:3699 : Stopping job: async nested (async=start vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.410+0000: 1065: debug : qemuDomainObjEndAsyncJob:3716 : Stopping async job: start (vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.410+0000: 1065: debug : virThreadJobClear:121 : Thread 1065 (virNetServerHandleJob) finished job remoteDispatchDomainCreate with ret=-1 2017-04-07 07:09:58.410+0000: 1067: debug : virThreadJobSet:96 : Thread 1067 (virNetServerHandleJob) is now running job remoteDispatchDomainUndefine 2017-04-07 07:09:58.410+0000: 1067: debug : virDomainUndefine:6243 : dom=0x7f653400c210, (VM: name=f25-l2, uuid=d397f20a-1c29-48b1-84c4-e2c354dc4eae) 2017-04-07 07:09:58.410+0000: 1067: debug : virObjectEventNew:640 : obj=0x7f653400ffc0 2017-04-07 07:09:58.410+0000: 1067: info : qemuDomainUndefineFlags:7218 : Undefining domain 'f25-l2' 2017-04-07 07:09:58.410+0000: 1067: debug : qemuDomainObjBeginJobInternal:3504 : Starting job: modify (vm=0x7f652c019bf0 name=f25-l2, current job=none async=none) 2017-04-07 07:09:58.410+0000: 1067: debug : qemuDomainObjBeginJobInternal:3545 : Started job: modify (async=none vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.410+0000: 1067: debug : qemuDomainObjEndJob:3699 : Stopping job: modify (async=none vm=0x7f652c019bf0 name=f25-l2) 2017-04-07 07:09:58.410+0000: 1067: debug : virDomainObjDispose:2847 : obj=0x7f652c019bf0 2017-04-07 07:09:58.410+0000: 1067: debug : virObjectEventDispose:134 : obj=0x7f653400ffc0 2017-04-07 07:09:58.410+0000: 1067: debug : virThreadJobClear:121 : Thread 1067 (virNetServerHandleJob) finished job remoteDispatchDomainUndefine with ret=0 2017-04-07 07:09:58.429+0000: 1064: debug : virThreadJobSet:96 : Thread 1064 (virNetServerHandleJob) is now running job remoteDispatchConnectClose 2017-04-07 07:09:58.429+0000: 1064: debug : virThreadJobClear:121 : Thread 1064 (virNetServerHandleJob) finished job remoteDispatchConnectClose with ret=0 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=4 value=976 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=5 value=604 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=0 value=root 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=1 value=0 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=2 value=root 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=3 value=0 2017-04-07 07:09:58.429+0000: 976: debug : virIdentitySetAttr:248 : ident=0x55bba3018990 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023 2017-04-07 07:09:58.429+0000: 976: debug : virConnectClose:1290 : conn=0x7f653400fed0 2017-04-07 07:09:58.429+0000: 976: debug : virCloseCallbacksRun:325 : conn=0x7f653400fed0