Second Instance of LiveTV or Recording Still Fails in Version 1.5.28

[tjb]

Sub,

I was really hoping that the new beta 1.5.28 would rectify the problems I am having with the 2nd instance of LiveTV or recordings. Reference my last post at http://forums.gbpvr.com//showthread.php?...s-Programs

But the same problems appear in the new version on my system. I believe the problems are related, and since it is easier to test and recover from failures in LiveTV this is where I am focusing my efforts. Just to review, I am using an HDHR configured as ATSC/OTA and I have completely reinstalled my Windows 7 Ultimate system from scratch.

The first time I run LiveTV everything runs perfectly. If I escape out and then go back into LiveTV, I always get the message "Failed to communicate with recording service" even though the recording service is still running. If I try a third time, I get the message "Failed to start stream". If I restart the recording service, then LiveTV works again (but only once). I have attached my log files and was hoping you would have a minute to look at them.

The first instance of LiveTV in the logs looks like this:

Code:

2010-08-09 11:18:13.275    [DEBUG][5]    StartStream(7173:WLWT-HD)
2010-08-09 11:18:13.291    [INFO]  [5]                    DigitalRecorder.StartStream(LIVE&C:\Temp\live-WLWT-HD-3761.ts)
2010-08-09 11:18:13.291    [INFO]  [5]                    Creating BDA graph
2010-08-09 11:18:13.378    [DEBUG][5]    Using tuner Silicondust HDHomeRun Tuner 10117487-0 (1)
2010-08-09 11:18:13.430    [DEBUG][5]    About to auto detect capture filter
2010-08-09 11:18:13.716    [DEBUG][5]    No BDA capture filter for this device
2010-08-09 11:18:13.814    [DEBUG][5]    Graph filter list:
2010-08-09 11:18:13.814    [DEBUG][5]     - BDA MPEG2 Transport Information Filter
2010-08-09 11:18:13.815    [DEBUG][5]     - MPEG-2 Demultiplexer
2010-08-09 11:18:13.815    [DEBUG][5]     - NPVR TS Mon
2010-08-09 11:18:13.815    [DEBUG][5]     - Tuner
2010-08-09 11:18:13.815    [DEBUG][5]     - Network Provider
2010-08-09 11:18:13.818    [INFO]  [5]                    About to start BDA graph
2010-08-09 11:18:13.820    [DEBUG][5]    Starting graph...
2010-08-09 11:18:13.823    [DEBUG][5]    About to tune BDA graph:
<tuning>
  <type>ATSC</type>
  <locator>
    <physical_channel>35</physical_channel>
  </locator>
  <service_id>1</service_id>
  <tsid>2243</tsid>
  <service_type>1</service_type>
</tuning>

2010-08-09 11:18:15.156    [DEBUG][5]    locked=1, present=1, strength=95, quality=97   (took 1186ms to check)
2010-08-09 11:18:15.185    [INFO]   [5]    DigitalRecorder.StartStream() allocated handle: 0x1
2010-08-09 11:18:15.188    [DEBUG][5]    Live TV handle: 0x140001
2010-08-09 11:18:15.238    [DEBUG][3]    GetQuality()
2010-08-09 11:18:15.238    [DEBUG][3]    GetQuality()@exit
2010-08-09 11:18:15.256    [DEBUG][3]    GetQuality()
...
2010-08-09 11:18:21.540    [INFO]  [3]                    DigitalRecorder.StopStream() handle: 1
2010-08-09 11:18:21.562    [INFO]  [3]                    No more streams active. Stopping device.
2010-08-09 11:18:21.564    [DEBUG][3]    Graph stopping...
2010-08-09 11:18:21.580    [DEBUG][3]    Graph stopped
2010-08-09 11:18:21.600    [DEBUG][3]    Removing filter  BDA MPEG2 Transport Information Filter
2010-08-09 11:18:21.601    [DEBUG][3]    Removing filter  MPEG-2 Demultiplexer
2010-08-09 11:18:21.602    [DEBUG][3]    Removing filter  NPVR TS Mon
2010-08-09 11:18:21.604    [DEBUG][3]    Removing filter  Tuner
2010-08-09 11:18:21.604    [DEBUG][3]    Removing filter  Network Provider

The second one (which is a different channel) is much shorter and appears to me not to try to tune the channel (there is no <tuning> section in the log for this entry):

Code:

2010-08-09 11:18:32.023    [DEBUG][3]    StartStream(7148:WCPO-HD)
2010-08-09 11:18:32.023    [INFO]   [3]    DigitalRecorder.StartStream(LIVE&C:\Temp\live-WCPO-HD-3762.ts)
2010-08-09 11:18:32.023    [INFO]   [3]    Creating BDA graph
2010-08-09 11:18:32.033    [DEBUG][3]    Using tuner Silicondust HDHomeRun Tuner 10117487-0 (1)
2010-08-09 11:18:32.066    [DEBUG][3]    About to auto detect capture filter
2010-08-09 11:18:32.163    [DEBUG][3]    GetQuality()
...
2010-08-09 11:18:53.376    [DEBUG][12]    OnStop()
2010-08-09 11:18:53.610    [INFO]  [12]    RecordingService.Shutdown()
2010-08-09 11:18:53.891    [INFO]   [6]    DigitalRecorder.StopAllStreams()
2010-08-09 11:18:53.891    [DEBUG][6]    Graph stopping...
2010-08-09 11:18:53.891    [DEBUG][6]    Graph stopped
2010-08-09 11:18:53.891    [DEBUG][6]    Removing filter  Tuner
2010-08-09 11:18:53.891    [DEBUG][6]    Removing filter  Network Provider
2010-08-09 11:18:53.891    [INFO]   [6]    DigitalRecorder.StopAllStreams()
2010-08-09 11:18:53.891    [INFO]   [6]    DigitalRecorder.StopAllStreams()
2010-08-09 11:18:53.891    [INFO]   [6]    DigitalRecorder.StopAllStreams()
2010-08-09 11:18:54.093    [DEBUG][12]    OnStop() complete...

[tjb]

I found a post that included an update to Nextpvr.exe to include more logging information and have included logs while running this version. The NPVR log file now contains the following when starting LiveTV the 2nd time.

Code:

2010-08-09 12:38:41.133    [DEBUG][1]    StartLiveTV@1
2010-08-09 12:38:41.133    [DEBUG][1]    StartLiveTV@2
2010-08-09 12:38:41.133    [DEBUG][1]    StartLiveTV@3
2010-08-09 12:38:41.133    [DEBUG][1]    StartLiveTV@4
2010-08-09 12:38:41.200    [ERROR][1]    Unexpected error calling StartStream(): System.AccessViolationException: Attempted to read or write protected memory. This is often an indication that other memory is corrupt.

Server stack trace:
   at VariantClear(tagVARIANT* )
   at Native.DigitalRecorderBase.GetCaptureFilter(IBaseFilter* pTunerFilter)
   at Native.DigitalRecorderBase.BuildGraph(String deviceFilter, Int32 deviceInstance, Int32 commonInterface, Int32 commonInterfaceInstances, String& message)
   at NShared.DigitalRecorder.StartStream(ChannelMapping channelMapping, String target, String& reason)
   at NShared.RecordingService.StartStream(Int32 channelOID, String target, Int32 previousHandle, ChannelMapping& returnMapping, String& reason)
   at NShared.RecordingRemote.StartStream(Int32 channelOID, String target, Int32 previousHandle, ChannelMapping& channelMapping, String& reason)
   at System.Runtime.Remoting.Messaging.StackBuilderSink._PrivateProcessMessage(IntPtr md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.PrivateProcessMessage(RuntimeMethodHandle md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.SyncProcessMessage(IMessage msg, Int32 methodPtr, Boolean fExecuteInContext)

Exception rethrown at [0]:
   at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(IMessage reqMsg, IMessage retMsg)
   at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(MessageData& msgData, Int32 type)
   at NShared.IRecordingService.StartStream(Int32 channelOID, String target, Int32 previousHandle, ChannelMapping& channelMapping, String& reason)
   at NShared.RecordingServiceProxy.StartStream(Int32 channelOID, String target, Int32 previousHandle, ChannelMapping& channelMapping, String& reason)
2010-08-09 12:38:46.538    [DEBUG][1]    FadeThroughTransitionHelper.GetRenderList() created
2010-08-09 12:38:46.790    [DEBUG][1]    FadeThroughTransitionHelper.GetRenderList() transition complete
2010-08-09 12:39:10.880    [INFO][1]    Exiting...
2010-08-09 12:39:10.880    [INFO][1]    Done.

[sub]

Unfortunately I've got no idea at this stage. It seems to be HDHomeRun specific though.

[tjb]

Unfortunately I've got no idea at this stage. It seems to be HDHomeRun specific though.

Did the log in my first post look correct? Just wondering because it did not show the tuning statements

[tjb]

Just curious - is there anyone that has successfully switched over to Npvr using a dual tuner HDHR in ATSC-OTA mode under Windows 7?

[sub]

Did the log in my first post look correct? Just wondering because it did not show the tuning statements

I did look at it. It can be normal not to see tuning information for different channels if they're on the same physical channel.

[tjb]

That's why I thought it might provide a clue - the stations in question were not on the same physical channel.

[tjb]

Yesterday I received the new HDHR that I ordered from woot, installed and configured the new device in NPVR, held my breath, and tried to watch LiveTV two times in a row. Alas, no luck, same problems. It appears (at least for me) that it is time to go back to GbPvr. The wife will be very happy because with the additional HDHR she now has 4 tuners!

I would still be interested to find out if anyone else has had any success using Npvr with the HDHR in ATSC/OTA configuration under Windows 7. I definitely have not given up, but just taking a rest. Sub, if there is anything I can do to help narrow this down, please let me know. I made an image of the system with Acronis and can restore it at anytime. Once again, I appreciate all the help from you and whurlston in trying to figure this thing out.