[mythtv] backend stops responding to clients if you exit and try to re-enter live tv

David Maher dm at netsol.com.au
Sun Sep 18 08:11:24 UTC 2005 

I am not sure if this is a problem with my setup or with the svn version
of myth.
I am running svn 7270, FC4 with vanilla 2.6.13 kernel
When I open live tv on either of my two client frontends, or open the
frontend on the backend, live tv works properly the first time I open
it, no problems changing channels, switching tuners etc. If I exit live
tv and try to go back in again, the backend doesn't tune the channel,
but just sits there. The only way to get live tv going again is to
restart the backend service.

I have tried downgrading the backend to 18.1. This fixes the problem.

Upgrading to CVS brings the problem back.

I have two dvb cards and one analogue tuner, all in the backend.

Here are the frontend and backend logs

Backend:

2005-09-18 17:28:58.874 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.874 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.875 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.875 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.875 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.876 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.876 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.876 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.876 DVB#0 Rec: Resetting Demux Filters
2005-09-18 17:28:58.876 DVB#0 Rec: Adding pid 512 (0x200)
2005-09-18 17:28:58.876 DVB#0 Rec: Adding pid 650 (0x28a)
2005-09-18 17:28:58.876 DVB#0 Rec: Adding pid 576 (0x240)
2005-09-18 17:28:58.876 DVB#0 Rec: Adding pid 660 (0x294)
2005-09-18 17:28:58.876 DVB#0 Rec: Adding pid 2305 (0x901)
2005-09-18 17:28:58.879 DummyRec: Restart! Frames seen 166
2005-09-18 17:28:58.879 TVRec::StopDummyRecorder() -- begin
2005-09-18 17:28:58.879 DummyDTVRecorder::StopRecordingThread(void)
2005-09-18 17:28:58.891 DummyDTVRecorder::FinishRecording()
2005-09-18 17:28:58.894 DummyDTVRecorder::StartRecording -- end
2005-09-18 17:28:58.899 TVRec::StopDummyRecorder() -- end
2005-09-18 17:28:58.899 DVB#0 Rec: Card opened successfully (using TS
mode).
2005-09-18 17:28:58.899 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.899 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.900 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.900 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.900 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.900 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.900 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.901 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.901 DVB#0 Rec: DVBRecorder::ChannelChanged()
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID for ServiceID=689,
PCRPID=2305 (0x901)
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID selecting PID 512 (0x200),
MPEG-2 Video
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID selecting PID 650 (0x28a),
MPEG-2 Audio (eng
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID selecting PID 576 (0x240),
Teletext
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID selecting PID 660 (0x294),
AC3 Audio (eng
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID skipping PID 6080 (0x17c0),
Unknown type: 6
2005-09-18 17:28:58.901 DVB#0 Rec: AutoPID Complete - PAT/PMT Loaded for
service
2005-09-18 17:28:58.901 DVB#0 Rec: Service is FTA
2005-09-18 17:28:58.907 DVB#0 Rec: Resetting Demux Filters
2005-09-18 17:28:58.907 DVB#0 Rec: Adding pid 512 (0x200)
2005-09-18 17:28:58.907 DVB#0 Rec: Adding pid 650 (0x28a)
2005-09-18 17:28:58.907 DVB#0 Rec: Adding pid 576 (0x240)
2005-09-18 17:28:58.907 DVB#0 Rec: Adding pid 660 (0x294)
2005-09-18 17:28:58.907 DVB#0 Rec: Adding pid 2305 (0x901)
2005-09-18 17:28:58.907 DVB#0 Data read from DMX - This is for debugging
with transform.c
2005-09-18 17:28:58.907 DVB#0 Rec: Found first keyframe
2005-09-18 17:28:58.907 DVB#0 Rec: Found Payload Start for PID 576
2005-09-18 17:28:58.938 DVB#0 Rec: Found Payload Start for PID 660
2005-09-18 17:28:58.943 DVB#0 Rec: Found Payload Start for PID 512
2005-09-18 17:28:58.992 DVB#0 Rec: Found Payload Start for PID 650
2005-09-18 17:29:00.448 SIParser: Private Type channel_numbers = 131
defined for NetworkID 4112
2005-09-18 17:29:00.448 SIParser: ChannelNumbers Present using
Descriptor 131
2005-09-18 17:29:00.448 SIParser: Unknown Descriptor: 41 12  2 b7  2  2
b1  1  2 b2  1  2 b0  1  2 b3  1  2
2005-09-18 17:29:00.448 SIParser: Unknown Descriptor: 5f  4  0  0
2005-09-18 17:29:00.448 SIParser: Table[6]->Complete() == true
2005-09-18 17:29:00.448 SIParser: Table[5]->RequirePIDs() == true
2005-09-18 17:29:00.448 SIParser: Adding PID 0x  11 Filter 0x46 Mask
0xfb Buffer 40960
2005-09-18 17:29:01.462 SIParser: SDT: NetworkID=4112 TransportID=563
2005-09-18 17:29:01.462 SIParser: SDT: sid=689 type=1 eit_present=0
eit_requested=0 name=ABC TV Victoria
2005-09-18 17:29:01.462 SIParser: SDT: sid=690 type=1 eit_present=0
eit_requested=0 name=ABC2
2005-09-18 17:29:01.463 SIParser: SDT: sid=688 type=1 eit_present=0
eit_requested=0 name=ABC HDTV
2005-09-18 17:29:01.463 SIParser: SDT: sid=691 type=1 eit_present=0
eit_requested=0 name=ABC TV
2005-09-18 17:29:01.463 SIParser: SDT: sid=694 type=2 eit_present=0
eit_requested=0 name=ABC DiG Radio
2005-09-18 17:29:01.463 SIParser: SDT: sid=695 type=2 eit_present=0
eit_requested=0 name=ABC DiG Jazz
2005-09-18 17:29:01.463 SIParser: Table[4]->RequirePIDs() == true
2005-09-18 17:29:01.463 SIParser: Adding PID 0x  12 Filter 0x 0 Mask 0x
0 Buffer 4096000
2005-09-18 17:29:21.096 Changing from WatchingLiveTV to None
2005-09-18 17:29:21.096 HandleStateChange()::closeRecorder -- begin
2005-09-18 17:29:21.098 DVB#0 Rec: Closing DVB recorder
2005-09-18 17:29:21.110 DummyDTVRecorder::StopRecordingThread(void)
2005-09-18 17:29:21.129 HandleStateChange()::closeRecorder -- end
2005-09-18 17:29:21.131 StopLiveTV()::closeRecorder -- begin
2005-09-18 17:29:21.131 StopLiveTV()::closeRecorder -- end rbuffer(0)
2005-09-18 17:35:46.253 Getting next free recorder after : -1
2005-09-18 17:35:46.254 Checking card 1. Best card so far 1
2005-09-18 17:35:46.254 Checking card 2. Best card so far 1
2005-09-18 17:35:46.254 Checking card 3. Best card so far 1
2005-09-18 17:35:46.254 Checking card 4. Best card so far 1
2005-09-18 17:35:46.288 MainServer::HandleAnnounce Playback
2005-09-18 17:35:46.288 adding: xcube.netsol.local as a client (events:
0)
2005-09-18 17:35:46.309 MainServer::HandleAnnounce Playback
2005-09-18 17:35:46.309 adding: xcube.netsol.local as a client (events:
0)
2005-09-18 17:35:46.321 adding: xcube.netsol.local as a remote
ringbuffer

At this point the backend  just sits there. If there is another client
frontend watching live tv at the time, it will continue to work, unless
you also exit that frontend's live tv and try to go back in.

The frontend the logs are:

2005-09-18 18:05:35.967 Changing from None to WatchingLiveTV
2005-09-18 18:05:35.996 Video timing method: USleep with busy wait
2005-09-18 18:05:40.450 AVFD: Opened codec 0x8548fa0, id(MPEG2VIDEO)
type(Video)
2005-09-18 18:05:40.524 AvFormatDecoder: Video has changed from 0x0 to
768x576.
2005-09-18 18:05:41.934 AVFD: Opened codec 0x8636360, id(MPEG2VIDEO)
type(Video)
2005-09-18 18:05:41.934 AVFD: Opened codec 0x8528620, id(MP3)
type(Audio)
2005-09-18 18:05:41.935 AvFormatDecoder: Video has changed from 0x0 to
720x576.
2005-09-18 18:05:41.988 prebuffering pause
2005-09-18 18:05:47.140 Attempting to change from WatchingLiveTV to None
2005-09-18 18:05:47.260 cardid: 1 state: 0
2005-09-18 18:05:47.276 cardid: 2 state: 4
2005-09-18 18:05:47.312 cardid: 3 state: 4
2005-09-18 18:05:47.340 cardid: 4 state: -1
2005-09-18 18:05:47.356 cardid: 1 state: 0
2005-09-18 18:05:47.376 cardid: 2 state: 4
2005-09-18 18:05:47.400 cardid: 3 state: 4
2005-09-18 18:05:47.432 cardid: 4 state: -1
2005-09-18 18:05:47.572 Changing from WatchingLiveTV to None
2005-09-18 18:05:47.576 Enable DPMS
2005-09-18 18:05:47.579 Attempting to change from None to None
2005-09-18 18:05:48.584 Attempting to change from None to WatchingLiveTV
2005-09-18 18:05:48.600 Using protocol version 18
2005-09-18 18:06:08.652 ReadStringList timeout (quick).

then

2005-09-18 18:06:48.661 RemoteEncoder::SendReceiveStringList(): No
response.
2005-09-18 18:06:48.673 WriteStringList: Bad socket
2005-09-18 18:06:48.673 ReadStringList: Bad socket
2005-09-18 18:06:48.673 RemoteEncoder::SendReceiveStringList(): No
response.
2005-09-18 18:06:48.673 WriteStringList: Bad socket
2005-09-18 18:06:48.673 ReadStringList: Bad socket
2005-09-18 18:06:48.673 RemoteEncoder::SendReceiveStringList(): No
response.
2005-09-18 18:06:48.674 StartRecorder() -- timed out waiting for
recorder to start
2005-09-18 18:06:48.674 LiveTV not successfully started
2005-09-18 18:07:08.698 ReadStringList timeout (quick).
2005-09-18 18:07:08.698 Remote file timeout.
2005-09-18 18:07:08.698 Enable DPMS

More information about the mythtv-dev mailing list