Normal
I think I have seen this problem before. Refer to the comments starting here:https://forum.team-mediaportal.com/threads/tswriter-deadlock-potential-fix.114898/page-29#post-1033905The graph does not run.[collapse]12-11-2013 20:10:32.668 analyzer: reset12-11-2013 20:10:32.668 PatParser::Reset12-11-2013 20:10:32.684 NIT:Reset12-11-2013 20:10:32.684 PatParser::Reset done12-11-2013 20:10:32.684 cagrabber: reset12-11-2013 20:10:32.700 epg: reset12-11-2013 20:10:32.715 epg:ResetEPG()12-11-2013 20:10:32.715 mhw reset12-11-2013 20:10:32.731 CMpTsFilter:ause()[/collapse][collapse][2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: StartTimeShifting TV3 22[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: epg stop[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: find free card for channel TV3[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: find card for channel TV3[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: got 1 tuning details for TV3[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: channel #1 DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8 [2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:2 typevbIP cannot tune to channel[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:3 type:Analog is disabled[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT can tune to channel[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:5 typevbC is disabled[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:6 typevbC is disabled[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:7 typevbT channel not mapped[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:8 typevbT channel not mapped[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:9 typevbT is disabled[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:10 typevbT is disabled[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT can tune to channel[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: found 2 card(s) for channel[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 36 msec[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: found 2 free card(s)[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 42 msec[2013-11-12 20:10:32,622] [Log ] [7 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=66, tuningdetails=DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8[2013-11-12 20:10:32,622] [Log ] [7 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=67, tuningdetails=DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT users: 0[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT is available[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT users: 0[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT is available[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: try max 2 of 2 cards for timeshifting[2013-11-12 20:10:32,637] [Log ] [7 ] [DEBUG] - CardReservationBase: tvcard=11, user=setuptv-22--1, dbChannel=22, ticket=66, tunestate=Tuning, stopstate=Idle[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - card: CardTune 11 TV3 LINKÖPING setuptv-22--1:11:-1[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - card: Tune on card 11 to subchannel TV3 LINKÖPING[2013-11-12 20:10:32,653] [Log ] [7 ] [DEBUG] - card: user: setuptv-22--1:11:-1 tune DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8[2013-11-12 20:10:32,653] [Log ] [7 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events[2013-11-12 20:10:32,653] [Log ] [7 ] [INFO ] - dvbt: TuneVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8[2013-11-12 20:10:32,653] [Log ] [7 ] [INFO ] - dvbubmiting tunerequest Channel:TV3 LINKÖPING subChannel:-1 [2013-11-12 20:10:32,668] [Log ] [7 ] [INFO ] - dvb:Getting new subchannel[2013-11-12 20:10:32,668] [Log ] [7 ] [INFO ] - dvb:GetNewSubChannel:0 #0[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - TvDvbChannel ctor new subchIndex:0[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - card: AddTuneEvent card: 11 / subch: 0[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - dvbubmit tunerequest size:1 new:0[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - subch:0 OnBeforeTune[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvbubmit tunerequest calling put_TuneRequest[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvbubmit tunerequest done calling put_TuneRequest[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - subch:0 OnAfterTune[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - RunGraph[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - subch:0 OnGraphStart[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvb: RunGraph[/collapse]With this TsWriter we can't see whether TsWriter deadlocks in pause (extremely unlikely). I think this is related to TBS tuners, same as with @[USER=54136]mcraenz[/USER]. Hmmm...
I think I have seen this problem before. Refer to the comments starting here:
https://forum.team-mediaportal.com/threads/tswriter-deadlock-potential-fix.114898/page-29#post-1033905
The graph does not run.
[collapse]
12-11-2013 20:10:32.668 analyzer: reset
12-11-2013 20:10:32.668 PatParser::Reset
12-11-2013 20:10:32.684 NIT:Reset
12-11-2013 20:10:32.684 PatParser::Reset done
12-11-2013 20:10:32.684 cagrabber: reset
12-11-2013 20:10:32.700 epg: reset
12-11-2013 20:10:32.715 epg:ResetEPG()
12-11-2013 20:10:32.715 mhw reset
12-11-2013 20:10:32.731 CMpTsFilter:ause()[/collapse]
[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: StartTimeShifting TV3 22
[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: epg stop
[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: find free card for channel TV3
[2013-11-12 20:10:32,575] [Log ] [7 ] [INFO ] - Controller: find card for channel TV3
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: got 1 tuning details for TV3
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: channel #1 DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:1 type:RadioWebStream cannot tune to channel
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:2 typevbIP cannot tune to channel
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:3 type:Analog is disabled
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT can tune to channel
[2013-11-12 20:10:32,590] [Log ] [7 ] [INFO ] - Controller: card:5 typevbC is disabled
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:6 typevbC is disabled
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:7 typevbT channel not mapped
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:8 typevbT channel not mapped
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:9 typevbT is disabled
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:10 typevbT is disabled
[2013-11-12 20:10:32,606] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT can tune to channel
[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: found 2 card(s) for channel
[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 36 msec
[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: found 2 free card(s)
[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 42 msec
[2013-11-12 20:10:32,622] [Log ] [7 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=66, tuningdetails=DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8
[2013-11-12 20:10:32,622] [Log ] [7 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=67, tuningdetails=DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8
[2013-11-12 20:10:32,622] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT users: 0
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:11 typevbT is available
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT users: 0
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: card:4 typevbT is available
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - Controller: try max 2 of 2 cards for timeshifting
[2013-11-12 20:10:32,637] [Log ] [7 ] [DEBUG] - CardReservationBase: tvcard=11, user=setuptv-22--1, dbChannel=22, ticket=66, tunestate=Tuning, stopstate=Idle
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - card: CardTune 11 TV3 LINKÖPING setuptv-22--1:11:-1
[2013-11-12 20:10:32,637] [Log ] [7 ] [INFO ] - card: Tune on card 11 to subchannel TV3 LINKÖPING
[2013-11-12 20:10:32,653] [Log ] [7 ] [DEBUG] - card: user: setuptv-22--1:11:-1 tune DVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8
[2013-11-12 20:10:32,653] [Log ] [7 ] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events
[2013-11-12 20:10:32,653] [Log ] [7 ] [INFO ] - dvbt: TuneVBT:tv:Viasat AB TV3 LINKÖPING Freq:474000 ONID:8945 TSID:1110 SID:3110 PMT:0xC26 FTA:False LCN:3 BandWidth:8
[2013-11-12 20:10:32,653] [Log ] [7 ] [INFO ] - dvbubmiting tunerequest Channel:TV3 LINKÖPING subChannel:-1
[2013-11-12 20:10:32,668] [Log ] [7 ] [INFO ] - dvb:Getting new subchannel
[2013-11-12 20:10:32,668] [Log ] [7 ] [INFO ] - dvb:GetNewSubChannel:0 #0
[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - TvDvbChannel ctor new subchIndex:0
[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - card: AddTuneEvent card: 11 / subch: 0
[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - dvbubmit tunerequest size:1 new:0
[2013-11-12 20:10:32,700] [Log ] [7 ] [INFO ] - subch:0 OnBeforeTune
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvbubmit tunerequest calling put_TuneRequest
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvbubmit tunerequest done calling put_TuneRequest
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - subch:0 OnAfterTune
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - RunGraph
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - subch:0 OnGraphStart
[2013-11-12 20:10:32,731] [Log ] [7 ] [INFO ] - dvb: RunGraph
[/collapse]
With this TsWriter we can't see whether TsWriter deadlocks in pause (extremely unlikely). I think this is related to TBS tuners, same as with @[USER=54136]mcraenz[/USER]. Hmmm...