[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]

Re: [libvirt] [PATCH 0/2] qemu: Rework CD/DVD changing



On 05/17/2013 04:52 PM, Michal Privoznik wrote:
> On 17.05.2013 10:16, Li Wei wrote:
>> On 05/17/2013 04:07 PM, Michal Privoznik wrote:
>>> On 17.05.2013 07:47, Li Wei wrote:
>>>> Hi Michal, Daniel
>>>>
>>>> It seems there is something wrong with the 2/2 part of this patchset.
>>>> When I do an "change-media" command in virsh, it doesn't do any better
>>>> than before, but even worse(I must to wait 5 secs to see the error).
>>>>
>>>> I'm not family with libvirt, just add some log things in the qemu_hotplug.c
>>>> and found the tray_status never change to open, but with michal's original
>>>> patch (which do active poll on tray_status), I can do "change-media" successfully
>>>> every time.
>>>
>>> Yes & no. Thing is - originally we ignored tray status in the guest. So
>>> in case guest has locked the tray and thus ignored our eject request, we
>>> have changed the media anyway, resulting in possible I/O errors within
>>> guest.
>>>
>>> With my patch, we send eject request to the qemu, and actively poll for
>>> the tray to open. We check for up to 10 times every 200ms. So guest has
>>> 2 seconds to open the tray. If the guest doesn't eject media and open
>>> the tray we don't proceed with forcibly changing the media.
>>>
>>> These patches are actually a bug fix for
>>> https://bugzilla.redhat.com/show_bug.cgi?id=892289
>>
>> Hmm... I think you misunderstood my meaning(sorry for my pool english),
>> simply to say, I can get expected behavior by apply patch V1 but not V2.
>>
>> In V1, we do active poll on qemuMonitorGetBlockInfo(), and in V2 we just
>> wait for origdisk->tray_status to become VIR_DOMAIN_DISK_TRAY_OPEN but
>> this never happened.
>>
>> Thanks,
>> Wei
> 
> Aaah, now I understand. So you are saying that the event is not being
> delivered. What's the qemu version?


I have the following version:

virsh # version
Compiled against library: libvirt 1.0.5
Using library: libvirt 1.0.5
Using API: QEMU 1.0.5
Running hypervisor: QEMU 1.4.1

> 
> If you turn the debug logs on, do you see DEVICE_TRAY_MOVED in them?
> http://wiki.libvirt.org/page/DebugLogs
> 
> Or maybe the timeout is just short for your guest to eject the tray. So
> after a while, does 'virsh change-media' succeed?

Yes, I can see the DEVICE_TRAY_MOVED in the log file, it goes after
the debug message "qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 0".
does that mean the timeout is too short? 

If the guest needs too much time to eject, I'm wondering why when I use
the v1 patch, I can get succeed in about 1 to 2 seconds. I doubt if there
are other problems cause tray_status not updated in time?

I appended the log file for your reference, if you need any other information,
please tell me.

Thanks,
Wei

> 
> Michal
> 
>>
>>>
>>>>
>>>> IMHO, there must be some bug in the processing of qemu tray change.
>>>>
>>>> Thanks,
>>>> Wei
>>>>
>>>>
>>>> On 01/25/2013 08:20 PM, Michal Privoznik wrote:
>>>>> The first patch is bare bug fix (without any bug reported though).
>>>>> The second is again a bug fix, but not so easy to spot. Basically, when we
>>>>> change a media, we should issue 'eject' first, then wait until the tray gets
>>>>> open, after which we can finally issue 'change' command. Even for bare 'eject'
>>>>> we ought to check status, shouldn't we?
>>>>>
>>>>> Michal Privoznik (2):
>>>>>   qemu_monitor: Fix tray-open attribute in query-block
>>>>>   qemu_hotplug: Rework media changing process
>>>>>
>>>>>  src/qemu/qemu_hotplug.c      | 51 +++++++++++++++++++++++++++++++++++++++++---
>>>>>  src/qemu/qemu_monitor_json.c |  2 +-
>>>>>  src/qemu/qemu_monitor_text.c |  6 +++---
>>>>>  3 files changed, 52 insertions(+), 7 deletions(-)
>>>>>
>>>
>>>
>>
>> --
>> libvir-list mailing list
>> libvir-list redhat com
>> https://www.redhat.com/mailman/listinfo/libvir-list
>>
> 
> 
2013-05-17 09:04:46.228+0000: 27004: debug : virFileClose:88 : Closed fd 20
2013-05-17 09:04:46.228+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.228+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.228+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.228+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.229+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.229+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d3bf0
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.229+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.230+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.230+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.230+0000: 27006: debug : virConnectOpen:1366 : name=qemu:///system
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ac0000af0 classname=virConnect
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ac0000d00 classname=virConnectCloseCallbackData
2013-05-17 09:04:46.230+0000: 27006: debug : virConnectGetConfigFile:985 : Loading config file '/etc/libvirt/libvirt.conf'
2013-05-17 09:04:46.230+0000: 27006: debug : virConfReadFile:767 : filename=/etc/libvirt/libvirt.conf
2013-05-17 09:04:46.230+0000: 27006: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1155 : name "qemu:///system" to URI components:
  scheme qemu
  server (null)
  user (null)
  port 0
  path /system

2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 0 (Test) ...
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 1 (OPENVZ) ...
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 1 OPENVZ returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 2 (VMWARE) ...
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 2 VMWARE returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 3 (ESX) ...
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 3 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 4 (remote) ...
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 4 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1201 : trying driver 5 (QEMU) ...
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1207 : driver 5 QEMU returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1231 : network driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1231 : network driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1231 : network driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1231 : network driver 3 Network returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1247 : interface driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1247 : interface driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1247 : interface driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1247 : interface driver 3 udev returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1264 : storage driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1264 : storage driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1264 : storage driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1264 : storage driver 3 storage returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1281 : node driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1281 : node driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1281 : node driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1281 : node driver 3 udevNodeDeviceDriver returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1298 : secret driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1298 : secret driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1298 : secret driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1298 : secret driver 3 secret returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1315 : nwfilter driver 0 Test returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1315 : nwfilter driver 1 ESX returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1315 : nwfilter driver 2 remote returned DECLINED
2013-05-17 09:04:46.230+0000: 27006: debug : do_open:1315 : nwfilter driver 3 nwfilter returned SUCCESS
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.230+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.231+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.231+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.231+0000: 27007: debug : virDomainLookupByName:2152 : conn=0x7f7ac0000af0, name=rhel70a3
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ab40008c0 classname=virDomain
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:46.231+0000: 27007: debug : virDomainFree:2316 : dom=0x7f7ab40008c0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ab40008c0
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ab40008c0
2013-05-17 09:04:46.231+0000: 27007: debug : virDomainDispose:262 : release domain 0x7f7ab40008c0 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.231+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.232+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.232+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7acc0b91d0 classname=virDomain
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:46.232+0000: 27003: debug : virDomainGetXMLDesc:4381 : dom=0x7f7acc0b91d0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6), flags=0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:46.232+0000: 27003: debug : virDomainFree:2316 : dom=0x7f7acc0b91d0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0b91d0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7acc0b91d0
2013-05-17 09:04:46.232+0000: 27003: debug : virDomainDispose:262 : release domain 0x7f7acc0b91d0 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.232+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.233+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:46.233+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ac4000b30 classname=virDomain
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:46.233+0000: 27004: debug : virDomainUpdateDeviceFlags:10058 : dom=0x7f7ac4000b30, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6), xml=<disk type="file" device="cdrom">
      <driver name="qemu" type="raw"/>
      
      <target dev="hdc" bus="ide"/>
      <readonly/>
      <alias name="ide0-1-0"/>
      <address type="drive" controller="0" bus="1" target="0" unit="0"/>
    <source file="/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso"/></disk>, flags=1
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0a4160
2013-05-17 09:04:46.233+0000: 27004: debug : qemuDomainObjBeginJobInternal:972 : Starting job: modify (async=none)
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc09b9d0
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : virStorageFileGetMetadata:1009 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso format=1 uid=0 gid=0 probe=0
2013-05-17 09:04:46.233+0000: 27004: debug : virStorageFileGetMetadataRecurse:941 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso format=1 uid=0 gid=0 probe=0
2013-05-17 09:04:46.233+0000: 27004: debug : virStorageFileGetMetadataInternal:689 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso, fd=19, format=1
2013-05-17 09:04:46.233+0000: 27004: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.233+0000: 27004: debug : qemuSetupDiskPathAllow:58 : Process path /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso for disk
2013-05-17 09:04:46.233+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:46.234+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.234+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.234+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:46.234+0000: 27004: debug : virDomainLockDiskAttach:254 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 disk=0x7f7ac4003690
2013-05-17 09:04:46.234+0000: 27004: debug : virDomainLockManagerNew:128 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 withResources=0
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerPluginGetDriver:295 : plugin=0x7f7acc007c00
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerNew:319 : driver=0x7f7ae53dc5e0 type=0 nparams=5 params=0x7f7ad7d25820 flags=0
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel70a3
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerLogParams:88 :   key=pid type=uint value=23941
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=qemu:///system
2013-05-17 09:04:46.234+0000: 27004: debug : virDomainLockManagerAddDisk:86 : Add disk /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerAddResource:348 : lock=0x7f7ac4001a50 type=0 name=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso nparams=0 params=(nil) flags=1
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerAcquire:366 : lock=0x7f7ac4001a50 state='(null)' flags=0 action=0 fd=(nil)
2013-05-17 09:04:46.234+0000: 27004: debug : virLockManagerFree:403 : lock=0x7f7ac4001a50
2013-05-17 09:04:46.234+0000: 27004: debug : virSecurityDACParseImageIds:193 : DAC imagelabel for domain 'rhel70a3' wasn't found
2013-05-17 09:04:46.234+0000: 27004: info : virSecurityDACSetOwnership:273 : Setting DAC user and group on '/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso' to '0:0'
2013-05-17 09:04:46.234+0000: 27004: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:46.234+0000: 27004: debug : qemuMonitorEjectMedia:1696 : mon=0x7f7ac4000bd0 dev_name=drive-ide0-1-0 force=0
2013-05-17 09:04:46.234+0000: 27004: debug : qemuMonitorSend:892 : QEMU_MONITOR_SEND_MSG: mon=0x7f7ac4000bd0 msg={"execute":"eject","arguments":{"device":"drive-ide0-1-0","force":false},"id":"libvirt-7"}
 fd=-1
2013-05-17 09:04:46.234+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:46.234+0000: 27001: debug : qemuMonitorIOWrite:458 : QEMU_MONITOR_IO_WRITE: mon=0x7f7ac4000bd0 buf={"execute":"eject","arguments":{"device":"drive-ide0-1-0","force":false},"id":"libvirt-7"}
 len=92 ret=92 errno=2
2013-05-17 09:04:46.234+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:46.234+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:46.234+0000: 27001: debug : qemuMonitorIOProcess:350 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7ac4000bd0 buf={"id": "libvirt-7", "error": {"class": "GenericError", "desc": "Device 'drive-ide0-1-0' is locked"}}
 len=102
2013-05-17 09:04:46.234+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:46.235+0000: 27004: error : qemuMonitorJSONCheckError:352 : internal error unable to execute QEMU command 'eject': Device 'drive-ide0-1-0' is locked
2013-05-17 09:04:46.235+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 9
2013-05-17 09:04:46.735+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 8
2013-05-17 09:04:47.235+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 7
2013-05-17 09:04:47.735+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 6
2013-05-17 09:04:48.186+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:48.235+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 5
2013-05-17 09:04:48.735+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 4
2013-05-17 09:04:49.235+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 3
2013-05-17 09:04:49.736+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 2
2013-05-17 09:04:50.236+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 1
2013-05-17 09:04:50.736+0000: 27004: debug : qemuDomainChangeEjectableMedia:103 : Waiting 500ms for tray to open. Retries left 0
2013-05-17 09:04:51.236+0000: 27004: error : qemuDomainChangeEjectableMedia:117 : operation failed: Unable to eject media before changing it
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:51.236+0000: 27004: debug : virDomainLockDiskDetach:282 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 disk=0x7f7ac4003690
2013-05-17 09:04:51.236+0000: 27004: debug : virDomainLockManagerNew:128 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 withResources=0
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerPluginGetDriver:295 : plugin=0x7f7acc007c00
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerNew:319 : driver=0x7f7ae53dc5e0 type=0 nparams=5 params=0x7f7ad7d25830 flags=0
2013-05-17 09:04:51.236+0000: 27001: debug : qemuMonitorIOProcess:350 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7ac4000bd0 buf={"timestamp": {"seconds": 1368781488, "microseconds": 186399}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": true}}
 len=151
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel70a3
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerLogParams:88 :   key=pid type=uint value=23941
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=(null)
2013-05-17 09:04:51.236+0000: 27004: debug : virDomainLockManagerAddDisk:86 : Add disk /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerAddResource:348 : lock=0x7f7ac4001b30 type=0 name=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso nparams=0 params=(nil) flags=1
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerRelease:381 : lock=0x7f7ac4001b30 state=(nil) flags=0
2013-05-17 09:04:51.236+0000: 27004: debug : virLockManagerFree:403 : lock=0x7f7ac4001b30
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:51.236+0000: 27004: debug : qemuTeardownDiskPathDeny:104 : Process path /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso for disk
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:51.236+0000: 27004: debug : qemuDomainObjEndJob:1084 : Stopping job: modify (async=none)
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0a4160
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc09b9d0
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:51.236+0000: 27004: debug : virDomainFree:2316 : dom=0x7f7ac4000b30, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000b30
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ac4000b30
2013-05-17 09:04:51.236+0000: 27004: debug : virDomainDispose:262 : release domain 0x7f7ac4000b30 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:51.236+0000: 27001: debug : qemuMonitorEmitTrayChange:1096 : mon=0x7f7ac4000bd0
2013-05-17 09:04:51.236+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:51.236+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:51.236+0000: 27004: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:51.237+0000: 27001: debug : virFileMakePathHelper:1996 : path=/var/run/libvirt/qemu mode=0777
2013-05-17 09:04:51.237+0000: 27001: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:51.237+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:51.237+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:51.237+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:51.237+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d3bf0
2013-05-17 09:04:51.237+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d3bf0
2013-05-17 09:04:54.430+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:54.430+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.430+0000: 27005: debug : virDomainLookupByName:2152 : conn=0x7f7ac0000af0, name=rhel70a3
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7abc0008c0 classname=virDomain
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.430+0000: 27005: debug : virDomainFree:2316 : dom=0x7f7abc0008c0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7abc0008c0
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7abc0008c0
2013-05-17 09:04:54.430+0000: 27005: debug : virDomainDispose:262 : release domain 0x7f7abc0008c0 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.430+0000: 27005: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:54.431+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:54.431+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ac00020f0 classname=virDomain
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.431+0000: 27006: debug : virDomainGetXMLDesc:4381 : dom=0x7f7ac00020f0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6), flags=0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.431+0000: 27006: debug : virDomainFree:2316 : dom=0x7f7ac00020f0, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac00020f0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ac00020f0
2013-05-17 09:04:54.431+0000: 27006: debug : virDomainDispose:262 : release domain 0x7f7ac00020f0 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.431+0000: 27006: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:54.432+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:54.432+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectNew:201 : OBJECT_NEW: obj=0x7f7ab4000900 classname=virDomain
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.432+0000: 27007: debug : virDomainUpdateDeviceFlags:10058 : dom=0x7f7ab4000900, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6), xml=<disk type="file" device="cdrom">
      <driver name="qemu" type="raw"/>
      
      <target dev="hdc" bus="ide" tray="open"/>
      <readonly/>
      <alias name="ide0-1-0"/>
      <address type="drive" controller="0" bus="1" target="0" unit="0"/>
    <source file="/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso"/></disk>, flags=1
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.432+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0a4160
2013-05-17 09:04:54.432+0000: 27007: debug : qemuDomainObjBeginJobInternal:972 : Starting job: modify (async=none)
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc09b9d0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virStorageFileGetMetadata:1009 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso format=1 uid=0 gid=0 probe=0
2013-05-17 09:04:54.433+0000: 27007: debug : virStorageFileGetMetadataRecurse:941 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso format=1 uid=0 gid=0 probe=0
2013-05-17 09:04:54.433+0000: 27007: debug : virStorageFileGetMetadataInternal:689 : path=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso, fd=19, format=1
2013-05-17 09:04:54.433+0000: 27007: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : qemuSetupDiskPathAllow:58 : Process path /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso for disk
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.433+0000: 27007: debug : virDomainLockDiskAttach:254 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 disk=0x7f7ab4002b60
2013-05-17 09:04:54.433+0000: 27007: debug : virDomainLockManagerNew:128 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 withResources=0
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerPluginGetDriver:295 : plugin=0x7f7acc007c00
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerNew:319 : driver=0x7f7ae53dc5e0 type=0 nparams=5 params=0x7f7ad6522820 flags=0
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel70a3
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerLogParams:88 :   key=pid type=uint value=23941
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=qemu:///system
2013-05-17 09:04:54.433+0000: 27007: debug : virDomainLockManagerAddDisk:86 : Add disk /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerAddResource:348 : lock=0x7f7ab4000dd0 type=0 name=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso nparams=0 params=(nil) flags=1
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerAcquire:366 : lock=0x7f7ab4000dd0 state='(null)' flags=0 action=0 fd=(nil)
2013-05-17 09:04:54.433+0000: 27007: debug : virLockManagerFree:403 : lock=0x7f7ab4000dd0
2013-05-17 09:04:54.433+0000: 27007: debug : virSecurityDACParseImageIds:193 : DAC imagelabel for domain 'rhel70a3' wasn't found
2013-05-17 09:04:54.433+0000: 27007: info : virSecurityDACSetOwnership:273 : Setting DAC user and group on '/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso' to '0:0'
2013-05-17 09:04:54.433+0000: 27007: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.433+0000: 27007: debug : qemuMonitorEjectMedia:1696 : mon=0x7f7ac4000bd0 dev_name=drive-ide0-1-0 force=0
2013-05-17 09:04:54.433+0000: 27007: debug : qemuMonitorSend:892 : QEMU_MONITOR_SEND_MSG: mon=0x7f7ac4000bd0 msg={"execute":"eject","arguments":{"device":"drive-ide0-1-0","force":false},"id":"libvirt-8"}
 fd=-1
2013-05-17 09:04:54.433+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.433+0000: 27001: debug : qemuMonitorIOWrite:458 : QEMU_MONITOR_IO_WRITE: mon=0x7f7ac4000bd0 buf={"execute":"eject","arguments":{"device":"drive-ide0-1-0","force":false},"id":"libvirt-8"}
 len=92 ret=92 errno=2
2013-05-17 09:04:54.433+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.434+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.434+0000: 27001: debug : qemuMonitorIOProcess:350 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7ac4000bd0 buf={"return": {}, "id": "libvirt-8"}
 len=35
2013-05-17 09:04:54.434+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.434+0000: 27007: debug : qemuMonitorChangeMedia:1719 : mon=0x7f7ac4000bd0 dev_name=drive-ide0-1-0 newmedia=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso format=raw
2013-05-17 09:04:54.434+0000: 27007: debug : qemuMonitorSend:892 : QEMU_MONITOR_SEND_MSG: mon=0x7f7ac4000bd0 msg={"execute":"change","arguments":{"device":"drive-ide0-1-0","target":"/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso","arg":"raw"},"id":"libvirt-9"}
 fd=-1
2013-05-17 09:04:54.435+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.435+0000: 27001: debug : qemuMonitorIOWrite:458 : QEMU_MONITOR_IO_WRITE: mon=0x7f7ac4000bd0 buf={"execute":"change","arguments":{"device":"drive-ide0-1-0","target":"/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso","arg":"raw"},"id":"libvirt-9"}
 len=183 ret=183 errno=11
2013-05-17 09:04:54.435+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27001: debug : qemuMonitorIOProcess:350 : QEMU_MONITOR_IO_PROCESS: mon=0x7f7ac4000bd0 buf={"timestamp": {"seconds": 1368781494, "microseconds": 436010}, "event": "DEVICE_TRAY_MOVED", "data": {"device": "drive-ide0-1-0", "tray-open": false}}
{"return": {}, "id": "libvirt-9"}
 len=187
2013-05-17 09:04:54.436+0000: 27001: debug : qemuMonitorEmitTrayChange:1096 : mon=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.436+0000: 27001: debug : virFileMakePathHelper:1996 : path=/var/run/libvirt/qemu mode=0777
2013-05-17 09:04:54.436+0000: 27001: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac4000bd0
2013-05-17 09:04:54.436+0000: 27007: debug : virDomainLockDiskDetach:282 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 disk=0x7f7acc09aec0
2013-05-17 09:04:54.436+0000: 27007: debug : virDomainLockManagerNew:128 : plugin=0x7f7acc007c00 dom=0x7f7acc0a4160 withResources=0
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerPluginGetDriver:295 : plugin=0x7f7acc007c00
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerNew:319 : driver=0x7f7ae53dc5e0 type=0 nparams=5 params=0x7f7ad6522830 flags=0
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel70a3
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerLogParams:88 :   key=pid type=uint value=23941
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=(null)
2013-05-17 09:04:54.436+0000: 27007: debug : virDomainLockManagerAddDisk:86 : Add disk /home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerAddResource:348 : lock=0x7f7ab4001c70 type=0 name=/home/liwei/Downloads/iso/RHEL6.4GA_i386/RHEL6.4-20130130.0-Server-i386-DVD1.iso nparams=0 params=(nil) flags=1
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerRelease:381 : lock=0x7f7ab4001c70 state=(nil) flags=0
2013-05-17 09:04:54.436+0000: 27007: debug : virLockManagerFree:403 : lock=0x7f7ab4001c70
2013-05-17 09:04:54.436+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.436+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.437+0000: 27007: debug : virFileMakePathHelper:1996 : path=/var/run/libvirt/qemu mode=0777
2013-05-17 09:04:54.437+0000: 27007: debug : virFileClose:88 : Closed fd 19
2013-05-17 09:04:54.437+0000: 27007: debug : qemuDomainObjEndJob:1084 : Stopping job: modify (async=none)
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0a4160
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc09b9d0
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01f3e0
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc01b0c0
2013-05-17 09:04:54.437+0000: 27007: debug : virDomainFree:2316 : dom=0x7f7ab4000900, (VM: name=rhel70a3, uuid=1a023c45-a90d-81b9-7f60-a911d19fe9f6)
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ab4000900
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ab4000900
2013-05-17 09:04:54.437+0000: 27007: debug : virDomainDispose:262 : release domain 0x7f7ab4000900 rhel70a3 1a023c45-a90d-81b9-7f60-a911d19fe9f6
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:54.437+0000: 27007: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.293+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.293+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75c5f40
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75c5f40
2013-05-17 09:04:57.293+0000: 27003: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d3bf0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectRef:295 : OBJECT_REF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : daemonRemoveAllClientStreams:488 : stream=(nil)
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4b80
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d3bf0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ae75d3bf0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ae75d4e30
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7acc0bafe0
2013-05-17 09:04:57.294+0000: 27001: debug : virConnectClose:1484 : conn=0x7f7ac0000af0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000af0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ac0000af0
2013-05-17 09:04:57.294+0000: 27001: debug : virQEMUCloseCallbacksRun:913 : conn=0x7f7ac0000af0
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ac0000d00
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ac0000d00
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0x7f7ae75d4b80
2013-05-17 09:04:57.294+0000: 27001: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0x7f7ae75d4b80
2013-05-17 09:04:57.294+0000: 27001: debug : virFileClose:88 : Closed fd 17

[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]