Still unable to tune high-band channels with Nova-SE2 (1 Viewer)

[milagroso]

Hello devs,

I would be glad if we could fix this issue before the 1.0 release goes out. There still seems to remain a bug in that part of TVS where the card is triggered to send the 22kHz signal in order to tune to high-band channels (over 11700 MHz on Astra) on my Hauppauge NOVA-SE2 (AFAIK equal to Nova-S-Plus). I keep trying current a svn every month or so since february but without success, so again I supply a logfile which demonstrates the issue. The current behaviour with (see system spec plus this):

• RC2 (no svn)
• WMP11
• new dvbfix_2008_07
• newest Hauppauge driver 4.6b

is that I can tune in a high-band channel (e.g. Das Erste) once (see log at 18:37:36), but not a second time (18:38:13). I'm behind a multiswitch which seems to be switched in the old way (using the 22kHz tone iso using diseqc, but I'm not 100% sure about this).

The annoying thing about this is that all this works in other configurations:
- my old/productive configuration (svn17378 + old Hauppauge driver wdm88x_2105_23074)
- the latest Hauppauge driver with the Hauppauge SW WinTV

I asked meanwhile several Nova-SE2 users in the forum about this. Until now I did not get any response of a neatly working system with this card using current versions of TVS.

So please, if any dev could give me a hint, where to dig in order to analyse this behaviour. It should be possible to isolate to root cause for this bug.

many thanks in advance

 

[Ragendorf]

Could please any Developer have a look at this Problem? Or give Milagroso a hint?

I´m one of the People who have the same Problem. I want tu use 2 Cards behind a Multiswitch, but it doesn´t work correctly (like Milagroso subscribed it). I want to use my two Cards, please....

Think we will donate a Hauppauge-Card if it´s neccessary, just say it. If it´s neccessary to reproduce the Bug, I will collect some Money for the Card.

Sorry for bad English..., hope any Dev will reply.

Best regards.

--Jens--


TV-Server Version:
MediaPortal Version: MediaPortal at the Moment RC2
MediaPortal Skin: Blue Two Wide
Windows Version: Windows XP Home SP3
CPU Type: AMD Athlon 64 X2 4850e
HDD: Samsung 500 GB Spinpoint F1
Memory: 2 GB OCZ DDR2-800
Motherboard: GA-MA78GM-S2H
Video Card: Radeon HD3200 (onboard)
Video Card Driver: Catalyst 8.5
Sound Card: OnBoard Sound Realtek® ALC888
Sound Card AC3:
Sound Card Driver:
1. TV Card: Hauppauge WinTV Nova 2 SE
1. TV Card Type: DVB-S
1. TV Card Driver: aktuellster Treiber Stand 31.05.2008
2. TV Card:
2. TV Card Type:
2. TV Card Driver:
3. TV Card:
3. TV Card Type:
3. TV Card Driver:
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
MPEG2 Video Codec: MPV (MP-default)
MPEG2 Audio Codec: MPA (MP-default)
h.264 Video Codec:
Satelite/CableTV Provider: Astra 19,2 Ost
HTPC Case: GMC Toast
Cooling: AMD Boxed
Power Supply: BeQuiet! 350W Straight Power
Remote: Microsoft MCE Remote 2002
TV: Panasonic 106cm Plasma TH-42PV7F/S
TV - HTPC Connection: HDMI

 

[blaudden]

milagroso: just noticed you have db connection errors in your error.log file

The first attempt -> ok

2008-07-26 18:37:32.718750 [10]: Hauppauge: DVB-S card found!
2008-07-26 18:37:32.718750 [10]: Hauppauge card detected
2008-07-26 18:37:32.718750 [10]: dvb:  AddTransportStreamFiltersToGraph
2008-07-26 18:37:32.718750 [10]:     add BDA MPEG2 Transport Information Filter filter
2008-07-26 18:37:33.718750 [10]:     pinTif:name:IB Input [3/1] Direction:Input Connected:False
2008-07-26 18:37:33.718750 [10]:     Connect tif and mpeg2 sections and tables
2008-07-26 18:37:33.718750 [10]: dvb:try tif:name:1 [4/1] Direction:Output Connected:False
2008-07-26 18:37:33.734375 [10]:     tif connected
2008-07-26 18:37:33.734375 [10]: dvb: GetTunerSignalStatistics()
2008-07-26 18:37:33.734375 [10]: LNB low:9750 hi:10600 switch:11700
2008-07-26 18:37:33.750000 [10]:   Channel modulation is set to ModNotSet
2008-07-26 18:37:33.750000 [10]:   Channel FECRate is set to Rate3_4
2008-07-26 18:37:33.750000 [10]: Hauppauge: setdiseqc: 0xE0 0x10 0x38 0xFF 
2008-07-26 18:37:33.750000 [10]: Hauppauge: setdiseqc returned:8007001F
magnus:                                                                                                    ^^ Ok, even if diseqc fails!!

2008-07-26 18:37:33.859375 [10]: dvb:Submiting tunerequest Channel:Das Erste subChannel:-1 
<snip>
2008-07-26 18:37:35.890625 [10]: subch:0 wait for pmt 64
2008-07-26 18:37:35.906250 [4]: subch:0 OnPMTReceived() True
2008-07-26 18:37:35.906250 [4]: subch:0 SendPmt:6DCA 6DCA FFFFFFFF 1C
2008-07-26 18:37:35.921875 [4]: Decode pmt
2008-07-26 18:37:35.921875 [10]: subch:0 wait for pmt 64
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 3
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 6
2008-07-26 18:37:35.921875 [4]: PROBLEM : descriptor lengths dont match 3 9
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 6
2008-07-26 18:37:35.921875 [4]: PROBLEM : descriptor lengths dont match 3 9
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 3
2008-07-26 18:37:35.921875 [4]: PROBLEM : descriptor lengths dont match 7 10
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 6
2008-07-26 18:37:35.921875 [4]: PROBLEM : descriptor lengths dont match 3 9
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 3
2008-07-26 18:37:35.921875 [4]: Set descriptor data with length 3
2008-07-26 18:37:35.937500 [4]: Set descriptor data with length 3
2008-07-26 18:37:35.937500 [4]: Set descriptor data with length 3
2008-07-26 18:372:35.937500 [4]: subch:0 SendPMT version:28 len:196 28106

Second attempt -> fail

2008-07-26 18:37:58.562500 [10]: card: Tune 1 to Das Erste
2008-07-26 18:37:58.562500 [10]: card: user: setuptv:1:-1 tune DVBS:tv:ARD Das Erste Freq:11835000 ONID:1 TSID:1101 SID:28106 PMT:64 FTA:True LCN:52 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-26 18:37:58.625000 [10]: dvbs:  Tune:DVBS:tv:ARD Das Erste Freq:11835000 ONID:1 TSID:1101 SID:28106 PMT:64 FTA:True LCN:52 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
2008-07-26 18:37:58.625000 [10]: LNB low:9750 hi:10600 switch:11700
2008-07-26 18:37:58.625000 [10]:   Channel modulation is set to ModNotSet
2008-07-26 18:37:58.625000 [10]:   Channel FECRate is set to Rate3_4
2008-07-26 18:37:58.625000 [10]: Hauppauge: setdiseqc: 0xE0 0x10 0x38 0xFF 
2008-07-26 18:37:58.765625 [10]: Hauppauge: setdiseqc returned:0
<snip>
2008-07-26 18:38:13.281250 [10]: card2:setuptv 1 0
2008-07-26 18:38:13.281250 [10]: Controller: setuptv 1 0
2008-07-26 18:38:13.281250 [10]: control2:setuptv 1 0
2008-07-26 18:38:13.281250 [10]: Controller: delete timeshift files H:\Timeshifting\live1-0.ts
2008-07-26 18:38:13.281250 [10]: card: StartTimeShifting 1 H:\Timeshifting\live1-0.ts 
2008-07-26 18:38:13.296875 [10]: card: WaitForUnScrambledSignal
2008-07-26 18:38:13.296875 [10]: card:   return not scrambled
2008-07-26 18:38:13.296875 [10]: subch:0 SetTimeShiftFileName no pmt received. Timeshifting failed

Have you tried differehnt diseqc settings? Maybe none at all?

Both of you have exactly the same problem with a diseqc switch in between? Logs from more than one user could be good to have.



Btw: please read instructions how to submit logs. Use the Mediaportal logs collector and remove all logs before you "test/reproduce". That way the logs will be much smaller, with just the relevant parts included.

 

[blaudden]

Could you please try with todays SVN version, it includes patches for Mantis bug#1523 and bug#1527 that could be related to your problem.

0001523: OnGraphStart() called twice during tuning process - MediaPortal Bugtracker
0001527: Random failures to tune scrambled DVB-S channel (Twinhan) - MediaPortal Bugtracker

You'll find the download in the forum thread https://forum.team-mediaportal.com/...t-08-16-2008-10-57h-revision-19936-rar-43808/

 

[milagroso]

Still no change, but additional clues

Hello blaudden,

Thank You for Your input. I tried this new svn out, still no success. However maybe this test runs give some additional clue, because I could reproduce the following sequence many times:

1. Reboot, wait until everything is up and running
2. Start TV config, goto Manual Control
3. Select a high band channel (Das Erste), start Timeshift: It works
4. Stop Timeshift.
5. Start Timeshift again: 'unable to create graph'
6. Goto TV servers, LNB setup. Check 'Override default LNB frequencies'. Enter the default ones (9750, 10600, 11700). (This was a hint from GregorV)
7. Goto Manaual Control (takes 3-4 secs, reinit of card is probably done)
8. Select a high band channel (Das Erste), start Timeshift: It works
9. Stop Timeshift and start it again. It works! From now on it always worked tuning a new channel. I can even go back to Uncheck 'Override default LNB frequencies' and it still works.

Another analysis shows that if I omit steps 2-6, i.e. doing the second init of the card directly, it (surprisingly) does not help. I have no logical explanation for this.

So my current clue is that the card has to be initialized, run into its first problem, then be reinitialized and only then it works as it should. This sounds a bit strange to me, so maybe somebody else could try to verify this behaviour.

I'll append my current log files.

BTW: I read the bug posting rules. I have posted many help calls containing the required log file format (created by the test tool) but never got any reaction from any dev. Therefore I admit that I lost my motivation somehow. However I tried to keep this log clean from any unnecessary information.

regards from Stuttgart

 

[blaudden]

Hi,


the logs look better now, but unfortunately I can't really see directly what could be wrong.

1. It seems like your problem is rather random, i.e it does not fail every time. Or does it fail every time after it's failed the first time?

2. The first thing that draws my attention is the line that says "2008-08-18 22:54:18.859375 [10]: Timedout waiting for PMT after 5 seconds. Increase the PMT timeout value?". This could mean that nothing is received, but it could also be that the default 5 second timeout value is too short. So could you please try to increase the PMT timeout value on "General settings -> Scan -> PMT:" from 5 to 30 seconds and then try again. Any difference?

3. The second thing to check is TsWriter.log file, it's written by the dll that actually receives the data from the driver, the tv service "just controls it". So please check in there if any error messages is written to it. Or for that matter anything indicating that data is receieved at all. If the TsWriter dll would have got any valid data, it should have called the 'OnPmtReceived' function and we should have seen that printout in the log. But apparently it is never called.

4. I will add some additional debug logs and send you a new tvlibrary.dll to install. Will send it later. Let's assume it's something to do with the LNB Low, LNB High and LNB switch values and we'll look at that to start with. Especially since the "Signal quality" always shows 0 when this error occurs.

5. Could you please use the "MediaPortal Logs Collector" aka "Mediaportal Watchdog" to collect the log files _next_ time. The nice thing with that is it will first remove any logs so we don't have to wade through page up and page down with unrelated log messages(altough your was quite short, it still had all the channel scan and CREATE TABLE's etc. in it).
Use it like this:
- Stop TvService, in cmd.exe "net stop tvservice"
- Copy in the new dll
- Start Mediaportal Logs Collector and run "Perform action necessary before testing"
- don't start up MediaPortal as it says, instead start the "TvServer Configuration" tool. It will prompt you about starting the service. Select "yes" Use the Manual control as you did to trigger the failure. You can "start" and "stop" like you did plus it's also possible to "zap", by selecting another channel in the dropdown and then press "Start timeshift".
- Finally, use the Logs Collector and press "Perform action necessary after testing" to collect the logs. As I mentioned above, there could be other logs of interest.
- Then upload the zip file with logs.

/ Magnus

Found two interesting links:

The first one appears to come from Hauupauge and is their "example" of how things hould be done. As the code works now, it submits the DiseqC commands _before_ tun request but maybe it should be after. Worth a try.

Nova-S-Plus: DiSEqC specs in BDA drivers needed - Hauppauge UK Forum

The second one is what I believe is your channel list you get from the ARD provider. Can you try to tune to other channels on the same transponder? Other "high" channels?
Packages: ARD Digital - KingOfSat

 

[milagroso]

Hello blaudden,

I'm glad that You take some time for this issue, I know that out there are some other users that are thankful, too.

While waiting for Your tvlibrary.dll, some responses:

1. As I said, during last nights test runs it was pretty reproducable. The first tune after reboot worked, all subsequent ones (to high band channels) didn't, until I did that reinit trick.

2. I noticed that funny log line, too - the default value fr PMT is 10 seconds, but during my many test runs I found out that my card has no problem when reducing it to 3-5 seconds - will say, either it locks on a pmt after 5 secs or it never will. Therefore I don't see an issue here (for my exemplar card). Increasing the value only delayed the appearance of any error message a la 'no graph'.

3. I still have that log file. At the moment while it is unsuccessful tuning in it shows these lines:

18-08-2008 22:54:04.187 analyzer: reset
18-08-2008 22:54:04.187 cagrabber: reset
18-08-2008 22:54:04.437 epg: reset
18-08-2008 22:54:04.437 epg:ResetEPG()
18-08-2008 22:54:04.437 mhw reset
18-08-2008 22:54:07.218 CMpTsFilter::Pause()
18-08-2008 22:54:13.859 CMpTsFilter::Run()
18-08-2008 22:54:13.859 pmtgrabber: set callback:d7fff8
18-08-2008 22:54:13.859 pmtgrabber: grab pmt:64 sid:6dca
18-08-2008 22:54:18.937 del m_pVideoAnalyzer
18-08-2008 22:54:18.937 analyzer: reset
18-08-2008 22:54:18.937 del m_pPmtGrabber
18-08-2008 22:54:18.937 del m_pRecorder
18-08-2008 22:54:18.937 del m_pTimeShifting
18-08-2008 22:54:18.937 del m_pTeletextGrabber
18-08-2008 22:54:18.937 del m_pCaGrabber
18-08-2008 22:54:18.937 del done...
18-08-2008 22:54:18.937 CMpTsFilter::Pause()
18-08-2008 22:54:18.937 CMpTsFilter::Stop()

4. That sounds fine to me. You maybe get in touch with GregorV which is currently also working on the tvlibrary, see this thread: https://forum.team-mediaportal.com/...-not-always-work-32004/index3.html#post295135

5. I remember that I used this tool previously. However one thought: when I stop and start the tvservice, then I init the card twice? I hope that this won't bring in any side effects.

6. I will zap to other channels inside the same bouquet, if You mean that. I believe to remember that this did no difference, but I can enrich the log files with this test case.

kind regards from rainy Stuttgart

 

[blaudden]

Hi again,

have now writte a patch that will try all different diseqc settings when the wait for PMT timeout fails - it's only 15 values possible(only _4_ bits according to the comments in the code). Also added quite a lot of printouts, that's why I send you also the Tvlibrary.Interfaces.dll

Patch is in the .rar

/ Magnus

 

[GregorV]

hi blaudden,

I checked your new dlls and attached the tv.log.
Besides the active grabber you can see my tune request for n-tv (high-band) and a lot of different setting are tried by your dll but finally no success. After the error message I tuned to DW-TV (low- band) and got sound and video.
I just used the TV-Setup application - so no MediaPortal involved.

My suggestion is that this is somewhere in the DirectShow Library. On my machine I see a different behaviour, when I just exchange the function for finding the tuner pin in the Hauppauge.cs:

public Hauppauge(IBaseFilter tunerFilter, IBaseFilter analyzerFilter)
{
//IPin pin = DsFindPin.ByDirection(tunerFilter, PinDirection.Input, 0);
IPin pin = DsFindPin.ByName(tunerFilter, "Input0");
...

This functions seems to set the Hauppauge drivers into a '22KHz non operational mode' (sometimes).
I read about issues with 'ReleasComObject' in .NET which is used in the above functions.
I am really not an expert in DS, may it rings a bell for an expert.

Thanks,
Gregor

 

[milagroso]

Hello blaudden,

here go my logs. They reflect the perfectly reproducible behaviour: After each start of the service, the tune of high band is impossible. Then after a reinit (perfectly visible in the log) of the card, everything is well.

However I could not see any clou in any difference between the bad and the good case. Maybe You detect sth.

Maybe one idea how to proceed: Whats the difference when tuning a low band and a high band channel? I assume that the card has to do something when tuning a high band which is doesn't do when tuning a low band. On the outside in the first case the 22kHz tone is sent in the other not. However I fear that this action is done deep in the Hauppauge drivers? However there must be different ways to trigger this from outside - because other applications are able to trigger it and TVS is not.

Will revert now to my working config and go to sleep.

regards