[mythtv-users] mythtv upgrade broke firewire recording

Don Lewis dl-mythtv at catspoiler.org
Sun May 1 19:15:35 UTC 2011


I've been running 0.24-fixes on Fedora 14 x86_64 for a while now and
firewire recording has been working recently well.  I just upgraded
using atrpms for the first time in a few months and now firewire
recording is totally broken and all I get is zero byte recordings.  My
HDHR still works fine, and my HD-PVR also seems to work (until it hangs
again).  The recording process never seems to get past the Tuning state.
The only odd thing in the log file is this message:
	Warning: No Input in 50 msec

firewire_tester and test-mpeg2 say that everything is ok, and firewire
channel changing works ok.

Any suggestions?

Kernel info is: 2.6.35.12-90.fc14.x86_64

Mythtv info is:
	mythtv-frontend-0.24-270.fc14.x86_64
	libmythtv-0.24_0-0.24-270.fc14.x86_64
	mythtv-0.24-270.fc14.x86_64
	mythtv-themes-0.24-270.fc14.x86_64
	myththemes-0.24-177.noarch
	mythtv-backend-0.24-270.fc14.x86_64
	mythtv-setup-0.24-270.fc14.x86_64
	mythtv-docs-0.24-270.fc14.x86_64

2011-04-30 17:58:01.567 TVRec(13): StartRecording("Doctor Who":"Day of the Moon")
2011-04-30 17:58:01.795 TVRec(13): StartedRecording(0x7fca05b8fd50) fn(/var/video/recordings01/d/4162_20110430175800.mpg)
2011-04-30 17:58:01.865 TVRec(13): ClearFlags(CancelNextRecording,) -> RunMainLoop,RingBufferReady,
2011-04-30 17:58:01.907 TVRec(13): Changing from None to RecordingOnly
2011-04-30 17:58:01.950 TVRec(13): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RingBufferReady,
2011-04-30 17:58:01.991 TVRec(13): HandleTuning Request: Program(yes) channel() input() flags(Recording,)
2011-04-30 17:58:02.034 TVRec(13): HW Tuner: 13->13
2011-04-30 17:58:02.074 TVRec(13): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady,
2011-04-30 17:58:02.115 TVRec(13): No recorder yet, calling TuningFrequency
2011-04-30 17:58:02.158 FireChan(00509400002172DE): Open()
2011-04-30 17:58:02.225 ChannelBase(13): Input #13: 'MPEG2TS' schan(781) sourceid(4) ccid(13)
2011-04-30 17:58:02.256 ChannelBase(13): Current Input #13: 'MPEG2TS'
2011-04-30 17:58:02.290 LFireDev(00509400002172DE): Starting Port Handler Thread
2011-04-30 17:58:02.323 LFireDev(00509400002172DE): Starting Port Handler Thread -- locked
2011-04-30 17:58:02.356 LFireDev(00509400002172DE): OpenPort()
2011-04-30 17:58:02.389 LFireDev(00509400002172DE): OpenPort() -- got lock
2011-04-30 17:58:02.422 LAVCInfo(): Getting raw1394 handle for port 0
2011-04-30 17:58:02.513 LFireDev(00509400002172DE): Subunit Types: Tuner, Panel, 
2011-04-30 17:58:02.547 LFireDev(00509400002172DE): Starting port handler thread
2011-04-30 17:58:02.580 LFireDev(00509400002172DE): Waiting for port handler thread to start
2011-04-30 17:58:02.580 LFireDev(00509400002172DE): RunPortHandler -- start
2011-04-30 17:58:02.646 LFireDev(00509400002172DE): RunPortHandler -- got first lock
2011-04-30 17:58:02.680 LFireDev(00509400002172DE): Port handler thread started
2011-04-30 17:58:02.724 ChannelBase(13): SelectChannel 162
2011-04-30 17:58:02.771 TVRec(13): Starting Signal Monitor
2011-04-30 17:58:02.771 ChannelThread::run
2011-04-30 17:58:02.812 TVRec(13): SetupSignalMonitor(1, 0)
2011-04-30 17:58:02.854 FireChan(00509400002172DE): SetChannelByString(162)
2011-04-30 17:58:02.895 FireChan(00509400002172DE): Open()
2011-04-30 17:58:02.944 SetChannelByNumber(162)
2011-04-30 17:58:02.970 FireSM(00509400002172DE): ctor
2011-04-30 17:58:03.003 FireDev(00509400002172DE): Requesting STB Power State
2011-04-30 17:58:03.036 SM(00509400002172DE)::AddFlags: Seen() Match() Wait(Sig,)
2011-04-30 17:58:03.073 FireDev(00509400002172DE): STB Power State: On
2011-04-30 17:58:03.136 SetChannel(model PACE-779, alt 0, chan 162)
2011-04-30 17:58:03.136 FireDev(00509400002172DE): Requesting STB Power State
2011-04-30 17:58:03.213 FireDev(00509400002172DE): STB Power State: On
2011-04-30 17:58:03.244 TVRec(13): Signal monitor successfully created
2011-04-30 17:58:03.244 SetChannel() -- locked
2011-04-30 17:58:03.277 SM(00509400002172DE)::Start: begin
2011-04-30 17:58:03.345 SM(00509400002172DE)::Start: end
2011-04-30 17:58:03.377 TVRec(13): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,
2011-04-30 17:58:03.418 TVRec(13): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,RingBufferReady,
2011-04-30 17:58:03.451 TVRec(13): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2011-04-30 17:58:03.484 TVRec(13): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
2011-04-30 17:58:03.518 TVRec(13): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2011-04-30 17:58:03.615 AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min
2011-04-30 17:58:03.652 Started recording: "Doctor Who":"Day of the Moon": channel 4162 on cardid 13, sourceid 4
2011-04-30 17:58:03.888 MainServer::ANN Monitor
2011-04-30 17:58:03.924 adding: myth2.catspoiler.org as a client (events: 2)
2011-04-30 17:58:04.823 SetLastChannel(162): cleared: no
2011-04-30 17:58:04.859 FireChan(00509400002172DE): SetChannelByString(162) success
2011-04-30 17:58:04.909 TVRec(13): Setting up table monitoring.
2011-04-30 17:58:04.946 Using profile 'Default' to record
2011-04-30 17:58:04.984 TVRec(13): MPEG program number: 1
2011-04-30 17:58:05.017 DTVSM(00509400002172DE)::SetProgramNumber(1): 
2011-04-30 17:58:05.050 SM(00509400002172DE)::RemoveFlags: Seen(PMT,Crypt,) Match(PMT,Crypt,) Wait()
2011-04-30 17:58:05.083 SM(00509400002172DE)::AddFlags: Seen() Match() Wait(PMT,)
2011-04-30 17:58:05.117 SM(00509400002172DE)::AddFlags: Seen() Match() Wait(PAT,PMT,Pos,)
2011-04-30 17:58:05.158 TVRec(13): Successfully set up MPEG table monitoring.
2011-04-30 17:58:05.208 FireDev(00509400002172DE): Requesting STB Power State
2011-04-30 17:58:05.253 FireDev(00509400002172DE): STB Power State: On
2011-04-30 17:58:05.291 SM(00509400002172DE)::AddFlags: Seen(STB,) Match(STB,) Wait()
2011-04-30 17:58:05.324 FireSM(00509400002172DE): UpdateValues() -- Waiting for table monitor to start
2011-04-30 17:58:05.324 FireSM(00509400002172DE): RunTableMonitor(): -- begin
2011-04-30 17:58:05.357 FireSM(00509400002172DE): UpdateValues() -- Table monitor started
2011-04-30 17:58:05.390 LFireDev(00509400002172DE): Starting Port Handler Thread
2011-04-30 17:58:05.457 LFireDev(00509400002172DE): Starting Port Handler Thread -- locked
2011-04-30 17:58:05.490 LFireDev(00509400002172DE): OpenPort()
2011-04-30 17:58:05.523 LFireDev(00509400002172DE): OpenPort() -- got lock
2011-04-30 17:58:05.556 FireDev(00509400002172DE): AddListener() 1
2011-04-30 17:58:05.589 LFireDev(00509400002172DE): Opening P2P connection
2011-04-30 17:58:05.641 LFireDev(00509400002172DE): OpenAVStream
2011-04-30 17:58:05.681 LFireDev(00509400002172DE): Opening A/V stream object
2011-04-30 17:58:05.714 LFireDev(00509400002172DE): Buffered packets 2000 (8000 KB)
2011-04-30 17:58:05.747 LFireDev(00509400002172DE): Starting A/V streaming -- really
2011-04-30 17:58:05.792 LFireDev(00509400002172DE): Starting A/V streaming -- done
2011-04-30 17:58:05.830 LFireDev(00509400002172DE), Warning: No Input in 50 msec...
[SNIP]
2011-04-30 19:01:00.942 TVRec(13): ClearFlags(FinishRecording,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2011-04-30 19:01:00.976 TVRec(13): Changing from RecordingOnly to None
2011-04-30 19:01:01.018 TVRec(13): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,RingBufferReady,
2011-04-30 19:01:01.059 TVRec(13): HandleTuning Request: Program(no) channel() input() flags(CloseRec,KillRingBuffer,)
2011-04-30 19:01:01.101 TVRec(13): TeardownSignalMonitor() -- begin
2011-04-30 19:01:01.142 FireSM(00509400002172DE): dtor
2011-04-30 19:01:01.200 FireSM(00509400002172DE): Stop() -- begin
2011-04-30 19:01:01.250 SM(00509400002172DE)::Stop: begin
2011-04-30 19:01:01.318 SM(00509400002172DE)::Stop: end
2011-04-30 19:01:01.433 FireSM(00509400002172DE): RunTableMonitor(): -- shutdown 
2011-04-30 19:01:01.474 FireDev(00509400002172DE): RemoveListener() 0
2011-04-30 19:01:01.524 LFireDev(00509400002172DE): Stopping A/V streaming -- really
2011-04-30 19:01:01.575 LFireDev(00509400002172DE): Stopped A/V streaming
2011-04-30 19:01:01.607 LFireDev(00509400002172DE): Closing A/V stream object
2011-04-30 19:01:01.640 LFireDev(00509400002172DE): Closing P2P connection
2011-04-30 19:01:01.682 LFireDev(00509400002172DE): Stopping Port Handler Thread
2011-04-30 19:01:01.714 LFireDev(00509400002172DE): Stopping Port Handler Thread -- locked
2011-04-30 19:01:01.748 LFireDev(00509400002172DE): ClosePort()
2011-04-30 19:01:01.781 FireSM(00509400002172DE): RunTableMonitor(): -- end
2011-04-30 19:01:01.814 FireSM(00509400002172DE): Stop() -- end
2011-04-30 19:01:01.847 SM(00509400002172DE)::Stop: begin
2011-04-30 19:01:01.889 SM(00509400002172DE)::Stop: end
2011-04-30 19:01:01.930 TVRec(13): TeardownSignalMonitor() -- end
2011-04-30 19:01:01.972 TVRec(13): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,RingBufferReady,
2011-04-30 19:01:02.013 TVRec(13): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
2011-04-30 19:01:02.047 TVRec(13): FinishedRecording(Doctor Who) in recgroup: Default
2011-04-30 19:01:02.080 Updating status for "Doctor Who":"Day of the Moon" on cardid 13 (Tuning => Recorder Failed)
2011-04-30 19:01:02.081 TVRec(13): ClearFlags(RecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
2011-04-30 19:01:02.123 Reschedule requested for id 0.
2011-04-30 19:01:02.156 TVRec(13): FinishedRecording(Doctor Who) in recgroup: Default



More information about the mythtv-users mailing list