[mythtv-users] mythbackend stops recording after first completed recording

Jim O'Neill oneillji at gmail.com
Sun Mar 5 02:54:31 UTC 2006


I would appreciate some guidance trouble-shooting a problem i'm having with
recording within MythTV.

Consistently after mythbackend is started it will record the next scheduled
recording as it should but any scheduled recordings after the first are not
recorded.  There are no obvious (to me) symptoms.  If i restart mythbackend,
it will the begin to record the program missed either automatically or after
it is reactivated.   According to the date/time stamp of the 'Housekeeping'
messages, mythbackend is not hung but if mythfrontend is running it will be
unable to connect to mythbackend.

I believe this problem may be unique to my installation as i haven't been
able to find a similiar problem although i've searched extensively.  The
problem has persisted from my first installation of mythtv .18.1 as well as
.19, and the svn branch installed yesterday.  I am having some other
problems but i think that they are unrelated.  These are intermitted
playback problems which appear to be a problem with some AMD64 installations
and a problem with transcoding.  These are of secondary concern, i mention
them only on the off chance there is a relationship.


Thanks
Jim


uname -r
--------------------------------------------------------
2.6.8-11-amd64-k8

mythfrontend --version
--------------------------------------------------------
Library API version: 0.19.20060226-1
Source code version: 9261
Options compiled in:
 linux release using_v4l using_oss using_alsa using_arts using_jack
using_ivtv using_dbox2 using_lirc using_joystick_menu using_x11 using_xv
using_xrandr using_frontend using_backend

mythbackend --version
--------------------------------------------------------
Library API version: 0.19.20060226-1
Source code version: 9261
Options compiled in:
 linux release using_v4l using_oss using_alsa using_arts using_jack
using_ivtv using_dbox2 using_lirc using_joystick_menu using_x11 using_xv
using_xrandr using_frontend using_backend

mysqlcheck mythconverg -p
--------------------------------------------------------
no errors

mythbackend -v all,nodatabase (Log represents a properly recorded program
that ends at 19:00 with another program scheduled for recording at 19:00.
Used '...' to represent blocks of similiar information parse out.
mythfrontend was running for this example.)
--------------------------------------------------------
2006-03-04 18:59:30.028 TVRec(1): ClearFlags(AskAllowRecording,) ->
FrontendReady,RunMainLoop,RecorderRunning,RingBufferReady,
2006-03-04 19:00:00.084 TVRec(1): ClearFlags(FinishRecording,) ->
FrontendReady,RunMainLoop,RecorderRunning,RingBufferReady,
2006-03-04 19:00:00.084 TVRec(1): Changing from RecordingOnly to None
2006-03-04 19:00:00.084 TVRec(1):
ClearFlags(FrontendReady,CancelNextRecording,) ->
RunMainLoop,RecorderRunning,RingBufferReady,
2006-03-04 19:00:00.084 TVRec(1): SetFlags(AskAllowRecording,) ->
RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReady,
2006-03-04 19:00:00.085 TVRec(1): Request: Program(no) channel() input()
flags(CloseRec,KillRingBuffer,)
2006-03-04 19:00:00.086 TVRec(1): FinishedRecording(Sea Hunters) in
recgroup: Default
2006-03-04 19:00:00.125 Finished recording Sea Hunters "The Search for the
Andrea Gail": channel 1278
2006-03-04 19:00:00.181 New DB connection, total: 4
2006-03-04 19:00:00.197 Connected to database 'mythconverg' at host:
localhost
2006-03-04 19:00:00.422 scheduler: Finished recording
2006-03-04 19:00:00.438 write -> 14 51
BACKEND_MESSAGE[]:[]DONE_RECORDING 1 2864[]:[]empty
2006-03-04 19:00:00.568 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-03-04 19:00:00.665RingBuf(/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg):
OpenFile(/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg, 0)
2006-03-04 19:00:00.767 TVRec(1): FinishedRecording(Sea Hunters) in
recgroup: Default
2006-03-04 19:00:00.768 Finished recording Sea Hunters "The Search for the
Andrea Gail": channel 1278
2006-03-04 19:00:00.804 TVRec(1): ClearFlags(RecorderRunning,) ->
RunMainLoop,AskAllowRecording,RingBufferReady,
2006-03-04 19:00:00.805 TVRec(1): Tearing down RingBuffer
2006-03-04 19:00:00.805 write -> 14 51
BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
0: start_time: 0.036 duration: 257.820
1: start_time: 0.026 duration: 257.790
stream: start_time: 0.289 duration: 2864.773 bitrate=5189 kb/s
2006-03-04 19:00:01.071 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO,
type Video at 0x0x6ccb60
2006-03-04 19:00:01.071 detectInterlace(Detect Scan, Detect Scan, 29.97,
480) ->Interlaced Scan
2006-03-04 19:00:01.071 Interlaced: Interlaced Scan  video_height: 480  fps:
29.97
2006-03-04 19:00:01.071 AFD: Looking for decoder for MPEG2VIDEO
2006-03-04 19:00:01.072 AFD: Opened codec 0x7868d0, id(MPEG2VIDEO)
type(Video)
2006-03-04 19:00:01.072 AFD: Stream #1, has id 0x448 codec id MP2, type
Audio at 0x0x75a330
2006-03-04 19:00:01.072 AFD: Looking for decoder for MP2
2006-03-04 19:00:01.083 AFD: Opened codec 0x647f00, id(MP2) type(Audio)
2006-03-04 19:00:01.083 AFD: Audio Track #1 is A/V stream #1 and has 2
channels in the Unknown language(0).
2006-03-04 19:00:01.083RingBuf(/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg):
CalcReadAheadThresh(6384 KB)
                         -> threshhold(233 KB) min read(32 KB) blk size(128
KB)
2006-03-04 19:00:01.083 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream
#1)
2006-03-04 19:00:01.083 AFD: Initializing audio parms from audio track #1
2006-03-04 19:00:01.083 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-03-04 19:00:01.083 Resyncing position map. posmapStarted = 0 livetv(0)
watchingRec(0)
2006-03-04 19:00:01.235 Position map filled from DB to: 5725
2006-03-04 19:00:01.235 SyncPositionMap prerecorded, from DB: 5725 entries
2006-03-04 19:00:01.235 SyncPositionMap, new totframes: 85875, new length:
2865, posMap size: 5725
Input #0, mpeg, from
'/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg':
  Duration: 00:47:44.7, start: 0.289378, bitrate: 5189 kb/s
  Stream #0.0[0x1e0], 1/90000, 29.97 fps(r): Video: mpeg2video, yuv420p,
640x480, 1001/30000, 6000 kb/s
  Stream #0.1[0x1c0], 1/90000: Audio: mp2, 48000 Hz, stereo, 384 kb/s
2006-03-04 19:00:01.236 AFD: Position map found
2006-03-04 19:00:01.236 AFD: Successfully opened decoder for file:
"/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg". novideo(0)
2006-03-04 19:00:01.238 VideoOutputNull()
2006-03-04 19:00:01.242 Image size. dispxoff 0, dispyoff: 0, dispwoff: 0,
disphoff: 0, dispaspect: 1.33333
2006-03-04 19:00:01.242 Image size. imgx 0, imgy: 0, imgw: 640, imgh: 480,
aspect: 1.33333
2006-03-04 19:00:01.243 Created data @0x912200->0x982a02
...
2006-03-04 19:00:01.275 Created data @0x2a9d3e2010->0x2a9d452812
2006-03-04 19:00:01.275 Image size. dispxoff 0, dispyoff: 0, dispwoff: 0,
disphoff: 0, dispaspect: 1.33333
2006-03-04 19:00:01.275 Image size. imgx 0, imgy: 0, imgw: 640, imgh: 480,
aspect: 1.33333
2006-03-04 19:00:01.275 NVP: ClearAfterSeek()
2006-03-04 19:00:01.363 AFD: DoFastForward(1917 (1), do discard frames)
2006-03-04 19:00:01.364 Dec: DoFastForward(1917 (1), do discard frames)
2006-03-04 19:00:01.364 AFD: SeekReset(1905, 0, do flush, do discard)
2006-03-04 19:00:01.364 AFD: SeekReset() flushing
2006-03-04 19:00:01.364 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-03-04 19:00:01.364 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-03-04 19:00:01.364 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-03-04 19:00:01.364 NVP: ClearAfterSeek()
2006-03-04 19:00:01.435 ~VideoOutputNull()
2006-03-04 19:00:01.739 Preview: previewThreadDone took 6ms
2006-03-04 19:00:01.746 Preview: previewThreadDone took 7ms
2006-03-04 19:00:01.815 read  <- 13 21      QUERY_RECORDINGS Play
2006-03-04 19:00:01.831 write -> 13 3517    6[]:[]Dial M for
Murder[]:[][]:[]A husband's plot to murder his u...
2006-03-04 19:00:02.373 TVRec(1): StartRecording(NHL Hockey)
2006-03-04 19:00:09.939 AutoExpire: Adding Programs to 'Do Not Expire' List
2006-03-04 19:00:09.939 AutoExpire: ExpireLiveTV(10000)
2006-03-04 19:00:09.940 AutoExpire: SendDeleteMessages. Nothing to expire.
2006-03-04 19:00:09.941 AutoExpire: ExpireLiveTV(10001)
2006-03-04 19:00:09.942 AutoExpire: SendDeleteMessages. Nothing to expire.
2006-03-04 19:00:09.942 AutoExpire: Found 41 record profiles using max
episode expiration
2006-03-04 19:00:09.942     American Chopper (2 for rec id 2)
...
2006-03-04 19:00:09.945 AutoExpire: Checking episode count for each
recording profile using max episodes
...
2006-03-04 19:00:09.968     Recordid 78 has 0 recordings.
2006-03-04 19:00:14.101 read  <- 13 651     DELETE_RECORDING[]:[]Sea
Hunters[]:[]The Search for the Andrea Ga...
2006-03-04 19:00:14.110 write -> 13 2       -1
2006-03-04 19:00:14.120 write -> 14 51
BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2006-03-04 19:00:15.229 read  <- 13 21      QUERY_RECORDINGS Play
2006-03-04 19:00:17.145 About to unlink/delete file:
/home/oneillji/.mythtv/recordings/1278_20060304181200.mpg
2006-03-04 19:01:09.982 AutoExpire: Adding Programs to 'Do Not Expire' List
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mythtv.org/pipermail/mythtv-users/attachments/20060304/0685e533/attachment-0001.htm 


More information about the mythtv-users mailing list