[winswitch] xpra 0.14.23 - Spinners not going away

Antoine Martin antoine at nagafix.co.uk
Tue May 19 16:23:53 BST 2015


On 19/05/15 22:20, Douglas Doole wrote:
> I am now running xpra 0.14.24 (both client and server) and I just got 
> the stuck spinners again. Anything I can gather to help debug this?
Yes, please file a ticket following the guidelines here:
http://xpra.org/trac/wiki/ReportingBugs
I'll make sure we track it down.

Cheers
Antoine

>
> On Wed, May 6, 2015 at 11:11 AM, Douglas Doole <dougdoole at gmail.com 
> <mailto:dougdoole at gmail.com>> wrote:
>
>     Just hit the problem again. Here's the client log with "-d client"
>     starting from the server not responding message
>
>     2015-05-06 11:06:35,078 server is not responding, drawing spinners
>     over the windows
>     2015-05-06 11:06:35,196 ping echo server load=(0, 20, 50),
>     measured client latency=2ms
>     2015-05-06 11:06:35,205 server is OK again
>     2015-05-06 11:06:35,206 process_draw 109990 bytes for window 1
>     using png encoding with options={'compress_level': 2}
>     2015-05-06 11:06:35,219 process_draw 99427 bytes for window 1
>     using png encoding with options={'compress_level': 2}
>     2015-05-06 11:06:35,228 record_decode_time(True) wid=1, png:
>     575x768, 22.3ms
>     2015-05-06 11:06:35,231 record_decode_time(True) wid=1, png:
>     575x768, 11.0ms
>     2015-05-06 11:06:35,287 process_draw 125023 bytes for window 1
>     using png encoding with options={'compress_level': 2}
>     2015-05-06 11:06:35,297 record_decode_time(True) wid=1, png:
>     575x768, 10.3ms
>     2015-05-06 11:06:40,269 process_draw 122605 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:40,286 record_decode_time(True) wid=1, png:
>     575x768, 17.4ms
>     2015-05-06 11:06:42,228 process_draw 124183 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:42,246 record_decode_time(True) wid=1, png:
>     575x768, 18.0ms
>     2015-05-06 11:06:42,385 process_draw 123751 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:42,403 record_decode_time(True) wid=1, png:
>     575x768, 18.2ms
>     2015-05-06 11:06:44,065 check_echo_timeout(1430924744063)
>     last_ping_echoed_time=1430924794068
>     2015-05-06 11:06:44,069 average server latency=6.5, using max wait
>     1.01s
>     2015-05-06 11:06:44,072 ping echo server load=(0, 20, 50),
>     measured client latency=3ms
>     2015-05-06 11:06:47,007 process_draw 125209 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:47,025 record_decode_time(True) wid=1, png:
>     575x768, 17.4ms
>     2015-05-06 11:06:48,871 process_draw 105618 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:48,884 record_decode_time(True) wid=1, png:
>     575x768, 12.4ms
>     2015-05-06 11:06:54,071 average server latency=6.5, using max wait
>     1.01s
>     2015-05-06 11:06:54,072 check_echo_timeout(1430924754065)
>     last_ping_echoed_time=1430924804069
>     2015-05-06 11:06:54,074 ping echo server load=(0, 20, 50),
>     measured client latency=7ms
>     2015-05-06 11:06:54,231 process_draw 106959 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:54,254 record_decode_time(True) wid=1, png:
>     575x768, 22.5ms
>     2015-05-06 11:06:57,833 process_draw 110440 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:57,847 record_decode_time(True) wid=1, png:
>     575x768, 13.5ms
>     2015-05-06 11:06:58,493 process_draw 108951 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:58,507 record_decode_time(True) wid=1, png:
>     575x768, 13.5ms
>     2015-05-06 11:06:58,922 process_draw 104383 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:58,943 record_decode_time(True) wid=1, png:
>     575x768, 20.0ms
>     2015-05-06 11:06:58,957 process_draw 101628 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:06:58,973 record_decode_time(True) wid=1, png:
>     575x768, 15.9ms
>     2015-05-06 11:06:59,230 process_draw 753 bytes for window 1 using
>     png encoding with options={'compress_level': 3, 'store': 634,
>     'delta': 604}
>     2015-05-06 11:06:59,232 record_decode_time(True) wid=1, png:
>     553x15, 1.9ms
>     2015-05-06 11:07:03,057 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:03,057 new cursor at 6,4 with serial=135,
>     dimensions: 24x24, len(pixels)=2304, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:03,276 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:03,277 new cursor at 11,11 with serial=3,
>     dimensions: 24x24, len(pixels)=2304, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:03,304 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:03,305 new cursor at 6,4 with serial=114,
>     dimensions: 24x24, len(pixels)=2304, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:03,448 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:03,448 new cursor at 11,11 with serial=3,
>     dimensions: 24x24, len(pixels)=2304, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:03,871 update_focus(1, True) focused=None,
>     grabbed=None
>     2015-05-06 11:07:03,871 send_focus(1)
>     2015-05-06 11:07:03,888 process_draw 90 bytes for window 1 using
>     png encoding with options={'compress_level': 3}
>     2015-05-06 11:07:03,891 record_decode_time(True) wid=1, png: 7x15,
>     2.6ms
>     2015-05-06 11:07:04,067 check_echo_timeout(1430924764066)
>     last_ping_echoed_time=1430924814071
>     2015-05-06 11:07:04,072 average server latency=6.5, using max wait
>     1.01s
>     2015-05-06 11:07:04,077 ping echo server load=(0, 20, 50),
>     measured client latency=3ms
>     2015-05-06 11:07:05,009 handle_key_action(GLClientWindow(1 :
>     GLPixmapBacking(1, (575, 768), None)), <GTKKeyEvent object,
>     contents: {'modifiers': [], 'group': 0, 'string': '\r', 'keyname':
>     'Return', 'pressed': True, 'keyval': 65293, 'keycode': 36}>) wid=1
>     2015-05-06 11:07:05,035 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:05,035 new cursor at 8,8 with serial=4,
>     dimensions: 16x16, len(pixels)=1024, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:05,072 handle_key_action(GLClientWindow(1 :
>     GLPixmapBacking(1, (575, 768), None)), <GTKKeyEvent object,
>     contents: {'modifiers': [], 'group': 0, 'string': '\r', 'keyname':
>     'Return', 'pressed': False, 'keyval': 65293, 'keycode': 36}>) wid=1
>     2015-05-06 11:07:05,112 process_draw 99622 bytes for window 1
>     using png encoding with options={'compress_level': 3}
>     2015-05-06 11:07:05,131 record_decode_time(True) wid=1, png:
>     575x748, 18.8ms
>     2015-05-06 11:07:05,563 server cursor sizes: default=85, max=(64, 64)
>     2015-05-06 11:07:05,563 new cursor at 11,11 with serial=3,
>     dimensions: 24x24, len(pixels)=2304, default cursor size is 25,
>     maximum=(64, 64)
>     2015-05-06 11:07:06,183 update_focus(1, False) focused=1, grabbed=None
>     2015-05-06 11:07:06,204 send_focus(0)
>     2015-05-06 11:07:06,218 process_draw 104 bytes for window 1 using
>     png encoding with options={'compress_level': 3}
>     2015-05-06 11:07:06,220 record_decode_time(True) wid=1, png: 7x15,
>     1.0ms
>     2015-05-06 11:07:14,074 average server latency=6.5, using max wait
>     1.01s
>     2015-05-06 11:07:14,074 check_echo_timeout(1430924774067)
>     last_ping_echoed_time=1430924824072
>     2015-05-06 11:07:14,077 ping echo server load=(0, 20, 50),
>     measured client latency=3ms
>     2015-05-06 11:07:24,068 check_echo_timeout(1430924784068)
>     last_ping_echoed_time=1430924834073
>     2015-05-06 11:07:24,074 average server latency=6.5, using max wait
>     1.01s
>     2015-05-06 11:07:24,077 ping echo server load=(0, 20, 50),
>     measured client latency=4ms
>
>     Toggling opengl on and off did make the spinner go away.
>
>     On Wed, May 6, 2015 at 9:22 AM, Douglas Doole <dougdoole at gmail.com
>     <mailto:dougdoole at gmail.com>> wrote:
>
>         > You may also be able to restore things by toggling opengl off then on
>         > again from the tray menu. (assuming your system supports opengl)
>
>         I am using opengl, so I'll give this a try the next time I see
>         it happen.
>
>         > Thanks for reporting it.
>         > It would help if I could reproduce the problem on my setup.
>
>         If I ever determine something that triggers it, I'll be sure
>         to update you.
>
>         > Can you tell us which applications were running when the problem
>         > occurred? (and also which ones were affected and which ones
>         were not)
>
>         Every time I've seen it (3 times now), I've had a mix of
>         konsole and gvim up. The problem has affected both window
>         types. On the last occurrence, I had two konsole windows and 4
>         gvim windows and only one konsole window was affected.
>         Previously I'd had a similar mix of windows, but it was one
>         gvim and two konsole windows that were affected.
>
>         On the most recent occurrence I noticed that the troublesome
>         spinner is actually static until the window contents change.
>         So, on the stuck konsole, the spinner was frozen until I
>         started typing, at which point it rotated one segment for each
>         character typed. I also noticed that the stuck spinner
>         survives things like window resizing, minimizing, etc.
>
>         > Unfortunately, there isn't a specific debug switch for this
>         feature, so
>         > you would need to use the big hammer "-d client", which will
>         also log a
>         > lot of unrelated things.
>         > And even if the logging was more specific, it may not tell
>         us enough to
>         > debug further.
>
>         On the last occurrence, I captured the basic client log, but
>         all it showed was the "server is not responding, drawing
>         spinners over the wind
>         ows" message. I'll add "-d client" and see if I get anything
>         useful.
>
>         On Wed, May 6, 2015 at 6:49 AM, Antoine Martin
>         <antoine at nagafix.co.uk <mailto:antoine at nagafix.co.uk>> wrote:
>
>             Hi Douglas,
>
>             On 06/05/15 00:00, Douglas Doole wrote:
>             > Since upgrading to xpra 0.14.23, I've had a couple
>             instances where I've had
>             > a network hiccup and the spinners have appeared. Yet
>             once the network is
>             > moving again, the spinners have remained on some of the
>             windows (not all)
>             > even though they are otherwise responding properly. That
>             is, I'm editing
>             > text, cutting & pasting, etc. but the spinner is sitting
>             in front of
>             > everything spinning away. Needless to say, it makes the
>             affected windows
>             > pretty much unusable.
>             >
>             > Once this has happened, the only way I can clear things
>             up is to drop the
>             > client connection and re-connect to the server.
>             You may also be able to restore things by toggling opengl
>             off then on
>             again from the tray menu. (assuming your system supports
>             opengl)
>             > I am not able to reproduce this on demand, but it has happened a couple
>             > times now. I have no idea what the trigger might be. I
>             had not seen this
>             > problem before upgrading to 0.14.23. Bother the server
>             and the client are
>             > running Ubuntu 14.04.
>             Thanks for reporting it.
>             It would help if I could reproduce the problem on my setup.
>             Can you tell us which applications were running when the
>             problem
>             occurred? (and also which ones were affected and which
>             ones were not)
>             > Unfortunately, I don't have a log from either of the times I've seen it
>             > happen. Anything in particular I should capture to help
>             debug it?
>             Unfortunately, there isn't a specific debug switch for
>             this feature, so
>             you would need to use the big hammer "-d client", which
>             will also log a
>             lot of unrelated things.
>             And even if the logging was more specific, it may not tell
>             us enough to
>             debug further.
>
>             Looking at the spinner code, I found a couple of issues,
>             maybe one of
>             those is what was causing your problem.
>             These fixes will be included in 0.14.24.
>
>             Thanks
>             Antoine
>
>             _______________________________________________
>             shifter-users mailing list
>             shifter-users at lists.devloop.org.uk
>             <mailto:shifter-users at lists.devloop.org.uk>
>             http://lists.devloop.org.uk/mailman/listinfo/shifter-users
>
>
>
>
>         -- 
>         -- Doug Doole
>
>         aibohphobia - The irrational fear of palindromes
>
>
>
>
>     -- 
>     -- Doug Doole
>
>     aibohphobia - The irrational fear of palindromes
>
>
>
>
> -- 
> -- Doug Doole
>
> aibohphobia - The irrational fear of palindromes




More information about the shifter-users mailing list