[winswitch] xpra 0.14.23 - Spinners not going away

Wed May 6 16:11:33 BST 2015

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> 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>
> 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
>> 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