[mythtv-users] After upgrad 0.21 -> 0.22, backend randomly hangs

Simon Ferber simon at ist-schlau.de
Fri Jun 18 15:47:10 UTC 2010


> Malcolm Parsons wrote:
>> On 18 June 2010 10:38, Simon Ferber <simon at ist-schlau.de> wrote:
>>
>>> I have a strange problem. I used to have a pretty stable
>>> mythbackend. Two
>>> weeks ago I updated it from 0.21 to 0.22 as the former version wasn't
>>> supported by gentoo anymore.
>>> Since then I see randomly hangs from the backend couple times a day.
>>> The
>>> process is still running, but it doesn't respond. Trying to open
>>> LiveTV or
>>> "backend status" via web, it (the client) waits forever. The logs
>>> doesn't
>>> say anything about what's happening.
>>> I still can browse EPG via mythweb and see some "adding: fe1 as a
>>> client
>>> (events: 1)" in the log. So the backend didn't die complete.
>>>
>>> Has anyone seen this behaviour?
>>
>> I'm seeing this with 0.23-fixes.
>>
>>> What could I do?
>>
>> Open a ticket?
>>
> If it's responding via Mythweb, it isn't hanging. Try investigating
> the connection between BE and FE. If you run mythfrontend in a console
> window (so that you get the log), what does it say? Does it find the
> back end?
>
I tried what you sugested.
The (remote) frontend starts quite fine. I can even watch recordings
without problems.

When starting LiveTV I get this on the frontends console:

2010-06-18 17:38:56.796 MythSocket(a37c8f8:24): readStringList: Error,
timed out after 7000 ms.
2010-06-18 17:38:56.796 Connection to backend server
lost                                     
2010-06-18 17:38:56.819 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)
2010-06-18 17:38:56.835 Using protocol version
50                                              
2010-06-18 17:38:56.837 MythSocket(aba2530:24): writeStringList: Error,
invalid string list.   
2010-06-18 17:39:03.837 MythSocket(aba2530:24): readStringList: Error,
timed out after 7000 ms.
2010-06-18 17:39:03.838 Reconnection to backend server
failed                                  
2010-06-18 17:39:03.869 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)
2010-06-18 17:39:03.884 Using protocol version
50                                              
2010-06-18 17:39:03.922 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:03.943 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:03.963 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:03.983 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.003 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.023 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.043 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.063 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.084 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.104 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.124 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.144 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.164 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.184 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.204 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.224 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.245 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.265 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.285 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.305 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.325 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.345 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.366 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.386 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.406 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.427 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.447 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.467 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.487 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.507 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.527 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.547 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.567 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.588 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.608 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.628 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.648 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.668 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.688 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.708 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.729 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.749 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.769 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.789 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.809 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.829 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.849 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.869 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.890 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.910 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.930 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.950 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.970 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:04.990 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.010 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.030 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.051 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.071 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.091 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.111 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.131 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.151 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.171 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.191 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.211 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.232 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.252 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.272 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.292 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.312 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.332 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.352 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.375 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.395 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.415 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.435 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.455 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.475 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.496 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.516 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.536 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.556 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.576 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.596 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.616 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.636 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.657 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.677 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.697 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.717 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.737 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.757 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.777 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.797 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.817 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.838 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.858 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.878 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.898 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.918 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.938 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.958 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.978 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:05.999 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.019 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.039 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.059 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.079 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.099 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.120 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.140 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.160 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.180 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.200 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.220 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.240 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.260 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.280 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.301 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.321 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.341 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.361 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.381 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.401 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.421 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.441 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.461 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.482 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.502 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.522 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.542 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.562 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.582 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.602 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.622 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.642 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.662 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.683 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.703 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.723 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.743 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.763 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.783 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.803 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.823 Ignoring key event for now because nvp is not
set                      
2010-06-18 17:39:06.843 Ignoring key event for now because nvp is not
set                      

Quit LiveTV and started to watch a recording without problem:

2010-06-18 17:39:10.653 Loading menu theme from
/usr/share/mythtv/themes/defaultmenu//library.xml
2010-06-18 17:39:12.000 Loading window theme from
/usr/share/mythtv/themes/ProjectGrayhem-wide/recordings-ui.xml
2010-06-18 17:39:12.000 Loading window theme from
/usr/share/mythtv/themes/default-wide/recordings-ui.xml      
2010-06-18 17:39:13.234 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)               
2010-06-18 17:39:13.255 Using protocol version
50                                                              
2010-06-18 17:39:13.303 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)               
2010-06-18 17:39:13.307 Using protocol version
50                                                              
2010-06-18 17:39:13.335 Preview Error: Remote Preview failed, reason
given: ERROR_UNKNOWN                      
2010-06-18 17:39:13.364 Preview Error: Remote Preview failed, reason
given: ERROR_UNKNOWN                      
2010-06-18 17:39:13.433 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)               
2010-06-18 17:39:13.439 Using protocol version
50                                                              
2010-06-18 17:39:13.475 Preview Error: Remote Preview failed, reason
given: ERROR_UNKNOWN                      
2010-06-18 17:39:16.759 TV: Attempting to change from None to Watching
WatchingPreRecorded                     
2010-06-18 17:39:16.908 TV: StartPlayer(0, Watching WatchingPreRecorded,
main) -- begin                        
2010-06-18 17:39:17.745 AFD: Opened codec 0xab5f1a0, id(MPEG2VIDEO)
type(Video)                                
2010-06-18 17:39:17.745 AFD: codec MP2 has 2
channels                                                          
2010-06-18 17:39:17.745 AFD: Opened codec 0xab5f8d0, id(MP2)
type(Audio)                                       
2010-06-18 17:39:17.747 Opening audio device 'default'. ch 2(2) sr
48000                                       
2010-06-18 17:39:17.748 Opening ALSA audio device
'default'.                                                   
2010-06-18 17:39:17.755 mixer unable to find control Master
1                                                  
2010-06-18 17:39:18.997 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video
Texture'                               
2010-06-18 17:39:19.071 OSD Theme Dimensions W: 640 H:
480                                                     
2010-06-18 17:39:19.481 TV: StartPlayer(0, Watching WatchingPreRecorded,
main) -- end ok                       
2010-06-18 17:39:19.482 TV: Changing from None to Watching
WatchingPreRecorded                                 
2010-06-18 17:39:19.485 Couldn't load deinterlace filter
none                                                  
2010-06-18 17:39:19.485 Video timing method: USleep with busy
wait                                             
2010-06-18 17:39:19.485 Using realtime
priority.                                                               
2010-06-18 17:39:19.510 ScreenSaverX11Private: DPMS Deactivated
1                                              
2010-06-18 17:39:19.594 Couldn't load deinterlace filter none
2010-06-18 17:39:19.594 Failed to enable deinterlacing
2010-06-18 17:39:21.124 TV: Attempting to change from Watching
WatchingPreRecorded to None
2010-06-18 17:39:21.156 TV: Changing from Watching WatchingPreRecorded
to None
2010-06-18 17:39:21.159 ScreenSaverX11Private: DPMS Reactivated 1

Exit from watching recording and tried LiveTV again:

2010-06-18 17:39:31.224 MythSocket(a2a2b60:24): readStringList: Error,
timed out after 7000 ms.
2010-06-18 17:39:31.225 Connection to backend server lost
2010-06-18 17:39:31.247 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)
2010-06-18 17:39:31.261 Using protocol version 50
2010-06-18 17:39:31.264 MythSocket(a075e48:24): writeStringList: Error,
invalid string list.
2010-06-18 17:39:38.266 MythSocket(a075e48:24): readStringList: Error,
timed out after 7000 ms.
2010-06-18 17:39:38.266 Reconnection to backend server failed
2010-06-18 17:39:38.295 MythContext: Connecting to backend server:
192.168.2.1:6543 (try 1 of 1)
2010-06-18 17:39:38.311 Using protocol version 50
2010-06-18 17:39:39.462 TV: Attempting to change from None to Watching
WatchingRecording
2010-06-18 17:39:46.126 TV Error: Couldn't find recorder for in-progress
recording
2010-06-18 17:39:46.126 TV: StartPlayer(0, Watching WatchingPreRecorded,
main) -- begin
2010-06-18 17:39:46.392
RingBuf(myth://192.168.2.1:6543/33011_20100618173439.mpg) Warning:
Peek() requested 2048 bytes, but only returning 0
2010-06-18 17:39:46.392 NVP::OpenFile(): Error, couldn't read file:
myth://192.168.2.1:6543/33011_20100618173439.mpg
2010-06-18 17:39:46.392 TV: StartPlayer(0, Watching WatchingPreRecorded,
main) -- end error
2010-06-18 17:39:46.400 ScreenSaverX11Private: DPMS Deactivated 1


The browser is currently still waiting (since 7 minutes) for the status
page in mythweb...



More information about the mythtv-users mailing list