[Spice-devel] vdagent-win32 update

[Alon Levy]

On Sun, Nov 27, 2011 at 02:57:12PM -0600, Mike Hinz wrote:
> On Thu, 2011-11-24 at 14:56 +0200, Arnon Gilboa wrote:
> > Hi there,
> > No updates for some time. Have you tried using the debug vdagent I 
> > posted a while ago?
> > A newer version with several fixes can be found at spice-space.org 
> > download page, or directly at:
> > http://www.spice-space.org/download/binaries/vdagent-win32_20111124.zip
> > Please update with your results.
> > Regards,
> > Arnon
> > 
> 
> (resent.  sent from wrong email account the first time).
> 
> Arnon,
> 
> I've gotten back on this problem and have done some more testing.  
> 
> First, there was a new version of qemu that I installed from the F15
> virt-preview repos, 0.15.1.  Then within my WinXP VM, I installed the
> new vdagent dated 11/24/2011.  
> 
> I can say that things have changed, but the problem is still present.
> Let me try to describe.  First though are the contents of the log files:
> 
> vdservice.log
> 
> 2976::INFO::2011-11-27 11:45:53,453::VDService::main::***Service
> started***
> 2976::INFO::2011-11-27 11:45:53,453::log_version::1.1.0.1009
> 2976::INFO::2011-11-27 11:45:53,453::VDService::launch_agent::Wait for
> vdagent to connect
> 2976::INFO::2011-11-27 11:45:53,531::VDService::launch_agent::Pipe
> connected by vdagent
> 2976::INFO::2011-11-27 11:45:53,531::VDService::execute::created
> VirtioVDIPort
> 2976::INFO::2011-11-27 11:45:53,531::VDService::execute::Connected to
> server
> 3932::INFO::2011-11-27
> 11:45:55,453::VDService::control_handler::Interrogate service
> 
> vdagent.log
> 
> 3632::INFO::2011-11-27 11:45:53,515::VDAgent::run::***Agent started in
> session 0***
> 3632::INFO::2011-11-27 11:45:53,515::log_version::1.1.0.1009
> 3632::INFO::2011-11-27
> 11:45:53,515::DesktopLayout::consistent_displays::#qxls 1 #others 0
> 3632::INFO::2011-11-27 11:45:53,515::VDAgent::connect_pipe::Connected to
> service pipe
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::send_announce_capabilities::Sending capabilities:
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::send_announce_capabilities::37
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::input_desktop_message_loop::Desktop: Default
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::input_desktop_message_loop::First display setting
> 3632::INFO::2011-11-27 11:45:53,531::DisplaySetting::load::loading
> display setting
> 3632::INFO::2011-11-27
> 11:45:53,531::DisplaySetting::reload_from_registry::explorer pid 144
> 3632::INFO::2011-11-27 11:45:53,531::DisplaySetting::reload_wallpaper::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_wallpaper::wallpaper wasn't
> disabled
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_font_smoothing::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_font_smoothing::font smoothing
> value didn't change
> 3632::INFO::2011-11-27 11:45:53,546::DisplaySetting::reload_animation::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_win_animation::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_win_animation::reload window
> animation: success
> 3632::INFO::2011-11-27 11:45:53,546::DisplaySetting::reload_ui_effects::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_ui_effects::UserPreferencesMask =
> 80073e9e 6c
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::Got capabilities
> (1)
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::77
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::send_announce_capabilities::Sending capabilities:
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::send_announce_capabilities::37
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::Got
> capabilitiesArnon,
> 
> I've gotten back on this problem and have done some more testing.  
> 
> First, there was a new version of qemu that I installed from the F15
> virt-preview repos, 0.15.1.  Then within my WinXP VM, I installed the
> new vdagent dated 11/24/2011.  
> 
> I can say that things have changed, but the problem is still present.
> Let me try to describe.  First though are the contents of the log files:
> 
> vdservice.log
> 
> 2976::INFO::2011-11-27 11:45:53,453::VDService::main::***Service
> started***
> 2976::INFO::2011-11-27 11:45:53,453::log_version::1.1.0.1009
> 2976::INFO::2011-11-27 11:45:53,453::VDService::launch_agent::Wait for
> vdagent to connect
> 2976::INFO::2011-11-27 11:45:53,531::VDService::launch_agent::Pipe
> connected by vdagent
> 2976::INFO::2011-11-27 11:45:53,531::VDService::execute::created
> VirtioVDIPort
> 2976::INFO::2011-11-27 11:45:53,531::VDService::execute::Connected to
> server
> 3932::INFO::2011-11-27
> 11:45:55,453::VDService::control_handler::Interrogate service
> 
> vdagent.log
> 
> 3632::INFO::2011-11-27 11:45:53,515::VDAgent::run::***Agent started in
> session 0***
> 3632::INFO::2011-11-27 11:45:53,515::log_version::1.1.0.1009
> 3632::INFO::2011-11-27
> 11:45:53,515::DesktopLayout::consistent_displays::#qxls 1 #others 0
> 3632::INFO::2011-11-27 11:45:53,515::VDAgent::connect_pipe::Connected to
> service pipe
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::send_announce_capabilities::Sending capabilities:
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::send_announce_capabilities::37
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::input_desktop_message_loop::Desktop: Default
> 3632::INFO::2011-11-27
> 11:45:53,515::VDAgent::input_desktop_message_loop::First display setting
> 3632::INFO::2011-11-27 11:45:53,531::DisplaySetting::load::loading
> display setting
> 3632::INFO::2011-11-27
> 11:45:53,531::DisplaySetting::reload_from_registry::explorer pid 144
> 3632::INFO::2011-11-27 11:45:53,531::DisplaySetting::reload_wallpaper::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_wallpaper::wallpaper wasn't
> disabled
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_font_smoothing::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_font_smoothing::font smoothing
> value didn't change
> 3632::INFO::2011-11-27 11:45:53,546::DisplaySetting::reload_animation::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_win_animation::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_win_animation::reload window
> animation: success
> 3632::INFO::2011-11-27 11:45:53,546::DisplaySetting::reload_ui_effects::
> 3632::INFO::2011-11-27
> 11:45:53,546::DisplaySetting::reload_ui_effects::UserPreferencesMask =
> 80073e9e 6c
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::Got capabilities
> (1)
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::77
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::send_announce_capabilities::Sending capabilities:
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::send_announce_capabilities::37
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::Got capabilities
> (1)
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::77
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::set::setting
> display options
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_from_registry::explorer pid 144
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_wallpaper::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_wallpaper::wallpaper wasn't
> disabled
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_font_smoothing::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_font_smoothing::font smoothing
> value didn't change
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_animation::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_win_animation::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_win_animation::reload window
> animation: success
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_ui_effects::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_ui_effects::UserPreferencesMask =
> 80073e9e 7c
> 3632::INFO::2011-11-27
> 11:56:13,828::VDAgent::on_clipboard_grab::Unsupported clipboard format
>   (1)
> 3632::INFO::2011-11-27
> 11:45:53,656::VDAgent::handle_announce_capabilities::77
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::set::setting
> display options
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_from_registry::explorer pid 144
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_wallpaper::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_wallpaper::wallpaper wasn't
> disabled
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_font_smoothing::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_font_smoothing::font smoothing
> value didn't change
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_animation::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_win_animation::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_win_animation::reload window
> animation: success
> 3632::INFO::2011-11-27 11:45:53,656::DisplaySetting::reload_ui_effects::
> 3632::INFO::2011-11-27
> 11:45:53,656::DisplaySetting::reload_ui_effects::UserPreferencesMask =
> 80073e9e 7c
> 3632::INFO::2011-11-27
> 11:56:13,828::VDAgent::on_clipboard_grab::Unsupported clipboard format
> 
> Now, if whenever I first attempt to copy/paste, I can always do 1 and
> sometimes up to 4 successful copy/paste operations.  However, copy/paste
> will then abruptly fail to operate after that initial success.
> Interestingly, there's one additional thing that I always note.  I've
> disabled the USB Graphics Tablet function on the VM and with now I note
> that initially, the cursor is NOT captured within the VM AND the
> copy/paste operation works!  However, after a few copy/paste operations,
> the cursor becomes trapped (the VM window header bar has "Press
> Control_L+ALT_L to release the cursor) AND the copy/paste operation then
> fails! 
> 

Mouse can be not captured by two seperate mechanisms:
 usb tablet mouse on server
 guest agent in guest

Since you've disabled the first, you are left with the second - as long
as the guest agent is alive. Once it dies you lose both the copy/paste
and the non-captured mouse, aka client side mouse.

To actually help you I hope Arnon can follow up :/

> When the above occurs, I can stop and start the vdservice and copy/paste
> will again work for a very brief time, until the cursor again becomes
> trapped.  
> 
> I've also tried enabling the USB Graphics Tablet and essentially the
> same thing occurs;  that is copy/paste works for a very short time and
> then fails.  However, with the Tablet enabled, the cursor never becomes
> trapped.
> 
> I hope the above is helpful.  Please let me know if you need more info
> or want me to try something else.
> 
> Thanks!
> 
> _______________________________________________
> Spice-devel mailing list
> Spice-devel at lists.freedesktop.org
> http://lists.freedesktop.org/mailman/listinfo/spice-devel