<div dir="ltr">On Sun, Aug 18, 2013 at 4:15 PM, Joseph Bebel <span dir="ltr"><<a href="mailto:joseph.bebel@gmail.com" target="_blank">joseph.bebel@gmail.com</a>></span> wrote:<br><div class="gmail_extra"><div class="gmail_quote">
<blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex"><div dir="ltr"><div><div class="h5"><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex">
The definite good news here is that it appears the problem is not due to a<br>
difference in the devices behavior. Based on the info you've provided, It<br>appears (at least so far) to be compatible with the 4 tuner models<br>protocols.<br>Beyond that, I'm a little uncertain. The first thing I notice when looking<br>
at your logs, is the following in the HTTP log:<br>POST /channel_request.cgi HTTP/1.1<br>content-type: application/x-www-form-urlencoded<br>content-length: 22<br>instance_id=0&channel=<br>Note that the channel parameter is blank in your last line. Then I look in<br>
the backend logs and see:<br>CetonSH(192.168.1.8-RTP.0): PerformTuneVChannel()<br>I double checked the code, and PerformTuneVChannel should be getting passed<br>a channel number as a parameter, and that channel number should have been<br>
included in the log. So it would seem it's trying to tune a blank channel<br>number. Looking at the code, it's a little complex to trace down, but the<br>only way I can see that happening is if your channel configuration is a bit<br>
screwy. So that's my current theory. Where did your current channel<br>configuration come from? Did you create it from scratch, or did you reuse<br>one you already had setup for a different tuner? If the latter, what type<br>
of tuner was it created for? Different tuners fill in different values in<br>the database fields, so channel configs for one tuner type is not always<br>compatible with another tuner type.<br>My suggestion is going to be to create a brand new channel lineup, just for<br>
your ceton device. So create a brand new video source linked to the same<br>same schedules direct lineup, assign it to your ceton tuner in the Input<br>Connections screen and then click "Fetch channels from listing source". You<br>
might need to then run mythfilldatabase again (not positive).<br><br>-- <br>Ron Frazier</blockquote><div><br></div></div></div><div>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.</div>
<div><br></div><div>(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)</div><div><br></div><div>Thanks a lot, </div>
<div>Joe</div><div><br></div><div><div>2013-08-18 03:11:47.615453 N MythBackend: Starting up as the master server.</div><div>2013-08-18 03:11:47.616814 I TVRec(1): Start channel: 1002.</div><div>2013-08-18 03:11:47.616831 I CetonChan(192.168.1.8-RTP.5): Opening Ceton channel</div>
<div>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</div><div>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</div>
<div>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</div><div>2013-08-18 03:11:48.008492 I ChannelBase(1): Input #1: 'MPEG2TS' schan(1002) sourceid(1) ccid(1)</div>
<div>2013-08-18 03:11:48.008507 I ChannelBase(1): Current Input #1: 'MPEG2TS'</div><div>2013-08-18 03:11:48.008763 I DTVChan(192.168.1.8-RTP.5): SetChannelByString(1002): </div><div>2013-08-18 03:11:48.008922 I CetonSH(192.168.1.8-RTP.5): ClearProgramNumber()</div>
<div>2013-08-18 03:11:48.008924 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(0)</div><div>2013-08-18 03:11:48.152585 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(1002)</div><div>2013-08-18 03:11:48.152590 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(1002)</div>
<div>2013-08-18 03:11:48.214280 I DTVChan(192.168.1.8-RTP.5): SetChannelByString(1002): success</div><div>2013-08-18 03:11:48.266362 I CetonSH(192.168.1.8-RTP.5): GetProgramNumber() got 0 on attempt 1</div><div>2013-08-18 03:11:48.433857 I CetonSH(192.168.1.8-RTP.5): GetProgramNumber() got 0 on attempt 2</div>
<div>2013-08-18 03:11:48.586089 I CetonSH(192.168.1.8-RTP.5): GetProgramNumber() got 0 on attempt 3</div><div>2013-08-18 03:11:48.753121 I CetonSH(192.168.1.8-RTP.5): GetProgramNumber() got 19567 on attempt 4</div><div>
2013-08-18 03:11:48.753136 I CetonSH(192.168.1.8-RTP.5): TuneFrequency(0, qam_256)</div><div>2013-08-18 03:11:50.943357 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(0)</div><div>2013-08-18 03:11:50.943372 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(0)</div>
<div>2013-08-18 03:11:51.065845 I TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,</div><div>2013-08-18 03:11:51.065872 I TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,</div><div>2013-08-18 03:11:51.069571 I Found 1 distinct programid authorities</div>
<div>2013-08-18 03:11:51.069960 I New static DB connectionSchedCon</div><div>2013-08-18 03:11:51.076745 I Listening on TCP <a href="http://127.0.0.1:6544" target="_blank">127.0.0.1:6544</a></div><div>2013-08-18 03:11:51.076861 I Listening on TCP [::1]:6544</div>
<div>2013-08-18 03:11:51.079966 N MediaServer:: Loopback address specified - 127.0.0.1. Disabling UPnP</div><div>2013-08-18 03:11:51.079977 I Main::Registering HttpStatus Extension</div><div>2013-08-18 03:11:51.082334 I Listening on TCP <a href="http://127.0.0.1:6543" target="_blank">127.0.0.1:6543</a></div>
<div>2013-08-18 03:11:51.082439 I Listening on TCP [::1]:6543</div><div>2013-08-18 03:11:51.085064 N AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min</div><div>2013-08-18 03:11:54.075366 I Reschedule requested for MATCH 0 0 0 - SchedulerInit</div>
<div>2013-08-18 03:11:54.112373 I Scheduled 0 items in 0.0 = 0.01 match + 0.00 check + 0.02 place</div><div>2013-08-18 03:11:54.119185 I Scheduler: Seem to be woken up by USER</div><div>2013-08-18 03:12:01.071453 I Running housekeeping thread</div>
<div>2013-08-18 03:12:02.465810 I MainServer::ANN Monitor</div><div>2013-08-18 03:12:02.465827 I adding: localhost as a client (events: 0)</div><div>2013-08-18 03:12:02.466562 I MainServer::ANN Monitor</div><div>2013-08-18 03:12:02.466574 I adding: localhost as a client (events: 1)</div>
<div>2013-08-18 03:12:02.538960 I Getting next free recorder after : -1</div><div>2013-08-18 03:12:02.538965 I Checking card 1. Best card so far -1</div><div>2013-08-18 03:12:02.539311 I Best card is 1</div><div>2013-08-18 03:12:02.540407 I MainServer::ANN Playback</div>
<div>2013-08-18 03:12:02.540410 I adding: localhost as a client (events: 0)</div><div>2013-08-18 03:12:02.541292 I TVRec(1): Changing from None to WatchingLiveTV</div><div>2013-08-18 03:12:02.541301 I TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,</div>
<div>2013-08-18 03:12:02.541308 I TVRec(1): HandleTuning Request: Program(NULL) channel() input() flags(LiveTV,)</div><div>2013-08-18 03:12:02.541749 I TVRec(1): Start channel: 1002.</div><div>2013-08-18 03:12:02.542268 I TVRec(1): HW Tuner: 1->1</div>
<div>2013-08-18 03:12:02.542273 I TVRec(1): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,</div><div>2013-08-18 03:12:02.542278 I TVRec(1): No recorder yet, calling TuningFrequency</div><div>2013-08-18 03:12:02.542374 I CetonChan(192.168.1.8-RTP.5): Opening Ceton channel</div>
<div>2013-08-18 03:12:02.542691 I DTVChan(192.168.1.8-RTP.5): SetChannelByString(1002): </div><div>2013-08-18 03:12:02.543040 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(1002)</div><div>2013-08-18 03:12:02.543043 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(1002)</div>
<div>2013-08-18 03:12:02.589146 I DTVChan(192.168.1.8-RTP.5): SetChannelByString(1002): success</div><div>2013-08-18 03:12:02.686216 I CetonSH(192.168.1.8-RTP.5): GetProgramNumber() got 19567 on attempt 1</div><div>2013-08-18 03:12:02.686236 I TVRec(1): CreateLiveTVRingBuffer(1002)</div>
<div>2013-08-18 03:12:02.686872 I TVRec(1): GetProgramRingBufferForLiveTV()</div><div>2013-08-18 03:12:02.688799 N AutoExpire: CalcParams(): Max required Free Space: 3.0 GB w/freq: 14 min</div><div>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)</div>
<div>2013-08-18 03:12:02.696650 I Chain: Appended@0 '2002_20130818101202'</div><div>2013-08-18 03:12:02.697280 I TVRec(1): Starting Signal Monitor</div><div>2013-08-18 03:12:02.697287 I TVRec(1): SetupSignalMonitor(1, 1)</div>
<div>2013-08-18 03:12:02.697306 I CetonChan(192.168.1.8-RTP.5): Opening Ceton channel</div><div>2013-08-18 03:12:02.697344 I CetonSM(192.168.1.8-RTP.5): ctor</div><div>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)</div>
<div>2013-08-18 03:12:02.697357 I TVRec(1): Signal monitor successfully created</div><div>2013-08-18 03:12:02.697366 I TVRec(1): Setting up table monitoring.</div><div>2013-08-18 03:12:02.698685 I Using profile 'Live TV' to record</div>
<div>2013-08-18 03:12:02.698726 I TVRec(1): MPEG program number: 19567</div><div>2013-08-18 03:12:02.698776 I DTVSM(192.168.1.8-RTP.5)::SetProgramNumber(19567): </div><div>2013-08-18 03:12:02.698815 I TVRec(1): Successfully set up MPEG table monitoring.</div>
<div>2013-08-18 03:12:02.699125 I TVRec(1): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning,</div><div>2013-08-18 03:12:02.699132 I SH(192.168.1.8-RTP.5): AddListener(0x7f2ec4020660) -- begin</div>
<div>2013-08-18 03:12:02.699143 I TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning,</div><div>2013-08-18 03:12:02.699152 I SH(192.168.1.8-RTP.5): AddListener(0x7f2ec4020660) -- locked</div>
<div>2013-08-18 03:12:02.699159 I TVRec(1): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,</div><div>2013-08-18 03:12:02.699174 I TVRec(1): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,</div>
<div>2013-08-18 03:12:02.699180 I DummyDTVRecorder -- started</div><div>2013-08-18 03:12:02.699196 I TVRec(1): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div>
<div>2013-08-18 03:12:02.699211 I TVRec(1): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div><div>2013-08-18 03:12:02.699228 I TVRec(1): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div>
<div>2013-08-18 03:12:02.699396 I TVRec(1): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div><div>
2013-08-18 03:12:02.717522 I CetonRTP(192.168.1.8-5): Buffer size was 212992 - requested 16777216 - now 2097152</div>
<div>2013-08-18 03:12:02.853112 I TVRec(1): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div>
<div>2013-08-18 03:12:02.857757 I CetonSH(192.168.1.8-RTP.5): RunTS(): begin</div><div>2013-08-18 03:12:02.857771 I SH(192.168.1.8-RTP.5): AddListener(0x7f2ec4020660) -- end</div><div>2013-08-18 03:12:07.857960 W CetonSH(192.168.1.8-RTP.5): No data received for 5 seconds...checking tuning</div>
<div>2013-08-18 03:12:07.952040 W CetonSH(192.168.1.8-RTP.5): VerifyTuning detected no carrier lock</div><div>2013-08-18 03:12:07.952054 I CetonSH(192.168.1.8-RTP.5): ClearProgramNumber()</div><div>2013-08-18 03:12:07.952063 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(0)</div>
<div>2013-08-18 03:12:08.094642 I TVRec(1): StopLiveTV(void) curRec: 0x7f2ec4019880 pseudoRec: 0x0</div><div>2013-08-18 03:12:08.105735 I TVRec(1): Changing from WatchingLiveTV to None</div><div>2013-08-18 03:12:08.105768 I TVRec(1): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,</div>
<div>2013-08-18 03:12:08.105790 I TVRec(1): HandleTuning Request: Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)</div><div>2013-08-18 03:12:08.105807 I TVRec(1): TeardownSignalMonitor() -- begin</div><div>
2013-08-18 03:12:08.105825 I CetonSM(192.168.1.8-RTP.5): dtor</div><div>2013-08-18 03:12:08.105833 I CetonSM(192.168.1.8-RTP.5): Stop() -- begin</div><div>2013-08-18 03:12:08.132565 I SH(192.168.1.8-RTP.5): RemoveListener(0x7f2ec4020660) -- begin</div>
<div>2013-08-18 03:12:08.132589 I SH(192.168.1.8-RTP.5): RemoveListener(0x7f2ec4020660) -- locked</div><div>2013-08-18 03:12:10.102363 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(1002)</div><div>2013-08-18 03:12:10.102378 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(1002)</div>
<div>2013-08-18 03:12:10.195306 I CetonSH(192.168.1.8-RTP.5): RunTS(): shutdown</div><div>2013-08-18 03:12:10.233993 I CetonSH(192.168.1.8-RTP.5): RunTS(): end</div><div>2013-08-18 03:12:10.234240 I SH(192.168.1.8-RTP.5): RemoveListener(0x7f2ec4020660) -- end</div>
<div>2013-08-18 03:12:10.234257 I CetonSM(192.168.1.8-RTP.5): Stop() -- end</div><div>2013-08-18 03:12:10.234324 I TVRec(1): TeardownSignalMonitor() -- end</div><div>2013-08-18 03:12:10.234351 I TVRec(1): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,</div>
<div>2013-08-18 03:12:10.234401 I TVRec(1): ClearFlags(WaitingForSignal,) -> RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,</div><div>2013-08-18 03:12:10.235190 I TVRec(1): FinishedRecording(2002_2013-08-18T10:12:02Z)</div>
<div><span style="white-space:pre-wrap"> </span>title: Made in America</div><div><span style="white-space:pre-wrap"> </span>in recgroup: LiveTV status: Unknown:Recorder Failed is_dummy finished_now</div><div>2013-08-18 03:12:10.235986 I Chain: Updated endtime for '2002_20130818101202' to 20130818101210</div>
<div>2013-08-18 03:12:10.236951 I TVRec(1): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,</div><div>2013-08-18 03:12:10.237721 I TVRec(1): ClearFlags(RecorderRunning,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady,</div>
<div>2013-08-18 03:12:10.238408 I TVRec(1): FinishedRecording(2002_2013-08-18T10:12:02Z)</div><div><span style="white-space:pre-wrap"> </span>title: Made in America</div><div><span style="white-space:pre-wrap"> </span>in recgroup: LiveTV status: Recorder Failed:Recorder Failed not_dummy already_finished</div>
<div>2013-08-18 03:12:10.239012 I CetonSH(192.168.1.8-RTP.5): TuneFrequency(0, qam_256)</div><div>2013-08-18 03:12:10.694075 N Expiring 0 MB for 2002 at 2013-08-18T10:06:46Z => "Made in America"</div><div>
2013-08-18 03:12:15.096176 I MainServer::ANN Playback</div>
<div>2013-08-18 03:12:15.096181 I adding: localhost as a client (events: 0)</div><div>2013-08-18 03:12:15.096357 I TVRec(1): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,NeedToStartRecorder,RingBufferReady,</div>
<div>^C2013-08-18 03:12:16.851389 C Received Interrupt: Code 128, PID 0, UID 0, Value 0x00000000</div><div>2013-08-18 03:12:16.853345 N MythBackend exiting</div><div>2013-08-18 03:12:17.874404 I CetonSH(192.168.1.8-RTP.5): TuneVChannel(0)</div>
<div>2013-08-18 03:12:17.874418 I CetonSH(192.168.1.8-RTP.5): PerformTuneVChannel(0)</div><div>2013-08-18 03:12:19.245621 I TVRec(1): ClearFlags(RecorderRunning,) -> FrontendReady,RunMainLoop,NeedToStartRecorder,RingBufferReady,</div>
<div>2013-08-18 03:12:19.245644 I TVRec(1): Tearing down RingBuffer</div><div>2013-08-18 03:12:19.245890 I TVRec(1): ClearFlags(PENDINGACTIONS,) -> FrontendReady,RunMainLoop,RingBufferReady,</div><div>2013-08-18 03:12:19.246201 W MainServer: Unknown socket closing MythSocket(0x2205b20)</div>
<div>2013-08-18 03:12:19.247399 E MythSocket(2205b20:-1): writeStringList: Error, socket went unconnected.</div><div><span style="white-space:pre-wrap"> </span>We wrote 0 of 10 bytes with 1 errors</div><div><span style="white-space:pre-wrap"> </span>starts with: 2 OK</div>
<div>2013-08-18 03:13:10.689152 N AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min</div></div><div><br></div></div>
<br>_______________________________________________<br>
mythtv-users mailing list<br>
<a href="mailto:mythtv-users@mythtv.org">mythtv-users@mythtv.org</a><br>
<a href="http://www.mythtv.org/mailman/listinfo/mythtv-users" target="_blank">http://www.mythtv.org/mailman/listinfo/mythtv-users</a><br>
<br></blockquote></div><div class="gmail_extra"><br></div>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:</div><div class="gmail_extra"><br>
</div><div class="gmail_extra"><div style="font-family:arial,sans-serif;font-size:13px">2013-08-18 03:12:07.857960 W CetonSH(192.168.1.8-RTP.5): No data received for 5 seconds...checking tuning</div><div style="font-family:arial,sans-serif;font-size:13px">
2013-08-18 03:12:07.952040 W CetonSH(192.168.1.8-RTP.5): VerifyTuning detected no carrier lock</div><div style="font-family:arial,sans-serif;font-size:13px"><br></div><div style="font-family:arial,sans-serif;font-size:13px">
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.<br>
</div><br clear="all"><div><br></div>-- <br>Ron Frazier
</div></div>