home
products
contribute
download
documentation
forum
Home
Forums
New posts
Search forums
What's new
New posts
All posts
Latest activity
Members
Registered members
Current visitors
Donate
Log in
Register
What's new
Search
Search
Search titles only
By:
New posts
Search forums
Search titles only
By:
Menu
Log in
Register
Navigation
Install the app
Install
More options
Contact us
Close Menu
Forums
MediaPortal 1
Support
General Support
Weird problem where TV Server "half-crashes"
Contact us
RSS
JavaScript is disabled. For a better experience, please enable JavaScript in your browser before proceeding.
You are using an out of date browser. It may not display this or other websites correctly.
You should upgrade or use an
alternative browser
.
Reply to thread
Message
<blockquote data-quote="Alphathon" data-source="post: 926351" data-attributes="member: 105814"><p>I don't know if this is related to the previous one or not, but I'm now having another, similar, problem.</p><p> </p><p>Like before the TV server will register that it is supposed to record something, but won't. Also like before, the service will need to be restarted before it will record anything, and the TVserver.exe process needs to be manually killed rather than simply stopping it. However, unlike before, logs continue to be written and show the following (or similar depending on what kind of channel is being recorded; in this case it is on BBC THREE, which is a combined DVB-T/DVB-S channel, but the same is true of uncombined channels) when a schedule tries to record:</p><p> </p><p>[CODE]2012-10-26 19:48:07.962881 [scheduler thread(7)]: Scheduler: Check recordings for schedule Doctor Who...</p><p>2012-10-26 19:48:08.039885 [scheduler thread(7)]: Scheduler: Time to record BBC THREE 19:48-21:00 Doctor Who</p><p>2012-10-26 19:48:08.117890 [scheduler thread(7)]: Controller: find free card for channel BBC THREE</p><p>2012-10-26 19:48:08.196894 [scheduler thread(7)]: Controller: find card for channel BBC THREE</p><p>2012-10-26 19:48:08.279899 [scheduler thread(7)]: Controller: got 2 tuning details for BBC THREE</p><p>2012-10-26 19:48:08.354903 [scheduler thread(7)]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:514000 ONID:9018 TSID:4220 SID:4288 PMT:0x12C FTA:True LCN:7 BandWidth:8</p><p>2012-10-26 19:48:08.430907 [scheduler thread(7)]: Controller: card:18 type:DvbS cannot tune to channel</p><p>2012-10-26 19:48:08.505912 [scheduler thread(7)]: Controller: card:19 type:DvbS cannot tune to channel</p><p>2012-10-26 19:48:08.590917 [scheduler thread(7)]: Controller: card:14 type:DvbT can tune to channel</p><p>2012-10-26 19:48:08.665921 [scheduler thread(7)]: Controller: card:14 type:DvbT users: 0</p><p>2012-10-26 19:48:08.746926 [scheduler thread(7)]: Controller: card:15 type:DvbT can tune to channel</p><p>2012-10-26 19:48:08.819930 [scheduler thread(7)]: Controller: card:15 type:DvbT users: 0</p><p>2012-10-26 19:48:08.895934 [scheduler thread(7)]: Controller: card:5 type:DvbT can tune to channel</p><p>2012-10-26 19:48:08.968938 [scheduler thread(7)]: Controller: card:5 type:DvbT users: 0</p><p>2012-10-26 19:48:09.045943 [scheduler thread(7)]: Controller: card:4 type:DvbT can tune to channel</p><p>2012-10-26 19:48:09.118947 [scheduler thread(7)]: Controller: card:4 type:DvbT users: 0</p><p>2012-10-26 19:48:09.193951 [scheduler thread(7)]: Controller: card:2 type:DvbC is disabled</p><p>2012-10-26 19:48:09.266955 [scheduler thread(7)]: Controller: card:3 type:DvbC is disabled</p><p>2012-10-26 19:48:09.340959 [scheduler thread(7)]: Controller: card:6 type:DvbT is disabled</p><p>2012-10-26 19:48:09.414964 [scheduler thread(7)]: Controller: card:7 type:DvbT is disabled</p><p>2012-10-26 19:48:09.486968 [scheduler thread(7)]: Controller: card:11 type:RadioWebStream is disabled</p><p>2012-10-26 19:48:09.560972 [scheduler thread(7)]: Controller: card:10 type:Analog cannot tune to channel</p><p>2012-10-26 19:48:09.636976 [scheduler thread(7)]: Controller: channel #2 DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:48:09.712981 [scheduler thread(7)]: Controller: card:18 type:DvbS can tune to channel</p><p>2012-10-26 19:48:09.785985 [scheduler thread(7)]: Controller: card:18 type:DvbS users: 0</p><p>2012-10-26 19:48:09.861989 [scheduler thread(7)]: Controller: card:19 type:DvbS can tune to channel</p><p>2012-10-26 19:48:09.935994 [scheduler thread(7)]: Controller: card:19 type:DvbS users: 0</p><p>2012-10-26 19:48:10.008998 [scheduler thread(7)]: Controller: card:14 type:DvbT cannot tune to channel</p><p>2012-10-26 19:48:10.083002 [scheduler thread(7)]: Controller: card:15 type:DvbT cannot tune to channel</p><p>2012-10-26 19:48:10.157006 [scheduler thread(7)]: Controller: card:5 type:DvbT cannot tune to channel</p><p>2012-10-26 19:48:10.229010 [scheduler thread(7)]: Controller: card:4 type:DvbT cannot tune to channel</p><p>2012-10-26 19:48:10.302014 [scheduler thread(7)]: Controller: card:2 type:DvbC is disabled</p><p>2012-10-26 19:48:10.377019 [scheduler thread(7)]: Controller: card:3 type:DvbC is disabled</p><p>2012-10-26 19:48:10.450023 [scheduler thread(7)]: Controller: card:6 type:DvbT is disabled</p><p>2012-10-26 19:48:10.524027 [scheduler thread(7)]: Controller: card:7 type:DvbT is disabled</p><p>2012-10-26 19:48:10.599031 [scheduler thread(7)]: Controller: card:11 type:RadioWebStream is disabled</p><p>2012-10-26 19:48:10.673036 [scheduler thread(7)]: Controller: card:10 type:Analog cannot tune to channel</p><p>2012-10-26 19:48:10.746040 [scheduler thread(7)]: Controller: found 6 card(s) for channel</p><p>2012-10-26 19:48:10.820044 [scheduler thread(7)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 2623 msec</p><p>2012-10-26 19:48:10.896048 [scheduler thread(7)]: Controller: card:18 type:DvbS is available</p><p>2012-10-26 19:48:10.969053 [scheduler thread(7)]: Controller: card:19 type:DvbS is available</p><p>2012-10-26 19:48:11.043057 [scheduler thread(7)]: Controller: card:14 type:DvbT is available</p><p>2012-10-26 19:48:11.117061 [scheduler thread(7)]: Controller: card:15 type:DvbT is available</p><p>2012-10-26 19:48:11.191065 [scheduler thread(7)]: Controller: card:5 type:DvbT is available</p><p>2012-10-26 19:48:11.264069 [scheduler thread(7)]: Controller: card:4 type:DvbT is available</p><p>2012-10-26 19:48:11.337074 [scheduler thread(7)]: Controller: found 6 free card(s)</p><p>2012-10-26 19:48:11.415078 [scheduler thread(7)]: AdvancedCardAllocation.GetFreeCardsForChannel took 3296 msec</p><p>2012-10-26 19:48:11.495083 [scheduler thread(7)]: scheduler: try max 6 of 6 FREE cards for recording</p><p>2012-10-26 19:48:11.583088 [scheduler thread(7)]: Controller: epg stop</p><p>2012-10-26 19:48:11.688094 [scheduler thread(7)]: user:epg remove</p><p>2012-10-26 19:48:11.768098 [scheduler thread(7)]: Scheduler : record, first tune to channel</p><p>2012-10-26 19:48:11.844103 [scheduler thread(7)]: card: Tune 18 to BBC THREE</p><p>2012-10-26 19:48:11.917107 [scheduler thread(7)]: card: user: scheduler4943:18:-1 tune DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:48:12.012112 [scheduler thread(7)]: dvbs: Tune:DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:48:12.085116 [scheduler thread(7)]: dvbs: Tune:DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:48:12.158121 [scheduler thread(7)]: dvb:GetNewSubChannel:0 #1[/CODE]</p><p> </p><p>Nothing else shows up in the logs until the server tries to do something else (in this case I went to set another recording at 2012-10-26 20:31:25.938476, which seems to have happened normally). In this case, prior to the schedule starting the DVB EPG grabber was running and same issue seem to have affected it when grabbing data for Radio Caroline, with the following being recorded in the log:</p><p> </p><p>[CODE]2012-10-26 19:25:19.592614 [(3)]: card: Tune 18 to Sky Games</p><p>2012-10-26 19:25:19.666619 [(3)]: card: user: epg:18:-1 tune DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:25:19.760624 [(3)]: dvbs: Tune:DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:25:19.835628 [(3)]: dvbs: Tune:DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:25:19.908632 [(3)]: dvb:GetNewSubChannel:0 #0</p><p>2012-10-26 19:25:20.006638 [(3)]: TvDvbChannel ctor new subchIndex:0</p><p>2012-10-26 19:25:20.078642 [(3)]: LNB low:9750 hi:10600 switch:11700</p><p>2012-10-26 19:25:20.152646 [(3)]: ProfRed DVB-S2 modulation set to:ModNotDefined</p><p>2012-10-26 19:25:20.226651 [(3)]: ProfRed DVB-S2 Pilot set to:NotSet</p><p>2012-10-26 19:25:20.299655 [(3)]: ProfRed DVB-S2 RollOff set to:NotSet</p><p>2012-10-26 19:25:20.372659 [(3)]: ProfRed DVB-S2 fec set to:Rate2_3</p><p>2012-10-26 19:25:20.446663 [(3)]: dvbs:channel modulation is set to ModNotDefined</p><p>2012-10-26 19:25:20.520667 [(3)]: dvbs:channel FECRate is set to Rate2_3</p><p>2012-10-26 19:25:20.595672 [(3)]: ProfRed: SendDiseq: 0xE0 0x10 0x38 0xF3 0x4 0x2 0x2 0x3 0x0 0x1 0x0</p><p>2012-10-26 19:25:20.936691 [(3)]: ProfRed: setdiseqc returned:0</p><p>2012-10-26 19:25:21.110701 [(3)]: dvb:Submiting tunerequest Channel:Sky Games subChannel:0</p><p>2012-10-26 19:25:21.183705 [(3)]: dvb:using existing subchannel:0</p><p>2012-10-26 19:25:21.258710 [(3)]: dvb:Submit tunerequest size:1 new:0</p><p>2012-10-26 19:25:21.332714 [(3)]: subch:0 OnBeforeTune</p><p>2012-10-26 19:25:21.419719 [(3)]: dvb:Submit tunerequest calling put_TuneRequest</p><p>2012-10-26 19:25:21.493723 [(3)]: dvb:Submit tunerequest done calling put_TuneRequest</p><p>2012-10-26 19:25:21.567727 [(3)]: subch:0 OnAfterTune</p><p>2012-10-26 19:25:21.640732 [(3)]: RunGraph</p><p>2012-10-26 19:25:21.715736 [(3)]: subch:0 OnGraphStart</p><p>2012-10-26 19:25:21.791740 [(3)]: dvb: RunGraph</p><p>2012-10-26 19:25:22.019753 [(3)]: dvb: LockedInOnSignal ok</p><p>2012-10-26 19:25:22.093757 [(3)]: subch:0 OnGraphStarted</p><p>2012-10-26 19:25:22.166762 [(3)]: subch:0 SetupPmtGrabber:pid 11D sid:1FAB</p><p>2012-10-26 19:25:22.239766 [(3)]: subch:0 set pmt grabber pmt:11D sid:1FAB</p><p>2012-10-26 19:25:22.323771 [(3)]: WaitForPMT: Waiting for PMT 11D</p><p>2012-10-26 19:25:22.469779 [(13)]: subch:0 OnPMTReceived() pmt:11D ran:True dynamic:False</p><p>2012-10-26 19:25:22.544783 [(3)]: WaitForPMT: Found PMT after 0.2200125 seconds.</p><p>2012-10-26 19:25:22.619787 [(3)]: subch:0 SendPmt:1FAB 1FAB FFFFFFFF 4</p><p>2012-10-26 19:25:22.694792 [(3)]: Decode pmt</p><p>2012-10-26 19:25:22.770796 [(3)]: Set descriptor data with length 3</p><p>2012-10-26 19:25:22.844800 [(3)]: Set descriptor data with length 3</p><p>2012-10-26 19:25:22.919805 [(3)]: Set descriptor data with length 3</p><p>2012-10-26 19:25:22.992809 [(3)]: Set descriptor data with length 3</p><p>2012-10-26 19:25:23.067813 [(3)]: subch:0 SendPMT: Channel is FTA, nothing to do.</p><p>2012-10-26 19:25:23.140817 [(3)]: subch:0 SetMpegPidMapping</p><p>2012-10-26 19:25:23.215822 [(3)]: subch:0 pid:A46 pcr</p><p>2012-10-26 19:25:23.287826 [(3)]: subch:0 pid:11D pmt</p><p>2012-10-26 19:25:23.360830 [(3)]: subch:0 pid:A46 audio lang: type:MPEG-2</p><p>2012-10-26 19:25:23.436834 [(3)]: subch:0 map pid:A46 audio lang: type:MPEG-2</p><p>2012-10-26 19:25:23.518839 [(3)]: subch:0 pid:A3A type:5</p><p>2012-10-26 19:25:23.593843 [(3)]: subch:0 pid:A3B type:5</p><p>2012-10-26 19:25:23.668848 [(3)]: subch:0 pid:A3C type:5</p><p>2012-10-26 19:25:23.743852 [(3)]: subch:0 stop tif</p><p>2012-10-26 19:25:23.820856 [(3)]: WaitForPmt: PMT handling took 1.2000686 seconds.</p><p>2012-10-26 19:25:23.899861 [(3)]: card: Tuner locked: True</p><p>2012-10-26 19:25:23.976865 [(3)]: **************************************************</p><p>2012-10-26 19:25:24.052869 [(3)]: ***** SIGNAL LEVEL: 71, SIGNAL QUALITY: 61 *****</p><p>2012-10-26 19:25:24.128874 [(3)]: **************************************************</p><p>2012-10-26 19:25:24.204878 [(3)]: card: tuned user: epg subchannel: 0</p><p>2012-10-26 19:25:24.281883 [(3)]: user:epg add</p><p>2012-10-26 19:25:24.357887 [DVB EPG timer(10)]: Controller: GrabEpg on card ID == 18</p><p>2012-10-26 19:25:24.446892 [DVB EPG timer(10)]: dvb:grab epg...</p><p>2012-10-26 19:26:26.449438 [(13)]: EPG: database updated for #0 Sky Games</p><p>2012-10-26 19:26:26.534443 [(13)]: user:epg remove</p><p>2012-10-26 19:26:26.612448 [(13)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False</p><p>2012-10-26 19:26:26.695452 [(13)]: DVB subch:0 Decompose()</p><p>2012-10-26 19:26:26.811459 [(13)]: FreeSubChannel CA: freeing sub channel : 0</p><p>2012-10-26 19:26:26.890464 [(13)]: tvcard:FreeSubChannel : no subchannels present, pausing graph</p><p>2012-10-26 19:26:26.968468 [(13)]: dvb:StopGraph called</p><p>2012-10-26 19:26:27.045472 [(13)]: tvcard:FreeAllSubChannels</p><p>2012-10-26 19:26:27.120477 [(13)]: dvb:StopGraph</p><p>2012-10-26 19:26:49.653766 [(3)]: card: Tune 18 to Radio Caroline</p><p>2012-10-26 19:26:49.729770 [(3)]: card: user: epg:18:-1 tune DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:26:49.823775 [(3)]: dvbs: Tune:DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:26:49.897780 [(3)]: dvbs: Tune:DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet</p><p>2012-10-26 19:26:49.973784 [(3)]: dvb:GetNewSubChannel:0 #0</p><p>2012-10-26 19:27:53.563421 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl: RegisterChannel first called in Domain MediaPortal.exe for thread TvClient-TvHome: HeartBeat transmitter thread with id 12</p><p>2012-10-26 19:27:53.767433 [TvClient-TvHome: HeartBeat transmitter thread(12)]: TCP connect took : 23</p><p>2012-10-26 19:27:53.859438 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected</p><p>2012-10-26 19:27:53.958444 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected</p><p>2012-10-26 19:27:54.316464 [MPMain(1)]: RemoteControl - Connected</p><p>2012-10-26 19:27:57.094623 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected</p><p>2012-10-26 19:27:58.177685 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected[/CODE]</p><p> </p><p>Any ideas?</p></blockquote><p></p>
[QUOTE="Alphathon, post: 926351, member: 105814"] I don't know if this is related to the previous one or not, but I'm now having another, similar, problem. Like before the TV server will register that it is supposed to record something, but won't. Also like before, the service will need to be restarted before it will record anything, and the TVserver.exe process needs to be manually killed rather than simply stopping it. However, unlike before, logs continue to be written and show the following (or similar depending on what kind of channel is being recorded; in this case it is on BBC THREE, which is a combined DVB-T/DVB-S channel, but the same is true of uncombined channels) when a schedule tries to record: [CODE]2012-10-26 19:48:07.962881 [scheduler thread(7)]: Scheduler: Check recordings for schedule Doctor Who... 2012-10-26 19:48:08.039885 [scheduler thread(7)]: Scheduler: Time to record BBC THREE 19:48-21:00 Doctor Who 2012-10-26 19:48:08.117890 [scheduler thread(7)]: Controller: find free card for channel BBC THREE 2012-10-26 19:48:08.196894 [scheduler thread(7)]: Controller: find card for channel BBC THREE 2012-10-26 19:48:08.279899 [scheduler thread(7)]: Controller: got 2 tuning details for BBC THREE 2012-10-26 19:48:08.354903 [scheduler thread(7)]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:514000 ONID:9018 TSID:4220 SID:4288 PMT:0x12C FTA:True LCN:7 BandWidth:8 2012-10-26 19:48:08.430907 [scheduler thread(7)]: Controller: card:18 type:DvbS cannot tune to channel 2012-10-26 19:48:08.505912 [scheduler thread(7)]: Controller: card:19 type:DvbS cannot tune to channel 2012-10-26 19:48:08.590917 [scheduler thread(7)]: Controller: card:14 type:DvbT can tune to channel 2012-10-26 19:48:08.665921 [scheduler thread(7)]: Controller: card:14 type:DvbT users: 0 2012-10-26 19:48:08.746926 [scheduler thread(7)]: Controller: card:15 type:DvbT can tune to channel 2012-10-26 19:48:08.819930 [scheduler thread(7)]: Controller: card:15 type:DvbT users: 0 2012-10-26 19:48:08.895934 [scheduler thread(7)]: Controller: card:5 type:DvbT can tune to channel 2012-10-26 19:48:08.968938 [scheduler thread(7)]: Controller: card:5 type:DvbT users: 0 2012-10-26 19:48:09.045943 [scheduler thread(7)]: Controller: card:4 type:DvbT can tune to channel 2012-10-26 19:48:09.118947 [scheduler thread(7)]: Controller: card:4 type:DvbT users: 0 2012-10-26 19:48:09.193951 [scheduler thread(7)]: Controller: card:2 type:DvbC is disabled 2012-10-26 19:48:09.266955 [scheduler thread(7)]: Controller: card:3 type:DvbC is disabled 2012-10-26 19:48:09.340959 [scheduler thread(7)]: Controller: card:6 type:DvbT is disabled 2012-10-26 19:48:09.414964 [scheduler thread(7)]: Controller: card:7 type:DvbT is disabled 2012-10-26 19:48:09.486968 [scheduler thread(7)]: Controller: card:11 type:RadioWebStream is disabled 2012-10-26 19:48:09.560972 [scheduler thread(7)]: Controller: card:10 type:Analog cannot tune to channel 2012-10-26 19:48:09.636976 [scheduler thread(7)]: Controller: channel #2 DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:48:09.712981 [scheduler thread(7)]: Controller: card:18 type:DvbS can tune to channel 2012-10-26 19:48:09.785985 [scheduler thread(7)]: Controller: card:18 type:DvbS users: 0 2012-10-26 19:48:09.861989 [scheduler thread(7)]: Controller: card:19 type:DvbS can tune to channel 2012-10-26 19:48:09.935994 [scheduler thread(7)]: Controller: card:19 type:DvbS users: 0 2012-10-26 19:48:10.008998 [scheduler thread(7)]: Controller: card:14 type:DvbT cannot tune to channel 2012-10-26 19:48:10.083002 [scheduler thread(7)]: Controller: card:15 type:DvbT cannot tune to channel 2012-10-26 19:48:10.157006 [scheduler thread(7)]: Controller: card:5 type:DvbT cannot tune to channel 2012-10-26 19:48:10.229010 [scheduler thread(7)]: Controller: card:4 type:DvbT cannot tune to channel 2012-10-26 19:48:10.302014 [scheduler thread(7)]: Controller: card:2 type:DvbC is disabled 2012-10-26 19:48:10.377019 [scheduler thread(7)]: Controller: card:3 type:DvbC is disabled 2012-10-26 19:48:10.450023 [scheduler thread(7)]: Controller: card:6 type:DvbT is disabled 2012-10-26 19:48:10.524027 [scheduler thread(7)]: Controller: card:7 type:DvbT is disabled 2012-10-26 19:48:10.599031 [scheduler thread(7)]: Controller: card:11 type:RadioWebStream is disabled 2012-10-26 19:48:10.673036 [scheduler thread(7)]: Controller: card:10 type:Analog cannot tune to channel 2012-10-26 19:48:10.746040 [scheduler thread(7)]: Controller: found 6 card(s) for channel 2012-10-26 19:48:10.820044 [scheduler thread(7)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 2623 msec 2012-10-26 19:48:10.896048 [scheduler thread(7)]: Controller: card:18 type:DvbS is available 2012-10-26 19:48:10.969053 [scheduler thread(7)]: Controller: card:19 type:DvbS is available 2012-10-26 19:48:11.043057 [scheduler thread(7)]: Controller: card:14 type:DvbT is available 2012-10-26 19:48:11.117061 [scheduler thread(7)]: Controller: card:15 type:DvbT is available 2012-10-26 19:48:11.191065 [scheduler thread(7)]: Controller: card:5 type:DvbT is available 2012-10-26 19:48:11.264069 [scheduler thread(7)]: Controller: card:4 type:DvbT is available 2012-10-26 19:48:11.337074 [scheduler thread(7)]: Controller: found 6 free card(s) 2012-10-26 19:48:11.415078 [scheduler thread(7)]: AdvancedCardAllocation.GetFreeCardsForChannel took 3296 msec 2012-10-26 19:48:11.495083 [scheduler thread(7)]: scheduler: try max 6 of 6 FREE cards for recording 2012-10-26 19:48:11.583088 [scheduler thread(7)]: Controller: epg stop 2012-10-26 19:48:11.688094 [scheduler thread(7)]: user:epg remove 2012-10-26 19:48:11.768098 [scheduler thread(7)]: Scheduler : record, first tune to channel 2012-10-26 19:48:11.844103 [scheduler thread(7)]: card: Tune 18 to BBC THREE 2012-10-26 19:48:11.917107 [scheduler thread(7)]: card: user: scheduler4943:18:-1 tune DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:48:12.012112 [scheduler thread(7)]: dvbs: Tune:DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:48:12.085116 [scheduler thread(7)]: dvbs: Tune:DVBS:tv:BBC BBC THREE Freq:10818000 ONID:2 TSID:2048 SID:10351 PMT:0x101 FTA:True LCN:129 SymbolRate:22000 Modulation:ModQpsk Polarisation:LinearV InnerFecRate:Rate5_6 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:48:12.158121 [scheduler thread(7)]: dvb:GetNewSubChannel:0 #1[/CODE] Nothing else shows up in the logs until the server tries to do something else (in this case I went to set another recording at 2012-10-26 20:31:25.938476, which seems to have happened normally). In this case, prior to the schedule starting the DVB EPG grabber was running and same issue seem to have affected it when grabbing data for Radio Caroline, with the following being recorded in the log: [CODE]2012-10-26 19:25:19.592614 [(3)]: card: Tune 18 to Sky Games 2012-10-26 19:25:19.666619 [(3)]: card: user: epg:18:-1 tune DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:25:19.760624 [(3)]: dvbs: Tune:DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:25:19.835628 [(3)]: dvbs: Tune:DVBS:radio:BSkyB Sky Games Freq:12070000 ONID:2 TSID:2019 SID:8107 PMT:0x11D FTA:True LCN:4590 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:25:19.908632 [(3)]: dvb:GetNewSubChannel:0 #0 2012-10-26 19:25:20.006638 [(3)]: TvDvbChannel ctor new subchIndex:0 2012-10-26 19:25:20.078642 [(3)]: LNB low:9750 hi:10600 switch:11700 2012-10-26 19:25:20.152646 [(3)]: ProfRed DVB-S2 modulation set to:ModNotDefined 2012-10-26 19:25:20.226651 [(3)]: ProfRed DVB-S2 Pilot set to:NotSet 2012-10-26 19:25:20.299655 [(3)]: ProfRed DVB-S2 RollOff set to:NotSet 2012-10-26 19:25:20.372659 [(3)]: ProfRed DVB-S2 fec set to:Rate2_3 2012-10-26 19:25:20.446663 [(3)]: dvbs:channel modulation is set to ModNotDefined 2012-10-26 19:25:20.520667 [(3)]: dvbs:channel FECRate is set to Rate2_3 2012-10-26 19:25:20.595672 [(3)]: ProfRed: SendDiseq: 0xE0 0x10 0x38 0xF3 0x4 0x2 0x2 0x3 0x0 0x1 0x0 2012-10-26 19:25:20.936691 [(3)]: ProfRed: setdiseqc returned:0 2012-10-26 19:25:21.110701 [(3)]: dvb:Submiting tunerequest Channel:Sky Games subChannel:0 2012-10-26 19:25:21.183705 [(3)]: dvb:using existing subchannel:0 2012-10-26 19:25:21.258710 [(3)]: dvb:Submit tunerequest size:1 new:0 2012-10-26 19:25:21.332714 [(3)]: subch:0 OnBeforeTune 2012-10-26 19:25:21.419719 [(3)]: dvb:Submit tunerequest calling put_TuneRequest 2012-10-26 19:25:21.493723 [(3)]: dvb:Submit tunerequest done calling put_TuneRequest 2012-10-26 19:25:21.567727 [(3)]: subch:0 OnAfterTune 2012-10-26 19:25:21.640732 [(3)]: RunGraph 2012-10-26 19:25:21.715736 [(3)]: subch:0 OnGraphStart 2012-10-26 19:25:21.791740 [(3)]: dvb: RunGraph 2012-10-26 19:25:22.019753 [(3)]: dvb: LockedInOnSignal ok 2012-10-26 19:25:22.093757 [(3)]: subch:0 OnGraphStarted 2012-10-26 19:25:22.166762 [(3)]: subch:0 SetupPmtGrabber:pid 11D sid:1FAB 2012-10-26 19:25:22.239766 [(3)]: subch:0 set pmt grabber pmt:11D sid:1FAB 2012-10-26 19:25:22.323771 [(3)]: WaitForPMT: Waiting for PMT 11D 2012-10-26 19:25:22.469779 [(13)]: subch:0 OnPMTReceived() pmt:11D ran:True dynamic:False 2012-10-26 19:25:22.544783 [(3)]: WaitForPMT: Found PMT after 0.2200125 seconds. 2012-10-26 19:25:22.619787 [(3)]: subch:0 SendPmt:1FAB 1FAB FFFFFFFF 4 2012-10-26 19:25:22.694792 [(3)]: Decode pmt 2012-10-26 19:25:22.770796 [(3)]: Set descriptor data with length 3 2012-10-26 19:25:22.844800 [(3)]: Set descriptor data with length 3 2012-10-26 19:25:22.919805 [(3)]: Set descriptor data with length 3 2012-10-26 19:25:22.992809 [(3)]: Set descriptor data with length 3 2012-10-26 19:25:23.067813 [(3)]: subch:0 SendPMT: Channel is FTA, nothing to do. 2012-10-26 19:25:23.140817 [(3)]: subch:0 SetMpegPidMapping 2012-10-26 19:25:23.215822 [(3)]: subch:0 pid:A46 pcr 2012-10-26 19:25:23.287826 [(3)]: subch:0 pid:11D pmt 2012-10-26 19:25:23.360830 [(3)]: subch:0 pid:A46 audio lang: type:MPEG-2 2012-10-26 19:25:23.436834 [(3)]: subch:0 map pid:A46 audio lang: type:MPEG-2 2012-10-26 19:25:23.518839 [(3)]: subch:0 pid:A3A type:5 2012-10-26 19:25:23.593843 [(3)]: subch:0 pid:A3B type:5 2012-10-26 19:25:23.668848 [(3)]: subch:0 pid:A3C type:5 2012-10-26 19:25:23.743852 [(3)]: subch:0 stop tif 2012-10-26 19:25:23.820856 [(3)]: WaitForPmt: PMT handling took 1.2000686 seconds. 2012-10-26 19:25:23.899861 [(3)]: card: Tuner locked: True 2012-10-26 19:25:23.976865 [(3)]: ************************************************** 2012-10-26 19:25:24.052869 [(3)]: ***** SIGNAL LEVEL: 71, SIGNAL QUALITY: 61 ***** 2012-10-26 19:25:24.128874 [(3)]: ************************************************** 2012-10-26 19:25:24.204878 [(3)]: card: tuned user: epg subchannel: 0 2012-10-26 19:25:24.281883 [(3)]: user:epg add 2012-10-26 19:25:24.357887 [DVB EPG timer(10)]: Controller: GrabEpg on card ID == 18 2012-10-26 19:25:24.446892 [DVB EPG timer(10)]: dvb:grab epg... 2012-10-26 19:26:26.449438 [(13)]: EPG: database updated for #0 Sky Games 2012-10-26 19:26:26.534443 [(13)]: user:epg remove 2012-10-26 19:26:26.612448 [(13)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False 2012-10-26 19:26:26.695452 [(13)]: DVB subch:0 Decompose() 2012-10-26 19:26:26.811459 [(13)]: FreeSubChannel CA: freeing sub channel : 0 2012-10-26 19:26:26.890464 [(13)]: tvcard:FreeSubChannel : no subchannels present, pausing graph 2012-10-26 19:26:26.968468 [(13)]: dvb:StopGraph called 2012-10-26 19:26:27.045472 [(13)]: tvcard:FreeAllSubChannels 2012-10-26 19:26:27.120477 [(13)]: dvb:StopGraph 2012-10-26 19:26:49.653766 [(3)]: card: Tune 18 to Radio Caroline 2012-10-26 19:26:49.729770 [(3)]: card: user: epg:18:-1 tune DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:26:49.823775 [(3)]: dvbs: Tune:DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:26:49.897780 [(3)]: dvbs: Tune:DVBS:radio:Arqiva Radio Caroline Freq:11428000 ONID:59 TSID:2315 SID:10551 PMT:0xFA1 FTA:True LCN:4480 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate2_3 DisEqc:Level1AA band:Universal Pilot:NotSet RollOff:NotSet 2012-10-26 19:26:49.973784 [(3)]: dvb:GetNewSubChannel:0 #0 2012-10-26 19:27:53.563421 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl: RegisterChannel first called in Domain MediaPortal.exe for thread TvClient-TvHome: HeartBeat transmitter thread with id 12 2012-10-26 19:27:53.767433 [TvClient-TvHome: HeartBeat transmitter thread(12)]: TCP connect took : 23 2012-10-26 19:27:53.859438 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected 2012-10-26 19:27:53.958444 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected 2012-10-26 19:27:54.316464 [MPMain(1)]: RemoteControl - Connected 2012-10-26 19:27:57.094623 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected 2012-10-26 19:27:58.177685 [TvClient-TvHome: HeartBeat transmitter thread(12)]: RemoteControl - Connected[/CODE] Any ideas? [/QUOTE]
Insert quotes…
Verification
Post reply
Forums
MediaPortal 1
Support
General Support
Weird problem where TV Server "half-crashes"
Contact us
RSS
Top
Bottom
