Hello All,
I've been playing around with MythTV for the last couple weeks. I've posted a messgae similar to this one on www.mythtvtalk.com in hopes that someone can help me out. Anyway, I've got a strange problem and I'm trying to determine it's source. When I use the "-v all" switch with both the backend and the frontend it doesn't seem to give me any errors. Here's my issue:
I launch the backend and it appears to work like a charm, I'm using DVB with a pcHDTV card and it finds the channel (strong signal) and waits for a user. Then I launch the frontend and it comes up looking normal. When I select "LiveTV" one of two things happens. Either it starts to play the channel and everything's okay, I can change channels, audio is good, etc. Or, it gives me a black screen and freezes up. I can ALT-TAB back to my konsole and see the output log of the frontend and backend and no errors. Here is a sample of one run when I went to LiveTV and it froze:
$ cat myth_backend.log
Starting up as the master server.
2005-02-18 23:58:00.063 SIParser: About to do a reset
2005-02-18 23:58:00.064 SIParser: Closing all PIDs
2005-02-18 23:58:00.064 SIParser: Using DelAllPids from SIParser which does nothing
2005-02-18 23:58:00.065 SIParser: Resetting all Table Handlers
2005-02-18 23:58:00.065 SIParser: SIParser Reset due to channel change
2005-02-18 23:58:00.066 DVB#0 DVB SI Table Parser Started
2005-02-18 23:58:00.067 SIParser: Starting DVB Section Reader thread
2005-02-18 23:58:00.067 DVB#0 Using DVB card 0, with frontend pcHDTV HD3000 HDTV.
2005-02-18 23:58:00.069 DVB#0 Trying to tune to channel 12.1.
2005-02-18 23:58:00.069 DVB#0 DVB Signal Monitor Starting
2005-02-18 23:58:00.0692005-02-18 23:58:00.070 DVB#0 Mod:QPSK
SIParser: Starting SIScanner
2005-02-18 23:58:00.135 SIParser: About to do a reset
2005-02-18 23:58:00.136 SIParser: Closing all PIDs
2005-02-18 23:58:00.327 DVB#0 Signal Locked
2005-02-18 23:58:01.069 SIParser: Resetting all Table Handlers
2005-02-18 23:58:01.071 SIParser: SIParser Reset due to channel change
2005-02-18 23:58:01.071 DVB#0 Waiting for frontend event after tune.
2005-02-18 23:58:01.520 DVB#0 DVB signal d5c1 | snr f9a5 | ber 0 | unc 0
2005-02-18 23:58:01.521 DVB#0 Status: LOCK.
2005-02-18 23:58:01.521 SIParser: Requesting PAT
2005-02-18 23:58:01.522 DVB#0 Multiplex Locked
2005-02-18 23:58:01.522 SIParser: Adding a PMT (3) to the request list
2005-02-18 23:58:02.072 SIParser: Table[0]->RequirePIDs() == true
2005-02-18 23:58:02.074 SIParser: Adding PID 0 Filter 0 Mask ff Buffer 40960
2005-02-18 23:58:02.075 SIParser: Table[2]->RequirePIDs() == true
2005-02-18 23:58:02.076 SIParser: Adding PID 1ffb Filter ff Mask 0 Buffer 40960
2005-02-18 23:58:02.076 SIParser: Table[3]->RequirePIDs() == true
2005-02-18 23:58:02.077 SIParser: Adding PID 1ffb Filter ff Mask 0 Buffer 40960
2005-02-18 23:58:02.145 SIParser: No Private Types defined for NetworkID 975
2005-02-18 23:58:02.146 SIParser: Found Channel 12-1 - KTRV-DT CAStatus=0
2005-02-18 23:58:02.147 SIParser: Unknown Descriptor: a0 25 1 65 6e 67 1 0 0 1d 4b 54 52 56 20 46 4f 58 31 32 20 44 69 67 69 74 61 6c 20 54 65 6c 65 76 69 73 69
2005-02-18 23:58:02.147 SIParser: Unknown Descriptor: a1 15 e0 31 3 2 e0 31 0 0 0 81 e0 34 65 6e 67 81 e0 35 65
2005-02-18 23:58:02.149 SIScan#0: Updating Services
2005-02-18 23:58:02.150 SIScan#0: Service table up to date for this network.
2005-02-18 23:58:02.150 SIScan#0: Service Update Complete
2005-02-18 23:58:02.194 SIParser: PAT Version = 1
2005-02-18 23:58:02.195 SIParser: Tuned to TransportID: 975
2005-02-18 23:58:02.196 SIParser: Services on this Transport: 3
2005-02-18 23:58:02.196 SIParser: Table[0]->Complete() == true
2005-02-18 23:58:02.197 SIParser: Table[1]->RequirePIDs() == true
2005-02-18 23:58:02.197 SIParser: Adding PID 30 Filter 2 Mask ff Buffer 40960
2005-02-18 23:58:02.254 SIParser: TVCT Present on this Transport
2005-02-18 23:58:02.255 SIParser: Channel ETT Present on PID 11ff (51)
2005-02-18 23:58:02.255 SIParser: EIT-0 Present on PID 1053
2005-02-18 23:58:02.257 SIParser: EIT-1 Present on PID 1054
--------- lots and lots of these I cut out -----------
2005-02-18 23:58:02.332 SIParser: ETT-54 Present on PID 1219
2005-02-18 23:58:02.332 SIParser: ETT-55 Present on PID 121a
2005-02-18 23:58:02.332 SIParser: Unknown Table 301 in MGT on PID 1ffb
2005-02-18 23:58:02.333 SIParser: Table[2]->Complete() == true
2005-02-18 23:58:02.333 SIParser: Table[5]->RequirePIDs() == true
2005-02-18 23:58:02.334 SIParser: Adding PID 1ffb Filter ff Mask 0 Buffer 40960
2005-02-18 23:58:02.334 SIParser: GPS Time Offset = 13 Seconds
2005-02-18 23:58:02.335 SIParser: Table[3]->Complete() == true
2005-02-18 23:58:02.335 SIParser: Table[4]->RequirePIDs() == true
2005-02-18 23:58:02.336 SIParser: Adding PID 1053 Filter cb Mask ff Buffer 40960
2005-02-18 23:58:02.336 SIParser: Adding PID 1054 Filter cb Mask ff Buffer 40960
--------- lots and lots of these I cut out too -----------
2005-02-18 23:58:02.499 SIParser: Adding PID 1219 Filter cc Mask ff Buffer 40960
2005-02-18 23:58:02.500 SIParser: Adding PID 121a Filter cc Mask ff Buffer 40960
2005-02-18 23:58:02.611 SIParser: PMT ServiceID: 3 Version = 0
2005-02-18 23:58:02.613 SIParser: Unknown descriptor, tag = 5
2005-02-18 23:58:02.613 SIParser: Unknown descriptor, tag = 16
2005-02-18 23:58:02.614 SIParser: PID: 49
2005-02-18 23:58:02.614 SIParser: Unknown descriptor, tag = 134
2005-02-18 23:58:02.615 SIParser: Unknown descriptor, tag = 6
2005-02-18 23:58:02.615 SIParser: PID: 52
2005-02-18 23:58:02.615 SIParser: Unknown descriptor, tag = 5
2005-02-18 23:58:02.616 SIParser: Unknown descriptor, tag = 129
2005-02-18 23:58:02.616 SIParser: PID: 53
2005-02-18 23:58:02.617 SIParser: Unknown descriptor, tag = 5
2005-02-18 23:58:02.618 SIParser: Unknown descriptor, tag = 129
2005-02-18 23:58:02.618 SIParser: Table[1]->EmitRequired() == true
2005-02-18 23:58:02.620 DVB#0 Successfully tuned to channel 12.1.
2005-02-18 23:58:02.624 JobQueue::RecoverQueue: Checking for unfinished jobs to recover.
2005-02-18 23:58:02.625 mythbackend version: 0.17.20050130-1 www.mythtv.org
2005-02-18 23:58:02.626 Enabled verbose msgs :all
2005-02-18 23:58:02.628 JobQueue::GetJobsInQueue: findJobs search bitmask 4, found 0 total jobs
2005-02-18 23:58:04.626 Reschedule requested for id -1.
Query 0: /record.search = 0 AND (record.recordid = -1 OR -1 = -1) AND program.title = record.title
2005-02-18 23:58:04.630 |-- Start DB Query 0...
2005-02-18 23:58:04.632 |-- -1 results in 0.001359 sec.
2005-02-18 23:58:04.633 +-- Done.
2005-02-18 23:58:04.635 PruneOldRecords...
2005-02-18 23:58:04.637 AddNewRecords...
2005-02-18 23:58:04.638 |-- Start DB Query...
2005-02-18 23:58:04.642 |-- 2 results in 0.002955 sec. Processing...
2005-02-18 23:58:04.643 +-- Cleanup...
2005-02-18 23:58:04.644 Sort by time...
2005-02-18 23:58:04.645 PruneOverlaps...
2005-02-18 23:58:04.645 Sort by priority...
2005-02-18 23:58:04.646 BuildListMaps...
2005-02-18 23:58:04.647 SchedNewRecords...
2005-02-18 23:58:04.648 Scheduling:
2005-02-18 23:58:04.648 Sort retrylist...
2005-02-18 23:58:04.649 MoveHigherRecords...
2005-02-18 23:58:04.650 Retrying:
2005-02-18 23:58:04.650 ClearListMaps...
2005-02-18 23:58:04.651 Sort by time...
2005-02-18 23:58:04.652 PruneRedundants...
--- print list start ---
Title - Subtitle Chan ChID Day Start End S C I T N Pri
--- print list end ---
2005-02-18 23:58:04.654 Scheduled 0 items in 0.0 = 0.01 match + 0.02 place
2005-02-18 23:58:04.656 Seem to be woken up by USER
2005-02-18 23:58:12.627 JobQueue::RecoverQueue: Checking for unfinished jobs to recover.
2005-02-18 23:58:12.629 JobQueue::GetJobsInQueue: findJobs search bitmask 4, found 0 total jobs
2005-02-18 23:58:12.632 JobQueue::ProcessQueue() started
2005-02-18 23:58:12.633 JobQueue currently set to run maximum of 1 job(s)
2005-02-18 23:58:12.634 JobQueue::GetJobsInQueue: findJobs search bitmask 4, found 0 total jobs
2005-02-18 23:58:13.379 13 ACCEPT[]:[]14
2005-02-18 23:58:13.386 MainServer::HandleAnnounce Playback
2005-02-18 23:58:13.387 adding: debian64 as a client (events: 0)
2005-02-18 23:58:13.389 2 OK
2005-02-18 23:58:13.397 Getting next free recorder after : -1
2005-02-18 23:58:13.398 Card 1 is local.
2005-02-18 23:58:13.399 24 1[]:[]127.0.0.1[]:[]6543
2005-02-18 23:58:13.406 MainServer::HandleAnnounce Playback
2005-02-18 23:58:13.406 adding: debian64 as a client (events: 1)
2005-02-18 23:58:13.407 2 OK
2005-02-18 23:58:13.411 13 ACCEPT[]:[]14
2005-02-18 23:58:13.417 MainServer::HandleAnnounce Playback
2005-02-18 23:58:13.418 adding: debian64 as a client (events: 0)
2005-02-18 23:58:13.419 2 OK
2005-02-18 23:58:13.426 85 rbuf://127.0.0.1:6543/stuff/live/ringbuf1.nuv[]...
2005-02-18 23:58:13.432 MainServer::HandleAnnounce Playback
2005-02-18 23:58:13.432 adding: debian64 as a client (events: 0)
2005-02-18 23:58:13.433 2 OK
2005-02-18 23:58:13.440 adding: debian64 as a remote ringbuffer
2005-02-18 23:58:13.442 2 OK
2005-02-18 23:58:13.452 Changing from None to WatchingLiveTV
2005-02-18 23:58:13.461 Using profile 'Live TV' to record
2005-02-18 23:58:13.463 DVB#0 Recorder: Card opened successfully (using PS mode).
2005-02-18 23:58:13.464 DVB#0 AutoPID selecting PID 49, MPEG-2 Video
2005-02-18 23:58:13.466 DVB#0 AutoPID selecting PID 52, AC3 Audio
2005-02-18 23:58:13.467 DVB#0 AutoPID skipping PID 53, AC3 Audio
2005-02-18 23:58:13.467 DVB#0 AutoPID Complete - PAT/PMT Loaded for service
2005-02-18 23:58:13.468 DVB#0 Service is FTA
2005-02-18 23:58:13.469 DVB#0 Adding pid 49
2005-02-18 23:58:13.471 2 ok
2005-02-18 23:58:13.480 DVB#0 Adding pid 52
2005-02-18 23:58:13.481 DVB#0 Data read from DMX - This is for debugging with transform.c
2005-02-18 23:58:13.485 294 Fear Factor[]:[] []:[]Contestants climb down th...
2005-02-18 23:58:13.713 6 128000
2005-02-18 23:59:12.636 JobQueue currently set to run maximum of 1 job(s)
2005-02-18 23:59:12.638 JobQueue::GetJobsInQueue: findJobs search bitmask 4, found 0 total jobs
2005-02-19 00:00:12.641 JobQueue currently set to run maximum of 1 job(s)
2005-02-19 00:00:12.643 JobQueue::GetJobsInQueue: findJobs search bitmask 4, found 0 total jobs
2005-02-19 00:00:25.456 Changing from WatchingLiveTV to None
2005-02-19 00:00:25.462 Closing DVB recorder
---- end of backend log, I CTRL-C'd the program after I CTRL-C'd the frontend ----
$ cat myth_frontend.log
2005-02-18 23:58:10.357 mythfrontend version: 0.17.20050130-1 www.mythtv.org
2005-02-18 23:58:10.357 Enabled verbose msgs :all
2005-02-18 23:58:10.458 Switching to square mode (blue)
2005-02-18 23:58:10.757 Registering Internal as a media playback plugin.
mythtv: could not connect to socket
mythtv: Connection refused
lirc_init failed for mythtv, see preceding messages
2005-02-18 23:58:13.373 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5)
2005-02-18 23:58:13.374 write->11 21 MYTH_PROTO_VERSION 14:
2005-02-18 23:58:13.381 Using protocol version 14
2005-02-18 23:58:13.381 write->11 23 ANN Playback debian64 0:
2005-02-18 23:58:13.391 write->11 29 GET_NEXT_FREE_RECORDER[]:[]-1:
2005-02-18 23:58:13.400 23 ANN Playback debian64 1
2005-02-18 23:58:13.401 write->14 21 MYTH_PROTO_VERSION 14:
2005-02-18 23:58:13.411 Using protocol version 14
2005-02-18 23:58:13.412 write->14 23 ANN Playback debian64 0:
2005-02-18 23:58:13.420 write->14 44 QUERY_RECORDER 1[]:[]SETUP_RING_BUFFE...
2005-02-18 23:58:13.427 write->15 23 ANN Playback debian64 0:
2005-02-18 23:58:13.435 write->16 25 ANN RingBuffer debian64 1:
2005-02-18 23:58:13.443 write->14 33 QUERY_RECORDER 1[]:[]SPAWN_LIVETV:
2005-02-18 23:58:13.478 write->14 37 QUERY_RECORDER 1[]:[]GET_PROGRAM_INFO:
2005-02-18 23:58:13.486 Output filters for this channel are: ''
2005-02-18 23:58:13.487 write->15 53 QUERY_RECORDER 1[]:[]REQUEST_BLOCK_RI...
2005-02-18 23:58:13.715 Read(): reqd=128000, rcvd=128000, rept=128000, error=0
2005-02-18 23:58:13.715 write->15 66 QUERY_RECORDER 1[]:[]SEEK_RINGBUF[]:[...
---- end of frontend log, I can wait here for a long time and it'll never print more ----
From what I can tell these logs don't have errors in them? When I startedhaving this problem, I was quick to blame the driver or the video capture card but now I'm starting to think that it's the video deocder/encoder part of Myth because when I go to the recorded videos section and try to play a recorded video it's as hit and miss as the live tv.
Sometimes Myth Frontend freezes just going into the "Recorded Videos" menu. I think it does this because it tries to play the video in the lower right hand corner. Plus, I am using a pcHDTV card, two weeks ago I was using the pcHDTV drivers (not the new DVB ones) with 0.16 of myth and I still had the problem).
When the frontend freezes after selecting LiveTV, I can ALT-TAB back to my console and go watch the ringbuffer file grow so I'm pretty sure that the backend thinks the frontend is working.
Also, I don't know if it's that big of a deal but I'm running on an AMD64 port of Debian with a 2.6.10 kernel. I'm using cvs versions of mythtv, dvb-kernel and video4linux, which are all up to date right now.
Does anyone have a suggestion for what could be causing this? Or possibly a way to get more debugging info on the issue?
Thanks,
-Greg
_______________________________________________ mythtv-users mailing list [email protected] http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users
