id,summary,reporter,owner,description,type,status,priority,milestone,component,version,severity,resolution,keywords,cc,mlocked 10934,Can't enter liveTV with IPTV,JYA,jya,"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@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@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: 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@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 ",Bug Report - General,closed,blocker,0.26,MythTV - General,Master Head,medium,fixed,,,0