recording tv just stopped :( HELP!!! (2 Viewers)

toplad

Portal Pro
October 30, 2013
58
2
Home Country
United Kingdom United Kingdom
all of a sudden I cant record tv its driving me insane. has this happened to anyone else or does anyone know a fix? ive included the log below but im unsure if its the right one to include. thanks in advance for any help given.
[collapse]
[2013-11-06 19:38:14,920] [Log ] [13 ] [INFO ] - Controller: find free card for channel Pick
[2013-11-06 19:38:14,921] [Log ] [13 ] [INFO ] - Controller: find card for channel Pick
[2013-11-06 19:38:14,922] [Log ] [13 ] [INFO ] - Controller: got 1 tuning details for Pick
[2013-11-06 19:38:14,923] [Log ] [13 ] [INFO ] - Controller: channel #1 DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,923] [Log ] [13 ] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel
[2013-11-06 19:38:14,923] [Log ] [13 ] [INFO ] - Controller: card:2 type:DvbIP cannot tune to channel
[2013-11-06 19:38:14,924] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel
[2013-11-06 19:38:14,925] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT users: 2
[2013-11-06 19:38:14,926] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT can tune to channel
[2013-11-06 19:38:14,926] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT users: 0
[2013-11-06 19:38:14,927] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT can tune to channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT users: 0
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT can tune to channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT users: 0
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: found 4 card(s) for channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 9 msec
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT is available
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT is available
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT is available
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT is not available, tuned to different transponder
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: found 3 free card(s)
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 13 msec
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: TimeShiftingWouldUseCard Pick 69
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: find free card for channel Pick
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: find card for channel Pick
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: got 1 tuning details for Pick
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: channel #1 DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:2 type:DvbIP cannot tune to channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT users: 2
[2013-11-06 19:38:14,928] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT can tune to channel
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT users: 0
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT can tune to channel
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT users: 0
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT can tune to channel
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT users: 0
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: found 4 card(s) for channel
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 9 msec
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT is available
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT is available
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT is available
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT is not available, tuned to different transponder
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: found 3 free card(s)
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 12 msec
[2013-11-06 19:38:14,944] [Log ] [13 ] [DEBUG] - CiMenuSupported called cardid 4
[2013-11-06 19:38:14,944] [Log ] [13 ] [DEBUG] - CiMenuSupported card TBS 6284 DVBT/T2/C Tuner B supported: False
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: StartTimeShifting Pick 69
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: epg stop
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: find free card for channel Pick
[2013-11-06 19:38:14,944] [Log ] [13 ] [INFO ] - Controller: find card for channel Pick
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: got 1 tuning details for Pick
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: channel #1 DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:2 type:DvbIP cannot tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT can tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT can tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT can tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT can tune to channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: found 4 card(s) for channel
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 8 msec
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: found 4 free card(s)
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 9 msec
[2013-11-06 19:38:14,959] [Log ] [13 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=117, tuningdetails=DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,959] [Log ] [13 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=118, tuningdetails=DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,959] [Log ] [13 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=119, tuningdetails=DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,959] [Log ] [13 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=120, tuningdetails=DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,959] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT users: 2
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:3 type:DvbT is not available, tuned to different transponder
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT users: 0
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:4 type:DvbT is available
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT users: 0
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:5 type:DvbT is available
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT users: 0
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: card:6 type:DvbT is available
[2013-11-06 19:38:14,975] [Log ] [13 ] [DEBUG] - CancelCardReservationsExceedingMaxConcurrentTickets: removing exceeding nr of tickets, only 2 allowed at a time but found 4
[2013-11-06 19:38:14,975] [Log ] [13 ] [DEBUG] - CardReservation.CancelCardReservation id=120
[2013-11-06 19:38:14,975] [Log ] [13 ] [DEBUG] - CardReservation.CancelCardReservation id=117
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - Controller: try max 3 of 3 cards for timeshifting
[2013-11-06 19:38:14,975] [Log ] [13 ] [DEBUG] - CardReservationBase: tvcard=4, user=media-server, dbChannel=69, ticket=118, tunestate=Tuning, stopstate=Idle
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - card: CardTune 4 Pick media-server:4:-1
[2013-11-06 19:38:14,975] [Log ] [13 ] [INFO ] - card: Tune on card 4 to subchannel Pick
[2013-11-06 19:38:14,975] [Log ] [13 ] [DEBUG] - card: user: media-server:4:-1 tune DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,991] [Log ] [13 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvbt: Tune:DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:confused:ubmiting tunerequest Channel:pick subChannel:-1
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:Getting new subchannel
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:GetNewSubChannel:0 #0
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - TvDvbChannel ctor new subchIndex:0
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - card: AddTuneEvent card: 4 / subch: 0
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:confused:ubmit tunerequest size:1 new:0
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - subch:0 OnBeforeTune
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:confused:ubmit tunerequest calling put_TuneRequest
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb:confused:ubmit tunerequest done calling put_TuneRequest
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - subch:0 OnAfterTune
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - RunGraph
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - subch:0 OnGraphStart
[2013-11-06 19:38:14,991] [Log ] [13 ] [INFO ] - dvb: RunGraph
[2013-11-06 19:38:15,616] [Log ] [13 ] [INFO ] - dvb: LockInOnSignal ok
[2013-11-06 19:38:15,616] [Log ] [13 ] [INFO ] - subch:0 OnGraphStarted
[2013-11-06 19:38:15,616] [Log ] [13 ] [INFO ] - subch:0 SetupPmtGrabber:pid 2BF sid:56C0
[2013-11-06 19:38:15,616] [Log ] [13 ] [INFO ] - subch:0 set pmt grabber pmt:2BF sid:56C0
[2013-11-06 19:38:15,616] [Log ] [13 ] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time
[2013-11-06 19:38:15,631] [Log ] [13 ] [DEBUG] - WaitForPMT: Waiting for PMT 2BF
[2013-11-06 19:38:15,772] [Log ] [27 ] [INFO ] - subch:0 OnPMTReceived() pmt:2BF ran:True dynamic:False
[2013-11-06 19:38:15,772] [Log ] [13 ] [DEBUG] - WaitForPMT: Found PMT after 0.140628 seconds.
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 SendPmt:56C0 56C0 FFFFFFFF 13
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - Decode pmt
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - Set descriptor data with length 3
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - Set descriptor data with length 3
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - PROBLEM : descriptor lengths dont match 6 9
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - Set descriptor data with length 3
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - PROBLEM : descriptor lengths dont match 6 9
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - Set descriptor data with length 3
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - PROBLEM : descriptor lengths dont match 10 13
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 SendPMT: Channel is FTA, nothing to do.
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 SetMpegPidMapping
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 pid:12D pcr
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 pid:2BF pmt
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 pid:12D video type:MPEG-2
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 map pid:12D video type:MPEG-2
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 pid:12E audio lang:eng type:MPEG-2
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 map pid:12E audio lang:eng type:MPEG-2
[2013-11-06 19:38:15,772] [Log ] [13 ] [INFO ] - subch:0 pid:130 audio lang:eng type:MPEG-2
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - subch:0 pid:12F subtitle type:6
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - subch:0 map pid:12F subtitle type:6
[2013-11-06 19:38:15,788] [Log ] [13 ] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - subch:0 stop tif
[2013-11-06 19:38:15,788] [Log ] [13 ] [DEBUG] - WaitForPmt: PMT handling took 0.0156234 seconds.
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - dvbt: tune: Graph running. Returning TvLibrary.Implementations.DVB.TvDvbChannel
[2013-11-06 19:38:15,788] [Log ] [13 ] [DEBUG] - card: Tuner locked: True
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - **************************************************
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - ***** SIGNAL LEVEL: 51, SIGNAL QUALITY: 62 *****
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - **************************************************
[2013-11-06 19:38:15,788] [Log ] [13 ] [DEBUG] - card: tuned user: media-server subchannel: 0
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - user:media-server add
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - card2:media-server 4 0
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - Controller: delete timeshift files D:\Recorded TV\live4-0.ts
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - Controller: epg stop
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - card: StartTimeShifting 4 D:\Recorded TV\live4-0.ts
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - card: CAM enabled : False
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - subch:0 SetTimeShiftFileName:D:\Recorded TV\live4-0.ts
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - Set video / audio observer
[2013-11-06 19:38:15,788] [Log ] [13 ] [INFO ] - subch:0 SetTimeShiftFileName fill in pids
[2013-11-06 19:38:15,803] [Log ] [13 ] [INFO ] - subch:0-0 tswriter StartTimeshifting...
[2013-11-06 19:38:15,803] [Log ] [13 ] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo
[2013-11-06 19:38:15,881] [Log ] [27 ] [INFO ] - PID seen - type = Video
[2013-11-06 19:38:15,881] [Log ] [27 ] [INFO ] - audioVideoEventHandler Video
[2013-11-06 19:38:15,913] [Log ] [27 ] [INFO ] - PID seen - type = Audio
[2013-11-06 19:38:15,913] [Log ] [27 ] [INFO ] - audioVideoEventHandler Audio
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - card: WaitForFile - video and audio are seen after 0.1093751 seconds
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - user:media-server card:4 sub:0 add stream:D:\Recorded TV\live4-0.ts.tsbuffer
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - Controller: StartTimeShifting started on card:4 to D:\Recorded TV\live4-0.ts.tsbuffer
[2013-11-06 19:38:15,913] [Log ] [13 ] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=118, tuningdetails=DVBT:tv: Pick Freq:770000 ONID:9018 TSID:20544 SID:22208 PMT:0x2BF FTA:True LCN:11 BandWidth:8
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - card: SignalTuneEvent card: 4 / subch: 0
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - Controller: media-server 4 0
[2013-11-06 19:38:15,913] [Log ] [13 ] [DEBUG] - CardReservation.CancelCardReservation id=119
[2013-11-06 19:38:15,913] [Log ] [13 ] [INFO ] - control2:media-server 4 0
[2013-11-06 19:38:15,928] [Log ] [13 ] [DEBUG] - CiMenuSupported called cardid 4
[2013-11-06 19:38:15,928] [Log ] [13 ] [DEBUG] - CiMenuSupported card TBS 6284 DVBT/T2/C Tuner B supported: False
[2013-11-06 19:38:18,711] [Log ] [32 ] [DEBUG] - Controller: DoSetChannelStates for 54 channels
[2013-11-06 19:38:18,725] [Log ] [32 ] [INFO ] - ChannelStates.GetAllTimeshiftingAndRecordingChannels took 3 msec
[2013-11-06 19:38:18,984] [Log ] [32 ] [INFO ] - ChannelStates.DoSetChannelStates took 273 msec
[2013-11-06 19:38:35,092] [Log ] [32 ] [DEBUG] - Deleting invalid recordings
[2013-11-06 19:39:06,689] [Log ] [32 ] [DEBUG] - Deleting invalid recordings
[2013-11-06 19:40:34,014] [Log ] [19 ] [INFO ] - Controller: StopTimeShifting 4
[2013-11-06 19:40:34,015] [Log ] [19 ] [DEBUG] - CardTuner.RequestCardStopReservation: placed reservation with id=121, user=media-server
[2013-11-06 19:40:34,117] [Log ] [19 ] [DEBUG] - CardTuner.Stop: ticket id=121, found=True
[2013-11-06 19:40:34,117] [Log ] [19 ] [INFO ] - Stop cardid=4, ticket=121, tunestate=Idle, stopstate=Stopping
[2013-11-06 19:40:34,117] [Log ] [19 ] [INFO ] - card TBS 6284 DVBT/T2/C Tuner B: StopTimeShifting user:media-server sub:0
[2013-11-06 19:40:34,118] [Log ] [19 ] [DEBUG] - card not IDLE - removing user: media-server
[2013-11-06 19:40:34,119] [Log ] [19 ] [DEBUG] - usermanagement.RemoveUser: media-server, subch: 0 of 1, card: 4
[2013-11-06 19:40:34,119] [Log ] [19 ] [INFO ] - user:media-server remove
[2013-11-06 19:40:34,119] [Log ] [19 ] [INFO ] - subch:0-0 tswriter StopTimeshifting...
[2013-11-06 19:40:34,155] [Log ] [19 ] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0
[2013-11-06 19:40:34,155] [Log ] [19 ] [INFO ] - DVB subch:0 Decompose()
[2013-11-06 19:40:34,158] [Log ] [19 ] [INFO ] - FreeSubChannel CA: freeing sub channel : 0
[2013-11-06 19:40:34,158] [Log ] [19 ] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph
[2013-11-06 19:40:34,159] [Log ] [19 ] [INFO ] - dvb:confused:topGraph called
[2013-11-06 19:40:34,159] [Log ] [19 ] [INFO ] - tvcard:FreeAllSubChannels
[2013-11-06 19:40:34,160] [Log ] [19 ] [INFO ] - dvb:confused:topGraph
[2013-11-06 19:40:34,210] [Log ] [19 ] [INFO ] - dvb:confused:topGraph called
[2013-11-06 19:40:34,210] [Log ] [19 ] [INFO ] - tvcard:FreeAllSubChannels
[2013-11-06 19:40:34,212] [Log ] [19 ] [INFO ] - dvb:confused:topGraph filterstate already stopped, returning.
[2013-11-06 19:40:34,214] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files D:\Recorded TV\live4-0.ts
[2013-11-06 19:40:34,214] [Log ] [19 ] [INFO ] - user:media-server remove
[2013-11-06 19:40:34,214] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete D:\Recorded TV\live4-0.ts.tsbuffer
[2013-11-06 19:40:34,217] [Log ] [19 ] [DEBUG] - CardReservation.RemoveStopTicket: removed STOP reservation with id=121
[2013-11-06 19:40:34,217] [Log ] [19 ] [INFO ] - Controller:Timeshifting stopped on card:4
[2013-11-06 19:40:34,217] [Log ] [19 ] [INFO ] - RTSP: remove stream stream4.0
[2013-11-06 19:40:39,220] [Log ] [TS_File_Cleanup] [INFO ] - card: delete timeshift files D:\Recorded TV\live4-0.ts
[2013-11-06 19:40:39,238] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete D:\Recorded TV\live4-0.ts.tsbuffer
[2013-11-06 19:40:39,243] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file D:\Recorded TV\live4-0.ts.tsbuffer
[2013-11-06 19:40:39,243] [Log ] [TS_File_Cleanup] [INFO ] - card: trying to delete D:\Recorded TV\live4-0.ts.tsbuffer1.ts
[2013-11-06 19:40:39,247] [Log ] [TS_File_Cleanup] [INFO ] - card: deleted file D:\Recorded TV\live4-0.ts.tsbuffer1.ts
[2013-11-06 19:41:00,656] [Log ] [32 ] [INFO ] - Controller: epg stop[/collapse]
 
Last edited by a moderator:

toplad

Portal Pro
October 30, 2013
58
2
Home Country
United Kingdom United Kingdom
please see attached think it stopped working about a week ago. hope this helps its driving me nuts!
 

mm1352000

Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Hello again

    In your system event logs I see many of these messages:
    "28/10/2013 07:10:23";"Microsoft-Windows-WHEA-Logger";"(0)";"Warning";"A corrected hardware error has occurred. Reported by component: Processor Core Error Source: 1 Error Type: 8 Processor APIC ID: 0 The details view of this entry contains further information.";"19"

    That makes me think something might be seriously wrong with your PC.

    The last recording I see is this one:
    [2013-11-01 11:43:00,353] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record BBC ONE HD 11:43-12:15 Britain's Empty Homes

    It starts successfully... and I don't see it ever stop.
    I suggest for the moment that you restart your PC and make sure the recording folder is not full. If the problem persists, post fresh log files.

    mm
     

    toplad

    Portal Pro
    October 30, 2013
    58
    2
    Home Country
    United Kingdom United Kingdom
    thanks for your reply ive found the problem in which the hard drive that was dedicated to the tv recording was beginning to fail. ive since moved it over to another drive and recordings have begun again.

    the next problem is that sometimes it'll record one program forever and if I try to cancel the recording its crashes the system. any ideas on that one?
     

    mm1352000

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    This is a response to @robbo100 's post here:
    https://forum.team-mediaportal.com/...heduled-recordings.122668/page-3#post-1040058

    <off-topic>
    @michael_t
    49 MB 450 k lines of TV Service log in 3.5 days, filled 90% with PowerScheduler... <sigh> :(
    </off-topic>

    Robbo100, I couldn't help but notice this first:
    [collapse][2013-11-10 20:53:22,227] [Log ] [107 ] [INFO ] - dvbt:BuildGraph
    [2013-11-10 20:53:22,228] [Log ] [107 ] [INFO ] - dvb:AddNetworkProviderFilter
    [2013-11-10 20:53:22,228] [Log ] [107 ] [INFO ] - dvb:AddNetworkProviderFilter
    [2013-11-10 20:53:22,230] [Log ] [107 ] [INFO ] - dvb:Add Generic Network Provider
    [2013-11-10 20:53:22,231] [Log ] [107 ] [INFO ] - dvb: Add Mediaportal TsWriter filter
    [2013-11-10 20:53:22,237] [Log ] [107 ] [INFO ] - dvbt:CreateTuningSpace()
    [2013-11-10 20:53:22,242] [Log ] [107 ] [INFO ] - dvbt:found correct tuningspace MediaPortal DVBT TuningSpace
    [2013-11-10 20:53:22,242] [Log ] [107 ] [INFO ] - dvb:Add MPEG2 Demultiplexer filter
    [2013-11-10 20:53:22,243] [Log ] [107 ] [INFO ] - dvb:AddAndConnectBDABoardFilters
    [2013-11-10 20:53:22,243] [Log ] [107 ] [INFO ] - dvb: find bda tuner
    [2013-11-10 20:53:22,255] [Log ] [107 ] [INFO ] - dvb: using [Tuner]: WinTV HVR-900H BDA Tuner
    [2013-11-10 20:53:22,256] [Log ] [107 ] [INFO ] - dvb: Render [Network provider]->[Tuner] OK
    [2013-11-10 20:53:22,256] [Log ] [107 ] [INFO ] - dvb: Setting lastFilter to Tuner filter
    [2013-11-10 20:53:22,256] [Log ] [107 ] [INFO ] - dvb: Find BDA receiver
    [2013-11-10 20:53:22,257] [Log ] [107 ] [INFO ] - dvb: match Capture by Tuner device path
    [2013-11-10 20:53:22,258] [Log ] [107 ] [INFO ] - dvb: -WinTV HVR-900H BDA Capture
    [2013-11-10 20:53:22,259] [Log ] [107 ] [INFO ] - dvb: Render [Tuner]->[Capture] AOK
    [2013-11-10 20:53:22,260] [Log ] [107 ] [INFO ] - dvb: Setting lastFilter to Capture device
    [2013-11-10 20:53:22,261] [Log ] [107 ] [INFO ] - dvb: WinTv CI module not detected.
    [2013-11-10 20:53:22,261] [Log ] [107 ] [INFO ] - dvb:add Inf Tee filter
    [2013-11-10 20:53:22,262] [Log ] [107 ] [INFO ] - dvb: Render ...->[inftee]
    [2013-11-10 20:53:22,263] [Log ] [107 ] [INFO ] - dvb: Setting lastFilter to Inf Tee
    [2013-11-10 20:53:22,263] [Log ] [107 ] [INFO ] - dvb: Render [inftee]->[demux]
    [2013-11-10 20:53:22,264] [Log ] [107 ] [INFO ] - dvb: AddTransportStreamFiltersToGraph
    [2013-11-10 20:53:22,265] [Log ] [107 ] [INFO ] - add BDA MPEG2 Transport Information Filter filter
    [2013-11-10 20:53:22,265] [Log ] [107 ] [INFO ] - pinTif:name:IB Input [3/0] Direction:Input Connected:False
    [2013-11-10 20:53:22,266] [Log ] [107 ] [INFO ] - Connect tif and mpeg2 sections and tables
    [2013-11-10 20:53:22,266] [Log ] [107 ] [INFO ] - dvb:try tif:name:001 [5/0] Direction:Output Connected:False
    [2013-11-10 20:53:22,267] [Log ] [107 ] [INFO ] - tif connected
    [2013-11-10 20:53:22,269] [Log ] [107 ] [INFO ] - dvb: Render ..->[TsWriter]
    [2013-11-10 20:53:22,270] [Log ] [107 ] [INFO ] - dvb: Checking for hardware specific extensions
    [2013-11-10 20:53:22,271] [Log ] [107 ] [INFO ] - CAM is Default model
    [2013-11-10 20:53:22,271] [Log ] [107 ] [INFO ] - Check for KNC
    [2013-11-10 20:53:22,275] [Log ] [107 ] [INFO ] - KNC: IsKNC False
    [2013-11-10 20:53:22,275] [Log ] [107 ] [DEBUG] - KNC: Disable CI
    [2013-11-10 20:53:22,275] [Log ] [107 ] [DEBUG] - KNC: Disposing CI handler
    [2013-11-10 20:53:22,276] [Log ] [107 ] [INFO ] - Check for Digital Everywhere
    [2013-11-10 20:53:22,276] [Log ] [107 ] [INFO ] - Check for Twinhan
    [2013-11-10 20:53:22,276] [Log ] [107 ] [INFO ] - Twinhan: CI detection finished
    [2013-11-10 20:53:22,277] [Log ] [107 ] [INFO ] - Twinhan: CI State:255E0082 MMI State:4B032017
    [2013-11-10 20:53:22,277] [Log ] [107 ] [INFO ] - Twinhan: CI status read
    [2013-11-10 20:53:22,277] [Log ] [107 ] [INFO ] - Twinhan: CAM inserted
    [2013-11-10 20:53:22,277] [Log ] [107 ] [INFO ] - Twinhan: CI State:255E0082 MMI State:4B032017
    [2013-11-10 20:53:22,278] [Log ] [107 ] [INFO ] - Twinhan: CI status read
    [2013-11-10 20:53:22,278] [Log ] [107 ] [INFO ] - Twinhan: CAM detected:True
    [2013-11-10 20:53:22,278] [Log ] [107 ] [INFO ] - Twinhan card detected
    [2013-11-10 20:53:22,278] [Log ] [107 ] [INFO ] - Twinhan registering CI menu capabilities[/collapse]

    A Hauppauge HVR-900H tuner being detected as a Twinhan tuner... with CI slot no less!!! Is that how TV Server has always recognised that tuner? It seems... wrong. Completely off topic of course - sorry. :)

    I don't see anything that stands out as the cause of the recording-not-stopping problem. Unfortunately I was expecting this based on the other reports from toplad et. al. In my opinion we need a debug version of the TV service and/or somebody that can reproduce this problem on an OS that is not W8.1 to be able to narrow down the cause.

    I note two of these exceptions in your log file:
    [collapse][2013-11-10 20:53:12,965] [Log ] [Channel state thread] [ERROR] - ChannelState.DoSetChannelStates: An unknown error occured while setting channel states - The database backend (provider MySQL) could not be reached.
    Check the connection string: Server=Server-PC;Database=MpTvDb;User ID=root;Password=;charset=utf8;Connection Timeout=30;
    Error: DatabaseUnavailableUnclassified
    Gentle.Common.GentleException: The database backend (provider MySQL) could not be reached.
    Check the connection string: Server=Server-PC;Database=MpTvDb;User ID=root;Password=MediaPortal;charset=utf8;Connection Timeout=30; ---> System.Threading.ThreadAbortException: Thread was being aborted.
    at System.Net.UnsafeNclNativeMethods.OSSOCK.recv(IntPtr socketHandle, Byte* pinnedBuffer, Int32 len, SocketFlags socketFlags)
    at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, SocketError& errorCode)
    at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
    at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
    at System.IO.BufferedStream.ReadByte()
    at MySql.Data.MySqlClient.MySqlStream.LoadPacket()
    at MySql.Data.MySqlClient.MySqlStream.OpenPacket()
    at MySql.Data.MySqlClient.NativeDriver.ReadOk(Boolean read)
    at MySql.Data.MySqlClient.NativeDriver.SetDatabase(String dbName)
    at MySql.Data.MySqlClient.MySqlConnection.ChangeDatabase(String databaseName)
    at MySql.Data.MySqlClient.MySqlConnection.Open()
    at Gentle.Provider.MySQL.MySQLProvider.GetConnection()
    --- End of inner exception stack trace ---
    at Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
    at Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
    at Gentle.Provider.MySQL.MySQLProvider.GetConnection()
    at Gentle.Framework.PersistenceBroker.Execute(SqlStatement stmt, IDbConnection dbConnection, IDbTransaction dbTransaction)
    at Gentle.Framework.SqlStatement.Execute()
    at TvDatabase.TvBusinessLayer.IsChannelMappedToCard(Channel dbChannel, Card card, Boolean forEpg)
    at TvService.ChannelStates.DoSetChannelStates(IDictionary`2 cards, ICollection`1 channels, ICollection`1 allUsers, IController tvController)System.Threading.ThreadAbortException: Thread was being aborted.
    at System.Net.UnsafeNclNativeMethods.OSSOCK.recv(IntPtr socketHandle, Byte* pinnedBuffer, Int32 len, SocketFlags socketFlags)
    at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags, SocketError& errorCode)
    at System.Net.Sockets.Socket.Receive(Byte[] buffer, Int32 offset, Int32 size, SocketFlags socketFlags)
    at System.Net.Sockets.NetworkStream.Read(Byte[] buffer, Int32 offset, Int32 size)
    at System.IO.BufferedStream.ReadByte()
    at MySql.Data.MySqlClient.MySqlStream.LoadPacket()
    at MySql.Data.MySqlClient.MySqlStream.OpenPacket()
    at MySql.Data.MySqlClient.NativeDriver.ReadOk(Boolean read)
    at MySql.Data.MySqlClient.NativeDriver.SetDatabase(String dbName)
    at MySql.Data.MySqlClient.MySqlConnection.ChangeDatabase(String databaseName)
    at MySql.Data.MySqlClient.MySqlConnection.Open()
    at Gentle.Provider.MySQL.MySQLProvider.GetConnection()[/collapse]

    I don't think they're directly related as I haven't seen them in other logs... but maybe they point to something network'y as the cause. This is somewhat clutching at straws...
     

    robbo100

    Retired Team Member
  • Premium Supporter
  • May 5, 2009
    1,291
    308
    UK
    Home Country
    United Kingdom United Kingdom
    Thanks mm

    I am not sure what to do then...

    Might it be worth an OS reinstall and start again? I have mucked this system round quite a lot when I had to remove my BGT3600 card and send it back to be fixed, and so the current config was supposed to only be temporary. This may fix the H900 being detected incorrectly, but not sure if it will resolve the other issues.

    What do you think?
     

    mm1352000

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    Might it be worth an OS reinstall and start again?
    Well, at this point it seems likely that the issue is W8.1 related... so I think reinstalling W8.1 will just result in the same problems. If you're planning to reinstall with just W8 and then upgrade to 8.1 again I think that would be a good opportunity to check whether the issue is confined to 8.1.
    Otherwise, I would be interested to know if there are people not running 8.1 who do have this same problem, or conversely if there are people who are running 8.1 who do not have this problem.

    This may fix the H900 being detected incorrectly...
    My comment really was off topic. That is almost certainly entirely unrelated to the issue at hand and I suspect the tuner has always been detected as a Twinhan tuner. Knowing the code, I find that odd so I would be interested if you are able to produce logs showing what happens when that tuner is used in a clean install. However, as above: it likely has no relevance to recordings not stopping etc.
     

    toplad

    Portal Pro
    October 30, 2013
    58
    2
    Home Country
    United Kingdom United Kingdom
    hi guys, thanks for the replys. the only other thing im running on that pc if it helps is blue iris cctv software and utorrent but i dont think theyd interfere. Im looking at a complete reinstall to windows 8 to see if i can make it happen then if not up to 8.1. i'll let you know how it goes
     

    Users who are viewing this thread

    Top Bottom