Attachment 584702 Details for Bug 821870 – Client/DST/SRC logs

Client/DST/SRC logs

Client_DST-SRC-qemu_logs.log (text/plain), 14.42 KB, created by Marian Krcmarik on 2012-05-15 15:53:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marian Krcmarik

Created: 2012-05-15 15:53:09 UTC

Size: 14.42 KB

patch

obsolete

>SRC:
>2012-05-15 15:15:31.719+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/bin QEMU_AUDIO_DRV=spice /usr/libexec/qemu-kvm -S -M rhel6.2.0 -cpu Nehalem -enable-kvm -m 1024 -smp 2,sockets=2,cores=1,threads=1 -name WinXP-mk -uuid 7c30be6e-b4b3-4bd0-9591-eb833aac6efc -smbios type=1,manufacturer=Red Hat,product=RHEV Hypervisor,version=6Server-6.3.0.2.el6,serial=33313934-3432-5A43-3230-323437523147_78:E7:D1:E0:29:3A,uuid=7c30be6e-b4b3-4bd0-9591-eb833aac6efc -nodefconfig -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/WinXP-mk.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2012-05-15T16:16:57,driftfix=slew -no-shutdown -device virtio-serial-pci,id=virtio-serial0,max_ports=16,bus=pci.0,addr=0x5 -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -drive file=/rhev/data-center/46b669e1-67d2-45b4-b449-51d0688a1824/d085ca29-a99d-4ff5-8211-d8f55e300eea/images/9165dba9-4e9c-4c31-babf-e474cd2387b4/5947396e-b52f-4b5c-87a7-d5aaa0ef3b2f,if=none,id=drive-virtio-disk0,format=qcow2,serial=31-babf-e474cd2387b4,cache=none,werror=stop,rerror=stop,aio=native -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -drive file=/rhev/data-center/46b669e1-67d2-45b4-b449-51d0688a1824/9aa406d4-ded7-43f4-af90-7664ceb04d95/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.0_37.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw -device ide-drive,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -netdev tap,fd=25,id=hostnet0,vhost=on,vhostfd=28 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:1a:4a:31:7f:0c,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/WinXP-mk.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev spicevmc,id=charchannel1,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=com.redhat.spice.0 -spice port=5904,tls-port=5905,addr=10.34.58.5,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=inputs -k en-us -vga qxl -global qxl-vga.vram_size=67108864 -device AC97,id=sound0,bus=pci.0,addr=0x4
>do_spice_init: starting 0.10.1
>reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>spice_server_add_interface: SPICE_INTERFACE_MOUSE
>spice_server_add_interface: SPICE_INTERFACE_QXL
>red_worker_main: begin
>display_channel_create: create display channel
>cursor_channel_create: create cursor channel
>spice_server_add_interface: SPICE_INTERFACE_RECORD
>spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>Domain id=32 is tainted: custom-monitor
>spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_handle_main_link: 
>reds_disconnect: 
>reds_show_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>reds_handle_main_link: NEW Client 0x7f12de5a9280 mcc 0x7f12de58b010 connect-id 1804289383
>main_channel_handle_parsed: agent start
>handle_dev_set_mouse_mode: mouse mode 2
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>main_channel_handle_parsed: net test: latency 5.865000 ms, bitrate 13889830 bps (13.246374 Mbps)
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>red_dispatcher_set_cursor_peer: 
>handle_dev_cursor_connect: cursor connect
>red_connect_cursor: add cursor channel client
>listen_to_new_client_channel: NEW ID = 0
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>red_dispatcher_set_display_peer: 
>handle_dev_display_connect: connect
>handle_new_display_channel: add display channel client
>handle_new_display_channel: New display (client 0x7f12de5a9280) dcc 0x7f12c4201b40 stream 0x7f12de58bd30
>handle_new_display_channel: jpeg disabled
>handle_new_display_channel: zlib-over-glz disabled
>listen_to_new_client_channel: NEW ID = 0
>reds_show_new_channel: channel 3:0, connected successfully, over Secure link
>inputs_connect: inputs channel client create
>display_channel_client_wait_for_init: creating encoder with id == 0
>display_channel_release_item: not pushed (101)
>spice_server_migrate_connect: 
>reds_mig_started: 
>main_channel_client_handle_migrate_connected: client 0x7f12de5a9280 connected: 1
>spice_server_migrate_start: 
>handle_dev_stop: stop
>spice_server_migrate_end: 
>reds_mig_finished: 
>main_channel_migrate_complete: 
>main_channel_migrate_complete: client 0x7f12de5a9280 MIGRATE_END
>2012-05-15 15:44:18.843+0000: shutting down
>qemu: terminating on signal 15 from pid 2209
>spice_server_remove_interface: remove SPICE_INTERFACE_PLAYBACK
>snd_channel_put: sound channel freed
>================================================================
>DST:
>2012-05-15 15:43:46.885+0000: starting up
>LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/sbin:/sbin:/bin QEMU_AUDIO_DRV=spice /usr/libexec/qemu-kvm -S -M rhel6.2.0 -cpu Nehalem -enable-kvm -m 1024 -smp 2,sockets=2,cores=1,threads=1 -name WinXP-mk -uuid 7c30be6e-b4b3-4bd0-9591-eb833aac6efc -smbios type=1,manufacturer=Red Hat,product=RHEV Hypervisor,version=6Server-6.3.0.2.el6,serial=33313934-3432-5A43-3230-323437523147_78:E7:D1:E0:29:3A,uuid=7c30be6e-b4b3-4bd0-9591-eb833aac6efc -nodefconfig -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/WinXP-mk.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2012-05-15T16:45:12,driftfix=slew -no-shutdown -device virtio-serial-pci,id=virtio-serial0,max_ports=16,bus=pci.0,addr=0x5 -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -drive file=/rhev/data-center/46b669e1-67d2-45b4-b449-51d0688a1824/d085ca29-a99d-4ff5-8211-d8f55e300eea/images/9165dba9-4e9c-4c31-babf-e474cd2387b4/5947396e-b52f-4b5c-87a7-d5aaa0ef3b2f,if=none,id=drive-virtio-disk0,format=qcow2,serial=31-babf-e474cd2387b4,cache=none,werror=stop,rerror=stop,aio=native -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -drive file=/rhev/data-center/46b669e1-67d2-45b4-b449-51d0688a1824/9aa406d4-ded7-43f4-af90-7664ceb04d95/images/11111111-1111-1111-1111-111111111111/RHEV-toolsSetup_3.0_37.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw -device ide-drive,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -netdev tap,fd=29,id=hostnet0,vhost=on,vhostfd=31 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:1a:4a:31:7f:0c,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/WinXP-mk.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev spicevmc,id=charchannel1,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=com.redhat.spice.0 -spice port=5902,tls-port=5903,addr=10.34.58.7,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=inputs -k en-us -vga qxl -global qxl-vga.vram_size=67108864 -device AC97,id=sound0,bus=pci.0,addr=0x4 -incoming tcp:0.0.0.0:49165
>do_spice_init: starting 0.10.1
>reds_init_ssl: Loaded certificates from /etc/pki/vdsm/libvirt-spice/server-cert.pem
>reds_init_ssl: Using private key from /etc/pki/vdsm/libvirt-spice/server-key.pem
>reds_init_ssl: Loaded CA certificates from /etc/pki/vdsm/libvirt-spice/ca-cert.pem
>spice_server_add_interface: SPICE_INTERFACE_MIGRATION
>spice_server_add_interface: SPICE_INTERFACE_KEYBOARD
>spice_server_add_interface: SPICE_INTERFACE_MOUSE
>spice_server_add_interface: SPICE_INTERFACE_QXL
>red_worker_main: begin
>display_channel_create: create display channel
>cursor_channel_create: create cursor channel
>spice_server_add_interface: SPICE_INTERFACE_RECORD
>spice_server_add_interface: SPICE_INTERFACE_PLAYBACK
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_handle_main_link: 
>reds_disconnect: 
>reds_show_new_channel: channel 1:0, connected successfully, over Secure link
>main_channel_link: add main channel client
>reds_handle_main_link: NEW Client 0x7fb0edf7fde0 mcc 0x7fb0edf932b0 connect-id 1804289383
>reds_mig_target_client_add: 
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 5:0, connected successfully, over Non Secure link
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 6:0, connected successfully, over Non Secure link
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 4:0, connected successfully, over Non Secure link
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 2:0, connected successfully, over Non Secure link
>reds_handle_read_link_done: Peer doesn't support AUTH selection
>reds_show_new_channel: channel 3:0, connected successfully, over Secure link
>red_dispatcher_loadvm_commands: 
>handle_dev_loadvm_commands: loadvm_commands
>spice_server_char_device_add_interface: CHAR_DEVICE vdagent
>main_channel_send_item: Init msg for client 0x7fb0edf7fde0 was not sent yet (client is probably during migration). Ignoring msg type 105
>main_channel_send_item: Init msg for client 0x7fb0edf7fde0 was not sent yet (client is probably during migration). Ignoring msg type 107
>Domain id=14 is tainted: custom-monitor
>red_channel_client_disconnect: 0x7fb0edf932b0 (channel 0x7fb0edeb0730 type 1 id 0)
>main_channel_client_on_disconnect: rcc=0x7fb0edf932b0
>reds_client_disconnect: 
>red_client_destroy: destroy client with #channels 1
>red_channel_client_disconnect: 0x7fb0edf932b0 (channel 0x7fb0edeb0730 type 1 id 0)
>==================================================================================
>Client:
>1337094732 INFO [24270:24270] Application::main: starting 0.8.3
>1337094733 INFO [24270:24270] init_key_map: using evdev mapping
>1337094733 INFO [24270:24270] MultyMonScreen::MultyMonScreen: platform_win: 79691777
>1337094733 INFO [24270:24270] ForeignMenu::ForeignMenu: Creating a foreign menu connection /tmp/spicec-VUL1z4/spice-foreign
>1337094733 INFO [24270:24270] Controller::Controller: Creating a controller connection /tmp/spicec-VUL1z4/spice-xpi
>1337094735 ERROR [24270:24270] ControllerConnection::handle_message: Ignoring an unknown/SEND_CAD controller message 22
>1337094735 INFO [24270:24272] RedPeer::connect_secure: Connected to 10.34.58.5 5905
>1337094735 INFO [24270:24272] RedClient::handle_init: 
>1337094735 INFO [24270:24281] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337094735 INFO [24270:24280] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337094735 INFO [24270:24282] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337094735 INFO [24270:24283] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337094735 INFO [24270:24284] RedPeer::connect_secure: Connected to 10.34.58.5 5905
>1337094735 INFO [24270:24283] DisplayChannel::create_primary_surface: 
>1337094735 INFO [24270:24270] DisplayChannel::create_sw_canvas: display 0: using sw
>1337094767 INFO [24270:24270] Application::enter_full_screen: 
>1337094784 INFO [24270:24270] main: Spice client terminated (exitcode = 0)
>1337096533 INFO [26313:26313] Application::main: starting 0.8.3
>1337096533 INFO [26313:26313] init_key_map: using evdev mapping
>1337096534 INFO [26313:26313] MultyMonScreen::MultyMonScreen: platform_win: 73400321
>1337096534 INFO [26313:26313] ForeignMenu::ForeignMenu: Creating a foreign menu connection /tmp/spicec-VUL1z4/spice-foreign
>1337096534 INFO [26313:26313] Controller::Controller: Creating a controller connection /tmp/spicec-VUL1z4/spice-xpi
>1337096536 INFO [26313:26315] RedPeer::connect_secure: Connected to 10.34.58.5 5905
>1337096536 ERROR [26313:26313] ControllerConnection::handle_message: Ignoring an unknown/SEND_CAD controller message 22
>1337096536 INFO [26313:26315] RedClient::handle_init: 
>1337096536 INFO [26313:26323] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337096536 INFO [26313:26324] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337096536 INFO [26313:26325] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337096536 INFO [26313:26326] RedPeer::connect_unsecure: Connected to 10.34.58.5 5904
>1337096536 INFO [26313:26327] RedPeer::connect_secure: Connected to 10.34.58.5 5905
>1337096536 INFO [26313:26326] DisplayChannel::create_primary_surface: 
>1337096536 INFO [26313:26313] DisplayChannel::create_sw_canvas: display 0: using sw
>1337096627 INFO [26313:26315] RedClient::handle_migrate_begin: 
>1337096627 INFO [26313:26428] RedPeer::connect_secure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5903
>1337096627 INFO [26313:26428] RedPeer::connect_unsecure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5902
>1337096627 INFO [26313:26428] RedPeer::connect_unsecure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5902
>1337096627 INFO [26313:26428] RedPeer::connect_unsecure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5902
>1337096627 INFO [26313:26428] RedPeer::connect_unsecure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5902
>1337096627 INFO [26313:26428] RedPeer::connect_secure: Connected to hyper06.spice.lab.eng.brq.redhat.com 5903
>1337096659 INFO [26313:26315] RedClient::handle_migrate_end: 
>1337096659 INFO [26313:26324] RedClient::on_channel_disconnect_mig_src_completed: 
>1337096659 INFO [26313:26326] RedClient::on_channel_disconnect_mig_src_completed: 
>1337096659 INFO [26313:26315] RedClient::on_channel_disconnect_mig_src_completed: 
>1337096659 INFO [26313:26327] RedClient::on_channel_disconnect_mig_src_completed: 
>1337096659 INFO [26313:26325] RedClient::on_channel_disconnect_mig_src_completed: 
>1337096659 WARN [26313:26315] RedChannel::run: abort
>1337096660 WARN [26313:26324] RedChannel::run: abort
>1337096660 WARN [26313:26325] RedChannel::run: abort
>1337096660 WARN [26313:26326] RedChannel::run: abort
>1337096661 WARN [26313:26327] RedChannel::run: abort
>1337096661 INFO [26313:26313] main: Spice client terminated (exitcode = 0)

Actions: View

Attachments on bug 821870: 584702