[mythtv-users] Can't grab Schedules from within mythtv

[James Pifer]

On 12/28/2013 7:08 AM, Mark Perkins wrote:
> __________________________________________
> Going to Live TV in Mythfrontend should achieve the same goal - ie confirm Myth is getting data from the tuners.
>
> When you say there are no program listings is that just in Mythweb or Mythfrontend as well?
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-users
>

Good catch. The guide DOES show up in mythfrontend. I tried initiating a 
recording from there.

It shows it as recording in mythfronend.
I can see one of the tuner lights is green on the HDHomeRun.
But the mpg file in recordings in not growing.

In the mythbackend log I see:
# tail -f mythbackend.20131227115620.16310.log
2013-12-28 14:08:30.547301 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2036 items in 6.8 = 0.00 match + 6.78 place
2013-12-28 14:08:30.555607 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.
2013-12-28 14:08:32.870906 E [16310/1721] DeleteThread 
mainserver.cpp:114 (delete_file_immediately) - Error deleting 
'GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythtv/1311_20131227125701.mpg': 
No such file or directory
2013-12-28 14:08:37.087077 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2036 items in 6.5 = 0.00 match + 6.53 place
2013-12-28 14:08:37.095568 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.
2013-12-28 14:08:38.878254 E [16310/1633] DeleteThread 
mainserver.cpp:114 (delete_file_immediately) - Error deleting 
'GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythtv/2311_20131227125700.mpg': 
No such file or directory
2013-12-28 14:08:44.357115 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2036 items in 7.3 = 0.00 match + 7.26 place
2013-12-28 14:08:44.361261 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.
2013-12-28 14:08:50.860126 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2036 items in 6.5 = 0.00 match + 6.50 place
2013-12-28 14:08:50.868617 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.
2013-12-28 14:08:56.909109 E [16310/1724] DeleteThread 
mainserver.cpp:114 (delete_file_immediately) - Error deleting 
'GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/mythtv/1311_20131227125700.mpg': 
No such file or directory
2013-12-28 14:08:57.413412 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2037 items in 6.5 = 0.00 match + 6.54 place
2013-12-28 14:08:57.426178 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.
2013-12-28 14:08:57.426187 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 1222.
2013-12-28 14:09:03.699881 I [16310/16326] Scheduler scheduler.cpp:2094 
(HandleReschedule) - Scheduled 2037 items in 6.3 = 0.08 match + 6.19 place
2013-12-28 14:09:03.706210 I [16310/16317] TVRecEvent tv_rec.cpp:1544 
(HandlePendingRecordings) - TVRec(1): ASK_RECORDING 1 0 0 0
2013-12-28 14:09:03.714857 I [16310/16317] TVRecEvent tv_rec.cpp:1030 
(HandleStateChange) - TVRec(1): Changing from None to RecordingOnly
2013-12-28 14:09:03.716538 I [16310/16317] TVRecEvent tv_rec.cpp:3503 
(TuningCheckForHWChange) - TVRec(1): HW Tuner: 1->1
2013-12-28 14:09:03.746696 N [16310/16326] Scheduler autoexpire.cpp:263 
(CalcParams) - AutoExpire: CalcParams(): Max required Free Space: 5.0 GB 
w/freq: 14 min
2013-12-28 14:09:03.747821 I [16310/16326] Scheduler scheduler.cpp:2514 
(HandleRecordingStatusChange) - Tuning recording: Valkyrie: channel 1026 
on cardid 1, sourceid 1
2013-12-28 14:09:04.360658 E [16310/1743] HDHRStreamHandler 
dtvsignalmonitor.cpp:321 (HandlePAT) - DTVSM(1319BC4C-0): Program #0 not 
found in PAT!
Program Association Section
  PSIP tableID(0x0) length(13) extension(0x0)
       version(3) current(1) section(0) last_section(0)
       tsid(0) programCount(1)
   program number     3 has PID 0x02a8

2013-12-28 14:09:04.360670 E [16310/1743] HDHRStreamHandler 
dtvsignalmonitor.cpp:326 (HandlePAT) - DTVSM(1319BC4C-0): But there is 
only one program in the PAT, so we'll just use it
2013-12-28 14:09:04.624198 N [16310/1743] HDHRStreamHandler 
dtvsignalmonitor.cpp:354 (HandlePMT) - DTVSM(1319BC4C-0): PMT says 
program 3 is encrypted
2013-12-28 14:09:04.670883 I [16310/16310] CoreContext scheduler.cpp:638 
(UpdateRecStatus) - Updating status for Valkyrie on cardid 1 (Tuning => 
Recording)
2013-12-28 14:09:04.725748 E [16310/1743] HDHRStreamHandler 
hdhrstreamhandler.cpp:211 (UpdateFilters) - HDHRSH(1319BC4C-0): 
UpdateFilters called in wrong tune mode
2013-12-28 14:09:04.728516 I [16310/16317] TVRecEvent tv_rec.cpp:3997 
(TuningNewRecorder) - TVRec(1): rec->GetPathname(): 
'/mythtv/recordings/1026_20131228140900.mpg'
2013-12-28 14:09:04.729688 E [16310/16317] TVRecEvent 
recorderbase.cpp:164 (SetStrOption) - RecBase(1:1319BC4C-0): 
SetStrOption(...recordingtype): Option not in profile.
2013-12-28 14:09:04.754702 I [16310/16326] Scheduler scheduler.cpp:2034 
(HandleReschedule) - Reschedule requested for id 0.

Thanks,
James