[winswitch] xpra 0.14.23 - Spinners not going away

Tue May 19 16:20:42 BST 2015

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?

On Wed, May 6, 2015 at 11:11 AM, Douglas Doole <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> 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
>

-- 
-- Doug Doole

aibohphobia - The irrational fear of palindromes