[Spice-devel] Windows 10 VDAgent incompatible with "hidden" KVM?

Frediano Ziglio fziglio at redhat.com
Fri Sep 25 16:39:01 UTC 2020 

> 
> On 9/25/20 9:47 AM, Ian Pilcher wrote:
> > On 9/25/20 2:34 AM, Frediano Ziglio wrote:
> > 
> >> Can you post the agent logs when it's not working?
> > 
> > Where are the agent logs stored?
> > 
> 
> Found 'em.
> 
> Here is the vdagent.log when the GPU is disabled (when the mouse works):
> 
> 4348::INFO::2020-09-25 10:26:31,627::run::***Agent started in session 1***
> 4348::INFO::2020-09-25 10:26:31,627::log_version::0.9.0.0
> 4348::INFO::2020-09-25 10:26:31,627::debug_print_config::\\.\DISPLAY1
> [Before SetDisplayConfig] (0,0) (1680x1050).
> 4348::INFO::2020-09-25 10:26:31,627::set_display_config::path states
> says nothing changed
> 4348::INFO::2020-09-25 10:26:31,627::consistent_displays::#qxls 1 #others 0
> 4348::INFO::2020-09-25 10:26:31,643::send_announce_capabilities::Sending
> capabilities:
> 4348::INFO::2020-09-25 10:26:31,643::send_announce_capabilities::6B7
> 4348::INFO::2020-09-25 10:26:31,643::run::Connected to server
> 4348::INFO::2020-09-25
> 10:26:31,643::input_desktop_message_loop::Desktop: Winlogon
> 4348::INFO::2020-09-25 10:26:31,658::handle_announce_capabilities::Got
> capabilities (1)
> 4348::INFO::2020-09-25 10:26:31,674::handle_announce_capabilities::35077
> 4348::INFO::2020-09-25 10:26:31,674::send_announce_capabilities::Sending
> capabilities:
> 4348::INFO::2020-09-25 10:26:31,674::send_announce_capabilities::6B7
> 4348::INFO::2020-09-25 10:26:31,674::handle_announce_capabilities::Got
> capabilities (1)
> 4348::INFO::2020-09-25 10:26:31,674::handle_announce_capabilities::35077
> 4348::INFO::2020-09-25 10:26:31,674::set::setting display options
> 4348::INFO::2020-09-25 10:26:31,674::get_user_process_id::explorer.exe
> not found
> 4348::INFO::2020-09-25
> 10:26:31,674::reload_from_registry::get_user_process_id failed
> 4348::INFO::2020-09-25 10:26:31,674::handle_max_clipboard::Set max
> clipboard size: 104857600
> 4348::INFO::2020-09-25 10:26:31,674::handle_mon_config::0. 1680*1050*32
> (0,0) 1
> 4348::INFO::2020-09-25 10:26:31,674::consistent_displays::#qxls 1 #others 0
> 4348::INFO::2020-09-25 10:26:31,674::update_mode_position::\\.\DISPLAY1
> updated path mode to (0, 0) - (1680 x1050)
> 4348::INFO::2020-09-25 10:26:31,674::handle_max_clipboard::Set max
> clipboard size: 104857600
> 4348::INFO::2020-09-25 10:26:31,674::handle_mon_config::0. 1680*1050*32
> (0,0) 1
> 4348::INFO::2020-09-25 10:26:31,674::consistent_displays::#qxls 1 #others 0
> 4348::INFO::2020-09-25 10:26:31,674::update_mode_position::\\.\DISPLAY1
> updated path mode to (0, 0) - (1680 x1050)
> 4348::INFO::2020-09-25 10:26:33,408::handle_control_event::Control command 3
> 4348::INFO::2020-09-25 10:26:33,408::handle_control_event::session logon
> 4348::INFO::2020-09-25 10:26:36,440::handle_control_event::Control command 2
> 4348::INFO::2020-09-25
> 10:26:36,440::input_desktop_message_loop::Desktop: Default
> 4348::INFO::2020-09-25 10:26:36,440::input_desktop_message_loop::First
> display setting
> 4348::INFO::2020-09-25 10:26:36,440::load::loading display setting
> 4348::INFO::2020-09-25 10:26:36,440::reload_from_registry::explorer pid 5796
> 4348::INFO::2020-09-25 10:26:36,440::reload_wallpaper::
> 4348::INFO::2020-09-25 10:26:36,440::reload_wallpaper::wallpaper wasn't
> disabled
> 4348::INFO::2020-09-25 10:26:36,440::reload_font_smoothing::
> 4348::INFO::2020-09-25 10:26:36,440::reload_font_smoothing::font
> smoothing value didn't change
> 4348::INFO::2020-09-25 10:26:36,440::reload_animation::
> 4348::INFO::2020-09-25 10:26:36,440::reload_win_animation::
> 4348::INFO::2020-09-25 10:26:36,440::reload_win_animation::reload window
> animation: success
> 4348::INFO::2020-09-25 10:26:36,440::reload_ui_effects::
> 4348::INFO::2020-09-25
> 10:26:36,440::reload_ui_effects::UserPreferencesMask = 80071e9e 12
> 4348::INFO::2020-09-25 10:26:46,268::handle_control_event::Control command 2
> 4348::INFO::2020-09-25
> 10:26:46,268::input_desktop_message_loop::Desktop: Winlogon
> 4348::INFO::2020-09-25 10:26:50,596::handle_control_event::Control command 2
> 4348::INFO::2020-09-25
> 10:26:50,596::input_desktop_message_loop::Desktop: Default
> 4348::INFO::2020-09-25 10:27:48,096::event_dispatcher::received stop event
> 4348::INFO::2020-09-25 10:27:48,112::run::Agent stopped
> 
> And here is vdagent.log when the GPU is enabled (when the mouse doesn't
> work):
> 
> 4172::INFO::2020-09-25 10:09:09,885::run::***Agent started in session 1***
> 4172::INFO::2020-09-25 10:09:09,885::log_version::0.9.0.0
> 4172::INFO::2020-09-25 10:09:09,885::debug_print_config::\\.\DISPLAY1
> [Before SetDisplayConfig] (0,0) (1680x1050).
> 4172::INFO::2020-09-25 10:09:09,885::set_display_config::path states
> says nothing changed
> 4172::INFO::2020-09-25 10:09:09,885::consistent_displays::#qxls 1 #others 4
> 4172::INFO::2020-09-25 10:09:09,885::run::No QXL devices!
> 4172::INFO::2020-09-25 10:09:09,901::send_announce_capabilities::Sending
> capabilities:
> 4172::INFO::2020-09-25 10:09:09,901::send_announce_capabilities::6B7
> 4172::INFO::2020-09-25 10:09:09,901::run::Connected to server
> 4172::INFO::2020-09-25
> 10:09:09,901::input_desktop_message_loop::Desktop: Winlogon
> 4172::INFO::2020-09-25 10:09:09,916::handle_announce_capabilities::Got
> capabilities (1)
> 4172::INFO::2020-09-25 10:09:09,916::handle_announce_capabilities::35077
> 4172::INFO::2020-09-25 10:09:09,916::send_announce_capabilities::Sending
> capabilities:
> 4172::INFO::2020-09-25 10:09:09,916::send_announce_capabilities::6B7
> 4172::INFO::2020-09-25 10:09:09,916::handle_announce_capabilities::Got
> capabilities (1)
> 4172::INFO::2020-09-25 10:09:09,916::handle_announce_capabilities::35077
> 4172::INFO::2020-09-25 10:09:09,916::set::setting display options
> 4172::INFO::2020-09-25 10:09:09,916::get_user_process_id::explorer.exe
> not found
> 4172::INFO::2020-09-25
> 10:09:09,916::reload_from_registry::get_user_process_id failed
> 4172::INFO::2020-09-25 10:09:09,916::handle_max_clipboard::Set max
> clipboard size: 104857600
> 4172::INFO::2020-09-25 10:09:09,916::consistent_displays::#qxls 1 #others 4
> 4172::INFO::2020-09-25 10:09:09,916::handle_max_clipboard::Set max
> clipboard size: 104857600
> 4172::INFO::2020-09-25 10:09:09,916::consistent_displays::#qxls 1 #others 4
> 4172::INFO::2020-09-25 10:09:12,182::handle_control_event::Control command 3
> 4172::INFO::2020-09-25 10:09:12,182::handle_control_event::session logon
> 4172::INFO::2020-09-25 10:09:15,651::handle_control_event::Control command 2
> 4172::INFO::2020-09-25
> 10:09:15,651::input_desktop_message_loop::Desktop: Default
> 4172::INFO::2020-09-25 10:09:15,651::input_desktop_message_loop::First
> display setting
> 4172::INFO::2020-09-25 10:09:15,651::load::loading display setting
> 4172::INFO::2020-09-25 10:09:15,666::reload_from_registry::explorer pid 6488
> 4172::INFO::2020-09-25 10:09:15,666::reload_wallpaper::
> 4172::INFO::2020-09-25 10:09:15,666::reload_wallpaper::wallpaper wasn't
> disabled
> 4172::INFO::2020-09-25 10:09:15,666::reload_font_smoothing::
> 4172::INFO::2020-09-25 10:09:15,666::reload_font_smoothing::font
> smoothing value didn't change
> 4172::INFO::2020-09-25 10:09:15,666::reload_animation::
> 4172::INFO::2020-09-25 10:09:15,666::reload_win_animation::
> 4172::INFO::2020-09-25 10:09:15,666::reload_win_animation::reload window
> animation: success
> 4172::INFO::2020-09-25 10:09:15,666::reload_ui_effects::
> 4172::INFO::2020-09-25
> 10:09:15,666::reload_ui_effects::UserPreferencesMask = 80071e9e 12
> 4172::INFO::2020-09-25 10:09:23,104::handle_control_event::Control command 2
> 4172::INFO::2020-09-25
> 10:09:23,104::input_desktop_message_loop::Desktop: Winlogon
> 4172::INFO::2020-09-25 10:09:27,416::handle_control_event::Control command 2
> 4172::INFO::2020-09-25
> 10:09:27,416::input_desktop_message_loop::Desktop: Default
> 4172::INFO::2020-09-25 10:10:36,182::event_dispatcher::received stop event
> 4172::INFO::2020-09-25 10:10:36,182::run::Agent stopped
> 
> Line 6 of the non-working log says "run::No QXL devices!", which does
> seem like it might be a problem.  :-)
>

Yes, it's caused by the "#qxls 1 #others 4", the agent currently does not
like a mix of both QXL and something else. It looks like different tools
create multiple displays. 

There was a recent patch but specifically for a Citrix product.

Maybe it would be a good time to have a fix for more cases.

> (Is the a bug tracker somewhere that would be a more appropriate place
> for this?)
> 
> Thanks!
> 
> --
> ========================================================================
> Ian Pilcher                                         arequipeno at gmail.com
> -------- "I grew up before Mark Zuckerberg invented friendship" --------

More than "invented" I hope "redefined".

Frediano

More information about the Spice-devel mailing list