CAM limit not working properly after Mantis 0001515 (1 Viewer)

[vuego]

TV-Server Version: 1.0 RC2 build 20268
MediaPortal Version: 1.0 RC2 build 20268
MediaPortal Skin: Blue Two Wide
Windows Version: Vista Ultimate x86 SP1
CPU Type: Intel Core 2 Duo E6400
HDD: 2*400GB Samsung RAID0
Memory: 2*1GB PC4200
Motherboard: Gigabyte GA-965G-DS3
Video Card: Gigabyte X1950 Pro 256MB
Video Card Driver:
Sound Card: Creative Soundblaster X-Fi Platinum
Sound Card AC3:
Sound Card Driver:
1. TV Card: Terratec Cinergy 1200 DVB-C
1. TV Card Type: DVB-C
1. TV Card Driver:

I have one DVB-C card with CAM limit set to 2. When changing channel while recording I get "No free cards available" using SVN 19876 and later. Also if I'm recording and timeshifting and stop the timeshift, all channels becomes unavailable. This does not happen using SVN 19843.

Steps involved:
1. Start timeshift channel #1

1 timeshifting, 0 recording
works fine with both 19843 and 19876​

2008-09-21 16:27:31.015625 [10]: Controller: StartTimeShifting SVT1 172
2008-09-21 16:27:31.031250 [10]: Controller: find free card for channel SVT1
2008-09-21 16:27:31.031250 [10]: Controller: got 1 tuning details for SVT1
2008-09-21 16:27:31.031250 [10]: Controller: channel #1 DVBC:tv:Com Hem SVT1 Freq:290000 ONID:40999 TSID:45 SID:1097 PMT:106 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:27:31.031250 [10]: Controller: card:1 typevbC is available priority:2 #users:0 same transponder:False
2008-09-21 16:27:31.031250 [10]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:27:31.031250 [10]: Controller: found 1 available

2. Start manual record channel #1

1 timeshifting, 1 recording
works fine with both 19843 and 19876​

2008-09-21 16:57:44.515625 [5]: Recording Manual (SVT1) added in _recordingsInProgressList
2008-09-21 16:57:44.515625 [5]: Scheduler : time to record TvDatabase.Channel 2008-09-21 16:57:44-2008-09-22 16:57:44 Manual (SVT1)
2008-09-21 16:57:44.515625 [5]: Controller: find free card for channel SVT1
2008-09-21 16:57:44.515625 [5]: Controller: got 1 tuning details for SVT1
2008-09-21 16:57:44.515625 [5]: Controller: channel #1 DVBC:tv:Com Hem SVT1 Freq:290000 ONID:40999 TSID:45 SID:1097 PMT:106 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:57:44.531250 [5]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:57:44.531250 [5]: Controller: card:1 typevbC is available priority:102 #users:1 same transponder:True
2008-09-21 16:57:44.531250 [5]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:57:44.531250 [5]: Controller: found 1 available
2008-09-21 16:57:44.531250 [5]: Scheduler : record on recommended card:1 priority:2
2008-09-21 16:57:44.531250 [5]: Scheduler : record, first tune to channel

3. Start timeshift channel #2 (stop timeshift channel #1)

1 timeshifting, 1 recording
works fine with both 19843 and 19876 but different logs​

19843:

2008-09-21 16:57:51.750000 [6]: Controller: channel #1 DVBC:tv:Com Hem SVT2 Freq:290000 ONID:40999 TSID:45 SID:1098 PMT:107 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:57:51.750000 [6]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:57:51.750000 [6]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:57:51.750000 [6]: Controller: found 1 available

2008-09-21 16:57:51.765625 [6]: Controller: TimeShiftingWouldUseCard SVT2 173
2008-09-21 16:57:51.765625 [6]: Controller: find free card for channel SVT2
2008-09-21 16:57:51.765625 [6]: Controller: got 1 tuning details for SVT2
2008-09-21 16:57:51.765625 [6]: Controller: channel #1 DVBC:tv:Com Hem SVT2 Freq:290000 ONID:40999 TSID:45 SID:1098 PMT:107 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:57:51.765625 [6]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:57:51.765625 [6]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:57:51.765625 [6]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:57:51.765625 [6]: Controller: found 1 available
2008-09-21 16:57:51.781250 [6]: Controller: StartTimeShifting SVT2 173
2008-09-21 16:57:51.781250 [6]: Controller: find free card for channel SVT2
2008-09-21 16:57:51.781250 [6]: Controller: got 1 tuning details for SVT2
2008-09-21 16:57:51.781250 [6]: Controller: channel #1 DVBC:tv:Com Hem SVT2 Freq:290000 ONID:40999 TSID:45 SID:1098 PMT:107 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:57:51.781250 [6]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:57:51.781250 [6]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:57:51.781250 [6]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:57:51.781250 [6]: Controller: found 1 available

19876:

2008-09-21 16:28:03.703125 [11]: Controller: TimeShiftingWouldUseCard SVT2 173
2008-09-21 16:28:03.703125 [11]: Controller: find free card for channel SVT2
2008-09-21 16:28:03.703125 [11]: Controller: got 1 tuning details for SVT2
2008-09-21 16:28:03.703125 [11]: Controller: channel #1 DVBC:tv:Com Hem SVT2 Freq:290000 ONID:40999 TSID:45 SID:1098 PMT:107 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:28:03.703125 [11]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:28:03.703125 [11]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:28:03.703125 [11]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:28:03.703125 [11]: Controller: found 1 available
2008-09-21 16:28:03.703125 [11]: Controller: StartTimeShifting SVT2 173
2008-09-21 16:28:03.718750 [11]: Controller: find free card for channel SVT2
2008-09-21 16:28:03.718750 [11]: Controller: got 1 tuning details for SVT2
2008-09-21 16:28:03.718750 [11]: Controller: channel #1 DVBC:tv:Com Hem SVT2 Freq:290000 ONID:40999 TSID:45 SID:1098 PMT:107 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:28:03.718750 [11]: Controller: card:1 typevbC is tuned to same transponder decrypting 1/2 channels
2008-09-21 16:28:03.718750 [11]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:28:03.718750 [11]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:28:03.718750 [11]: Controller: found 1 available

4. Change to channel #3 (stop timeshift channel #2)

1 timeshifting, 1 recording
works fine in 19843, failed using 19876​

19843:

2008-09-21 16:58:02.218750 [12]: Controller: channel #1 DVBC:tv:Com Hem SVT Barn&Kunskap Freq:290000 ONID:40999 TSID:45 SID:1107 PMT:101 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:58:02.218750 [12]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels
2008-09-21 16:58:02.218750 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:58:02.218750 [12]: Controller: found 1 available

2008-09-21 16:58:02.234375 [12]: Controller: TimeShiftingWouldUseCard SVT Barn&Kunskap 175
2008-09-21 16:58:02.234375 [12]: Controller: find free card for channel SVT Barn&Kunskap
2008-09-21 16:58:02.234375 [12]: Controller: got 1 tuning details for SVT Barn&Kunskap
2008-09-21 16:58:02.234375 [12]: Controller: channel #1 DVBC:tv:Com Hem SVT Barn&Kunskap Freq:290000 ONID:40999 TSID:45 SID:1107 PMT:101 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:58:02.234375 [12]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels
2008-09-21 16:58:02.234375 [12]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:58:02.234375 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:58:02.234375 [12]: Controller: found 1 available
2008-09-21 16:58:02.250000 [12]: Controller: StartTimeShifting SVT Barn&Kunskap 175
2008-09-21 16:58:02.250000 [12]: Controller: find free card for channel SVT Barn&Kunskap
2008-09-21 16:58:02.250000 [12]: Controller: got 1 tuning details for SVT Barn&Kunskap
2008-09-21 16:58:02.250000 [12]: Controller: channel #1 DVBC:tv:Com Hem SVT Barn&Kunskap Freq:290000 ONID:40999 TSID:45 SID:1107 PMT:101 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:58:02.250000 [12]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels
2008-09-21 16:58:02.250000 [12]: Controller: card:1 typevbC is available priority:2 #users:1 same transponder:True
2008-09-21 16:58:02.250000 [12]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:58:02.250000 [12]: Controller: found 1 available

19876:

2008-09-21 16:29:08.453125 [17]: Controller: TimeShiftingWouldUseCard TV4 169
2008-09-21 16:29:08.453125 [17]: Controller: find free card for channel TV4
2008-09-21 16:29:08.453125 [17]: Controller: got 1 tuning details for TV4
2008-09-21 16:29:08.453125 [17]: Controller: channel #1 DVBC:tv:Com Hem TV4 Freq:290000 ONID:40999 TSID:45 SID:1012 PMT:133 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:29:08.453125 [17]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels. cam limit reached
2008-09-21 16:29:08.453125 [17]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:29:08.453125 [17]: Controller: found 0 available
2008-09-21 16:29:08.468750 [17]: Controller: StartTimeShifting TV4 169
2008-09-21 16:29:08.468750 [17]: Controller: find free card for channel TV4
2008-09-21 16:29:08.468750 [17]: Controller: got 1 tuning details for TV4
2008-09-21 16:29:08.468750 [17]: Controller: channel #1 DVBC:tv:Com Hem TV4 Freq:290000 ONID:40999 TSID:45 SID:1012 PMT:133 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 16:29:08.468750 [17]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels. cam limit reached
2008-09-21 16:29:08.468750 [17]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 16:29:08.468750 [17]: Controller: found 0 available
2008-09-21 16:29:08.484375 [17]: Controller: StartTimeShifting failed:AllCardsBusy

My guess is that Mantis 1515 caused this issue.

Sorry for the long post. I didn't want to leave out any details

 

[gibman]

I'll look at it 2nite.

Nice bug report btw.

All channels that u use in this bug report, are they located on same transponder or not ?
Also are they FTA or encrypted ?

/gibman

 

[vuego]

Thanks
All channels are on the same transponder and all are encrypted.

 

[gibman]

are u able to get into IRC 2nite?

 

[gibman]

Try the attached file + latest SVN.

/gibman

 

[vuego]

Yes, I'm on IRC in the main channel.

The attached TVLibrary.dll gave me the same error

2008-09-21 20:28:55.255363 [19]: Controller: TimeShiftingWouldUseCard SVT24 6
2008-09-21 20:28:55.256340 [19]: Controller: find free card for channel SVT24
2008-09-21 20:28:55.257316 [19]: Controller: got 1 tuning details for SVT24
2008-09-21 20:28:55.258293 [19]: Controller: channel #1 DVBC:tv:Com Hem SVT24 Freq:290000 ONID:40999 TSID:45 SID:1099 PMT:105 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 20:28:55.265128 [19]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels. cam limit reached
2008-09-21 20:28:55.267081 [19]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 20:28:55.268058 [19]: Controller: found 0 available
2008-09-21 20:28:55.275870 [19]: Controller: StartTimeShifting SVT24 6
2008-09-21 20:28:55.276846 [19]: Controller: find free card for channel SVT24
2008-09-21 20:28:55.281729 [19]: Controller: got 1 tuning details for SVT24
2008-09-21 20:28:55.281729 [19]: Controller: channel #1 DVBC:tv:Com Hem SVT24 Freq:290000 ONID:40999 TSID:45 SID:1099 PMT:105 FTA:False LCN:10000 SymbolRate:6875 Modulation:Mod64Qam
2008-09-21 20:28:55.289541 [19]: Controller: card:1 typevbC is tuned to same transponder decrypting 2/2 channels. cam limit reached
2008-09-21 20:28:55.291494 [19]: Controller: card:2 type:RadioWebStream cannot tune to channel
2008-09-21 20:28:55.292470 [19]: Controller: found 0 available
2008-09-21 20:28:55.295400 [19]: Controller: StartTimeShifting failed:AllCardsBusy

I'm using SVN 20268.

 

[vuego]

Here's what I did exactly:
1. Open My TV and press TV On
2. Click Record Now
3. Open Mini EPG (Channel button) and select an available channel (timeshift changes and the recording is still on)
4. Open Mini EPG again and select another available channel
= error message "No free card available" (but still recording correctly)

Image 1 - Recording SVT1
Image 2 - Recording SVT1 and timeshifting SVT2
Image 3 - Trying to change to SVT24
Image 4 - Still recording but all channels unavailable

 

[gibman]

I'd like to see a clean tv server log of this situation u have described.

remember to delete logs first.
and try and keep the logs as "clean" as possible, meaning, only do the basic things needed for reproducing this bug.

/gibman

 

[gibman]

Have to be careful not to break mantis 1515 while fixing this one.

could u try attachment?

/gibman

 

[vuego]

This issue is fixed using 1.1.0 Alpha SVN 23361