[mythtv-users] After upgrad 0.21 -> 0.22, backend randomly hangs
Simon Ferber
simon at ist-schlau.de
Tue Jun 22 14:08:23 UTC 2010
*bump* Noone else seen this behaviour?
>
>> 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...
>
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users
>
>
More information about the mythtv-users
mailing list