Scheduled recording goes past scheduled end time

[sub]

Are you sure that's not a case of the timeline being reported incorrectly during playback? At 21:02:01 it clearly showed the graph being stopped and all the components removed.

[pcostanza]

Don't think so. 15 minutes of the news was recorded as well. The file size is larger and the properties show the same amount of time it ran over. Usually the file size is about 7 GB and it's more than 8.
It was still showing as recording in the status screen but if I looked in the TV Guide, it shows it as completed and not still recording.

[sub]

What was the last write time on that file?

[pcostanza]

9:21

[sub]

Thats odd. The logs look like that is definitely not the case.

[pcostanza]

I honestly have too many tuners going on at once and no real need for these many. My brother in law just got a new job and threw me a Ceton USB tuner that he can't use and I've added that (though this problem began before that was added).
I need to do some cleaning up and it's really not a big deal now that I know what I have to do. I'll keep an eye on things and see if I can find a contributing factor to it all. Don't waste your time on this.

[pcostanza]

Just an FYI....I started all the tuners from scratch removing all pending and recurring recordings, re-scanned for channels for each device and then scheduled the recordings.
Since the recording is taking place as I type this, I wanted to show what the device status shows and it's confusing. It appears to show all my tuners recording the same show at the same time. I have the HVR2250, the 950 and both of the HDHR tuners recording at the same time.
I'm sure when this recording is scheduled to stop in the next 40 minutes, it won't.
To the best of my knowledge, I've done nothing to NPVR with the exception of adding the Network Recorder but I'm sure the problem of the recording going past end time was happening before.

[ottoguy]

Did you ever get this resolved? I just recently installed a HDHomerun Prime and now am having a very similar issue. I have had recordings that record late at night 2am or something and they record for hours on end. This morning when I went to the pvr at 7:30am 2 shows that were to record from 2:30 -3 I believe were still recording. I had to restart the services to get them to stop. Unfortunatly I didn't grab the logs and they have been over-written. It happened another night too, and it recorded about 5-6 hours of the same channel. If it happens again I will post the log files.

[mian]

I had a recording of House go on for several hours past its stop time until I manually restarted the npvr recording service. The tray icon remained red the whole time, status showed the program was still recording, and the file contained many, many hours of... I think it was NCIS. I don't care much, no harm no foul, just trying to be helpful.

We both have 2250s, relevant?

Relevant log data:

Code:

2011-10-19 09:57:00.939    [DEBUG][7]    allocating recording target filename: T:\npvr\House\House_20111019_10001100.ts
2011-10-19 09:57:00.939    [DEBUG][7]    About to start recording (882): T:\npvr\House\House_20111019_10001100.ts
2011-10-19 09:57:00.939    [INFO][7]    AnalogRecorder.StartStream(T:\npvr\House\House_20111019_10001100.ts)
2011-10-19 09:57:00.939    [INFO][7]    AnalogRecorder stream count now 2
2011-10-19 09:57:00.939    [INFO][7]    Creating graph
2011-10-19 09:57:00.939    [DEBUG][7]    Building graph using: Hauppauge WinTV-7164 Analog Capture (1)
2011-10-19 09:57:00.970    [DEBUG][7]    about to look for encoder
2011-10-19 09:57:00.970    [DEBUG][7]    About to look for hardware encoder.
2011-10-19 09:57:00.970    [DEBUG][7]    AttachHardEncoder@1
2011-10-19 09:57:00.970    [DEBUG][7]    AttachHardEncoder@2 (got medium)
2011-10-19 09:57:00.986    [DEBUG][7]    AttachHardEncoder@3 (got encoder)
2011-10-19 09:57:00.986    [DEBUG][7]    AttachHardEncoder@4 (about to connect encoder)
2011-10-19 09:57:00.986    [DEBUG][7]    AttachHardEncoder@5 (connected encoder)
2011-10-19 09:57:01.001    [DEBUG][7]    AttachHardEncoder@6 (connected demux)
2011-10-19 09:57:01.001    [DEBUG][7]    about to add mux
2011-10-19 09:57:01.001    [DEBUG][7]    about to add tee
2011-10-19 09:57:01.001    [DEBUG][7]    about to connect writers
2011-10-19 09:57:01.001    [DEBUG][7]    Graph filter list:
2011-10-19 09:57:01.001    [DEBUG][7]     - NPVR Writer2
2011-10-19 09:57:01.001    [DEBUG][7]     - NPVR Writer
2011-10-19 09:57:01.001    [DEBUG][7]     - Infinite Pin Tee Filter
2011-10-19 09:57:01.001    [DEBUG][7]     - MainConcept (HCW) MPEG Multiplexer-Plus
2011-10-19 09:57:01.001    [DEBUG][7]     - MPEG-2 Demultiplexer
2011-10-19 09:57:01.001    [DEBUG][7]     - Hauppauge WinTV-7164 MPEG2 PS Encoder/Mux
2011-10-19 09:57:01.001    [DEBUG][7]     - Hauppauge WinTV-7164 Analog Capture
2011-10-19 09:57:01.001    [DEBUG][7]     - Hauppauge WinTV-7164 Analog Xbar
2011-10-19 09:57:01.001    [DEBUG][7]     - Hauppauge WinTV-7164 Analog TV Audio
2011-10-19 09:57:01.001    [DEBUG][7]     - Hauppauge WinTV-7164 Analog TV Tuner
2011-10-19 09:57:01.017    [DEBUG][7]    About to check crossbar routing
2011-10-19 09:57:01.095    [DEBUG][7]    Audio crossbar routed successfully
2011-10-19 09:57:01.095    [DEBUG][7]    Video crossbar already routed correctly
2011-10-19 09:57:01.095    [DEBUG][7]    Tuning to channel: 34
2011-10-19 09:57:01.906    [DEBUG][7]    Tuner reports frequency: 283250000
2011-10-19 09:57:01.906    [INFO][7]    About to start graph
2011-10-19 09:57:01.922    [DEBUG][7]    device minimum bitrate: 0
2011-10-19 09:57:01.922    [DEBUG][7]    device maximum bitrate: 6000000
2011-10-19 09:57:01.922    [DEBUG][7]    device bitrate step size: 1
2011-10-19 09:57:01.922    [DEBUG][7]    device reports current bitrate as: 6000000
2011-10-19 09:57:01.937    [DEBUG][7]    device reports current peak bitrate as: 6000000
2011-10-19 09:57:01.937    [DEBUG][7]    About to set BITRATE MODE
2011-10-19 09:57:01.953    [DEBUG][7]    requesting variable encoding
2011-10-19 09:57:01.968    [DEBUG][7]    trying to request bitrate: 3000000
2011-10-19 09:57:01.984    [DEBUG][7]    device confirms bitrate as: 3000000
2011-10-19 09:57:01.984    [DEBUG][7]    About to set PEAK BITRATE
2011-10-19 09:57:02.000    [DEBUG][7]    device minimum peak bitrate: 0
2011-10-19 09:57:02.000    [DEBUG][7]    device maximum peak bitrate: 9500000
2011-10-19 09:57:02.000    [DEBUG][7]    device peak bitrate step size: 1
2011-10-19 09:57:02.000    [DEBUG][7]    trying to request peak bitrate: 5000000
2011-10-19 09:57:02.015    [DEBUG][7]    device confirms bitrate as: 5000000
2011-10-19 09:57:02.202    [DEBUG][7]    About to tune graph to:
<tuning>
  <type>Analog</type>
  <locator>
    <channel>34</channel>
    <country>1</country>
    <input>cable</input>
    <source>Tuner</source>
  </locator>
</tuning>

2011-10-19 09:57:02.202    [DEBUG][7]    About to check crossbar routing
2011-10-19 09:57:02.296    [DEBUG][7]    Audio crossbar routed successfully
2011-10-19 09:57:02.296    [DEBUG][7]    Video crossbar already routed correctly
2011-10-19 09:57:02.296    [DEBUG][7]    Tuning to channel: 34
2011-10-19 09:57:02.982    [DEBUG][7]    Tuner reports frequency: 283250000
2011-10-19 09:57:02.982    [DEBUG][7]    About to switch AnalogRecorder to target:
T:\npvr\House\House_20111019_10001100.ts
2011-10-19 09:57:03.014    [DEBUG][7]    Temp at 10/19/2011 9:57:13 AM
2011-10-19 09:57:03.014    [DEBUG][7]    Started recording (882:T:\npvr\House\House_20111019_10001100.ts)
2011-10-19 09:57:03.029    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2011-10-19 11:03:00.210    [DEBUG][7]    Stopping recording (882). Past end time of recording.
2011-10-19 11:03:00.210    [INFO][7]    AnalogRecorder stream count now 1
2011-10-19 11:03:00.226    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\PostProcessing.bat
2011-10-19 12:37:39.728    [DEBUG][32]    Resuming...
2011-10-19 12:37:39.743    [DEBUG][7]    Recording service noted the system was resuming...
2011-10-19 12:37:39.743    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\Wakeup.bat
2011-10-19 12:37:41.678    [DEBUG][5]    cycling MVP servers
2011-10-19 16:20:45.824    [DEBUG][23]    OnStop()
2011-10-19 16:20:45.824    [DEBUG][23]    About to stop webserver
2011-10-19 16:20:46.045    [DEBUG][23]    webserver stopped
2011-10-19 16:20:46.045    [DEBUG][23]    About to stop MVP servers
2011-10-19 16:20:46.045    [DEBUG][23]    MVPManager shutdown initiated
2011-10-19 16:20:46.045    [DEBUG][23]    MVPManager shutdown complete
2011-10-19 16:20:46.045    [DEBUG][23]    MVP servers stopped
2011-10-19 16:20:46.045    [DEBUG][23]    About to stop recording service
2011-10-19 16:20:46.046    [INFO][23]    RecordingService.Shutdown()
2011-10-19 16:20:46.223    [INFO][7]    DigitalRecorder.StopAllStreams()
2011-10-19 16:20:46.223    [INFO][7]    DigitalRecorder.StopAllStreams()
2011-10-19 16:20:46.224    [DEBUG][7]    Graph stopping...
2011-10-19 16:20:46.369    [DEBUG][7]    Graph stopped
2011-10-19 16:20:46.369    [DEBUG][7]    Removing filter  NPVR Writer2
2011-10-19 16:20:46.370    [DEBUG][7]    Removing filter  NPVR Writer
2011-10-19 16:20:46.370    [DEBUG][7]    Removing filter  Infinite Pin Tee Filter
2011-10-19 16:20:46.370    [DEBUG][7]    Removing filter  MainConcept (HCW) MPEG Multiplexer-Plus
2011-10-19 16:20:46.371    [DEBUG][7]    Removing filter  MPEG-2 Demultiplexer
2011-10-19 16:20:46.373    [DEBUG][7]    Removing filter  Hauppauge WinTV-7164 MPEG2 PS Encoder/Mux
2011-10-19 16:20:46.374    [DEBUG][7]    Removing filter  Hauppauge WinTV-7164 Analog Capture
2011-10-19 16:20:46.375    [DEBUG][7]    Removing filter  Hauppauge WinTV-7164 Analog Xbar
2011-10-19 16:20:46.375    [DEBUG][7]    Removing filter  Hauppauge WinTV-7164 Analog TV Audio
2011-10-19 16:20:46.376    [DEBUG][7]    Removing filter  Hauppauge WinTV-7164 Analog TV Tuner
2011-10-19 16:20:46.377    [INFO][7]    DigitalRecorder.StopAllStreams()
2011-10-19 16:20:46.377    [INFO][7]    DigitalRecorder.StopAllStreams()
2011-10-19 16:20:46.579    [DEBUG][23]    recording service stopped
2011-10-19 16:20:46.579    [DEBUG][23]    OnStop() complete...

[sub]

I had a recording of House go on for several hours past its stop time until I manually restarted the npvr recording service. The tray icon remained red the whole time, status showed the program was still recording, and the file contained many, many hours of... I think it was NCIS. I don't care much, no harm no foul, just trying to be helpful.

Can you zip and attach the whole log?