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

[Dodgy Bob]

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.

Left one of them on the default recording and timeshift directories (on C: ) changed the other to directories on D:, stopped/started the TV Service. No difference, Family Guy on BBC3 still didn't record. Can still watch BBC3 fine though.

After making the change I set-up a recording that would happen within 10 mins on a different channel (can't remember right now) and that did work. I will do some more tests but tbh, this problem added to my other problem (TV Service taking 90+% CPU and loads of memory) would indicate a possible installation/TV card issue. I'm gearing up to do a complete re-install (of MP not Vista yet).

 

[Dodgy Bob]

Did a clean uninstall of MP and TV, rebooted PC, re-installed 1.0.0.0 and added latest SVN.

Tested a scheduled recording on BBC 1, worked OK. Re-scheduled Family Guy on BBC 3, it didn't work. Possibly due to weak signal on that channel (even though I can watch live TV fine)?

Have scheduled a recording on BBC 1 for later today.

 

[Dodgy Bob]

Got in from work and checked the recording, it hadn't worked. The logs show that it couldn't lock onto the channel (BBC 1) yet when I started MP and selected BBC 1 it worked fine.

2009-03-27 15:01:46.153493 [Scheduler timer]: Recording Diagnosis Murder  added in _recordingsInProgressList
2009-03-27 15:01:46.154493 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 27/03/2009 15:01:46-27/03/2009 15:00:00 Diagnosis Murder
2009-03-27 15:01:46.154493 [Scheduler timer]: Controller: find free card for channel BBC ONE Wales
2009-03-27 15:01:46.157493 [Scheduler timer]: Controller:   got 1 tuning details for BBC ONE Wales
2009-03-27 15:01:46.158493 [Scheduler timer]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8 
2009-03-27 15:01:46.164493 [Scheduler timer]: Controller:    card:1 type:DvbT is tuned to same transponder no CA present
2009-03-27 15:01:46.165493 [Scheduler timer]: Controller:    card:1 type:DvbT is available priority:7 #users:0 same transponder:True
2009-03-27 15:01:46.168493 [Scheduler timer]: Controller:    card:2 type:DvbT is available priority:6 #users:0 same transponder:False
2009-03-27 15:01:46.169493 [Scheduler timer]: Controller:    card:3 type:DvbS is disabled
2009-03-27 15:01:46.169493 [Scheduler timer]: Controller:    card:4 type:DvbS is disabled
2009-03-27 15:01:46.170493 [Scheduler timer]: Controller:    card:5 type:Analog is disabled
2009-03-27 15:01:46.170493 [Scheduler timer]: Controller:    card:6 type:Analog is disabled
2009-03-27 15:01:46.171493 [Scheduler timer]: Controller:    card:7 type:RadioWebStream is disabled
2009-03-27 15:01:46.172493 [Scheduler timer]: Controller: found 2 available
2009-03-27 15:01:46.173493 [Scheduler timer]: Scheduler : record on free card:1 priority:7
2009-03-27 15:01:46.174493 [Scheduler timer]: Scheduler : record, first tune to channel
2009-03-27 15:01:46.176493 [18]: card: Tune 1 to BBC ONE Wales
2009-03-27 15:01:46.177493 [18]: card: user: scheduler5:1:-1 tune DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 15:01:46.189493 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 15:01:46.190493 [18]: dvbt: tune: Assigning oldChannel
2009-03-27 15:01:46.190493 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 15:01:46.191493 [18]: dvbt: tune: Graph is tunning
2009-03-27 15:01:46.191493 [18]: dvbt: tune: Getting default locator
2009-03-27 15:01:46.192493 [18]: dvbt: tune: Putting bandwidth 8
2009-03-27 15:01:46.192493 [18]: dvbt: tune: put_ONID 9018
2009-03-27 15:01:46.192493 [18]: dvbt: tune: put_SID 4222
2009-03-27 15:01:46.193493 [18]: dvbt: tune: put_TSID 4158
2009-03-27 15:01:46.193493 [18]: dvbt: tune: Carrier frequency 738000
2009-03-27 15:01:46.194493 [18]: dvbt: tune: put_Locator
2009-03-27 15:01:46.194493 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 15:01:46.196493 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2009-03-27 15:01:46.196493 [18]: dvb:Submiting tunerequest Channel:BBC ONE Wales subChannel:-1 
2009-03-27 15:01:46.197493 [18]: dvb:Getting new subchannel
2009-03-27 15:01:46.197493 [18]: dvb:GetNewSubChannel:157 #157
2009-03-27 15:01:46.198493 [18]: TvDvbChannel ctor new subchIndex:157
2009-03-27 15:01:46.199493 [18]: dvb:Submit tunerequest size:158 new:157
2009-03-27 15:01:46.199493 [18]: subch:157 OnBeforeTune
2009-03-27 15:01:46.200493 [18]: dvb:Submit tunerequest calling put_TuneRequest
2009-03-27 15:01:46.951493 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2009-03-27 15:01:46.952493 [18]: subch:157 OnAfterTune
2009-03-27 15:01:46.952493 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2009-03-27 15:01:46.953493 [18]: dvbt: tune: SubChannel 157
2009-03-27 15:01:46.956493 [18]: dvb:  LockedInOnSignal waiting 20ms
2009-03-27 15:01:46.977493 [18]: dvb:  LockedInOnSignal waiting 20ms
2009-03-27 15:01:46.998493 [18]: dvb:  LockedInOnSignal waiting 20ms
2009-03-27 15:01:47.019493 [18]: dvb:  LockedInOnSignal waiting 20ms
2009-03-27 15:01:47.040493 [18]: dvb:  LockedInOnSignal waiting 20ms
2009-03-27 15:01:48.974493 [18]: dvb:  LockedInOnSignal could not lock onto channel - no signal or bad signal
2009-03-27 17:15:52.402493 [18]: Controller: StartTimeShifting BBC ONE Wales 76
2009-03-27 17:15:52.405493 [18]: Controller: find free card for channel BBC ONE Wales
2009-03-27 17:15:52.407493 [18]: Controller:   got 1 tuning details for BBC ONE Wales
2009-03-27 17:15:52.407493 [18]: Controller:   channel #1 DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8 
2009-03-27 17:15:52.411493 [18]: Controller:    card:1 type:DvbT is tuned to same transponder no CA present
2009-03-27 17:15:52.413493 [18]: Controller:    card:1 type:DvbT is available priority:7 #users:0 same transponder:True
2009-03-27 17:15:52.419493 [18]: Controller:    card:2 type:DvbT is available priority:6 #users:0 same transponder:False
2009-03-27 17:15:52.420493 [18]: Controller:    card:3 type:DvbS is disabled
2009-03-27 17:15:52.420493 [18]: Controller:    card:4 type:DvbS is disabled
2009-03-27 17:15:52.421493 [18]: Controller:    card:5 type:Analog is disabled
2009-03-27 17:15:52.421493 [18]: Controller:    card:6 type:Analog is disabled
2009-03-27 17:15:52.422493 [18]: Controller:    card:7 type:RadioWebStream is disabled
2009-03-27 17:15:52.422493 [18]: Controller: found 2 available
2009-03-27 17:15:52.428493 [18]: card: CardTune 1 BBC ONE Wales HTPC:1:-1
2009-03-27 17:15:52.430493 [18]: card: Tune 1 to BBC ONE Wales
2009-03-27 17:15:52.431493 [18]: card: user: HTPC:1:-1 tune DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 17:15:52.444493 [18]: dvbt:  Tune:DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 17:15:52.444493 [18]: dvbt: tune: Assigning oldChannel
2009-03-27 17:15:52.445493 [18]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 17:15:52.445493 [18]: dvbt: tune: Graph is tunning
2009-03-27 17:15:52.446493 [18]: dvbt: tune: Getting default locator
2009-03-27 17:15:52.446493 [18]: dvbt: tune: Putting bandwidth 8
2009-03-27 17:15:52.447493 [18]: dvbt: tune: put_ONID 9018
2009-03-27 17:15:52.447493 [18]: dvbt: tune: put_SID 4222
2009-03-27 17:15:52.447493 [18]: dvbt: tune: put_TSID 4158
2009-03-27 17:15:52.448493 [18]: dvbt: tune: Carrier frequency 738000
2009-03-27 17:15:52.448493 [18]: dvbt: tune: put_Locator
2009-03-27 17:15:52.449493 [18]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC ONE Wales Freq:738000 ONID:9018 TSID:4158 SID:4222 PMT:0x107E FTA:True LCN:1 BandWidth:8
2009-03-27 17:15:52.449493 [18]: dvbt: tune: Submitting tune request: System.__ComObject
2009-03-27 17:15:52.450493 [18]: dvb:Submiting tunerequest Channel:BBC ONE Wales subChannel:-1 
2009-03-27 17:15:52.451493 [18]: dvb:Getting new subchannel
2009-03-27 17:15:52.452493 [18]: dvb:GetNewSubChannel:158 #158
2009-03-27 17:15:52.453493 [18]: TvDvbChannel ctor new subchIndex:158
2009-03-27 17:15:52.453493 [18]: dvb:Submit tunerequest size:159 new:158
2009-03-27 17:15:52.454493 [18]: subch:158 OnBeforeTune
2009-03-27 17:15:52.455493 [18]: dvb:Submit tunerequest calling put_TuneRequest
2009-03-27 17:15:52.456493 [18]: dvb:Submit tunerequest done calling put_TuneRequest
2009-03-27 17:15:52.457493 [18]: subch:158 OnAfterTune
2009-03-27 17:15:52.457493 [18]: dvbt: tune: Running graph for channel TvLibrary.Implementations.DVB.TvDvbChannel
2009-03-27 17:15:52.458493 [18]: dvbt: tune: SubChannel 158
2009-03-27 17:15:52.460493 [18]: dvb:  LockedInOnSignal ok
2009-03-27 17:15:52.461493 [18]: subch:158 OnGraphStart
2009-03-27 17:15:52.463493 [18]: subch:158 SetupPmtGrabber:pid 107E sid:107E
2009-03-27 17:15:52.463493 [18]: subch:158 set pmt grabber pmt:107E sid:107E
2009-03-27 17:15:52.465493 [18]: WaitForPMT: Waiting for PMT.
2009-03-27 17:15:52.599493 [11]: subch:158 OnPMTReceived() True
2009-03-27 17:15:52.599493 [18]: WaitForPMT: Found PMT after 0.135 seconds.
2009-03-27 17:15:52.606493 [18]: subch:158 SendPmt:107E 107E FFFFFFFF 1
2009-03-27 17:15:52.606493 [18]: Decode pmt
2009-03-27 17:15:52.607493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.607493 [18]: Set descriptor data with length 6
2009-03-27 17:15:52.608493 [18]: PROBLEM : descriptor lengths dont match 3 9
2009-03-27 17:15:52.608493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.609493 [18]: PROBLEM : descriptor lengths dont match 10 13
2009-03-27 17:15:52.609493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.610493 [18]: PROBLEM : descriptor lengths dont match 6 9
2009-03-27 17:15:52.610493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.610493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.611493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.612493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.612493 [18]: Set descriptor data with length 3
2009-03-27 17:15:52.612493 [18]: subch:158 SendPMT version:1 len:130 4222
2009-03-27 17:15:52.613493 [18]: subch:158 cam flags:True
2009-03-27 17:15:52.613493 [18]: subch:158 SetMpegPidMapping
2009-03-27 17:15:52.614493 [18]: subch:158  pid:C9 pcr
2009-03-27 17:15:52.614493 [18]: subch:158  pid:107E pmt
2009-03-27 17:15:52.615493 [18]: subch:158  pid:C9 video type:MPEG-2
2009-03-27 17:15:52.615493 [18]: subch:158    map pid:C9 video type:MPEG-2
2009-03-27 17:15:52.616493 [18]: subch:158  pid:CA audio lang:eng type:MPEG-1
2009-03-27 17:15:52.616493 [18]: subch:158    map pid:CA audio lang:eng type:MPEG-1
2009-03-27 17:15:52.618493 [18]: subch:158  pid:CD subtitle type:6
2009-03-27 17:15:52.618493 [18]: subch:158    map pid:CD subtitle type:6
2009-03-27 17:15:52.619493 [18]: subch:158  pid:CE audio lang:eng type:MPEG-1
2009-03-27 17:15:52.619493 [18]: subch:158  pid:28A type:B
2009-03-27 17:15:52.620493 [18]: subch:158  pid:28B type:B
2009-03-27 17:15:52.620493 [18]: subch:158  pid:28C type:B
2009-03-27 17:15:52.620493 [18]: subch:158  pid:258 type:B
2009-03-27 17:15:52.621493 [18]: subch:158  pid:259 type:B
2009-03-27 17:15:52.621493 [18]: subch:158  pid:25A type:B
2009-03-27 17:15:52.622493 [18]: subch:158 stop tif
2009-03-27 17:15:52.622493 [18]: WaitForPMT: sending PMT to CAM took 0.023 seconds.
2009-03-27 17:15:52.623493 [18]: dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
2009-03-27 17:15:52.630493 [18]: card: Tuner locked: True
2009-03-27 17:15:52.631493 [18]: **************************************************
2009-03-27 17:15:52.632493 [18]: ***** SIGNAL LEVEL: 0, SIGNAL QUALITY: 70 *****
2009-03-27 17:15:52.632493 [18]: **************************************************
2009-03-27 17:15:52.632493 [18]: card: tuned user: HTPC subchannel: 158
2009-03-27 17:15:52.633493 [18]: user:HTPC add
2009-03-27 17:15:52.634493 [18]: card2:HTPC 1 158
2009-03-27 17:15:52.634493 [18]: Controller: HTPC 1 158
2009-03-27 17:15:52.635493 [18]: control2:HTPC 1 158
2009-03-27 17:15:52.638493 [18]: Controller: delete timeshift files C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-158.ts
2009-03-27 17:15:52.647493 [18]: card: StartTimeShifting 1 C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-158.ts 
2009-03-27 17:15:52.648493 [18]: card: CAM enabled : False
2009-03-27 17:15:52.650493 [18]: subch:158 SetTimeShiftFileName:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-158.ts
2009-03-27 17:15:52.651493 [18]: Set video / audio observer
2009-03-27 17:15:52.665493 [18]: subch:158 SetTimeShiftFileName fill in pids
2009-03-27 17:15:52.670493 [18]: subch:158-158 tswriter StartTimeshifting...
2009-03-27 17:15:52.677493 [18]: card: WaitForTimeShiftFile - waiting _eventAudio & _eventVideo
2009-03-27 17:15:52.769493 [11]: PID seen - type = Video
2009-03-27 17:15:52.771493 [11]: audioVideoEventHandler Video
2009-03-27 17:15:52.772493 [11]: PID seen - type = Audio
2009-03-27 17:15:52.774493 [11]: audioVideoEventHandler Audio
2009-03-27 17:15:52.775493 [18]: card: WaitForTimeShiftFile - video and audio are seen after 0.099 seconds
2009-03-27 17:15:52.780493 [11]: PID seen - type = Audio
2009-03-27 17:15:52.781493 [18]: user:HTPC card:1 sub:158 add stream:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-158.ts.tsbuffer
2009-03-27 17:15:52.783493 [18]: RTSP: start streamer
2009-03-27 17:15:52.791493 [RTSP Streaming thread]: RTSP: Streamer started
2009-03-27 17:15:52.792493 [18]: RTSP: add stream stream1.158 file:C:\ProgramData\Team MediaPortal\MediaPortal TV Server\timeshiftbuffer\live1-158.ts.tsbuffer
2009-03-27 17:15:52.794493 [18]: Controller: StartTimeShifting started on card:1 to C:\ProgramData\Team MediaPortal\MediaPortal TV

 

[Dodgy Bob]

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

I'm having an extension built atm and this weekend had to move the HTPC. Obviously this meant I was using a different aerial feed (actually same aerial just with one less join in it) with a better signal. All now OK.

So it's down to signal strength. Even though live TV is fine it seems recording is a bit more fussy! Weird.