[mythtv-users] Recording Issues with External Channel Change Script

mythtv user mythtv.ef at gmail.com
Mon Oct 29 23:40:05 UTC 2007 

Hi-
 I've got myth SVN running with a DCT-6200 cable box connected over
firewire. Recordings (usually HD ones) often fail for some reason. I'm using
an external channel change script to make sure my firewire connection is
active before beginning the recording. The script can be found at
http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh
. My myth backend logs don't show any obvious errors, they are included
below. Strangely, myth seems to keep tuning to channel 420- which happens to
be the Adult Top 40 music choice channel. The other channel myth likes to
tune to is 146 (Fox Soccer Channel). At times there are recordings schedule
for that channel, but none currently.

I think there are two separate issues here- one being the recordings failing
and one being the spontaneous channel changes.

I can provide more of the log if you think it would help.

Any ideas?

Thanks,
Eric


2007-10-23 12:59:55.168 Channel(/dev/video0): InitPictureAttribute(
colour):  63
            [    0,  127] dflt(   64, 0.50 , 257)
2007-10-23 12:59:55.270 Channel(/dev/video0): InitPictureAttribute(
hue):  0
            [ -128,  127] dflt(    0, 0.50, 128)
2007-10-23 12:59:55.291 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,
2007-10-23 12:59:55.345 TVRec(1): ClearFlags(ExitPlayer,FinishRecording,) ->
RunMainLoop,
2007-10-23 12:59:55.388 TVRec(2): Start channel: 146.
2007-10-23 12:59:55.500 LFireDev(001404FFFE99C670): Adding   0x4c01070070a3
2007-10-23 12:59:55.523 LFireDev(001404FFFE99C670): Updating 0x4c01070070a3
port: 0 node: 0
2007-10-23 12:59:56.284 LFireDev(001404FFFE99C670): Adding
0x1404fffe99c670
2007-10-23 12:59:56.517 LFireDev(001404FFFE99C670): Updating
0x1404fffe99c670 port: 0 node: 1
2007-10-23 12:59:56.635 ChannelBase(2): Input #2: 'MPEG2TS' schan(146)
sourceid(2) ccid(2)
2007-10-23 12:59:56.747 ChannelBase(2): Current Input #2: 'MPEG2TS'
2007-10-23 12:59:56.831 FireChan(001404FFFE99C670): Open()
2007-10-23 12:59:56.832 LFireDev(001404FFFE99C670): Starting Port Handler
Thread
2007-10-23 12:59:56.832 LFireDev(001404FFFE99C670): Starting Port Handler
Thread -- locked
2007-10-23 12:59:56.833 LFireDev(001404FFFE99C670): OpenPort()
2007-10-23 12:59:56.833 LFireDev(001404FFFE99C670): OpenPort() -- got lock
2007-10-23 12:59:56.835 LAVCInfo(): Getting raw1394 handle for port 0
2007-10-23 12:59:56.841 LFireDev(001404FFFE99C670): Subunit Types: Tuner,
Panel,
2007-10-23 12:59:56.842 LFireDev(001404FFFE99C670): Starting port handler
thread
2007-10-23 12:59:56.843 LFireDev(001404FFFE99C670): Waiting for port handler
thread to start
2007-10-23 12:59:56.843 LFireDev(001404FFFE99C670): RunPortHandler -- start
2007-10-23 12:59:56.848 LFireDev(001404FFFE99C670): RunPortHandler -- got
first lock
2007-10-23 12:59:56.855 LFireDev(001404FFFE99C670): Port handler thread
started
2007-10-23 12:59:56.860 External channel change:
/usr/local/bin/6200changer.sh 001404fffe99c670 146
2007-10-23 12:59:56.862 Waiting for External Tuning program to exit
libiec61883 error: error reading oMPR
libiec61883 error: error reading iMPR
Node: '1'
Changing to channel 146
starting with node: 0
node 1: vendor_id = 0x00001404 model_id = 0x00006200
AV/C command for channel 146 = 0x00487C67 040092FF FF000000
2007-10-23 12:59:57.871 ret_pid(0) child(21149) status(0x0)
2007-10-23 12:59:58.883 ret_pid(0) child(21149) status(0x0)
2007-10-23 12:59:59.891 ret_pid(0) child(21149) status(0x0)
2007-10-23 13:00:00.899 ret_pid(0) child(21149) status(0x0)
Stabilizing Firewire Connection!...
Action: Attempt to fix broadcast connection 2 times, node 1
Broadcast: Testing...Success, 74 packets
Broadcast: Testing...Success, 101 packets
Broadcast: Testing...Success, 102 packets
Broadcast: Testing...Success, 96 packets
Broadcast: Testing...Success, 175 packets
Broadcast Fix: Success (already stable)
Broadcast: Testing...Success, 83 packets
Broadcast: Testing...Success, 136 packets
Broadcast: Testing...Success, 165 packets
Broadcast: Testing...Success, 160 packets
Broadcast: Testing...Success, 182 packets
Broadcast Fix: Success (already stable)
Broadcast Fix: Success (already stable)
Broadcast Fix: Success (already stable)
Stable!
2007-10-23 13:00:01.907 ret_pid(21149) child(21149) status(0x0)
2007-10-23 13:00:01.911 External Tuning program no longer running
2007-10-23 13:00:01.912 External Tuning program exited with no error
2007-10-23 13:00:01.912 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,
2007-10-23 13:00:01.915 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) ->
RunMainLoop,
2007-10-23 13:00:01.917 New DB scheduler connection
2007-10-23 13:00:01.920 Connected to database 'mythconverg' at host:
localhost
2007-10-23 13:00:02.120     0 replacements made
2007-10-23 13:00:02.124 Marking generic episodes.
2007-10-23 13:00:03.341 Main::Registering HttpStatus Extension
2007-10-23 13:00:04.981     Found 10673
2007-10-23 13:00:07.753 Marking repeats.
2007-10-23 13:00:07.741 mythbackend version: 0.21.20070910-2 www.mythtv.org
2007-10-23 13:00:08.372 Enabled verbose msgs: important general file record
channel
2007-10-23 13:00:11.904 AutoExpire: CalcParams()
2007-10-23 13:00:04.976 Reschedule requested for id -1.
2007-10-23 13:00: 15.492     Found 18463
2007-10-23 13:00:14.965 Cardid 1: max bitrate 73828 KB/min
2007-10-23 13:00:21.501 Unmarking new episode rebroadcast repeats.
2007-10-23 13:00:21.526 Cardid 2: max bitrate 142089 KB/min
2007-10-23 13:00:21.530 Maximal bitrate of connected encoders is 215917
KB/min
--- GetFilesystemInfos directory list start ---
Dir: mythbox:/video
     Location: Local
     Drive ID: 0
     TotalKB : 110650276
     UsedKB  : 65010856
     FreeKB  : 45639420

--- GetFilesystemInfos directory list end ---
2007-10-23 13:00:21.607 fsID #0: Total:   105.5 GB   Used:    62.0 GB
Free:    43.5 GB
2007-10-23 13:00: 21.634 Checking Hosts that use this filesystem.
2007-10-23 13:00:21.641   mythbox:/video
2007-10-23 13:00:21.644     Cardid 1: max bitrate 9843 Kb/sec, fsID max is
now 73828 KB/min
2007-10-23 13:00:21.654     Cardid 2: max bitrate 18945 Kb/sec, fsID max is
now 215917 KB/min
2007-10-23 13:00:21.669   Max of 215917 KB/min for this fsID is higher than
the existing Max of 0 so we'll use this Max instead
2007-10-23 13:00:21.691 AutoExpire: Found max recording rate of 210 MB/min
2007-10-23 13:00: 21.693 AutoExpire: CalcParams(): Required Free
Space: 2.0GB w/freq: 5 min
2007-10-23 13:00:21.696 SG(): CheckAllStorageGroupDirs(): Checking All
Storage Group directories
2007-10-23 13:00:21.698 SG(Default): Checking directory '/video/' in group
'Default'.
2007-10-23 13:00:21.794 Scheduled 282 items in 16.8 = 15.96 match + 0.86place
2007-10-23 13:00:21.803 AUTO-Startup assumed
2007-10-23 13:00:21.805 TVRec(2): StartRecording(English Premier League
Soccer)
2007-10-23 13:00: 21.806 TVRec(2): ClearFlags(AskAllowRecording,) ->
RunMainLoop,
2007-10-23 13:00:21.901 ProgramInfo: StartedRecording: Recording to
'/video/2146_20071023130000.mpg'
2007-10-23 13:00:21.932 AutoExpire: Adding Programs to 'Do Not Expire' List
2007-10-23 13:00:21.999     2146 @ 2007-10-23T13:00:00 in use by recorder on
mythbox
2007-10-23 13:00:22.001 AutoExpire: ExpireLiveTV(10000)
2007-10-23 13:00:22.001 AutoExpire: FillDBOrdered: Adding Short LiveTV
programs in starttime order
2007-10-23 13:00:22.002 AutoExpire: SendDeleteMessages. Nothing to expire.
2007-10-23 13:00:22.933     Found 0
2007-10-23 13:00:23.066 TVRec(2): StartedRecording(0xaec0b128)
fn(/video/2146_20071023130000.mpg)
2007-10-23 13:00:44.111 TVRec(2): ClearFlags(CancelNextRecording,) ->
RunMainLoop,
2007-10-23 13:00:25.169 Marking episode first showings.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mythtv.org/pipermail/mythtv-users/attachments/20071029/be15bba2/attachment.htm

More information about the mythtv-users mailing list