Zero length recordings

[ttfitz]

So, this has become more common recently, and the last time I posted sub said something about if it could be recreated, maybe he could find something. I'm not sure how to go about it, but I have a number of recent instances of this happening, so if there are any suggestions of what I could try, I would appreciate it.

In the attached logs, there are 4 instances where the file ends up as 0 length - Public Morals on 9-22, The Nightly Show with Larry Wilmore on 9-21 and 9-22, and College Football on 9-26 (luckily I recorded this on my other system, too, so I didn't miss it).

Anyway, any help would be appreciated.

[sub]

Is it a SiliconDust HDHR Prime or Hauppauge DCR you're using?

You're probably going to need to catch it when one of these problem recordings is happening, and take a look in the HDHomeRun Config utility, so you can check if the signal is locked at the time, and where it thinks it's streaming to, and what bitrate of data it thinks it's streaming.

[ttfitz]

It's the Prime.

Okay, here's a new one. The Daily Show with Trevor Noah. Log is attached, here's what the config program showed while it was supposedly recording:

[ATTACHMENT NOT FOUND]

Hope that helps. Let me know if I can do anything else - this happened earlier tonight, too, so it is getting worse.

[sub]

If you run "netstat -a -b", do you see any other application using port 36318?

[ttfitz]

The only entry with 36318 says:

UDP 0.0.0.0:36316 *:*
[System]

EDIT - sorry, copied the wrong one, but the entry for 36318 says the same.

[robertclemens]

Any update on this? I think you are talking about the issue I just started having.
NextPVR is recording and mostly everything works but I have some (not all) of my recordings that don't have the correct video length.

Some of them show fine in windows explorer but if you open them in VLC or via another streaming service (such as Plex) they do not see the length.

I do utilize a HDHR Prime as my primary tuner and local Hauppage card as well.

I don't see a 36318 port on my system but do see the UDP 0.0.0.0:36316 as ttfitz pasted above.

Any ideas?

[sub]

Any ideas?

Nope. You're probably best to post your logs, and info about which recording failed. Also check the HDHR Config utility when a failed recording is underway.

[robertclemens]

The recording succeeds. I haven't had a failed recording yet. The length of the video is incorrect on some of the recordings. The HDHR device shows correct tuner lock and frees up after recording is finished.
I restarted the device just to cover that base just now.

Some recordings have shown 25 or 26 hrs as well.

Some recordings only show correct length in Windows Media Player. Opening the file in VLC on files with length issues shows a zero length video.

Here is one comparison of time between Plex and Windows Explorer.
[ATTACHMENT NOT FOUND]

NRecord.log during the episode with length issue (log directory attached):

Code:

2015-09-28 18:59:01.365    [DEBUG][9]    allocating recording target filename: E:\NextPVR\Penn & Teller Fool Us\Penn & Teller Fool Us_20150928_19002000.ts
2015-09-28 18:59:01.365    [DEBUG][9]    About to start recording (3377 on 34): E:\NextPVR\Penn & Teller Fool Us\Penn & Teller Fool Us_20150928_19002000.ts...
2015-09-28 18:59:01.365    [DEBUG][9]    Free space 155138383872  (E:\)
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder.StartStream()
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.0
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.1
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.2 nextPort=36318
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.2.0
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.2.0@allocated
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@2.3
2015-09-28 18:59:01.365    [DEBUG][9]    Allocated port: 36318
2015-09-28 18:59:01.365    [DEBUG][9]    HDHomeRunRecorder@3
2015-09-28 18:59:01.365    [DEBUG][9]    About to start HDHR device. Checking status for information sake... (could tell if it's already in use elsewhere)
2015-09-28 18:59:01.365    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 get /tuner1/status
2015-09-28 18:59:01.400    [DEBUG][9]    Status: ch=none lock=none ss=0 snq=0 seq=0 bps=0 pps=0

2015-09-28 18:59:02.400    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 set /tuner1/lockkey 99034
2015-09-28 18:59:02.445    [DEBUG][9]    SetIncomingPort@2
2015-09-28 18:59:02.445    [DEBUG][9]    SetIncomingPort@exit
2015-09-28 18:59:02.446    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 key 99034 set /tuner1/vchannel 111
2015-09-28 18:59:02.484    [DEBUG][9]    ForcedIP.xml does not exists
2015-09-28 18:59:02.582    [DEBUG][9]    131BE875 can be found via adapter with ip address: 192.168.1.10
2015-09-28 18:59:02.582    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 key 99034 set /tuner1/target rtp://192.168.1.10:36318
2015-09-28 18:59:02.583    [DEBUG][9]    SetIncomingPort@2
2015-09-28 18:59:02.583    [DEBUG][9]    SetIncomingPort@exit
2015-09-28 18:59:02.584    [DEBUG][9]    StartStream() returned handle=65
2015-09-28 18:59:02.584    [DEBUG][9]    Allocated Handle: 65
2015-09-28 18:59:02.585    [DEBUG][9]    Temp at 9/28/2015 6:59:12 PM
2015-09-28 18:59:02.585    [DEBUG][9]    Started recording (3377:65:E:\NextPVR\Penn & Teller Fool Us\Penn & Teller Fool Us_20150928_19002000.ts)
2015-09-28 18:59:02.599    [DEBUG][69]    DownloadMetaData: 'Penn & Teller: Fool Us'
2015-09-28 18:59:02.600    [DEBUG][69]    DownloadMetaData@1
2015-09-28 18:59:02.600    [DEBUG][9]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2015-09-28 18:59:02.600    [DEBUG][69]    DownloadMetaData@2
2015-09-28 18:59:02.600    [DEBUG][69]    DownloadMetaData@want to download to C:\Users\Public\NPVR\media\shows\Penn & Teller: Fool Us.jpg
2015-09-28 18:59:02.833    [DEBUG][69]    DownloadMetaData@3
2015-09-28 18:59:02.833    [DEBUG][69]    DownloadMetaData@4
2015-09-28 18:59:02.833    [DEBUG][69]    DownloadMetaData@4.1
2015-09-28 18:59:02.833    [DEBUG][69]    DownloadMetaData@4.2: Penn & Teller: Fool Us
2015-09-28 18:59:02.833    [DEBUG][69]    http://thetvdb.com/api/3DC9CDF8E41D4737/series/239851/en.xml
2015-09-28 18:59:02.856    [DEBUG][69]    Unexpected error downloading metadata and artwork for: E:\NextPVR\Penn & Teller Fool Us\Penn & Teller Fool Us_20150928_19002000.ts
2015-09-28 18:59:02.856    [DEBUG][69]    DownloadMetaData@exit
2015-09-28 18:59:02.856    [DEBUG][69]    DownloadMetaData: 'Penn & Teller: Fool Us'
2015-09-28 19:02:00.218    [DEBUG][9]    Stopping recording (3314 on 33). Past end time of recording. 63
2015-09-28 19:02:00.228    [DEBUG][9]    StopStream(63)
2015-09-28 19:02:00.230    [INFO][9]    No more streams active. Stopping device.
2015-09-28 19:02:00.230    [DEBUG][9]    SetIncomingPort@2
2015-09-28 19:02:00.230    [DEBUG][9]    SetIncomingPort@exit
2015-09-28 19:02:00.430    [DEBUG][9]    stopping graph
2015-09-28 19:02:00.430    [DEBUG][9]    Graph stopping...
2015-09-28 19:02:00.842    [DEBUG][9]    Graph stopped
2015-09-28 19:02:00.842    [DEBUG][9]    destroying graph
2015-09-28 19:02:00.842    [DEBUG][9]    Removing filter  NPVR Writer
2015-09-28 19:02:00.842    [DEBUG][9]    Removing filter  NPVR Net Push Source
2015-09-28 19:02:00.942    [DEBUG][9]    deallocating ports
2015-09-28 19:02:00.942    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 key 99033 set /tuner0/channel none
2015-09-28 19:02:00.975    [DEBUG][9]    Executing: C:\Program Files\Silicondust\HDHomeRun\hdhomerun_config.exe 131BE875 key 99033 set /tuner0/lockkey none
2015-09-28 19:02:01.011    [DEBUG][9]    StoreMetaData@1
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@2
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@3
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@4
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@5
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@6
2015-09-28 19:02:01.017    [DEBUG][9]    StoreMetaData@7
2015-09-28 19:02:01.018    [DEBUG][9]    StoreMetaData@8
2015-09-28 19:02:01.018    [DEBUG][9]    StoreMetaData@exit

Anything else specifically I should be able to locate for the time length issue?

[sub]

Does it report the correct duration when played in NextPVR?

[robertclemens]

Yes it does. Only NextPVR and Windows Media Player from what I've seen.
Unfortunately I don't have all of the files with issues anymore. Windows Media Player was showing the ones with 25hr or 26hr lengths as well. Most players could not play those. The actual file length of those was appropriate to the show recorded.