[mythtv] Lockup problem with analogue tuner

Sat Oct 1 03:13:56 UTC 2005

On Fri, 2005-09-30 at 13:13 -0400, Daniel Kristjansson wrote:

> For the lockup problem I will need an annotated backend log with
> "-v record,channel". It looks like something is broken, but I can't
> tell what without the proper debug messages. Use the latest SVN,
> it contains some fixes for RingBuffer problems.
> 
> -- Daniel
> 

Hi,
I've tested the analogue tuner with tvtime, no probs at all (I wish I
could get 80% of the picture quality that tvtime has from myth).

I'm using the latest SVN 7368.

I have to admit that I am not a linux expert, so I'm not sure if I am
giving you all the information that you want. I have recompiled the
latest SVN with debug enabled but here are some annotated backend logs

Switch to analogue tuner and try to change channels:

2005-10-01 13:06:49.332 Getting next free recorder after : 2
2005-10-01 13:06:49.364 MainServer::HandleAnnounce Playback
2005-10-01 13:06:49.364 adding: myth.netsol.local as a client (events:
0)
2005-10-01 13:06:49.388 MainServer::HandleAnnounce Playback
2005-10-01 13:06:49.388 adding: myth.netsol.local as a client (events:
0)
2005-10-01 13:06:49.400 adding: myth.netsol.local as a remote ringbuffer
2005-10-01 13:06:49.416 TVRec(3): Changing from None to WatchingLiveTV
2005-10-01 13:06:49.416 TVRec(3):
ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
2005-10-01 13:06:49.416 TVRec(3): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,
2005-10-01 13:06:49.417 TVRec(3): Request: Program(no) channel() input()
flags(LiveTV,)
2005-10-01 13:06:49.417 TVRec(3):
ClearFlags(WaitingForSIParser,SIParserRunning,) ->
RunMainLoop,AskAllowRecording,
2005-10-01 13:06:49.417 TVRec(3):
ClearFlags(WaitingForSignal,SignalMonitorRunning,) ->
RunMainLoop,AskAllowRecording,
2005-10-01 13:06:49.417 TVRec(3): ClearFlags(PENDINGACTIONS,) ->
RunMainLoop,AskAllowRecording,
2005-10-01 13:06:49.444 Channel(/dev/video0)::SwitchToInput(in 0)
2005-10-01 13:06:49.468 Channel(/dev/video0)::SwitchToInput() setting
video mode to PAL
2005-10-01 13:06:49.492 Channel(/dev/video0)::SetChannelByString(10)
2005-10-01 13:06:49.493 ChannelBase(/dev/video0)::SetCachedATSCInfo():
RESET
2005-10-01 13:06:49.495 Channel(/dev/video0)::TuneTo(8):
curList[9].freq(189250)
2005-10-01 13:06:49.495 Channel(/dev/video0)::Tune(189250000, , analog)
2005-10-01 13:06:49.504 Channel(/dev/video0)::Tune(): Frequency is now
189250000
2005-10-01 13:06:49.504 ChannelBase(/dev/video0)::SetCachedATSCInfo(10):
1_0
2005-10-01 13:06:49.506 TVRec(3): SetFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,
2005-10-01 13:06:49.507 TVRec(3): Starting Recorder
2005-10-01 13:06:49.517 TVRec(3): Using profile 'Live TV' to record
2005-10-01 13:06:49.531 SetRecording(0x0)
[mpeg4 @ 0xb75f0630]removing common factors from framerate
2005-10-01 13:06:49.560 TVRec(3): SetFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,NeedToStartRecorder,RecorderRunning,
2005-10-01 13:06:49.560 TVRec(3): ClearFlags(NeedToStartRecorder,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,
strange error flushing buffer ...
2005-10-01 13:06:50.441 TVRec(3): SetFlags(FrontendReady,) ->
FrontendReady,RunMainLoop,AskAllowRecording,RecorderRunning,
2005-10-01 13:07:06.485 TVRec(3): ClearFlags(FlagRingBufferReset,) ->
FrontendReady,RunMainLoop,AskAllowRecording,RecorderRunning,
2005-10-01 13:07:06.485 TVRec(3): Request: Program(no)
channel(NextChannel 0) input() flags(LiveTV,)
2005-10-01 13:07:06.486 TVRec(3):
ClearFlags(WaitingForSIParser,SIParserRunning,) ->
FrontendReady,RunMainLoop,AskAllowRecording,RecorderRunning,
2005-10-01 13:07:06.486 TVRec(3):
ClearFlags(WaitingForSignal,SignalMonitorRunning,) ->
FrontendReady,RunMainLoop,AskAllowRecording,RecorderRunning,
2005-10-01 13:07:06.486 TVRec(3): ClearFlags(PENDINGACTIONS,) ->
FrontendReady,RunMainLoop,AskAllowRecording,RecorderRunning,
2005-10-01 13:07:06.486 TVRec(3): SetFlags(WaitingForRecPause,) ->
FrontendReady,RunMainLoop,AskAllowRecording,WaitingForRecPause,RecorderRunning,
2005-10-01 13:07:41.514 RingBuffer: Couldn't read data from the capture
card in 15 seconds. Stopping.
2005-10-01 13:07:41.534 TVRec(3): Changing from WatchingLiveTV to None
2005-10-01 13:07:41.534 TVRec(3):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,AskAllowRecording,WaitingForRecPause,RecorderRunning,
2005-10-01 13:07:41.534 TVRec(3): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,WaitingForRecPause,RecorderRunning,
2005-10-01 13:07:41.534 TVRec(3): Request: Program(no) channel() input()
flags(KillRec,)
2005-10-01 13:07:41.534 TVRec(3):
ClearFlags(WaitingForSIParser,SIParserRunning,) ->
RunMainLoop,AskAllowRecording,WaitingForRecPause,RecorderRunning,
2005-10-01 13:07:41.535 TVRec(3):
ClearFlags(WaitingForSignal,SignalMonitorRunning,) ->
RunMainLoop,AskAllowRecording,WaitingForRecPause,RecorderRunning,

Frontend says Error was encountered while displaying video, click on
return to main menu and black screen follows.

Frontend log excerpt
2005-10-01 13:10:19.207 AVFD: Opened codec 0x87dc160, id(MPEG2VIDEO)
type(Video)
2005-10-01 13:10:19.207 AVFD: Opened codec 0x8786340, id(MP3)
type(Audio)
2005-10-01 13:10:19.208 Opening audio device 'mixed-digital'.
2005-10-01 13:10:19.565 SyncPositionMap liveTV, from Encoder: 4 entries
2005-10-01 13:10:19.822 Changing from None to WatchingLiveTV
2005-10-01 13:10:19.823 The realtime priority setting is not enabled.
2005-10-01 13:10:19.836 Video timing method: USleep with busy wait
2005-10-01 13:10:20.722 Attempting to change from WatchingLiveTV to None
[mpeg2video @ 0xb75ce630]ac-tex damaged at 7 28
[mpeg2video @ 0xb75ce630]Warning MVs not available
2005-10-01 13:10:21.046 Changing from WatchingLiveTV to None
2005-10-01 13:10:21.070 Attempting to change from None to WatchingLiveTV
2005-10-01 13:10:21.078 Using protocol version 18
2005-10-01 13:10:21.278 Output filters for this channel are: ''
2005-10-01 13:10:21.789 SyncPositionMap liveTV, from Encoder: 1 entries
2005-10-01 13:10:21.790 Opening audio device 'mixed-digital'.
2005-10-01 13:10:22.066 Changing from None to WatchingLiveTV
2005-10-01 13:10:22.058 The realtime priority setting is not enabled.
2005-10-01 13:10:22.089 Video timing method: USleep with busy wait
2005-10-01 13:11:27.884 ReadStringList timeout (quick).
2005-10-01 13:11:27.884 RemoteEncoder::SendReceiveStringList(): No
response.
2005-10-01 13:11:27.884 WriteStringList: Bad socket
2005-10-01 13:11:27.884 ReadStringList: Bad socket
2005-10-01 13:11:27.884 RemoteEncoder::SendReceiveStringList(): No
response.
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
2005-10-01 13:11:27.884 Output filters for this channel are: ''
2005-10-01 13:11:27.884 WriteStringList: Bad socket
2005-10-01 13:11:27.885 ReadStringList: Bad socket
2005-10-01 13:11:27.885 RemoteEncoder::SendReceiveStringList(): No
response.
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
2005-10-01 13:11:27.885 WriteStringList: Bad socket
2005-10-01 13:11:27.885 ReadStringList: Bad socket
2005-10-01 13:11:27.885 RemoteEncoder::SendReceiveStringList(): No
response.
2005-10-01 13:11:27.885 decodeLongLong() called with offset >= list
size.
2005-10-01 13:11:27.887 WriteStringList: Bad socket
2005-10-01 13:11:27.887 ReadStringList: Bad socket
2005-10-01 13:11:27.887 RemoteEncoder::SendReceiveStringList(): No
response.
2005-10-01 13:11:27.887 decodeLongLong() called with offset >= list
size.
2005-10-01 13:11:27.919 WriteStringList: Bad socket
2005-10-01 13:11:27.919 ReadStringList: Bad socket
2005-10-01 13:11:27.919 RemoteEncoder::SendReceiveStringList(): No
response.
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
ASSERT: "i <= nodes" in /usr/lib/qt-3.3/include/qvaluelist.h (373)
2005-10-01 13:11:27.919 WriteStringList: Bad socket
2005-10-01 13:11:27.919 ReadStringList: Bad socket
2005-10-01 13:11:27.919 RemoteEncoder::SendReceiveStringList(): No
response.
2005-10-01 13:11:27.919 decodeLongLong() called with offset >= list
size.
2005-10-01 13:11:27.919 WriteStringList: Bad socket
2005-10-01 13:11:27.919 ReadStringList: Bad socket
2005-10-01 13:11:27.920 RemoteEncoder::SendReceiveStringList(): No
response.
2005-10-01 13:11:27.920 decodeLongLong() called with offset >= list
size.
2005-10-01 13:11:27.923 Attempting to change from WatchingLiveTV to None
2005-10-01 13:11:28.924 Waited too long for ringbuffer pause..
2005-10-01 13:11:29.928 Waited too long for ringbuffer pause..
2005-10-01 13:11:30.932 Waited too long for ringbuffer pause..
2005-10-01 13:11:31.936 Waited too long for ringbuffer pause..
2005-10-01 13:11:32.940 Waited too long for ringbuffer pause..
2005-10-01 13:11:33.944 Waited too long for ringbuffer pause..
2005-10-01 13:11:34.948 Waited too long for ringbuffer pause..
2005-10-01 13:11:35.952 Waited too long for ringbuffer pause..
2005-10-01 13:11:36.956 Waited too long for ringbuffer pause..
2005-10-01 13:11:37.960 Waited too long for ringbuffer pause..
2005-10-01 13:11:38.964 Waited too long for ringbuffer pause..
2005-10-01 13:11:39.968 Waited too long for ringbuffer pause..
2005-10-01 13:11:40.972 Waited too long for ringbuffer pause..
2005-10-01 13:11:41.976 Waited too long for ringbuffer pause..
2005-10-01 13:11:42.916 WriteStringList: Bad socket
2005-10-01 13:11:42.917 ReadStringList: Bad socket
2005-10-01 13:11:42.917 RemoteEncoder::SendReceiveStringList(): No
response.

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mythtv.org/pipermail/mythtv-dev/attachments/20051001/1f2998e7/attachment-0001.htm