[mythtv-commits] Ticket #12100: Ceton Tuner recording problems

MythTV noreply at mythtv.org
Wed Jul 2 00:49:48 UTC 2014


#12100: Ceton Tuner recording problems
----------------------------------+--------------------------------
 Reporter:  Matt <matt@…>         |          Owner:  jyavenard
     Type:  Bug Report - General  |         Status:  infoneeded_new
 Priority:  minor                 |      Milestone:  unknown
Component:  MythTV - Recording    |        Version:  0.27-fixes
 Severity:  medium                |     Resolution:
 Keywords:                        |  Ticket locked:  0
----------------------------------+--------------------------------

Comment (by Matt <matt@…>):

 I did have a problem today.  A recording should have been 33 minutes and
 it was 29 minutes and 30 seconds.

 mythbackend.log
 Jul  1 15:58:00 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2139 (HandleReschedule) Reschedule requested for PLACE
 PrepareToRecord
 Jul  1 15:58:00 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2252 (HandleReschedule) Scheduled 306 items in 0.7 = 0.00
 match + 0.00 check + 0.75 place
 Jul  1 15:58:30 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 13
 Jul  1 15:58:30 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:1567 (HandlePendingRecordings) TVRec[1]: ASK_RECORDING 1 29 0 0
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I CoreContext
 mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 13
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:1050 (HandleStateChange) TVRec[1]: Changing from None to
 RecordingOnly
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 13
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:3602 (TuningCheckForHWChange) TVRec[1]: HW Tuner: 1->1
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/iptvstreamhandler.cpp:127 (run) IPTVSH(::-1):
 run()
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: N Scheduler
 autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required
 Free Space: 3.0 GB w/freq: 14 min
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2689 (HandleRecordingStatusChange) Tuning recording: "Boy
 Meets World":"Uncle Daddy": channel 1254 on cardid 1, sourceid 1
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/cetonrtsp.cpp:413 (Setup) CetonRTSP:
 Transport: RTP/AVP;unicast;client_port=42640-42641
 Jul  1 16:02:28 prdmt01 mythbackend: mythbackend[4574]: E ProcessRequest
 programinfo.cpp:2358 (GetPlaybackURL)
 ProgramInfo(1254_20140701195900.mpg): GetPlaybackURL:
 '1254_20140701195900.mpg' should be local, but it can not be found.
 Jul  1 16:02:29  mythbackend: last message repeated 2 times
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: N
 CetonStreamHandler recorders/dtvsignalmonitor.cpp:367 (HandlePMT)
 DTVSigMon[1](10.1.9.12-RTP.0): PMT says program 254 is encrypted
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: I CoreContext
 scheduler.cpp:704 (UpdateRecStatus) Updating status for "Boy Meets
 World":"Uncle Daddy" on cardid 1 (Tuning => Recording)
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:4129 (TuningNewRecorder) TVRec[1]: rec->GetPathname():
 '/mythtv/livetv/1254_20140701195900.mpg'
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: E TVRecEvent
 recorders/recorderbase.cpp:206 (SetStrOption) RecBase[1](10.1.9.12-RTP.0):
 SetStrOption(...recordingtype): Option not in profile.
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/iptvstreamhandler.cpp:127 (run) IPTVSH(::-1):
 run()
 Jul  1 16:02:29 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/cetonrtsp.cpp:413 (Setup) CetonRTSP:
 Transport: RTP/AVP;unicast;client_port=45698-45699
 Jul  1 16:02:30 prdmt01 mythbackend: mythbackend[4574]: I
 PreviewGeneratorQueue mythdbcon.cpp:409 (PurgeIdleConnections) New DB
 connection, total: 16
 Jul  1 16:02:31 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:02:31 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 0)
 Jul  1 16:02:31 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:02:31 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 1)
 Jul  1 16:05:27 prdmt01 mythbackend: mythbackend[4574]: N Expire
 autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required
 Free Space: 3.0 GB w/freq: 14 min
 Jul  1 16:19:27 prdmt01 mythbackend: mythbackend[4574]: N Expire
 autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required
 Free Space: 3.0 GB w/freq: 14 min
 Jul  1 16:28:00 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2139 (HandleReschedule) Reschedule requested for PLACE
 PrepareToRecord
 Jul  1 16:28:00 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2252 (HandleReschedule) Scheduled 306 items in 0.7 = 0.00
 match + 0.00 check + 0.73 place
 Jul  1 16:28:30 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:1567 (HandlePendingRecordings) TVRec[2]: ASK_RECORDING 2 29 0 0
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:1050 (HandleStateChange) TVRec[2]: Changing from None to
 RecordingOnly
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 mythdbcon.cpp:409 (PurgeIdleConnections) New DB connection, total: 16
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:3602 (TuningCheckForHWChange) TVRec[2]: HW Tuner: 2->2
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: E ProcessRequest
 programinfo.cpp:2358 (GetPlaybackURL)
 ProgramInfo(1254_20140701202900.mpg): GetPlaybackURL:
 '1254_20140701202900.mpg' should be local, but it can not be found.
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: E ProcessRequest
 programinfo.cpp:2358 (GetPlaybackURL)
 ProgramInfo(1254_20140701202900.mpg): GetPlaybackURL:
 '1254_20140701202900.mpg' should be local, but it can not be found.
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/iptvstreamhandler.cpp:127 (run) IPTVSH(::-1):
 run()
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: N Scheduler
 autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required
 Free Space: 3.0 GB w/freq: 7 min
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2689 (HandleRecordingStatusChange) Tuning recording: "Boy
 Meets World":"Quiz Show": channel 1254 on cardid 2, sourceid 1
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: E ProcessRequest
 programinfo.cpp:2358 (GetPlaybackURL)
 ProgramInfo(1254_20140701202900.mpg): GetPlaybackURL:
 '1254_20140701202900.mpg' should be local, but it can not be found.
 Jul  1 16:29:00 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/cetonrtsp.cpp:413 (Setup) CetonRTSP:
 Transport: RTP/AVP;unicast;client_port=34757-34758
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: N
 CetonStreamHandler recorders/dtvsignalmonitor.cpp:367 (HandlePMT)
 DTVSigMon[2](10.1.9.12-RTP.1): PMT says program 254 is encrypted
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: I CoreContext
 scheduler.cpp:704 (UpdateRecStatus) Updating status for "Boy Meets
 World":"Quiz Show" on cardid 2 (Tuning => Recording)
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:4129 (TuningNewRecorder) TVRec[2]: rec->GetPathname():
 '/mythtv/livetv/1254_20140701202900.mpg'
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: E TVRecEvent
 recorders/recorderbase.cpp:206 (SetStrOption) RecBase[2](10.1.9.12-RTP.1):
 SetStrOption(...recordingtype): Option not in profile.
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/iptvstreamhandler.cpp:127 (run) IPTVSH(::-1):
 run()
 Jul  1 16:29:02 prdmt01 mythbackend: mythbackend[4574]: I
 CetonStreamHandler recorders/cetonrtsp.cpp:413 (Setup) CetonRTSP:
 Transport: RTP/AVP;unicast;client_port=50446-50447
 Jul  1 16:29:04 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:29:04 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 0)
 Jul  1 16:29:04 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:29:04 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 1)
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:1050 (HandleStateChange) TVRec[1]: Changing from RecordingOnly
 to None
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I TVRecEvent
 tv_rec.cpp:834 (FinishedRecording) TVRec[1]:
 FinishedRecording(1254_2014-07-01T19:59:00Z) damaged
 recq:<RecordingQuality overall_score="0" key="1254_2014-07-01T19:59:00Z"
 countinuity_error_count="0" packet_count="16087395">#012    <Gap
 start="2014-07-01T20:00:00Z" end="2014-07-01T20:02:30Z" duration="150"
 />#012</RecordingQuality>
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: E CoreContext
 mainserver.cpp:963 (customEvent) MainServer: PREVIEW_SUCCESS but no
 receivers.
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I CoreContext
 scheduler.cpp:704 (UpdateRecStatus) Updating status for "Boy Meets
 World":"Uncle Daddy" on cardid 1 (Recording => Recorded)
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2139 (HandleReschedule) Reschedule requested for CHECK -3
 226 0 UpdateRecStatus2 | Boy Meets World | Uncle Daddy | Eric begins
 dating a woman with a young child, prompting his friends and family to
 warn him about responsibility. | EP000838770086
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 0)
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:01 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 1)
 Jul  1 16:32:02 prdmt01 mythbackend: mythbackend[4574]: I Scheduler
 scheduler.cpp:2252 (HandleReschedule) Scheduled 305 items in 1.2 = 0.01
 match + 0.00 check + 1.22 place
 Jul  1 16:32:29 prdmt01 mythbackend: mythbackend[4574]: I Metadata_6462
 jobqueue.cpp:2156 (DoMetadataLookupThread) JobQueue: Metadata Lookup
 Starting for "Boy Meets World":"Uncle Daddy" recorded from channel 1254 at
 2014-07-01T19:59:00Z
 Jul  1 16:32:29 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:29 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 0)
 Jul  1 16:32:29 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:29 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 1)
 Jul  1 16:32:30 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:30 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 0)
 Jul  1 16:32:30 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1436 (HandleAnnounce) MainServer::ANN Monitor
 Jul  1 16:32:30 prdmt01 mythbackend: mythbackend[4574]: I ProcessRequest
 mainserver.cpp:1438 (HandleAnnounce) adding: prdmt01 as a client (events:
 1)

 there was another recording that started at 16:29 (16:30 show but starts 1
 minute early) on the same channel and it recorded without a problem.

--
Ticket URL: <https://code.mythtv.org/trac/ticket/12100#comment:27>
MythTV <http://www.mythtv.org>
MythTV Media Center


More information about the mythtv-commits mailing list