[mythtv-users] Unable to watch long recordings after they've begun

Ryan Steffes rbsteffes at gmail.com
Sun Dec 31 21:23:36 UTC 2006


I'm having a frustrating problem that seems to have come out of no where; I
record lots of basketball games, and if I begin watching them shortly after
they've begun I can watch them just fine, all the way through.  If I wait
until there's perhaps an hour or so of game recorded already, while it's
still recording, I can't begin watching it until the recording is finished.
I've just tried disabling commercial flagging during the recording to see if
that's somehow messing it up, but I don't have anymore games to record today
to see if that changed anything.  I can play the file directly, and the
preview of the file is showing from the selection screen, but if I play the
recording the screen goes to black for a while, then sometimes the front end
crashes and shows a segmentation fault, other times it just returns to the
selection screen.

I haven't built it with debug flags and tried to run it through the debugger
(I'm not honestly not entirely sure how) but I pulled last night's svn and
built it with no change.  It's being recorded on a PVR150, so no fancy HD
issues, and it's a combination frontend/backend with local file access.

The log entries I'm getting with '-v most' are as follows:


2006-12-31 16:19:34.139 TV: Attempting to change from None to
WatchingRecording
2006-12-31 16:19:34.139 RingBuf(/home/video/1099_20061231145800.mpg):
OpenFile(/home/video/1099_20061231145800.mpg, 12)
2006-12-31 16:19:34.139 RingBuf(/home/video/1099_20061231145800.mpg):
CalcReadAheadThresh(3066635316 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2006-12-31 16:19:34.139 write -> 10 513     GET_RECORDER_NUM[]:[]College
Basketball[]:[]Dayton at North Carol...
2006-12-31 16:19:34.140 read  <- 10 28      1[]:[]192.168.0.100[]:[]6543
2006-12-31 16:19:34.140 MythSocket(8354ab0:16): new socket
2006-12-31 16:19:34.140 MythSocket(8354ab0:16): attempting connect() to (
192.168.0.100:6543)
2006-12-31 16:19:34.140 MythSocket(8354ab0:16): state change Idle ->
Connected
2006-12-31 16:19:34.140 write -> 16 21      MYTH_PROTO_VERSION 32
2006-12-31 16:19:34.141 read  <- 16 13      ACCEPT[]:[]32
2006-12-31 16:19:34.141 Using protocol version 32
2006-12-31 16:19:34.141 write -> 16 21      ANN Playback mythtv 0
2006-12-31 16:19:34.143 read  <- 16 2       OK
2006-12-31 16:19:34.144 NVP: Exited decoder loop.
2006-12-31 16:19:34.147 write -> 10 33
MESSAGE[]:[]RECORDING_LIST_CHANGE
2006-12-31 16:19:34.148 read  <- 10 2       OK
2006-12-31 16:19:34.148 MythSocket(83605a8:8): socket is readable
2006-12-31 16:19:34.148 MythSocket(83605a8:8): cb->readyRead()
2006-12-31 16:19:34.148 read  <-  8 51
BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-12-31 16:19:34.148 MythEvent: RECORDING_LIST_CHANGE
2006-12-31 16:19:34.163 ~VideoOutputNull()
2006-12-31 16:19:34.179 TV: Output filters for this channel are: ''
2006-12-31 16:19:34.185 Avg read interval was 197 msec. 64K block size
2006-12-31 16:19:34.193 Avg read interval was 196 msec. 64K block size
0: start_time: 0.036 duration: 440.063
1: start_time: 0.026 duration: 440.046
stream: start_time: 0.289 duration: 4889.696 bitrate=4995 kb/s
2006-12-31 16:19:34.194 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO,
type Video, bitrate 5800000 at 0x0x8412ae0
2006-12-31 16:19:34.195 detectInterlace(Detect Scan, Interlaced Scan, 29.97,
480) ->Interlaced Scan
2006-12-31 16:19:34.195 AFD: Looking for decoder for MPEG2VIDEO
2006-12-31 16:19:34.195 AFD: Opened codec 0x8676c10, id(MPEG2VIDEO)
type(Video)
2006-12-31 16:19:34.196 AFD: Stream #1, has id 0x448 codec id MP2, type
Audio, bitrate 192000 at 0x0x8437960
2006-12-31 16:19:34.196 AFD: Looking for decoder for MP2
2006-12-31 16:19:34.196 AFD: Opened codec 0x83ed6c0, id(MP2) type(Audio)
2006-12-31 16:19:34.196 AFD: Audio Track #1 is A/V stream #1 and has 2
channels in the Unknown language(0).
2006-12-31 16:19:34.196 RingBuf(/home/video/1099_20061231145800.mpg):
CalcReadAheadThresh(3056879382 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2006-12-31 16:19:34.196 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream
#1)
2006-12-31 16:19:34.196 AFD: Initializing audio parms from audio track #1
2006-12-31 16:19:34.196 AFD: Audio format changed
                        from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)
-1Hz -1ch   0bps
                        to   id( MP2) 48000Hz  2ch  16bps    ; id( MP2)
48000Hz  2ch  16bps
2006-12-31 16:19:34.198 AO: Killing AudioOutputDSP
2006-12-31 16:19:34.199 Opening ALSA audio device 'mixed-digital'.
2006-12-31 16:19:34.200 Avg read interval was 197 msec. 64K block size
2006-12-31 16:19:34.222 in SetParameters(format=2, channels=2, rate=48000,
buffer_time=500000, period_time=125000)
2006-12-31 16:19:34.224 get_buffer_size returned 4096
2006-12-31 16:19:34.224 set_period_time_near returned 21333
2006-12-31 16:19:34.224 get_period_size returned 1024
2006-12-31 16:19:34.228 Opening mixer /dev/mixer
ALSA lib control.c:739:(snd_ctl_open_noupdate) Invalid CTL /dev/mixer
2006-12-31 16:19:34.228 AudioOutput Warning: Mixer attach error -2: No such
file or directory
                        Check Mixer Name in Setup: '/dev/mixer'
2006-12-31 16:19:34.229 AO: Audio fragment size: 6144
2006-12-31 16:19:34.230 AO: Audio Stretch Factor: 1
2006-12-31 16:19:34.230 AO: Ending reconfigure
2006-12-31 16:19:34.230 Dec: Trying to select track (w/lang)
2006-12-31 16:19:34.230 Dec: Selecting first track
2006-12-31 16:19:34.230 Dec: Selected track #1 in the Unknown language(0)
2006-12-31 16:19:34.230 Resyncing position map. posmapStarted = 0 livetv(0)
watchingRec(1)
2006-12-31 16:19:34.232 SyncPositionMap watchingrecording, from DB: 0
entries
2006-12-31 16:19:34.232 write -> 16 39      QUERY_RECORDER
1[]:[]GET_FRAMES_WRITTEN
2006-12-31 16:19:34.233 read  <- 16 12      0[]:[]146550
2006-12-31 16:19:34.233 Filling position map from 0 to 146550
2006-12-31 16:19:34.233 write -> 16 55      QUERY_RECORDER
1[]:[]FILL_POSITION_MAP[]:[]0[]:[]146550
2006-12-31 16:19:34.417 read  <- 16 349984
0[]:[]1[]:[]0[]:[]0[]:[]0[]:[]2[]:[]0[]:[]374784[]:[]0[]:[]3[]:[]...
2006-12-31 16:19:34.422 AO: kickoffOutputAudioLoop: pid = 30988
2006-12-31 16:19:34.422 AO: OutputAudioLoop: Play Event
2006-12-31 16:19:34.422 AO: Broadcasting free space avail
2006-12-31 16:19:34.423 Avg read interval was 195 msec. 96K block size
2006-12-31 16:19:34.433 Avg read interval was 197 msec. 128K block size

2006-12-31 16:19:34.433 Avg read interval was 197 msec. 128K block size
2006-12-31 16:19:34.443 Avg read interval was 197 msec. 160K block size
2006-12-31 16:19:34.457 Avg read interval was 196 msec. 192K block size
2006-12-31 16:19:34.471 Avg read interval was 197 msec. 224K block size
2006-12-31 16:19:34.527 AO: Broadcasting free space avail
2006-12-31 16:19:34.531 AO: Broadcasting free space avail
2006-12-31 16:19:34.535 AO: Broadcasting free space avail
2006-12-31 16:19:34.539 AO: Broadcasting free space avail
2006-12-31 16:19:34.543 AO: Broadcasting free space avail
2006-12-31 16:19:34.547 AO: Broadcasting free space avail
[Last line repeats hundreds of times]








Anyone seen anything like before?

Ryan Steffes
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mythtv.org/pipermail/mythtv-users/attachments/20061231/3b6ce6c8/attachment.htm 


More information about the mythtv-users mailing list