Hello all,
I'm using latest SVN of MP and TV server (4 jul). The same problem was there when using Jun SVN and main release.
MP works fine for X minutes (sometimes 3, sometimes 30min) then the first sign is that i see mouse on screen and them i'm in My TV screen (from fullscreen) with black TV screen. No channel selection button works (the best is that MP starts playing timeshift record).
Other functions seems to be continue to work. Simple restart of MP helps and i can continue to watch my TV.
-----------------------
In some cases looking at TV server LOG i found something like this.....
2008-07-08 20:22:52.828125 [HeartBeatMonitor]: Controller: Heartbeat Monitor - kicking idle user media
2008-07-08 20:22:52.828125 [HeartBeatMonitor]: Controller: StopTimeShifting 1
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: card: StopTimeShifting user:media sub:0
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: card: StopTimeShifting user:media sub:0
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: user:media remove
------------------------
Latest SVN TV server log is something like this.
2008-07-09 22:18:30.828125 [12]: Controller: StopTimeShifting 1
2008-07-09 22:18:30.859375 [12]: card: StopTimeShifting user:media sub:0
2008-07-09 22:18:30.859375 [12]: card: StopTimeShifting user:media sub:0
2008-07-09 22:18:30.859375 [12]: user:media remove
2008-07-09 22:18:30.859375 [12]: Stopcard
2008-07-09 22:18:30.859375 [12]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:18:30.859375 [12]: analog subch:0 Decompose()
2008-07-09 22:18:30.875000 [12]: subch:0 StopTimeshifting()
2008-07-09 22:18:30.890625 [12]: tvcard:FreeAllSubChannels:
2008-07-09 22:18:30.890625 [12]: dvb
topGraph
2008-07-09 22:18:31.109375 [12]: tvcard:FreeAllSubChannels:
2008-07-09 22:18:32.890625 [19]: card: Tune 1 to Viasat Sport
2008-07-09 22:18:32.890625 [19]: card: user: epg:1:-1 tune DVBS:tv: Viasat Sport Freq:11900000 ONID:85 TSID:18 SID:6800 PMT:1A90 FTA:False LCN:55 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:18:32.953125 [19]: dvbs: Tune
VBS:tv: Viasat Sport Freq:11900000 ONID:85 TSID:18 SID:6800 PMT:1A90 FTA:False LCN:55 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:18:32.953125 [19]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:18:32.953125 [19]: Channel modulation is set to ModNotSet
2008-07-09 22:18:32.953125 [19]: Channel FECRate is set to Rate3_4
2008-07-09 22:18:33.062500 [19]: dvb
ubmiting tunerequest Channel:Viasat Sport subChannel:-1
2008-07-09 22:18:33.062500 [19]: dvb:Getting new subchannel
2008-07-09 22:18:33.062500 [19]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:18:33.062500 [19]: dvb
ubmit tunerequest size:1 new:0
2008-07-09 22:18:33.062500 [19]: subch:0 OnBeforeTune
2008-07-09 22:18:33.062500 [19]: dvb
ubmit tunerequest calling put_TuneRequest
2008-07-09 22:18:33.312500 [19]: dvb
ubmit tunerequest done calling put_TuneRequest
2008-07-09 22:18:33.312500 [19]: subch:0 OnAfterTune
2008-07-09 22:18:33.312500 [19]: subch:0 OnGraphStart
2008-07-09 22:18:33.312500 [19]: subch0:RunGraph
2008-07-09 22:18:33.312500 [19]: dvb: RunGraph
2008-07-09 22:18:33.593750 [19]: subch:0 OnGraphStart
2008-07-09 22:18:33.593750 [19]: subch:0 RunGraph: already running
2008-07-09 22:18:33.593750 [19]: subch:0 SetupPmtGrabber
id 1A90 sid:1A90
2008-07-09 22:18:33.593750 [19]: subch:0 set pmt grabber pmt:1A90 sid:1A90
2008-07-09 22:18:33.593750 [19]: subch:0 set ca grabber
2008-07-09 22:18:33.593750 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.625000 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.640625 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.656250 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.687500 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.765625 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:18:33.765625 [13]: subch:0 SendPmt:wait for ca
2008-07-09 22:18:33.765625 [13]: subch:OnCaReceived()
2008-07-09 22:18:33.765625 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.781250 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.796875 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.828125 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.859375 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.859375 [6]: subch:0 SendPmt:1A90 1A90 FFFFFFFF 2
2008-07-09 22:18:33.859375 [6]: Decode pmt
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A91 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: Set descriptor data with length 6
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A93 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: Set descriptor data with length 6
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A92 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: subch:0 SendPMT version:2 len:58 6800
2008-07-09 22:18:33.859375 [6]: subch:0 cam flags:True
2008-07-09 22:18:33.859375 [6]: subch:0 SetMpegPidMapping
2008-07-09 22:18:33.859375 [6]: subch:0 pid:0 pcr
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A90 pmt
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A91 video type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 map pid:1A91 video type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A93 audio lang:eng type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 map pid:1A93 audio lang:eng type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A92 audio lang:rus type:MPEG-1
2008-07-09 22:18:33.859375 [6]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:18:33.859375 [6]: emm len:18 9 16 5 0 E2 5A 13 1 20 14 3 1 94 0 14 3 2 2D 8 14 3 2 31 0
2008-07-09 22:18:33.859375 [6]: EMM #0 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:18:33.859375 [6]: EMM #1 CA:0x500 EMM:0x25A ID:0x23100
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ECM #0 CA:0x90F ECM:0x1A99 ID:0x0
2008-07-09 22:18:33.859375 [6]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:18:33.859375 [6]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:18:33.859375 [6]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:18:33.859375 [6]: mdplug: tp_id:18(0x12) sid:6800(0x1A90) pmt_id:6800(0x1A90)
2008-07-09 22:18:33.859375 [6]: mdplug: #0 CA_typ:2319(0x90F) ECM:6809(0x1A99) EMM:0xC0 provider:0(0x0)
2008-07-09 22:18:33.859375 [6]: mdplug: #1 CA_typ:1280(0x500) ECM:0(0x0) EMM:0x25A provider:143616(0x23100)
2008-07-09 22:18:33.875000 [6]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:2 ECM_PID:6809(0x1A99)
2008-07-09 22:18:33.875000 [6]: subch:0 stop tif
2008-07-09 22:18:33.875000 [19]: card: tuned user: epg subchannel: 0
2008-07-09 22:18:33.875000 [19]: user:epg add
2008-07-09 22:18:33.890625 [19]: card: Tuner locked: True
2008-07-09 22:18:33.890625 [19]: **************************************************
2008-07-09 22:18:33.890625 [19]: ***** SIGNAL LEVEL: 50, SIGNAL QUALITY: 100 *****
2008-07-09 22:18:33.890625 [19]: **************************************************
2008-07-09 22:18:33.906250 [7]: dvb:grab epg...
2008-07-09 22:18:41.218750 [PowerEventThread]: TV service PowerEventThread 49380
2008-07-09 22:18:41.218750 [PowerEventThread]: TV service PowerEventThread 49380
----------------------------
What is strange is that I was watching totaly different channel:
Log:
2008-07-09 21:58:02.078125 [12]: Controller: find free card for channel TV3 Latvia
2008-07-09 21:58:02.078125 [12]: Controller: got 1 tuning details for TV3 Latvia
2008-07-09 21:58:02.078125 [12]: Controller: channel #1 DVBS:tv: TV3 Latvia Freq:11958000 ONID:85 TSID:14 SID:6300 PMT:189C FTA:False LCN:191 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 21:58:02.093750 [12]: Controller: card:1 type
vbS is available priority:2 #users:0 same transponder:False
2008-07-09 21:58:02.093750 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-07-09 21:58:02.093750 [12]: Controller: found 1 available
2008-07-09 21:58:02.093750 [12]: card: CardTune 1 TV3 Latvia media:1:-1
2008-07-09 21:58:02.093750 [12]: card: Tune 1 to TV3 Latvia
2008-07-09 21:58:02.093750 [12]: card: user: media:1:-1 tune DVBS:tv: TV3 Latvia Freq:11958000 ONID:85 TSID:14 SID:6300 PMT:189C FTA:False LCN:191 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 21:58:02.156250 [12]: dvbs: Tune
VBS:tv: TV3 Latvia Freq:11958000 ONID:85 TSID:14 SID:6300 PMT:189C FTA:False LCN:191 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 21:58:02.156250 [12]: LNB low:9750 hi:10600 switch:11700
2008-07-09 21:58:02.171875 [12]: Channel modulation is set to ModNotSet
2008-07-09 21:58:02.171875 [12]: Channel FECRate is set to Rate3_4
2008-07-09 21:58:02.265625 [12]: dvb
ubmiting tunerequest Channel:TV3 Latvia subChannel:-1
2008-07-09 21:58:02.265625 [12]: dvb:Getting new subchannel
2008-07-09 21:58:02.265625 [12]: dvb:GetNewSubChannel:0 #0
2008-07-09 21:58:02.265625 [12]: dvb
ubmit tunerequest size:1 new:0
2008-07-09 21:58:02.265625 [12]: subch:0 OnBeforeTune
--------------------------------------------------------------------------------
And more strange is that while i was writing this email TV server LOG shows some strange scanning of other channels...... Note - MP is down.
2008-07-09 22:29:03.796875 [23]: card: Tuner locked: True
2008-07-09 22:29:03.796875 [23]: **************************************************
2008-07-09 22:29:03.796875 [23]: ***** SIGNAL LEVEL: 50, SIGNAL QUALITY: 100 *****
2008-07-09 22:29:03.796875 [23]: **************************************************
2008-07-09 22:29:03.812500 [7]: dvb:grab epg...
2008-07-09 22:31:32.390625 [EPG Update thread]: EPG: database updated for #0 RTR Planeta
2008-07-09 22:31:32.390625 [EPG Update thread]: user:epg remove
2008-07-09 22:31:32.390625 [EPG Update thread]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:31:32.390625 [EPG Update thread]: analog subch:0 Decompose()
2008-07-09 22:31:32.390625 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:31:32.390625 [EPG Update thread]: dvb
topGraph
2008-07-09 22:31:32.609375 [EPG Update thread]: Stopcard
2008-07-09 22:31:32.609375 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:31:32.812500 [17]: card: Tune 1 to TV1000 East
2008-07-09 22:31:32.812500 [17]: card: user: epg:1:-1 tune DVBS:tv: TV1000 East Freq:11977000 ONID:86 TSID:4 SID:2170 PMT:87A FTA:False LCN:90 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:31:32.875000 [17]: dvbs: Tune
VBS:tv: TV1000 East Freq:11977000 ONID:86 TSID:4 SID:2170 PMT:87A FTA:False LCN:90 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:31:32.875000 [17]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:31:32.875000 [17]: Channel modulation is set to ModNotSet
2008-07-09 22:31:32.875000 [17]: Channel FECRate is set to Rate3_4
2008-07-09 22:31:32.968750 [17]: dvb
ubmiting tunerequest Channel:TV1000 East subChannel:-1
2008-07-09 22:31:32.968750 [17]: dvb:Getting new subchannel
2008-07-09 22:31:32.968750 [17]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:31:32.984375 [17]: dvb
ubmit tunerequest size:1 new:0
2008-07-09 22:31:32.984375 [17]: subch:0 OnBeforeTune
2008-07-09 22:31:32.984375 [17]: dvb
ubmit tunerequest calling put_TuneRequest
2008-07-09 22:31:33.234375 [17]: dvb
ubmit tunerequest done calling put_TuneRequest
2008-07-09 22:31:33.234375 [17]: subch:0 OnAfterTune
2008-07-09 22:31:33.234375 [17]: subch:0 OnGraphStart
2008-07-09 22:31:33.234375 [17]: subch0:RunGraph
2008-07-09 22:31:33.234375 [17]: dvb: RunGraph
2008-07-09 22:31:33.515625 [17]: subch:0 OnGraphStart
2008-07-09 22:31:33.515625 [17]: subch:0 RunGraph: already running
2008-07-09 22:31:33.515625 [17]: subch:0 SetupPmtGrabber
id 87A sid:87A
2008-07-09 22:31:33.515625 [17]: subch:0 set pmt grabber pmt:87A sid:87A
2008-07-09 22:31:33.515625 [17]: subch:0 set ca grabber
2008-07-09 22:31:33.515625 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.531250 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.546875 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.578125 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.593750 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.671875 [13]: subch:OnCaReceived()
2008-07-09 22:31:33.671875 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.671875 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:31:33.671875 [13]: subch:0 SendPmt:87A 87A FFFFFFFF 2
2008-07-09 22:31:33.671875 [13]: Decode pmt
2008-07-09 22:31:33.671875 [13]: descr2 pid:87B len:6 9 4 9 f e8 83
2008-07-09 22:31:33.671875 [13]: Set descriptor data with length 10
2008-07-09 22:31:33.687500 [13]: descr2 pid:87C len:6 9 4 9 f e8 83
2008-07-09 22:31:33.687500 [13]: Set descriptor data with length 22
2008-07-09 22:31:33.687500 [13]: subch:0 SendPMT version:2 len:72 2170
2008-07-09 22:31:33.687500 [13]: subch:0 cam flags:True
2008-07-09 22:31:33.687500 [13]: subch:0 SetMpegPidMapping
2008-07-09 22:31:33.687500 [13]: subch:0 pid:0 pcr
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87A pmt
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87B video type:MPEG-2
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:87B video type:MPEG-2
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87C audio lang:ruseng type:MPEG-1
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:87C audio lang:ruseng type:MPEG-1
2008-07-09 22:31:33.687500 [13]: subch:0 pid:880 teletext type:6
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:880 teletext type:6
2008-07-09 22:31:33.687500 [13]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:31:33.687500 [13]: EMM #0 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:31:33.687500 [13]: ecm len:6 9 4 9 F E8 83
2008-07-09 22:31:33.687500 [13]: ecm len:6 9 4 9 F E8 83
2008-07-09 22:31:33.687500 [13]: ECM #0 CA:0x90F ECM:0x883 ID:0x0
2008-07-09 22:31:33.687500 [13]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:31:33.687500 [13]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:31:33.687500 [13]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:31:33.687500 [13]: mdplug: tp_id:4(0x4) sid:2170(0x87A) pmt_id:2170(0x87A)
2008-07-09 22:31:33.687500 [13]: mdplug: #0 CA_typ:2319(0x90F) ECM:2179(0x883) EMM:0xC0 provider:0(0x0)
2008-07-09 22:31:33.687500 [13]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:1 ECM_PID:2179(0x883)
2008-07-09 22:31:33.687500 [13]: subch:0 stop tif
2008-07-09 22:31:33.703125 [17]: card: tuned user: epg subchannel: 0
2008-07-09 22:31:33.703125 [17]: user:epg add
2008-07-09 22:31:33.718750 [17]: card: Tuner locked: True
2008-07-09 22:31:33.718750 [17]: **************************************************
2008-07-09 22:31:33.718750 [17]: ***** SIGNAL LEVEL: 48, SIGNAL QUALITY: 100 *****
2008-07-09 22:31:33.718750 [17]: **************************************************
2008-07-09 22:31:33.734375 [7]: dvb:grab epg...
2008-07-09 22:32:46.484375 [EPG Update thread]: EPG: database updated for #2 TV1000 East
2008-07-09 22:32:46.484375 [EPG Update thread]: user:epg remove
2008-07-09 22:32:46.484375 [EPG Update thread]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:32:46.484375 [EPG Update thread]: analog subch:0 Decompose()
2008-07-09 22:32:46.484375 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:32:46.484375 [EPG Update thread]: dvb
topGraph
2008-07-09 22:32:46.703125 [EPG Update thread]: Stopcard
2008-07-09 22:32:46.703125 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:33:02.828125 [22]: card: Tune 1 to 1st Baltic Ch. Latvia
2008-07-09 22:33:02.828125 [22]: card: user: epg:1:-1 tune DVBS:tv: 1st Baltic Ch. Latvia Freq:11843000 ONID:85 TSID:13 SID:6210 PMT:1842 FTA:False LCN:162 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:33:02.875000 [22]: dvbs: Tune
VBS:tv: 1st Baltic Ch. Latvia Freq:11843000 ONID:85 TSID:13 SID:6210 PMT:1842 FTA:False LCN:162 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:33:02.875000 [22]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:33:02.875000 [22]: Channel modulation is set to ModNotSet
2008-07-09 22:33:02.875000 [22]: Channel FECRate is set to Rate3_4
2008-07-09 22:33:02.984375 [22]: dvb
ubmiting tunerequest Channel:1st Baltic Ch. Latvia subChannel:-1
2008-07-09 22:33:02.984375 [22]: dvb:Getting new subchannel
2008-07-09 22:33:02.984375 [22]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:33:02.984375 [22]: dvb
ubmit tunerequest size:1 new:0
2008-07-09 22:33:02.984375 [22]: subch:0 OnBeforeTune
2008-07-09 22:33:02.984375 [22]: dvb
ubmit tunerequest calling put_TuneRequest
2008-07-09 22:33:03.234375 [22]: dvb
ubmit tunerequest done calling put_TuneRequest
2008-07-09 22:33:03.234375 [22]: subch:0 OnAfterTune
2008-07-09 22:33:03.234375 [22]: subch:0 OnGraphStart
2008-07-09 22:33:03.234375 [22]: subch0:RunGraph
2008-07-09 22:33:03.234375 [22]: dvb: RunGraph
2008-07-09 22:33:03.515625 [22]: subch:0 OnGraphStart
2008-07-09 22:33:03.515625 [22]: subch:0 RunGraph: already running
2008-07-09 22:33:03.515625 [22]: subch:0 SetupPmtGrabber
id 1842 sid:1842
2008-07-09 22:33:03.515625 [22]: subch:0 set pmt grabber pmt:1842 sid:1842
2008-07-09 22:33:03.515625 [22]: subch:0 set ca grabber
2008-07-09 22:33:03.515625 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.546875 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.562500 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.578125 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.609375 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.687500 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:33:03.687500 [13]: subch:0 SendPmt:wait for ca
2008-07-09 22:33:03.687500 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.687500 [13]: subch:OnCaReceived()
2008-07-09 22:33:03.703125 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.718750 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.750000 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.765625 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.781250 [6]: subch:0 SendPmt:1842 1842 FFFFFFFF 2
2008-07-09 22:33:03.781250 [6]: Decode pmt
2008-07-09 22:33:03.781250 [6]: descr2 pid:1843 len:11 9 f 5 0 f8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.781250 [6]: descr2 pid:1843 len:6 9 4 9 f f8 4b
2008-07-09 22:33:03.781250 [6]: Set descriptor data with length 6
2008-07-09 22:33:03.781250 [6]: descr2 pid:1844 len:11 9 f 5 0 f8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.781250 [6]: descr2 pid:1844 len:6 9 4 9 f f8 4b
2008-07-09 22:33:03.781250 [6]: Set descriptor data with length 6
2008-07-09 22:33:03.781250 [6]: subch:0 SendPMT version:2 len:89 6210
2008-07-09 22:33:03.781250 [6]: subch:0 cam flags:True
2008-07-09 22:33:03.781250 [6]: subch:0 SetMpegPidMapping
2008-07-09 22:33:03.781250 [6]: subch:0 pid:0 pcr
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1842 pmt
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1843 video type:MPEG-2
2008-07-09 22:33:03.781250 [6]: subch:0 map pid:1843 video type:MPEG-2
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1844 audio lang:rus type:MPEG-1
2008-07-09 22:33:03.781250 [6]: subch:0 map pid:1844 audio lang:rus type:MPEG-1
2008-07-09 22:33:03.781250 [22]: card: tuned user: epg subchannel: 0
2008-07-09 22:33:03.781250 [22]: user:epg add
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1845 audio lang:lat type:MPEG-1
2008-07-09 22:33:03.781250 [6]: emm len:18 9 16 5 0 E2 5A 13 1 20 14 3 1 94 0 14 3 2 2D 8 14 3 2 31 0
2008-07-09 22:33:03.781250 [6]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:33:03.781250 [6]: EMM #0 CA:0x500 EMM:0x25A ID:0x23100
2008-07-09 22:33:03.781250 [6]: EMM #1 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:33:03.796875 [6]: ecm len:11 9 F 5 0 F8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.796875 [6]: ecm len:6 9 4 9 F F8 4B
2008-07-09 22:33:03.796875 [6]: ecm len:11 9 F 5 0 F8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.796875 [6]: ecm len:6 9 4 9 F F8 4B
2008-07-09 22:33:03.796875 [6]: ECM #0 CA:0x500 ECM:0x1846 ID:0x23108
2008-07-09 22:33:03.796875 [6]: ECM #1 CA:0x90F ECM:0x184B ID:0x0
2008-07-09 22:33:03.796875 [6]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:33:03.796875 [6]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:33:03.796875 [6]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:33:03.796875 [6]: mdplug: tp_id:13(0xD) sid:6210(0x1842) pmt_id:6210(0x1842)
2008-07-09 22:33:03.796875 [6]: mdplug: #0 CA_typ:1280(0x500) ECM:6214(0x1846) EMM:0x0 provider:143624(0x23108)
2008-07-09 22:33:03.796875 [6]: mdplug: #1 CA_typ:2319(0x90F) ECM:6219(0x184B) EMM:0xC0 provider:0(0x0)
2008-07-09 22:33:03.796875 [6]: mdplug: #2 CA_typ:1280(0x500) ECM:0(0x0) EMM:0x25A provider:143616(0x23100)
2008-07-09 22:33:03.796875 [6]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:3 ECM_PID:6214(0x1846)
2008-07-09 22:33:03.796875 [6]: subch:0 stop tif
2008-07-09 22:33:03.812500 [22]: card: Tuner locked: True
2008-07-09 22:33:03.812500 [22]: **************************************************
2008-07-09 22:33:03.812500 [22]: ***** SIGNAL LEVEL: 49, SIGNAL QUALITY: 100 *****
2008-07-09 22:33:03.812500 [22]: **************************************************
2008-07-09 22:33:03.812500 [11]: dvb:grab epg...
============================================
ANY IDEAS of the problem ?

P.S.
:sorry: for putting LOG directly in message.
Cheers.
I'm using latest SVN of MP and TV server (4 jul). The same problem was there when using Jun SVN and main release.
MP works fine for X minutes (sometimes 3, sometimes 30min) then the first sign is that i see mouse on screen and them i'm in My TV screen (from fullscreen) with black TV screen. No channel selection button works (the best is that MP starts playing timeshift record).
Other functions seems to be continue to work. Simple restart of MP helps and i can continue to watch my TV.
-----------------------
In some cases looking at TV server LOG i found something like this.....
2008-07-08 20:22:52.828125 [HeartBeatMonitor]: Controller: Heartbeat Monitor - kicking idle user media
2008-07-08 20:22:52.828125 [HeartBeatMonitor]: Controller: StopTimeShifting 1
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: card: StopTimeShifting user:media sub:0
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: card: StopTimeShifting user:media sub:0
2008-07-08 20:22:52.843750 [HeartBeatMonitor]: user:media remove
------------------------
Latest SVN TV server log is something like this.
2008-07-09 22:18:30.828125 [12]: Controller: StopTimeShifting 1
2008-07-09 22:18:30.859375 [12]: card: StopTimeShifting user:media sub:0
2008-07-09 22:18:30.859375 [12]: card: StopTimeShifting user:media sub:0
2008-07-09 22:18:30.859375 [12]: user:media remove
2008-07-09 22:18:30.859375 [12]: Stopcard
2008-07-09 22:18:30.859375 [12]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:18:30.859375 [12]: analog subch:0 Decompose()
2008-07-09 22:18:30.875000 [12]: subch:0 StopTimeshifting()
2008-07-09 22:18:30.890625 [12]: tvcard:FreeAllSubChannels:
2008-07-09 22:18:30.890625 [12]: dvb
2008-07-09 22:18:31.109375 [12]: tvcard:FreeAllSubChannels:
2008-07-09 22:18:32.890625 [19]: card: Tune 1 to Viasat Sport
2008-07-09 22:18:32.890625 [19]: card: user: epg:1:-1 tune DVBS:tv: Viasat Sport Freq:11900000 ONID:85 TSID:18 SID:6800 PMT:1A90 FTA:False LCN:55 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:18:32.953125 [19]: dvbs: Tune
2008-07-09 22:18:32.953125 [19]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:18:32.953125 [19]: Channel modulation is set to ModNotSet
2008-07-09 22:18:32.953125 [19]: Channel FECRate is set to Rate3_4
2008-07-09 22:18:33.062500 [19]: dvb
2008-07-09 22:18:33.062500 [19]: dvb:Getting new subchannel
2008-07-09 22:18:33.062500 [19]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:18:33.062500 [19]: dvb
2008-07-09 22:18:33.062500 [19]: subch:0 OnBeforeTune
2008-07-09 22:18:33.062500 [19]: dvb
2008-07-09 22:18:33.312500 [19]: dvb
2008-07-09 22:18:33.312500 [19]: subch:0 OnAfterTune
2008-07-09 22:18:33.312500 [19]: subch:0 OnGraphStart
2008-07-09 22:18:33.312500 [19]: subch0:RunGraph
2008-07-09 22:18:33.312500 [19]: dvb: RunGraph
2008-07-09 22:18:33.593750 [19]: subch:0 OnGraphStart
2008-07-09 22:18:33.593750 [19]: subch:0 RunGraph: already running
2008-07-09 22:18:33.593750 [19]: subch:0 SetupPmtGrabber
2008-07-09 22:18:33.593750 [19]: subch:0 set pmt grabber pmt:1A90 sid:1A90
2008-07-09 22:18:33.593750 [19]: subch:0 set ca grabber
2008-07-09 22:18:33.593750 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.625000 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.640625 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.656250 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.687500 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.765625 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:18:33.765625 [13]: subch:0 SendPmt:wait for ca
2008-07-09 22:18:33.765625 [13]: subch:OnCaReceived()
2008-07-09 22:18:33.765625 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.781250 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.796875 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.828125 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.859375 [19]: subch:0 wait for pmt 1A90
2008-07-09 22:18:33.859375 [6]: subch:0 SendPmt:1A90 1A90 FFFFFFFF 2
2008-07-09 22:18:33.859375 [6]: Decode pmt
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A91 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: Set descriptor data with length 6
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A93 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: Set descriptor data with length 6
2008-07-09 22:18:33.859375 [6]: descr2 pid:1A92 len:6 9 4 9 f fa 99
2008-07-09 22:18:33.859375 [6]: subch:0 SendPMT version:2 len:58 6800
2008-07-09 22:18:33.859375 [6]: subch:0 cam flags:True
2008-07-09 22:18:33.859375 [6]: subch:0 SetMpegPidMapping
2008-07-09 22:18:33.859375 [6]: subch:0 pid:0 pcr
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A90 pmt
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A91 video type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 map pid:1A91 video type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A93 audio lang:eng type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 map pid:1A93 audio lang:eng type:MPEG-2
2008-07-09 22:18:33.859375 [6]: subch:0 pid:1A92 audio lang:rus type:MPEG-1
2008-07-09 22:18:33.859375 [6]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:18:33.859375 [6]: emm len:18 9 16 5 0 E2 5A 13 1 20 14 3 1 94 0 14 3 2 2D 8 14 3 2 31 0
2008-07-09 22:18:33.859375 [6]: EMM #0 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:18:33.859375 [6]: EMM #1 CA:0x500 EMM:0x25A ID:0x23100
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ecm len:6 9 4 9 F FA 99
2008-07-09 22:18:33.859375 [6]: ECM #0 CA:0x90F ECM:0x1A99 ID:0x0
2008-07-09 22:18:33.859375 [6]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:18:33.859375 [6]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:18:33.859375 [6]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:18:33.859375 [6]: mdplug: tp_id:18(0x12) sid:6800(0x1A90) pmt_id:6800(0x1A90)
2008-07-09 22:18:33.859375 [6]: mdplug: #0 CA_typ:2319(0x90F) ECM:6809(0x1A99) EMM:0xC0 provider:0(0x0)
2008-07-09 22:18:33.859375 [6]: mdplug: #1 CA_typ:1280(0x500) ECM:0(0x0) EMM:0x25A provider:143616(0x23100)
2008-07-09 22:18:33.875000 [6]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:2 ECM_PID:6809(0x1A99)
2008-07-09 22:18:33.875000 [6]: subch:0 stop tif
2008-07-09 22:18:33.875000 [19]: card: tuned user: epg subchannel: 0
2008-07-09 22:18:33.875000 [19]: user:epg add
2008-07-09 22:18:33.890625 [19]: card: Tuner locked: True
2008-07-09 22:18:33.890625 [19]: **************************************************
2008-07-09 22:18:33.890625 [19]: ***** SIGNAL LEVEL: 50, SIGNAL QUALITY: 100 *****
2008-07-09 22:18:33.890625 [19]: **************************************************
2008-07-09 22:18:33.906250 [7]: dvb:grab epg...
2008-07-09 22:18:41.218750 [PowerEventThread]: TV service PowerEventThread 49380
2008-07-09 22:18:41.218750 [PowerEventThread]: TV service PowerEventThread 49380
----------------------------
What is strange is that I was watching totaly different channel:
Log:
2008-07-09 21:58:02.078125 [12]: Controller: find free card for channel TV3 Latvia
2008-07-09 21:58:02.078125 [12]: Controller: got 1 tuning details for TV3 Latvia
2008-07-09 21:58:02.078125 [12]: Controller: channel #1 DVBS:tv: TV3 Latvia Freq:11958000 ONID:85 TSID:14 SID:6300 PMT:189C FTA:False LCN:191 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 21:58:02.093750 [12]: Controller: card:1 type
2008-07-09 21:58:02.093750 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-07-09 21:58:02.093750 [12]: Controller: found 1 available
2008-07-09 21:58:02.093750 [12]: card: CardTune 1 TV3 Latvia media:1:-1
2008-07-09 21:58:02.093750 [12]: card: Tune 1 to TV3 Latvia
2008-07-09 21:58:02.093750 [12]: card: user: media:1:-1 tune DVBS:tv: TV3 Latvia Freq:11958000 ONID:85 TSID:14 SID:6300 PMT:189C FTA:False LCN:191 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 21:58:02.156250 [12]: dvbs: Tune
2008-07-09 21:58:02.156250 [12]: LNB low:9750 hi:10600 switch:11700
2008-07-09 21:58:02.171875 [12]: Channel modulation is set to ModNotSet
2008-07-09 21:58:02.171875 [12]: Channel FECRate is set to Rate3_4
2008-07-09 21:58:02.265625 [12]: dvb
2008-07-09 21:58:02.265625 [12]: dvb:Getting new subchannel
2008-07-09 21:58:02.265625 [12]: dvb:GetNewSubChannel:0 #0
2008-07-09 21:58:02.265625 [12]: dvb
2008-07-09 21:58:02.265625 [12]: subch:0 OnBeforeTune
--------------------------------------------------------------------------------
And more strange is that while i was writing this email TV server LOG shows some strange scanning of other channels...... Note - MP is down.
2008-07-09 22:29:03.796875 [23]: card: Tuner locked: True
2008-07-09 22:29:03.796875 [23]: **************************************************
2008-07-09 22:29:03.796875 [23]: ***** SIGNAL LEVEL: 50, SIGNAL QUALITY: 100 *****
2008-07-09 22:29:03.796875 [23]: **************************************************
2008-07-09 22:29:03.812500 [7]: dvb:grab epg...
2008-07-09 22:31:32.390625 [EPG Update thread]: EPG: database updated for #0 RTR Planeta
2008-07-09 22:31:32.390625 [EPG Update thread]: user:epg remove
2008-07-09 22:31:32.390625 [EPG Update thread]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:31:32.390625 [EPG Update thread]: analog subch:0 Decompose()
2008-07-09 22:31:32.390625 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:31:32.390625 [EPG Update thread]: dvb
2008-07-09 22:31:32.609375 [EPG Update thread]: Stopcard
2008-07-09 22:31:32.609375 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:31:32.812500 [17]: card: Tune 1 to TV1000 East
2008-07-09 22:31:32.812500 [17]: card: user: epg:1:-1 tune DVBS:tv: TV1000 East Freq:11977000 ONID:86 TSID:4 SID:2170 PMT:87A FTA:False LCN:90 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearV InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:31:32.875000 [17]: dvbs: Tune
2008-07-09 22:31:32.875000 [17]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:31:32.875000 [17]: Channel modulation is set to ModNotSet
2008-07-09 22:31:32.875000 [17]: Channel FECRate is set to Rate3_4
2008-07-09 22:31:32.968750 [17]: dvb
2008-07-09 22:31:32.968750 [17]: dvb:Getting new subchannel
2008-07-09 22:31:32.968750 [17]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:31:32.984375 [17]: dvb
2008-07-09 22:31:32.984375 [17]: subch:0 OnBeforeTune
2008-07-09 22:31:32.984375 [17]: dvb
2008-07-09 22:31:33.234375 [17]: dvb
2008-07-09 22:31:33.234375 [17]: subch:0 OnAfterTune
2008-07-09 22:31:33.234375 [17]: subch:0 OnGraphStart
2008-07-09 22:31:33.234375 [17]: subch0:RunGraph
2008-07-09 22:31:33.234375 [17]: dvb: RunGraph
2008-07-09 22:31:33.515625 [17]: subch:0 OnGraphStart
2008-07-09 22:31:33.515625 [17]: subch:0 RunGraph: already running
2008-07-09 22:31:33.515625 [17]: subch:0 SetupPmtGrabber
2008-07-09 22:31:33.515625 [17]: subch:0 set pmt grabber pmt:87A sid:87A
2008-07-09 22:31:33.515625 [17]: subch:0 set ca grabber
2008-07-09 22:31:33.515625 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.531250 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.546875 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.578125 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.593750 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.671875 [13]: subch:OnCaReceived()
2008-07-09 22:31:33.671875 [17]: subch:0 wait for pmt 87A
2008-07-09 22:31:33.671875 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:31:33.671875 [13]: subch:0 SendPmt:87A 87A FFFFFFFF 2
2008-07-09 22:31:33.671875 [13]: Decode pmt
2008-07-09 22:31:33.671875 [13]: descr2 pid:87B len:6 9 4 9 f e8 83
2008-07-09 22:31:33.671875 [13]: Set descriptor data with length 10
2008-07-09 22:31:33.687500 [13]: descr2 pid:87C len:6 9 4 9 f e8 83
2008-07-09 22:31:33.687500 [13]: Set descriptor data with length 22
2008-07-09 22:31:33.687500 [13]: subch:0 SendPMT version:2 len:72 2170
2008-07-09 22:31:33.687500 [13]: subch:0 cam flags:True
2008-07-09 22:31:33.687500 [13]: subch:0 SetMpegPidMapping
2008-07-09 22:31:33.687500 [13]: subch:0 pid:0 pcr
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87A pmt
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87B video type:MPEG-2
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:87B video type:MPEG-2
2008-07-09 22:31:33.687500 [13]: subch:0 pid:87C audio lang:ruseng type:MPEG-1
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:87C audio lang:ruseng type:MPEG-1
2008-07-09 22:31:33.687500 [13]: subch:0 pid:880 teletext type:6
2008-07-09 22:31:33.687500 [13]: subch:0 map pid:880 teletext type:6
2008-07-09 22:31:33.687500 [13]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:31:33.687500 [13]: EMM #0 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:31:33.687500 [13]: ecm len:6 9 4 9 F E8 83
2008-07-09 22:31:33.687500 [13]: ecm len:6 9 4 9 F E8 83
2008-07-09 22:31:33.687500 [13]: ECM #0 CA:0x90F ECM:0x883 ID:0x0
2008-07-09 22:31:33.687500 [13]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:31:33.687500 [13]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:31:33.687500 [13]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:31:33.687500 [13]: mdplug: tp_id:4(0x4) sid:2170(0x87A) pmt_id:2170(0x87A)
2008-07-09 22:31:33.687500 [13]: mdplug: #0 CA_typ:2319(0x90F) ECM:2179(0x883) EMM:0xC0 provider:0(0x0)
2008-07-09 22:31:33.687500 [13]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:1 ECM_PID:2179(0x883)
2008-07-09 22:31:33.687500 [13]: subch:0 stop tif
2008-07-09 22:31:33.703125 [17]: card: tuned user: epg subchannel: 0
2008-07-09 22:31:33.703125 [17]: user:epg add
2008-07-09 22:31:33.718750 [17]: card: Tuner locked: True
2008-07-09 22:31:33.718750 [17]: **************************************************
2008-07-09 22:31:33.718750 [17]: ***** SIGNAL LEVEL: 48, SIGNAL QUALITY: 100 *****
2008-07-09 22:31:33.718750 [17]: **************************************************
2008-07-09 22:31:33.734375 [7]: dvb:grab epg...
2008-07-09 22:32:46.484375 [EPG Update thread]: EPG: database updated for #2 TV1000 East
2008-07-09 22:32:46.484375 [EPG Update thread]: user:epg remove
2008-07-09 22:32:46.484375 [EPG Update thread]: tvcard:FreeSubChannel:1 #0
2008-07-09 22:32:46.484375 [EPG Update thread]: analog subch:0 Decompose()
2008-07-09 22:32:46.484375 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:32:46.484375 [EPG Update thread]: dvb
2008-07-09 22:32:46.703125 [EPG Update thread]: Stopcard
2008-07-09 22:32:46.703125 [EPG Update thread]: tvcard:FreeAllSubChannels:
2008-07-09 22:33:02.828125 [22]: card: Tune 1 to 1st Baltic Ch. Latvia
2008-07-09 22:33:02.828125 [22]: card: user: epg:1:-1 tune DVBS:tv: 1st Baltic Ch. Latvia Freq:11843000 ONID:85 TSID:13 SID:6210 PMT:1842 FTA:False LCN:162 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-09 22:33:02.875000 [22]: dvbs: Tune
2008-07-09 22:33:02.875000 [22]: LNB low:9750 hi:10600 switch:11700
2008-07-09 22:33:02.875000 [22]: Channel modulation is set to ModNotSet
2008-07-09 22:33:02.875000 [22]: Channel FECRate is set to Rate3_4
2008-07-09 22:33:02.984375 [22]: dvb
2008-07-09 22:33:02.984375 [22]: dvb:Getting new subchannel
2008-07-09 22:33:02.984375 [22]: dvb:GetNewSubChannel:0 #0
2008-07-09 22:33:02.984375 [22]: dvb
2008-07-09 22:33:02.984375 [22]: subch:0 OnBeforeTune
2008-07-09 22:33:02.984375 [22]: dvb
2008-07-09 22:33:03.234375 [22]: dvb
2008-07-09 22:33:03.234375 [22]: subch:0 OnAfterTune
2008-07-09 22:33:03.234375 [22]: subch:0 OnGraphStart
2008-07-09 22:33:03.234375 [22]: subch0:RunGraph
2008-07-09 22:33:03.234375 [22]: dvb: RunGraph
2008-07-09 22:33:03.515625 [22]: subch:0 OnGraphStart
2008-07-09 22:33:03.515625 [22]: subch:0 RunGraph: already running
2008-07-09 22:33:03.515625 [22]: subch:0 SetupPmtGrabber
2008-07-09 22:33:03.515625 [22]: subch:0 set pmt grabber pmt:1842 sid:1842
2008-07-09 22:33:03.515625 [22]: subch:0 set ca grabber
2008-07-09 22:33:03.515625 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.546875 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.562500 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.578125 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.609375 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.687500 [13]: subch:0 OnPMTReceived() True
2008-07-09 22:33:03.687500 [13]: subch:0 SendPmt:wait for ca
2008-07-09 22:33:03.687500 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.687500 [13]: subch:OnCaReceived()
2008-07-09 22:33:03.703125 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.718750 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.750000 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.765625 [22]: subch:0 wait for pmt 1842
2008-07-09 22:33:03.781250 [6]: subch:0 SendPmt:1842 1842 FFFFFFFF 2
2008-07-09 22:33:03.781250 [6]: Decode pmt
2008-07-09 22:33:03.781250 [6]: descr2 pid:1843 len:11 9 f 5 0 f8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.781250 [6]: descr2 pid:1843 len:6 9 4 9 f f8 4b
2008-07-09 22:33:03.781250 [6]: Set descriptor data with length 6
2008-07-09 22:33:03.781250 [6]: descr2 pid:1844 len:11 9 f 5 0 f8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.781250 [6]: descr2 pid:1844 len:6 9 4 9 f f8 4b
2008-07-09 22:33:03.781250 [6]: Set descriptor data with length 6
2008-07-09 22:33:03.781250 [6]: subch:0 SendPMT version:2 len:89 6210
2008-07-09 22:33:03.781250 [6]: subch:0 cam flags:True
2008-07-09 22:33:03.781250 [6]: subch:0 SetMpegPidMapping
2008-07-09 22:33:03.781250 [6]: subch:0 pid:0 pcr
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1842 pmt
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1843 video type:MPEG-2
2008-07-09 22:33:03.781250 [6]: subch:0 map pid:1843 video type:MPEG-2
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1844 audio lang:rus type:MPEG-1
2008-07-09 22:33:03.781250 [6]: subch:0 map pid:1844 audio lang:rus type:MPEG-1
2008-07-09 22:33:03.781250 [22]: card: tuned user: epg subchannel: 0
2008-07-09 22:33:03.781250 [22]: user:epg add
2008-07-09 22:33:03.781250 [6]: subch:0 pid:1845 audio lang:lat type:MPEG-1
2008-07-09 22:33:03.781250 [6]: emm len:18 9 16 5 0 E2 5A 13 1 20 14 3 1 94 0 14 3 2 2D 8 14 3 2 31 0
2008-07-09 22:33:03.781250 [6]: emm len:6 9 4 9 F E0 C0
2008-07-09 22:33:03.781250 [6]: EMM #0 CA:0x500 EMM:0x25A ID:0x23100
2008-07-09 22:33:03.781250 [6]: EMM #1 CA:0x90F EMM:0xC0 ID:0x0
2008-07-09 22:33:03.796875 [6]: ecm len:11 9 F 5 0 F8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.796875 [6]: ecm len:6 9 4 9 F F8 4B
2008-07-09 22:33:03.796875 [6]: ecm len:11 9 F 5 0 F8 46 10 1 0 13 1 20 14 3 2 31 8
2008-07-09 22:33:03.796875 [6]: ecm len:6 9 4 9 F F8 4B
2008-07-09 22:33:03.796875 [6]: ECM #0 CA:0x500 ECM:0x1846 ID:0x23108
2008-07-09 22:33:03.796875 [6]: ECM #1 CA:0x90F ECM:0x184B ID:0x0
2008-07-09 22:33:03.796875 [6]: mdplug: MDAPIProvID.xml Filling out MDAPIProvID False
2008-07-09 22:33:03.796875 [6]: mdplug: MDAPIProvID.xml mode used = Channel:False Provider:False Ca_typ:False
2008-07-09 22:33:03.796875 [6]: mdapi: Attempting to add entry to MDAPIProvID.xml
2008-07-09 22:33:03.796875 [6]: mdplug: tp_id:13(0xD) sid:6210(0x1842) pmt_id:6210(0x1842)
2008-07-09 22:33:03.796875 [6]: mdplug: #0 CA_typ:1280(0x500) ECM:6214(0x1846) EMM:0x0 provider:143624(0x23108)
2008-07-09 22:33:03.796875 [6]: mdplug: #1 CA_typ:2319(0x90F) ECM:6219(0x184B) EMM:0xC0 provider:0(0x0)
2008-07-09 22:33:03.796875 [6]: mdplug: #2 CA_typ:1280(0x500) ECM:0(0x0) EMM:0x25A provider:143616(0x23100)
2008-07-09 22:33:03.796875 [6]: mdplug: Send channel change to MDAPI filter Ca_Id:0 CA_Nr:3 ECM_PID:6214(0x1846)
2008-07-09 22:33:03.796875 [6]: subch:0 stop tif
2008-07-09 22:33:03.812500 [22]: card: Tuner locked: True
2008-07-09 22:33:03.812500 [22]: **************************************************
2008-07-09 22:33:03.812500 [22]: ***** SIGNAL LEVEL: 49, SIGNAL QUALITY: 100 *****
2008-07-09 22:33:03.812500 [22]: **************************************************
2008-07-09 22:33:03.812500 [11]: dvb:grab epg...
============================================
ANY IDEAS of the problem ?
P.S.
:sorry: for putting LOG directly in message.
Cheers.