error on starting livetv in 1.5.28

[johnsonx42]

When I try to start live tv from the TV Guide, it hangs for awhile, then I hear sound but get a .net exception on the screen. After closing the .net exception, the sound continues for a few more moments, then ends.

NPVR/NRECORD logs attached, and here is the text of the .net exception:

Code:

See the end of this message for details on invoking
just-in-time (JIT) debugging instead of this dialog box.

************** Exception Text **************
System.Collections.Generic.KeyNotFoundException: The given key was not present in the dictionary.
   at System.ThrowHelper.ThrowKeyNotFoundException()
   at System.Collections.Generic.Dictionary`2.get_Item(TKey key)
   at NextPVR.Players.LiveTvPlayer.StartLiveTV(Channel channel, String& reason)
   at NextPVR.ControllerForm.PlayLiveTV(Channel channel)
   at NextPVR.TV.ShowDetailsPopup.HandleCommand(String command)
   at NextPVR.TV.ShowDetailsPopup.HandleButtonCommand(String buttonName)
   at NUtility.Controls.UiButtonList.OnKeyDown(KeyEventArgs e)
   at NextPVR.TV.ShowDetailsPopup.OnKeyDown(KeyEventArgs e)
   at NextPVR.ControllerForm.OnKeyDown(Object sender, KeyEventArgs e)
   at System.Windows.Forms.Control.OnKeyDown(KeyEventArgs e)
   at System.Windows.Forms.Control.ProcessKeyEventArgs(Message& m)
   at System.Windows.Forms.Control.ProcessKeyMessage(Message& m)
   at System.Windows.Forms.Control.WmKeyChar(Message& m)
   at System.Windows.Forms.Control.WndProc(Message& m)
   at System.Windows.Forms.ScrollableControl.WndProc(Message& m)
   at System.Windows.Forms.ContainerControl.WndProc(Message& m)
   at System.Windows.Forms.Form.WndProc(Message& m)
   at NextPVR.ControllerForm.WndProc(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.OnMessage(Message& m)
   at System.Windows.Forms.Control.ControlNativeWindow.WndProc(Message& m)
   at System.Windows.Forms.NativeWindow.Callback(IntPtr hWnd, Int32 msg, IntPtr wparam, IntPtr lparam)


************** Loaded Assemblies **************
mscorlib
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/Microsoft.NET/Framework/v2.0.50727/mscorlib.dll
----------------------------------------
NextPVR
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NextPVR.exe
----------------------------------------
NShared
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NShared.DLL
----------------------------------------
NUtility
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.DLL
----------------------------------------
System.Data
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_32/System.Data/2.0.0.0__b77a5c561934e089/System.Data.dll
----------------------------------------
System
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System/2.0.0.0__b77a5c561934e089/System.dll
----------------------------------------
System.Windows.Forms
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Windows.Forms/2.0.0.0__b77a5c561934e089/System.Windows.Forms.dll
----------------------------------------
System.Drawing
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Drawing/2.0.0.0__b03f5f7f11d50a3a/System.Drawing.dll
----------------------------------------
System.Data.SQLite
    Assembly Version: 1.0.65.0
    Win32 Version: 1.0.65.0
    CodeBase: file:///C:/Program%20Files/NPVR/System.Data.SQLite.DLL
----------------------------------------
System.Xml
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Xml/2.0.0.0__b77a5c561934e089/System.Xml.dll
----------------------------------------
System.Data.Entity
    Assembly Version: 3.5.0.0
    Win32 Version: 3.5.30729.4926 built by: NetFXw7
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Data.Entity/3.5.0.0__b77a5c561934e089/System.Data.Entity.dll
----------------------------------------
System.Core
    Assembly Version: 3.5.0.0
    Win32 Version: 3.5.30729.4926 built by: NetFXw7
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Core/3.5.0.0__b77a5c561934e089/System.Core.dll
----------------------------------------
System.Runtime.Remoting
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Runtime.Remoting/2.0.0.0__b77a5c561934e089/System.Runtime.Remoting.dll
----------------------------------------
Test1
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.dll
----------------------------------------
Test2
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.dll
----------------------------------------
Test3
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.dll
----------------------------------------
StreamIt.Netflix
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.dll
----------------------------------------
System.Data.Services.Client
    Assembly Version: 3.5.0.0
    Win32 Version: 3.5.30729.5004 built by: Win7RTMLDR
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Data.Services.Client/3.5.0.0__b77a5c561934e089/System.Data.Services.Client.dll
----------------------------------------
WebRadio
    Assembly Version: 1.0.0.0
    Win32 Version: 1.0.0.0
    CodeBase: file:///C:/Program%20Files/NPVR/NUtility.dll
----------------------------------------
SlimDX
    Assembly Version: 2.0.8.42
    Win32 Version:
    CodeBase: file:///C:/Program%20Files/NPVR/SlimDX.DLL
----------------------------------------
msvcm90
    Assembly Version: 9.0.30729.4926
    Win32 Version: 9.00.30729.4926
    CodeBase: file:///C:/Windows/WinSxS/x86_microsoft.vc90.crt_1fc8b3b9a1e18e3b_9.0.30729.4926_none_508ed732bcbc0e5a/msvcm90.dll
----------------------------------------
taglib-sharp
    Assembly Version: 2.0.3.0
    Win32 Version: 2.0.3.0
    CodeBase: file:///C:/Program%20Files/NPVR/taglib-sharp.DLL
----------------------------------------
System.Transactions
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_32/System.Transactions/2.0.0.0__b77a5c561934e089/System.Transactions.dll
----------------------------------------
System.Configuration
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_MSIL/System.Configuration/2.0.0.0__b03f5f7f11d50a3a/System.Configuration.dll
----------------------------------------
System.EnterpriseServices
    Assembly Version: 2.0.0.0
    Win32 Version: 2.0.50727.4927 (NetFXspW7.050727-4900)
    CodeBase: file:///C:/Windows/assembly/GAC_32/System.EnterpriseServices/2.0.0.0__b03f5f7f11d50a3a/System.EnterpriseServices.dll
----------------------------------------
Unmanaged
    Assembly Version: 1.0.3870.12587
    Win32 Version:
    CodeBase: file:///C:/Program%20Files/NPVR/Unmanaged.DLL
----------------------------------------

************** JIT Debugging **************
To enable just-in-time (JIT) debugging, the .config file for this
application or computer (machine.config) must have the
jitDebugging value set in the system.windows.forms section.
The application must also be compiled with debugging
enabled.

For example:

<configuration>
    <system.windows.forms jitDebugging="true" />
</configuration>

When JIT debugging is enabled, any unhandled exception
will be sent to the JIT debugger registered on the computer
rather than be handled by this dialog box.

[johnsonx42]

bump... nobody has anything on this? I can no longer watch Live TV. My wife is going to kill me if I don't get this resolved. So much is improved with 1.5.28 I can't even consider going back to 1.5.24.

[sub]

I cant see any obvious reason in your logs, but try it with this patch, then repost the npvr.log. It adds more messages around the area of code that is having the problem.

[johnsonx42]

thanks sub, I'll put in the patch and test as soon as my wife gives up the TV for the evening.

[johnsonx42]

ok, weird, livetv works fine with the new patch. I have to admit though I didn't actually try livetv tonight before posting, I just asked my wife if she had tried again while I was away, she said yes, she got the same exception so I left it at that

here's the double weird thing, livetv now works fine on my test client too (the desktop win7 pc I'm typing this from), except I didn't change anything on the client. both the client and the server got the same exception error when trying to watch livetv, and now it's gone. both machines have been rebooted since livetv was discovered not to work, with no change until now.

this doesn't make a hell of a lot of sense. I also had the phantom epg problem where all my pending recordings disappeared, and that went away by itself too.

gremlins?

[sub]

Odd. Come back to me if it stops working.

[johnsonx42]

This is back. I figured out the mystery - I get this exception if I try to start LiveTV while a recording is in progress. As far as I can tell, both tuners are working fine. In a few minutes I have recordings starting on both tuners; I will report whether those proceed normally.

In the meantime, here are the logs of tonight's problem. Same symptoms as reported before - hangs a moment, then I get sound, the exception pops up, then sound continues for a bit and ends.

My wife came and got me because she couldn't watch Entertainment tonight. I first restarted NextPVR.exe, and then tried it myself.

Look at the logs at 19:21:21 - I tried to start live tv, and got the exception. Then I noticed a recording (#4056) was in progress so on a hunch I cancelled that, at 19:22:13. Then I was able to start Live TV at 19:22:41.

Then at 19:30:00 it tried to end the recording 4056 again at the normal time even though I already cancelled it 9 minutes earlier. LiveTV came to an abrupt end with the message "The tuner being used for LiveTV is no longer available". It appears that when it thought it was killing 4056 it was actually killing the graph being used by LiveTV, and the renew lease failed a few seconds later.

I was able to restart LiveTV again a few seconds later. My wife declared that this all sucks, all she wants to do is watch stupid tv.

[johnsonx42]

Yes, both recordings are running and playable, so the tuners are fine. Here is a log excerpt from when the recordings started (we left LiveTV running up until it was forcibly ended):

Code:

2010-08-10 19:59:59.779    [DEBUG][5]    GetQuality()
2010-08-10 19:59:59.785    [DEBUG][5]    GetQuality()@exit
2010-08-10 20:00:00.148    [DEBUG][6]    Scheduled recording is supposed to start on tuner currently being used for live tv. Need to look for alternative tuner.
2010-08-10 20:00:00.157    [DEBUG][6]    Checking if multi-record possible...
2010-08-10 20:00:00.161    [DEBUG][6]    Unable to find alternative tuner. Live TV will be stopped.
2010-08-10 20:00:00.161    [DEBUG][6]    About to start recording (4048): F:\Recordings\Wipeout\Wipeout_20100810_20002101.ts
2010-08-10 20:00:00.161    [INFO][6]    DigitalRecorder.StartStream(F:\Recordings\Wipeout\Wipeout_20100810_20002101.ts)
2010-08-10 20:00:00.172    [DEBUG][6]    Switching BDA graph to tune:
<tuning>
  <type>ATSC</type>
  <locator>
    <physical_channel>7</physical_channel>
  </locator>
  <service_id>1</service_id>
  <tsid>295</tsid>
  <service_type>1</service_type>
</tuning>

2010-08-10 20:00:00.686    [DEBUG][6]    locked=1, present=1, strength=-10000, quality=100   (took 15ms to check)
2010-08-10 20:00:00.687    [INFO][6]    DigitalRecorder.StartStream() allocated handle: 0x2
2010-08-10 20:00:00.687    [DEBUG][6]    Temp at 8/10/2010 8:00:10 PM
2010-08-10 20:00:00.687    [DEBUG][6]    Started recording (4048:F:\Recordings\Wipeout\Wipeout_20100810_20002101.ts)
2010-08-10 20:00:00.752    [DEBUG][6]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2010-08-10 20:00:00.759    [DEBUG][6]    About to start recording (4070): F:\Recordings\Minute to Win It\Minute to Win It_20100810_20002100.ts
2010-08-10 20:00:00.759    [INFO][6]    DigitalRecorder.StartStream(F:\Recordings\Minute to Win It\Minute to Win It_20100810_20002100.ts)
2010-08-10 20:00:00.759    [INFO][6]    Creating BDA graph
2010-08-10 20:00:00.766    [DEBUG][6]    Using tuner WinTV HVR-950 BDA Tuner (1)
2010-08-10 20:00:00.781    [DEBUG][6]    About to auto detect capture filter
2010-08-10 20:00:00.784    [DEBUG][6]    Got capture filter.
2010-08-10 20:00:00.784    [DEBUG][6]    Added capture filter
2010-08-10 20:00:00.786    [DEBUG][6]    Connected Tuner to Capture
2010-08-10 20:00:00.801    [DEBUG][6]    Graph filter list:
2010-08-10 20:00:00.802    [DEBUG][6]     - BDA MPEG2 Transport Information Filter
2010-08-10 20:00:00.802    [DEBUG][6]     - MPEG-2 Demultiplexer
2010-08-10 20:00:00.802    [DEBUG][6]     - NPVR TS Mon
2010-08-10 20:00:00.802    [DEBUG][6]     - Capture
2010-08-10 20:00:00.802    [DEBUG][6]     - Tuner
2010-08-10 20:00:00.802    [DEBUG][6]     - Network Provider
2010-08-10 20:00:00.803    [INFO][6]    About to start BDA graph
2010-08-10 20:00:00.803    [DEBUG][6]    Starting graph...
2010-08-10 20:00:04.625    [DEBUG][6]    About to tune BDA graph:
<tuning>
  <type>ATSC</type>
  <locator>
    <physical_channel>36</physical_channel>
  </locator>
  <service_id>3</service_id>
  <tsid>291</tsid>
  <service_type>1</service_type>
</tuning>

2010-08-10 20:00:04.944    [DEBUG][6]    locked=1, present=1, strength=-28, quality=73   (took 78ms to check)
2010-08-10 20:00:04.945    [INFO][6]    DigitalRecorder.StartStream() allocated handle: 0x1
2010-08-10 20:00:04.946    [DEBUG][6]    Temp at 8/10/2010 8:00:14 PM
2010-08-10 20:00:04.946    [DEBUG][6]    Started recording (4070:F:\Recordings\Minute to Win It\Minute to Win It_20100810_20002100.ts)
2010-08-10 20:00:05.021    [DEBUG][6]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2010-08-10 20:00:05.027    [DEBUG][5]    Renew lease failed (0x5F20001)
2010-08-10 20:00:05.027    [DEBUG][5]    liveHandleRenewalTime contains 1 handles:
2010-08-10 20:00:05.027    [DEBUG][5]     - 0x5F20001
2010-08-10 20:00:05.027    [DEBUG][15]    GetQuality()
2010-08-10 20:00:05.034    [DEBUG][15]    GetQuality()@exit
2010-08-10 20:00:05.034    [DEBUG][15]    GetQuality()
2010-08-10 20:00:05.056    [DEBUG][15]    GetQuality()@exit
2010-08-10 20:00:05.134    [DEBUG][14]    GetQuality()
2010-08-10 20:00:05.139    [DEBUG][14]    GetQuality()@exit
2010-08-10 20:00:05.139    [DEBUG][14]    GetQuality()
2010-08-10 20:00:05.161    [DEBUG][14]    GetQuality()@exit
2010-08-10 20:00:05.227    [DEBUG][13]    GetQuality()
2010-08-10 20:00:05.232    [DEBUG][13]    GetQuality()@exit
2010-08-10 20:00:05.232    [DEBUG][13]    GetQuality()
2010-08-10 20:00:05.254    [DEBUG][13]    GetQuality()@exit
2010-08-10 20:00:05.320    [INFO][5]    DigitalRecorder.StopStream() handle: 1
2010-08-10 20:00:05.324    [DEBUG][15]    GetQuality()
2010-08-10 20:00:05.330    [DEBUG][15]    GetQuality()@exit
2010-08-10 20:00:05.330    [DEBUG][15]    GetQuality()
2010-08-10 20:00:05.359    [DEBUG][15]    GetQuality()@exit

[sub]

I was able to restart LiveTV again a few seconds later. My wife declared that this all sucks, all she wants to do is watch stupid tv.

Hey - I did say it was a beta

[johnsonx42]

yeah, wives are less understanding of the whole beta thing than us nerds are