NPVR stops to work / recordinga missing and not done ok

Zacken · 2012-03-21, 09:14 PM

I have some strange thing happens
NPVR upto patch 33 including skip
1) some times then I delete file and then I answer "OK" it just hung , file is deleted
If start NPVR again same will happen next time, If I reboot it works again (this from NPVR.log)

Code:
2012-03-21 18:57:17.078    [DEBUG][1]    OnKeyDown() done. Took 81,0046005249023 ms

2012-03-21 18:57:17.321    [DEBUG][1]    alpha = 0

2012-03-21 18:57:17.346    [DEBUG][1]    alpha = 25

2012-03-21 18:57:17.365    [DEBUG][1]    alpha = 44

2012-03-21 18:57:17.382    [DEBUG][1]    alpha = 62

2012-03-21 18:57:17.403    [DEBUG][1]    alpha = 83

2012-03-21 18:57:17.420    [DEBUG][1]    alpha = 100

2012-03-21 18:57:17.441    [DEBUG][1]    alpha = 122

2012-03-21 18:57:17.458    [DEBUG][1]    alpha = 139

2012-03-21 18:57:17.479    [DEBUG][1]    alpha = 161

2012-03-21 18:57:17.500    [DEBUG][1]    alpha = 182

2012-03-21 18:57:17.521    [DEBUG][1]    alpha = 204

2012-03-21 18:57:17.542    [DEBUG][1]    alpha = 225

2012-03-21 18:57:17.560    [DEBUG][1]    alpha = 243

2012-03-21 18:57:17.581    [DEBUG][1]    Calling dispose on old popup(2): ShowDetailsPopup

2012-03-21 18:57:17.581    [DEBUG][1]    ShowDetailsPopup.Dispose()

2012-03-21 18:57:17.581    [DEBUG][1]    UiStatic.Dispose()

2012-03-21 18:57:17.660    [DEBUG][1]    OnKeyDown() called: Return

2012-03-21 18:57:17.660    [DEBUG][1]    Deleting recording (file:D:\multimedia\pvr\NCIS Los Angeles\NCIS Los Angeles_20120316_00300130.ts)

Sven Ãke

Zacken · 2012-03-21, 09:15 PM

2) is more difficult to explain, ofter reboot I get a 60 minute recording 192Kb and logfile Nrecord says this

Code:
2012-03-21 19:01:18.119    [DEBUG][7]    allocating recording target filename: D:\multimedia\pvr\Extreme makeover weightloss\Extreme makeover weightloss_20120321_19002000.ts

2012-03-21 19:01:18.119    [DEBUG][7]    About to start recording (3121 on 23): D:\multimedia\pvr\Extreme makeover weightloss\Extreme makeover weightloss_20120321_19002000.ts...

2012-03-21 19:01:18.228    [INFO][7]    AnalogRecorder.StartStream(D:\multimedia\pvr\Extreme makeover weightloss\Extreme makeover weightloss_20120321_19002000.ts)

2012-03-21 19:01:18.228    [INFO][7]    AnalogRecorder stream count now 1

2012-03-21 19:01:18.228    [INFO][7]    Running blaster: Q:\DATA\USBUIRTSend\USBUIRTSend.exe Q:\DATA\USBUIRTSend\digiality.dat 7

2012-03-21 19:01:19.040    [INFO][7]    Creating graph

2012-03-21 19:01:19.086    [DEBUG][7]    Building graph using: Hauppauge WinTV PVR PCI II Capture (1)

2012-03-21 19:01:20.241    [INFO][4]    Starting web server on: 8866

2012-03-21 19:01:20.241    [INFO][4]    Setting working directory to: C:\Program Files\NPVR\

2012-03-21 19:01:20.241    [INFO][4]    Web root: C:\Users\Public\NPVR\web

2012-03-21 19:01:20.740    [DEBUG][7]    about to look for encoder

2012-03-21 19:01:20.756    [DEBUG][7]    About to look for hardware encoder.

2012-03-21 19:01:20.756    [DEBUG][7]    PVR150/250/350/500 Capture

2012-03-21 19:01:20.756    [DEBUG][7]    AttachHardEncoder@1

2012-03-21 19:01:20.756    [DEBUG][7]    AttachHardEncoder@2 (got medium)

2012-03-21 19:01:20.771    [DEBUG][7]    AttachHardEncoder@3 (got encoder)

2012-03-21 19:01:20.771    [DEBUG][7]    AttachHardEncoder@4 (about to connect encoder)

2012-03-21 19:01:20.802    [DEBUG][7]    AttachHardEncoder@5 (connected encoder)

2012-03-21 19:01:20.802    [DEBUG][7]    AttachHardEncoder@6 (connected demux)

2012-03-21 19:01:20.818    [DEBUG][7]    about to add mux

2012-03-21 19:01:20.818    [DEBUG][7]    about to add tee

2012-03-21 19:01:20.818    [DEBUG][7]    about to connect writers

2012-03-21 19:01:20.849    [DEBUG][7]    Graph filter list:

2012-03-21 19:01:20.849    [DEBUG][7]     - NPVR Writer2

2012-03-21 19:01:20.849    [DEBUG][7]     - NPVR Writer

2012-03-21 19:01:20.849    [DEBUG][7]     - Infinite Pin Tee Filter

2012-03-21 19:01:20.849    [DEBUG][7]     - MainConcept (HCW) MPEG Multiplexer-Plus

2012-03-21 19:01:20.849    [DEBUG][7]     - MPEG-2 Demultiplexer

2012-03-21 19:01:20.849    [DEBUG][7]     - Hauppauge WinTV PVR PCI II Encoder

2012-03-21 19:01:20.849    [DEBUG][7]     - Hauppauge WinTV PVR PCI II Capture

2012-03-21 19:01:20.849    [DEBUG][7]     - Hauppauge WinTV PVR PCI II Crossbar

2012-03-21 19:01:20.849    [DEBUG][7]     - Hauppauge WinTV PVR PCI II TvAudio

2012-03-21 19:01:20.849    [DEBUG][7]     - Hauppauge WinTV PVR PCI II TvTuner

2012-03-21 19:01:20.880    [DEBUG][7]    About to check crossbar routing

2012-03-21 19:01:20.880    [DEBUG][7]    SVideo: v-idx=2, a-idx=4

2012-03-21 19:01:21.005    [DEBUG][7]    Audio crossbar routed successfully

2012-03-21 19:01:21.270    [DEBUG][7]    Video crossbar routed successfully

2012-03-21 19:01:21.270    [INFO][7]    About to start graph

2012-03-21 19:01:21.270    [DEBUG][7]    device minimum bitrate: 2500000

2012-03-21 19:01:21.270    [DEBUG][7]    device maximum bitrate: 8500000

2012-03-21 19:01:21.270    [DEBUG][7]    device bitrate step size: 1

2012-03-21 19:01:21.270    [DEBUG][7]    device reports current bitrate as: 5000000

2012-03-21 19:01:21.270    [DEBUG][7]    device reports current peak bitrate as: 7000000

2012-03-21 19:01:21.270    [DEBUG][7]    About to set BITRATE MODE

2012-03-21 19:01:21.270    [DEBUG][7]    requesting variable encoding

2012-03-21 19:01:21.286    [DEBUG][7]    trying to request bitrate: 3000000

2012-03-21 19:01:21.302    [DEBUG][7]    device confirms bitrate as: 3000000

2012-03-21 19:01:21.302    [DEBUG][7]    About to set PEAK BITRATE 

2012-03-21 19:01:21.302    [DEBUG][7]    device minimum peak bitrate: 2250000

2012-03-21 19:01:21.302    [DEBUG][7]    device maximum peak bitrate: 9500000

2012-03-21 19:01:21.302    [DEBUG][7]    device peak bitrate step size: 1

2012-03-21 19:01:21.302    [DEBUG][7]    trying to request peak bitrate: 5000000

2012-03-21 19:01:21.317    [DEBUG][7]    device confirms bitrate as: 5000000

2012-03-21 19:01:22.004    [DEBUG][9]    Client Listener starting on port 7969

2012-03-21 19:01:23.080    [DEBUG][7]    About to tune graph to: 

<tuning>

  <type>Analog</type>

  <locator>

    <channel>7</channel>

    <country>46</country>

    <input>cable</input>

    <source>SVideo</source>

    <fixed>2</fixed>

  </locator>

</tuning>

2012-03-21 19:01:23.080    [DEBUG][7]    About to check crossbar routing

2012-03-21 19:01:23.080    [DEBUG][7]    SVideo: v-idx=2, a-idx=4

2012-03-21 19:01:23.252    [DEBUG][7]    Audio crossbar routed successfully

2012-03-21 19:01:23.252    [DEBUG][7]    Video crossbar already routed correctly

2012-03-21 19:01:23.252    [DEBUG][7]    About to switch AnalogRecorder to target: 

D:\multimedia\pvr\Extreme makeover weightloss\Extreme makeover weightloss_20120321_19002000.ts

2012-03-21 19:01:23.579    [DEBUG][7]    Temp at 2012-03-21 19:01:33

2012-03-21 19:01:23.579    [DEBUG][7]    Started recording (3121:2:D:\multimedia\pvr\Extreme makeover weightloss\Extreme makeover weightloss_20120321_19002000.ts)

2012-03-21 19:01:23.766    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat

2012-03-21 19:01:26.996    [DEBUG][4]    OnStart() complete...

2012-03-21 19:59:00.182    [DEBUG][7]    Recording due to start pre-padding, but tuner not available

3 ) next record says "NO errors , but no data delivered. This is usually becacuse the device faild to"
In LOG flie NO errors!
What do I look for

Code:
21:00:00.262    [DEBUG][7]    allocating recording target filename: D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120321_21002130.ts

21:00:00.262    [DEBUG][7]    About to start recording (3088 on 23): D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120321_21002130.ts...

21:00:00.262    [INFO][7]    AnalogRecorder.StartStream(D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120321_21002130.ts)

21:00:00.262    [INFO][7]    AnalogRecorder reuse stream. count still 1

21:00:00.262    [INFO][7]    Running blaster: Q:\DATA\USBUIRTSend\USBUIRTSend.exe Q:\DATA\USBUIRTSend\digiality.dat 58

21:00:01.395    [DEBUG][7]    About to tune graph to: 

<tuning>

  <type>Analog</type>

  <locator>

    <channel>58</channel>

    <country>46</country>

    <input>cable</input>

    <source>SVideo</source>

    <fixed>2</fixed>

  </locator>

</tuning>

2012-03-21 21:00:01.395    [DEBUG][7]    About to check crossbar routing

2012-03-21 21:00:01.395    [DEBUG][7]    SVideo: v-idx=2, a-idx=4

2012-03-21 21:00:01.466    [DEBUG][7]    Audio crossbar routed successfully

2012-03-21 21:00:01.466    [DEBUG][7]    Video crossbar already routed correctly

2012-03-21 21:00:01.466    [DEBUG][7]    About to switch AnalogRecorder to target: 

D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120321_21002130.ts

2012-03-21 21:00:01.467    [DEBUG][7]    Temp at 2012-03-21 21:00:11

2012-03-21 21:00:01.467    [DEBUG][7]    Started recording (3088:4:D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120321_21002130.ts)

2012-03-21 21:00:01.475    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat

2012-03-21 21:00:01.483    [DEBUG][7]     - deleting extra recording: D:\multimedia\pvr\NASCAR Now\NASCAR Now_20120320_10301100.ts (2012-03-20 09:30:00)

2012-03-21 21:00:01.552    [DEBUG][7]    Scheduled Recording about to start maybe a candidate for moving to another tuner (different locator than another recording on same device)

2012-03-21 21:00:01.553    [DEBUG][7]    Existing: 

<channel>8</channel><country>46</country><input>cable</input><source>Composite</source><fixed>2</fixed>

2012-03-21 21:00:01.553    [DEBUG][7]    Required: 

<channel>5</channel><country>46</country><input>cable</input><source>Composite</source><fixed>2</fixed>

2012-03-21 21:00:01.553    [DEBUG][7]    Was allocated to capture source26

2012-03-21 21:00:01.562    [DEBUG][7]    Recording not moved. No other available tuner could be found.

2012-03-21 21:00:01.562    [DEBUG][7]    allocating recording target filename: D:\multimedia\pvr\Greys Anatomy\Greys Anatomy_20120321_21002155.ts

2012-03-21 21:00:01.562    [DEBUG][7]    About to start recording (3124 on 26): D:\multimedia\pvr\Greys Anatomy\Greys Anatomy_20120321_21002155.ts...

2012-03-21 21:00:01.562    [INFO][7]    AnalogRecorder.StartStream(D:\multimedia\pvr\Greys Anatomy\Greys Anatomy_20120321_21002155.ts)

2012-03-21 21:00:01.562    [INFO][7]    AnalogRecorder reuse stream. count still 1

2012-03-21 21:00:01.563    [INFO][7]    Running blaster: Q:\DATA\USBUIRTSend\USBUIRTSend.exe "Q:\DATA\USBUIRTSend\ViasatPlus pace 460.dat" 5

2012-03-21 21:00:02.526    [DEBUG][7]    About to tune graph to: 

<tuning>

  <type>Analog</type>

  <locator>

    <channel>5</channel>

    <country>46</country>

    <input>cable</input>

    <source>Composite</source>

    <fixed>2</fixed>

  </locator>

</tuning>

2012-03-21 21:00:02.526    [DEBUG][7]    About to check crossbar routing

2012-03-21 21:00:02.526    [DEBUG][7]    Composite: v-idx=1, a-idx=4

2012-03-21 21:00:02.626    [DEBUG][7]    Audio crossbar routed successfully

2012-03-21 21:00:02.626    [DEBUG][7]    Video crossbar already routed correctly

2012-03-21 21:00:02.626    [DEBUG][7]    About to switch AnalogRecorder to target: 

D:\multimedia\pvr\Greys Anatomy\Greys Anatomy_20120321_21002155.ts

2012-03-21 21:00:02.642    [DEBUG][7]    Temp at 2012-03-21 21:00:12

2012-03-21 21:00:02.642    [DEBUG][7]    Started recording (3124:3:D:\multimedia\pvr\Greys Anatomy\Greys Anatomy_20120321_21002155.ts)

2012-03-21 21:00:02.649    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat

2012-03-21 21:04:00.207    [DEBUG][7]    Stopping recording (3095 on 23). Past end time of recording. 3

2012-03-21 21:04:00.218    [DEBUG][7]    No C:\Users\Public\NPVR\Scripts\PostProcessing.bat

2012-03-21 21:04:00.218    [DEBUG][7]    Stopping recording (3098 on 26). Past end time of recording. 2

Sven Ãke

***sub*** · 2012-03-21, 09:18 PM

Can you post the complete npvr.log and matching nrecord.log when this happens?

Zacken · 2012-03-21, 09:41 PM

Here is log files
edited;
3 search for efterlyst and Nascar

Sven Ãke

***sub*** · 2012-03-21, 10:06 PM

I'm not really sure what to suggest for #2 & #3. Its unusual for a analog device to either deliver nothing or spontaneously stop after delivering a small amount of data. I'm guessing this is somehow driver related.

So you have the complete log showing #1?

Zacken · 2012-03-21, 10:34 PM

Quote:So you have the complete log showing #1?

Sorry I forget this

Quote:Its unusual for a analog device to either deliver nothing or spontaneously stop after delivering a small amount of data. I'm guessing this is somehow driver related.

I have been running GBpvr until 2 weeks ago and have NO problem with doing recording
I have done recording on same device today without any problem (se logfile)
I think I have same drivers , I will check that

Sven Ãke

***sub*** · 2012-03-21, 10:44 PM

Zacken Wrote:I have been running GBpvr until 2 weeks ago and have NO problem with doing recording
I have done recording on same device today without any problem (se logfile)
I think I have same drivers , I will check that

Sorry, not really sure what to suggest on this one. There is no obvious sign of any problems in those log snippets.

Zacken · 2012-03-22, 01:42 PM

Last night all recording failed " recording service not running at recoding time", only 1 of them started right as you saw in nrecord (Greys 21:00)

I could see it Nrecord.exe was running and using 49% and 50520Kb (no recording was scheduled at this time

After reboot and som recording that was OK Nrecord used 1-3% and 37204kB

have now done 4 recording after rebot
all OK

Sven Ãke

johnsonx42 · 2012-03-22, 09:55 PM

Graph stop crash:

Code:
2012-03-21 21:59:00.153    [DEBUG][7]    Stopping recording (3124 on 26). Past end time of recording. 3

2012-03-21 21:59:00.162    [INFO][7]    AnalogRecorder stream count now 0

2012-03-21 21:59:00.162    [DEBUG][7]    Graph stopping...

  ---- missing Graph Stopped message; only the EPG update thread runs after this ----

2012-03-22 06:01:17.298    [DEBUG][8]    About to update EPG

do you have a c:\temp folder? if not, create it. if so, is there a crash.mdmp in there? post it if so, though sub probably won't be able to do much with it since he's well into npvr 2.4.x by now.

@sub - maybe analog recording needs to use out-of-process recording too?

Zacken · 2012-03-23, 07:11 AM

Quote:do you have a c:\temp folder?

C:\Temp Created

Last night every thing worked OK so this is a problem that came and goes

any time schedule for 2.4?

Sven Ãke

Possibly Related Threads…
Thread		Author	Replies	Views	Last Post
	Missing Audio	NND	39	8,490	2023-06-24, 01:42 PM Last Post: mvallevand
	npvr is not responding	johndutcher	3	1,493	2022-05-05, 07:57 PM Last Post: sub
	NPVR 4.2.4 Video Library No Longer Shows folder.jpg	Lao Pan	12	3,865	2022-04-14, 04:33 AM Last Post: Antmannz
	nPVR Continually Wakes PC	bfos	23	11,171	2022-02-17, 03:48 PM Last Post: sub
	Can't find tuner filter in NPVR 4.05 on old XP machine (eee 701) with RTL2832U	iamanotheruser	3	1,690	2022-01-03, 04:45 AM Last Post: mvallevand
	ITV/BBC HD and EPG missing from scan and update	angelisle	16	4,122	2021-08-26, 11:45 AM Last Post: NumberFive
	Missing Audio - Switching from MPEG-1 to AC3 and vice versa	NumberFive	15	4,336	2021-05-06, 01:57 AM Last Post: raymondjpg
	Streaming Services with NPVR	tesla1886	4	2,686	2021-01-04, 02:58 AM Last Post: tesla1886
	NPVR choosing incorrect xml guide display-name after reboot	drlava	7	3,058	2020-12-27, 02:27 AM Last Post: sub
	Can NPVR offset only 3 channel listings in EPG?	acemib	20	6,997	2020-04-27, 01:28 PM Last Post: mvallevand