[mythtv-users] livetv dies with hdhomerun prime

Khanh Tran khanh at khanh.net
Wed Nov 16 00:29:45 UTC 2011


On Sun, Nov 13, 2011 at 9:18 AM, Khanh Tran <khanh at khanh.net> wrote:
> I'm having this problem where livetv just stops.  I'm logging
> mythbackend with general,important,record and this is a sample of the
> log across where livetv just dies.  Any help is appreciated.
>
>
> 2011-11-13 09:00:06.419 HDHRSH(192.168.1.102-0): RunTS(): begin
> 2011-11-13 09:00:06.485 HDHRRec(1): SetPAT(704 on 0x229)
> 2011-11-13 09:00:06.493 HDHRSH(192.168.1.102-0) Error: UpdateFilters
> called in wrong tune mode
> 2011-11-13 09:00:06.501 CreatePATSingleProgram()
> 2011-11-13 09:00:06.518 PAT in input stream
> 2011-11-13 09:00:06.526 Program Association Table
> 2011-11-13 09:00:06.535 desired_program(704) pid(0x229)
> 2011-11-13 09:00:06.543 pmt_pid(0x229)
> 2011-11-13 09:00:06.551 PAT for output stream
> 2011-11-13 09:00:06.560 Program Association Table
> 2011-11-13 09:00:06.568 HDHRRec(1): SetPMT(704)
> 2011-11-13 09:00:06.576 CreatePMTSingleProgram()
> 2011-11-13 09:00:06.585 PMT in input stream
> 2011-11-13 09:00:06.593 Program Map Table ver(0) pid(0x229) pnum(704) len(78)
> 2011-11-13 09:00:06.643 PMT for output stream
> 2011-11-13 09:00:06.651 Program Map Table ver(0) pid(0x229) pnum(1) len(78)
> 2011-11-13 09:00:06.660 RecBase(1:1): SetRecording(0xb0c66680) title(Today)
> 2011-11-13 09:00:06.669 HDHRRec(1): StartRecording -- begin
> 2011-11-13 09:00:06.676 HDHRSH: Using existing stream handler
> 101430AA-0 for 101430AA-0 (2 in use)
> 2011-11-13 09:00:06.684 HDHRRec(1): HDHR opened successfully
> 2011-11-13 09:00:06.693 HDHRSH(101430AA-0): AddListener(0x8a2efd8) -- begin
> 2011-11-13 09:00:06.694 TVRec(1):
> SetFlags(RecorderRunning,RingBufferReady,) ->
> RunMainLoop,NeedToStartRecorder,RecorderRunning,RingBufferReady,
> 2011-11-13 09:00:06.701 HDHRSH(101430AA-0): AddListener(0x8a2efd8) -- locked
> 2011-11-13 09:00:06.709 TVRec(1): ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,RecorderRunning,RingBufferReady,
> 2011-11-13 09:00:06.718 HDHRSH(101430AA-0): AddListener(0x8a2efd8) -- end
> 2011-11-13 09:00:06.782 HDHRSH(101430AA-0): RunTS(): begin
> 2011-11-13 09:00:06.826 FindMPEG2Keyframes: frame rate = 29970
> 2011-11-13 09:00:06.835 PID 0x8c Found Payload Start
> 2011-11-13 09:00:06.863 PID 0x8d Found Payload Start
> 2011-11-13 09:00:07.127 FindMPEG2Keyframes: frame rate = 29970
> 2011-11-13 09:00:07.135 PID 0x220 Found Payload Start
> 2011-11-13 09:00:07.239 PID 0x222 Found Payload Start
> 2011-11-13 09:00:07.268 PID 0x221 Found Payload Start
> 2011-11-13 09:07:27.664 TVRec(18): StopLiveTV(void) curRec: 0xb0c276d0
> pseudoRec: 0x0
> 2011-11-13 09:07:27.676 TVRec(18): Changing from WatchingLiveTV to None
> 2011-11-13 09:07:27.683 TVRec(18):
> ClearFlags(FrontendReady,CancelNextRecording,) ->
> RunMainLoop,RecorderRunning,RingBufferReady,
> 2011-11-13 09:07:27.692 TVRec(18): HandleTuning Request: Program(no)
> channel() input() flags(KillRec,KillRingBuffer,)
> 2011-11-13 09:07:27.718 HDHRRec(18): StartRecording -- ending...
> 2011-11-13 09:07:27.733 HDHRSH(192.168.1.102-2):
> RemoveListener(0x82cc3a8) -- begin
>
> 2011-11-13 09:07:27.742 HDHRSH(192.168.1.102-2):
> RemoveListener(0x82cc3a8) -- locked
> 2011-11-13 09:07:27.750 HDHRSH(192.168.1.102-2) Error: UpdateFilters
> called in wrong tune mode
> 2011-11-13 09:07:27.763 HDHRSH(192.168.1.102-2): RunTS(): shutdown
> 2011-11-13 09:07:27.775 HDHRSH(192.168.1.102-2) Error: UpdateFilters
> called in wrong tune mode
> 2011-11-13 09:07:28.284 HDHRSH(192.168.1.102-2): RunTS(): end
> 2011-11-13 09:07:28.291 HDHRSH(192.168.1.102-2):
> RemoveListener(0x82cc3a8) -- end
> 2011-11-13 09:07:28.300 HDHRRec(18): Close() -- begin
> 2011-11-13 09:07:28.308 HDHRRec(18): Close() -- end
> 2011-11-13 09:07:28.349 HDHRRec(18): StartRecording -- end
> 2011-11-13 09:07:28.358 TVRec(18): ClearFlags(RecorderRunning,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.375 HDHRRec(18): Close() -- begin
> 2011-11-13 09:07:28.383 HDHRRec(18): Close() -- end
> 2011-11-13 09:07:28.391 RecBase(18:18): SetRecording(0x0)
> 2011-11-13 09:07:28.400 TVRec(18): FinishedRecording(Eyewitness News
> This Morning) in recgroup: LiveTV
> 2011-11-13 09:07:28.408 Chain: Updated endtime for
> '5707_20111113090000' to 20111113090728
> 2011-11-13 09:07:28.419 HDHRSH(192.168.1.102-2): Tuning channel none
> (was qam:699000000)
> 2011-11-13 09:07:28.427 TVRec(18): ClearFlags(RecorderRunning,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.433 HDHRSH: Closing handler for 192.168.1.102-2
> 2011-11-13 09:07:28.525 HDHRSH(192.168.1.102-2): Not Re-Tuning channel none
> 2011-11-13 09:07:28.557 TVRec(18): Tearing down RingBuffer
> 2011-11-13 09:07:28.567 TVRec(18): ClearFlags(PENDINGACTIONS,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.576 TVRec(18): SetFlags(FrontendReady,) ->
> FrontendReady,RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.595 Getting next free recorder after : -1
> 2011-11-13 09:07:28.601 Checking card 1. Best card so far -1
> 2011-11-13 09:07:28.608 Checking card 2. Best card so far 2
> 2011-11-13 09:07:28.619 Checking card 4. Best card so far 4
> 2011-11-13 09:07:28.626 Checking card 14. Best card so far 4
> 2011-11-13 09:07:28.633 Checking card 16. Best card so far 16
> 2011-11-13 09:07:28.642 Checking card 18. Best card so far 18
> 2011-11-13 09:07:28.666 MainServer::ANN Playback
> 2011-11-13 09:07:28.674 adding: LivingRoom-Dell1525 as a client (events: 0)
> 2011-11-13 09:07:28.716 TVRec(18): Found channel (707) on current card(18).
> 2011-11-13 09:07:28.727 TVRec(18): Changing from None to WatchingLiveTV
> 2011-11-13 09:07:28.733 TVRec(18):
> ClearFlags(FrontendReady,CancelNextRecording,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.741 TVRec(18): HandleTuning Request: Program(no)
> channel() input() flags(LiveTV,)
> 2011-11-13 09:07:28.753 TVRec(18): HW Tuner: 18->18
> 2011-11-13 09:07:28.758 TVRec(18): ClearFlags(PENDINGACTIONS,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:28.766 TVRec(18): No recorder yet, calling TuningFrequency
> 2011-11-13 09:07:28.785 HDHRSH(192.168.1.102-2): Successfully
> connected to device
> 2011-11-13 09:07:28.791 HDHRSH: Creating new stream handler
> 192.168.1.102-2 for 192.168.1.102-2
> 2011-11-13 09:07:28.814 TVRec(18): CreateLiveTVRingBuffer(707)
> 2011-11-13 09:07:28.825 TVRec(18): GetProgramRingBufferForLiveTV()
> 2011-11-13 09:07:28.834 LoadFromScheduler(): Error, called from backend.
> 2011-11-13 09:07:28.846 TVRec(18): StartedRecording(0x88e0358)
> fn(/mnt/video/livetv/5707_20111113090728.mpg)
> 2011-11-13 09:07:28.849 AutoExpire: CalcParams(): Max required Free
> Space: 6.0 GB w/freq: 4 min
> 2011-11-13 09:07:28.860 Chain: Appended at 0 '5707_20111113090728'
> 2011-11-13 09:07:28.878 TVRec(18): Starting Signal Monitor
> 2011-11-13 09:07:28.883 TVRec(18): SetupSignalMonitor(1, 1)
> 2011-11-13 09:07:28.891 HDHRSH: Using existing stream handler
> 192.168.1.102-2 for 192.168.1.102-2 (2 in use)
> 2011-11-13 09:07:28.899 TVRec(18): Signal monitor successfully created
> 2011-11-13 09:07:28.881 HDHRSH(192.168.1.102-2): Tuning vchannel 707
> 2011-11-13 09:07:28.908 TVRec(18): SetFlags(SignalMonitorRunning,) ->
> RunMainLoop,SignalMonitorRunning,RingBufferReady,
> 2011-11-13 09:07:28.924 TVRec(18): ClearFlags(WaitingForSignal,) ->
> RunMainLoop,SignalMonitorRunning,RingBufferReady,
> 2011-11-13 09:07:28.933 TVRec(18): SetFlags(WaitingForSignal,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
> 2011-11-13 09:07:28.941 TVRec(18): SetFlags(DummyRecorderRunning,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2011-11-13 09:07:28.949 DummyDTVRecorder -- started
> 2011-11-13 09:07:28.958 TVRec(18): SetFlags(RingBufferReady,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
>
> 2011-11-13 09:07:28.949 DummyDTVRecorder -- started
> 2011-11-13 09:07:28.958 TVRec(18): SetFlags(RingBufferReady,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2011-11-13 09:07:28.958 TVRec(18): Setting up table monitoring.
> 2011-11-13 09:07:28.966 TVRec(18): ClearFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2011-11-13 09:07:28.977 Using profile 'Live TV' to record
> 2011-11-13 09:07:28.983 TVRec(18): SetFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBuf$
> 2011-11-13 09:07:28.991 TVRec(18): MPEG program number: 0
> 2011-11-13 09:07:29.007 TVRec(18): Successfully set up MPEG table monitoring.
> 2011-11-13 09:07:29.016 TVRec(18): SetFlags(CancelNextRecording,) ->
> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRec$
> 2011-11-13 09:07:29.057 TVRec(18): StopLiveTV(void) curRec: 0x88e0358
> pseudoRec: 0x0
> 2011-11-13 09:07:29.158 TVRec(18): Changing from WatchingLiveTV to None
> 2011-11-13 09:07:29.191 TVRec(18):
> ClearFlags(FrontendReady,CancelNextRecording,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorde$
> 2011-11-13 09:07:29.199 TVRec(18): HandleTuning Request: Program(no)
> channel() input() flags(KillRec,KillRingBuffer,)
> 2011-11-13 09:07:29.207 TVRec(18): TeardownSignalMonitor() -- begin
> 2011-11-13 09:07:29.222 HDHRSH(192.168.1.102-2):
> RemoveListener(0x9a61a3a8) -- begin
> 2011-11-13 09:07:29.232 HDHRSH(192.168.1.102-2):
> RemoveListener(0x9a61a3a8) -- locked
> 2011-11-13 09:07:29.241 HDHRSH(192.168.1.102-2):
> RemoveListener(0x9a61a3a8) -- end
> 2011-11-13 09:07:29.249 TVRec(18): TeardownSignalMonitor() -- end
> 2011-11-13 09:07:29.257 TVRec(18): ClearFlags(SignalMonitorRunning,)
> -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
> 2011-11-13 09:07:29.266 TVRec(18): ClearFlags(WaitingForSignal,) ->
> RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
> 2011-11-13 09:07:29.274 TVRec(18): ClearFlags(DummyRecorderRunning,)
> -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2011-11-13 09:07:29.283 TVRec(18): FinishedRecording(Eyewitness News
> This Morning) in recgroup: LiveTV
> 2011-11-13 09:07:29.291 Chain: Updated endtime for
> '5707_20111113090728' to 20111113090729
> 2011-11-13 09:07:29.300 TVRec(18): ClearFlags(RecorderRunning,) ->
> RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2011-11-13 09:07:29.308 TVRec(18): FinishedRecording(Eyewitness News
> This Morning) in recgroup: LiveTV
> 2011-11-13 09:07:29.316 Chain: Updated endtime for
> '5707_20111113090728' to 20111113090729
> 2011-11-13 09:07:29.325 HDHRSH(192.168.1.102-2): Tuning channel none
> (was qam:699000000)
> 2011-11-13 09:07:29.334 TVRec(18): ClearFlags(RecorderRunning,) ->
> RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2011-11-13 09:07:29.341 HDHRSH: Closing handler for 192.168.1.102-2
> 2011-11-13 09:07:29.349 HDHRSH(192.168.1.102-2): Not Re-Tuning channel none
> 2011-11-13 09:07:29.357 TVRec(18): Tearing down RingBuffer
> 2011-11-13 09:07:29.366 TVRec(18): ClearFlags(PENDINGACTIONS,) ->
> RunMainLoop,RingBufferReady,
> 2011-11-13 09:07:35.699 MainServer::ANN Monitor
> 2011-11-13 09:07:35.711 adding: LivingRoom-Dell1525 as a client (events: 0)
> 2011-11-13 09:07:35.721 MainServer::ANN Monitor
> 2011-11-13 09:07:35.728 adding: LivingRoom-Dell1525 as a client (events: 1)
>

still trying to track down the issue here...  My frontend shows a lot of:


2011-11-15 19:07:20.375 Player(4): Waited 100ms for video buffers
AAAAALAAfAAAAAAAA
2011-11-15 19:07:20.380
RingBuf(myth://192.168.1.3:6543/5725_20111115190000.mpg) Warning:
Taking too long to be allowed to read..
2011-11-15 19:07:20.396
RingBuf(myth://192.168.1.3:6543/5725_20111115190000.mpg) Warning:
Taking too long to be allowed to read..

and after about two minutes of this:


2011-11-15 19:09:10.477 Player(4), Error: Waited too long for decoder
to fill video buffers. Exiting..
2011-11-15 19:09:10.605 TV: Attempting to change from WatchingLiveTV to None
2011-11-15 19:09:10.833 VDPAU Painter: Clearing VDPAU painter cache.
2011-11-15 19:09:10.901 MythPainter: 9 images not yet de-allocated.
2011-11-15 19:09:12.315 TV: Changing from WatchingLiveTV to None
2011-11-15 19:09:12.367 TV: Attempting to change from None to WatchingLiveTV
2011-11-15 19:09:12.368 MythCoreContext: Connecting to backend server:
192.168.1.3:6543 (try 1 of 1)
2011-11-15 19:09:12.369 Using protocol version 63
2011-11-15 19:09:12.430 Spawning LiveTV Recorder -- begin
2011-11-15 19:09:12.543 Spawning LiveTV Recorder -- end
2011-11-15 19:09:12.565 We have a
playbackURL(myth://192.168.1.3:6543/5725_20111115190909.mpg) &
cardtype(DUMMY)
2011-11-15 19:09:12.568 We have a RingBuffer
2011-11-15 19:09:12.570 TV Error: LiveTV not successfully started

...then livetv quits back to the menu.  Could my issue really not be a
backend issue, but rather a frontend or network problem?  I don't seem
to have this problem with anything BUT trying to watch livetv with the
HDHomeRun Prime.  Or maybe, I should say, I never had this problem
UNTIL I started using the Prime.  I also have a HD HomeRun for clear
QAM which I've never had problems with for years.  Finally, I don't
have issues recording, watching pre-recorded or in-progress HDHR Prime
recordings from my frontends...


More information about the mythtv-users mailing list