Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 1485038 Details for
Bug 1631191
VM start fail with block with lun type
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
libvirtd log
libvirtd.log (text/plain), 92.90 KB, created by
Han Han
on 2018-09-20 08:06:51 UTC
(
hide
)
Description:
libvirtd log
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.
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1631191
: 1485038