2 files created when recording

[pcostanza]

On another system with an HVR1600 the exact same thing just happened to me. 2 files both 6kb each in size.
Oddly, if I use Vista Media Center, the first time I boot up and try to watch or record any ATSC channel, I get a message ' No tuner available to satisfy the current request' Not sure yet but I think it may have something to do with the order of my tuners but I'll have to play around with it later and see how it goes.

[ktalley]

Paul (or anyone else):
Did you ever figure anything out with this issue?

Thanks,
Kevin T.

[pcostanza]

No and the problem is now happening much more. I'm now getting the 2 6kb files instead of 1 6kb file and 1 complete file. I still don't know why but it's only the digital channels that this happens to. I'm wondering if it's not getting a lock on the signal and cuts off but I hope to have some time this weekend to test more.
I was also going to wait until early Dec. when sub gets back to ask him to take a look at it. Kevin, if there's something particular you want me to try as a comparison to your problems, just let me know.

[ktalley]

Well, at this point, Sub may be the only one who can figure this out. I don't see anything obvious in the logs to indicate what's going on. While I already posted an attachment of my "native" recording log, but here's a snippet of the relevant section of the other recording log:

[SIZE="2"]GBPVRRecordingService.exe.log (I've reformatted/edited a bit for display purposes)
===================================================================================
18:50:28.296 V [5] pending: (335) 5 WTVFDT 11/11/2008 7:00 PM
19:00:00.203 V [5] Changing to channel (GBPVR.Backend.Common.NullTunerController): 5
19:00:00.203 V [5] Requesting IRecordingSource to start recording:
I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.mpg
19:00:00.203 V [5] start recording @1
19:00:00.203 V [5] start recording @2
19:00:00.203 V [5] BDARecordingSource about to start recording oid:2 channel:T:56,5,1
file:I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.mpg
19:00:02.609 V [5] start recording @3
19:00:02.609 V [5] start recording @4
19:00:02.609 V [5] Programme is now recording
19:00:02.609 V [5] RecordingFactory.save()
19:00:02.703 V [5] Save(): Updated existing scheduled recording
19:00:02.703 V [5] Adding I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms
to parallel processing queue
19:00:25.406 I [5] Recording is unusually small. Giving second chance:
I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms (5584)
19:00:25.406 V [5] Stopping recording
19:00:25.406 V [5] Stopping recording oid:2
19:00:26.953 V [5] Restarting recording
19:00:26.984 V [5] BDARecordingSource about to start recording oid:2 channel:T:56,5,1
file:I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms
20:10:55.156 V [5] Recording has passed endtime+padding. Stopping.
20:10:55.156 V [5] Stopping recording oid:2
20:10:56.187 V [5] getValue cached value: /settings/ProcessCommercialsDuringRecording : false
20:10:56.203 V [5] Adding I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms
to commercial detection queue
20:10:56.203 V [5] Adding I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms
to conversion detection queue
20:10:56.203 V [14] getValue cached value: /settings/CommercialDetectionScheme : 1
20:10:56.203 I [14] Post Processing thread starting...
20:10:56.312 V [5] RecordingStatus is now RecordingStatus.RECORDING_COMPETED
20:10:56.312 V [5] RecordingFactory.save()
20:10:56.421 V [5] Save(): Updated existing scheduled recording
20:10:56.421 V [5] RecordingFactory.loadSchedule()
20:10:56.421 V [5] getValue cached value: /settings/AutoRemoveMissingRecordings : true
20:10:56.421 V [5] getValue cached value: /settings/CheckForRenamedRecordings : true
20:10:56.484 V [5] checking recordings
20:10:56.484 V [5] - checking I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111184000.dvr-ms
20:10:56.484 V [5] - checking I:\PVR_Recordings\Manual Recordings\5 WTVFDT_20081111190000.dvr-ms[/SIZE]


As you can see, at "19:00:02.609" I get a "Programme is now recording" immediately followed by "RecordingFactory.save()". A bit later, we see an entry about the recording being unusually small.


Oh well -- Sub, we humbly await your genius.....

[pcostanza]

So the message 'giving second chance' could be the reason why we see 2, 6kb files? Makes me wonder even more if it could be a signal issue. However, on the other side, I removed the cable from 1 card and scheduled a recording and let it go for the full 30 minutes and got a full 30 minutes of 'snow'. It got a signal if you could call it that.
Do you have more than 1 tuner that is set to use ATSC? I ask because on both of my systems I do and at times, have more than 1 ATSC recording at the same time but even with no other recordings set on these systems I see the same problem.

[ktalley]

No, only one ATSC tuner in my setup. I have a PVR-150 for analog (s-video from Dish Network tuner) and an HVR-950 for OTA/ATSC (outdoor HD antenna).

As for the "second chance", yes I would tend to agree that it's having some issue that makes the initial recording stop. But I sure would think there would be something in the "native" log (I attached a zip of mine earlier) to indicate something like a signal loss/cutout. HOWEVER, I'm also a .NET developer for a living, and there ARE times when I don't trap and/or log exceptions properly in my code, so maybe we're just losing an error along the way.

[sub]

It looks at the file after about 25 seconds and if its too small then it decides the file isnt growing normally, and restarts it. This is usually caused by the device failing to lock onto a signal for some reason, and no data is delivered.

[pcostanza]

sub, if you have the time, please look at my logs. I can play the channel fine in live tv but the minute I ctrl-k or schedule a manual or advanced recording, I get the dual, 6kb files. I see an 'error not connected' message a few times and am not sure what is not connected.
Thanks

[ktalley]

Hey Paul...
Are you still having this issue? I have been playing around with MediaPortal recently (not that I think it's any better, just experimenting -- FWIW, it records my digital ATSC streams without a hitch -- no signal lock issues) and saw that Sub now has a new release out. I'd definitely prefer to "come home" to GB-PVR, but was curious if this issue remains.

Thanks!
Kevin T.

[sub]

Hey Paul...
Are you still having this issue? I have been playing around with MediaPortal recently (not that I think it's any better, just experimenting -- FWIW, it records my digital ATSC streams without a hitch -- no signal lock issues) and saw that Sub now has a new release out. I'd definitely prefer to "come home" to GB-PVR, but was curious if this issue remains.

I'm fairly sure the issue that caused this has been resolved in 1.3.11.