Attachment 1485038 Details for Bug 1631191 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 92.90 KB, created by Han Han on 2018-09-20 08:06:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Han Han

Created: 2018-09-20 08:06:51 UTC

Size: 92.90 KB

patch

obsolete

>
>2018-09-20 07:12:41.945+0000: 28748: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=0 value=root
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=1 value=0
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=2 value=root
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=3 value=0
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=4 value=30567
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=5 value=146582878
>2018-09-20 07:12:41.945+0000: 28748: debug : virIdentitySetAttr:245 : ident=0x7f27a8001380 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
>2018-09-20 07:12:41.945+0000: 28748: debug : virThreadJobSet:96 : Thread 28748 (virNetServerHandleJob) is now running job remoteDispatchAuthList
>2018-09-20 07:12:41.945+0000: 28748: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:41.945+0000: 28748: debug : virThreadJobClear:121 : Thread 28748 (virNetServerHandleJob) finished job remoteDispatchAuthList with ret=0
>2018-09-20 07:12:41.945+0000: 28749: debug : virThreadJobSet:96 : Thread 28749 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-09-20 07:12:41.945+0000: 28749: debug : virThreadJobClear:121 : Thread 28749 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-09-20 07:12:41.945+0000: 28756: debug : virThreadJobSet:96 : Thread 28756 (virNetServerHandleJob) is now running job remoteDispatchConnectOpen
>2018-09-20 07:12:41.945+0000: 28756: debug : virConnectOpen:1116 : name=
>2018-09-20 07:12:41.945+0000: 28756: debug : virConfLoadConfig:1576 : Loading config file '/etc/libvirt/libvirt.conf'
>2018-09-20 07:12:41.945+0000: 28756: debug : virConfReadFile:752 : filename=/etc/libvirt/libvirt.conf
>2018-09-20 07:12:41.945+0000: 28756: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:41.945+0000: 28756: debug : virConfGetValueString:897 : Get value string (nil) 0
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:899 : Trying to probe for default URI
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:906 : QEMU driver URI probe returned 'qemu:///system'
>2018-09-20 07:12:41.946+0000: 28756: debug : virConfGetValueStringList:946 : Get value string list (nil) 0
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:944 : Split "qemu:///system" to URI components:
>  scheme qemu
>  server <null>
>  user <null>
>  port 0
>  path /system
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 0 (Test) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 1 (ESX) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 2 (remote) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1029 : Matching any URI scheme for 'qemu'
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1045 : driver 2 remote returned DECLINED
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 3 (network) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 4 (interface) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 5 (storage) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 6 (nodedev) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 7 (secret) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 8 (nwfilter) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1001 : trying driver 9 (QEMU) ...
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1019 : Matched URI scheme 'qemu'
>2018-09-20 07:12:41.946+0000: 28756: debug : virConnectOpenInternal:1045 : driver 9 QEMU returned SUCCESS
>2018-09-20 07:12:41.946+0000: 28756: debug : virThreadJobClear:121 : Thread 28756 (virNetServerHandleJob) finished job remoteDispatchConnectOpen with ret=0
>2018-09-20 07:12:41.946+0000: 28755: debug : virThreadJobSet:96 : Thread 28755 (virNetServerHandleJob) is now running job remoteDispatchConnectGetURI
>2018-09-20 07:12:41.946+0000: 28755: debug : virConnectGetURI:255 : conn=0x7f2784000920
>2018-09-20 07:12:41.946+0000: 28755: debug : virThreadJobClear:121 : Thread 28755 (virNetServerHandleJob) finished job remoteDispatchConnectGetURI with ret=0
>2018-09-20 07:12:41.946+0000: 28751: debug : virThreadJobSet:96 : Thread 28751 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-09-20 07:12:41.946+0000: 28751: debug : virThreadJobClear:121 : Thread 28751 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-09-20 07:12:41.946+0000: 28748: debug : virThreadJobSet:96 : Thread 28748 (virNetServerHandleJob) is now running job remoteDispatchConnectSupportsFeature
>2018-09-20 07:12:41.946+0000: 28748: debug : virThreadJobClear:121 : Thread 28748 (virNetServerHandleJob) finished job remoteDispatchConnectSupportsFeature with ret=0
>2018-09-20 07:12:41.946+0000: 28749: debug : virThreadJobSet:96 : Thread 28749 (virNetServerHandleJob) is now running job remoteDispatchConnectRegisterCloseCallback
>2018-09-20 07:12:41.946+0000: 28749: debug : virConnectRegisterCloseCallback:1375 : conn=0x7f2784000920
>2018-09-20 07:12:41.946+0000: 28749: debug : virThreadJobClear:121 : Thread 28749 (virNetServerHandleJob) finished job remoteDispatchConnectRegisterCloseCallback with ret=0
>2018-09-20 07:12:41.946+0000: 28753: debug : virThreadJobSet:96 : Thread 28753 (virNetServerHandleJob) is now running job remoteDispatchDomainLookupByName
>2018-09-20 07:12:41.946+0000: 28753: debug : virDomainLookupByName:412 : conn=0x7f2784000920, name=A
>2018-09-20 07:12:41.946+0000: 28753: debug : virThreadJobClear:121 : Thread 28753 (virNetServerHandleJob) finished job remoteDispatchDomainLookupByName with ret=0
>2018-09-20 07:12:41.946+0000: 28752: debug : virThreadJobSet:96 : Thread 28752 (virNetServerHandleJob) is now running job remoteDispatchDomainCreate
>2018-09-20 07:12:41.946+0000: 28752: debug : virDomainCreate:6520 : dom=0x7f27a0000e60, (VM: name=A, uuid=b1838836-e956-48a6-ad12-f9700046f020)
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuDomainObjBeginJobInternal:6610 : Starting job: job=none agentJob=none asyncJob=start (vm=0x7f27502ee350 name=A, current job=none agentJob=none async=none)
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuDomainObjBeginJobInternal:6667 : Started async job: start (vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuProcessStart:6663 : conn=0x7f2784000920 driver=0x7f275013b190 vm=0x7f27502ee350 name=A id=-1 asyncJob=start migrateFrom=<null> migrateFd=-1 migratePath=<null> snapshot=(nil) vmop=0 flags=0x1
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuProcessInit:5216 : vm=0x7f27502ee350 name=A id=-1 migration=0
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuProcessInit:5218 : Beginning VM startup process
>2018-09-20 07:12:41.947+0000: 28752: debug : qemuProcessInit:5238 : Determining emulator version
>2018-09-20 07:12:41.957+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:41.957+0000: 28752: debug : qemuProcessStartValidate:5141 : Checking for KVM availability
>2018-09-20 07:12:41.957+0000: 28752: debug : qemuProcessStartValidate:5152 : Checking domain and device security labels
>2018-09-20 07:12:41.966+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:41.976+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:41.985+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:41.993+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.002+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.011+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.020+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.029+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.038+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.038+0000: 28752: debug : qemuProcessStartValidate:5180 : Checking for any possible (non-fatal) issues
>2018-09-20 07:12:42.038+0000: 28752: debug : qemuProcessInit:5258 : Setting current domain def as transient
>2018-09-20 07:12:42.047+0000: 28752: debug : virQEMUCapsCacheLookup:4761 : Returning caps 0x7f27502e2d40 for /usr/libexec/qemu-kvm
>2018-09-20 07:12:42.047+0000: 28752: debug : qemuDomainAssignVirtioSerialAddresses:137 : Finished reserving existing ports
>2018-09-20 07:12:42.047+0000: 28752: debug : qemuDomainUSBAddressAddHubs:2763 : Found 0 USB devices and 2 provided USB ports; adding 0 hubs
>2018-09-20 07:12:42.047+0000: 28752: debug : qemuDomainAssignUSBAddresses:2902 : Existing USB addresses have been reserved
>2018-09-20 07:12:42.047+0000: 28752: debug : qemuDomainAssignUSBAddresses:2910 : Finished assigning USB ports
>2018-09-20 07:12:42.047+0000: 28752: debug : qemuProcessPrepareDomain:5859 : Generating domain security label (if required)
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5886 : Assigning domain PCI addresses
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuDomainAssignVirtioSerialAddresses:137 : Finished reserving existing ports
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuDomainUSBAddressAddHubs:2763 : Found 0 USB devices and 2 provided USB ports; adding 0 hubs
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuDomainAssignUSBAddresses:2902 : Existing USB addresses have been reserved
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuDomainAssignUSBAddresses:2910 : Finished assigning USB ports
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5895 : Setting graphics devices
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5899 : Create domain masterKey
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5903 : Setting up storage
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5907 : Prepare chardev source backends for TLS
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5910 : Add secrets to hostdevs and chardevs
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5923 : Preparing monitor state
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareDomain:5932 : Updating guest CPU definition
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6098 : Preparing network devices
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6103 : Preparing host devices
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6112 : Preparing chr devices
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6124 : Ensuring no historical cgroup is lying around
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/log/libvirt/qemu mode=0777
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/lib/libvirt/qemu/domain-11-A mode=0750
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/lib/libvirt/qemu mode=0750
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/lib/libvirt/qemu/channel/target/domain-11-A mode=0750
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/lib/libvirt/qemu/channel/target mode=0750
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6157 : Write domain masterKey
>2018-09-20 07:12:42.050+0000: 28752: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6161 : Preparing disks (host)
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessPrepareHost:6165 : Preparing external devices
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessLaunch:6257 : conn=0x7f2784000920 driver=0x7f275013b190 vm=0x7f27502ee350 name=A if=11 asyncJob=6 incoming.launchURI=<null> incoming.deferredURI=<null> incoming.fd=-1 incoming.path=<null> snapshot=(nil) vmop=0 flags=0x11
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuProcessLaunch:6283 : Creating domain log file
>2018-09-20 07:12:42.050+0000: 28752: debug : qemuDomainLogContextNew:7664 : Context new 0x7f27a0010df0 stdioLogD=1
>2018-09-20 07:12:42.052+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuProcessLaunch:6295 : Building emulator command line
>2018-09-20 07:12:42.052+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuBuildCommandLine:10237 : driver=0x7f275013b190 def=0x7f279c007a20 mon=0x7f27a000ef90 json=1 qemuCaps=0x7f27a0000d80 migrateURI=(null) snapshot=(nil) vmop=0
>2018-09-20 07:12:42.052+0000: 28752: debug : virArchFromHost:181 : Mapped x86_64 to 32 (x86_64)
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuProcessLaunch:6322 : Building mount namespace
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuProcessLaunch:6328 : Clear emulator capabilities: 1
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuProcessLaunch:6332 : Setting up raw IO
>2018-09-20 07:12:42.052+0000: 28752: debug : virFileClose:111 : Closed fd 30
>2018-09-20 07:12:42.052+0000: 28752: debug : qemuProcessLaunch:6342 : Setting up security labelling
>2018-09-20 07:12:42.052+0000: 28752: debug : virCommandRequireHandshake:2675 : Transfer handshake wait=31 notify=32, keep handshake wait=30 notify=33
>2018-09-20 07:12:42.053+0000: 28752: debug : virCommandRunAsync:2476 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin QEMU_AUDIO_DRV=none /usr/libexec/qemu-kvm -name guest=A,debug-threads=on -S -object secret,id=masterKey0,format=raw,file=/var/lib/libvirt/qemu/domain-11-A/master-key.aes -machine pc-i440fx-rhel7.6.0,accel=kvm,usb=off,dump-guest-core=off -m 1024 -realtime mlock=off -smp 1,sockets=1,cores=1,threads=1 -uuid b1838836-e956-48a6-ad12-f9700046f020 -display none -no-user-config -nodefaults -chardev socket,id=charmonitor,fd=28,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=utc -no-shutdown -no-acpi -boot strict=on -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x2 -device ahci,id=sata0,bus=pci.0,addr=0x4 -drive file=/dev/dm-5,format=raw,if=none,id=drive-scsi0-0-0-0 -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x3 -sandbox on,obsolete=deny,elevateprivileges=deny,spawn=deny,resourcecontrol=deny -msg timestamp=on
>2018-09-20 07:12:42.061+0000: 28752: debug : virFileClose:111 : Closed fd 34
>2018-09-20 07:12:42.061+0000: 28752: debug : virCommandRunAsync:2479 : Command result 0, with PID 30580
>2018-09-20 07:12:42.061+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.061+0000: 28752: debug : virFileClose:111 : Closed fd 31
>2018-09-20 07:12:42.061+0000: 28752: debug : virFileClose:111 : Closed fd 32
>2018-09-20 07:12:42.073+0000: 28752: debug : virCommandRun:2327 : Result status 0, stdout: '(null)' stderr: '(null)'
>2018-09-20 07:12:42.073+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.073+0000: 28752: debug : qemuProcessLaunch:6367 : QEMU vm=0x7f27502ee350 name=A running with pid=30581
>2018-09-20 07:12:42.073+0000: 28752: debug : qemuProcessLaunch:6373 : Writing early domain status to disk
>2018-09-20 07:12:42.073+0000: 28752: debug : virFileMakePathHelper:3093 : path=/var/run/libvirt/qemu mode=0777
>2018-09-20 07:12:42.073+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.073+0000: 28752: debug : qemuProcessLaunch:6377 : Waiting for handshake from child
>2018-09-20 07:12:42.073+0000: 28752: debug : virCommandHandshakeWait:2710 : Wait for handshake on 30
>2018-09-20 07:12:42.075+0000: 28752: debug : virFileClose:111 : Closed fd 30
>2018-09-20 07:12:42.075+0000: 28752: debug : qemuProcessLaunch:6385 : Setting up domain cgroup (if required)
>2018-09-20 07:12:42.075+0000: 28752: debug : virCgroupNewMachineSystemd:1583 : Trying to setup machine 'qemu-11-A' via systemd
>2018-09-20 07:12:42.075+0000: 28752: debug : virSystemdCreateMachine:320 : Attempting to create machine via systemd
>2018-09-20 07:12:42.075+0000: 28752: debug : virDBusMessageIterEncode:622 : rootiter=0x7f27af17cea0 types=sayssusa&ia(sv)
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=15 types='sayssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'qemu-11-A'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=14 types='ayssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:727 : Got array non-ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 'y' from 'ayssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:749 : Contsig 'y' skip='1' len='1'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='ssusa&ia(sv)' nstruct=12 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=16 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '177'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=15 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '131'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=14 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '136'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=13 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '54'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=12 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '233'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=11 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '86'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=10 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '72'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=9 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '166'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=8 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '173'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=7 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '18'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=6 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '249'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=5 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '112'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=4 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '0'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=3 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '70'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=2 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '240'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=1 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:674 : Appended basic type 'unsigned char' varg 'int' sig 'y' val '32'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=0 nstruct=1 types='yssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a000f690
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='ssusa&ia(sv)' nstruct=12 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27af17cea0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=12 types='ssusa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'libvirt-qemu'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=11 types='susa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'vm'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=10 types='usa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:696 : Appended basic type 'dbus_uint32_t' varg 'unsigned int' sig 'u' val '30581'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=9 types='sa&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val ''
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=8 types='a&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:721 : Got array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 'i' from '&ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:749 : Contsig 'i' skip='1' len='1'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='a(sv)' nstruct=5 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=0 nstruct=1 types='ia(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='a(sv)' nstruct=5 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27af17cea0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=5 types='a(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:727 : Got array non-ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted '(sv)' from 'a(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:749 : Contsig '(sv)' skip='4' len='4'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=3 nstruct=4 types='(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 'sv)' from '(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:801 : Contsig 'sv)' skip='4' len='4'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='(sv)' nstruct=4 narray=2
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'Slice'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=1 types='s'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'machine.slice'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=0 types=''
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0016690
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=0 types=')'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='(sv)' nstruct=4 narray=2
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010a70
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=2 nstruct=4 types='(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 'sv)' from '(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:801 : Contsig 'sv)' skip='4' len='4'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='(sv)' nstruct=4 narray=1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'After'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=2 types='as'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:727 : Got array non-ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 's' from 'as'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:749 : Contsig 's' skip='1' len='1'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=4 narray=1 nstruct=1 types='s'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'libvirtd.service'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=4 narray=0 nstruct=1 types='s'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a00169a0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a00168c0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=0 types=''
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a00168c0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=0 types=')'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='(sv)' nstruct=4 narray=1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010a70
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=1 nstruct=4 types='(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 'sv)' from '(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:801 : Contsig 'sv)' skip='4' len='4'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='(sv)' nstruct=4 narray=0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=2 types='sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'Before'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=1 types='v)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=2 types='as'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:727 : Got array non-ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusCopyContainerSignature:472 : Extracted 's' from 'as'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:749 : Contsig 's' skip='1' len='1'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPush:513 : Pushed types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=4 narray=1 nstruct=1 types='s'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'virt-guest-shutdown.target'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=4 narray=0 nstruct=1 types='s'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0016c60
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0016b80
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=3 narray=-1 nstruct=0 types=''
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0016b80
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types=')' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=2 narray=-1 nstruct=0 types=')'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0010990
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='(sv)' nstruct=4 narray=0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27a0010a70
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=1 narray=0 nstruct=4 types='(sv)'
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27a0010a70
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusTypeStackPop:536 : Popped types='' nstruct=0 narray=-1
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:650 : Popped iter=0x7f27af17cea0
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=0 types=''
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.076+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27af17cea0
>2018-09-20 07:12:42.076+0000: 28752: info : virDBusCall:1548 : DBUS_METHOD_CALL: 'org.freedesktop.machine1.Manager.CreateMachineWithNetwork' on '/org/freedesktop/machine1' at 'org.freedesktop.machine1'
>2018-09-20 07:12:42.081+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:12:42.081+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:12:42.117+0000: 28752: info : virDBusCall:1577 : DBUS_METHOD_REPLY: 'org.freedesktop.machine1.Manager.CreateMachineWithNetwork' on '/org/freedesktop/machine1' at 'org.freedesktop.machine1'
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupNewMachineSystemd:1598 : Detecting systemd placement
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupNew:1158 : pid=30581 path= parent=(nil) controllers=-1 group=0x7f27af17d328
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:648 : group=0x7f27a000f170 controllers=-1 path= parent=(nil)
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:699 : Auto-detecting controllers
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpu' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuacct' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuset' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'memory' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'devices' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'freezer' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'blkio' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'net_cls' present=yes
>2018-09-20 07:12:42.117+0000: 28752: debug : virCgroupDetect:704 : Controller 'perf_event' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'name=systemd' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetectPlacement:553 : Detecting placement for pid 30581 path 
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupNew:1158 : pid=30581 path= parent=(nil) controllers=-1 group=0x7f27af17d330
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:648 : group=0x7f27a000f170 controllers=-1 path= parent=(nil)
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:699 : Auto-detecting controllers
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpu' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuacct' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuset' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'memory' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'devices' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'freezer' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'blkio' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'net_cls' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'perf_event' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'name=systemd' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetectPlacement:553 : Detecting placement for pid 30581 path 
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in / for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupNew:1158 : pid=30581 path=/machine.slice parent=0x7f27a000f170 controllers=-1 group=0x7f27af17d340
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:648 : group=0x7f27a0017d50 controllers=-1 path=/machine.slice parent=0x7f27a000f170
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:699 : Auto-detecting controllers
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpu' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuacct' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuset' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'memory' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'devices' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'freezer' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'blkio' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'net_cls' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'perf_event' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'name=systemd' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetectPlacement:553 : Detecting placement for pid 30581 path /machine.slice
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in /machine.slice for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1049 : Make group /machine.slice
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpuset/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/memory/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/devices/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/freezer/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/blkio/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/net_cls,net_prio/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/perf_event/machine.slice/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1055 : Not creating systemd controller group
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1126 : Done making controllers for group
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupNew:1158 : pid=30581 path=/machine.slice/machine-qemu\x2d11\x2dA.scope parent=0x7f27a0017d50 controllers=-1 group=0x7f27af17d340
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:648 : group=0x7f27a000f170 controllers=-1 path=/machine.slice/machine-qemu\x2d11\x2dA.scope parent=0x7f27a0017d50
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:699 : Auto-detecting controllers
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpu' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuacct' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'cpuset' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'memory' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'devices' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'freezer' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'blkio' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'net_cls' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'perf_event' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:704 : Controller 'name=systemd' present=yes
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetectPlacement:553 : Detecting placement for pid 30581 path /machine.slice/machine-qemu\x2d11\x2dA.scope
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 7:net_cls at /sys/fs/cgroup/net_cls,net_prio in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 8:perf_event at /sys/fs/cgroup/perf_event in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 9:name=systemd at /sys/fs/cgroup/systemd in /machine.slice/machine-qemu\x2d11\x2dA.scope for pid 30581
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1049 : Make group /machine.slice/machine-qemu\x2d11\x2dA.scope
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupCpuSetInherit:989 : Setting up inheritance /machine.slice -> /machine.slice/machine-qemu\x2d11\x2dA.scope
>2018-09-20 07:12:42.118+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/cpuset.cpus
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.cpus = 0-7
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.cpus' to '0-7'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/cpuset.mems
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.mems = 0
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.mems' to '0'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/cpuset.memory_migrate
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.memory_migrate = 0
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.memory_migrate' to '0'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/memory/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/blkio/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1055 : Not creating systemd controller group
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupMakeGroup:1126 : Done making controllers for group
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/memory/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/blkio/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2d11\x2dA.scope/tasks' to '30581'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.deny' to 'a'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.119+0000: 28752: debug : qemuSetupImagePathCgroup:77 : Allow path /dev/dm-5, perms: rw
>2018-09-20 07:12:42.119+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'b 253:5 rw'
>2018-09-20 07:12:42.119+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'b 8:2 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 136:* rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 1:3 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 1:7 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 1:5 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 1:8 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 1:9 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 5:2 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 10:232 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : qemuSetupDevicesCgroup:759 : Ignoring non-existent device /dev/kqemu
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 252:0 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/devices.allow' to 'c 10:228 rw'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : qemuSetupDevicesCgroup:759 : Ignoring non-existent device /dev/sev
>2018-09-20 07:12:42.120+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.memory_migrate' to '1'
>2018-09-20 07:12:42.120+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.120+0000: 28752: debug : qemuProcessInitCpuAffinity:2336 : Set CPU affinity with specified cpuset
>2018-09-20 07:12:42.121+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.121+0000: 28752: debug : qemuProcessLaunch:6404 : Setting emulator tuning/settings
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupNew:1158 : pid=-1 path=emulator parent=0x7f27a000f170 controllers=7 group=0x7f27af17d3c8
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:648 : group=0x7f27a0016e00 controllers=7 path=emulator parent=0x7f27a000f170
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:659 : Filtering controllers 7
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'cpu' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'cpuacct' wanted=yes, mount='/sys/fs/cgroup/cpu,cpuacct'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'cpuset' wanted=yes, mount='/sys/fs/cgroup/cpuset'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'memory' wanted=no, mount='/sys/fs/cgroup/memory'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'devices' wanted=no, mount='/sys/fs/cgroup/devices'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'freezer' wanted=no, mount='/sys/fs/cgroup/freezer'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'blkio' wanted=no, mount='/sys/fs/cgroup/blkio'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'net_cls' wanted=no, mount='/sys/fs/cgroup/net_cls,net_prio'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'perf_event' wanted=no, mount='/sys/fs/cgroup/perf_event'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:675 : Controller 'name=systemd' wanted=no, mount='/sys/fs/cgroup/systemd'
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetectPlacement:553 : Detecting placement for pid -1 path emulator
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope/emulator for pid -1
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /machine.slice/machine-qemu\x2d11\x2dA.scope/emulator for pid -1
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupDetect:747 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in /machine.slice/machine-qemu\x2d11\x2dA.scope/emulator for pid -1
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupMakeGroup:1049 : Make group /machine.slice/machine-qemu\x2d11\x2dA.scope/emulator
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupMakeGroup:1073 : Make controller /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupCpuSetInherit:989 : Setting up inheritance /machine.slice/machine-qemu\x2d11\x2dA.scope -> /machine.slice/machine-qemu\x2d11\x2dA.scope/emulator
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.cpus
>2018-09-20 07:12:42.121+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.cpus = 0-7
>2018-09-20 07:12:42.121+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/cpuset.cpus' to '0-7'
>2018-09-20 07:12:42.121+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.mems
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.mems = 0
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/cpuset.mems' to '0'
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupGetValueStr:832 : Get value /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/cpuset.memory_migrate
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupCpuSetInherit:999 : Inherit cpuset.memory_migrate = 1
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/cpuset.memory_migrate' to '1'
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller memory
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller devices
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller freezer
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller blkio
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller net_cls
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1062 : Skipping unmounted controller perf_event
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1055 : Not creating systemd controller group
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupMakeGroup:1126 : Done making controllers for group
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/tasks' to '30581'
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/tasks' to '30581'
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : virCgroupSetValueStr:796 : Set value '/sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/emulator/tasks' to '30581'
>2018-09-20 07:12:42.122+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.122+0000: 28752: debug : qemuProcessLaunch:6408 : Setting cgroup for external devices (if required)
>2018-09-20 07:12:42.122+0000: 28752: debug : qemuProcessLaunch:6412 : Setting up resctrl
>2018-09-20 07:12:42.122+0000: 28752: debug : qemuProcessLaunch:6416 : Setting up managed PR daemon
>2018-09-20 07:12:42.122+0000: 28752: debug : qemuProcessLaunch:6421 : Setting domain security labels
>2018-09-20 07:12:42.131+0000: 28752: debug : virFileClose:111 : Closed fd 30
>2018-09-20 07:12:42.133+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.141+0000: 28752: debug : virFileClose:111 : Closed fd 30
>2018-09-20 07:12:42.142+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.142+0000: 28752: debug : qemuProcessLaunch:6452 : Labelling done, completing handshake to child
>2018-09-20 07:12:42.142+0000: 28752: debug : virCommandHandshakeNotify:2776 : Notify handshake on 33
>2018-09-20 07:12:42.142+0000: 28752: debug : virFileClose:111 : Closed fd 33
>2018-09-20 07:12:42.142+0000: 28752: debug : qemuProcessLaunch:6455 : Handshake complete, child running
>2018-09-20 07:12:42.142+0000: 28752: debug : qemuProcessLaunch:6463 : Waiting for monitor to show up
>2018-09-20 07:12:42.142+0000: 28752: debug : qemuProcessWaitForMonitor:2212 : Connect monitor to vm=0x7f27502ee350 name='A' retry=0
>2018-09-20 07:12:42.143+0000: 28752: info : qemuMonitorOpenInternal:869 : QEMU_MONITOR_NEW: mon=0x7f27a0016e00 refs=2 fd=28
>2018-09-20 07:12:42.143+0000: 28752: debug : qemuDomainObjBeginJobInternal:6610 : Starting job: job=async nested agentJob=none asyncJob=none (vm=0x7f27502ee350 name=A, current job=none agentJob=none async=start)
>2018-09-20 07:12:42.143+0000: 28752: debug : qemuDomainObjBeginJobInternal:6659 : Started job: async nested (async=start vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.143+0000: 28752: debug : qemuDomainObjEnterMonitorInternal:7051 : Entering monitor (mon=0x7f27a0016e00 vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.143+0000: 28752: debug : qemuMonitorSetCapabilities:1690 : mon:0x7f27a0016e00 vm:0x7f27502ee350 json:1 fd:28
>2018-09-20 07:12:42.143+0000: 28752: debug : qemuMonitorJSONCommandWithFd:305 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
>2018-09-20 07:12:42.143+0000: 28752: info : qemuMonitorSend:1083 : QEMU_MONITOR_SEND_MSG: mon=0x7f27a0016e00 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2018-09-20 07:12:42.176+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:12:42.176+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:12:42.197+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:12:42.197+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:12:42.198+0000: 28747: error : qemuMonitorIORead:609 : Unable to read from monitor: Connection reset by peer
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuDomainLogContextRead:7778 : Context read 0x7f27a0010df0 manager=0x7f27a000d370 inode=34738199 pos=85939
>2018-09-20 07:12:42.198+0000: 28747: error : qemuProcessReportLogError:1899 : internal error: qemu unexpectedly closed the monitor: sata0,bus=pci.0,addr=0x4 -drive file=/dev/dm-5,format=raw,if=none,id=drive-scsi0-0-0-0 -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x3 -sandbox on,obsolete=deny,elevateprivileges=deny,spawn=deny,resourcecontrol=deny -msg timestamp=on
>2018-09-20T07:12:42.188661Z qemu-kvm: -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1: cannot get SG_IO version number: Inappropriate ioctl for device
>Is this a SCSI device?
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuMonitorIO:765 : Error on monitor internal error: qemu unexpectedly closed the monitor: sata0,bus=pci.0,addr=0x4 -drive file=/dev/dm-5,format=raw,if=none,id=drive-scsi0-0-0-0 -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x3 -sandbox on,obsolete=deny,elevateprivileges=deny,spawn=deny,resourcecontrol=deny -msg timestamp=on
>2018-09-20T07:12:42.188661Z qemu-kvm: -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1: cannot get SG_IO version number: Inappropriate ioctl for device
>Is this a SCSI device?
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuMonitorIO:796 : Triggering error callback
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuProcessHandleMonitorError:341 : Received error on 0x7f27502ee350 'A'
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuMonitorSend:1095 : Send command resulted in error internal error: qemu unexpectedly closed the monitor: sata0,bus=pci.0,addr=0x4 -drive file=/dev/dm-5,format=raw,if=none,id=drive-scsi0-0-0-0 -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x3 -sandbox on,obsolete=deny,elevateprivileges=deny,spawn=deny,resourcecontrol=deny -msg timestamp=on
>2018-09-20T07:12:42.188661Z qemu-kvm: -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1: cannot get SG_IO version number: Inappropriate ioctl for device
>Is this a SCSI device?
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuMonitorJSONCommandWithFd:310 : Receive command reply ret=-1 rxObject=(nil)
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainObjExitMonitorInternal:7074 : Exited monitor (mon=0x7f27a0016e00 vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuMonitorIO:765 : Error on monitor internal error: qemu unexpectedly closed the monitor: sata0,bus=pci.0,addr=0x4 -drive file=/dev/dm-5,format=raw,if=none,id=drive-scsi0-0-0-0 -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x3 -sandbox on,obsolete=deny,elevateprivileges=deny,spawn=deny,resourcecontrol=deny -msg timestamp=on
>2018-09-20T07:12:42.188661Z qemu-kvm: -device scsi-block,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1: cannot get SG_IO version number: Inappropriate ioctl for device
>Is this a SCSI device?
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainObjEndJob:6935 : Stopping job: async nested (async=start vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.198+0000: 28747: debug : qemuMonitorIO:786 : Triggering EOF callback
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainLogContextDispose:158 : ctxt=0x7f27a0010df0
>2018-09-20 07:12:42.198+0000: 28752: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:42.198+0000: 28752: debug : virFileClose:111 : Closed fd 26
>2018-09-20 07:12:42.198+0000: 28752: debug : virFileClose:111 : Closed fd 25
>2018-09-20 07:12:42.198+0000: 28752: debug : virFileClose:111 : Closed fd 29
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuProcessStop:6875 : Shutting down vm=0x7f27502ee350 name=A id=11 pid=30581, reason=failed, asyncJob=start, flags=0x0
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainObjBeginJobInternal:6610 : Starting job: job=async nested agentJob=none asyncJob=none (vm=0x7f27502ee350 name=A, current job=none agentJob=none async=start)
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainObjBeginJobInternal:6659 : Started job: async nested (async=start vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.198+0000: 28752: debug : virFileClose:111 : Closed fd 24
>2018-09-20 07:12:42.198+0000: 28752: debug : qemuDomainLogAppendMessage:7847 : Append log message (vm='A' message='2018-09-20 07:12:42.198+0000: shutting down, reason=failed
>) stdioLogD=1
>2018-09-20 07:12:42.199+0000: 28752: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:42.199+0000: 28752: debug : virFileClose:111 : Closed fd 25
>2018-09-20 07:12:42.199+0000: 28752: debug : virFileClose:111 : Closed fd 24
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpen:1116 : name=nwfilter:///system
>2018-09-20 07:12:42.199+0000: 28752: debug : virConfLoadConfig:1576 : Loading config file '/etc/libvirt/libvirt.conf'
>2018-09-20 07:12:42.199+0000: 28752: debug : virConfReadFile:752 : filename=/etc/libvirt/libvirt.conf
>2018-09-20 07:12:42.199+0000: 28752: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:42.199+0000: 28752: debug : virConfGetValueStringList:946 : Get value string list (nil) 0
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:944 : Split "nwfilter:///system" to URI components:
>  scheme nwfilter
>  server <null>
>  user <null>
>  port 0
>  path /system
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 0 (Test) ...
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 1 (ESX) ...
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 2 (remote) ...
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1029 : Matching any URI scheme for 'nwfilter'
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1045 : driver 2 remote returned DECLINED
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 3 (network) ...
>2018-09-20 07:12:42.199+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 4 (interface) ...
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 5 (storage) ...
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 6 (nodedev) ...
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 7 (secret) ...
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1025 : No matching URI scheme
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1001 : trying driver 8 (nwfilter) ...
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1016 : Checking for supported URI schemes
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1019 : Matched URI scheme 'nwfilter'
>2018-09-20 07:12:42.200+0000: 28752: debug : virConnectOpenInternal:1045 : driver 8 nwfilter returned SUCCESS
>2018-09-20 07:12:42.200+0000: 28752: info : qemuMonitorClose:1005 : QEMU_MONITOR_CLOSE: mon=0x7f27a0016e00 refs=3
>2018-09-20 07:12:42.200+0000: 28752: debug : virFileClose:111 : Closed fd 28
>2018-09-20 07:12:42.200+0000: 28752: debug : qemuProcessKill:6791 : vm=0x7f27502ee350 name=A pid=30581 flags=0x5
>2018-09-20 07:12:42.200+0000: 28752: debug : virProcessKillPainfully:355 : vpid=30581 force=1
>2018-09-20 07:12:42.400+0000: 28752: debug : qemuDomainCleanupRun:8386 : driver=0x7f275013b190, vm=A
>2018-09-20 07:12:42.400+0000: 28752: debug : qemuProcessAutoDestroyRemove:7463 : vm=A
>2018-09-20 07:12:42.400+0000: 28752: debug : virCloseCallbacksUnset:157 : vm=A, uuid=b1838836-e956-48a6-ad12-f9700046f020, cb=0x7f276bc7a4f0
>2018-09-20 07:12:42.400+0000: 28752: debug : virSystemdTerminateMachine:429 : Attempting to terminate machine via systemd
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:622 : rootiter=0x7f27af17d330 types=s
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=1 types='s'
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:715 : Appended basic type 'char *' varg 'char *' sig 's' val 'qemu-11-A'
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:634 : Loop nstack=0 narray=-1 nstruct=0 types=''
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:640 : Reset array ref
>2018-09-20 07:12:42.400+0000: 28752: debug : virDBusMessageIterEncode:644 : Popping iter=0x7f27af17d330
>2018-09-20 07:12:42.400+0000: 28752: info : virDBusCall:1548 : DBUS_METHOD_CALL: 'org.freedesktop.machine1.Manager.TerminateMachine' on '/org/freedesktop/machine1' at 'org.freedesktop.machine1'
>2018-09-20 07:12:42.401+0000: 28752: info : virDBusCall:1558 : DBUS_METHOD_ERROR: 'org.freedesktop.machine1.Manager.TerminateMachine' on '/org/freedesktop/machine1' at 'org.freedesktop.machine1' error org.freedesktop.machine1.NoSuchMachine: No machine 'qemu-11-A' known
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3513 : Removing cgroup /machine.slice/machine-qemu\x2d11\x2dA.scope
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemoveRecursively:3484 : Removing cgroup /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope//emulator
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemoveRecursively:3484 : Removing cgroup /sys/fs/cgroup/cpuset/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/memory/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.401+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/devices/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemoveRecursively:3484 : Removing cgroup /sys/fs/cgroup/freezer/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/blkio/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemoveRecursively:3484 : Removing cgroup /sys/fs/cgroup/net_cls,net_prio/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemove:3534 : Removing cgroup /sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2d11\x2dA.scope/ and all child cgroups
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemoveRecursively:3484 : Removing cgroup /sys/fs/cgroup/perf_event/machine.slice/machine-qemu\x2d11\x2dA.scope/
>2018-09-20 07:12:42.402+0000: 28752: debug : virCgroupRemove:3538 : Done removing cgroup /machine.slice/machine-qemu\x2d11\x2dA.scope
>2018-09-20 07:12:42.402+0000: 28752: debug : qemuDomainObjEndJob:6935 : Stopping job: none (async=none vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.402+0000: 28752: debug : qemuDomainObjEndAsyncJob:6998 : Stopping async job: none (vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.402+0000: 28752: debug : virThreadJobClear:121 : Thread 28752 (virNetServerHandleJob) finished job remoteDispatchDomainCreate with ret=-1
>2018-09-20 07:12:42.402+0000: 28747: debug : qemuProcessHandleMonitorEOF:293 : Received EOF on 0x7f27502ee350 'A'
>2018-09-20 07:12:42.402+0000: 28747: debug : qemuMonitorDispose:324 : mon=0x7f27a0016e00
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuProcessEventHandler:4802 : vm=0x7f27502ee350, event=6
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuProcessKill:6791 : vm=0x7f27502ee350 name=A pid=-1 flags=0x1
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuProcessKill:6795 : VM 'A' not active
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuDomainObjBeginJobInternal:6610 : Starting job: job=destroy agentJob=none asyncJob=none (vm=0x7f27502ee350 name=A, current job=none agentJob=none async=none)
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuDomainObjBeginJobInternal:6659 : Started job: destroy (async=none vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.402+0000: 29004: debug : processMonitorEOFEvent:4752 : Domain 0x7f27502ee350 'A' is not active, ignoring EOF
>2018-09-20 07:12:42.402+0000: 29004: debug : qemuDomainObjEndJob:6935 : Stopping job: destroy (async=none vm=0x7f27502ee350 name=A)
>2018-09-20 07:12:42.403+0000: 28751: debug : virThreadJobSet:96 : Thread 28751 (virNetServerHandleJob) is now running job remoteDispatchConnectUnregisterCloseCallback
>2018-09-20 07:12:42.403+0000: 28751: debug : virConnectUnregisterCloseCallback:1410 : conn=0x7f2784000920
>2018-09-20 07:12:42.403+0000: 28751: debug : virThreadJobClear:121 : Thread 28751 (virNetServerHandleJob) finished job remoteDispatchConnectUnregisterCloseCallback with ret=0
>2018-09-20 07:12:42.404+0000: 28748: debug : virThreadJobSet:96 : Thread 28748 (virNetServerHandleJob) is now running job remoteDispatchConnectClose
>2018-09-20 07:12:42.404+0000: 28748: debug : virThreadJobClear:121 : Thread 28748 (virNetServerHandleJob) finished job remoteDispatchConnectClose with ret=0
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=4 value=28747
>2018-09-20 07:12:42.404+0000: 28747: debug : virFileClose:111 : Closed fd 23
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=5 value=146450403
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=0 value=root
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=1 value=0
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=2 value=root
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=3 value=0
>2018-09-20 07:12:42.404+0000: 28747: debug : virIdentitySetAttr:245 : ident=0x564cadf99ee0 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virConnectClose:1237 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virCloseCallbacksRun:320 : conn=0x7f2784000920
>2018-09-20 07:12:42.404+0000: 28747: debug : virFileClose:111 : Closed fd 21
>2018-09-20 07:13:12.480+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:12.480+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.983+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:30.984+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.001+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.
>2018-09-20 07:13:31.002+0000: 28747: debug : virNetlinkEventCallback:791 : dispatching to max 0 clients, called from event watch 7
>2018-09-20 07:13:31.002+0000: 28747: debug : virNetlinkEventCallback:804 : event not handled.

Actions: View

Attachments on bug 1631191: 1485038