Recording Service Crash (R2 patch applied)

[perry]

Hi

I also see frequent Recording Service Crashes but haven't been sure what the user did and the time frame for the crash until yesterday.
Deleting the recording Masterchef -> watching Fristet -> trying to delete Fristet
From Nrecord log

2011-11-15 22:57:56.856 [DEBUG][21] DeleteRecording(1216)
2011-11-15 22:57:56.856 [DEBUG][21] DeleteRecording@1
2011-11-15 22:57:56.856 [DEBUG][21] Cancelling wake up request for for 'RecordingOID1216'
2011-11-15 22:57:58.393 [DEBUG][21] -removing: F:\NextPVR\Optagelser\Masterchef (52)\Masterchef (52)_20111115_19002000.ts
2011-11-15 22:57:58.553 [DEBUG][21] DeleteRecording@2
2011-11-15 22:57:58.554 [DEBUG][21] DeleteRecording@4
2011-11-15 22:57:58.554 [DEBUG][21] PlaybackPositionHelper.DeletePlaybackPosition(F:\NextPVR\Optagelser\Masterchef (52)\Masterchef (52)_20111115_19002000.ts)
2011-11-15 22:57:58.568 [DEBUG][21] DeleteRecording@5
2011-11-15 22:59:41.174 [DEBUG][19] DeleteRecording(1216)
2011-11-15 22:59:41.175 [DEBUG][19] DeleteRecording@1
2011-11-15 22:59:41.175 [DEBUG][19] Cancelling wake up request for for 'RecordingOID1216'
2011-11-15 22:59:41.178 [DEBUG][19] DeleteRecording@2
2011-11-15 22:59:41.179 [DEBUG][19] DeleteRecording@4
2011-11-15 22:59:41.179 [DEBUG][19] PlaybackPositionHelper.DeletePlaybackPosition(F:\NextPVR\Optagelser\Masterchef (52)\Masterchef (52)_20111115_19002000.ts)
2011-11-15 22:59:41.183 [DEBUG][19] DeleteRecording@5
2011-11-15 23:42:57.504 [DEBUG][17] DeleteRecording(1208)
2011-11-15 23:42:57.504 [DEBUG][17] DeleteRecording@1
2011-11-15 23:42:57.504 [DEBUG][17] Cancelling wake up request for for 'RecordingOID1208'
2011-11-15 23:42:58.278 [DEBUG][17] -removing: F:\NextPVR\Optagelser\Fristet (41)\Fristet (41)_20111114_22002255.ts
2011-11-15 23:42:58.373 [DEBUG][17] DeleteRecording@2
2011-11-15 23:42:58.374 [DEBUG][17] DeleteRecording@4
2011-11-15 23:42:58.374 [DEBUG][17] PlaybackPositionHelper.DeletePlaybackPosition(F:\NextPVR\Optagelser\Fristet (41)\Fristet (41)_20111114_22002255.ts)
2011-11-15 23:42:58.381 [DEBUG][17] DeleteRecording@5

[johnsonx42]

you need to post your nrecord log for the same time period. I suspect what you'll find is that NRecord died doing a graph stop sometime between 22:59:41 and 23:42:57.

[perry]

The quote part is the Nrecord log. But your right about graph stopping, it happen 40 min before

2011-11-15 21:59:02.475 [DEBUG][7] Started recording (1213:F:\NextPVR\Optagelser\Fristet (42)\Fristet (42)_20111115_22002250.ts)
2011-11-15 21:59:02.483 [DEBUG][7] No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2011-11-15 22:10:00.168 [DEBUG][7] Stopping recording (1214). Past end time of recording.
2011-11-15 22:10:00.178 [INFO][7] DigitalRecorder.StopStream() handle: F
2011-11-15 22:10:00.184 [INFO][7] No more streams active. Stopping device.
2011-11-15 22:10:00.184 [DEBUG][7] Graph stopping...
2011-11-15 22:57:56.856 [DEBUG][21] DeleteRecording(1216)

[BrettB]

The quote part is the Nrecord log. But your right about graph stopping, it happen 40 min before

Yes, I've seen several cases in the last week where I discover that the NRecord service is "hung" when I attempt to delete a recording from the PCH. (I say "hung" because the Recording Service hasn't actually died, but one or more of its threads has become unresponsive causing recordings to not stop, to not start, etc.) (FYI in my cases, the recording does get deleted, but the PCH doesn't get the message back and then it does hang.)

I haven't made any software changes to NPVR (i.e. no new patches, config changes, etc.) since installing the R2 patch set when it came out. But, these problems seem to now be happening more frequently now (every 1-2 days). I haven't been able to figure out why. I'm really hoping that sub will be able to locate the cause and get it fixed in a new release for us soon!

[Reddwarf]

The case where Nrecord gets unresponsive is something I have also seen on rare occations. As far as I remember, every time it has happend I deleted a recording while Nrecord was recording, and I had to kill NextPVR from task manager. Ntray stops responding and it is also impossible to start the System plugin, but as soon as Nrecord has finished recording everything loosens up again. I have not been able to reproduce it at will, and the logs showed nothing out of the ordinary.

[johnsonx42]

The quote part is the Nrecord log. But your right about graph stopping, it happen 40 min before

there are more messages for a completed graph stop. when you just see that one message, it means NRecord died trying to stop the graph. anything after that which depends on NRecord will hang

[Deev8]

It looks very similar to the issues reported in this thread.

[sub]

It looks very similar to the issues reported in this thread.

Yeah, it's exactly the same.

[perry]

Any debugger we can try?