Attachment 649583 Details for Bug 879159 – both the source and destination of qemu logs output.

both the source and destination of qemu logs output.

the qemu output logs.txt (text/plain), 11.92 KB, created by Sibiao Luo on 2012-11-22 08:56:54 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Sibiao Luo

Created: 2012-11-22 08:56:54 UTC

Size: 11.92 KB

patch

obsolete

>=====source qemu=====
>/usr/libexec/qemu-kvm -M rhel6.4.0 -cpu SandyBridge -enable-kvm -m 2048 -smp 2,sockets=1,cores=2,threads=1 -name use_device -uuid 990ea161-6b67-47b2-b803-19fb01d30d30 -rtc base=localtime,clock=host,driftfix=slew -device virtio-serial-pci,id=virtio-serial0,max_ports=16,bus=pci.0,addr=0x3 -chardev socket,id=channel1,path=/tmp/helloworld1,server,nowait -device virtserialport,chardev=channel1,name=com.redhat.rhevm.vdsm,bus=virtio-serial0.0,id=port1 -chardev socket,id=channel2,path=/tmp/helloworld2,server,nowait -device virtserialport,chardev=channel2,name=com.redhat.rhevm.vdsm,bus=virtio-serial0.0,id=port2 -drive file=/home/RHEL6.4-20121106.0-6.4-x86_64.qcow2,if=none,id=drive-scsi-disk,format=qcow2,cache=none,werror=stop,rerror=stop -device virtio-scsi-pci,id=scsi0,addr=0x4 -device scsi-disk,drive=drive-scsi-disk,bus=scsi0.0,scsi-id=0,lun=0,id=scsi-disk,bootindex=1 -netdev tap,id=hostnet0,vhost=on,script=/etc/qemu-ifup -device virtio-net-pci,netdev=hostnet0,id=virtio-net-pci0,mac=08:2E:5F:0A:0D:B1,bus=pci.0,addr=0x5,bootindex=2 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x6 -global PIIX4_PM.disable_s3=0 -global PIIX4_PM.disable_s4=0 -spice port=5931,disable-ticketing,seamless-migration=on -vga qxl -global qxl-vga.vram_size=67108864 -nodefaults -boot menu=on -monitor stdio -device usb-ehci,id=ehci -drive file=/dev/vg-90.100-sluo/lv-90-100-usb-storage1,if=none,format=qcow2,cache=none,werror=stop,rerror=stop,aio=native,id=usb-stick -device usb-storage,bus=ehci.0,drive=usb-stick,id=usb-disk,bootindex=3
>[Thread debugging using libthread_db enabled]
>Detaching after fork from child process 30528.
>QEMU 0.12.1 monitor - type 'help' for more information
>(qemu) main_channel_link: add main channel client
>main_channel_handle_parsed: net test: latency 0.108000 ms, bitrate 11702857142 bps (11160.714285 Mbps)
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer:        
>(qemu) __com.redhat_spice_migrate_info 10.66.11.229 5931
>main_channel_client_handle_migrate_connected: client 0x7ffff8aaaa90 connected: 1 seamless 1  
>(qemu) migrate -d tcp:10.66.11.229:5888
>(qemu) info migrate 
>Migration status: active
>transferred ram: 52831 kbytes
>remaining ram: 2047504 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 73143 kbytes
>remaining ram: 1323864 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 88187 kbytes
>remaining ram: 620796 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 101339 kbytes
>remaining ram: 606088 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 219760 kbytes
>remaining ram: 489300 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 239496 kbytes
>remaining ram: 469420 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 252652 kbytes
>remaining ram: 454964 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 265805 kbytes
>remaining ram: 439656 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate 
>Migration status: active
>transferred ram: 423687 kbytes
>remaining ram: 273560 kbytes
>total ram: 2228624 kbytes
>(qemu) info migrate red_client_migrate: migrate client with #channels 4
>red_dispatcher_cursor_migrate: channel type 4 id 0
>red_dispatcher_display_migrate: channel type 2 id 0
>
>Migration status: completed
>(qemu) red_channel_client_disconnect: 0x7ffff949acd0 (channel 0x7ffff89c8ab0 type 3 id 0)
>red_channel_client_disconnect: 0x7fffec385010 (channel 0x7fff4c21d0b0 type 2 id 0)
>red_channel_client_disconnect: 0x7ffff9485970 (channel 0x7ffff89be4f0 type 1 id 0)
>main_channel_client_on_disconnect: rcc=0x7ffff9485970
>red_client_destroy: destroy client with #channels 4
>red_dispatcher_disconnect_cursor_peer: 
>red_channel_client_disconnect: 0x7fff4c26eaa0 (channel 0x7fff4c21d670 type 4 id 0)
>red_channel_client_disconnect: 0x7fff4c26eaa0 (channel 0x7fff4c21d670 type 4 id 0)
>red_channel_client_disconnect: 0x7fff4c26eaa0 (channel 0x7fff4c21d670 type 4 id 0)
>red_channel_client_disconnect: 0x7ffff949acd0 (channel 0x7ffff89c8ab0 type 3 id 0)
>red_channel_client_disconnect: 0x7ffff949acd0 (channel 0x7ffff89c8ab0 type 3 id 0)
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: 0x7fffec385010 (channel 0x7fff4c21d0b0 type 2 id 0)
>red_channel_client_disconnect: 0x7fffec385010 (channel 0x7fff4c21d0b0 type 2 id 0)
>red_channel_client_disconnect: 0x7ffff9485970 (channel 0x7ffff89be4f0 type 1 id 0)
>red_channel_client_disconnect: 0x7ffff9485970 (channel 0x7ffff89be4f0 type 1 id 0)
>
>(qemu) info migrate 
>Migration status: completed
>
>(qemu) q
>
>
>=====destination qemu=====
>/usr/libexec/qemu-kvm -M rhel6.4.0 -cpu SandyBridge -enable-kvm -m 2048 -smp 2,sockets=1,cores=2,threads=1 -name use_device -uuid 990ea161-6b67-47b2-b803-19fb01d30d30 -rtc base=localtime,clock=host,driftfix=slew -device virtio-serial-pci,id=virtio-serial0,max_ports=16,bus=pci.0,addr=0x3 -chardev socket,id=channel1,path=/tmp/helloworld1,server,nowait -device virtserialport,chardev=channel1,name=com.redhat.rhevm.vdsm,bus=virtio-serial0.0,id=port1 -chardev socket,id=channel2,path=/tmp/helloworld2,server,nowait -device virtserialport,chardev=channel2,name=com.redhat.rhevm.vdsm,bus=virtio-serial0.0,id=port2 -drive file=/home/RHEL6.4-20121106.0-6.4-x86_64.qcow2,if=none,id=drive-scsi-disk,format=qcow2,cache=none,werror=stop,rerror=stop -device virtio-scsi-pci,id=scsi0,addr=0x4 -device scsi-disk,drive=drive-scsi-disk,bus=scsi0.0,scsi-id=0,lun=0,id=scsi-disk,bootindex=1 -netdev tap,id=hostnet0,vhost=on,script=/etc/qemu-ifup -device virtio-net-pci,netdev=hostnet0,id=virtio-net-pci0,mac=08:2E:5F:0A:0D:B1,bus=pci.0,addr=0x5,bootindex=2 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x6 -global PIIX4_PM.disable_s3=0 -global PIIX4_PM.disable_s4=0 -spice port=5931,disable-ticketing,seamless-migration=on -vga qxl -global qxl-vga.vram_size=67108864 -nodefaults -boot menu=on -monitor stdio -device usb-ehci,id=ehci -drive file=/dev/vg-90.100-sluo/lv-90-100-usb-storage1,if=none,format=qcow2,cache=none,werror=stop,rerror=stop,aio=native,id=usb-stick -device usb-storage,bus=ehci.0,drive=usb-stick,id=usb-disk,bootindex=3 -incoming tcp:0:5888,server,nowait
>[Thread debugging using libthread_db enabled]
>Detaching after fork from child process 23976.
>QEMU 0.12.1 monitor - type 'help' for more information
>(qemu) [Thread 0x7ffff005f700 (LWP 23983) exited]
>main_channel_link: add main channel client
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>red_dispatcher_loadvm_commands: 
>[New Thread 0x7ffff005f700 (LWP 23996)]
>[New Thread 0x7fffe63fc700 (LWP 23997)]
>
>(qemu) info status 
>VM status: running
>(qemu) red_client_destroy: destroy client with #channels 4
>red_dispatcher_disconnect_cursor_peer: 
>red_channel_client_disconnect: 0x7fff4c22c080 (channel 0x7fff4c21d670 type 4 id 0)
>red_channel_client_disconnect: 0x7fff4c22c080 (channel 0x7fff4c21d670 type 4 id 0)
>red_channel_client_disconnect: 0x7ffff9481c30 (channel 0x7ffff89c8ab0 type 3 id 0)
>red_channel_client_disconnect: 0x7ffff9481c30 (channel 0x7ffff89c8ab0 type 3 id 0)
>red_dispatcher_disconnect_display_peer: 
>red_channel_client_disconnect: 0x7fffec49f010 (channel 0x7fff4c21d0b0 type 2 id 0)
>red_channel_client_disconnect: 0x7fffec49f010 (channel 0x7fff4c21d0b0 type 2 id 0)
>red_channel_client_disconnect: 0x7ffff947b7f0 (channel 0x7ffff89be4f0 type 1 id 0)
>main_channel_client_on_disconnect: rcc=0x7ffff947b7f0
>red_channel_client_disconnect: 0x7ffff947b7f0 (channel 0x7ffff89be4f0 type 1 id 0)
>main_channel_link: add main channel client
>main_channel_handle_parsed: net test: invalid values, latency 0 roundtrip 235. assuming highbandwidth
>inputs_connect: inputs channel client create
>red_dispatcher_set_cursor_peer: 
>[Thread 0x7ffff005f700 (LWP 23996) exited]
>[New Thread 0x7ffff005f700 (LWP 24006)]
>
>(qemu)  
>(qemu) info status 
>VM status: running
>(qemu) [New Thread 0x7fffe59fb700 (LWP 24007)]
>(/usr/bin/gdb:23971): SpiceWorker-CRITICAL **: red_worker.c:5467:lz_usr_error: stride != width*bytes_per_pixel (rgb) 448 != 445 * 1 (11)
>
>Detaching after fork from child process 24008.
>
>Program received signal SIGABRT, Aborted.
>[Switching to Thread 0x7fffe75fe700 (LWP 23986)]
>0x00007ffff57468a5 in raise () from /lib64/libc.so.6
>Missing separate debuginfos, use: debuginfo-install alsa-lib-1.0.22-3.el6.x86_64 celt051-0.5.1.3-0.el6.x86_64 cyrus-sasl-gssapi-2.1.23-13.el6.x86_64 cyrus-sasl-lib-2.1.23-13.el6.x86_64 cyrus-sasl-md5-2.1.23-13.el6.x86_64 cyrus-sasl-plain-2.1.23-13.el6.x86_64 db4-4.7.25-17.el6.x86_64 dbus-libs-1.2.24-7.el6_3.x86_64 flac-1.2.1-6.1.el6.x86_64 glib2-2.22.5-7.el6.x86_64 glibc-2.12-1.106.el6.x86_64 gnutls-2.8.5-9.el6.x86_64 keyutils-libs-1.4-4.el6.x86_64 krb5-libs-1.10.3-6.el6.x86_64 libICE-1.0.6-1.el6.x86_64 libSM-1.2.1-2.el6.x86_64 libX11-1.5.0-4.el6.x86_64 libXau-1.0.6-4.el6.x86_64 libXext-1.3.1-2.el6.x86_64 libXi-1.6.1-2.el6.x86_64 libXtst-1.2.1-2.el6.x86_64 libaio-0.3.107-10.el6.x86_64 libasyncns-0.8-1.1.el6.x86_64 libcom_err-1.41.12-14.el6.x86_64 libgcrypt-1.4.5-9.el6_2.2.x86_64 libgpg-error-1.7-4.el6.x86_64 libjpeg-turbo-1.2.1-1.el6.x86_64 libogg-1.1.4-2.1.el6.x86_64 libselinux-2.0.94-5.3.el6.x86_64 libsndfile-1.0.20-5.el6.x86_64 libtasn1-2.3-3.el6_2.1.x86_64 libuuid-2.17.2-12.8.el6.x86_64 libvorbis-1.2.3-4.el6_2.1.x86_64 libxcb-1.8.1-1.el6.x86_64 nss-softokn-freebl-3.12.9-11.el6.x86_64 openssl-1.0.0-27.el6.x86_64 pixman-0.26.2-4.el6.x86_64 pulseaudio-libs-0.9.21-14.el6_3.x86_64 tcp_wrappers-libs-7.6-57.el6.x86_64 usbredir-0.5.1-1.el6.x86_64 zlib-1.2.3-29.el6.x86_64
>(gdb) bt
>#0  0x00007ffff57468a5 in raise () from /lib64/libc.so.6
>#1  0x00007ffff5748085 in abort () from /lib64/libc.so.6
>#2  0x00007ffff5fa0c15 in spice_logv (log_domain=0x7ffff601cd7c "SpiceWorker", log_level=SPICE_LOG_LEVEL_CRITICAL, strloc=0x7ffff601d139 "red_worker.c:5467", 
>    function=0x7ffff601ee8e "lz_usr_error", format=0x7ffff602916a "%s", args=0x7fffe75fd370) at log.c:109
>#3  0x00007ffff5fa0d4a in spice_log (log_domain=<value optimized out>, log_level=<value optimized out>, strloc=<value optimized out>, function=<value optimized out>, 
>    format=<value optimized out>) at log.c:123
>#4  0x00007ffff5f656b4 in lz_usr_error (usr=0x7fff4c1d7720, fmt=<value optimized out>) at red_worker.c:5467
>#5  0x00007ffff5fa5a8e in lz_encode (lz=0x7ffff7c2e010, type=LZ_IMAGE_TYPE_A8, width=445, height=184, top_down=1, lines=0x0, num_lines=0, stride=448, 
>    io_ptr=0x7fff4c260ac0 "  ZL", num_io_bytes=65536) at lz.c:524
>#6  0x00007ffff5f6cda0 in red_lz_compress_image (dcc=0x7fff4c270ce0, dest=0x7fffe75fd780, src=0x7fffe75fd7c0, o_comp_data=0x7fffe75fd7f0, group_id=<value optimized out>)
>    at red_worker.c:6038
>#7  0x00007ffff5f7a716 in red_marshall_image (rcc=0x7fff4c270ce0, pipe_item=0x7fff4e493af0) at red_worker.c:8600
>#8  display_channel_send_item (rcc=0x7fff4c270ce0, pipe_item=0x7fff4e493af0) at red_worker.c:8911
>#9  0x00007ffff5f5a563 in red_channel_client_send_item (rcc=0x7fff4c270ce0) at red_channel.c:507
>#10 red_channel_client_push (rcc=0x7fff4c270ce0) at red_channel.c:1088
>#11 0x00007ffff5f65a59 in red_push_surface_image (dcc=0x7fff4c270ce0, surface_id=<value optimized out>) at red_worker.c:5095
>#12 0x00007ffff5f7cc1b in red_handle_drawable_surfaces_client_synced (worker=0x7fff4c0008c0, ring_is_empty=0x7fffe75fdbdc, max_pipe_size=50) at red_worker.c:1388
>#13 red_pipe_add_drawable (worker=0x7fff4c0008c0, ring_is_empty=0x7fffe75fdbdc, max_pipe_size=50) at red_worker.c:1444
>#14 red_pipes_add_drawable (worker=0x7fff4c0008c0, ring_is_empty=0x7fffe75fdbdc, max_pipe_size=50) at red_worker.c:1456
>#15 red_process_drawable (worker=0x7fff4c0008c0, ring_is_empty=0x7fffe75fdbdc, max_pipe_size=50) at red_worker.c:3958
>#16 red_process_commands (worker=0x7fff4c0008c0, ring_is_empty=0x7fffe75fdbdc, max_pipe_size=50) at red_worker.c:4902
>#17 0x00007ffff5f7fcca in red_worker_main (arg=<value optimized out>) at red_worker.c:11798
>#18 0x00007ffff773c851 in start_thread () from /lib64/libpthread.so.0
>#19 0x00007ffff57fc90d in clone () from /lib64/libc.so.6
>(gdb)
>
>

Actions: View

Attachments on bug 879159: 649583