2012-05-29 11:55:40.013975 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:55:40.013975 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:55:40.310375 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:55:40.310375 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:55:40.310375 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:55:40.372775 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:55:40.372775 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 359 msec
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:55:40.372775 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:55:40.372775 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:55:40.372775 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 365 msec
2012-05-29 11:55:55.005601 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:55:55.005601 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:55:55.005601 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:55:55.005601 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:55:55.005601 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:55:55.005601 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:55:55.005601 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:55:55.005601 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:55:55.005601 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:56:10.044027 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:56:10.044027 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:56:10.044027 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:56:10.044027 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:56:10.044027 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:56:10.044027 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:56:10.044027 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:56:10.059627 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:56:10.059627 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:56:25.082453 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:56:25.082453 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:56:25.082453 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:56:25.082453 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:56:25.082453 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:56:25.082453 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:56:25.082453 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:56:25.082453 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:56:25.082453 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:56:40.120880 [(7)]: Controller: find free card for channel 211 CIII
2012-05-29 11:56:40.120880 [(7)]: Controller: find card for channel 211 CIII
2012-05-29 11:56:40.120880 [(7)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:56:40.120880 [(7)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:56:40.120880 [(7)]: Controller: found 2 card(s) for channel
2012-05-29 11:56:40.120880 [(7)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:2 type:Analog is available
2012-05-29 11:56:40.120880 [(7)]: Controller:    card:3 type:Analog is available
2012-05-29 11:56:40.120880 [(7)]: Controller: found 2 free card(s)
2012-05-29 11:56:40.120880 [(7)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:56:55.143706 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:56:55.143706 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:56:55.159306 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:56:55.159306 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:56:55.159306 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:56:55.159306 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:56:55.159306 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:56:55.159306 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:56:55.159306 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:57:10.197732 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:57:10.197732 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:57:10.197732 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:57:10.197732 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:57:10.197732 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:57:10.197732 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:57:10.197732 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:57:10.197732 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:57:10.197732 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:57:25.033358 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:57:25.033358 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:57:25.033358 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:57:25.033358 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:57:25.033358 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:57:25.033358 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:57:25.033358 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:57:25.033358 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:57:25.033358 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:57:40.071784 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:57:40.071784 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:57:40.071784 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:57:40.071784 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:57:40.071784 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:57:40.071784 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:57:40.071784 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:57:40.071784 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:57:40.071784 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:57:55.110210 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:57:55.110210 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:57:55.110210 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:57:55.110210 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:57:55.110210 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:57:55.110210 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:57:55.110210 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:57:55.110210 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:57:55.110210 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:58:10.039436 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:58:10.039436 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:58:10.039436 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:58:10.039436 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:58:10.039436 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:58:10.039436 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:58:10.039436 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:58:10.055036 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:58:10.055036 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:58:10.055036 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:58:10.055036 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:58:10.055036 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:58:10.055036 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:58:10.055036 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:58:10.055036 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:58:10.055036 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:58:10.382637 [(5)]: Deleting invalid recordings
2012-05-29 11:58:23.441860 [(5)]: RTSP: start streamer
2012-05-29 11:58:23.441860 [RTSP Streaming thread(13)]: RTSP: Streamer started
2012-05-29 11:58:23.441860 [(5)]: RTSP: add stream C1079498 file:F:\RecordedTV\Pawn Stars\Pawn Stars - - - Family Feud.ts
2012-05-29 11:58:23.441860 [(5)]: Controller: streaming url:rtsp://MP:554/C1079498 file:F:\RecordedTV\Pawn Stars\Pawn Stars - - - Family Feud.ts
2012-05-29 11:58:31.101473 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:58:31.101473 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:58:31.101473 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:58:31.101473 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:58:31.101473 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:58:31.101473 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:58:31.101473 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:58:31.101473 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:58:31.101473 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:58:40.055889 [(5)]: Controller: find free card for channel 211 CIII
2012-05-29 11:58:40.055889 [(5)]: Controller: find card for channel 211 CIII
2012-05-29 11:58:40.055889 [(5)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:58:40.055889 [(5)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:58:40.055889 [(5)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:58:40.055889 [(5)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:58:40.055889 [(5)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:58:40.071489 [(5)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:58:40.071489 [(5)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:58:40.071489 [(5)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:58:40.071489 [(5)]: Controller: found 2 card(s) for channel
2012-05-29 11:58:40.071489 [(5)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:58:40.071489 [(5)]: Controller:    card:2 type:Analog is available
2012-05-29 11:58:40.071489 [(5)]: Controller:    card:3 type:Analog is available
2012-05-29 11:58:40.071489 [(5)]: Controller: found 2 free card(s)
2012-05-29 11:58:40.071489 [(5)]: AdvancedCardAllocation.GetFreeCardsForChannel took 6 msec
2012-05-29 11:58:44.080696 [(5)]: Controller: streaming url:rtsp://MP:554/C1079498 file:F:\RecordedTV\Pawn Stars\Pawn Stars - - - Family Feud.ts
2012-05-29 11:58:55.078715 [(7)]: Controller: find free card for channel 211 CIII
2012-05-29 11:58:55.078715 [(7)]: Controller: find card for channel 211 CIII
2012-05-29 11:58:55.078715 [(7)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:58:55.078715 [(7)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:58:55.078715 [(7)]: Controller: found 2 card(s) for channel
2012-05-29 11:58:55.078715 [(7)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:2 type:Analog is available
2012-05-29 11:58:55.078715 [(7)]: Controller:    card:3 type:Analog is available
2012-05-29 11:58:55.078715 [(7)]: Controller: found 2 free card(s)
2012-05-29 11:58:55.078715 [(7)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:59:02.630128 [scheduler thread(10)]: Scheduler: Check recordings for schedule The Big Flip...
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Scheduler: Time to record 211 CIII 11:59 AM-12:30 PM The Big Flip
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller: find free card for channel 211 CIII
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller: find card for channel 211 CIII
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:   got 1 tuning details for 211 CIII
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:   channel #1 tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:1 type:DvbIP cannot tune to channel
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:2 type:Analog can tune to channel
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:2 type:Analog users: 0
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:3 type:Analog can tune to channel
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:3 type:Analog users: 0
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:4 type:RadioWebStream cannot tune to channel
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller: found 2 card(s) for channel
2012-05-29 11:59:02.645728 [scheduler thread(10)]: AdvancedCardAllocation.GetAvailableCardsForChannel took 4 msec
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:2 type:Analog is available
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller:    card:3 type:Analog is available
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller: found 2 free card(s)
2012-05-29 11:59:02.645728 [scheduler thread(10)]: AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
2012-05-29 11:59:02.645728 [scheduler thread(10)]: scheduler: try max 2 of 2 FREE cards for recording
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Scheduler : record on recommended card:2 priority:3
2012-05-29 11:59:02.645728 [scheduler thread(10)]: TV3BlasterPlugin: Received TV Server Event "StartRecording"
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Controller: epg stop
2012-05-29 11:59:02.645728 [scheduler thread(10)]: Scheduler : record, first tune to channel
2012-05-29 11:59:02.661328 [scheduler thread(10)]: TV3BlasterPlugin: Received TV Server Event "StartZapChannel"
2012-05-29 11:59:02.661328 [scheduler thread(10)]: TV3BlasterPlugin: Analog channel input source "SvhsInput1"
2012-05-29 11:59:02.661328 [scheduler thread(10)]: TV3BlasterPlugin: Tune request - Card: 2, Channel: 211, 211 CIII
2012-05-29 11:59:02.661328 [scheduler thread(10)]: card: Tune 2 to 211 CIII
2012-05-29 11:59:02.661328 [ProcessExternalChannel(16)]: TV3BlasterPlugin: ProcessExternalCommand("STB: Echostar 3000\2|Port_1", 2, 211)
2012-05-29 11:59:02.661328 [scheduler thread(10)]: card: user: scheduler145:2:-1 tune tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:59:02.661328 [ProcessExternalChannel(16)]: TV3BlasterPlugin - BlastIR(): C:\ProgramData\IR Server Suite\Set Top Boxes\Echostar 3000\2.IR, Port_1
2012-05-29 11:59:02.661328 [scheduler thread(10)]: analog:  Tune:tv:211 CIII Freq:0 Channel:211 Country:Canada Tuner:Cable Video:SvhsInput1 Audio:Automatic
2012-05-29 11:59:02.661328 [scheduler thread(10)]: analog: build graph
2012-05-29 11:59:02.676928 [scheduler thread(10)]: analog: AddTvTunerFilter Hauppauge WinTV PVR PCI II TvTuner
2012-05-29 11:59:02.692528 [scheduler thread(10)]: analog: Using stored capabilities of the tuner
2012-05-29 11:59:02.692528 [scheduler thread(10)]: analog: Using Crossbar configuration from stored graph
2012-05-29 11:59:02.692528 [scheduler thread(10)]: analog: AddCrossBarFilter use:Hauppauge WinTV PVR PCI II Crossbar 0
2012-05-29 11:59:02.910929 [GenericPCQueue(14)]: TV3BlasterPlugin: Received Message "BlastIR"
2012-05-29 11:59:02.910929 [GenericPCQueue(14)]: TV3BlasterPlugin: Blast successful
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: AddCrossBarFilter use:Hauppauge WinTV PVR PCI II Crossbar 1
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: AddCrossBarFilter succeeded
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: Using Crossbar configuration from stored graph succeeded
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: Using TvAudio configuration from stored graph
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: AddTvAudioFilter use:Hauppauge WinTV PVR PCI II TvAudio 0
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: AddTvAudioFilter use:Hauppauge WinTV PVR PCI II TvAudio 1
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: AddTvAudioFilter succeeded:Hauppauge WinTV PVR PCI II TvAudio
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: Using TvAudio configuration from stored graph succeeded
2012-05-29 11:59:03.176129 [scheduler thread(10)]: analog: Using Capture configuration from stored graph
2012-05-29 11:59:03.191729 [scheduler thread(10)]: analog: AddTvCaptureFilter try:Hauppauge WinTV PVR PCI II Capture 0
2012-05-29 11:59:03.207329 [scheduler thread(10)]: analog: AddTvCaptureFilter failed to connect to crossbar
2012-05-29 11:59:03.207329 [scheduler thread(10)]: analog: AddTvCaptureFilter try:Hauppauge WinTV PVR PCI II Capture 1
2012-05-29 11:59:03.207329 [scheduler thread(10)]: analog: AddTvCaptureFilter connected video to crossbar successfully
2012-05-29 11:59:03.207329 [scheduler thread(10)]: analog: AddTvCaptureFilter connected audio to crossbar successfully
2012-05-29 11:59:03.207329 [scheduler thread(10)]: Set new video format to: NTSC_M
2012-05-29 11:59:03.207329 [scheduler thread(10)]: Set VideoProcAmp - Brightness to value: -12
2012-05-29 11:59:03.207329 [scheduler thread(10)]: Set VideoProcAmp - Contrast to value: 121
2012-05-29 11:59:03.207329 [scheduler thread(10)]: Set Framerate to 29.97 succeeded
2012-05-29 11:59:03.222929 [scheduler thread(10)]: Set Framesize to 704x480 succeeded
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog: Using Capture configuration from stored graph succeeded
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog: SetupTeletext()
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog: Using Teletext-Component configuration from stored graph
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog:Using teletext component - VBI Codec
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog: teletext setup
2012-05-29 11:59:03.222929 [scheduler thread(10)]: analog:connect wst/vbi codec->tsfilesink
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog: AddTvEncoderFilter - MatchPinNames: True - MPEG2ProgramFilter: True
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog: AddTvEncoderFilter found:2 encoders
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  try encoder:Hauppauge WinTV PVR PCI II Encoder 0
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog: encoder in-pin1:name:656 [7/0] Direction:Input Connected:False
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog: AddTvEncoderFilter no media types for pin1
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog: ConnectEncoderFilter video:True audio:False
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  found pin#0 name:656 [8/0] Direction:Input Connected:False
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  pinsAvailable on capture filter:6
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  capture pin:2 name:Capture [28/0] Direction:Output Connected:False
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  capture pin:3 name:Audio Out [29/0] Direction:Output Connected:False
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  capture pin:4 name:VBI [29/0] Direction:Output Connected:True
2012-05-29 11:59:03.238529 [scheduler thread(10)]: analog:  capture pin:5 name:656 [30/0] Direction:Output Connected:False
2012-05-29 11:59:03.768930 [ProcessExternalChannel(16)]: TV3BlasterPlugin: ProcessExternalCommand("STB: Echostar 3000\1|Port_1", 1, 211)
2012-05-29 11:59:03.768930 [ProcessExternalChannel(16)]: TV3BlasterPlugin - BlastIR(): C:\ProgramData\IR Server Suite\Set Top Boxes\Echostar 3000\1.IR, Port_1
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog: ConnectEncoderFilter failed (matchPinNames:True)
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  try encoder:Hauppauge WinTV PVR PCI II Encoder 1
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog: encoder in-pin1:name:656 [7/0] Direction:Input Connected:False
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog: AddTvEncoderFilter no media types for pin1
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog: ConnectEncoderFilter video:True audio:False
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  found pin#0 name:656 [8/0] Direction:Input Connected:False
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  pinsAvailable on capture filter:6
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  capture pin:2 name:Capture [28/0] Direction:Output Connected:False
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  capture pin:3 name:Audio Out [29/0] Direction:Output Connected:False
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  capture pin:4 name:VBI [29/0] Direction:Output Connected:True
2012-05-29 11:59:03.815730 [scheduler thread(10)]: analog:  capture pin:5 name:656 [30/0] Direction:Output Connected:False
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog:  connected pin:5 656 with pin0
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: ConnectEncoderFilter succeeded
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: AddTvEncoderFilter succeeded
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: AddTvEncoderFilter succeeded 3
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: FindCapturePin succeeded.
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: AddMpeg2Demultiplexer
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: connect capture->mpeg2 demux
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog:AddMpegMuxer()
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog:connect pinvideo name:Video [6/0] Direction:Output Connected:False ->mpeg muxer
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: connected pinvideo->mpeg muxer
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog:connect pinaudio name:Audio [6/0] Direction:Output Connected:False ->mpeg muxer
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog:AddMpegMuxer, connected pinaudio->mpeg muxer
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: Check quality control
2012-05-29 11:59:03.846930 [scheduler thread(10)]: analog: ICodecAPI supported by: Hauppauge WinTV PVR PCI II Encoder; Checking capabilities
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog: Encoder supports ENCAPIPARAM_BitRateMode
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog: Encoder supports ENCAPIPARAM_BitRate
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog: Encoder supports ENCAPIPARAM_PeakBitRate
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog:AddTsFileSink
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog:connect muxer->tsfilesink
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog:connect wst/vbi codec->tsfilesink
2012-05-29 11:59:03.862530 [scheduler thread(10)]: analog: Graph is built
2012-05-29 11:59:03.878130 [scheduler thread(10)]: Set new video format to: NTSC_M
2012-05-29 11:59:03.878130 [scheduler thread(10)]: Set VideoProcAmp - Brightness to value: -12
2012-05-29 11:59:03.878130 [scheduler thread(10)]: Set VideoProcAmp - Contrast to value: 121
2012-05-29 11:59:03.878130 [scheduler thread(10)]: Set Framerate to 29.97 succeeded
2012-05-29 11:59:03.893730 [scheduler thread(10)]: Set Framesize to 704x480 succeeded
2012-05-29 11:59:03.893730 [scheduler thread(10)]: analog:GetNewSubChannel:0 #0
2012-05-29 11:59:03.893730 [scheduler thread(10)]: analog subch:0 OnBeforeTune
2012-05-29 11:59:03.893730 [scheduler thread(10)]: analog:  set to TV
2012-05-29 11:59:03.893730 [scheduler thread(10)]: Analog: Tuned to country:2 video:0 Hz audio:0 Hz locked:True
2012-05-29 11:59:04.012132 [GenericPCQueue(14)]: TV3BlasterPlugin: Received Message "BlastIR"
2012-05-29 11:59:04.012132 [GenericPCQueue(14)]: TV3BlasterPlugin: Blast successful
2012-05-29 11:59:04.308533 [scheduler thread(10)]: analog subch:0 OnAfterTune
2012-05-29 11:59:04.308533 [scheduler thread(10)]: analog subch:0 OnGraphStart
2012-05-29 11:59:04.308533 [scheduler thread(10)]: analog: RunGraph
2012-05-29 11:59:04.870134 [ProcessExternalChannel(16)]: TV3BlasterPlugin: ProcessExternalCommand("STB: Echostar 3000\1|Port_1", 1, 211)
2012-05-29 11:59:04.870134 [ProcessExternalChannel(16)]: TV3BlasterPlugin - BlastIR(): C:\ProgramData\IR Server Suite\Set Top Boxes\Echostar 3000\1.IR, Port_1
2012-05-29 11:59:05.106134 [GenericPCQueue(14)]: TV3BlasterPlugin: Received Message "BlastIR"
2012-05-29 11:59:05.106134 [GenericPCQueue(14)]: TV3BlasterPlugin: Blast successful
2012-05-29 11:59:05.979736 [ProcessExternalChannel(16)]: TV3BlasterPlugin: ProcessExternalCommand("STB: Echostar 3000\ENTER|Port_1", -1, 211)
2012-05-29 11:59:05.979736 [ProcessExternalChannel(16)]: TV3BlasterPlugin - BlastIR(): C:\ProgramData\IR Server Suite\Set Top Boxes\Echostar 3000\ENTER.IR, Port_1
2012-05-29 11:59:06.120136 [scheduler thread(10)]: analog: RunGraph succeeded
2012-05-29 11:59:06.120136 [scheduler thread(10)]: analog:  LockedInOnSignal ok
2012-05-29 11:59:06.120136 [scheduler thread(10)]: analog subch:0 OnGraphStarted
2012-05-29 11:59:06.120136 [scheduler thread(10)]: card: Tuner locked: True
2012-05-29 11:59:06.120136 [scheduler thread(10)]: **************************************************
2012-05-29 11:59:06.120136 [scheduler thread(10)]: ***** SIGNAL LEVEL: 100, SIGNAL QUALITY: 100 *****
2012-05-29 11:59:06.120136 [scheduler thread(10)]: **************************************************
2012-05-29 11:59:06.120136 [scheduler thread(10)]: card: tuned user: scheduler145 subchannel: 0
2012-05-29 11:59:06.120136 [scheduler thread(10)]: user:scheduler145 add
2012-05-29 11:59:06.120136 [scheduler thread(10)]: TV3BlasterPlugin: Received TV Server Event "EndZapChannel"
2012-05-29 11:59:06.120136 [scheduler thread(10)]: Scheduler: MakeFileName() using "moviesformat" (_isSerie=False)
2012-05-29 11:59:06.120136 [scheduler thread(10)]: Scheduler : record to F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts
2012-05-29 11:59:06.120136 [scheduler thread(10)]: Recorder.start add audioVideoEventHandler
2012-05-29 11:59:06.135736 [scheduler thread(10)]: card: StartRecording 2 F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts
2012-05-29 11:59:06.135736 [scheduler thread(10)]: StartRecording to F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts
2012-05-29 11:59:06.135736 [scheduler thread(10)]: analog: Encoder mode setting to ConstantBitRate
2012-05-29 11:59:06.151336 [scheduler thread(10)]: analog: Encoder mode set to ConstantBitRate
2012-05-29 11:59:06.151336 [scheduler thread(10)]: analog: Encoder BitRate setting to Default
2012-05-29 11:59:06.151336 [scheduler thread(10)]: analog: Encoder BitRate Min 2500000 Max 8500000 Delta 1
2012-05-29 11:59:06.166936 [scheduler thread(10)]: analog: Encoder BitRate set to 4000000
2012-05-29 11:59:06.166936 [scheduler thread(10)]: analog:StartRecord(F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts)
2012-05-29 11:59:06.166936 [scheduler thread(10)]: analog subch:0 Started recording
2012-05-29 11:59:06.166936 [scheduler thread(10)]: card: WaitForRecordingFile - waiting _eventAudio & _eventVideo
2012-05-29 11:59:06.229336 [GenericPCQueue(14)]: TV3BlasterPlugin: Received Message "BlastIR"
2012-05-29 11:59:06.229336 [GenericPCQueue(14)]: TV3BlasterPlugin: Blast successful
2012-05-29 11:59:07.711339 [(21)]: PID seen - type = Audio
2012-05-29 11:59:07.711339 [(21)]: Recorder audioVideoEventHandler Audio
2012-05-29 11:59:07.711339 [(21)]: PID seen - type = Video
2012-05-29 11:59:07.711339 [(21)]: Recorder audioVideoEventHandler Video
2012-05-29 11:59:07.711339 [scheduler thread(10)]: card: WaitForRecordingFile - video and audio are seen after 1.5444027 seconds
2012-05-29 11:59:07.758139 [scheduler thread(10)]: Scheduler: adding new row in db for title="The Big Flip" of type="0"
2012-05-29 11:59:07.773739 [scheduler thread(10)]: TV3BlasterPlugin: Received TV Server Event "RecordingStarted"
2012-05-29 11:59:07.773739 [scheduler thread(10)]: Scheduler: recList: count: 1 add scheduleid: 145 card: Hauppauge WinTV PVR PCI II TvTuner
2012-05-29 12:04:51.230938 [(4)]: DiskManagement: checking free disk space
2012-05-29 12:19:51.243307 [(20)]: DiskManagement: checking free disk space
2012-05-29 12:31:14.133300 [scheduler thread(10)]: Scheduler: stop record 211 CIII 29/05/2012 11:59:07 AM-29/05/2012 12:30:00 PM The Big Flip
2012-05-29 12:31:14.133300 [scheduler thread(10)]: card: StopRecording card=2, user=scheduler145
2012-05-29 12:31:14.133300 [scheduler thread(10)]: card: StopRecording for card:2
2012-05-29 12:31:14.133300 [scheduler thread(10)]: analog:StopRecord()
2012-05-29 12:31:14.133300 [scheduler thread(10)]: tvcard:FreeSubChannel: subchannels count 1 subch#0 keep graph=False
2012-05-29 12:31:14.133300 [scheduler thread(10)]: analog subch:0 Decompose()
2012-05-29 12:31:14.133300 [scheduler thread(10)]: tvcard:FreeSubChannel : no subchannels present, pausing graph
2012-05-29 12:31:14.133300 [scheduler thread(10)]: tvcard:FreeAllSubChannels
2012-05-29 12:31:14.133300 [scheduler thread(10)]: analog: StopGraph state:Running
2012-05-29 12:31:14.351700 [scheduler thread(10)]: analog: Graph stopped
2012-05-29 12:31:14.351700 [scheduler thread(10)]: user:scheduler145 remove
2012-05-29 12:31:14.351700 [scheduler thread(10)]: tvcard:FreeAllSubChannels
2012-05-29 12:31:14.351700 [scheduler thread(10)]: analog: StopGraph state:Stopped
2012-05-29 12:31:14.382900 [scheduler thread(10)]: TV3BlasterPlugin: Received TV Server Event "ScheduleDeleted"
2012-05-29 12:31:14.492100 [scheduler thread(10)]: ComSkipLauncher: Recording ended (F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts on 211 CIII), launching program (C:\Program Files\comskip\comskip.exe "F:\RecordedTV\The Big Flip\The Big Flip - 1002 - Rocky Relationship.ts" -t) ...