Consecutive Schedules fail.

[SciDoctor]

The problem shown in the logs is that the second schedule starts before the first has been fully ended by the TV Service .Affects most of the recent SVNs

When the TV service actualy ends the first recording it is in fact stoping the second recording which has only been recording for a few moments.

The net result in a single tuner setup is that the second recording is only a few kb long.

Also the tuner is locked to this transponder even after the schedule (which isn't even recording properly) until the service is restarted.



11/05/2007 20:00:03 D card: Tuner locked:True signal strength:96 signal quality:42
11/05/2007 20:00:03 B Scheduler : record to F:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.mpg
11/05/2007 20:00:03 D card: StartRecording 1 F:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.ts
11/05/2007 20:00:03 D subch:0 StartRecord(F:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.ts)
11/05/2007 20:00:03 D subch:0 StartRecording...
11/05/2007 20:00:03 D subch:0 set record pid:259 video type:MPEG-2
11/05/2007 20:00:03 D subch:0 set record pid:25A audio lang: type:MPEG-2
11/05/2007 20:00:03 D subch:0 set record pid:25B subtitle type:6
11/05/2007 20:00:03 D subch:0 set record pid:25C audio lang: type:MPEG-2
11/05/2007 20:00:03 D subch:0 record transport stream mode
11/05/2007 20:00:03 B recList:count:2 add scheduleid:65 card:Nebula DigiTV PCI BDA Tuner Filter
11/05/2007 20:00:03 B Scheduler : stop record TvDatabase.Channel 11/05/2007 19:00:02-11/05/2007 20:00:00 Grand Designs
11/05/2007 20:00:03 D card: StopRecording 1
11/05/2007 20:00:03 D card: StopRecording for card:1
11/05/2007 20:00:03 D subch:0 StopRecord()
11/05/2007 20:00:03 B diskmanagement: recording Grand Designs ended. type:EveryTimeOnThisChannel max episodes:5


As you can see above the logs show that the StopRecording for card:1 occurs after the next shedule has started on the card, this seems to be the logic flow error.

[SciDoctor]

This is the log from a multituner setup and as you can see because the schedules are on different transponders the 'logic' assigns each schedule to to a different tuner so the bug doesn't affect in this situation.

11/05/2007 20:00:03 D card: Tuner locked:True signal strength:96 signal quality:42
11/05/2007 20:00:03 B Scheduler : record to C:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.mpg
11/05/2007 20:00:03 D card: StartRecording 1 C:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.ts
11/05/2007 20:00:03 D subch:0 StartRecord(C:\ATI\Smallville-ITV2+1\Smallville--2007-05-11-20_00.ts)
11/05/2007 20:00:03 D subch:0 StartRecording...
11/05/2007 20:00:03 D subch:0 set record pid:259 video type:MPEG-2
11/05/2007 20:00:03 D subch:0 set record pid:25A audio lang: type:MPEG-2
11/05/2007 20:00:03 D subch:0 set record pid:25B subtitle type:6
11/05/2007 20:00:03 D subch:0 set record pid:25C audio lang: type:MPEG-2
11/05/2007 20:00:03 D subch:0 record transport stream mode
11/05/2007 20:00:03 B recList:count:2 add scheduleid:36 card:Nebula DigiTV USB BDA Filter
11/05/2007 20:03:15 B Scheduler : stop record TvDatabase.Channel 11/05/2007 19:00:17-11/05/2007 20:00:00 Grand Designs
11/05/2007 20:03:15 A card: StopRecording 2
11/05/2007 20:03:15 A card: StopRecording for card:2
11/05/2007 20:03:15 A subch:0 StopRecord()
11/05/2007 20:03:15 A card: remove user:scheduler29 sub:0
11/05/2007 20:03:15 A user:scheduler29 remove
11/05/2007 20:03:15 A card: free subchannel sub:0
11/05/2007 20:03:15 A dvb:FreeSubChannel:1 #0
11/05/2007 20:03:15 A subch:0 Decompose()
11/05/2007 20:03:15 A dvb:FreeAllSubChannels:
11/05/2007 20:03:15 A dvbtopGraph
11/05/2007 20:03:15 A dvb:FreeAllSubChannels:
11/05/2007 20:03:15 B diskmanagement: recording Grand Designs ended. type:EveryTimeOnThisChannel max episodes:5


As you can see even though the StopRecording for card:2 occurs after the next schedule has started it doesn't cause problems in this instance as the schedule are on different transponders and hence tuners.

I have yet to show logs for the bug on a multituner setup but with the consecutive recordings on the same transponder Ihave setup some tests so should have logs in a few hours.

[SciDoctor]

Ok logs for consecutive recordings on same transponder/channel with multituner setup.

Bizare error produced

11/05/2007 21:30:13 A Exception ystem.Collections.Generic.KeyNotFoundException: The given key was not present in the dictionary.
at System.ThrowHelper.ThrowKeyNotFoundException()
at System.Collections.Generic.Dictionary`2.get_Item(T Key key)
at TvLibrary.Implementations.DVB.TvCardDVBT.Tune(Int3 2 subChannelId, IChannel channel)
11/05/2007 21:30:13 A Exception :The given key was not present in the dictionary.
11/05/2007 21:30:13 A site :Void ThrowKeyNotFoundException()
11/05/2007 21:30:13 A source :mscorlib
11/05/2007 21:30:13 A stacktrace: at System.ThrowHelper.ThrowKeyNotFoundException()
at System.Collections.Generic.Dictionary`2.get_Item(T Key key)
at TvLibrary.Implementations.DVB.TvCardDVBT.Tune(Int3 2 subChannelId, IChannel channel)
11/05/2007 21:30:13 A Exception ystem.Collections.Generic.KeyNotFoundException: The given key was not present in the dictionary.
at TvLibrary.Implementations.DVB.TvCardDVBT.Tune(Int3 2 subChannelId, IChannel channel)
at TvService.TvCard.Tune(User& user, IChannel channel, Int32 idChannel)
11/05/2007 21:30:13 A Exception :The given key was not present in the dictionary.
11/05/2007 21:30:13 A site :Void ThrowKeyNotFoundException()
11/05/2007 21:30:13 A source :mscorlib
11/05/2007 21:30:13 A stacktrace: at TvLibrary.Implementations.DVB.TvCardDVBT.Tune(Int3 2 subChannelId, IChannel channel)
at TvService.TvCard.Tune(User& user, IChannel channel, Int32 idChannel)


The recordings should have all started on the same tuner as they were all on the same transponder/channel but the consecutive schedule was moved to another tuner when the above error occured.

If all tuners had been busy at the time I doubt the schedule would have recorded, will try the test with a fully loaded schedule to tuner recordings.

[SciDoctor]

This problem has returned in SVN 14689

StopRecording doesn't check that it is stopping the correct recording but just stops all recordings on the specific card.

23/05/2007 22:00:05 12 card: Tuner locked:True signal strength:88 signal quality:37
23/05/2007 22:00:05 5 Scheduler : record to F:\ATI\Four Weddings and a Funeral-Film4+1\Four Weddings and a Funeral-2007-05-23-22_00.mpg
23/05/2007 22:00:05 12 card: StartRecording 2 F:\ATI\Four Weddings and a Funeral-Film4+1\Four Weddings and a Funeral-2007-05-23-22_00.ts
23/05/2007 22:00:05 12 subch:0 StartRecord(F:\ATI\Four Weddings and a Funeral-Film4+1\Four Weddings and a Funeral-2007-05-23-22_00.ts)
23/05/2007 22:00:05 12 subch:0 StartRecording...
23/05/2007 22:00:05 12 subch:0 set record pid:244 video type:MPEG-2
23/05/2007 22:00:05 12 subch:0 set record pid:245 audio lang: type:MPEG-2
23/05/2007 22:00:05 12 subch:0 set record pid:246 audio lang: type:MPEG-2
23/05/2007 22:00:05 12 subch:0 set record pid:247 subtitle type:6
23/05/2007 22:00:05 12 subch:0 record transport stream mode
23/05/2007 22:00:05 5 recList:count:4 add scheduleid:26 card:Nebula DigiTV USB BDA Filter
23/05/2007 22:00:05 5 Scheduler : stop record TvDatabase.Channel 23/05/2007 19:50:09-23/05/2007 22:00:00 Manon Des Sources
23/05/2007 22:00:05 12 card: StopRecording 2
23/05/2007 22:00:05 12 card: StopRecording for card:2
23/05/2007 22:00:05 12 subch:0 StopRecord()
23/05/2007 22:00:05 5 Scheduler : stop record TvDatabase.Channel 23/05/2007 21:00:02-23/05/2007 22:00:00 Shark
23/05/2007 22:00:05 12 card: StopRecording 1
23/05/2007 22:00:05 12 card: StopRecording for card:1
23/05/2007 22:00:05 12 subch:0 StopRecord()
23/05/2007 22:00:05 12 card: remove user:scheduler23 sub:0
23/05/2007 22:00:05 12 user:scheduler23 remove
23/05/2007 22:00:05 12 card: free subchannel sub:0
23/05/2007 22:00:05 12 dvb:FreeSubChannel:1 #0
23/05/2007 22:00:05 12 subch:0 Decompose()
23/05/2007 22:00:05 12 dvb:FreeAllSubChannels:
23/05/2007 22:00:05 12 dvbtopGraph
23/05/2007 22:00:05 12 dvb:FreeAllSubChannels:
23/05/2007 22:00:05 5 diskmanagement: recording Shark ended. type:EveryTimeOnThisChannel max episodes:5

The above shows the next schedule starts before the previous one has ended and the stop recording causes the new schedule to finish with the resulting file only a few kb in size.

The Tv server locks the frequency for the duration of the schedulle even if you delete the schedule form MP.