Last night at 8pm NPVR started recording the Grammys and also Dateline NBC. At 9pm when it went to stop Dateline NBC, it got the infamous "Async stop taking way too long" error and then died. In the process it also killed the Grammys recording at 9pm, even though that recording was to have continued until 11:30pm. After this both recordings continued to show as "Recording" until I restarted the recording service at around 10:30pm.
Both files were perfectly watchable.
I know the root cause of this error remains a mystery, but can you make sure it doesn't go tearing down the wrong graph (or all graphs?) when it's trying to recover from this?
Code:
2011-02-13 20:00:00.447 [DEBUG][7] About to start recording (52573): F:\Recordings\The 53rd Annual Grammy Awards\The 53rd Annual Grammy Awards_20110213_20002330.ts
2011-02-13 20:00:00.447 [INFO][7] DigitalRecorder.StartStream(F:\Recordings\The 53rd Annual Grammy Awards\The 53rd Annual Grammy Awards_20110213_20002330.ts)
2011-02-13 20:00:00.447 [INFO][7] Creating BDA graph
2011-02-13 20:00:00.457 [DEBUG][7] Using tuner WinTV HVR-950 BDA Tuner (1)
2011-02-13 20:00:00.470 [DEBUG][7] About to auto detect capture filter
2011-02-13 20:00:00.474 [DEBUG][7] Got capture filter: 'WinTV HVR-950 BDA Capture'
2011-02-13 20:00:00.475 [DEBUG][7] Added capture filter
2011-02-13 20:00:00.477 [DEBUG][7] Connected Tuner to Capture
2011-02-13 20:00:00.501 [DEBUG][7] Graph filter list:
2011-02-13 20:00:00.501 [DEBUG][7] - MPEG-2 Sections and Tables
2011-02-13 20:00:00.501 [DEBUG][7] - BDA MPEG2 Transport Information Filter
2011-02-13 20:00:00.501 [DEBUG][7] - MPEG-2 Demultiplexer
2011-02-13 20:00:00.501 [DEBUG][7] - NPVR TS Mon
2011-02-13 20:00:00.501 [DEBUG][7] - Capture
2011-02-13 20:00:00.501 [DEBUG][7] - Tuner
2011-02-13 20:00:00.501 [DEBUG][7] - Network Provider
2011-02-13 20:00:00.501 [INFO][7] About to start BDA graph
2011-02-13 20:00:00.501 [DEBUG][7] Starting graph...
2011-02-13 20:00:00.957 [DEBUG][7] About to tune BDA graph:
<tuning>
<type>ATSC</type>
<locator>
<physical_channel>43</physical_channel>
</locator>
<service_id>1</service_id>
<tsid>289</tsid>
<service_type>1</service_type>
</tuning>
2011-02-13 20:00:01.193 [DEBUG][7] Calling LockChannel()
2011-02-13 20:00:01.256 [DEBUG][7] locked=1, present=1, strength=-28, quality=78 (took 63ms to check)
2011-02-13 20:00:01.258 [INFO][7] DigitalRecorder.StartStream() allocated handle: 0x147
2011-02-13 20:00:01.258 [DEBUG][7] Temp at 2/13/2011 8:00:11 PM
2011-02-13 20:00:01.258 [DEBUG][7] Started recording (52573:F:\Recordings\The 53rd Annual Grammy Awards\The 53rd Annual Grammy Awards_20110213_20002330.ts)
2011-02-13 20:00:01.393 [DEBUG][7] Starting: C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat "F:\Recordings\The 53rd Annual Grammy Awards\The 53rd Annual Grammy Awards_20110213_20002330.ts" 2 52573
2011-02-13 20:00:01.417 [DEBUG][7] About to start recording (53634): F:\Recordings\Dateline NBC\Dateline NBC_20110213_20002100.ts
2011-02-13 20:00:01.417 [INFO][7] DigitalRecorder.StartStream(F:\Recordings\Dateline NBC\Dateline NBC_20110213_20002100.ts)
2011-02-13 20:00:01.417 [INFO][7] Creating BDA graph
2011-02-13 20:00:01.424 [DEBUG][7] Using tuner Hauppauge WinTV 885 BDA Tuner/Demod (1)
2011-02-13 20:00:01.436 [DEBUG][7] About to auto detect capture filter
2011-02-13 20:00:01.440 [DEBUG][7] Got capture filter: 'Hauppauge WinTV 885 TS Capture'
2011-02-13 20:00:01.441 [DEBUG][7] Added capture filter
2011-02-13 20:00:01.443 [DEBUG][7] Connected Tuner to Capture
2011-02-13 20:00:01.465 [DEBUG][7] Graph filter list:
2011-02-13 20:00:01.465 [DEBUG][7] - MPEG-2 Sections and Tables
2011-02-13 20:00:01.465 [DEBUG][7] - BDA MPEG2 Transport Information Filter
2011-02-13 20:00:01.465 [DEBUG][7] - MPEG-2 Demultiplexer
2011-02-13 20:00:01.465 [DEBUG][7] - NPVR TS Mon
2011-02-13 20:00:01.465 [DEBUG][7] - Capture
2011-02-13 20:00:01.465 [DEBUG][7] - Tuner
2011-02-13 20:00:01.465 [DEBUG][7] - Network Provider
2011-02-13 20:00:01.465 [INFO][7] About to start BDA graph
2011-02-13 20:00:01.465 [DEBUG][7] Starting graph...
2011-02-13 20:00:01.559 [DEBUG][7] 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>
2011-02-13 20:00:02.261 [DEBUG][7] Calling LockChannel()
2011-02-13 20:00:02.278 [DEBUG][7] locked=1, present=1, strength=-9000, quality=99 (took 15ms to check)
2011-02-13 20:00:02.279 [INFO][7] DigitalRecorder.StartStream() allocated handle: 0x148
2011-02-13 20:00:02.279 [DEBUG][7] Temp at 2/13/2011 8:00:12 PM
2011-02-13 20:00:02.279 [DEBUG][7] Started recording (53634:F:\Recordings\Dateline NBC\Dateline NBC_20110213_20002100.ts)
2011-02-13 20:00:02.475 [DEBUG][7] Starting: C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat "F:\Recordings\Dateline NBC\Dateline NBC_20110213_20002100.ts" 4 53634
2011-02-13 20:00:03.057 [DEBUG][7] - deleting extra recording: F:\Recordings\Dateline NBC\Dateline NBC_20110206_21002308.ts (2/7/2011 5:00:00 AM)
2011-02-13 20:00:03.207 [DEBUG][7] -removing: F:\Recordings\Dateline NBC\Dateline NBC_20110206_21002308.txt
2011-02-13 20:00:03.207 [DEBUG][7] -removing: F:\Recordings\Dateline NBC\Dateline NBC_20110206_21002308.log
2011-02-13 20:00:03.207 [DEBUG][7] -removing: F:\Recordings\Dateline NBC\Dateline NBC_20110206_21002308.edl
2011-02-13 20:00:03.208 [DEBUG][7] -removing: F:\Recordings\Dateline NBC\Dateline NBC_20110206_21002308.logo.txt
2011-02-13 21:00:00.023 [DEBUG][7] Stopping recording (53634). Past end time of recording.
2011-02-13 21:00:00.023 [INFO][7] DigitalRecorder.StopStream() handle: 148
2011-02-13 21:00:00.023 [INFO][7] No more streams active. Stopping device.
2011-02-13 21:00:00.023 [DEBUG][7] About to request async graph stop
2011-02-13 21:00:00.023 [DEBUG][16] Graph stopping... (async)
2011-02-13 21:00:08.023 [DEBUG][7] Async stop taking way too long
2011-02-13 21:00:08.023 [DEBUG][7] Removing filter MPEG-2 Sections and Tables
2011-02-13 21:00:08.023 [DEBUG][7] Removing filter BDA MPEG2 Transport Information Filter
2011-02-13 21:00:08.023 [DEBUG][7] Removing filter MPEG-2 Demultiplexer
Sub, I ran into a a problem on the Hauppauge remote yesterday when I noticed an NPVR change has caused a conflict of the meaning of Ctrl-R NPVR wants Ctrl-R to Rewind (ShortSkip) instead of Ctrl-D in GB-PVR where Ctrl-R has been assigned to Red.
To fix this would you be able assign a new permanent key for the Red key? All my plugins use Red for something and to lose any key on the remote hurts the 10' experience better. Some MCE remotes have a Red key too.
I'm putting the finishing touches to a Beta version of a simple Android client which I intend to release in the next week but I'd like to get some indication of interest and some idea of devices being used by people (see follow up post).
The design is based purely on my own requirements and at the moment covers the following...
Browse TV Guide information (7 days)
Set new / cancel pending recordings
The next stage will add the following...
View What's New recordings (purely for interest see note about streaming below)
Provide Status information (i.e., what's currently recording on which tuner)
Show System information (free disk space and so on)
More advanced future possibilities (if there is interest from users)...
Manage multiple N-PVR machines
Streaming audio files (i.e., from the 'Music' folders)
C2DM to provide PC->Android 'push' alerts/notifications
Note on streaming: I have no personal interest in streaming either video or audio and although streaming audio is relatively straight-forward, it's low on my list of priorities. Streaming video is more complex in particular with problems streaming .ts recordings created by N-PVR to Android devices. Further to this, the unreliable nature of mobile internet connections and potential data costs mean I have no plans to add this feature.
I have a new installation of NPVR 1.5.36 running on Win7 x32. I have the two tuners of an SiliconDust HD Homerun box up and working well; now I am working on getting the Motorola DCH-3200 STB working thru firewire.
I have the (legacy) firewire driver and the timmmoore drivers installed and appearing in device manager ( 1394 OHCI Compliant Host Controller (Legacy), Motorola AV/C Panel Device (DCH-3200), Motorola AV/C Tuner Device (DCH-3200) and Unknown IEEE 1394\5068 Device are all there).
The Motorola STB does not appear in Devices; I also have a total of four entries for the HDHR (there is only one two-channel device on the LAN).
What additional setup do I need to perform for the Moto, and how can I remove the duplicate HDHR entries?
Why won't it work? Whatever navigation button I hit causes that text to show in the search field. For instance, I press rewind and the word REVERSE shows up. Any other way to search the guide besides from the keyboard. Wil it work with an MCE remote?
I've noticed nPVR seems to fail to let me use both tuners. I've got a HVR-4000 and a Technisat Skystar2. It used to work fine using both but recently if it's been recording something, live TV fails etc. Here's a log from tonight when we were watching live TV with the HVR-4000 and Top Gear attempted to record on the skystar2. It correctly tries the skystar2 but gets a 0 byte file. I cant see anything from the log that looks wrong though...
