[mythtv-commits] Ticket #10934: Can't enter liveTV with IPTV

MythTV noreply at mythtv.org
Tue Jul 24 02:11:32 UTC 2012


#10934: Can't enter liveTV with IPTV
--------------------------------------+-------------------------
     Reporter:  jyavenard             |      Owner:  danielk
         Type:  Bug Report - General  |     Status:  new
     Priority:  blocker               |  Milestone:  unknown
    Component:  MythTV - General      |    Version:  Unspecified
     Severity:  medium                |   Keywords:
Ticket locked:  0                     |
--------------------------------------+-------------------------
 Used to work..

 Now it always fail to enter LiveTV using the HLS recorder.

 Recording HLS channels work just fine.

 Timeout for capture card was set to 30s, it takes usually less than 10s to
 watch that same URL using mythavtest.

 From time to time it works, very rarely.
 You can see in the log that it got a locks, signal monitor is closed and
 then it stops everything.


 {{{
 2012-07-24 12:05:33.406985 I  MainServer::ANN Playback
 2012-07-24 12:05:33.406991 I  adding: jyamba as a client (events: 0)
 2012-07-24 12:05:33.427861 I  MainServer::HandleAnnounce FileTransfer
 2012-07-24 12:05:33.427867 I  adding: jyamba as a remote file transfer
 2012-07-24 12:05:36.588791 I  Getting next free recorder after : -1
 2012-07-24 12:05:36.588894 I  Checking card 1. Best card so far -1
 2012-07-24 12:05:36.589376 I  Best card is 1
 2012-07-24 12:05:36.643602 I  MainServer::ANN Playback
 2012-07-24 12:05:36.643608 I  adding: jyamba as a client (events: 0)
 2012-07-24 12:05:36.722958 I  TVRec(1): Found channel (300) on current
 card(1).
 2012-07-24 12:05:36.738567 I  TVRec(1): Changing from None to
 WatchingLiveTV
 2012-07-24 12:05:36.738596 I  TVRec(1):
 ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,
 2012-07-24 12:05:36.738652 I  TVRec(1): HandleTuning Request:
 Program(NULL) channel() input() flags(LiveTV,)
 2012-07-24 12:05:36.738686 I  ChannelBase(1): Looking for startchannel
 '300' on input 'MPEG2TS'
 2012-07-24 12:05:36.740538 I  ChannelBase(1): Found startchannel '300' on
 input 'MPEG2TS'
 2012-07-24 12:05:36.740553 I  TVRec(1): HW Tuner: 1->1
 2012-07-24 12:05:36.740561 I  TVRec(1): ClearFlags(PENDINGACTIONS,) ->
 RunMainLoop,
 2012-07-24 12:05:36.740604 I  TVRec(1): No recorder yet, calling
 TuningFrequency
 2012-07-24 12:05:36.740874 I  IPTVChan(1): Open() -- begin
 2012-07-24 12:05:36.740880 I  IPTVChan(1): Open() -- locked
 2012-07-24 12:05:36.741977 I  ChannelBase(1): Input #1: 'MPEG2TS'
 schan(301) sourceid(1) ccid(1)
 2012-07-24 12:05:36.741990 I  ChannelBase(1): Current Input #1: 'MPEG2TS'
 2012-07-24 12:05:36.742016 I  IPTVChan(1): Open() -- end
 2012-07-24 12:05:36.742022 D  ChannelBase(1): SwitchToInput(MPEG2TS,300)
 2012-07-24 12:05:36.742936 I  IPTVChan(1): SetChannelByString(300) --
 begin
 2012-07-24 12:05:36.742941 I  IPTVChan(1): SetChannelByString() -- locked
 2012-07-24 12:05:36.743739 I  IPTVChan(1): GetChanInfo() -- begin
 2012-07-24 12:05:36.743744 I  IPTVChan(1): GetChanInfo() -- locked
 2012-07-24 12:05:36.743914 D  IPTVChan(1): Found: IPTVChannelInfo: Name(Al
 Jazeera (English))
 Url(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) xmltvid(
 2012-07-24 12:05:36.743938 I  IPTVChan(1): SetChannelByString(300) = 300
 -- end
 2012-07-24 12:05:36.743970 I  TVRec(1): CreateLiveTVRingBuffer(300)
 2012-07-24 12:05:36.744255 I  TVRec(1): GetProgramRingBufferForLiveTV()
 2012-07-24 12:05:36.748376 N  AutoExpire: CalcParams(): Max required Free
 Space: 3.0 GB w/freq: 14 min
 2012-07-24 12:05:36.757905 I  TVRec(1):
 StartedRecording(1300_2012-07-24T02:05:36Z)
 fn(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020536.mpg)
 2012-07-24 12:05:36.760600 I  Using profile 'Default' to record
 2012-07-24 12:05:36.762822 I  Chain: Appended at 0 '1300_20120724020536'
 2012-07-24 12:05:36.763642 I  TVRec(1): Starting Signal Monitor
 2012-07-24 12:05:36.763652 I  TVRec(1): SetupSignalMonitor(1, 1)
 2012-07-24 12:05:36.763740 I  IPTVChan(1): Open() -- begin
 2012-07-24 12:05:36.763744 I  IPTVChan(1): Open() -- locked
 2012-07-24 12:05:36.764874 I  ChannelBase(1): Input #1: 'MPEG2TS'
 schan(301) sourceid(1) ccid(1)
 2012-07-24 12:05:36.764881 I  ChannelBase(1): Current Input #1: 'MPEG2TS'
 2012-07-24 12:05:36.764900 I  IPTVChan(1): Open() -- end
 2012-07-24 12:05:36.765110 I  IPTVChan(1): GetChanInfo() -- begin
 2012-07-24 12:05:36.765114 I  IPTVChan(1): GetChanInfo() -- locked
 2012-07-24 12:05:36.765294 D  IPTVChan(1): Found: IPTVChannelInfo: Name(Al
 Jazeera (English))
 Url(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) xmltvid(
 2012-07-24 12:05:36.765332 I  IPTVFeed: Open() -- begin
 2012-07-24 12:05:36.765338 I  IPTVFeed: Init() -- begin
 2012-07-24 12:05:37.744024 I  IPTVFeed: Init() -- adding listeners
 2012-07-24 12:05:37.744093 I  IPTVFeed: Init() -- end 1
 2012-07-24 12:05:37.744100 I  IPTVHLS:
 Open(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) -- begin
 [Switching to process 860 thread 0x5107]
 2012-07-24 12:05:39.846373 I  IPTVHLS: Open() -- end
 2012-07-24 12:05:39.846380 I  IPTVFeed: Open() -- end
 2012-07-24 12:05:39.846389 I  TVRec(1): Signal monitor successfully
 created
 2012-07-24 12:05:39.846394 I  TVRec(1): Setting up table monitoring.
 2012-07-24 12:05:39.847991 I  Using profile 'Live TV' to record
 2012-07-24 12:05:39.848510 I  TVRec(1): MPEG program number: 1
 2012-07-24 12:05:39.849052 I  DTVSM()::SetProgramNumber(1):
 2012-07-24 12:05:39.849095 D  SM()::RemoveFlags: Seen(PMT,Crypt,)
 Match(PMT,Crypt,) Wait()
 2012-07-24 12:05:39.849305 D  SM()::AddFlags: Seen() Match() Wait(PMT,)
 2012-07-24 12:05:39.849326 D  SM()::AddFlags: Seen() Match()
 Wait(PAT,PMT,Pos,)
 2012-07-24 12:05:39.849333 I  TVRec(1): Successfully set up MPEG table
 monitoring.
 2012-07-24 12:05:39.849504 D  SM()::Start: begin
 2012-07-24 12:05:39.849639 D  SM()::Start: end
 2012-07-24 12:05:39.849651 I  TVRec(1): SetFlags(SignalMonitorRunning,) ->
 RunMainLoop,SignalMonitorRunning,
 2012-07-24 12:05:39.849660 I  TVRec(1): ClearFlags(WaitingForSignal,) ->
 RunMainLoop,SignalMonitorRunning,
 2012-07-24 12:05:39.849667 I  TVRec(1): SetFlags(WaitingForSignal,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,
 2012-07-24 12:05:39.849681 I  TVRec(1): SetFlags(DummyRecorderRunning,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,
 2012-07-24 12:05:39.849685 I  DummyDTVRecorder -- started
 2012-07-24 12:05:39.849693 I  TVRec(1): SetFlags(RingBufferReady,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:39.849701 I  TVRec(1): ClearFlags(NeedToStartRecorder,)
 ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:39.849703 D  IPTVSM()::UpdateValues: Waiting for table
 monitor to start
 2012-07-24 12:05:39.849709 I  TVRec(1): SetFlags(NeedToStartRecorder,) ->
 RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:39.849762 D  IPTVSM()::Run: begin
 2012-07-24 12:05:39.849778 I  IPTVFeed: AddListener(0x10901fe38) -- begin
 2012-07-24 12:05:39.849851 I  TVRec(1): SetFlags(CancelNextRecording,) ->
 RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:39.849970 I  FbFeedLive:AddListener(0x10901fe38) -- begin
 2012-07-24 12:05:39.849975 I  FbFeedLive:RemoveListener(0x10901fe38) --
 begin
 2012-07-24 12:05:39.849987 I  FbFeedLive:RemoveListener(0x10901fe38) --
 end 1
 2012-07-24 12:05:39.849994 I  FbFeedLive:AddListener(0x10901fe38) -- end
 2012-07-24 12:05:39.849998 I  IPTVFeed: AddListener(0x10901fe38) -- end 1
 2012-07-24 12:05:39.850001 I  IPTVFeed: Run() -- begin
 2012-07-24 12:05:39.851470 D  SM()::AddFlags: Seen(PAT,) Match() Wait()
 2012-07-24 12:05:39.851477 D  SM()::AddFlags: Seen() Match(PAT,) Wait()
 2012-07-24 12:05:39.851570 I  CreatePATSingleProgram()
 2012-07-24 12:05:39.851572 I  PAT in input stream
 2012-07-24 12:05:39.851613 I  Program Association Section
  PSIP tableID(0x0) length(13) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       tsid(1) programCount(1)
   program number     1 has PID 0x0fff

 2012-07-24 12:05:39.851618 I  desired_program(1) pid(0xfff)
 2012-07-24 12:05:39.851768 I  pmt_pid(0xfff)
 2012-07-24 12:05:39.851771 I  PAT for output stream
 2012-07-24 12:05:39.851785 I  Program Association Section
  PSIP tableID(0x0) length(13) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       tsid(1) programCount(1)
   program number     1 has PID 0x0fff

 2012-07-24 12:05:39.852082 D  SM()::AddFlags: Seen(PMT,) Match() Wait()
 2012-07-24 12:05:39.852469 D  SM()::AddFlags: Seen() Match(PMT,) Wait()
 2012-07-24 12:05:39.852473 I  CreatePMTSingleProgram()
 2012-07-24 12:05:39.852475 I  PMT in input stream
 2012-07-24 12:05:39.852547 I  Program Map Section
  PSIP tableID(0x2) length(60) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xfff)
   Metadata Pointer Descriptor (0x25) length(15)
   Stream #0 pid(0x102) type(0x15 unknown)
     Metadata Descriptor (0x26) length(13)
   Stream #1 pid(0x100) type(0x1b video-h264)
   Stream #2 pid(0x101) type(0x0f audio-aac)

 2012-07-24 12:05:39.853099 I  PMT for output stream
 2012-07-24 12:05:39.853131 I  Program Map Section
  PSIP tableID(0x2) length(60) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xfff)
   Metadata Pointer Descriptor (0x25) length(15)
   Stream #0 pid(0x102) type(0x15 unknown)
     Metadata Descriptor (0x26) length(13)
   Stream #1 pid(0x100) type(0x1b video-h264)
   Stream #2 pid(0x101) type(0x0f audio-aac)

 2012-07-24 12:05:39.855376 D  IPTVSM()::UpdateValues: Table monitor
 started
 2012-07-24 12:05:40.106214 I  TVRec(1): Got good signal
 2012-07-24 12:05:40.106240 D  TVRec(1):
 SetRecordingStatus(Unknown->Recording) on line 3836
 2012-07-24 12:05:40.106280 I  TVRec(1): TeardownSignalMonitor() -- begin
 2012-07-24 12:05:40.106580 I  IPTVFeed: RemoveListener(0x10901fe38) --
 begin
 2012-07-24 12:05:40.106637 I  FbFeedLive:RemoveListener(0x10901fe38) --
 begin
 2012-07-24 12:05:40.106642 I  FbFeedLive:RemoveListener(0x10901fe38) --
 end 2
 2012-07-24 12:05:40.106654 I  IPTVFeed: RemoveListener(0x10901fe38) -- end
 (ok, removed)
 2012-07-24 12:05:40.106658 D  IPTVSM()::Stop: begin
 2012-07-24 12:05:40.106662 I  IPTVFeed: RemoveListener(0x10901fe38) --
 begin
 2012-07-24 12:05:40.106666 E  IPTVFeed: RemoveListener(0x10901fe38) -- end
 (not found)
 2012-07-24 12:05:40.106670 D  SM()::Stop: begin
 2012-07-24 12:05:40.357486 D  SM()::Stop: end
 2012-07-24 12:05:40.357504 I  IPTVFeed: Stop() -- begin
 2012-07-24 12:05:40.357507 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:05:41.761003 I  IPTVFeed: Run() -- end
 2012-07-24 12:05:41.761010 I  IPTVHLS: Stop() -- end
 2012-07-24 12:05:41.761016 I  IPTVFeed: RemoveListener(0x10901fe38) --
 begin
 2012-07-24 12:05:41.761018 I  IPTVFeed: Stop() -- end
 2012-07-24 12:05:41.761022 E  IPTVFeed: RemoveListener(0x10901fe38) -- end
 (not found)
 2012-07-24 12:05:41.761026 D  IPTVSM()::Run: end
 2012-07-24 12:05:41.761099 D  IPTVSM()::Stop: end
 2012-07-24 12:05:41.761213 D  SM()::Stop: begin
 2012-07-24 12:05:41.761218 D  SM()::Stop: end
 2012-07-24 12:05:41.761270 I  TVRec(1): TeardownSignalMonitor() -- end
 2012-07-24 12:05:41.761280 I  TVRec(1): ClearFlags(SignalMonitorRunning,)
 ->
 RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:41.761289 I  TVRec(1): ClearFlags(WaitingForSignal,) ->
 RunMainLoop,CancelNextRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:05:41.761711 I  TVRec(1): Starting Recorder
 2012-07-24 12:05:41.762246 I  TVRec(1):
 FinishedRecording(1300_2012-07-24T02:05:36Z)
                         title:
                         in recgroup: LiveTV status: Recording:Recorded
 is_dummy finished_now
 2012-07-24 12:05:41.762558 I  Chain: Updated endtime for
 '1300_20120724020536' to 20120724020541
 2012-07-24 12:05:41.762948 I  TVRec(1): ClearFlags(DummyRecorderRunning,)
 -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady,
 2012-07-24 12:05:41.764760 I  Using profile 'Live TV' to record
 2012-07-24 12:05:41.764778 I  TVRec(1): SwitchLiveTVRingBuffer(discont 1,
 set_next_rec 0) curRec(1300_2012-07-24T02:05:36Z) curRec.size(0)
 2012-07-24 12:05:41.765193 I  TVRec(1): GetProgramRingBufferForLiveTV()
 2012-07-24 12:05:41.769052 N  AutoExpire: CalcParams(): Max required Free
 Space: 3.0 GB w/freq: 14 min
 2012-07-24 12:05:41.773664 I  TVRec(1):
 StartedRecording(1300_2012-07-24T02:05:41Z)
 fn(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020541.mpg)
 2012-07-24 12:05:41.775712 I  Using profile 'Default' to record
 2012-07-24 12:05:41.778091 I  Chain: Appended at 1 '1300_20120724020541'
 2012-07-24 12:05:41.778623 I  DTVRec(1): ResetForNewFile(void)
 2012-07-24 12:05:41.778641 I  IPTVFeed: AddListener(0x10b809d08) -- begin
 2012-07-24 12:05:41.778647 I  FbFeedLive:AddListener(0x10b809d08) -- begin
 2012-07-24 12:05:41.778653 I  FbFeedLive:RemoveListener(0x10b809d08) --
 begin
 2012-07-24 12:05:41.778660 I  FbFeedLive:RemoveListener(0x10b809d08) --
 end 1
 2012-07-24 12:05:41.778665 I  FbFeedLive:AddListener(0x10b809d08) -- end
 2012-07-24 12:05:41.778671 I  IPTVFeed: AddListener(0x10b809d08) -- end 1
 2012-07-24 12:05:41.778683 W  RecBase(1:):
 SetOption(mrl,http://192.168.10.10/~jyavenard/playlist.m3u): Option not
 recognized
 2012-07-24 12:05:41.778692 I  RecBase(1:): SetRingBuffer(0x10a06d990)
 '/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020541.mpg'
 2012-07-24 12:05:41.778923 I  RecBase(1:): SetRecording(0x10994ab20)
 title()
 2012-07-24 12:05:41.779374 I  IPTVRec: run() -- begin
 2012-07-24 12:05:41.779378 I  IPTVRec: Open() -- begin
 2012-07-24 12:05:41.779381 I  IPTVFeed: IsOpen() -- begin
 2012-07-24 12:05:41.779386 I  IPTVFeed: IsOpen() -- end
 2012-07-24 12:05:41.779390 I  IPTVFeed: Run() -- begin
 2012-07-24 12:05:41.779394 I  TVRec(1): SetFlags(FrontendReady,) ->
 FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady,
 2012-07-24 12:05:41.780717 I  DTVRec(1): FindH264Keyframes: timescale: 50,
 tick: 1, framerate: 25000
 2012-07-24 12:05:41.784664 I  TVRec(1):
 SetFlags(RecorderRunning,RingBufferReady,) ->
 FrontendReady,RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady,
 2012-07-24 12:05:41.784688 I  TVRec(1): ClearFlags(NeedToStartRecorder,)
 ->
 FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady,
 2012-07-24 12:05:42.081057 I  MainServer::ANN Playback
 2012-07-24 12:05:42.081064 I  adding: jyamba as a client (events: 0)
 2012-07-24 12:05:42.089192 I  MainServer::HandleAnnounce FileTransfer
 2012-07-24 12:05:42.089196 I  adding: jyamba as a remote file transfer
 2012-07-24 12:05:43.171173 W
 RingBuf(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020541.mpg):
 Taking too long to be allowed to read..
 2012-07-24 12:05:44.172237 W
 RingBuf(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020541.mpg):
 Taking too long to be allowed to read..
 2012-07-24 12:05:44.546786 W
 RingBuf(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020541.mpg):
 Taking too long to be allowed to read..
 [Switching to process 860 thread 0x707]
 2012-07-24 12:05:47.469796 I  Starting mythlogserver
 2012-07-24 12:05:52.539254 I  JobQueue: Metadata Lookup Starting for
 recorded from channel 1300 at 2012-07-24T02:05:36Z
 2012-07-24 12:05:52.963734 E  JobQueue: Metadata Lookup Errored:  recorded
 from channel 1300 at 2012-07-24T02:05:36Z (Failed with exit status 128)
 2012-07-24 12:05:53.185228 D  DTVRec(1): Updating timeOfLatestData
 elapsed(11405) interval(2000)
 2012-07-24 12:05:53.186570 D  DTVRec(1): Updating timeOfLatestData
 elapsed(1) interval(1600)
 2012-07-24 12:05:58.285324 I  TVRec(1): StopLiveTV(void) curRec:
 0x10994ab20 pseudoRec: 0x0
 2012-07-24 12:05:58.296866 I  TVRec(1): Changing from WatchingLiveTV to
 None
 2012-07-24 12:05:58.296885 I  TVRec(1):
 ClearFlags(FrontendReady,CancelNextRecording,) ->
 RunMainLoop,RecorderRunning,RingBufferReady,
 2012-07-24 12:05:58.296897 I  TVRec(1): HandleTuning Request:
 Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)
 2012-07-24 12:05:58.296904 I  IPTVFeed: Stop() -- begin
 2012-07-24 12:05:58.296907 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:01.771077 N  AutoExpire: CalcParams(): Max required Free
 Space: 1.0 GB w/freq: 15 min
 2012-07-24 12:06:01.773379 N  Expiring 0 MB for 1300 at
 2012-07-24T02:00:12Z =>
 2012-07-24 12:06:01.773414 N  Expiring 0 MB for 1300 at
 2012-07-24T02:00:18Z =>
 2012-07-24 12:06:01.773439 N  Expiring 0 MB for 1300 at
 2012-07-24T02:00:49Z =>
 2012-07-24 12:06:02.990961 I  IPTVFeed: Run() -- end
 2012-07-24 12:06:02.990963 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:02.990973 I  IPTVFeed: Stop() -- end
 2012-07-24 12:06:02.990978 I  IPTVRec: Close() -- begin
 2012-07-24 12:06:02.990981 I  IPTVFeed: Stop() -- begin
 2012-07-24 12:06:02.990983 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:02.990985 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:02.990987 I  IPTVFeed: Stop() -- end
 2012-07-24 12:06:02.990990 I  IPTVFeed: Close() -- begin
 2012-07-24 12:06:02.990992 I  IPTVHLS: Close() -- begin
 2012-07-24 12:06:02.990994 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:02.990996 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:03.794910 C  MThread prolog was never run!
 2012-07-24 12:06:03.794917 C  MThread epilog was never run!
 2012-07-24 12:06:05.207267 C  MThread prolog was never run!
 2012-07-24 12:06:05.207275 C  MThread epilog was never run!
 2012-07-24 12:06:05.207546 I  IPTVHLS: Close() -- end
 2012-07-24 12:06:05.207550 I  IPTVFeed: Close() -- end
 2012-07-24 12:06:05.207553 I  IPTVRec: Close() -- end
 2012-07-24 12:06:05.260105 I  IPTVRec: run() -- end
 2012-07-24 12:06:05.260203 I  TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.260779 I  IPTVFeed: Stop() -- begin
 2012-07-24 12:06:05.260783 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:05.260786 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:05.260788 I  IPTVFeed: Stop() -- end
 2012-07-24 12:06:05.260793 I  IPTVFeed: RemoveListener(0x10b809d08) --
 begin
 2012-07-24 12:06:05.260800 I  FbFeedLive:RemoveListener(0x10b809d08) --
 begin
 2012-07-24 12:06:05.260804 I  FbFeedLive:RemoveListener(0x10b809d08) --
 end 2
 2012-07-24 12:06:05.260808 I  IPTVFeed: RemoveListener(0x10b809d08) -- end
 (ok, removed)
 2012-07-24 12:06:05.261413 I  RecBase(1:): SetRecording(0x0)
 2012-07-24 12:06:05.262204 I  TVRec(1):
 FinishedRecording(1300_2012-07-24T02:05:41Z) damaged
 recq:<RecordingQuality overall_score="0" key="1300_2012-07-24T02:05:41Z">
     <Gap start="2012-07-24T02:05:53Z" end="2012-07-24T02:30:00Z"
 duration="1446" />
 </RecordingQuality>

 2012-07-24 12:06:05.262293 I  TVRec(1):
 FinishedRecording(1300_2012-07-24T02:05:41Z)
                         title:
                         in recgroup: LiveTV status: Recorder
 Failed:Recorder Failed not_dummy finished_now
 2012-07-24 12:06:05.262587 I  Chain: Updated endtime for
 '1300_20120724020541' to 20120724020605
 2012-07-24 12:06:05.263177 I  SaveVideoProperties(0x38, 0x20)
 2012-07-24 12:06:05.264289 I  TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.264296 I  TVRec(1): Tearing down RingBuffer
 2012-07-24 12:06:05.264387 I  TVRec(1): ClearFlags(PENDINGACTIONS,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.294436 I  MainServer::ANN Playback
 2012-07-24 12:06:05.294443 I  adding: jyamba as a client (events: 0)
 2012-07-24 12:06:05.296295 I  TVRec(1): SetFlags(FrontendReady,) ->
 FrontendReady,RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.331829 I  TVRec(1): SetFlags(FrontendReady,) ->
 FrontendReady,RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.413509 I  Getting next free recorder after : -1
 2012-07-24 12:06:05.413518 I  Checking card 1. Best card so far -1
 2012-07-24 12:06:05.413958 I  Best card is 1
 2012-07-24 12:06:05.454326 I  MainServer::ANN Playback
 2012-07-24 12:06:05.454342 I  adding: jyamba as a client (events: 0)
 2012-07-24 12:06:05.502378 I  TVRec(1): Found channel (300) on current
 card(1).
 2012-07-24 12:06:05.516339 I  TVRec(1): Changing from None to
 WatchingLiveTV
 2012-07-24 12:06:05.516366 I  TVRec(1):
 ClearFlags(FrontendReady,CancelNextRecording,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.516381 I  TVRec(1): HandleTuning Request:
 Program(NULL) channel() input() flags(LiveTV,)
 2012-07-24 12:06:05.516399 I  ChannelBase(1): Looking for startchannel
 '300' on input 'MPEG2TS'
 2012-07-24 12:06:05.518310 I  ChannelBase(1): Found startchannel '300' on
 input 'MPEG2TS'
 2012-07-24 12:06:05.518326 I  TVRec(1): HW Tuner: 1->1
 2012-07-24 12:06:05.518335 I  TVRec(1): ClearFlags(PENDINGACTIONS,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:05.518342 I  TVRec(1): No recorder yet, calling
 TuningFrequency
 2012-07-24 12:06:05.518520 I  IPTVChan(1): Open() -- begin
 2012-07-24 12:06:05.518525 I  IPTVChan(1): Open() -- locked
 2012-07-24 12:06:05.519293 I  ChannelBase(1): Input #1: 'MPEG2TS'
 schan(301) sourceid(1) ccid(1)
 2012-07-24 12:06:05.519300 I  ChannelBase(1): Current Input #1: 'MPEG2TS'
 2012-07-24 12:06:05.519319 I  IPTVChan(1): Open() -- end
 2012-07-24 12:06:05.519324 D  ChannelBase(1): SwitchToInput(MPEG2TS,300)
 2012-07-24 12:06:05.520283 I  IPTVChan(1): SetChannelByString(300) --
 begin
 2012-07-24 12:06:05.520288 I  IPTVChan(1): SetChannelByString() -- locked
 2012-07-24 12:06:05.521199 I  IPTVChan(1): GetChanInfo() -- begin
 2012-07-24 12:06:05.521204 I  IPTVChan(1): GetChanInfo() -- locked
 2012-07-24 12:06:05.521374 D  IPTVChan(1): Found: IPTVChannelInfo: Name(Al
 Jazeera (English))
 Url(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) xmltvid(
 2012-07-24 12:06:05.521397 I  IPTVChan(1): SetChannelByString(300) = 300
 -- end
 2012-07-24 12:06:05.521403 I  TVRec(1): CreateLiveTVRingBuffer(300)
 2012-07-24 12:06:05.521668 I  TVRec(1): GetProgramRingBufferForLiveTV()
 2012-07-24 12:06:05.524369 N  AutoExpire: CalcParams(): Max required Free
 Space: 3.0 GB w/freq: 14 min
 2012-07-24 12:06:05.528696 I  TVRec(1):
 StartedRecording(1300_2012-07-24T02:06:05Z)
 fn(/DATA/Users/jyavenard/Downloads/mythtv/1300_20120724020605.mpg)
 2012-07-24 12:06:05.530159 I  Using profile 'Default' to record
 2012-07-24 12:06:05.531601 I  Chain: Appended at 0 '1300_20120724020605'
 2012-07-24 12:06:05.532361 I  TVRec(1): Starting Signal Monitor
 2012-07-24 12:06:05.532367 I  TVRec(1): SetupSignalMonitor(1, 1)
 2012-07-24 12:06:05.532372 I  IPTVChan(1): Open() -- begin
 2012-07-24 12:06:05.532376 I  IPTVChan(1): Open() -- locked
 2012-07-24 12:06:05.533724 I  ChannelBase(1): Input #1: 'MPEG2TS'
 schan(301) sourceid(1) ccid(1)
 2012-07-24 12:06:05.533732 I  ChannelBase(1): Current Input #1: 'MPEG2TS'
 2012-07-24 12:06:05.533758 I  IPTVChan(1): Open() -- end
 2012-07-24 12:06:05.533801 I  IPTVChan(1): GetChanInfo() -- begin
 2012-07-24 12:06:05.533805 I  IPTVChan(1): GetChanInfo() -- locked
 2012-07-24 12:06:05.534142 D  IPTVChan(1): Found: IPTVChannelInfo: Name(Al
 Jazeera (English))
 Url(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) xmltvid(
 2012-07-24 12:06:05.534180 I  IPTVFeed: Open() -- begin
 2012-07-24 12:06:05.534186 I  IPTVFeed: Init() -- begin
 2012-07-24 12:06:06.822849 I  IPTVFeed: Init() -- end 0
 2012-07-24 12:06:06.822858 I  IPTVHLS:
 Open(http://aj.lsops.net/live/aljazeer_en_high.sdp/playlist.m3u8) -- begin
 2012-07-24 12:06:08.851297 I  IPTVHLS: Open() -- end
 2012-07-24 12:06:08.851304 I  IPTVFeed: Open() -- end
 2012-07-24 12:06:08.851314 I  TVRec(1): Signal monitor successfully
 created
 2012-07-24 12:06:08.851319 I  TVRec(1): Setting up table monitoring.
 2012-07-24 12:06:08.852858 I  Using profile 'Live TV' to record
 2012-07-24 12:06:08.852878 I  TVRec(1): MPEG program number: 1
 2012-07-24 12:06:08.852895 I  DTVSM()::SetProgramNumber(1):
 2012-07-24 12:06:08.852904 D  SM()::RemoveFlags: Seen(PMT,Crypt,)
 Match(PMT,Crypt,) Wait()
 2012-07-24 12:06:08.852917 D  SM()::AddFlags: Seen() Match() Wait(PMT,)
 2012-07-24 12:06:08.852924 D  SM()::AddFlags: Seen() Match()
 Wait(PAT,PMT,Pos,)
 2012-07-24 12:06:08.852928 I  TVRec(1): Successfully set up MPEG table
 monitoring.
 2012-07-24 12:06:08.852942 D  SM()::Start: begin
 2012-07-24 12:06:08.853018 D  SM()::Start: end
 2012-07-24 12:06:08.853028 I  TVRec(1): SetFlags(SignalMonitorRunning,) ->
 RunMainLoop,SignalMonitorRunning,RingBufferReady,
 2012-07-24 12:06:08.853036 I  TVRec(1): ClearFlags(WaitingForSignal,) ->
 RunMainLoop,SignalMonitorRunning,RingBufferReady,
 2012-07-24 12:06:08.853040 D  IPTVSM()::UpdateValues: Waiting for table
 monitor to start
 2012-07-24 12:06:08.853043 I  TVRec(1): SetFlags(WaitingForSignal,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,RingBufferReady,
 2012-07-24 12:06:08.853050 I  TVRec(1): SetFlags(DummyRecorderRunning,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.853062 I  DummyDTVRecorder -- started
 2012-07-24 12:06:08.853066 D  IPTVSM()::Run: begin
 2012-07-24 12:06:08.853069 I  TVRec(1): SetFlags(RingBufferReady,) ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.853073 I  IPTVFeed: AddListener(0x108818e38) -- begin
 2012-07-24 12:06:08.853077 I  TVRec(1): ClearFlags(NeedToStartRecorder,)
 ->
 RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.853079 I  FbFeedLive:AddListener(0x108818e38) -- begin
 2012-07-24 12:06:08.853083 I  FbFeedLive:RemoveListener(0x108818e38) --
 begin
 2012-07-24 12:06:08.853087 I  FbFeedLive:RemoveListener(0x108818e38) --
 end 1
 2012-07-24 12:06:08.853087 I  TVRec(1): SetFlags(NeedToStartRecorder,) ->
 RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.853090 I  FbFeedLive:AddListener(0x108818e38) -- end
 2012-07-24 12:06:08.853093 I  IPTVFeed: AddListener(0x108818e38) -- end 1
 2012-07-24 12:06:08.853096 I  IPTVFeed: Run() -- begin
 2012-07-24 12:06:08.853131 I  TVRec(1): SetFlags(CancelNextRecording,) ->
 RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.853164 D  SM()::AddFlags: Seen(PAT,) Match() Wait()
 2012-07-24 12:06:08.853171 D  SM()::AddFlags: Seen() Match(PAT,) Wait()
 2012-07-24 12:06:08.853178 I  CreatePATSingleProgram()
 2012-07-24 12:06:08.853180 I  PAT in input stream
 2012-07-24 12:06:08.853197 I  Program Association Section
  PSIP tableID(0x0) length(13) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       tsid(1) programCount(1)
   program number     1 has PID 0x0fff

 2012-07-24 12:06:08.853202 I  desired_program(1) pid(0xfff)
 2012-07-24 12:06:08.853213 I  pmt_pid(0xfff)
 2012-07-24 12:06:08.853216 I  PAT for output stream
 2012-07-24 12:06:08.853229 I  Program Association Section
  PSIP tableID(0x0) length(13) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       tsid(1) programCount(1)
   program number     1 has PID 0x0fff

 2012-07-24 12:06:08.853255 D  SM()::AddFlags: Seen(PMT,) Match() Wait()
 2012-07-24 12:06:08.853276 D  SM()::AddFlags: Seen() Match(PMT,) Wait()
 2012-07-24 12:06:08.853279 I  CreatePMTSingleProgram()
 2012-07-24 12:06:08.853281 I  PMT in input stream
 2012-07-24 12:06:08.853316 I  Program Map Section
  PSIP tableID(0x2) length(60) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xfff)
   Metadata Pointer Descriptor (0x25) length(15)
   Stream #0 pid(0x102) type(0x15 unknown)
     Metadata Descriptor (0x26) length(13)
   Stream #1 pid(0x100) type(0x1b video-h264)
   Stream #2 pid(0x101) type(0x0f audio-aac)

 2012-07-24 12:06:08.853352 I  PMT for output stream
 2012-07-24 12:06:08.853384 I  Program Map Section
  PSIP tableID(0x2) length(60) extension(0x1)
       version(0) current(1) section(0) last_section(0)
       pnum(1) pid(0xfff)
   Metadata Pointer Descriptor (0x25) length(15)
   Stream #0 pid(0x102) type(0x15 unknown)
     Metadata Descriptor (0x26) length(13)
   Stream #1 pid(0x100) type(0x1b video-h264)
   Stream #2 pid(0x101) type(0x0f audio-aac)

 2012-07-24 12:06:08.858718 D  IPTVSM()::UpdateValues: Table monitor
 started
 2012-07-24 12:06:08.951885 I  TVRec(1): StopLiveTV(void) curRec:
 0x10a074800 pseudoRec: 0x0
 2012-07-24 12:06:08.952268 I  TVRec(1): Changing from WatchingLiveTV to
 None
 2012-07-24 12:06:08.952305 I  TVRec(1):
 ClearFlags(FrontendReady,CancelNextRecording,) ->
 RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:08.952355 I  TVRec(1): HandleTuning Request:
 Program(NULL) channel() input() flags(KillRec,KillRingBuffer,)
 2012-07-24 12:06:08.952366 I  TVRec(1): TeardownSignalMonitor() -- begin
 2012-07-24 12:06:08.952451 I  IPTVFeed: RemoveListener(0x108818e38) --
 begin
 2012-07-24 12:06:08.952464 I  FbFeedLive:RemoveListener(0x108818e38) --
 begin
 2012-07-24 12:06:08.952473 I  FbFeedLive:RemoveListener(0x108818e38) --
 end 2
 2012-07-24 12:06:08.952480 I  IPTVFeed: RemoveListener(0x108818e38) -- end
 (ok, removed)
 2012-07-24 12:06:08.952487 D  IPTVSM()::Stop: begin
 2012-07-24 12:06:08.952495 I  IPTVFeed: RemoveListener(0x108818e38) --
 begin
 2012-07-24 12:06:08.952502 E  IPTVFeed: RemoveListener(0x108818e38) -- end
 (not found)
 2012-07-24 12:06:08.952509 D  SM()::Stop: begin
 2012-07-24 12:06:09.109675 D  SM()::Stop: end
 2012-07-24 12:06:09.109688 I  IPTVFeed: Stop() -- begin
 2012-07-24 12:06:09.109693 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:09.159310 I  IPTVFeed: Run() -- end
 2012-07-24 12:06:09.159313 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:09.159322 I  IPTVFeed: RemoveListener(0x108818e38) --
 begin
 2012-07-24 12:06:09.159324 I  IPTVFeed: Stop() -- end
 2012-07-24 12:06:09.159327 E  IPTVFeed: RemoveListener(0x108818e38) -- end
 (not found)
 2012-07-24 12:06:09.159332 D  IPTVSM()::Run: end
 2012-07-24 12:06:09.159395 D  IPTVSM()::Stop: end
 2012-07-24 12:06:09.159401 D  IPTVSM()::~IPTVSignalMonitor: Didn't get a
 lock earlier, closing feed
 2012-07-24 12:06:09.159405 I  IPTVFeed: Close() -- begin
 2012-07-24 12:06:09.159407 I  IPTVHLS: Close() -- begin
 2012-07-24 12:06:09.159409 I  IPTVHLS: Stop() -- begin
 2012-07-24 12:06:09.159411 I  IPTVHLS: Stop() -- end
 2012-07-24 12:06:09.762202 C  MThread prolog was never run!
 2012-07-24 12:06:09.762209 C  MThread epilog was never run!
 2012-07-24 12:06:09.762256 C  MThread prolog was never run!
 2012-07-24 12:06:09.762260 C  MThread epilog was never run!
 2012-07-24 12:06:09.762388 I  IPTVHLS: Close() -- end
 2012-07-24 12:06:09.762392 I  IPTVFeed: Close() -- end
 2012-07-24 12:06:09.762408 D  SM()::Stop: begin
 2012-07-24 12:06:09.762412 D  SM()::Stop: end
 2012-07-24 12:06:09.762424 I  TVRec(1): TeardownSignalMonitor() -- end
 2012-07-24 12:06:09.762434 I  TVRec(1): ClearFlags(SignalMonitorRunning,)
 ->
 RunMainLoop,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:09.762482 I  TVRec(1): ClearFlags(WaitingForSignal,) ->
 RunMainLoop,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady,
 2012-07-24 12:06:09.762922 I  TVRec(1):
 FinishedRecording(1300_2012-07-24T02:06:05Z)
                         title:
                         in recgroup: LiveTV status: Unknown:Recorder
 Failed is_dummy finished_now
 2012-07-24 12:06:09.763098 I  Chain: Updated endtime for
 '1300_20120724020605' to 20120724020609
 2012-07-24 12:06:09.763358 I  TVRec(1): ClearFlags(DummyRecorderRunning,)
 -> RunMainLoop,NeedToStartRecorder,RingBufferReady,
 2012-07-24 12:06:09.763572 I  TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,NeedToStartRecorder,RingBufferReady,
 2012-07-24 12:06:09.763794 I  TVRec(1):
 FinishedRecording(1300_2012-07-24T02:06:05Z)
                         title:
                         in recgroup: LiveTV status: Recorder
 Failed:Recorder Failed not_dummy already_finished
 2012-07-24 12:06:09.763953 I  TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,NeedToStartRecorder,RingBufferReady,
 2012-07-24 12:06:09.763959 I  TVRec(1): Tearing down RingBuffer
 2012-07-24 12:06:09.764043 I  TVRec(1): ClearFlags(PENDINGACTIONS,) ->
 RunMainLoop,RingBufferReady,
 2012-07-24 12:06:17.528511 I  Starting mythlogserver

-- 
Ticket URL: <http://code.mythtv.org/trac/ticket/10934>
MythTV <http://code.mythtv.org/trac>
MythTV Media Center


More information about the mythtv-commits mailing list