Problem with TV-Server not starting scheduled recordings (1 Viewer)

[metalla_nz]

My scheduled recordings havent been starting in the last few days... Couldnt work out why. But then found repeated instances of this in my logs

2008-12-15 12:58:59.843750 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 12/15/2008 12:58:59 PM-12/15/2008 1:00:00 PM The Daily Show
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: find free card for channel C4
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: got 1 tuning details for C4
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: channel #1 DVBT:tv:MediaWorks C4 Freq:690000 ONID:8746 TSID:29 SID:1301 PMT:0x12D FTA:True LCN:4 BandWidth:8
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: card:1 typevbT is available priority:3 #users:0 same transponder:False
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: card:2 typevbT is available priority:2 #users:0 same transponder:False
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: card:3 type:RadioWebStream cannot tune to channel
2008-12-15 12:58:59.843750 [Scheduler timer]: Controller: found 2 available
2008-12-15 12:58:59.843750 [Scheduler timer]: Scheduler : record on free card:1 priority:3
2008-12-15 12:58:59.859375 [Scheduler timer]: Scheduler : record, first tune to channel
2008-12-15 12:58:59.859375 [11]: card: Tune 1 to C4
2008-12-15 12:58:59.859375 [11]: card: user: scheduler1:1:-1 tune DVBT:tv:MediaWorks C4 Freq:690000 ONID:8746 TSID:29 SID:1301 PMT:0x12D FTA:True LCN:4 BandWidth:8
2008-12-15 12:58:59.859375 [11]: card: to different transponder
2008-12-15 12:58:59.859375 [11]: dvbt: TuneVBT:tv:MediaWorks C4 Freq:690000 ONID:8746 TSID:29 SID:1301 PMT:0x12D FTA:True LCN:4 BandWidth:8
2008-12-15 12:58:59.859375 [11]: dvbt: tune: Assigning oldChannel
2008-12-15 12:58:59.859375 [11]: dvbt: tune: Current Channel != null DVBT:tv:TVNZ TV2 Freq:674000 ONID:8746 TSID:28 SID:1201 PMT:0x6F FTA:True LCN:2 BandWidth:8
2008-12-15 12:58:59.859375 [11]: dvbt: tune: Graph is tunning
2008-12-15 12:58:59.859375 [11]: dvbt: tune: Getting default locator
2008-12-15 12:58:59.859375 [11]: dvbt: tune: Putting bandwidth 8
2008-12-15 12:58:59.859375 [11]: dvbt: tune: put_ONID 8746
2008-12-15 12:58:59.875000 [11]: dvbt: tune: put_SID 1301
2008-12-15 12:58:59.875000 [11]: dvbt: tune: put_TSID 29
2008-12-15 12:58:59.875000 [11]: dvbt: tune: Carrier frequency 690000
2008-12-15 12:58:59.875000 [11]: dvbt: tune: put_Locator
2008-12-15 12:58:59.875000 [11]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:MediaWorks C4 Freq:690000 ONID:8746 TSID:29 SID:1301 PMT:0x12D FTA:True LCN:4 BandWidth:8
2008-12-15 12:58:59.875000 [11]: dvbt: tune: Submitting tune request: System.__ComObject
2008-12-15 12:58:59.875000 [11]: dvbubmiting tunerequest Channel:C4 subChannel:-1
2008-12-15 12:58:59.875000 [11]: dvb:Getting new subchannel
2008-12-15 12:58:59.875000 [11]: dvb:GetNewSubChannel:966 #966
2008-12-15 12:58:59.875000 [11]: dvbubmit tunerequest size:967 new:966
2008-12-15 12:58:59.875000 [11]: subch:966 OnBeforeTune
2008-12-15 12:58:59.875000 [11]: dvbubmit tunerequest calling put_TuneRequest
2008-12-15 12:59:00.125000 [11]: dvbubmit tunerequest done calling put_TuneRequest
2008-12-15 12:59:00.125000 [11]: subch:966 OnAfterTune
2008-12-15 12:59:00.125000 [11]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 12:59:00.125000 [11]: dvbt: tune: SubChannel 966
2008-12-15 12:59:00.140625 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.171875 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.218750 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.250000 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.281250 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:02.171875 [11]: dvb: LockedInOnSignal could not lock onto channel - no signal or bad signal

Which doesnt make sense as I have been able to watch tv throughout that time.

So i started playing an dtesting and couldnt get anything scheduled to record. If i was watching something and hit record no problem.


And then it started to record a scheduled program.

I want to know why it wasnt working, why it started, and how to prevent in the future... Here is the log section from where the recording was failing to when it started to record.

2008-12-15 13:59:49.500000 [24]: dvb: LockedInOnSignal could not lock onto channel - no signal or bad signal
2008-12-15 13:59:51.796875 [14]: Controller: StopTimeShifting 1
2008-12-15 13:59:51.812500 [14]: card: StopTimeShifting user:MediaPC sub:0
2008-12-15 13:59:51.812500 [14]: card: StopTimeShifting user:MediaPC sub:0
2008-12-15 13:59:51.812500 [14]: card not IDLE - freeing subch: 0
2008-12-15 13:59:51.812500 [14]: subch:0-0 tswriter StopTimeshifting...
2008-12-15 13:59:51.828125 [14]: tvcard:FreeSubChannel:1 #0 keep graph=False
2008-12-15 13:59:51.828125 [14]: DVB subch:0 Decompose()
2008-12-15 13:59:51.828125 [14]: FreeSubChannel CA: freeing sub channel : 0
2008-12-15 13:59:51.828125 [14]: tvcard:FreeAllSubChannels:
2008-12-15 13:59:51.828125 [14]: dvbtopGraph
2008-12-15 13:59:51.843750 [14]: user:MediaPC remove
2008-12-15 13:59:51.843750 [14]: Controller:Timeshifting stopped on card:1
2008-12-15 13:59:51.843750 [14]: RTSP: remove stream stream1.0
2008-12-15 13:59:54.703125 [14]: Controller: StartTimeShifting TV3 28
2008-12-15 13:59:54.703125 [14]: Controller: find free card for channel TV3
2008-12-15 13:59:54.703125 [14]: Controller: got 1 tuning details for TV3
2008-12-15 13:59:54.703125 [14]: Controller: channel #1 DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 13:59:54.703125 [14]: Controller: card:1 typevbT is available priority:3 #users:0 same transponder:False
2008-12-15 13:59:54.703125 [14]: Controller: card:2 typevbT is tuned to same transponder no CA present
2008-12-15 13:59:54.703125 [14]: Controller: card:2 typevbT is available priority:2 #users:0 same transponder:True
2008-12-15 13:59:54.718750 [14]: Controller: card:3 type:RadioWebStream cannot tune to channel
2008-12-15 13:59:54.718750 [14]: Controller: found 2 available
2008-12-15 13:59:54.718750 [14]: card: CardTune 1 TV3 setuptv:1:-1
2008-12-15 13:59:54.718750 [14]: card: Tune 1 to TV3
2008-12-15 13:59:54.718750 [14]: card: user: setuptv:1:-1 tune DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 13:59:54.718750 [14]: dvbt: TuneVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Assigning oldChannel
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Current channel is null
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Graph is tunning
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Getting default locator
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Putting bandwidth 8
2008-12-15 13:59:54.718750 [14]: dvbt: tune: put_ONID 8746
2008-12-15 13:59:54.718750 [14]: dvbt: tune: put_SID 1300
2008-12-15 13:59:54.718750 [14]: dvbt: tune: put_TSID 29
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Carrier frequency 690000
2008-12-15 13:59:54.718750 [14]: dvbt: tune: put_Locator
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 13:59:54.718750 [14]: dvbt: tune: Submitting tune request: System.__ComObject
2008-12-15 13:59:54.718750 [14]: dvbubmiting tunerequest Channel:TV3 subChannel:-1
2008-12-15 13:59:54.718750 [14]: dvb:Getting new subchannel
2008-12-15 13:59:54.718750 [14]: dvb:GetNewSubChannel:0 #0
2008-12-15 13:59:54.718750 [14]: dvbubmit tunerequest size:1 new:0
2008-12-15 13:59:54.718750 [14]: subch:0 OnBeforeTune
2008-12-15 13:59:54.734375 [14]: dvbubmit tunerequest calling put_TuneRequest
2008-12-15 13:59:54.984375 [14]: dvbubmit tunerequest done calling put_TuneRequest
2008-12-15 13:59:54.984375 [14]: subch:0 OnAfterTune
2008-12-15 13:59:54.984375 [14]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 13:59:54.984375 [14]: dvbt: tune: SubChannel 0
2008-12-15 13:59:54.984375 [14]: subch:0 OnGraphStart
2008-12-15 13:59:54.984375 [14]: subch:0 Graph already running - skip WaitForPMT
2008-12-15 13:59:54.984375 [14]: dvb: RunGraph
2008-12-15 13:59:55.625000 [14]: dvb: LockedInOnSignal ok
2008-12-15 13:59:55.625000 [14]: subch:0 OnGraphStarted
2008-12-15 13:59:55.625000 [14]: subch:0 SetupPmtGrabberid 12C sid:514
2008-12-15 13:59:55.625000 [14]: subch:0 set pmt grabber pmt:12C sid:514
2008-12-15 13:59:55.625000 [14]: WaitForPMT: Waiting for PMT.
2008-12-15 13:59:55.656250 [18]: subch:0 OnPMTReceived() True
2008-12-15 13:59:55.656250 [14]: WaitForPMT: Found PMT after 0.03125 seconds.
2008-12-15 13:59:55.656250 [14]: subch:0 SendPmt:514 514 FFFFFFFF E
2008-12-15 13:59:55.656250 [14]: Decode pmt
2008-12-15 13:59:55.656250 [14]: Set descriptor data with length 3
2008-12-15 13:59:55.656250 [14]: Set descriptor data with length 3
2008-12-15 13:59:55.656250 [14]: PROBLEM : descriptor lengths dont match 6 9
2008-12-15 13:59:55.656250 [14]: Set descriptor data with length 3
2008-12-15 13:59:55.656250 [14]: PROBLEM : descriptor lengths dont match 6 9
2008-12-15 13:59:55.656250 [14]: Set descriptor data with length 3
2008-12-15 13:59:55.656250 [14]: subch:0 SendPMT version:14 len:116 1300
2008-12-15 13:59:55.656250 [14]: subch:0 cam flags:True
2008-12-15 13:59:55.656250 [14]: subch:0 SetMpegPidMapping
2008-12-15 13:59:55.656250 [14]: subch:0 pid:0 pcr
2008-12-15 13:59:55.656250 [14]: subch:0 pid:12C pmt
2008-12-15 13:59:55.656250 [14]: subch:0 pid:1C2 video type:H.264
2008-12-15 13:59:55.656250 [14]: subch:0 map pid:1C2 video type:H.264
2008-12-15 13:59:55.656250 [14]: subch:0 pid:190 audio lang: type:LATM AAC
2008-12-15 13:59:55.656250 [14]: subch:0 map pid:190 audio lang: type:LATM AAC
2008-12-15 13:59:55.656250 [14]: subch:0 pid:19A audio lang: type:AC3
2008-12-15 13:59:55.656250 [14]: subch:0 map pid:19A audio lang: type:AC3
2008-12-15 13:59:55.656250 [14]: subch:0 pid:3EB type:B
2008-12-15 13:59:55.656250 [14]: subch:0 stop tif
2008-12-15 13:59:55.656250 [14]: WaitForPMT: sending PMT to CAM took 0 seconds.
2008-12-15 13:59:55.656250 [14]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 13:59:56.062500 [14]: card: Tuner locked: True
2008-12-15 13:59:56.062500 [14]: **************************************************
2008-12-15 13:59:56.062500 [14]: ***** SIGNAL LEVEL: 53, SIGNAL QUALITY: 10 *****
2008-12-15 13:59:56.062500 [14]: **************************************************
2008-12-15 13:59:56.062500 [14]: card: tuned user: setuptv subchannel: 0
2008-12-15 13:59:56.062500 [14]: user:setuptv add
2008-12-15 13:59:56.062500 [14]: card2:setuptv 1 0
2008-12-15 13:59:56.062500 [14]: Controller: setuptv 1 0
2008-12-15 13:59:56.062500 [14]: control2:setuptv 1 0
2008-12-15 13:59:56.062500 [14]: Controller: delete timeshift files C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts
2008-12-15 13:59:56.078125 [14]: card: StartTimeShifting 1 C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts
2008-12-15 13:59:56.078125 [14]: card: CAM enabled : False
2008-12-15 13:59:56.078125 [14]: subch:0 SetTimeShiftFileName:C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts
2008-12-15 13:59:56.078125 [14]: Set video / audio observer
2008-12-15 13:59:56.078125 [14]: subch:0 SetTimeShiftFileName fill in pids
2008-12-15 13:59:56.078125 [14]: subch:0-0 tswriter StartTimeshifting...
2008-12-15 13:59:56.078125 [14]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo
2008-12-15 13:59:56.093750 [18]: PID seen - type = Video
2008-12-15 13:59:56.093750 [18]: audioVideoEventHandler Video
2008-12-15 13:59:56.171875 [18]: PID seen - type = Audio
2008-12-15 13:59:56.171875 [18]: audioVideoEventHandler Audio
2008-12-15 13:59:56.171875 [14]: card: WaitForTimeShiftFile - video and audio are seen after 0.09375 seconds
2008-12-15 13:59:56.250000 [18]: PID seen - type = Audio
2008-12-15 13:59:56.250000 [18]: audioVideoEventHandler Audio
2008-12-15 13:59:56.375000 [14]: user:setuptv card:1 sub:0 add stream:C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts.tsbuffer
2008-12-15 13:59:56.375000 [14]: RTSP: add stream stream1.0 file:C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts.tsbuffer
2008-12-15 13:59:56.375000 [14]: Controller: StartTimeShifting started on card:1 to C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-0.ts.tsbuffer
2008-12-15 13:59:58.281250 [PowerEventThread]: TV service PowerEventThread 49371
2008-12-15 13:59:58.281250 [PowerEventThread]: TV service PowerEventThread 49371
2008-12-15 14:00:05.140625 [Scheduler timer]: Recording Windfall added in _recordingsInProgressList
2008-12-15 14:00:05.140625 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 12/15/2008 2:00:05 PM-12/15/2008 3:00:00 PM Windfall
2008-12-15 14:00:05.140625 [Scheduler timer]: Controller: find free card for channel TV3
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: got 1 tuning details for TV3
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: channel #1 DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: card:1 typevbT is tuned to same transponder no CA present
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: card:1 typevbT is available priority:103 #users:1 same transponder:True
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: card:2 typevbT is tuned to same transponder no CA present
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: card:2 typevbT is available priority:2 #users:0 same transponder:True
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: card:3 type:RadioWebStream cannot tune to channel
2008-12-15 14:00:05.156250 [Scheduler timer]: Controller: found 2 available
2008-12-15 14:00:05.156250 [Scheduler timer]: Scheduler : record on recommended card:1 priority:3
2008-12-15 14:00:05.156250 [Scheduler timer]: Scheduler : record, first tune to channel
2008-12-15 14:00:05.156250 [24]: card: Tune 1 to TV3
2008-12-15 14:00:05.156250 [24]: card: user: scheduler51:1:-1 tune DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 14:00:05.171875 [24]: dvbt: TuneVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Assigning oldChannel
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Current Channel != null DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:False LCN:3 BandWidth:8
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Graph is tunning
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Getting default locator
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Putting bandwidth 8
2008-12-15 14:00:05.171875 [24]: dvbt: tune: put_ONID 8746
2008-12-15 14:00:05.171875 [24]: dvbt: tune: put_SID 1300
2008-12-15 14:00:05.171875 [24]: dvbt: tune: put_TSID 29
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Carrier frequency 690000
2008-12-15 14:00:05.171875 [24]: dvbt: tune: put_Locator
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:MediaWorks TV3 Freq:690000 ONID:8746 TSID:29 SID:1300 PMT:0x12C FTA:True LCN:3 BandWidth:8
2008-12-15 14:00:05.171875 [24]: dvbt: tune: Submitting tune request: System.__ComObject
2008-12-15 14:00:05.171875 [24]: dvbubmiting tunerequest Channel:TV3 subChannel:-1
2008-12-15 14:00:05.171875 [24]: dvb:Getting new subchannel
2008-12-15 14:00:05.171875 [24]: dvb:GetNewSubChannel:1 #1
2008-12-15 14:00:05.171875 [24]: dvbubmit tunerequest size:2 new:1
2008-12-15 14:00:05.171875 [24]: subch:1 OnBeforeTune
2008-12-15 14:00:05.171875 [24]: dvbubmit tunerequest calling put_TuneRequest
2008-12-15 14:00:05.421875 [24]: dvbubmit tunerequest done calling put_TuneRequest
2008-12-15 14:00:05.421875 [24]: subch:1 OnAfterTune
2008-12-15 14:00:05.421875 [24]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 14:00:05.421875 [24]: dvbt: tune: SubChannel 1
2008-12-15 14:00:05.453125 [24]: dvb: LockedInOnSignal ok
2008-12-15 14:00:05.453125 [24]: subch:1 OnGraphStart
2008-12-15 14:00:05.453125 [24]: subch:1 SetupPmtGrabberid 12C sid:514
2008-12-15 14:00:05.453125 [24]: subch:1 set pmt grabber pmt:12C sid:514
2008-12-15 14:00:05.453125 [24]: WaitForPMT: Waiting for PMT.
2008-12-15 14:00:05.531250 [18]: subch:1 OnPMTReceived() True
2008-12-15 14:00:05.531250 [24]: WaitForPMT: Found PMT after 0.078125 seconds.
2008-12-15 14:00:05.531250 [24]: subch:1 SendPmt:514 514 FFFFFFFF E
2008-12-15 14:00:05.531250 [24]: Decode pmt
2008-12-15 14:00:05.531250 [24]: Set descriptor data with length 3
2008-12-15 14:00:05.531250 [24]: Set descriptor data with length 3
2008-12-15 14:00:05.531250 [24]: PROBLEM : descriptor lengths dont match 6 9
2008-12-15 14:00:05.531250 [24]: Set descriptor data with length 3
2008-12-15 14:00:05.531250 [24]: PROBLEM : descriptor lengths dont match 6 9
2008-12-15 14:00:05.531250 [24]: Set descriptor data with length 3
2008-12-15 14:00:05.531250 [24]: subch:1 SendPMT version:14 len:116 1300
2008-12-15 14:00:05.531250 [24]: subch:1 cam flags:True
2008-12-15 14:00:05.531250 [24]: subch:1 SetMpegPidMapping
2008-12-15 14:00:05.531250 [24]: subch:1 pid:0 pcr
2008-12-15 14:00:05.531250 [24]: subch:1 pid:12C pmt
2008-12-15 14:00:05.531250 [24]: subch:1 pid:1C2 video type:H.264
2008-12-15 14:00:05.531250 [24]: subch:1 map pid:1C2 video type:H.264
2008-12-15 14:00:05.531250 [24]: subch:1 pid:190 audio lang: type:LATM AAC
2008-12-15 14:00:05.531250 [24]: subch:1 map pid:190 audio lang: type:LATM AAC
2008-12-15 14:00:05.531250 [24]: subch:1 pid:19A audio lang: type:AC3
2008-12-15 14:00:05.531250 [24]: subch:1 map pid:19A audio lang: type:AC3
2008-12-15 14:00:05.531250 [24]: subch:1 pid:3EB type:B
2008-12-15 14:00:05.531250 [24]: subch:1 stop tif
2008-12-15 14:00:05.531250 [24]: WaitForPMT: sending PMT to CAM took 0 seconds.
2008-12-15 14:00:05.531250 [24]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 14:00:05.609375 [24]: card: Tuner locked: True
2008-12-15 14:00:05.609375 [24]: **************************************************
2008-12-15 14:00:05.609375 [24]: ***** SIGNAL LEVEL: 53, SIGNAL QUALITY: 100 *****
2008-12-15 14:00:05.609375 [24]: **************************************************
2008-12-15 14:00:05.609375 [24]: card: tuned user: scheduler51 subchannel: 1
2008-12-15 14:00:05.609375 [24]: user:scheduler51 add
2008-12-15 14:00:05.609375 [Scheduler timer]: Scheduler : record to C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\recordings\Windfall - TV3 - 2008-12-15.mpg
2008-12-15 14:00:05.609375 [24]: card: StartRecording 1 C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\recordings\Windfall - TV3 - 2008-12-15.ts
2008-12-15 14:00:05.609375 [24]: StartRecording to C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\recordings\Windfall - TV3 - 2008-12-15.ts
2008-12-15 14:00:05.609375 [24]: subch:1 StartRecord(C:\Documents and Settings\All Users\Application Data\Team MediaPortal\MediaPortal TV Server\recordings\Windfall - TV3 - 2008-12-15.ts)
2008-12-15 14:00:05.609375 [24]: subch:1 record transport stream mode
2008-12-15 14:00:05.609375 [24]: subch:1-1 tswriter StartRecording...
2008-12-15 14:00:05.609375 [24]: SetRecorderPids
2008-12-15 14:00:05.609375 [24]: DVB subch:1 Started recording

 

[kellizer2]

I have the same problem - do you have a fix for the above? What did you do?

Many Thanks,

Ian.

 

[metalla_nz]

I did a bunch of things which has essentially solved this for me...

1) Replace aerial cable from wall socket to tuner card, mine may have been a little dodgy and had to be in exactly the right position

2) Change from core avc to cyberlink Power dvd 8 decoder

3) Reset the machine every now and again

 

[geekiegeek]

So are you saying that the reason it didnt record is that the signal was to low at the time? I have a similar issue where recordings are starting late - sometimes 10mins sometimes half an hour. But I can watch liveTv at any time?

 

[metalla_nz]

Do your logs indicate an issue with signal strength?

Youll be seeing this

008-12-15 12:59:00.125000 [11]: subch:966 OnAfterTune
2008-12-15 12:59:00.125000 [11]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2008-12-15 12:59:00.125000 [11]: dvbt: tune: SubChannel 966
2008-12-15 12:59:00.140625 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.171875 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.218750 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.250000 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:00.281250 [11]: dvb: LockedInOnSignal waiting 20ms
2008-12-15 12:59:02.171875 [11]: dvb: LockedInOnSignal could not lock onto channel - no signal or bad signal

If its a reception issue.... And yeah mine came right some times.

If your using a thinner rg59 sort of cable to carry signal to the tuner card there might be issues, i changed to an RG6 one, havent had problems since.

If its not showing reception issues in the log, double check the computers time, if your using a hauppauge card theres a setting to delay MP's finding of those cards (t-500 especially) so it has time to wake up.

Is there a particular time it happes, for example is it usually when its recording after being in sleep or standby.. If so try powerscheduler, or MPStandbyHandler

 

[Dodgy Bob]

Have exactly the same issue. Watching TV fine, recordings don't work, have those messages in the log.

 

[metalla_nz]

Using DVB-T?

Which h.264 codec are you using?

Check aerial cable

 

[Dodgy Bob]

Using only DVB-T, not watching any HD. Aerial is fine as I can watch live TV no problem.

 

[Paranoid Delusion]

Using only DVB-T, not watching any HD. Aerial is fine as I can watch live TV no problem.

Can you try something, not sure where you are timeshifting too, but a user came up with this for dual\multiple cards.

Make sure you have seperate timeshifting folders for each TV card installed, just create a new folder called something like timeshift2 and point your second tuner to it in TVServer config\recording Settings recorded folders.

stop and restart service under manual control.

This solved his not recording issue, please give your feedback on this one too.

 

[metalla_nz]

I could watch live tv fine as well, but it was the aerial cable, as since replacing ive had no problems, made no sense to me either