- July 15, 2010
- 64
- 8
- Home Country
-
Scotland
Recently (the past two or three weeks at a guess), I've been having a really strange issue with TV Server. It's kinda difficult to explain so bear with me.
At seemingly arbitrary times it will stop writing to the log files, and after that point will not record anything. Also, if after this point one tries to watch TV via the client (single seat) the client will stop working. The client is however able to access channel lists, the EPG etc, so the TV Server hasn't crashed entirely. Prior to the server ceasing to work it (the server) works normally, with recordings, watching of TV etc working as expected.
On some, rarer occasions a similar and probably related issue manifests, where the server identifies all the cards as "N/A" (rather than DVB-S, DVB-T etc), so won't tune anything. When this happens, the Client simply says there is no free card available rather than locking up. I'm not sure if it writes to the logs when this happens. This, unlike the other issue manifests as soon as the server starts up - there is no period prior to it not working where it behaves normally.
In both cases a restart of the TV server fixes the issue. With the former TvService.exe has to be manually killed from the task manager; the service cannot be stopped from within the config utility or from within the Windows services windows (services.msc or the "services" tab of the task manager) - when trying to do so, it will sit in the "stopping" state indefinitely.
My hypothesis for the linking of the two is that whatever is causing the second problem ("N/A" type cards) is the same as the first, but with the first it manifests later and thus rather than the cards simply not being recognised properly it ceases to be able to access them in the way it is supposed to (i.e. it tries to access a card as, say, DVB-T but can't). It should be noted that I have three TV cards (listed in my system specs), and this affects all of them, as well as the integrated Radio Webstream soft-card.
Since the TV server stops writing to the logs during this problem I'm not sure if posting logs would be any use. I've had a look and I've seen multiple different things listed as the last items in the logs. The most recent, which seems to be it gearing up to record an episode of Family Guy on VIVA Deutschland, is posted below. However, as I said, there seems to be no pattern with what is the last recorded action in the log. For example, I remember seeing something along the lines of "RemoteControl - Connected" repeated several times on one occasion.
I intend to compile a set of logs of when this manifests, but as yet I only have today's. I'll post them once I have if need be.
Further information:
I have not changed my MediaPortal setup recently, so this hasn't been caused by the addition of a new plugin/extension. It may however be some kind of conflict with another piece (or pieces) of software, since that kind of thing changes all the time.
At seemingly arbitrary times it will stop writing to the log files, and after that point will not record anything. Also, if after this point one tries to watch TV via the client (single seat) the client will stop working. The client is however able to access channel lists, the EPG etc, so the TV Server hasn't crashed entirely. Prior to the server ceasing to work it (the server) works normally, with recordings, watching of TV etc working as expected.
On some, rarer occasions a similar and probably related issue manifests, where the server identifies all the cards as "N/A" (rather than DVB-S, DVB-T etc), so won't tune anything. When this happens, the Client simply says there is no free card available rather than locking up. I'm not sure if it writes to the logs when this happens. This, unlike the other issue manifests as soon as the server starts up - there is no period prior to it not working where it behaves normally.
In both cases a restart of the TV server fixes the issue. With the former TvService.exe has to be manually killed from the task manager; the service cannot be stopped from within the config utility or from within the Windows services windows (services.msc or the "services" tab of the task manager) - when trying to do so, it will sit in the "stopping" state indefinitely.
My hypothesis for the linking of the two is that whatever is causing the second problem ("N/A" type cards) is the same as the first, but with the first it manifests later and thus rather than the cards simply not being recognised properly it ceases to be able to access them in the way it is supposed to (i.e. it tries to access a card as, say, DVB-T but can't). It should be noted that I have three TV cards (listed in my system specs), and this affects all of them, as well as the integrated Radio Webstream soft-card.
Since the TV server stops writing to the logs during this problem I'm not sure if posting logs would be any use. I've had a look and I've seen multiple different things listed as the last items in the logs. The most recent, which seems to be it gearing up to record an episode of Family Guy on VIVA Deutschland, is posted below. However, as I said, there seems to be no pattern with what is the last recorded action in the log. For example, I remember seeing something along the lines of "RemoteControl - Connected" repeated several times on one occasion.
Code:
2012-09-11 20:15:14.220326 [scheduler thread(10)]: Scheduler: Check recordings for schedule Family Guy...
2012-09-11 20:15:14.316332 [scheduler thread(10)]: Scheduler: Time to record VIVA Deutschland 20:15-20:50 Family Guy
2012-09-11 20:15:14.319332 [scheduler thread(10)]: Controller: find free card for channel VIVA Deutschland
2012-09-11 20:15:14.322332 [scheduler thread(10)]: Controller: find card for channel VIVA Deutschland
2012-09-11 20:15:14.326332 [scheduler thread(10)]: Controller: got 1 tuning details for VIVA Deutschland
2012-09-11 20:15:14.330332 [scheduler thread(10)]: Controller: channel #1 DVBS:tv:MTV Networks Europe VIVA Germany Freq:11973000 ONID:1 TSID:1078 SID:28676 PMT:0x1A0 FTA:True LCN:3494 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:Level1AB band:Universal Pilot:NotSet RollOff:NotSet
2012-09-11 20:15:14.334333 [scheduler thread(10)]: Controller: card:18 type:DvbS can tune to channel
2012-09-11 20:15:14.337333 [scheduler thread(10)]: Controller: card:18 type:DvbS users: 0
2012-09-11 20:15:14.341333 [scheduler thread(10)]: Controller: card:19 type:DvbS can tune to channel
2012-09-11 20:15:14.344333 [scheduler thread(10)]: Controller: card:19 type:DvbS users: 0
2012-09-11 20:15:14.348333 [scheduler thread(10)]: Controller: card:14 type:DvbT cannot tune to channel
2012-09-11 20:15:14.350334 [scheduler thread(10)]: Controller: card:15 type:DvbT cannot tune to channel
2012-09-11 20:15:14.353334 [scheduler thread(10)]: Controller: card:5 type:DvbT cannot tune to channel
2012-09-11 20:15:14.356334 [scheduler thread(10)]: Controller: card:4 type:DvbT cannot tune to channel
2012-09-11 20:15:14.358334 [scheduler thread(10)]: Controller: card:2 type:DvbC is disabled
2012-09-11 20:15:14.361334 [scheduler thread(10)]: Controller: card:3 type:DvbC is disabled
2012-09-11 20:15:14.364334 [scheduler thread(10)]: Controller: card:6 type:DvbT is disabled
2012-09-11 20:15:14.367335 [scheduler thread(10)]: Controller: card:7 type:DvbT is disabled
2012-09-11 20:15:14.370335 [scheduler thread(10)]: Controller: card:11 type:RadioWebStream is disabled
2012-09-11 20:15:14.373335 [scheduler thread(10)]: Controller: card:10 type:Analog cannot tune to channel
2012-09-11 20:15:14.376335 [scheduler thread(10)]: Controller: found 2 card(s) for channel
2012-09-11 20:15:14.378335 [scheduler thread(10)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 56 msec
2012-09-11 20:15:14.381335 [scheduler thread(10)]: Controller: card:18 type:DvbS is available
2012-09-11 20:15:14.384336 [scheduler thread(10)]: Controller: card:19 type:DvbS is available
2012-09-11 20:15:14.387336 [scheduler thread(10)]: Controller: found 2 free card(s)
2012-09-11 20:15:14.389336 [scheduler thread(10)]: AdvancedCardAllocation.GetFreeCardsForChannel took 70 msec
2012-09-11 20:15:14.392336 [scheduler thread(10)]: scheduler: try max 2 of 2 FREE cards for recording
2012-09-11 20:15:14.396336 [scheduler thread(10)]: Controller: epg stop
2012-09-11 20:15:14.407337 [scheduler thread(10)]: user:epg remove
2012-09-11 20:15:14.409337 [scheduler thread(10)]: Scheduler : record, first tune to channel
2012-09-11 20:15:14.412337 [scheduler thread(10)]: card: Tune 18 to VIVA Germany
2012-09-11 20:15:14.415337 [scheduler thread(10)]: card: user: scheduler4087:18:-1 tune DVBS:tv:MTV Networks Europe VIVA Germany Freq:11973000 ONID:1 TSID:1078 SID:28676 PMT:0x1A0 FTA:True LCN:3494 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:Level1AB band:Universal Pilot:NotSet RollOff:NotSet
2012-09-11 20:15:14.427338 [scheduler thread(10)]: dvbs: Tune:DVBS:tv:MTV Networks Europe VIVA Germany Freq:11973000 ONID:1 TSID:1078 SID:28676 PMT:0x1A0 FTA:True LCN:3494 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:Level1AB band:Universal Pilot:NotSet RollOff:NotSet
2012-09-11 20:15:14.430338 [scheduler thread(10)]: dvbs: Tune:DVBS:tv:MTV Networks Europe VIVA Germany Freq:11973000 ONID:1 TSID:1078 SID:28676 PMT:0x1A0 FTA:True LCN:3494 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:Level1AB band:Universal Pilot:NotSet RollOff:NotSet
2012-09-11 20:15:14.433338 [scheduler thread(10)]: dvb:GetNewSubChannel:0 #1
I intend to compile a set of logs of when this manifests, but as yet I only have today's. I'll post them once I have if need be.
Further information:
I have not changed my MediaPortal setup recently, so this hasn't been caused by the addition of a new plugin/extension. It may however be some kind of conflict with another piece (or pieces) of software, since that kind of thing changes all the time.
Last edited: