[mythtv-users] Firewire and HDHomeRun Prime to Comcast stopped working

Bob Shanteau rmshant at gmail.com
Thu Oct 31 22:04:34 UTC 2013 

On 10/26/2013 3:06 PM, Eric Sharkey wrote:
> On Thu, Oct 24, 2013 at 5:11 PM, Bob Shanteau <rmshant at gmail.com> wrote:
>>> Have you checked the logs for interesting messages?
>> Where do I find those log files? I run MythBuntu 12.04.3.
> They're typically in the folder /var/log/mythtv.  You probably want to
> look at mythbackend.log

Here is mythbackend.log starting from when I restarted mythbackend:
---
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: C 
thread_unknown mythcommandlineparser.cpp:2595 (ConfigureLogging) 
mythbackend version: fixes/0.27 [v0.27-63-g8845997] www.mythtv.org
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: C 
thread_unknown mythcommandlineparser.cpp:2597 (ConfigureLogging) Qt 
version: compile: 4.8.1, runtime: 4.8.1
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N 
thread_unknown mythcommandlineparser.cpp:2599 (ConfigureLogging) Enabled 
verbose msgs:  general
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N 
thread_unknown logging.cpp:914 (logStart) Setting Log Level to LOG_INFO
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup 
Interrupt handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup 
Terminated handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup 
Segmentation fault handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Aborted 
handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Bus 
error handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Floating 
point exception handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup Illegal 
instruction handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I 
thread_unknown signalhandling.cpp:194 (SetHandlerPrivate) Setup 
Real-time signal 0 handler
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N 
thread_unknown mythdirs.cpp:55 (InitializeMythDirs) Using runtime prefix 
= /usr
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N 
thread_unknown mythdirs.cpp:68 (InitializeMythDirs) Using configuration 
directory = /home/mythtv/.mythtv
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I CoreContext 
mythcorecontext.cpp:249 (Init) Assumed character encoding: en_US.UTF-8
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N CoreContext 
mythcontext.cpp:504 (LoadDatabaseSettings) Empty LocalHostName.
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I CoreContext 
mythcontext.cpp:512 (LoadDatabaseSettings) Using localhost value of 
localhost
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I Logger 
logging.cpp:315 (run) Added logging to the console
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N CoreContext 
mythcorecontext.cpp:1294 (InitLocale) Setting QT default locale to en_US
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I CoreContext 
mythcorecontext.cpp:1327 (SaveLocaleDefaults) Current locale en_US
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N CoreContext 
mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from 
/usr/share/mythtv//locales/en_us.xml
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I CoreContext 
schemawizard.cpp:118 (Compare) Current MythTV Schema Version 
(DBSchemaVer): 1317
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I CoreContext 
mythtranslation.cpp:65 (load) Loading en_us translation for module 
mythfrontend
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: N CoreContext 
main_helpers.cpp:582 (run_backend) MythBackend: Starting up as the 
master server.
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I LogForward 
loggingserver.cpp:1372 (forwardMessage) New Client:  (#1)
Oct 31 14:05:28 localhost mythbackend: mythbackend[3243]: I LogForward 
loggingserver.cpp:295 (SyslogLogger) Added syslogging
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
recorders/firewiredevice.cpp:334 (SetLastChannel) SetLastChannel(706): 
cleared: no
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
programinfo.cpp:2147 (CheckProgramIDAuthorities) Found 1 distinct 
programid authorities
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I Scheduler 
mythdbcon.cpp:436 (getStaticCon) New static DB connectionSchedCon
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 'LogClean'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 'DBCleanup'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 
'ThemeUpdateNotifications'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 
'RecordedArtworkUpdate'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 'MythFillDB'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 
'JobQueueRecover'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:582 (RegisterTask) Registering HouseKeeperTask 
'HardwareProfiler'.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
housekeeper.cpp:655 (Start) Starting HouseKeeper.
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP 127.0.0.1:6544
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP [::1]:6544
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP 
[fe80::4261:86ff:fe99:1e28%eth0]:6544
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: N CoreContext 
mediaserver.cpp:168 (Init) MediaServer:: Loopback address specified - 
127.0.0.1. Disabling UPnP
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
main_helpers.cpp:668 (run_backend) Main::Registering HttpStatus Extension
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP 127.0.0.1:6543
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP [::1]:6543
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
serverpool.cpp:399 (listen) Listening on TCP 
[fe80::4261:86ff:fe99:1e28%eth0]:6543
Oct 31 14:05:30 localhost mythbackend: mythbackend[3243]: N CoreContext 
autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required 
Free Space: 1.0 GB w/freq: 15 min
Oct 31 14:05:33 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for MATCH 0 0 
0 - SchedulerInit
Oct 31 14:05:38 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2206 (HandleReschedule) Scheduled 473 items in 5.5 = 3.92 
match + 0.11 check + 1.48 place
Oct 31 14:05:38 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2273 (HandleRunSchedulerStartup) Scheduler: Seem to be 
woken up by USER
Oct 31 14:05:43 localhost mythbackend: mythbackend[3243]: I 
ProcessRequest mainserver.cpp:1420 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 14:05:43 localhost mythbackend: mythbackend[3243]: I 
ProcessRequest mainserver.cpp:1422 (HandleAnnounce) adding: localhost as 
a client (events: 0)
Oct 31 14:05:43 localhost mythbackend: mythbackend[3243]: I 
ProcessRequest mainserver.cpp:1420 (HandleAnnounce) MainServer::ANN Monitor
Oct 31 14:05:43 localhost mythbackend: mythbackend[3243]: I 
ProcessRequest mainserver.cpp:1422 (HandleAnnounce) adding: localhost as 
a client (events: 1)
Oct 31 14:06:09 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for MATCH 
4979 0 0 - SaveRule The People's Court
Oct 31 14:06:10 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2206 (HandleReschedule) Scheduled 474 items in 1.5 = 0.03 
match + 0.00 check + 1.48 place
Oct 31 14:06:10 localhost mythbackend: mythbackend[3243]: I TVRecEvent 
tv_rec.cpp:1048 (HandleStateChange) TVRec[7]: Changing from None to 
RecordingOnly
Oct 31 14:06:10 localhost mythbackend: mythbackend[3243]: I TVRecEvent 
tv_rec.cpp:3597 (TuningCheckForHWChange) TVRec[7]: HW Tuner: 7->7
Oct 31 14:06:12 localhost mythbackend: mythbackend[3243]: I TVRecEvent 
recorders/firewiredevice.cpp:334 (SetLastChannel) SetLastChannel(702): 
cleared: no
Oct 31 14:06:12 localhost mythbackend: mythbackend[3243]: N Scheduler 
autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required 
Free Space: 3.0 GB w/freq: 14 min
Oct 31 14:06:12 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2643 (HandleRecordingStatusChange) Tuning recording: "The 
People's Court":"Her Sugar Daddy Sent Her to Modeling School": channel 
1702 on cardid 7, sourceid 1
Oct 31 14:06:12 localhost mythbackend: mythbackend[3243]: I 
FirewireTableMonitor recorders/linuxfirewiredevice.cpp:731 
(SetAVStreamBufferSize) LFireDev(001C11FFFEA6E812): Buffered packets 
2350 (9400 KB)
Oct 31 14:06:21 localhost mythbackend: mythbackend[3243]: E 
ProcessRequest programinfo.cpp:2369 (GetPlaybackURL) 
ProgramInfo(12720_20120617140201.mpg): GetPlaybackURL: 
'12720_20120617140201.mpg' should be local, but it can not be found.
[same message repeated multiple times]
Oct 31 14:06:26 localhost mythbackend: mythbackend[3243]: E 
ProcessRequest programinfo.cpp:2369 (GetPlaybackURL) 
ProgramInfo(1702_20131031210600.mpg): GetPlaybackURL: 
'1702_20131031210600.mpg' should be local, but it can not be found.
Oct 31 14:06:30 localhost mythbackend: mythbackend[3243]: I CoreContext 
scheduler.cpp:704 (UpdateRecStatus) Updating status for "The People's 
Court":"Her Sugar Daddy Sent Her to Modeling School" on cardid 7 (Tuning 
=> Recorder Failed)
Oct 31 14:06:30 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for CHECK -9 
4979 0 UpdateRecStatus2 | The People's Court | Her Sugar Daddy Sent Her 
to Modeling School |  | EP002309632367
Oct 31 14:06:31 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2206 (HandleReschedule) Scheduled 474 items in 1.4 = 0.00 
match + 0.00 check + 1.42 place
Oct 31 14:06:33 localhost mythbackend: mythbackend[3243]: E 
ProcessRequest programinfo.cpp:2369 (GetPlaybackURL) 
ProgramInfo(1702_20131031210600.mpg): GetPlaybackURL: 
'1702_20131031210600.mpg' should be local, but it can not be found.
Oct 31 14:06:45  mythbackend: last message repeated 2 times
Oct 31 14:06:45 localhost mythbackend: mythbackend[3243]: I TVRecEvent 
tv_rec.cpp:1048 (HandleStateChange) TVRec[7]: Changing from 
RecordingOnly to None
Oct 31 14:06:45 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2093 (HandleReschedule) Reschedule requested for CHECK -9 
4979 0 DoHandleDelete3 | The People's Court | Her Sugar Daddy Sent Her 
to Modeling School |  | EP002309632367
Oct 31 14:06:45 localhost mythbackend: mythbackend[3243]: E 
ProcessRequest programinfo.cpp:2369 (GetPlaybackURL) 
ProgramInfo(1702_20131031205800.mpg): GetPlaybackURL: 
'1702_20131031205800.mpg' should be local, but it can not be found.
Oct 31 14:06:46 localhost mythbackend: mythbackend[3243]: I Scheduler 
scheduler.cpp:2206 (HandleReschedule) Scheduled 474 items in 1.4 = 0.00 
match + 0.00 check + 1.41 place
Oct 31 14:06:48 localhost mythbackend: mythbackend[3243]: N Expire 
autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required 
Free Space: 1.0 GB w/freq: 15 min
Oct 31 14:06:48 localhost mythbackend: mythbackend[3243]: E DeleteThread 
mainserver.cpp:117 (delete_file_immediately) Error deleting 
'GetPlaybackURL/UNABLE/TO/FIND/LOCAL/FILE/ON/localhost/1702_20131031210600.mpg': 
No such file or directory
---

>> What happens is that when I try to record certain HDTV channels on the DCH 3200 tuner through Firewire, I get an empty file. I have not found a workaround.
> Are these channels copy protected? You probably want to check the DCH 
> 3200's diagnostic mode. 
> http://en.wikibooks.org/wiki/How_to_use_a_Motorola_DVR/Configuration 
> Follow the instructions for "How To Check If 5C DTCP is Enabled". If 
> it's enabled, MythTV can't record from it.

Did I say that I can record these channels just fine with my HDHomerun 
Prime using a CableCard?

Anyway, when I follow the instructions on "How To Check If 5C DTCP is 
Enabled", here is what I get.

Channel 706 - can record through Firewire:
5C IMPLEMENTATION: YES
COPY CONTROL: COPY ONCE

IBTUNER 1
FREQUENCY: 183.0000 MHz
CCI: 0x02
DRM: 0x00
RC FLAG: 0x00

Channel 702 - cannot record through Firewire:
5C IMPLEMENTATION: YES
COPY CONTROL: COPY ONCE

IBTUNER 1
FREQUENCY: 789.0000 MHz
CCI: 0x02
DRM: 0x00
RC FLAG: 0x00

The only difference I see is the frequency. I see no reason why I can 
record one channel through Firewire and not the other.

Bob Shanteau

More information about the mythtv-users mailing list