2012-09-04 20:49:30.710602 [(18)]: card: SignalTuneEvent card: 7 / subch: 0
2012-09-04 20:49:30.712603 [(18)]: Controller: X4 7 0
2012-09-04 20:49:30.714603 [(18)]: control2:X4 7 0
2012-09-04 20:49:30.717103 [(22)]: PID seen - type = Audio
2012-09-04 20:49:30.718603 [(22)]: audio last seen at 04/09/2012 20:49:30
2012-09-04 20:49:30.721104 [Channel state thread(7)]: Controller: DoSetChannelStates for 49 channels
2012-09-04 20:49:30.725604 [Channel state thread(7)]: ChannelStates.GetAllTimeshiftingAndRecordingChannels took 2 msec
2012-09-04 20:49:30.738606 [Channel state thread(7)]: ChannelStates.DoSetChannelStates took 17 msec
2012-09-04 20:49:31.482700 [PowerScheduler poll thread(14)]: PowerScheduler: System changed from idle state to busy state
2012-09-04 20:49:48.541867 [PowerScheduler poll thread(14)]: ActiveSharesHandler: Share monitoring is disabled.
2012-09-04 20:50:16.204879 [(18)]: CiMenuSupported called cardid 7
2012-09-04 20:50:16.206880 [(18)]: CiMenuSupported card IT9135 BDA Filter supported: False
Very strange. Seems like MP was waiting for TV Server to say it had finished tuning the channel.
The following log lines are a clue: