function snprintf() in xen_save_domain.c for debugged

function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 399 bytes --]

Hello,
I check this function xc_domain_save.c, and attention at line 1185, function snprintf() amounts iter, sent_this_iter, skip_this_iter for print. (in xen 4.1.2 )
but i don't know where in amounts print!!! :-( 
Are except file xend.log where is another for print and debugged?
 
Adel Amani
M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir

[-- Attachment #1.2: Type: text/html, Size: 1350 bytes --]

[-- Attachment #2: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Sun, Feb 02, Adel Amani wrote:

> I check this function xc_domain_save.c, and attention at line 1185,
> function snprintf() amounts iter, sent_this_iter, skip_this_iter for
> print. (in xen 4.1.2 ) but i don't know where in amounts print!!! :-( 
> Are except file xend.log where is another for print and debugged?

The output in 4.1 is sent to xend.log, but only if a logger function is
registered. Please follow the code from tools/xcutils/xc_save.c:main to
the actual xc_report_progress_start call in
tools/libxc/xc_domain_save.c, as you will note xc_interface_open is
called without logger which means no output is printed.

For an example how a logger could look like see the xc_interface_open
call in tools/xenpaging/xenpaging.c.

Olaf

Re: function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 1097 bytes --]

Thanks, how i define logger for xc_interface_open to output print?!
can i use of code xc_save.c in xen 4.3.1 for logger in xen 4.1.2?!
 
Adel Amani
M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir



On Sunday, February 2, 2014 1:30 PM, Olaf Hering <olaf@aepfle.de> wrote:
 
On Sun, Feb 02, Adel Amani wrote:


> I check this function xc_domain_save.c, and attention at line 1185,
> function snprintf() amounts iter, sent_this_iter, skip_this_iter for
> print. (in xen 4.1.2 ) but i don't know where in amounts print!!! :-( 
> Are except file xend.log where is another for print and debugged?

The output in 4.1 is sent to xend.log, but only if a logger function is
registered. Please follow the code from tools/xcutils/xc_save.c:main to
the actual xc_report_progress_start call in
tools/libxc/xc_domain_save.c, as you will note xc_interface_open is
called without logger which means no output is printed.

For an example how a logger could look like see the xc_interface_open
call in tools/xenpaging/xenpaging.c.

Olaf

[-- Attachment #1.2: Type: text/html, Size: 2835 bytes --]

[-- Attachment #2: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Mon, Feb 03, Adel Amani wrote:

> Thanks, how i define logger for xc_interface_open to output print?!

See  the example I gave in my reply. I quoted it again (see below) for
your convenience.

> can i use of code xc_save.c in xen 4.3.1 for logger in xen 4.1.2?!

If all required changes are backported, most likely yes.

Olaf


> For an example how a logger could look like see the xc_interface_open
> call in tools/xenpaging/xenpaging.c.

Re: function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 3012 bytes --]

Hello Mr Olaf,
I tried for change code xc_save.c to attention backported, But not answer me :-(...
Should I change code xc_domain_save.c ? in this code used function 'static int print_stats(...)' .
I attache file xend.log later use of Code changing xc_save.c:main:

int
main(int argc, char **argv)
{
    unsigned int maxit, max_f, lflags; //Change, added lflags...
    int io_fd, ret, port;
    struct save_callbacks callbacks;
xentoollog_level lvl;//added...
xentoollog_logger *l;//added...

    if (argc != 6)
        errx(1, "usage: %s iofd domid maxit maxf flags", argv[0]);

    io_fd = atoi(argv[1]);
    si.domid = atoi(argv[2]);
    maxit = atoi(argv[3]);
    max_f = atoi(argv[4]);
    si.flags = atoi(argv[5]);

    si.suspend_evtchn = -1;

lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL;//added...
lflags = XTL_STDIOSTREAM_SHOW_PID | XTL_STDIOSTREAM_HIDE_PROGRESS;//added...
l = (xentoollog_logger *)xtl_createlogger_stdiostream(stderr, lvl, lflags);//added...
si.xch = xc_interface_open(l,0,0);//Change, orginal: si.xch = xc_interface_open(0,0,0);
    if (!si.xch)
        errx(1, "failed to open control interface");
    si.xce = xc_evtchn_open(NULL, 0);
    if (si.xce == NULL)
        warnx("failed to open event channel handle");
    else
    {
        port = xs_suspend_evtchn_port(si.domid);

        if (port < 0)
            warnx("failed to get the suspend evtchn port\n");
        else
        {
            si.suspend_evtchn =
              xc_suspend_evtchn_init(si.xch, si.xce, si.domid, port);

            if (si.suspend_evtchn < 0)
                warnx("suspend event channel initialization failed, "
                       "using slow path");
        }
    }
    memset(&callbacks, 0, sizeof(callbacks));
    callbacks.suspend = suspend;
    callbacks.switch_qemu_logdirty = switch_qemu_logdirty;
    ret = xc_domain_save(si.xch, io_fd, si.domid, maxit, max_f, si.flags, 
                         &callbacks, !!(si.flags & XCFLAGS_HVM)); 
 //in code xen 4.3.1 added parametr vm_generationid_addr to xc_domain_save(...) but here don't need

    if (si.suspend_evtchn > 0)
 xc_suspend_evtchn_release(si.xch, si.xce, si.domid, si.suspend_evtchn);

    if (si.xce > 0)
        xc_evtchn_close(si.xce);

    xc_interface_close(si.xch);

    return ret;
}
 
Adel Amani
M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir



On Monday, February 3, 2014 4:41 PM, Olaf Hering <olaf@aepfle.de> wrote:
 
On Mon, Feb 03, Adel Amani wrote:

> Thanks, how i define logger for xc_interface_open to output print?!

See  the example I gave in my reply. I quoted it again (see below) for
your convenience.

> can i use of code xc_save.c in xen 4.3.1 for logger in xen 4.1.2?!

If all required changes are backported, most likely yes.


Olaf


> For an example how a logger could look like see the xc_interface_open
> call in tools/xenpaging/xenpaging.c.

[-- Attachment #1.2: Type: text/html, Size: 11603 bytes --]

[-- Attachment #2: xend.log --]
[-- Type: application/octet-stream, Size: 13947 bytes --]

 File "/usr/local/lib/python2.7/dist-packages/xen/xend/XendCheckpoint.py", line 358, in restore
    raise exn
VmError: Disk image does not exist: /var/lib/libvirt/images/ubuntu11.img
[2014-02-05 08:10:56 1425] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'ubuntu11'], ['memory', 1024], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['oos', 1], ['image', ['hvm', ['kernel', 'hvmloader'], ['videoram', 4], ['serial', 'pty'], ['acpi', 1], ['apic', 1], ['boot', 'c'], ['cpuid', []], ['cpuid_check', []], ['device_model', 'qemu-dm'], ['display', ':0.0'], ['fda', ''], ['fdb', ''], ['guest_os_type', 'default'], ['hap', 1], ['hpet', 0], ['isa', 0], ['keymap', ''], ['localtime', 0], ['nographic', 0], ['opengl', 1], ['oos', 1], ['pae', 1], ['pci', []], ['pci_msitranslate', 1], ['pci_power_mgmt', 0], ['rtc_timeoffset', 0], ['sdl', 0], ['soundhw', ''], ['stdvga', 0], ['timer_mode', 1], ['usb', 1], ['usbdevice', ['host:125f:c96a']], ['vcpus', 1], ['vnc', 1], ['vncunused', 1], ['viridian', 0], ['vpt_align', 1], ['xauthority', '/root/.Xauthority'], ['xen_platform_pci', 1], ['memory_sharing', 0], ['vncpasswd', 'XXXXXXXX'], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'file:/var/lib/libvirt/images/ubuntu11.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'phy:/dev/cdrom'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['type', 'ioemu']]]])
[2014-02-05 08:10:56 1425] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2014-02-05 08:10:56 1425] DEBUG (balloon:187) Balloon: 2748304 KiB free; need 16384; done.
[2014-02-05 08:10:56 1425] DEBUG (XendDomain:476) Adding Domain: 3
[2014-02-05 08:10:56 1425] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 3 256
[2014-02-05 08:10:56 1425] DEBUG (image:339) No VNC passwd configured for vfb access
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: boot, val: c
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: fda, val: None
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: fdb, val: None
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: soundhw, val: None
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: localtime, val: 0
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: serial, val: ['pty']
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: std-vga, val: 0
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: isa, val: 0
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: acpi, val: 1
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: usb, val: 1
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: usbdevice, val: ['host:125f:c96a']
[2014-02-05 08:10:56 1425] DEBUG (image:891) args: gfx_passthru, val: None
[2014-02-05 08:10:56 1425] INFO (image:822) Need to create platform device.[domid:3]
[2014-02-05 08:10:56 1425] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x40000000, memory_static_min=0x0.
[2014-02-05 08:10:56 1425] INFO (image:182) buildDomain os=hvm dom=3 vcpus=1
[2014-02-05 08:10:56 1425] DEBUG (image:949) domid          = 3
[2014-02-05 08:10:56 1425] DEBUG (image:950) image          = /usr/lib/xen/boot/hvmloader
[2014-02-05 08:10:56 1425] DEBUG (image:951) store_evtchn   = 2
[2014-02-05 08:10:56 1425] DEBUG (image:952) memsize        = 1024
[2014-02-05 08:10:56 1425] DEBUG (image:953) target         = 1024
[2014-02-05 08:10:56 1425] DEBUG (image:954) vcpus          = 1
[2014-02-05 08:10:56 1425] DEBUG (image:955) vcpu_avail     = 1
[2014-02-05 08:10:56 1425] DEBUG (image:956) acpi           = 1
[2014-02-05 08:10:56 1425] DEBUG (image:957) apic           = 1
[2014-02-05 08:10:56 1425] INFO (XendDomainInfo:2357) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'vnc': '1'}, 'vnc': '1', 'uuid': '5434b1e7-97ab-b99e-d9d6-331b9115144f'}
[2014-02-05 08:10:56 1425] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/3/0'} to /local/domain/3/device/vfb/0.
[2014-02-05 08:10:56 1425] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vfb/0', 'uuid': '5434b1e7-97ab-b99e-d9d6-331b9115144f', 'frontend-id': '3', 'state': '1', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/3/0.
[2014-02-05 08:10:56 1425] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': 'b03c93c8-2ab3-040e-89ce-45ac7ffee317', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda', 'uname': 'file:/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w'}
[2014-02-05 08:10:56 1425] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/768'} to /local/domain/3/device/vbd/768.
[2014-02-05 08:10:56 1425] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/768', 'uuid': 'b03c93c8-2ab3-040e-89ce-45ac7ffee317', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w', 'online': '1', 'frontend-id': '3', 'type': 'file'} to /local/domain/0/backend/vbd/3/768.
[2014-02-05 08:10:57 1425] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': '555d20fc-86ca-862f-118a-9c8861ec042b', 'bootable': 0, 'driver': 'paravirtualised', 'dev': 'hdc:cdrom', 'uname': 'phy:/dev/cdrom', 'mode': 'r'}
[2014-02-05 08:10:57 1425] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/5632'} to /local/domain/3/device/vbd/5632.
[2014-02-05 08:10:57 1425] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/5632', 'uuid': '555d20fc-86ca-862f-118a-9c8861ec042b', 'bootable': '0', 'dev': 'hdc', 'state': '1', 'params': '/dev/cdrom', 'mode': 'r', 'online': '1', 'frontend-id': '3', 'type': 'phy'} to /local/domain/0/backend/vbd/3/5632.
[2014-02-05 08:10:57 1425] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3e:23:ec:62', 'type': 'ioemu', 'uuid': 'f880011a-c204-927b-d964-0a2c077b7a9f'}
[2014-02-05 08:10:57 1425] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/3/0'} to /local/domain/3/device/vif/0.
[2014-02-05 08:10:57 1425] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'ubuntu11', 'handle': '0', 'uuid': 'f880011a-c204-927b-d964-0a2c077b7a9f', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:23:ec:62', 'frontend-id': '3', 'state': '1', 'online': '1', 'frontend': '/local/domain/3/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/3/0.
[2014-02-05 08:10:57 1425] INFO (image:418) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '3', '-domain-name', 'ubuntu11', '-videoram', '4', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1', '-boot', 'c', '-serial', 'pty', '-acpi', '-usb', '-usbdevice', "['host:125f:c96a']", '-net', 'nic,vlan=1,macaddr=00:16:3e:23:ec:62,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap3.0,bridge=xenbr0', '-M', 'xenfv']
[2014-02-05 08:10:57 1425] INFO (image:467) device model pid: 4055
[2014-02-05 08:10:57 1425] INFO (image:590) waiting for sentinel_fifo
[2014-02-05 08:10:57 1425] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '9', 'uuid': '60245a54-ffdc-c6ce-4de4-1e210cb667c7', 'on_reboot': 'restart', 'start_time': '1391575257.53', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': "(hvm (kernel '') (superpages 0) (videoram 4) (hpet 0) (stdvga 0) (loader /usr/lib/xen/boot/hvmloader) (xen_platform_pci 1) (opengl 1) (rtc_timeoffset 0) (pci ()) (hap 1) (localtime 0) (timer_mode 1) (pci_msitranslate 1) (oos 1) (apic 1) (sdl 0) (usbdevice (host:125f:c96a)) (display :0.0) (vpt_align 1) (serial pty) (vncunused 1) (boot c) (pae 1) (viridian 0) (acpi 1) (vnc 1) (nographic 0) (nomigrate 0) (usb 1) (tsc_mode 0) (guest_os_type default) (device_model /usr/lib/xen/bin/qemu-dm) (pci_power_mgmt 0) (xauthority /root/.Xauthority) (isa 0) (notes (SUSPEND_CANCEL 1)))", 'name': 'ubuntu11'}
[2014-02-05 08:10:57 1425] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/60245a54-ffdc-c6ce-4de4-1e210cb667c7', 'domid': '3', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu', 'name': 'ubuntu11'}
[2014-02-05 08:10:57 1425] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/3/0'} to /local/domain/3/device/console/0.
[2014-02-05 08:10:57 1425] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/console/0', 'uuid': 'a3799d02-c53a-a4d9-3a43-ac31ac145fed', 'frontend-id': '3', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/3/0.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices tap2.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vif.
[2014-02-05 08:10:58 1425] DEBUG (DevController:144) Waiting for 0.
[2014-02-05 08:10:58 1425] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-05 08:10:58 1425] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
[2014-02-05 08:10:58 1425] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vkbd.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices ioports.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices tap.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vif2.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices console.
[2014-02-05 08:10:58 1425] DEBUG (DevController:144) Waiting for 0.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vscsi.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vbd.
[2014-02-05 08:10:58 1425] DEBUG (DevController:144) Waiting for 768.
[2014-02-05 08:10:58 1425] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/3/768/hotplug-status.
[2014-02-05 08:10:58 1425] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-05 08:10:58 1425] DEBUG (DevController:144) Waiting for 5632.
[2014-02-05 08:10:58 1425] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/3/5632/hotplug-status.
[2014-02-05 08:10:58 1425] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices irq.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vfb.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices pci.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vusb.
[2014-02-05 08:10:58 1425] DEBUG (DevController:139) Waiting for devices vtpm.
[2014-02-05 08:10:58 1425] INFO (XendDomain:1225) Domain ubuntu11 (3) unpaused.
[2014-02-05 08:18:35 1425] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen/bin/xc_save 26 3 0 0 5
[2014-02-05 08:18:35 1425] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2014-02-05 08:18:35 1425] INFO (XendCheckpoint:423) 
[2014-02-05 08:20:21 1425] DEBUG (XendCheckpoint:394) suspend
[2014-02-05 08:20:21 1425] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2014-02-05 08:20:21 1425] DEBUG (XendCheckpoint:129) Suspending 3 ...
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(suspend)
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-05 08:20:21 1425] INFO (XendDomainInfo:541) HVM save:remote shutdown dom 3!
[2014-02-05 08:20:21 1425] INFO (XendCheckpoint:135) Domain 3 suspended.
[2014-02-05 08:20:21 1425] INFO (XendDomainInfo:2078) Domain has shutdown: name=migrating-ubuntu11 id=3 reason=suspend.
[2014-02-05 08:20:21 1425] INFO (image:538) signalDeviceModel:restore dm state to running
[2014-02-05 08:20:21 1425] DEBUG (XendCheckpoint:144) Written done
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=3
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:2401) Destroying device model
[2014-02-05 08:20:21 1425] INFO (image:615) migrating-ubuntu11 device model terminated
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:2408) Releasing devices
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:2414) Removing vif/0
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:2414) Removing console/0
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:2414) Removing vbd/768
[2014-02-05 08:20:21 1425] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2014-02-05 08:20:22 1425] DEBUG (XendDomainInfo:2414) Removing vbd/5632
[2014-02-05 08:20:22 1425] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632
[2014-02-05 08:20:22 1425] DEBUG (XendDomainInfo:2414) Removing vfb/0
[2014-02-05 08:20:22 1425] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0

[-- Attachment #3: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Tue, Feb 04, Adel Amani wrote:

>     si.flags = atoi(argv[5]);

> lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL;
> lflags = XTL_STDIOSTREAM_SHOW_PID | XTL_STDIOSTREAM_HIDE_PROGRESS;
> l = (xentoollog_logger *)xtl_createlogger_stdiostream(stderr, lvl, lflags);
> si.xch = xc_interface_open(l,0,0);

Please check what XCFLAGS_DEBUG actually means, and if that condition
can ever be true without modifying also xend related code.

I guess in your exploration of how migration internally actually works
it would be easier for you to just write 'lvl = XTL_DEBUG;' and be done
with it.

Other than that, the changes you made appear to be correct.

Olaf

Re: function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 1164 bytes --]

I check XCFLAGS_DEBUG that definition at line 27 of file xenguest.h that mean's 
#define XCFLAGS_DEBUG     (1 << 1)
I done 2 migration to amount lvl = XTL_DEBUG; and lvl = XTL_DETAIL; that result attached But until i have not log of dirty page(dirty memory)and downtime :-( ...

 
Adel Amani
M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir



On Wednesday, February 5, 2014 4:09 PM, Olaf Hering <olaf@aepfle.de> wrote:
 
On Tue, Feb 04, Adel Amani wrote:

>     si.flags = atoi(argv[5]);

> lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL;
> lflags = XTL_STDIOSTREAM_SHOW_PID | XTL_STDIOSTREAM_HIDE_PROGRESS;
> l = (xentoollog_logger *)xtl_createlogger_stdiostream(stderr, lvl, lflags);
> si.xch = xc_interface_open(l,0,0);

Please check what XCFLAGS_DEBUG actually means, and if that condition
can ever be true without modifying also xend related code.

I guess in your exploration of how migration internally actually works
it would be easier for you to just write 'lvl = XTL_DEBUG;' and be done
with it.

Other than that, the changes you made appear to be correct.


Olaf

[-- Attachment #1.2: Type: text/html, Size: 3698 bytes --]

[-- Attachment #2: xend (XTL_DETAIL).log --]
[-- Type: application/octet-stream, Size: 13759 bytes --]

[2014-02-06 15:04:16 1570] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'ubuntu11'], ['memory', 1024], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['oos', 1], ['image', ['hvm', ['kernel', 'hvmloader'], ['videoram', 4], ['serial', 'pty'], ['acpi', 1], ['apic', 1], ['boot', 'c'], ['cpuid', []], ['cpuid_check', []], ['device_model', 'qemu-dm'], ['display', ':0'], ['fda', ''], ['fdb', ''], ['guest_os_type', 'default'], ['hap', 1], ['hpet', 0], ['isa', 0], ['keymap', ''], ['localtime', 0], ['nographic', 0], ['opengl', 1], ['oos', 1], ['pae', 1], ['pci', []], ['pci_msitranslate', 1], ['pci_power_mgmt', 0], ['rtc_timeoffset', 0], ['sdl', 0], ['soundhw', ''], ['stdvga', 0], ['timer_mode', 1], ['usb', 1], ['usbdevice', ['host:125f:c96a']], ['vcpus', 1], ['vnc', 1], ['vncunused', 1], ['viridian', 0], ['vpt_align', 1], ['xauthority', '/root/.Xauthority'], ['xen_platform_pci', 1], ['memory_sharing', 0], ['vncpasswd', 'XXXXXXXX'], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'file:/var/lib/libvirt/images/ubuntu11.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'phy:/dev/cdrom'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['type', 'ioemu']]]])
[2014-02-06 15:04:16 1570] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2014-02-06 15:04:16 1570] DEBUG (balloon:187) Balloon: 2748304 KiB free; need 16384; done.
[2014-02-06 15:04:16 1570] DEBUG (XendDomain:476) Adding Domain: 3
[2014-02-06 15:04:16 1570] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 3 256
[2014-02-06 15:04:16 1570] DEBUG (image:339) No VNC passwd configured for vfb access
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: boot, val: c
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: fda, val: None
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: fdb, val: None
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: soundhw, val: None
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: localtime, val: 0
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: serial, val: ['pty']
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: std-vga, val: 0
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: isa, val: 0
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: acpi, val: 1
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: usb, val: 1
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: usbdevice, val: ['host:125f:c96a']
[2014-02-06 15:04:16 1570] DEBUG (image:891) args: gfx_passthru, val: None
[2014-02-06 15:04:16 1570] INFO (image:822) Need to create platform device.[domid:3]
[2014-02-06 15:04:16 1570] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x40000000, memory_static_min=0x0.
[2014-02-06 15:04:16 1570] INFO (image:182) buildDomain os=hvm dom=3 vcpus=1
[2014-02-06 15:04:16 1570] DEBUG (image:949) domid          = 3
[2014-02-06 15:04:16 1570] DEBUG (image:950) image          = /usr/lib/xen/boot/hvmloader
[2014-02-06 15:04:16 1570] DEBUG (image:951) store_evtchn   = 2
[2014-02-06 15:04:16 1570] DEBUG (image:952) memsize        = 1024
[2014-02-06 15:04:16 1570] DEBUG (image:953) target         = 1024
[2014-02-06 15:04:16 1570] DEBUG (image:954) vcpus          = 1
[2014-02-06 15:04:16 1570] DEBUG (image:955) vcpu_avail     = 1
[2014-02-06 15:04:16 1570] DEBUG (image:956) acpi           = 1
[2014-02-06 15:04:16 1570] DEBUG (image:957) apic           = 1
[2014-02-06 15:04:16 1570] INFO (XendDomainInfo:2357) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'vnc': '1'}, 'vnc': '1', 'uuid': '2b2b7a27-88a7-44e8-e8e1-291776d28537'}
[2014-02-06 15:04:16 1570] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/3/0'} to /local/domain/3/device/vfb/0.
[2014-02-06 15:04:16 1570] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vfb/0', 'uuid': '2b2b7a27-88a7-44e8-e8e1-291776d28537', 'frontend-id': '3', 'state': '1', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/3/0.
[2014-02-06 15:04:16 1570] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': 'e0c04fb7-d5a5-0427-817d-effe8d696b3b', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda', 'uname': 'file:/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w'}
[2014-02-06 15:04:16 1570] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/768'} to /local/domain/3/device/vbd/768.
[2014-02-06 15:04:16 1570] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/768', 'uuid': 'e0c04fb7-d5a5-0427-817d-effe8d696b3b', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w', 'online': '1', 'frontend-id': '3', 'type': 'file'} to /local/domain/0/backend/vbd/3/768.
[2014-02-06 15:04:17 1570] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': '6f19eace-35a6-5785-40d8-62abf95e9a77', 'bootable': 0, 'driver': 'paravirtualised', 'dev': 'hdc:cdrom', 'uname': 'phy:/dev/cdrom', 'mode': 'r'}
[2014-02-06 15:04:17 1570] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/5632'} to /local/domain/3/device/vbd/5632.
[2014-02-06 15:04:17 1570] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/5632', 'uuid': '6f19eace-35a6-5785-40d8-62abf95e9a77', 'bootable': '0', 'dev': 'hdc', 'state': '1', 'params': '/dev/cdrom', 'mode': 'r', 'online': '1', 'frontend-id': '3', 'type': 'phy'} to /local/domain/0/backend/vbd/3/5632.
[2014-02-06 15:04:17 1570] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3e:03:63:a3', 'type': 'ioemu', 'uuid': '2170b040-3be6-7221-bb52-6d957ab0d733'}
[2014-02-06 15:04:17 1570] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/3/0'} to /local/domain/3/device/vif/0.
[2014-02-06 15:04:17 1570] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'ubuntu11', 'handle': '0', 'uuid': '2170b040-3be6-7221-bb52-6d957ab0d733', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:03:63:a3', 'frontend-id': '3', 'state': '1', 'online': '1', 'frontend': '/local/domain/3/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/3/0.
[2014-02-06 15:04:17 1570] INFO (image:418) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '3', '-domain-name', 'ubuntu11', '-videoram', '4', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1', '-boot', 'c', '-serial', 'pty', '-acpi', '-usb', '-usbdevice', "['host:125f:c96a']", '-net', 'nic,vlan=1,macaddr=00:16:3e:03:63:a3,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap3.0,bridge=xenbr0', '-M', 'xenfv']
[2014-02-06 15:04:17 1570] INFO (image:467) device model pid: 3612
[2014-02-06 15:04:17 1570] INFO (image:590) waiting for sentinel_fifo
[2014-02-06 15:04:17 1570] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '9', 'uuid': '196b1bcb-0a21-0891-41be-508013229950', 'on_reboot': 'restart', 'start_time': '1391686457.7', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': "(hvm (kernel '') (superpages 0) (videoram 4) (hpet 0) (stdvga 0) (loader /usr/lib/xen/boot/hvmloader) (xen_platform_pci 1) (opengl 1) (rtc_timeoffset 0) (pci ()) (hap 1) (localtime 0) (timer_mode 1) (pci_msitranslate 1) (oos 1) (apic 1) (sdl 0) (usbdevice (host:125f:c96a)) (display :0) (vpt_align 1) (serial pty) (vncunused 1) (boot c) (pae 1) (viridian 0) (acpi 1) (vnc 1) (nographic 0) (nomigrate 0) (usb 1) (tsc_mode 0) (guest_os_type default) (device_model /usr/lib/xen/bin/qemu-dm) (pci_power_mgmt 0) (xauthority /root/.Xauthority) (isa 0) (notes (SUSPEND_CANCEL 1)))", 'name': 'ubuntu11'}
[2014-02-06 15:04:17 1570] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/196b1bcb-0a21-0891-41be-508013229950', 'domid': '3', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu', 'name': 'ubuntu11'}
[2014-02-06 15:04:17 1570] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/3/0'} to /local/domain/3/device/console/0.
[2014-02-06 15:04:17 1570] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/console/0', 'uuid': '8608aaf1-cc13-8d72-1e55-50f20172c0ee', 'frontend-id': '3', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/3/0.
[2014-02-06 15:04:18 1570] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices tap2.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vif.
[2014-02-06 15:04:18 1570] DEBUG (DevController:144) Waiting for 0.
[2014-02-06 15:04:18 1570] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
[2014-02-06 15:04:18 1570] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vkbd.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices ioports.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices tap.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vif2.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices console.
[2014-02-06 15:04:18 1570] DEBUG (DevController:144) Waiting for 0.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vscsi.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vbd.
[2014-02-06 15:04:18 1570] DEBUG (DevController:144) Waiting for 768.
[2014-02-06 15:04:18 1570] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/3/768/hotplug-status.
[2014-02-06 15:04:18 1570] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 15:04:18 1570] DEBUG (DevController:144) Waiting for 5632.
[2014-02-06 15:04:18 1570] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/3/5632/hotplug-status.
[2014-02-06 15:04:18 1570] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices irq.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vfb.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices pci.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vusb.
[2014-02-06 15:04:18 1570] DEBUG (DevController:139) Waiting for devices vtpm.
[2014-02-06 15:04:18 1570] INFO (XendDomain:1225) Domain ubuntu11 (3) unpaused.
[2014-02-06 15:05:33 1570] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen/bin/xc_save 26 3 0 0 5
[2014-02-06 15:05:33 1570] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2014-02-06 15:05:33 1570] INFO (XendCheckpoint:423) 
[2014-02-06 15:07:19 1570] DEBUG (XendCheckpoint:394) suspend
[2014-02-06 15:07:19 1570] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2014-02-06 15:07:19 1570] DEBUG (XendCheckpoint:129) Suspending 3 ...
[2014-02-06 15:07:19 1570] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(suspend)
[2014-02-06 15:07:19 1570] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-06 15:07:19 1570] INFO (XendDomainInfo:541) HVM save:remote shutdown dom 3!
[2014-02-06 15:07:19 1570] INFO (XendCheckpoint:135) Domain 3 suspended.
[2014-02-06 15:07:19 1570] INFO (XendDomainInfo:2078) Domain has shutdown: name=migrating-ubuntu11 id=3 reason=suspend.
[2014-02-06 15:07:19 1570] INFO (image:538) signalDeviceModel:restore dm state to running
[2014-02-06 15:07:19 1570] DEBUG (XendCheckpoint:144) Written done
[2014-02-06 15:07:19 1570] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=3
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2401) Destroying device model
[2014-02-06 15:07:20 1570] INFO (image:615) migrating-ubuntu11 device model terminated
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2408) Releasing devices
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2414) Removing vif/0
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2414) Removing console/0
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2414) Removing vbd/768
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2414) Removing vbd/5632
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:2414) Removing vfb/0
[2014-02-06 15:07:20 1570] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0

[-- Attachment #3: xend(XTL_DEBUG).log --]
[-- Type: application/octet-stream, Size: 25513 bytes --]

[2014-02-06 13:27:58 1522] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'ubuntu11'], ['memory', 1024], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['oos', 1], ['image', ['hvm', ['kernel', 'hvmloader'], ['videoram', 4], ['serial', 'pty'], ['acpi', 1], ['apic', 1], ['boot', 'c'], ['cpuid', []], ['cpuid_check', []], ['device_model', 'qemu-dm'], ['display', ':0.0'], ['fda', ''], ['fdb', ''], ['guest_os_type', 'default'], ['hap', 1], ['hpet', 0], ['isa', 0], ['keymap', ''], ['localtime', 0], ['nographic', 0], ['opengl', 1], ['oos', 1], ['pae', 1], ['pci', []], ['pci_msitranslate', 1], ['pci_power_mgmt', 0], ['rtc_timeoffset', 0], ['sdl', 0], ['soundhw', ''], ['stdvga', 0], ['timer_mode', 1], ['usb', 1], ['usbdevice', ['host:125f:c96a']], ['vcpus', 1], ['vnc', 1], ['vncunused', 1], ['viridian', 0], ['vpt_align', 1], ['xauthority', '/root/.Xauthority'], ['xen_platform_pci', 1], ['memory_sharing', 0], ['vncpasswd', 'XXXXXXXX'], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'file:/var/lib/libvirt/images/ubuntu11.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'phy:/dev/cdrom'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['type', 'ioemu']]]])
[2014-02-06 13:27:58 1522] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2014-02-06 13:27:58 1522] DEBUG (balloon:187) Balloon: 2748304 KiB free; need 16384; done.
[2014-02-06 13:27:58 1522] DEBUG (XendDomain:476) Adding Domain: 3
[2014-02-06 13:27:58 1522] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 3 256
[2014-02-06 13:27:58 1522] DEBUG (image:339) No VNC passwd configured for vfb access
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: boot, val: c
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: fda, val: None
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: fdb, val: None
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: soundhw, val: None
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: localtime, val: 0
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: serial, val: ['pty']
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: std-vga, val: 0
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: isa, val: 0
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: acpi, val: 1
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: usb, val: 1
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: usbdevice, val: ['host:125f:c96a']
[2014-02-06 13:27:58 1522] DEBUG (image:891) args: gfx_passthru, val: None
[2014-02-06 13:27:58 1522] INFO (image:822) Need to create platform device.[domid:3]
[2014-02-06 13:27:58 1522] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x40000000, memory_static_min=0x0.
[2014-02-06 13:27:58 1522] INFO (image:182) buildDomain os=hvm dom=3 vcpus=1
[2014-02-06 13:27:58 1522] DEBUG (image:949) domid          = 3
[2014-02-06 13:27:58 1522] DEBUG (image:950) image          = /usr/lib/xen/boot/hvmloader
[2014-02-06 13:27:58 1522] DEBUG (image:951) store_evtchn   = 2
[2014-02-06 13:27:58 1522] DEBUG (image:952) memsize        = 1024
[2014-02-06 13:27:58 1522] DEBUG (image:953) target         = 1024
[2014-02-06 13:27:58 1522] DEBUG (image:954) vcpus          = 1
[2014-02-06 13:27:58 1522] DEBUG (image:955) vcpu_avail     = 1
[2014-02-06 13:27:58 1522] DEBUG (image:956) acpi           = 1
[2014-02-06 13:27:58 1522] DEBUG (image:957) apic           = 1
[2014-02-06 13:27:58 1522] INFO (XendDomainInfo:2357) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'vnc': '1'}, 'vnc': '1', 'uuid': 'da8a39ec-57eb-0ede-bc0b-c1d67eaa0926'}
[2014-02-06 13:27:58 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/3/0'} to /local/domain/3/device/vfb/0.
[2014-02-06 13:27:58 1522] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vfb/0', 'uuid': 'da8a39ec-57eb-0ede-bc0b-c1d67eaa0926', 'frontend-id': '3', 'state': '1', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/3/0.
[2014-02-06 13:27:58 1522] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': '4d435086-5fe4-fb6d-b5b8-f06b816783a1', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda', 'uname': 'file:/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w'}
[2014-02-06 13:27:58 1522] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/768'} to /local/domain/3/device/vbd/768.
[2014-02-06 13:27:58 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/768', 'uuid': '4d435086-5fe4-fb6d-b5b8-f06b816783a1', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w', 'online': '1', 'frontend-id': '3', 'type': 'file'} to /local/domain/0/backend/vbd/3/768.
[2014-02-06 13:27:58 1522] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': '6bd3245a-385e-cab1-5bca-a5621d8466ff', 'bootable': 0, 'driver': 'paravirtualised', 'dev': 'hdc:cdrom', 'uname': 'phy:/dev/cdrom', 'mode': 'r'}
[2014-02-06 13:27:58 1522] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/3/5632'} to /local/domain/3/device/vbd/5632.
[2014-02-06 13:27:58 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/vbd/5632', 'uuid': '6bd3245a-385e-cab1-5bca-a5621d8466ff', 'bootable': '0', 'dev': 'hdc', 'state': '1', 'params': '/dev/cdrom', 'mode': 'r', 'online': '1', 'frontend-id': '3', 'type': 'phy'} to /local/domain/0/backend/vbd/3/5632.
[2014-02-06 13:27:59 1522] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3e:03:3e:c2', 'type': 'ioemu', 'uuid': '7e4d692e-f846-964f-a34e-bfccbe9d4bcd'}
[2014-02-06 13:27:59 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/3/0'} to /local/domain/3/device/vif/0.
[2014-02-06 13:27:59 1522] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'ubuntu11', 'handle': '0', 'uuid': '7e4d692e-f846-964f-a34e-bfccbe9d4bcd', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:03:3e:c2', 'frontend-id': '3', 'state': '1', 'online': '1', 'frontend': '/local/domain/3/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/3/0.
[2014-02-06 13:27:59 1522] INFO (image:418) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '3', '-domain-name', 'ubuntu11', '-videoram', '4', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1', '-boot', 'c', '-serial', 'pty', '-acpi', '-usb', '-usbdevice', "['host:125f:c96a']", '-net', 'nic,vlan=1,macaddr=00:16:3e:03:3e:c2,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap3.0,bridge=xenbr0', '-M', 'xenfv']
[2014-02-06 13:27:59 1522] INFO (image:467) device model pid: 11124
[2014-02-06 13:27:59 1522] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '9', 'uuid': 'b1c5f1c8-9097-f3b7-9668-8474edcc338e', 'on_reboot': 'restart', 'start_time': '1391680679.42', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': "(hvm (kernel '') (superpages 0) (videoram 4) (hpet 0) (stdvga 0) (loader /usr/lib/xen/boot/hvmloader) (xen_platform_pci 1) (opengl 1) (rtc_timeoffset 0) (pci ()) (hap 1) (localtime 0) (timer_mode 1) (pci_msitranslate 1) (oos 1) (apic 1) (sdl 0) (usbdevice (host:125f:c96a)) (display :0.0) (vpt_align 1) (serial pty) (vncunused 1) (boot c) (pae 1) (viridian 0) (acpi 1) (vnc 1) (nographic 0) (nomigrate 0) (usb 1) (tsc_mode 0) (guest_os_type default) (device_model /usr/lib/xen/bin/qemu-dm) (pci_power_mgmt 0) (xauthority /root/.Xauthority) (isa 0) (notes (SUSPEND_CANCEL 1)))", 'name': 'ubuntu11'}
[2014-02-06 13:27:59 1522] INFO (image:590) waiting for sentinel_fifo
[2014-02-06 13:27:59 1522] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/b1c5f1c8-9097-f3b7-9668-8474edcc338e', 'domid': '3', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu', 'name': 'ubuntu11'}
[2014-02-06 13:27:59 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/3/0'} to /local/domain/3/device/console/0.
[2014-02-06 13:27:59 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/3/device/console/0', 'uuid': 'afa0ea9f-16fc-bc2b-ae17-9b72a4f7c246', 'frontend-id': '3', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/3/0.
[2014-02-06 13:27:59 1522] DEBUG (DevController:139) Waiting for devices tap2.
[2014-02-06 13:27:59 1522] DEBUG (DevController:139) Waiting for devices vif.
[2014-02-06 13:28:00 1522] DEBUG (DevController:144) Waiting for 0.
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-06 13:28:00 1522] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/3/0/hotplug-status.
[2014-02-06 13:28:00 1522] DEBUG (DevController:642) hotplugStatusCallback 2.
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=3
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2401) Destroying device model
[2014-02-06 13:28:00 1522] INFO (image:615) ubuntu11 device model terminated
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2408) Releasing devices
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vif/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing console/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vbd/768
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vbd/5632
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vfb/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2406) No device model
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2408) Releasing devices
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vif/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vbd/768
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:2414) Removing vbd/5632
[2014-02-06 13:28:00 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632
[2014-02-06 13:29:30 1522] DEBUG (XendDomainInfo:103) XendDomainInfo.create(['vm', ['name', 'ubuntu11'], ['memory', 1024], ['on_xend_start', 'ignore'], ['on_xend_stop', 'ignore'], ['vcpus', 1], ['oos', 1], ['image', ['hvm', ['kernel', 'hvmloader'], ['videoram', 4], ['serial', 'pty'], ['acpi', 1], ['apic', 1], ['boot', 'c'], ['cpuid', []], ['cpuid_check', []], ['device_model', 'qemu-dm'], ['display', ':0.0'], ['fda', ''], ['fdb', ''], ['guest_os_type', 'default'], ['hap', 1], ['hpet', 0], ['isa', 0], ['keymap', ''], ['localtime', 0], ['nographic', 0], ['opengl', 1], ['oos', 1], ['pae', 1], ['pci', []], ['pci_msitranslate', 1], ['pci_power_mgmt', 0], ['rtc_timeoffset', 0], ['sdl', 0], ['soundhw', ''], ['stdvga', 0], ['timer_mode', 1], ['usb', 1], ['usbdevice', ['host:125f:c96a']], ['vcpus', 1], ['vnc', 1], ['vncunused', 1], ['viridian', 0], ['vpt_align', 1], ['xauthority', '/root/.Xauthority'], ['xen_platform_pci', 1], ['memory_sharing', 0], ['vncpasswd', 'XXXXXXXX'], ['tsc_mode', 0], ['nomigrate', 0]]], ['s3_integrity', 1], ['device', ['vbd', ['uname', 'file:/var/lib/libvirt/images/ubuntu11.img'], ['dev', 'hda'], ['mode', 'w']]], ['device', ['vbd', ['uname', 'phy:/dev/cdrom'], ['dev', 'hdc:cdrom'], ['mode', 'r']]], ['device', ['vif', ['bridge', 'xenbr0'], ['type', 'ioemu']]]])
[2014-02-06 13:29:30 1522] DEBUG (XendDomainInfo:2498) XendDomainInfo.constructDomain
[2014-02-06 13:29:30 1522] DEBUG (balloon:187) Balloon: 2748304 KiB free; need 16384; done.
[2014-02-06 13:29:30 1522] DEBUG (XendDomain:476) Adding Domain: 4
[2014-02-06 13:29:30 1522] DEBUG (XendDomainInfo:2836) XendDomainInfo.initDomain: 4 256
[2014-02-06 13:29:30 1522] DEBUG (image:339) No VNC passwd configured for vfb access
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: boot, val: c
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: fda, val: None
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: fdb, val: None
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: soundhw, val: None
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: localtime, val: 0
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: serial, val: ['pty']
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: std-vga, val: 0
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: isa, val: 0
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: acpi, val: 1
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: usb, val: 1
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: usbdevice, val: ['host:125f:c96a']
[2014-02-06 13:29:30 1522] DEBUG (image:891) args: gfx_passthru, val: None
[2014-02-06 13:29:30 1522] INFO (image:822) Need to create platform device.[domid:4]
[2014-02-06 13:29:30 1522] DEBUG (XendDomainInfo:2863) _initDomain:shadow_memory=0x0, memory_static_max=0x40000000, memory_static_min=0x0.
[2014-02-06 13:29:30 1522] INFO (image:182) buildDomain os=hvm dom=4 vcpus=1
[2014-02-06 13:29:30 1522] DEBUG (image:949) domid          = 4
[2014-02-06 13:29:30 1522] DEBUG (image:950) image          = /usr/lib/xen/boot/hvmloader
[2014-02-06 13:29:30 1522] DEBUG (image:951) store_evtchn   = 2
[2014-02-06 13:29:30 1522] DEBUG (image:952) memsize        = 1024
[2014-02-06 13:29:30 1522] DEBUG (image:953) target         = 1024
[2014-02-06 13:29:30 1522] DEBUG (image:954) vcpus          = 1
[2014-02-06 13:29:30 1522] DEBUG (image:955) vcpu_avail     = 1
[2014-02-06 13:29:30 1522] DEBUG (image:956) acpi           = 1
[2014-02-06 13:29:30 1522] DEBUG (image:957) apic           = 1
[2014-02-06 13:29:30 1522] INFO (XendDomainInfo:2357) createDevice: vfb : {'vncunused': 1, 'other_config': {'vncunused': 1, 'vnc': '1'}, 'vnc': '1', 'uuid': '8ea96722-14be-84cd-9baa-c0b4e1c3bd3c'}
[2014-02-06 13:29:30 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vfb/4/0'} to /local/domain/4/device/vfb/0.
[2014-02-06 13:29:30 1522] DEBUG (DevController:97) DevController: writing {'vncunused': '1', 'domain': 'ubuntu11', 'frontend': '/local/domain/4/device/vfb/0', 'uuid': '8ea96722-14be-84cd-9baa-c0b4e1c3bd3c', 'frontend-id': '4', 'state': '1', 'online': '1', 'vnc': '1'} to /local/domain/0/backend/vfb/4/0.
[2014-02-06 13:29:30 1522] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': 'b850486e-9b26-b82e-2314-68cb8b38cd6c', 'bootable': 1, 'driver': 'paravirtualised', 'dev': 'hda', 'uname': 'file:/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w'}
[2014-02-06 13:29:30 1522] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '768', 'device-type': 'disk', 'state': '1', 'backend': '/local/domain/0/backend/vbd/4/768'} to /local/domain/4/device/vbd/768.
[2014-02-06 13:29:30 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/4/device/vbd/768', 'uuid': 'b850486e-9b26-b82e-2314-68cb8b38cd6c', 'bootable': '1', 'dev': 'hda', 'state': '1', 'params': '/var/lib/libvirt/images/ubuntu11.img', 'mode': 'w', 'online': '1', 'frontend-id': '4', 'type': 'file'} to /local/domain/0/backend/vbd/4/768.
[2014-02-06 13:29:30 1522] INFO (XendDomainInfo:2357) createDevice: vbd : {'uuid': 'a331278d-2f5f-5d73-4e9e-0c7871d60670', 'bootable': 0, 'driver': 'paravirtualised', 'dev': 'hdc:cdrom', 'uname': 'phy:/dev/cdrom', 'mode': 'r'}
[2014-02-06 13:29:30 1522] DEBUG (DevController:95) DevController: writing {'backend-id': '0', 'virtual-device': '5632', 'device-type': 'cdrom', 'state': '1', 'backend': '/local/domain/0/backend/vbd/4/5632'} to /local/domain/4/device/vbd/5632.
[2014-02-06 13:29:30 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/4/device/vbd/5632', 'uuid': 'a331278d-2f5f-5d73-4e9e-0c7871d60670', 'bootable': '0', 'dev': 'hdc', 'state': '1', 'params': '/dev/cdrom', 'mode': 'r', 'online': '1', 'frontend-id': '4', 'type': 'phy'} to /local/domain/0/backend/vbd/4/5632.
[2014-02-06 13:29:30 1522] INFO (XendDomainInfo:2357) createDevice: vif : {'bridge': 'xenbr0', 'mac': '00:16:3e:68:56:79', 'type': 'ioemu', 'uuid': '6e61d624-7eda-7dd7-19c6-dd58e9a93738'}
[2014-02-06 13:29:30 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/vif/4/0'} to /local/domain/4/device/vif/0.
[2014-02-06 13:29:30 1522] DEBUG (DevController:97) DevController: writing {'bridge': 'xenbr0', 'domain': 'ubuntu11', 'handle': '0', 'uuid': '6e61d624-7eda-7dd7-19c6-dd58e9a93738', 'script': '/etc/xen/scripts/vif-bridge', 'mac': '00:16:3e:68:56:79', 'frontend-id': '4', 'state': '1', 'online': '1', 'frontend': '/local/domain/4/device/vif/0', 'type': 'ioemu'} to /local/domain/0/backend/vif/4/0.
[2014-02-06 13:29:31 1522] INFO (image:418) spawning device models: /usr/lib/xen/bin/qemu-dm ['/usr/lib/xen/bin/qemu-dm', '-d', '4', '-domain-name', 'ubuntu11', '-videoram', '4', '-vnc', '127.0.0.1:0', '-vncunused', '-vcpus', '1', '-vcpu_avail', '0x1', '-boot', 'c', '-serial', 'pty', '-acpi', '-usb', '-usbdevice', "['host:125f:c96a']", '-net', 'nic,vlan=1,macaddr=00:16:3e:68:56:79,model=rtl8139', '-net', 'tap,vlan=1,ifname=tap4.0,bridge=xenbr0', '-M', 'xenfv']
[2014-02-06 13:29:31 1522] INFO (image:467) device model pid: 12254
[2014-02-06 13:29:31 1522] INFO (image:590) waiting for sentinel_fifo
[2014-02-06 13:29:31 1522] DEBUG (XendDomainInfo:3420) Storing VM details: {'on_xend_stop': 'ignore', 'pool_name': 'Pool-0', 'shadow_memory': '9', 'uuid': '5da47686-44a8-3ad4-d238-142c961fd72c', 'on_reboot': 'restart', 'start_time': '1391680771.04', 'on_poweroff': 'destroy', 'bootloader_args': '', 'on_xend_start': 'ignore', 'on_crash': 'restart', 'xend/restart_count': '0', 'vcpus': '1', 'vcpu_avail': '1', 'bootloader': '', 'image': "(hvm (kernel '') (superpages 0) (videoram 4) (hpet 0) (stdvga 0) (loader /usr/lib/xen/boot/hvmloader) (xen_platform_pci 1) (opengl 1) (rtc_timeoffset 0) (pci ()) (hap 1) (localtime 0) (timer_mode 1) (pci_msitranslate 1) (oos 1) (apic 1) (sdl 0) (usbdevice (host:125f:c96a)) (display :0.0) (vpt_align 1) (serial pty) (vncunused 1) (boot c) (pae 1) (viridian 0) (acpi 1) (vnc 1) (nographic 0) (nomigrate 0) (usb 1) (tsc_mode 0) (guest_os_type default) (device_model /usr/lib/xen/bin/qemu-dm) (pci_power_mgmt 0) (xauthority /root/.Xauthority) (isa 0) (notes (SUSPEND_CANCEL 1)))", 'name': 'ubuntu11'}
[2014-02-06 13:29:31 1522] DEBUG (XendDomainInfo:1794) Storing domain details: {'console/port': '3', 'description': '', 'console/limit': '1048576', 'store/port': '2', 'vm': '/vm/5da47686-44a8-3ad4-d238-142c961fd72c', 'domid': '4', 'image/suspend-cancel': '1', 'cpu/0/availability': 'online', 'memory/target': '1048576', 'control/platform-feature-multiprocessor-suspend': '1', 'store/ring-ref': '1044476', 'console/type': 'ioemu', 'name': 'ubuntu11'}
[2014-02-06 13:29:31 1522] DEBUG (DevController:95) DevController: writing {'state': '1', 'backend-id': '0', 'backend': '/local/domain/0/backend/console/4/0'} to /local/domain/4/device/console/0.
[2014-02-06 13:29:31 1522] DEBUG (DevController:97) DevController: writing {'domain': 'ubuntu11', 'frontend': '/local/domain/4/device/console/0', 'uuid': 'b3940ee2-08de-171a-ac85-a1546507471f', 'frontend-id': '4', 'state': '1', 'location': '3', 'online': '1', 'protocol': 'vt100'} to /local/domain/0/backend/console/4/0.
[2014-02-06 13:29:31 1522] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices tap2.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vif.
[2014-02-06 13:29:31 1522] DEBUG (DevController:144) Waiting for 0.
[2014-02-06 13:29:31 1522] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vif/4/0/hotplug-status.
[2014-02-06 13:29:31 1522] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vkbd.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices ioports.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices tap.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vif2.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices console.
[2014-02-06 13:29:31 1522] DEBUG (DevController:144) Waiting for 0.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vscsi.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vbd.
[2014-02-06 13:29:31 1522] DEBUG (DevController:144) Waiting for 768.
[2014-02-06 13:29:31 1522] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/4/768/hotplug-status.
[2014-02-06 13:29:31 1522] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 13:29:31 1522] DEBUG (DevController:144) Waiting for 5632.
[2014-02-06 13:29:31 1522] DEBUG (DevController:628) hotplugStatusCallback /local/domain/0/backend/vbd/4/5632/hotplug-status.
[2014-02-06 13:29:31 1522] DEBUG (DevController:642) hotplugStatusCallback 1.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices irq.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vfb.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices pci.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vusb.
[2014-02-06 13:29:31 1522] DEBUG (DevController:139) Waiting for devices vtpm.
[2014-02-06 13:29:31 1522] INFO (XendDomain:1225) Domain ubuntu11 (4) unpaused.
[2014-02-06 13:30:52 1522] DEBUG (XendCheckpoint:124) [xc_save]: /usr/lib/xen/bin/xc_save 28 4 0 0 5
[2014-02-06 13:30:52 1522] INFO (XendCheckpoint:423) xc_save: failed to get the suspend evtchn port
[2014-02-06 13:30:52 1522] INFO (XendCheckpoint:423) 
[2014-02-06 13:32:38 1522] DEBUG (XendCheckpoint:394) suspend
[2014-02-06 13:32:38 1522] DEBUG (XendCheckpoint:127) In saveInputHandler suspend
[2014-02-06 13:32:38 1522] DEBUG (XendCheckpoint:129) Suspending 4 ...
[2014-02-06 13:32:38 1522] DEBUG (XendDomainInfo:524) XendDomainInfo.shutdown(suspend)
[2014-02-06 13:32:38 1522] DEBUG (XendDomainInfo:1881) XendDomainInfo.handleShutdownWatch
[2014-02-06 13:32:38 1522] INFO (XendDomainInfo:541) HVM save:remote shutdown dom 4!
[2014-02-06 13:32:38 1522] INFO (XendCheckpoint:135) Domain 4 suspended.
[2014-02-06 13:32:38 1522] INFO (XendDomainInfo:2078) Domain has shutdown: name=migrating-ubuntu11 id=4 reason=suspend.
[2014-02-06 13:32:38 1522] INFO (image:538) signalDeviceModel:restore dm state to running
[2014-02-06 13:32:38 1522] DEBUG (XendCheckpoint:144) Written done
[2014-02-06 13:32:38 1522] DEBUG (XendDomainInfo:3071) XendDomainInfo.destroy: domid=4
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2401) Destroying device model
[2014-02-06 13:32:39 1522] INFO (image:615) migrating-ubuntu11 device model terminated
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2408) Releasing devices
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2414) Removing vif/0
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vif, device = vif/0
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2414) Removing console/0
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = console, device = console/0
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2414) Removing vbd/768
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/768
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2414) Removing vbd/5632
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vbd, device = vbd/5632
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:2414) Removing vfb/0
[2014-02-06 13:32:39 1522] DEBUG (XendDomainInfo:1276) XendDomainInfo.destroyDevice: deviceClass = vfb, device = vfb/0

[-- Attachment #4: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Thu, Feb 06, Adel Amani wrote:

> I check XCFLAGS_DEBUG that definition at line 27 of file xenguest.h that
> mean's 
> #define XCFLAGS_DEBUG (1 << 1)
> I done 2 migration to amount lvl = XTL_DEBUG; and lvl = XTL_DETAIL; that result
> attached But until i have not log of dirty page(dirty memory)and downtime :-(

Did you already follow the code paths to see why nothing is printed?
Does a simpe 'fprintf(stderr,"STDERR\n"); fprintf(stdout,"STDOUT\n");'
appear in xend.log?

The downtime is the time during domU suspend/resume. Its best measuered
by looking at domU dmesg. Boot the domU with 'initcall_debug' to get an
understanding whats going on during such a suspend/resume cycle.

Olaf

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

Please keep xen-devel@lists.xen.org in CC list.

On Fri, Feb 07, Adel Amani wrote:

> yes, for print data, function print_stats() in xc_domain_save.c should run and
> work. I read in function and check.... But i don't know really why this don't
> work!!! :-|... I test 'fprintf(stderr,"STDERR\n"); fprintf(stdout,"STDOUT\n");'
> But again not answer :'(.....

Please make sure the self-compiled binary is actually used. Try this to
verify: grep STDERR /usr/lib/xen/bin/xc_save (assuming the fprintf above
is actually in the compiled code.)

> how boot the domU with 'initcall_debug'?! Are affect on total time?!

This is a kernel cmdline option. Please check the documentation about
how to pass additional kernel parameters to a domU.

Olaf

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Tue, Feb 18, Adel Amani wrote:

> Hi
> i know because a lot of product output in xen-4.1, no logger in xc_save.c
> i change code again according to "http://xen.1045712.n5.nabble.com/
> xen-unstable-tools-xc-restore-logging-in-xc-save-td5714324.html"
> But i don't know purpose Mr "patchbot" of 
> @@ -185,6 +183,13 @@ main(int argc, char **argv)
> i test again to code Mr "patchbot" without Consideration 
> @@ -185,6 +183,13 @@ main(int argc, char **argv)
> and result again no message :-( ....


All that is very imprecise, so we can not help.
Also read what I wrote: dont drop xen-devel@lists.xen.org

Olaf



> On Saturday, February 8, 2014 1:52 AM, Olaf Hering <olaf@aepfle.de> wrote:
> Please keep xen-devel@lists.xen.org in CC list.
> 
> On Fri, Feb 07, Adel Amani wrote:
> 
> > yes, for print data, function print_stats() in xc_domain_save.c should run
> and
> > work. I read in function and check.... But i don't know really why this don't
> > work!!! :-|... I test 'fprintf(stderr,"STDERR\n"); fprintf(stdout,"STDOUT\
> n");'
> > But again not answer :'(.....
> 
> Please make sure the self-compiled binary is actually used. Try this to
> verify: grep STDERR /usr/lib/xen/bin/xc_save (assuming the fprintf above
> is actually in the compiled code.)
> 
> > how boot the domU with 'initcall_debug'?! Are affect on total time?!
> 
> This is a kernel cmdline option. Please check the documentation about
> how to pass additional kernel parameters to a domU.
> 
> 
> Olaf
> 
> 

Re: function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 3262 bytes --]

i change code xc_save.c as follows:

 int 
 main(int argc, char **argv) 
 { 
-    unsigned int maxit, max_f; 
+    unsigned int maxit, max_f, lflags; 
     int io_fd, ret, port; 
     struct save_callbacks callbacks; 
+    xentoollog_level lvl; 
+    xentoollog_logger *l; 
  
     if (argc != 6) 
         errx(1, "usage: %s iofd domid maxit maxf flags", argv[0]); 
  
-    si.xch = xc_interface_open(0,0,0); 
-    if (!si.xch) 
-        errx(1, "failed to open control interface"); 
- 
     io_fd = atoi(argv[1]); 
     si.domid = atoi(argv[2]); 
     maxit = atoi(argv[3]); 
@@ -185,6 +183,13 @@ main(int argc, char **argv) 
  
     si.suspend_evtchn = -1; 
  
+    lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL; 
+    lflags = XTL_STDIOSTREAM_HIDE_PROGRESS; 
+    l = (xentoollog_logger *)xtl_createlogger_stdiostream(stderr, lvl, lflags); 
+    si.xch = xc_interface_open(l, 0, 0); 
+    if (!si.xch) 
+        errx(1, "failed to open control interface"); 
+ 
     si.xce = xc_evtchn_open(NULL, 0); 
     if (si.xce == NULL) 
         warnx("failed to open event channel handle"); 


==============================================================
in this code change lflags to  lflags = XTL_STDIOSTREAM_HIDE_PROGRESS; and i don't know what mean's @@ -185,6 +183,13 @@ main(int argc, char **argv)    ?!!

 so i delete @@ -185,6 +183,13 @@ main(int argc, char **argv) from code and test logger (to live migration of  VM) But again result output in xend.log don't change.
 
 
Adel Amani
M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir



On Wednesday, February 19, 2014 5:42 PM, Olaf Hering <olaf@aepfle.de> wrote:
 
On Tue, Feb 18, Adel Amani wrote:

> Hi
> i know because a lot of product output in xen-4.1, no logger in xc_save.c
> i change code again according to "http://xen.1045712.n5.nabble.com/
> xen-unstable-tools-xc-restore-logging-in-xc-save-td5714324.html"
> But i don't know purpose Mr "patchbot" of 
> @@ -185,6 +183,13 @@ main(int argc, char **argv)
> i test again to code Mr "patchbot" without Consideration 
> @@ -185,6 +183,13 @@ main(int argc, char **argv)
> and result again no message :-( ....


All that is very imprecise, so we can not help.
Also read what I wrote: dont drop xen-devel@lists.xen.org

Olaf




> On Saturday, February 8, 2014 1:52 AM, Olaf Hering <olaf@aepfle.de> wrote:
> Please keep xen-devel@lists.xen.org in CC list.
> 
> On Fri, Feb 07, Adel Amani wrote:
> 
> > yes, for print data, function print_stats() in xc_domain_save.c should run
> and
> > work. I read in function and check.... But i don't know really why this don't
> > work!!! :-|... I test 'fprintf(stderr,"STDERR\n"); fprintf(stdout,"STDOUT\
> n");'
> > But again not answer :'(.....
> 
> Please make sure the self-compiled binary is actually used. Try this to
> verify: grep STDERR /usr/lib/xen/bin/xc_save (assuming the fprintf above
> is actually in the compiled code.)
> 
> > how boot the domU with 'initcall_debug'?! Are affect on total time?!
> 
> This is a kernel cmdline option. Please check the documentation about
> how to pass additional kernel parameters to a domU.
> 
> 
> Olaf
> 
> 

[-- Attachment #1.2: Type: text/html, Size: 13323 bytes --]

[-- Attachment #2: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Thu, Feb 20, Adel Amani wrote:

> +    lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL; 

Please follow the code and check how si.flags gets its values.

The "@@ " markers are from diff(1), so that patch(1) can do its work.

Olaf

Re: function snprintf() in xen_save_domain.c for debugged

[Adel Amani]

[-- Attachment #1.1: Type: text/plain, Size: 694 bytes --]

can you please explain in more details about: The "@@ " markers are from diff(1), so that patch(1) can do its work.what line code i need to add?   
do i need to add these lines of code:
max_f = atoi(argv[4]);
    si.flags = atoi(argv[5]);
 
Adel Amani

M.Sc. Candidate@Computer Engineering Department, University of Isfahan
Email: A.Amani@eng.ui.ac.ir



On Friday, February 21, 2014 1:00 PM, Olaf Hering <olaf@aepfle.de> wrote:
 
On Thu, Feb 20, Adel Amani wrote:

> +    lvl = si.flags & XCFLAGS_DEBUG ? XTL_DEBUG: XTL_DETAIL; 

Please follow the code and check how si.flags gets its values.

The "@@ " markers are from diff(1), so that patch(1) can do its work.


Olaf

[-- Attachment #1.2: Type: text/html, Size: 3596 bytes --]

[-- Attachment #2: Type: text/plain, Size: 126 bytes --]

_______________________________________________
Xen-devel mailing list
Xen-devel@lists.xen.org
http://lists.xen.org/xen-devel

Re: function snprintf() in xen_save_domain.c for debugged

[Olaf Hering]

On Fri, Feb 21, Adel Amani wrote:

> can you please explain in more details about: The "@@ " markers are from diff
> (1), so that patch(1) can do its work.what line code i need to add?   

http://en.wikipedia.org/wiki/Diff
http://en.wikipedia.org/wiki/Patch_%28Unix%29

> do i need to add these lines of code:
> max_f = atoi(argv[4]);
>     si.flags = atoi(argv[5]);

Counter question: how will si.flags get its value without an assignment?

Please go and find a mentor to get this task done.
This list is the wrong forum to learn hacking source code.

Olaf