Multi-tune fails (SubmitTuneRequest: returns 0x1) with Vista TV Pack or Windows 7

Discussion in '1.0 final and SVN Builds' started by markius, January 9, 2009.

  1. markius

    markius Portal Pro

    Joined:
    December 26, 2006
    Messages:
    175
    Likes Received:
    4
    Occupation:
    IT
    Location:
    Maidstone, UK
    Ratings:
    +4 / 0
    Home Country:
    England England
    TV-Server Version: 1.0
    MediaPortal Version: 1.0
    MediaPortal Skin: BlueThree Wide
    Windows Version: Vista Ultimate SP1
    CPU Type: Pentium M 760 (2.0GHz/533/2Mb)
    HDD: Seagate 300Gb 7200RPM SATA 150
    Memory: 2GB PC2-4200
    Motherboard: MSI Speedster-FA4 (MS-9625)
    Video Card: ASUS EAH3450 512MB
    Video Card Driver:
    Sound Card: Intel ICH6 HD Audio / Realtek ALC880
    Sound Card AC3:
    Sound Card Driver:
    1. TV Card: BlackGold BGT3540
    1. TV Card Type: 2xDVB-T, 2xDVB-S
    1. TV Card Driver: 6.0.0.39
    2. TV Card:
    2. TV Card Type:
    2. TV Card Driver:
    3. TV Card:
    3. TV Card Type:
    3. TV Card Driver:
    4. TV Card:
    4. TV Card Type:
    4. TV Card Driver:
    MPEG2 Video Codec: PowerDVD
    MPEG2 Audio Codec: PowerDVD
    h.264 Video Codec: PowerDVD
    Satelite/CableTV Provider: Astra 28.2°E & Eurobird 28.5°E
    HTPC Case: Antec Aria
    Cooling: Stock
    Power Supply: Stock
    Remote: MCE (mark 2)
    TV: Samsung 26in LCD LW26A33W
    TV - HTPC Connection: Sub-D

    UPDATE
    This problem has been pinned down to systems that have either the Vista Media Center TV Pack 2008 or Windows 7 installed.


    Overlapping recordings on the same fail because of a problem with subchannels.

    Use case
    * The TV Service is configured to pre-record for 5 minutes and post record for 15 minutes.

    * Two records are scheduled back to back on the same channel:
    Recording A BBC1 22:00 - 22:30 [Effective 21:55 - 22:45]
    Recording B BBC1 22:30 - 23:00 [Effective 22:25 - 23:15]

    Timeline
    21:55 Time to record Recording A
    21:55 TvService finds a free card and tunes to BBC1 - Recording A begins

    22:25 Time to record Recording B
    22:25 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
    22:25 Tune request fails (SubmitTuneRequest returns 0x01)
    22:26 Time to record Recording B
    22:26 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
    22:26 Tune request fails (SubmitTuneRequest returns 0x01)
    ...
    ... Repeated continuously until
    ...
    22:44 Time to record Recording B
    22:44 TvService finds a card that is already tuned to BBC1 and tries to create a new subchannel
    22:44 Tune request fails (SubmitTuneRequest returns 0x01)

    22:45 End Recording A
    22:45 TvService fully releases the tuner (FreeAllSubchannels) and Recording A ends

    22:45 Time to record Recording B
    22:45 TvService finds a free card and tunes to BBC1 - Recording B begins

    23:15 End Recording B
    23:15 TvService fully releases the tuner (FreeAllSubchannels) and Recording B ends

    Additional use case
    Update: A very similar issue seems to break manual recording. With manual recordings the TvServer attempts to re-use a subchannel instead of create a new one but the result is the same: SubmitTuneRequest: returns 0x1). I've attached some additional logs for this use case.

    If you need any more information please let me know



    Cheers
    Mark

    Log
    The following snippet is an example of what happens when recording B starts. A more complete log is attached

    2009-01-09 23:50:10.282134 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:10-10/01/2009 00:20:00 Family Guy
    2009-01-09 23:50:10.283110 [Scheduler timer]: Controller: find free card for channel BBC THREE
    2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
    2009-01-09 23:50:10.285063 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:10.315335 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
    2009-01-09 23:50:10.333888 [Scheduler timer]: Controller: card:8 type:DvbT is tuned to different transponder
    2009-01-09 23:50:10.334865 [Scheduler timer]: Controller: card:8 type:DvbT is available priority:-93 #users:1 same transponder:False
    2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 type:DvbT is tuned to same transponder no CA present
    2009-01-09 23:50:10.338771 [Scheduler timer]: Controller: card:9 type:DvbT is available priority:6 #users:1 same transponder:True
    2009-01-09 23:50:10.341700 [Scheduler timer]: Controller: card:10 type:DvbS cannot tune to channel
    2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:11 type:DvbS cannot tune to channel
    2009-01-09 23:50:10.344630 [Scheduler timer]: Controller: card:12 type:Analog is disabled
    2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: card:13 type:Analog is disabled
    2009-01-09 23:50:10.345606 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
    2009-01-09 23:50:10.348536 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
    2009-01-09 23:50:10.353418 [Scheduler timer]: Controller: card:10 type:DvbS is available priority:5 #users:0 same transponder:False
    2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:11 type:DvbS is available priority:4 #users:0 same transponder:False
    2009-01-09 23:50:10.357324 [Scheduler timer]: Controller: card:12 type:Analog is disabled
    2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: card:13 type:Analog is disabled
    2009-01-09 23:50:10.358301 [Scheduler timer]: Controller: found 4 available
    2009-01-09 23:50:10.358301 [Scheduler timer]: Scheduler : record on free card:9 priority:6
    2009-01-09 23:50:10.360254 [Scheduler timer]: Scheduler : record, first tune to channel
    2009-01-09 23:50:10.374901 [28]: card: Tune 9 to BBC THREE
    2009-01-09 23:50:10.375878 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:10.381737 [28]: dvbt: Tune:DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:10.382713 [28]: dvbt: tune: Assigning oldChannel
    2009-01-09 23:50:10.382713 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
    2009-01-09 23:50:10.383690 [28]: dvbt: tune: Graph is tunning
    2009-01-09 23:50:10.384666 [28]: dvbt: tune: Getting default locator
    2009-01-09 23:50:10.384666 [28]: dvbt: tune: Putting bandwidth 8
    2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_ONID 9018
    2009-01-09 23:50:10.385643 [28]: dvbt: tune: put_SID 4351
    2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_TSID 4108
    2009-01-09 23:50:10.386619 [28]: dvbt: tune: Carrier frequency 778000
    2009-01-09 23:50:10.386619 [28]: dvbt: tune: put_Locator
    2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:10.387596 [28]: dvbt: tune: Submitting tune request: System.__ComObject
    2009-01-09 23:50:10.389549 [28]: dvb:confused:ubmiting tunerequest Channel:BBC THREE subChannel:-1
    2009-01-09 23:50:10.390525 [28]: dvb:Getting new subchannel
    2009-01-09 23:50:10.390525 [28]: dvb:GetNewSubChannel:1 #2
    2009-01-09 23:50:10.391502 [28]: dvb:confused:ubmit tunerequest size:2 new:2
    2009-01-09 23:50:10.392478 [28]: subch:2 OnBeforeTune
    2009-01-09 23:50:10.394431 [28]: dvb:confused:ubmit tunerequest calling put_TuneRequest
    2009-01-09 23:50:10.395408 [28]: dvb:confused:ubmit tunerequest done calling put_TuneRequest
    2009-01-09 23:50:10.395408 [28]: dvb:confused:ubmitTuneRequest returns:0x1
    2009-01-09 23:50:10.395408 [28]: dvb:confused:ubmitTuneRequest failed - removing subchannel: 2
    2009-01-09 23:50:26.018431 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
    2009-01-09 23:50:26.018431 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:26-10/01/2009 00:20:00 Family Guy
    2009-01-09 23:50:26.019408 [Scheduler timer]: Controller: find free card for channel BBC THREE
    2009-01-09 23:50:26.022337 [Scheduler timer]: Controller: got 2 tuning details for BBC THREE
    2009-01-09 23:50:26.023314 [Scheduler timer]: Controller: channel #1 DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:26.080927 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
    2009-01-09 23:50:26.084833 [Scheduler timer]: Controller: card:8 type:DvbT is tuned to different transponder
    2009-01-09 23:50:26.085810 [Scheduler timer]: Controller: card:8 type:DvbT is available priority:-93 #users:1 same transponder:False
    2009-01-09 23:50:26.088739 [Scheduler timer]: Controller: card:9 type:DvbT is tuned to same transponder no CA present
    2009-01-09 23:50:26.089716 [Scheduler timer]: Controller: card:9 type:DvbT is available priority:6 #users:1 same transponder:True
    2009-01-09 23:50:26.091669 [Scheduler timer]: Controller: card:10 type:DvbS cannot tune to channel
    2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:11 type:DvbS cannot tune to channel
    2009-01-09 23:50:26.094598 [Scheduler timer]: Controller: card:12 type:Analog is disabled
    2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: card:13 type:Analog is disabled
    2009-01-09 23:50:26.095575 [Scheduler timer]: Controller: channel #2 DVBS:tv:BSkyB BBC THREE Freq:10773000 ONID:2 TSID:2045 SID:6319 PMT:0x10D FTA:True LCN:3 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DisEqc:None band:Universal Pilot:NotSet RollOff:NotSet
    2009-01-09 23:50:26.097528 [Scheduler timer]: Controller: card:7 type:RadioWebStream cannot tune to channel
    2009-01-09 23:50:26.102410 [Scheduler timer]: Controller: card:10 type:DvbS is available priority:5 #users:0 same transponder:False
    2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:11 type:DvbS is available priority:4 #users:0 same transponder:False
    2009-01-09 23:50:26.108269 [Scheduler timer]: Controller: card:12 type:Analog is disabled
    2009-01-09 23:50:26.109246 [Scheduler timer]: Controller: card:13 type:Analog is disabled
    2009-01-09 23:50:26.110222 [Scheduler timer]: Controller: found 4 available
    2009-01-09 23:50:26.110222 [Scheduler timer]: Scheduler : record on free card:9 priority:6
    2009-01-09 23:50:26.111199 [Scheduler timer]: Scheduler : record, first tune to channel
    2009-01-09 23:50:26.114128 [28]: card: Tune 9 to BBC THREE
    2009-01-09 23:50:26.115105 [28]: card: user: scheduler86:9:-1 tune DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:26.121940 [28]: dvbt: Tune:DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:26.122917 [28]: dvbt: tune: Assigning oldChannel
    2009-01-09 23:50:26.124870 [28]: dvbt: tune: Current Channel != null DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:False LCN:7 BandWidth:8
    2009-01-09 23:50:26.124870 [28]: dvbt: tune: Graph is tunning
    2009-01-09 23:50:26.125846 [28]: dvbt: tune: Getting default locator
    2009-01-09 23:50:26.125846 [28]: dvbt: tune: Putting bandwidth 8
    2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_ONID 9018
    2009-01-09 23:50:26.126823 [28]: dvbt: tune: put_SID 4351
    2009-01-09 23:50:26.127799 [28]: dvbt: tune: put_TSID 4108
    2009-01-09 23:50:26.127799 [28]: dvbt: tune: Carrier frequency 778000
    2009-01-09 23:50:26.128776 [28]: dvbt: tune: put_Locator
    2009-01-09 23:50:26.128776 [28]: dvbt: tune: Submitting tune request: SubId -1, channel DVBT:tv:BBC BBC THREE Freq:778000 ONID:9018 TSID:4108 SID:4351 PMT:0x10FF FTA:True LCN:7 BandWidth:8
    2009-01-09 23:50:26.129752 [28]: dvbt: tune: Submitting tune request: System.__ComObject
    2009-01-09 23:50:26.129752 [28]: dvb:confused:ubmiting tunerequest Channel:BBC THREE subChannel:-1
    2009-01-09 23:50:26.130729 [28]: dvb:Getting new subchannel
    2009-01-09 23:50:26.130729 [28]: dvb:GetNewSubChannel:1 #3
    2009-01-09 23:50:26.189319 [28]: dvb:confused:ubmit tunerequest size:2 new:3
    2009-01-09 23:50:26.189319 [28]: subch:3 OnBeforeTune
    2009-01-09 23:50:26.190295 [28]: dvb:confused:ubmit tunerequest calling put_TuneRequest
    2009-01-09 23:50:26.191272 [28]: dvb:confused:ubmit tunerequest done calling put_TuneRequest
    2009-01-09 23:50:26.191272 [28]: dvb:confused:ubmitTuneRequest returns:0x1
    2009-01-09 23:50:26.192248 [28]: dvb:confused:ubmitTuneRequest failed - removing subchannel: 3
    2009-01-09 23:50:28.172590 [28]: PowerScheduler: User input detected at 09/01/2009 23:50:28
    2009-01-09 23:50:42.034984 [Scheduler timer]: Recording Family Guy added in _recordingsInProgressList
    2009-01-09 23:50:42.034984 [Scheduler timer]: Scheduler : time to record TvDatabase.Channel 09/01/2009 23:50:42-10/01/2009 00:20:00 Family Guy
     

    Attached Files:

  2. Google AdSense Guest Advertisement



    to hide all adverts.
  3. markius

    markius Portal Pro

    Joined:
    December 26, 2006
    Messages:
    175
    Likes Received:
    4
    Occupation:
    IT
    Location:
    Maidstone, UK
    Ratings:
    +4 / 0
    Home Country:
    England England
    Is anyone else seeing this issue or is it just me?
     
  4. nicksti

    nicksti Portal Pro

    Joined:
    September 1, 2006
    Messages:
    68
    Likes Received:
    1
    Ratings:
    +1 / 0
    hello markius!

    same happened by me. I tried to record two shows as you and the second failed.
     
    • Like Like x 1
  5. iancalderban

    iancalderban Portal Pro

    Joined:
    December 12, 2008
    Messages:
    140
    Likes Received:
    7
    Occupation:
    network consultant
    Location:
    milton keynes
    Ratings:
    +7 / 0
    Home Country:
    United Kingdom United Kingdom
    afraid I've not seen this and I have almost identical setup to you.

    for channels such as BBC1 that exist on both dvb-t and dvb-s I went into the channel mappings etc and made sure that it can be viewed by all 4 tuners - dvb-t and dvb-s.

    I have been able to record 2 consecutive programs that way. Even 2 consecutive on BBCHD which is only on dvb-s. I often record the back-to-back "in the night gardens" for my kids @ 6pm and 6.30.

    I have seen something else which is doing my head in - corrupted recordings where on playback it seems to jump/stutter to frames from different recorded files - but I don't think thats the same problem at all

    if you want to see any of my cfg / logs let me know which files (I am not an mp debugging expert. I can debug big ip networks, but not s/w!)
    ta
    Ian
     
  6. markius

    markius Portal Pro

    Joined:
    December 26, 2006
    Messages:
    175
    Likes Received:
    4
    Occupation:
    IT
    Location:
    Maidstone, UK
    Ratings:
    +4 / 0
    Home Country:
    England England
    Hi Ian

    I'd definately be interested to see the tv.log from around the time that "In the Night Gardens" records. Can I ask, do you have pre and post-recording padding configured?

    I've not had much chance to do much further investigation of this over the last week or so... work has been manic... I've also been debugging IP networks but only in so far as trying to track down machines infected with the kido worm.

    Cheers
    Mark
     
  7. iancalderban

    iancalderban Portal Pro

    Joined:
    December 12, 2008
    Messages:
    140
    Likes Received:
    7
    Occupation:
    network consultant
    Location:
    milton keynes
    Ratings:
    +7 / 0
    Home Country:
    United Kingdom United Kingdom
    yes defo have pre (5) and post (10) . so I end up with overlapped recordings.
    log attached. annoying they go in a hidden directoy on vista!

    i just quickly watched them on ffwd - recording seem to overlap exactly as you'd expect.
    Ian
     

    Attached Files:

  8. markius

    markius Portal Pro

    Joined:
    December 26, 2006
    Messages:
    175
    Likes Received:
    4
    Occupation:
    IT
    Location:
    Maidstone, UK
    Ratings:
    +4 / 0
    Home Country:
    England England
    Hi Ian
    Well, what can I say? Your setup really is working like it says on the tin! This leads me to some more questions:
    * Is there a configuration setting that's breaking subchannels? I notice that you don't pre-load the cards whereas I do.
    * Is the problem limited to certain channels?
    * Is the problem limited to channels with multiple tuning details?

    Hopefully I'll get some time to sit down and figure this out over the weekend. Failing that I'll have a bash at recording "In the Night Garden" back to back. Can you remind me, how is that schedule created on your system - every time on this channel, every time every channel or in some other way?

    Thanks for posting your log

    Have a good weekend
    Mark
     
  9. iancalderban

    iancalderban Portal Pro

    Joined:
    December 12, 2008
    Messages:
    140
    Likes Received:
    7
    Occupation:
    network consultant
    Location:
    milton keynes
    Ratings:
    +7 / 0
    Home Country:
    United Kingdom United Kingdom
    I just go into the EPG and press "record" . it often offers me a number of instances of the same program in the bottom right hand corner, so I usually setup a few days worth from there. I haven't found anything that will do same time same channel for me yet - is there a plugin for that?

    I use djblu's tswriter for freesat epg, not sure if that makes a diff.

    Ian
     
  10. markius

    markius Portal Pro

    Joined:
    December 26, 2006
    Messages:
    175
    Likes Received:
    4
    Occupation:
    IT
    Location:
    Maidstone, UK
    Ratings:
    +4 / 0
    Home Country:
    England England
    OK so it doesn't seem to be related to preloading, or manually creating the recordings instead of using the scheduler.
    I've tried with and without the DJblu tswriter and get the same result.

    I suppose the next step would be reinstall the app but that's such a PIA when you receive 1000 channels - i've been trying to avoid it.

    I've noticed one different between my log and your log. In my log file, when the second recording starts I see this:
    Code (Text):
    1. Scheduler : record on recommended card:10 priority:5
    But in your logs it says this:
    Code (Text):
    1. Scheduler : record on free card:3 priority:5
    I don't know what's the difference between a "free" card and a "recommended" card though

    Mark
     
  11. gibman
    • Team MediaPortal

    gibman Retired Team Member

    Joined:
    October 4, 2006
    Messages:
    2,998
    Likes Received:
    1,321
    Occupation:
    Developer
    Location:
    Aarhus
    Ratings:
    +1,372 / 0
    Home Country:
    Denmark Denmark
    Some questions.

    Is the BBC1 channel FTA or scrambled ?
    Are u able to do b2b recordings on the dvb-s2 device ?

    Tried newer BDA drivers, or even older ones ?
    What card are u using ?
    Is it possible for you to try another dvb-t card, another brand ?

    Can u multitune the dvb-t device using 2 timeshifting sessions on the same BBC1 channel ?
    I guess you need a multiseat setup to test this.
    Or maybe u can tune BB1 in setuptv, and then tune the same channel in a windowed MP session...havent tried it.

    /gibman
     
Loading...

Users Viewing Thread (Users: 0, Guests: 0)

  1. This site uses cookies to help personalise content, tailor your experience and to keep you logged in if you register.
    By continuing to use this site, you are consenting to our use of cookies.
    Dismiss Notice
  • About The Project

    The vision of the MediaPortal project is to create a free open source media centre application, which supports all advanced media centre functions, and is accessible to all Windows users.

    In reaching this goal we are working every day to make sure our software is one of the best.

             

  • Support MediaPortal!

    The team works very hard to make sure the community is running the best HTPC-software. We give away MediaPortal for free but hosting and software is not for us.

    Care to support our work with a few bucks? We'd really appreciate it!