Hi-<br> 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 <a href="http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">http://www.mythtv.org/wiki/index.php/User:Steveadeff#6200changer.sh
</a>.
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. <br><br>I think there are two separate issues here- one being the recordings failing and one being the spontaneous channel changes.
<br><br>I can provide more
of the log if you think it would help. <br><br>Any ideas?<br><br>Thanks,<br>Eric<br><br><br>2007-10-23 12:59:55.168 Channel(/dev/video0): InitPictureAttribute( colour): 63<br> [ 0, 127] dflt( 64, 0.50
, 257)<br>2007-10-23 12:59:55.270 Channel(/dev/video0): InitPictureAttribute( hue): 0
<br> [ -128, 127] dflt( 0, 0.50, 128)<br>2007-10-23 12:59:55.291 TVRec(1): SetFlags(RunMainLoop,) -> RunMainLoop,<br>2007-10-23 12:59:55.345 TVRec(1): ClearFlags(ExitPlayer,FinishRec<div>ording,) -> RunMainLoop,
<br>2007-10-23 12:59:55.388 TVRec(2): Start channel: 146.<br>2007-10-23 12:59:55.500 LFireDev(001404FFFE99C670): Adding 0x4c01070070a3<br>2007-10-23 12:59:55.523 LFireDev(001404FFFE99C670): Updating 0x4c01070070a3 port: 0 node: 0
<br>2007-10-23 12:59:56.284 LFireDev(001404FFFE99C670): Adding 0x1404fffe99c670<br>2007-10-23 12:59:56.517 LFireDev(001404FFFE99C670): Updating 0x1404fffe99c670 port: 0 node: 1<br>2007-10-23 12:59:56.635 ChannelBase(2): Input #2: 'MPEG2TS' schan(146) sourceid(2) ccid(2)
<br>2007-10-23 12:59:56.747 ChannelBase(2): Current Input #2: 'MPEG2TS'<br>2007-10-23 12:59:56.831 FireChan(001404FFFE99C670): Open()<br>2007-10-23 12:59:56.832 LFireDev(001404FFFE99C670): Starting Port Handler Thread
<br>2007-10-23 12:59:56.832 LFireDev(001404FFFE99C670): Starting Port Handler Thread -- locked<br>2007-10-23 12:59:56.833 LFireDev(001404FFFE99C670): OpenPort()<br>2007-10-23 12:59:56.833 LFireDev(001404FFFE99C670): OpenPort() -- got lock
<br>2007-10-23 12:59:56.835 LAVCInfo(): Getting raw1394 handle for port 0<br>2007-10-23 12:59:56.841 LFireDev(001404FFFE99C670): Subunit Types: Tuner, Panel, <br>2007-10-23 12:59:56.842 LFireDev(001404FFFE99C670): Starting port handler thread
<br>2007-10-23 12:59:56.843 LFireDev(001404FFFE99C670): Waiting for port handler thread to start<br>2007-10-23 12:59:56.843 LFireDev(001404FFFE99C670): RunPortHandler -- start<br>2007-10-23 12:59:56.848 LFireDev(001404FFFE99C670): RunPortHandler -- got first lock
<br>2007-10-23 12:59:56.855 LFireDev(001404FFFE99C670): Port handler thread started<br>2007-10-23 12:59:56.860 External channel change: /usr/local/bin/6200changer.sh 001404fffe99c670 146<br>2007-10-23 12:59:56.862 Waiting for External Tuning program to exit
<br>libiec61883 error: error reading oMPR<br>libiec61883 error: error reading iMPR<br>Node: '1'<br>Changing to channel 146<br>starting with node: 0<br>node 1: vendor_id = 0x00001404 model_id = 0x00006200<br>AV/C command for channel 146 = 0x00487C67 040092FF FF000000
<br>2007-10-23 12:59:57.871 ret_pid(0) child(21149) status(0x0)<br>2007-10-23 12:59:58.883 ret_pid(0) child(21149) status(0x0)<br>2007-10-23 12:59:59.891 ret_pid(0) child(21149) status(0x0)<br>2007-10-23 13:00:00.899 ret_pid(0) child(21149) status(0x0)
<br>Stabilizing Firewire Connection!...<br>Action: Attempt to fix broadcast connection 2 times, node 1<br>Broadcast: Testing...Success, 74 packets<br>Broadcast: Testing...Success, 101 packets<br>Broadcast: Testing...Success, 102 packets
<br>Broadcast: Testing...Success, 96 packets<br>Broadcast: Testing...Success, 175 packets<br>Broadcast Fix: Success (already stable)<br>Broadcast: Testing...Success, 83 packets<br>Broadcast: Testing...Success, 136 packets
<br>Broadcast: Testing...Success, 165 packets<br>Broadcast: Testing...Success, 160 packets<br>Broadcast: Testing...Success, 182 packets<br>Broadcast Fix: Success (already stable)<br>Broadcast Fix: Success (already stable)
<br>Broadcast Fix: Success (already stable)<br>Stable!<br>2007-10-23 13:00:01.907 ret_pid(21149) child(21149) status(0x0)<br>2007-10-23 13:00:01.911 External Tuning program no longer running<br>2007-10-23 13:00:01.912 External Tuning program exited with no error
<br>2007-10-23 13:00:01.912 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop,<br>2007-10-23 13:00:01.915 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop,<br>2007-10-23 13:00:01.917 New DB scheduler connection
<br>2007-10-23 13:00:01.920 Connected to database 'mythconverg' at host: localhost<br>2007-10-23 13:00:02.120 0 replacements made<br>2007-10-23 13:00:02.124 Marking generic episodes.<br>2007-10-23 13:00:03.341
Main::Registering HttpStatus Extension<br>2007-10-23 13:00:04.981 Found 10673<br>2007-10-23 13:00:07.753 Marking repeats.<br>2007-10-23 13:00:07.741 mythbackend version: 0.21.20070910-2 <a href="http://www.mythtv.org/" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">
www.mythtv.org</a><br>2007-10-23 13:00:08.372 Enabled verbose msgs: important general file record channel<br>2007-10-23 13:00:11.904 AutoExpire: CalcParams()<br>2007-10-23 13:00:04.976 Reschedule requested for id -1.<br>
2007-10-23 13:00:
15.492 Found 18463<br>2007-10-23 13:00:14.965 Cardid 1: max bitrate 73828 KB/min<br>2007-10-23 13:00:21.501 Unmarking new episode rebroadcast repeats.<br>2007-10-23 13:00:21.526 Cardid 2: max bitrate 142089 KB/min<br>
2007-10-23 13:00:21.530 Maximal bitrate of connected encoders is 215917 KB/min<br>--- GetFilesystemInfos directory list start ---<br>Dir: mythbox:/video<br> Location: Local<br> Drive ID: 0<br> TotalKB : 110650276
<br> UsedKB : 65010856<br> FreeKB : 45639420<br><br>--- GetFilesystemInfos directory list end ---<br>2007-10-23 13:00:21.607 fsID #0: Total: 105.5 GB Used: 62.0 GB Free: 43.5 GB<br>2007-10-23 13:00:
21.634
Checking Hosts that use this filesystem.<br>2007-10-23 13:00:21.641 mythbox:/video<br>2007-10-23 13:00:21.644 Cardid 1: max bitrate 9843 Kb/sec, fsID max is now 73828 KB/min<br>2007-10-23 13:00:21.654 Cardid 2: max bitrate 18945 Kb/sec, fsID max is now 215917 KB/min
<br>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<br>2007-10-23 13:00:21.691 AutoExpire: Found max recording rate of 210 MB/min<br>2007-10-23 13:00:
21.693 AutoExpire: CalcParams(): Required Free Space: 2.0 GB w/freq: 5 min<br>2007-10-23 13:00:21.696 SG(): CheckAllStorageGroupDirs(): Checking All Storage Group directories<br>2007-10-23 13:00:21.698 SG(Default): Checking directory '/video/' in group 'Default'.
<br>2007-10-23 13:00:21.794 Scheduled 282 items in 16.8 = 15.96 match + 0.86 place<br>2007-10-23 13:00:21.803 AUTO-Startup assumed<br>2007-10-23 13:00:21.805 TVRec(2): StartRecording(English Premier League Soccer)<br>2007-10-23 13:00:
21.806 TVRec(2): ClearFlags(AskAllowRecording,) -> RunMainLoop,<br>2007-10-23 13:00:21.901 ProgramInfo: StartedRecording: Recording to '/video/2146_20071023130000.mpg'<br>2007-10-23 13:00:21.932 AutoExpire: Adding Programs to 'Do Not Expire' List
<br>2007-10-23 13:00:21.999 2146 @ 2007-10-23T13:00:00 in use by recorder on mythbox<br>2007-10-23 13:00:22.001 AutoExpire: ExpireLiveTV(10000)<br>2007-10-23 13:00:22.001 AutoExpire: FillDBOrdered: Adding Short LiveTV programs in starttime order
<br>2007-10-23 13:00:22.002 AutoExpire: SendDeleteMessages. Nothing to expire.<br>2007-10-23 13:00:22.933 Found 0<br>2007-10-23 13:00:23.066 TVRec(2): StartedRecording(0xaec0b128) fn(/video/2146_20071023130000.mpg)<br>
2007-10-23 13:00:44.111 TVRec(2): ClearFlags(CancelNextRecording,) -> RunMainLoop,<br>2007-10-23 13:00:25.169 Marking episode first showings.</div>