[fixed] the runaway recordings just keep coming (1 Viewer)

mrkaras

Portal Pro
December 9, 2006
541
7
Home Country
Unfortunatly it appares that those attached files are not compatable with the RC1 build, worth a try but the server doesn't seem to run or can't be connected to.
 

disaster123

MP Donator
  • Premium Supporter
  • May 14, 2008
    3,558
    434
    Home Country
    Germany Germany
    AW: the runaway recordings just keep coming

    i had again a runaway recording that didn't stop.

    error.log is full of:
    Code:
    2010-03-07 21:50:47.484200 [Scheduler timer(12)]: Exception   :Error: UnexpectedRowCountUnclassified
    Gentle.Common.GentleException: The number of returned rows 0 did not match the expected count of 1.
    If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
       bei Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
       bei Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
       bei Gentle.Common.Check.Verify(Boolean condition, Error error, Object[] args)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Type type, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Object obj, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.Persistent.Refresh()
       bei TvService.Scheduler.StopRecord(RecordingDetail recording)


    This always seems to happen, when i'm recording two overlapping shows on the same channel.
     

    Seidelin

    Retired Team Member
  • Premium Supporter
  • August 14, 2006
    1,755
    652
    Kgs. Lyngby
    Home Country
    Denmark Denmark
    I have recompiled gibmans patch against RC1 (attached).
     

    Attachments

    • RunAwayFix_forRC1.zip
      30.6 KB

    disaster123

    MP Donator
  • Premium Supporter
  • May 14, 2008
    3,558
    434
    Home Country
    Germany Germany
    AW: the runaway recordings just keep coming

    is this patch already in SVN?
     

    gibman

    Retired Team Member
  • Premium Supporter
  • October 4, 2006
    2,998
    1,372
    Aarhus
    Home Country
    Denmark Denmark
    Please test build 2 here.

    I discovered some more bugs during refactoring.

    Also implemented a safe guard, that makes sure that any recording about to start is not already recording.
    Normally this will only happen when tvclient or any other service erronously inserts duplicate once schedules in the DB.
    In this event, the scheduler will now compare any schedule that is about to start, if it is already recording or not.

    Also built for RC1 :)

    I'll commit it, once I get more feedback :)

    /gibman
     

    Attachments

    • refactored_schedule2.patch
      30.7 KB
    • runway_recordings2_RC1.zip
      30.7 KB

    disaster123

    MP Donator
  • Premium Supporter
  • May 14, 2008
    3,558
    434
    Home Country
    Germany Germany
    AW: the runaway recordings just keep coming

    wanted to try your lastest patch but tortoise tells me it is outdated... (tried to patch against 25202)
     

    Seidelin

    Retired Team Member
  • Premium Supporter
  • August 14, 2006
    1,755
    652
    Kgs. Lyngby
    Home Country
    Denmark Denmark
    I fixed gibmans patch so it is uptodate. (And I added an extra space just for fun).
     

    Attachments

    • refactored_schedule2.patch
      30.7 KB

    disaster123

    MP Donator
  • Premium Supporter
  • May 14, 2008
    3,558
    434
    Home Country
    Germany Germany
    AW: the runaway recordings just keep coming

    thanks applied his patch manually - so let's wait if this fixes this damn issue
     

    pocketpal

    Portal Member
    January 9, 2005
    49
    0
    Indiana, USA
    Home Country
    United States of America United States of America
    I've been having this issue fairly often since installing RC1.

    Last night when I got home, I found two HUUUUGE runaway recordings that had caused practically all of the other recordings to be bumped off the hard drive. WAF immediately suffered, but I promised to try this possible fix that had been posted that very day. I replaced the .dll and .exe with the ones provided in the .zip, and so far so good -- several programs were recorded since, and they were all the proper length.

    Only time will tell for sure, so I will update again one way or the other.
     

    disaster123

    MP Donator
  • Premium Supporter
  • May 14, 2008
    3,558
    434
    Home Country
    Germany Germany
    AW: the runaway recordings just keep coming

    Sorry - but gibman's "fix" does not fix this. It still happens - but errors have changed.

    Logs are 200MB big - so can't upload.

    tvservice.exe (SVN 25212 + gibmans fix) running with 100% CPU


    tv.log
    Code:
    2010-03-09 23:15:13.223400 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.223400 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.239000 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.239000 [(16)]: tvdvbchannel.OnStopRecording subch=0, subch index=0
    2010-03-09 23:15:13.239000 [(16)]: tvdvbchannel.OnStopRecording subch:0-0 tswriter StopRecording...
    2010-03-09 23:15:13.239000 [(16)]: mdplug: FreeChannel RTL Television
    2010-03-09 23:15:13.239000 [(16)]: mdplug: usage counter for channel 'RTL Television' is zero
    2010-03-09 23:15:13.239000 [(16)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
    2010-03-09 23:15:13.239000 [(16)]: DVB subch:0 Decompose()
    2010-03-09 23:15:13.239000 [(16)]: FreeSubChannel CA: freeing sub channel : 0
    2010-03-09 23:15:13.239000 [(16)]: tvcard:FreeSubChannel : no subchannels present, stopping graph
    2010-03-09 23:15:13.239000 [(16)]: dvb:StopGraph called
    2010-03-09 23:15:13.239000 [(16)]: tvcard:FreeAllSubChannels
    2010-03-09 23:15:13.239000 [(16)]: mdplug: FreeAllChannels
    2010-03-09 23:15:13.239000 [(16)]: dvb:StopGraph
    2010-03-09 23:15:13.254600 [(16)]: user:scheduler242 remove
    2010-03-09 23:15:13.254600 [(16)]: dvb:StopGraph called
    2010-03-09 23:15:13.254600 [(16)]: tvcard:FreeAllSubChannels
    2010-03-09 23:15:13.254600 [(16)]: mdplug: FreeAllChannels
    2010-03-09 23:15:13.254600 [(16)]: dvb:StopGraph filterstate already stopped, returning.
    2010-03-09 23:15:13.254600 [Channel state thread(17)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.254600 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.270200 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.270200 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.270200 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.270200 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.270200 [Channel state thread(20)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.270200 [Channel state thread(20)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.285800 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.285800 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.285800 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.285800 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.285800 [Channel state thread(23)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.285800 [Channel state thread(23)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.301400 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.301400 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.301400 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.301400 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.317000 [Channel state thread(20)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.317000 [Channel state thread(20)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.317000 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.317000 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.317000 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.317000 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.332600 [Channel state thread(17)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.332600 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 3 msec
    2010-03-09 23:15:13.332600 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.332600 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.332600 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.348200 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.348200 [Channel state thread(17)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.348200 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.348200 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.348200 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.363800 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.363800 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.363800 [Channel state thread(17)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.363800 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 1 msec
    2010-03-09 23:15:13.363800 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.363800 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.379400 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.379400 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    2010-03-09 23:15:13.379400 [Channel state thread(17)]: Controller: DoSetChannelStates for 136 channels
    2010-03-09 23:15:13.379400 [Channel state thread(17)]: ChannelStates.DoSetChannelStates took 2 msec
    2010-03-09 23:15:13.379400 [Scheduler timer(4)]: Scheduler: stop record RTL Television 09.03.2010 22:10:16-09.03.2010 23:10:00 Monk
    2010-03-09 23:15:13.395000 [(16)]: card: StopRecording card=27, user=scheduler242
    2010-03-09 23:15:13.395000 [(16)]: card: StopRecording for card:27
    2010-03-09 23:15:13.395000 [(16)]: card: StopRecording user 'scheduler242' not recording, skipping
    ...

    error.log
    Code:
    2010-03-09 23:15:13.270200 [Scheduler timer(4)]: Exception   :Error: UnexpectedRowCountUnclassified
    Gentle.Common.GentleException: The number of returned rows 0 did not match the expected count of 1.
    If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
       bei Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
       bei Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
       bei Gentle.Common.Check.Verify(Boolean condition, Error error, Object[] args)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Type type, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Object obj, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.Persistent.Refresh()
       bei TvService.Scheduler.StopRecord(RecordingDetail recording)
    
    2010-03-09 23:15:13.270200 [Scheduler timer(4)]: Exception   :Error: UnexpectedRowCountUnclassified
    Gentle.Common.GentleException: The number of returned rows 0 did not match the expected count of 1.
    If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
       bei Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
       bei Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
       bei Gentle.Common.Check.Verify(Boolean condition, Error error, Object[] args)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Type type, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Object obj, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.Persistent.Refresh()
       bei TvService.Scheduler.StopRecord(RecordingDetail recording)
    
    2010-03-09 23:15:13.301400 [Scheduler timer(4)]: Exception   :Error: UnexpectedRowCountUnclassified
    Gentle.Common.GentleException: The number of returned rows 0 did not match the expected count of 1.
    If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
       bei Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
       bei Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
       bei Gentle.Common.Check.Verify(Boolean condition, Error error, Object[] args)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Type type, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Object obj, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.Persistent.Refresh()
       bei TvService.Scheduler.StopRecord(RecordingDetail recording)
    
    2010-03-09 23:15:13.317000 [Scheduler timer(4)]: Exception   :Error: UnexpectedRowCountUnclassified
    Gentle.Common.GentleException: The number of returned rows 0 did not match the expected count of 1.
    If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
       bei Gentle.Common.Check.FailWith(Severity severity, Error error, Exception e, String msg)
       bei Gentle.Common.Check.Fail(Exception e, Error error, Object[] args)
       bei Gentle.Common.Check.Verify(Boolean condition, Error error, Object[] args)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Type type, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.PersistenceBroker.Retrieve(Object obj, Key key, IDbConnection conn, IDbTransaction tr)
       bei Gentle.Framework.Persistent.Refresh()
       bei TvService.Scheduler.StopRecord(RecordingDetail recording)
    ...

    forgot to say that i'm using: refactored_schedule2.patch
    and that the recording was stopped successful this time! but tvservice wasn't usable anymore.
     

    Users who are viewing this thread

    Top Bottom