"The tuner being used for LiveTV is no longer available"

[sub]

Give this patch a go. It'll hopefully fix an issue that can cause this on digital tuners (related to handle numbers being reused).

[johnsonx42]

Thanks Martin. I don't think I'm seeing that exact behaviour here, but I'll check for it. It seems this problem manifests a little differently for different users, and not at all for some. It probably depends on various configuration details like how many tuners, what type, channel configuration, etc. Hopefully sub will be able to spot the error(s) in the code and solve this all before too long.

edit: oh, looky there, sub has gone and spotted the errors in the code and issued a patch while I was typing! hooray!

[johnsonx42]

waiting 15 minutes for a recording to finish so I can put in that patch has never seemed so long as now....

update: no luck restarting recording service after patch, complained about tcp port being already in use; rebooting....

[mvallevand]

My problems are very easy to reproduce, and are on my analog tuners unfortunately that last one didn't help.

Martin

[johnsonx42]

so far so good. I repeated the test procedure I did in the original post, and all is well.

I have a scheduled recording starting in 10 minutes; I will leave live TV running and see how it fairs when the recording starts (per post 14).

sub, I think 1.5.28 + a few patches is the bee's knees, the mutt's nutts, the cat's meow, a few other expressions I can't think of right now...

[johnsonx42]

sorry Martin, I have no analog tuners actually in use, so I guess my jubilation doesn't extend to your situation.

[johnsonx42]

LiveTV still died when the recording started at 10:30pm. I'm guessing it must be related to the same issue, just a further fix needed.

Code:

2010-08-16 22:28:27.820    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:28:37.936    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:28:47.943    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:28:58.009    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:08.071    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:18.134    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:28.197    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:38.250    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:48.321    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:29:58.383    [DEBUG][15]    Renew lease ok (0x5F20009)
2010-08-16 22:30:00.251    [DEBUG][6]    Scheduled recording is supposed to start on tuner currently being used for live tv. Need to look for alternative tuner.
2010-08-16 22:30:00.258    [DEBUG][6]    Found alternative tuner.
2010-08-16 22:30:00.258    [DEBUG][6]    Moving from: Hauppauge WinTV 885 BDA Tuner/Demod     (ATSC)
2010-08-16 22:30:00.258    [DEBUG][6]    Moving to: WinTV HVR-950 BDA Tuner     (ATSC)
2010-08-16 22:30:00.331    [DEBUG][6]    About to start recording (4580): F:\Recordings\Seinfeld\Seinfeld_20100816_22302300.ts
2010-08-16 22:30:00.331    [INFO][6]    DigitalRecorder.StartStream(F:\Recordings\Seinfeld\Seinfeld_20100816_22302300.ts)
2010-08-16 22:30:00.331    [DEBUG][6]    Switching BDA graph to tune:
<tuning>
  <type>ATSC</type>
  <locator>
    <physical_channel>13</physical_channel>
  </locator>
  <service_id>3</service_id>
  <tsid>301</tsid>
  <service_type>1</service_type>
</tuning>

2010-08-16 22:30:00.924    [DEBUG][6]    locked=1, present=1, strength=-10000, quality=100   (took 16ms to check)
2010-08-16 22:30:00.926    [INFO][6]    DigitalRecorder.StartStream() allocated handle: 0xA
2010-08-16 22:30:00.929    [DEBUG][6]    Temp at 8/16/2010 10:30:10 PM
2010-08-16 22:30:00.929    [DEBUG][6]    Started recording (4580:F:\Recordings\Seinfeld\Seinfeld_20100816_22302300.ts)
2010-08-16 22:30:01.034    [DEBUG][6]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat
2010-08-16 22:30:08.443    [DEBUG][15]    Renew lease failed (0x5F20009)
2010-08-16 22:30:08.443    [DEBUG][15]    liveHandleRenewalTime contains 1 handles:
2010-08-16 22:30:08.444    [DEBUG][15]     - 0x5F20009
2010-08-16 22:30:08.572    [INFO][15]    DigitalRecorder.StopStream() handle: 9

edit: attached full log

[johnsonx42]

The recording that killed Live TV is again running on forever. Interestingly, when it starts the next recording at 23:00, it also still thinks LiveTV is running and goes to look for an alternate tuner, but I never restarted LiveTV after it ended at 22:30.

Code:

2010-08-16 23:00:00.015    [DEBUG][6]    Stopping recording (4580). Past end time of recording.
2010-08-16 23:00:00.015    [INFO][6]    DigitalRecorder.StopStream() handle: 10
2010-08-16 23:00:00.176    [DEBUG][6]    Starting: C:\Users\Public\NPVR\Scripts\PostProcessing.bat "F:\Recordings\Seinfeld\Seinfeld_20100816_22302300.ts" 13
2010-08-16 23:00:00.238    [DEBUG][6]    Scheduled recording is supposed to start on tuner currently being used for live tv. Need to look for alternative tuner.
2010-08-16 23:00:00.246    [DEBUG][6]    Found alternative tuner.
2010-08-16 23:00:00.246    [DEBUG][6]    Moving from: Hauppauge WinTV 885 BDA Tuner/Demod     (ATSC)
2010-08-16 23:00:00.246    [DEBUG][6]    Moving to: WinTV HVR-950 BDA Tuner     (ATSC)
2010-08-16 23:00:00.368    [DEBUG][6]    About to start recording (4592): F:\Recordings\Friends\Friends_20100816_23002330.ts
2010-08-16 23:00:00.368    [INFO][6]    DigitalRecorder.StartStream(F:\Recordings\Friends\Friends_20100816_23002330.ts)
2010-08-16 23:00:00.368    [DEBUG][6]    Switching BDA graph to tune:
<tuning>
  <type>ATSC</type>
  <locator>
    <physical_channel>31</physical_channel>
  </locator>
  <service_id>3</service_id>
  <tsid>293</tsid>
  <service_type>1</service_type>
</tuning>

2010-08-16 23:00:01.186    [DEBUG][6]    locked=1, present=1, strength=-10000, quality=100   (took 16ms to check)
2010-08-16 23:00:01.188    [INFO][6]    DigitalRecorder.StartStream() allocated handle: 0xB
2010-08-16 23:00:01.189    [DEBUG][6]    Temp at 8/16/2010 11:00:11 PM
2010-08-16 23:00:01.189    [DEBUG][6]    Started recording (4592:F:\Recordings\Friends\Friends_20100816_23002330.ts)
2010-08-16 23:00:01.355    [DEBUG][6]    No C:\Users\Public\NPVR\Scripts\ParallelProcessing.bat

[mvallevand]

The recording that killed Live TV is again running on forever. Interestingly, when it starts the next recording at 23:00, it also still thinks LiveTV is running and goes to look for an alternate tuner, but I never restarted LiveTV after it ended at 22:30.

That is what I suspected would happen, when I said restarting the service once wasn't enough. We are seeing the same thing.

Martin

[johnsonx42]

I didn't restart the recording service between the two shows, those were back to back recordings; the next recording after that at 23:30 proceeded normally with no mention of live tv. The 22:30 and 23:00 recordings were still running though, so I did restart the recording service to stop those sometime after midnight. I had to stop and restart twice though before I got a usable system.