[mythtv-users] Ceton infinitv 6 Eth & mythtv?
Ronald Frazier
ron at ronfrazier.net
Mon Aug 19 00:04:01 UTC 2013
On Sun, Aug 18, 2013 at 4:15 PM, Joseph Bebel <joseph.bebel at gmail.com>wrote:
> The definite good news here is that it appears the problem is not due to a
>> difference in the devices behavior. Based on the info you've provided, It
>> appears (at least so far) to be compatible with the 4 tuner models
>> protocols.
>> Beyond that, I'm a little uncertain. The first thing I notice when looking
>> at your logs, is the following in the HTTP log:
>> POST /channel_request.cgi HTTP/1.1
>> content-type: application/x-www-form-urlencoded
>> content-length: 22
>> instance_id=0&channel=
>> Note that the channel parameter is blank in your last line. Then I look in
>> the backend logs and see:
>> CetonSH(192.168.1.8-RTP.0): PerformTuneVChannel()
>> I double checked the code, and PerformTuneVChannel should be getting
>> passed
>> a channel number as a parameter, and that channel number should have been
>> included in the log. So it would seem it's trying to tune a blank channel
>> number. Looking at the code, it's a little complex to trace down, but the
>> only way I can see that happening is if your channel configuration is a
>> bit
>> screwy. So that's my current theory. Where did your current channel
>> configuration come from? Did you create it from scratch, or did you reuse
>> one you already had setup for a different tuner? If the latter, what type
>> of tuner was it created for? Different tuners fill in different values in
>> the database fields, so channel configs for one tuner type is not always
>> compatible with another tuner type.
>> My suggestion is going to be to create a brand new channel lineup, just
>> for
>> your ceton device. So create a brand new video source linked to the same
>> same schedules direct lineup, assign it to your ceton tuner in the Input
>> Connections screen and then click "Fetch channels from listing source".
>> You
>> might need to then run mythfilldatabase again (not positive).
>>
>> --
>> Ron Frazier
>
>
> Thanks. I figured that I had messed the configuration up somehow while
> trying to get it to work (because I hadn't upgraded the firmware yet on the
> device, so it wouldn't work no matter how myth was configured) so I wiped
> the ~/.mythtv dir and started over. Now at least the log shows something
> different (below) but the frontend still shows nothing.
>
> (also, I am seeing some signal strength issues which seem to depend on
> which tuner is used. So now I use tuner 6 which seems to be reliable for
> the moment)
>
> Thanks a lot,
> Joe
>
> 2013-08-18 03:11:47.615453 N MythBackend: Starting up as the master
> server.
> 2013-08-18 03:11:47.616814 I TVRec(1): Start channel: 1002.
> 2013-08-18 03:11:47.616831 I CetonChan(192.168.1.8-RTP.5): Opening Ceton
> channel
> 2013-08-18 03:11:47.883167 I CetonSH(192.168.1.8-RTP.5): Ceton device
> 192.168.1.8 initialized. SN: *removed*, Firmware ver. 13.5.24.142, Hardware
> ver. 7359
> 2013-08-18 03:11:47.986696 I CetonSH(192.168.1.8-RTP.5): Cable card
> installed (Motorola) - Validated, validation message is received,
> authenticated, and the IDs match those in the current binding
> 2013-08-18 03:11:47.986735 I CetonSH: Creating new stream handler
> 192.168.1.8-RTP.5 for 192.168.1.8-RTP.5
> 2013-08-18 03:11:48.008492 I ChannelBase(1): Input #1: 'MPEG2TS'
> schan(1002) sourceid(1) ccid(1)
> 2013-08-18 03:11:48.008507 I ChannelBase(1): Current Input #1: 'MPEG2TS'
> 2013-08-18 03:11:48.008763 I DTVChan(192.168.1.8-RTP.5):
> SetChannelByString(1002):
> 2013-08-18 03:11:48.008922 I CetonSH(192.168.1.8-RTP.5):
> ClearProgramNumber()
> 2013-08-18 03:11:48.008924 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(0)
> 2013-08-18 03:11:48.152585 I CetonSH(192.168.1.8-RTP.5):
> TuneVChannel(1002)
> 2013-08-18 03:11:48.152590 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(1002)
> 2013-08-18 03:11:48.214280 I DTVChan(192.168.1.8-RTP.5):
> SetChannelByString(1002): success
> 2013-08-18 03:11:48.266362 I CetonSH(192.168.1.8-RTP.5):
> GetProgramNumber() got 0 on attempt 1
> 2013-08-18 03:11:48.433857 I CetonSH(192.168.1.8-RTP.5):
> GetProgramNumber() got 0 on attempt 2
> 2013-08-18 03:11:48.586089 I CetonSH(192.168.1.8-RTP.5):
> GetProgramNumber() got 0 on attempt 3
> 2013-08-18 03:11:48.753121 I CetonSH(192.168.1.8-RTP.5):
> GetProgramNumber() got 19567 on attempt 4
> 2013-08-18 03:11:48.753136 I CetonSH(192.168.1.8-RTP.5): TuneFrequency(0,
> qam_256)
> 2013-08-18 03:11:50.943357 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(0)
> 2013-08-18 03:11:50.943372 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(0)
> 2013-08-18 03:11:51.065845 I TVRec(1): SetFlags(RunMainLoop,) ->
> RunMainLoop,
> 2013-08-18 03:11:51.065872 I TVRec(1):
> ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,
> 2013-08-18 03:11:51.069571 I Found 1 distinct programid authorities
> 2013-08-18 03:11:51.069960 I New static DB connectionSchedCon
> 2013-08-18 03:11:51.076745 I Listening on TCP 127.0.0.1:6544
> 2013-08-18 03:11:51.076861 I Listening on TCP [::1]:6544
> 2013-08-18 03:11:51.079966 N MediaServer:: Loopback address specified -
> 127.0.0.1. Disabling UPnP
> 2013-08-18 03:11:51.079977 I Main::Registering HttpStatus Extension
> 2013-08-18 03:11:51.082334 I Listening on TCP 127.0.0.1:6543
> 2013-08-18 03:11:51.082439 I Listening on TCP [::1]:6543
> 2013-08-18 03:11:51.085064 N AutoExpire: CalcParams(): Max required Free
> Space: 1.0 GB w/freq: 15 min
> 2013-08-18 03:11:54.075366 I Reschedule requested for MATCH 0 0 0 -
> SchedulerInit
> 2013-08-18 03:11:54.112373 I Scheduled 0 items in 0.0 = 0.01 match + 0.00
> check + 0.02 place
> 2013-08-18 03:11:54.119185 I Scheduler: Seem to be woken up by USER
> 2013-08-18 03:12:01.071453 I Running housekeeping thread
> 2013-08-18 03:12:02.465810 I MainServer::ANN Monitor
> 2013-08-18 03:12:02.465827 I adding: localhost as a client (events: 0)
> 2013-08-18 03:12:02.466562 I MainServer::ANN Monitor
> 2013-08-18 03:12:02.466574 I adding: localhost as a client (events: 1)
> 2013-08-18 03:12:02.538960 I Getting next free recorder after : -1
> 2013-08-18 03:12:02.538965 I Checking card 1. Best card so far -1
> 2013-08-18 03:12:02.539311 I Best card is 1
> 2013-08-18 03:12:02.540407 I MainServer::ANN Playback
> 2013-08-18 03:12:02.540410 I adding: localhost as a client (events: 0)
> 2013-08-18 03:12:02.541292 I TVRec(1): Changing from None to
> WatchingLiveTV
> 2013-08-18 03:12:02.541301 I TVRec(1):
> ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
> 2013-08-18 03:12:02.541308 I TVRec(1): HandleTuning Request:
> Program(NULL) channel() input() flags(LiveTV,)
> 2013-08-18 03:12:02.541749 I TVRec(1): Start channel: 1002.
> 2013-08-18 03:12:02.542268 I TVRec(1): HW Tuner: 1->1
> 2013-08-18 03:12:02.542273 I TVRec(1): ClearFlags(PENDINGACTIONS,) ->
> RunMainLoop,
> 2013-08-18 03:12:02.542278 I TVRec(1): No recorder yet, calling
> TuningFrequency
> 2013-08-18 03:12:02.542374 I CetonChan(192.168.1.8-RTP.5): Opening Ceton
> channel
> 2013-08-18 03:12:02.542691 I DTVChan(192.168.1.8-RTP.5):
> SetChannelByString(1002):
> 2013-08-18 03:12:02.543040 I CetonSH(192.168.1.8-RTP.5):
> TuneVChannel(1002)
> 2013-08-18 03:12:02.543043 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(1002)
> 2013-08-18 03:12:02.589146 I DTVChan(192.168.1.8-RTP.5):
> SetChannelByString(1002): success
> 2013-08-18 03:12:02.686216 I CetonSH(192.168.1.8-RTP.5):
> GetProgramNumber() got 19567 on attempt 1
> 2013-08-18 03:12:02.686236 I TVRec(1): CreateLiveTVRingBuffer(1002)
> 2013-08-18 03:12:02.686872 I TVRec(1): GetProgramRingBufferForLiveTV()
> 2013-08-18 03:12:02.688799 N AutoExpire: CalcParams(): Max required Free
> Space: 3.0 GB w/freq: 14 min
> 2013-08-18 03:12:02.695438 I TVRec(1):
> StartedRecording(2002_2013-08-18T10:12:02Z)
> fn(/var/lib/mythtv/recordings/2002_20130818101202.mpg)
> 2013-08-18 03:12:02.696650 I Chain: Appended at 0 '2002_20130818101202'
> 2013-08-18 03:12:02.697280 I TVRec(1): Starting Signal Monitor
> 2013-08-18 03:12:02.697287 I TVRec(1): SetupSignalMonitor(1, 1)
> 2013-08-18 03:12:02.697306 I CetonChan(192.168.1.8-RTP.5): Opening Ceton
> channel
> 2013-08-18 03:12:02.697344 I CetonSM(192.168.1.8-RTP.5): ctor
> 2013-08-18 03:12:02.697352 I CetonSH: Using existing stream handler
> 192.168.1.8-RTP.5 for 192.168.1.8-RTP.5 (2 in use)
> 2013-08-18 03:12:02.697357 I TVRec(1): Signal monitor successfully created
> 2013-08-18 03:12:02.697366 I TVRec(1): Setting up table monitoring.
> 2013-08-18 03:12:02.698685 I Using profile 'Live TV' to record
> 2013-08-18 03:12:02.698726 I TVRec(1): MPEG program number: 19567
> 2013-08-18 03:12:02.698776 I
> DTVSM(192.168.1.8-RTP.5)::SetProgramNumber(19567):
> 2013-08-18 03:12:02.698815 I TVRec(1): Successfully set up MPEG table
> monitoring.
> 2013-08-18 03:12:02.699125 I TVRec(1): SetFlags(SignalMonitorRunning,) ->
> RunMainLoop,SignalMonitorRunning,
> 2013-08-18 03:12:02.699132 I SH(192.168.1.8-RTP.5):
> AddListener(0x7f2ec4020660) -- begin
> 2013-08-18 03:12:02.699143 I TVRec(1): ClearFlags(WaitingForSignal,) ->
> RunMainLoop,SignalMonitorRunning,
> 2013-08-18 03:12:02.699152 I SH(192.168.1.8-RTP.5):
> AddListener(0x7f2ec4020660) -- locked
> 2013-08-18 03:12:02.699159 I TVRec(1): SetFlags(WaitingForSignal,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,
> 2013-08-18 03:12:02.699174 I TVRec(1): SetFlags(DummyRecorderRunning,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,
> 2013-08-18 03:12:02.699180 I DummyDTVRecorder -- started
> 2013-08-18 03:12:02.699196 I TVRec(1): SetFlags(RingBufferReady,) ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:02.699211 I TVRec(1): ClearFlags(NeedToStartRecorder,)
> ->
> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:02.699228 I TVRec(1): SetFlags(NeedToStartRecorder,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:02.699396 I TVRec(1): SetFlags(CancelNextRecording,) ->
> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:02.717522 I CetonRTP(192.168.1.8-5): Buffer size was
> 212992 - requested 16777216 - now 2097152
> 2013-08-18 03:12:02.853112 I TVRec(1): SetFlags(FrontendReady,) ->
> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:02.857757 I CetonSH(192.168.1.8-RTP.5): RunTS(): begin
> 2013-08-18 03:12:02.857771 I SH(192.168.1.8-RTP.5):
> AddListener(0x7f2ec4020660) -- end
> 2013-08-18 03:12:07.857960 W CetonSH(192.168.1.8-RTP.5): No data received
> for 5 seconds...checking tuning
> 2013-08-18 03:12:07.952040 W CetonSH(192.168.1.8-RTP.5): VerifyTuning
> detected no carrier lock
> 2013-08-18 03:12:07.952054 I CetonSH(192.168.1.8-RTP.5):
> ClearProgramNumber()
> 2013-08-18 03:12:07.952063 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(0)
> 2013-08-18 03:12:08.094642 I TVRec(1): StopLiveTV(void) curRec:
> 0x7f2ec4019880 pseudoRec: 0x0
> 2013-08-18 03:12:08.105735 I TVRec(1): Changing from WatchingLiveTV to
> None
> 2013-08-18 03:12:08.105768 I TVRec(1):
> ClearFlags(FrontendReady,CancelNextRecording,) ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:08.105790 I TVRec(1): HandleTuning Request:
> Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)
> 2013-08-18 03:12:08.105807 I TVRec(1): TeardownSignalMonitor() -- begin
> 2013-08-18 03:12:08.105825 I CetonSM(192.168.1.8-RTP.5): dtor
> 2013-08-18 03:12:08.105833 I CetonSM(192.168.1.8-RTP.5): Stop() -- begin
> 2013-08-18 03:12:08.132565 I SH(192.168.1.8-RTP.5):
> RemoveListener(0x7f2ec4020660) -- begin
> 2013-08-18 03:12:08.132589 I SH(192.168.1.8-RTP.5):
> RemoveListener(0x7f2ec4020660) -- locked
> 2013-08-18 03:12:10.102363 I CetonSH(192.168.1.8-RTP.5):
> TuneVChannel(1002)
> 2013-08-18 03:12:10.102378 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(1002)
> 2013-08-18 03:12:10.195306 I CetonSH(192.168.1.8-RTP.5): RunTS(): shutdown
> 2013-08-18 03:12:10.233993 I CetonSH(192.168.1.8-RTP.5): RunTS(): end
> 2013-08-18 03:12:10.234240 I SH(192.168.1.8-RTP.5):
> RemoveListener(0x7f2ec4020660) -- end
> 2013-08-18 03:12:10.234257 I CetonSM(192.168.1.8-RTP.5): Stop() -- end
> 2013-08-18 03:12:10.234324 I TVRec(1): TeardownSignalMonitor() -- end
> 2013-08-18 03:12:10.234351 I TVRec(1): ClearFlags(SignalMonitorRunning,)
> ->
> RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:10.234401 I TVRec(1): ClearFlags(WaitingForSignal,) ->
> RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
> 2013-08-18 03:12:10.235190 I TVRec(1):
> FinishedRecording(2002_2013-08-18T10:12:02Z)
> title: Made in America
> in recgroup: LiveTV status: Unknown:Recorder Failed is_dummy finished_now
> 2013-08-18 03:12:10.235986 I Chain: Updated endtime for
> '2002_20130818101202' to 20130818101210
> 2013-08-18 03:12:10.236951 I TVRec(1): ClearFlags(DummyRecorderRunning,)
> -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2013-08-18 03:12:10.237721 I TVRec(1): ClearFlags(RecorderRunning,) ->
> RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2013-08-18 03:12:10.238408 I TVRec(1):
> FinishedRecording(2002_2013-08-18T10:12:02Z)
> title: Made in America
> in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy
> already_finished
> 2013-08-18 03:12:10.239012 I CetonSH(192.168.1.8-RTP.5): TuneFrequency(0,
> qam_256)
> 2013-08-18 03:12:10.694075 N Expiring 0 MB for 2002 at
> 2013-08-18T10:06:46Z => "Made in America"
> 2013-08-18 03:12:15.096176 I MainServer::ANN Playback
> 2013-08-18 03:12:15.096181 I adding: localhost as a client (events: 0)
> 2013-08-18 03:12:15.096357 I TVRec(1): SetFlags(FrontendReady,) ->
> FrontendReady,RunMainLoop,NeedToStartRecorder,RingBufferReady,
> ^C2013-08-18 03:12:16.851389 C Received Interrupt: Code 128, PID 0, UID
> 0, Value 0x00000000
> 2013-08-18 03:12:16.853345 N MythBackend exiting
> 2013-08-18 03:12:17.874404 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(0)
> 2013-08-18 03:12:17.874418 I CetonSH(192.168.1.8-RTP.5):
> PerformTuneVChannel(0)
> 2013-08-18 03:12:19.245621 I TVRec(1): ClearFlags(RecorderRunning,) ->
> FrontendReady,RunMainLoop,NeedToStartRecorder,RingBufferReady,
> 2013-08-18 03:12:19.245644 I TVRec(1): Tearing down RingBuffer
> 2013-08-18 03:12:19.245890 I TVRec(1): ClearFlags(PENDINGACTIONS,) ->
> FrontendReady,RunMainLoop,RingBufferReady,
> 2013-08-18 03:12:19.246201 W MainServer: Unknown socket closing
> MythSocket(0x2205b20)
> 2013-08-18 03:12:19.247399 E MythSocket(2205b20:-1): writeStringList:
> Error, socket went unconnected.
> We wrote 0 of 10 bytes with 1 errors
> starts with: 2 OK
> 2013-08-18 03:13:10.689152 N AutoExpire: CalcParams(): Max required Free
> Space: 1.0 GB w/freq: 15 min
>
>
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-users
>
>
First, what version of myth are you running? 0.26? What I'm seeing there
looks to be ok to me, as far as I can tell, up until:
2013-08-18 03:12:07.857960 W CetonSH(192.168.1.8-RTP.5): No data received
for 5 seconds...checking tuning
2013-08-18 03:12:07.952040 W CetonSH(192.168.1.8-RTP.5): VerifyTuning
detected no carrier lock
If possible, it would be useful to see a backend log + the accompanying
ceton log from the device itself, both from the same timeframe. My initial
thought was going to be a firewall issue or something else wrong with the
RTP stream, but the fact that VerifyTuning didn't like what it found tells
me something else went wrong on the device's side.
--
Ron Frazier
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-users/attachments/20130818/23b177fd/attachment.html>
More information about the mythtv-users
mailing list