{\rtf1\ansi\ansicpg1252\cocoartf1348\cocoasubrtf170 {\fonttbl\f0\fmodern\fcharset0 Courier;} {\colortbl;\red255\green255\blue255;} \margl1440\margr1440\vieww25400\viewh13680\viewkind0 \deftab720 \pard\pardeftab720 \f0\fs24 \cf0 \expnd0\expndtw0\kerning0 16:20:30 T:139927409432640 NOTICE: Enabled debug logging due to GUI setting. Level 2.\ 16:20:30 T:139927409432640 DEBUG: ------ Window Init () ------\ 16:20:30 T:139927409432640 DEBUG: LIRC: Update - NEW at 34523801:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:20:31 T:139927409432640 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:20:36 T:139927409432640 DEBUG: LIRC: Update - NEW at 34530068:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:20:36 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu\ 16:20:36 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:20:37 T:139927409432640 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------\ 16:20:37 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:20:37 T:139927409432640 DEBUG: ------ Window Init (Settings.xml) ------\ 16:20:37 T:139927409432640 DEBUG: LIRC: Update - NEW at 34530444:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:20:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34533579:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:20:40 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu\ 16:20:40 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:20:40 T:139927409432640 DEBUG: ------ Window Deinit (Settings.xml) ------\ 16:20:40 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:20:40 T:139927409432640 DEBUG: ------ Window Init (Home.xml) ------\ 16:20:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34533951:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:20:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34534579:69 0 KEY_LEFT devinput (KEY_LEFT)\ 16:20:41 T:139927409432640 DEBUG: OnKey: 169 (0xa9) pressed, action is Left\ 16:20:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34534991:69 0 KEY_LEFT_UP devinput (KEY_LEFT_UP)\ 16:20:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34535031:69 0 KEY_LEFT devinput (KEY_LEFT)\ 16:20:41 T:139927409432640 DEBUG: OnKey: 169 (0xa9) pressed, action is Left\ 16:20:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34535402:69 0 KEY_LEFT_UP devinput (KEY_LEFT_UP)\ 16:20:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34535565:69 0 KEY_LEFT devinput (KEY_LEFT)\ 16:20:42 T:139927409432640 DEBUG: OnKey: 169 (0xa9) pressed, action is Left\ 16:20:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34535957:69 0 KEY_LEFT_UP devinput (KEY_LEFT_UP)\ 16:20:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34536186:160 0 KEY_OK devinput (KEY_OK)\ 16:20:42 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:20:42 T:139927409432640 DEBUG: Activating window ID: 10615\ 16:20:43 T:139927409432640 DEBUG: ------ Window Deinit (Home.xml) ------\ 16:20:43 T:139927409432640 DEBUG: ------ Window Init (MyPVRChannels.xml) ------\ 16:20:43 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All channels/)\ 16:20:43 T:139927409432640 DEBUG: ParentPath = [pvr://channels/tv/All channels/]\ 16:20:43 T:139927409432640 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All channels)\ 16:20:43 T:139926017562368 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:20:43 T:139926017562368 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-x86_64-linux.so\ 16:20:43 T:139926067918592 DEBUG: Thread JobWorker start, auto delete: true\ 16:20:43 T:139927409432640 DEBUG: LIRC: Update - NEW at 34536648:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:20:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34537769:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:20:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:20:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34538052:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:20:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34538070:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:20:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:20:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34538304:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:20:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34538320:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:20:45 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:20:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34538700:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:20:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34539000:160 0 KEY_OK devinput (KEY_OK)\ 16:20:45 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:20:45 T:139927409432640 DEBUG: Loading settings for pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr)\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv\ 16:20:45 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)\ 16:20:45 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players\ 16:20:45 T:139927409432640 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData\ 16:20:45 T:139927409432640 NOTICE: DVDPlayer: Opening: pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr\ 16:20:45 T:139927409432640 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED\ 16:20:45 T:139927409432640 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec\ 16:20:45 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:20:45 T:139927409432640 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16\ 16:20:45 T:139926009169664 DEBUG: Thread DVDPlayer start, auto delete: false\ 16:20:45 T:139926009169664 NOTICE: Creating InputStream\ 16:20:45 T:139926009169664 DEBUG: PVRManager - OpenLiveStream - opening live stream on channel '3 CBS'\ 16:20:45 T:139926009169664 DEBUG: opening live stream on url 'http://192.168.1.4:5004/auto/v3'\ 16:20:45 T:139926009169664 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr\ 16:20:45 T:139926009169664 INFO: easy_aquire - Created session to http://192.168.1.4\ 16:20:45 T:139926009169664 DEBUG: CCurlFile::GetMimeType - http://192.168.1.4:5004/auto/v3 -> video/mpeg\ 16:20:45 T:139926009169664 DEBUG: CFileCache::Open - opening using cache\ 16:20:45 T:139926009169664 DEBUG: CurlFile::Open(0x7f433823b050) http://192.168.1.4:5004/auto/v3\ 16:20:46 T:139926009169664 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: http://192.168.1.4:5004/auto/v3\ 16:20:46 T:139926009169664 NOTICE: Creating Demuxer\ 16:20:46 T:139925980976896 DEBUG: Thread FileCache start, auto delete: false\ 16:20:46 T:139926009169664 DEBUG: Open - probing detected format [mpegts]\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Input #0, mpegts, pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pv':\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Duration: N/A, bitrate: N/A\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Program 1003\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Stream #0:0[0x1eb1]: Video: mpeg2video ([2][0][0][0] / 0x0002), none, 90k tbn\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Stream #0:1[0x1eb4](eng): Audio: ac3 ([129][0][0][0] / 0x0081), 0 channels\ 16:20:46 T:139926009169664 INFO: ffmpeg[7F4310124700]: Stream #0:2[0x1eb5](spa): Audio: ac3 ([129][0][0][0] / 0x0081), 0 channels\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2\ 16:20:46 T:139926009169664 NOTICE: Opening stream: 0 source: 256\ 16:20:46 T:139926009169664 NOTICE: Creating video codec with codec id: 2\ 16:20:46 T:139926009169664 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Video: - Opening\ 16:20:46 T:139926009169664 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:20:46 T:139926009169664 NOTICE: Creating video thread\ 16:20:46 T:139926009169664 NOTICE: Opening stream: 1 source: 256\ 16:20:46 T:139926009169664 NOTICE: Finding audio codec for: 86019\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: passthrough - Opening\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: passthrough - Failed\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: FFmpeg - Opening\ 16:20:46 T:139926097045248 DEBUG: Thread DVDPlayerVideo start, auto delete: false\ 16:20:46 T:139926097045248 NOTICE: running thread: video_thread\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: FFmpeg - Opened\ 16:20:46 T:139926009169664 NOTICE: Creating audio thread\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:20:46 T:139926009169664 DEBUG: CDVDPlayer::SetCaching - caching state 2\ 16:20:46 T:139926088652544 DEBUG: Thread DVDPlayerAudio start, auto delete: false\ 16:20:46 T:139926088652544 NOTICE: running thread: CDVDPlayerAudio::Process()\ 16:20:46 T:139926088652544 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:20:46 T:139926009169664 DEBUG: OnPlayBackStarted: play state was 1, starting 1\ 16:20:46 T:139927409432640 DEBUG: PlayFile: OpenFile succeed, play state 2\ 16:20:46 T:139927409432640 DEBUG: OnPlayBackStarted: play state was 2, starting 0\ 16:20:46 T:139927409432640 DEBUG: LIRC: Update - NEW at 34539671:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:20:46 T:139927409432640 ERROR: CRenderManager::PrepareNextRender - asked to prepare with nothing available\ 16:20:46 T:139927409432640 DEBUG: ------ Window Init (DialogSeekBar.xml) ------\ 16:20:46 T:139927409432640 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr)\ 16:20:46 T:139927409432640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc\ 16:20:46 T:139927409432640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(88)\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:20:46 T:139926009169664 NOTICE: Opening stream: 0 source: 256\ 16:20:46 T:139926009169664 NOTICE: Creating video codec with codec id: 2\ 16:20:46 T:139926009169664 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Video: - Opening\ 16:20:46 T:139926009169664 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(62082093855.555557, 1)\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139926097045248 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(528x480)\ 16:20:46 T:139926097045248 NOTICE: (VDPAU) Close\ 16:20:46 T:139926097045248 DEBUG: VAAPI - open decoder\ 16:20:46 T:139926097045248 DEBUG: VAAPI - initialize version 0.39\ 16:20:46 T:139926097045248 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Sandybridge Mobile - 1.7.0\ 16:20:46 T:139926097045248 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0\ 16:20:46 T:139926097045248 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32\ 16:20:46 T:139926097045248 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0\ 16:20:46 T:139926097045248 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32\ 16:20:46 T:139926097045248 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 0\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 1\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 13\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 6\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 7\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 16\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 8\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 9\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile 10\ 16:20:46 T:139926097045248 DEBUG: VAAPI - profile -1\ 16:20:46 T:139926025955072 DEBUG: Thread Vaapi-Output start, auto delete: false\ 16:20:46 T:139926025955072 NOTICE: COutput::OnStartup: Output Thread created\ 16:20:46 T:139926025955072 DEBUG: VAAPI::COutput::CreateGlxContext - created context\ 16:20:46 T:139926025955072 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)\ 16:20:46 T:139926025955072 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so\ 16:20:46 T:139926025955072 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1\ 16:20:46 T:139926009169664 NOTICE: Opening stream: 1 source: 256\ 16:20:46 T:139926009169664 NOTICE: Finding audio codec for: 86019\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: passthrough - Opening\ 16:20:46 T:139926009169664 DEBUG: FactoryCodec - Audio: passthrough - Opened\ 16:20:46 T:139926088652544 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(62081838488.888893, 1)\ 16:20:46 T:139926088652544 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)\ 16:20:46 T:139926088652544 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)\ 16:20:46 T:139926088652544 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback\ 16:20:46 T:139926088652544 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:20:46 T:139926009169664 DEBUG: CDVDPlayer::HandleMessages - player started 1\ 16:20:46 T:139926009169664 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139926025955072 DEBUG: Previous line repeats 1 times.\ 16:20:46 T:139926025955072 DEBUG: ffmpeg[7F4311126700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:20:46 T:139926025955072 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139927390496512 DEBUG: Previous line repeats 3 times.\ 16:20:46 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0\ 16:20:46 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW\ 16:20:46 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none)\ 16:20:46 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:20:46 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:20:46 T:139927390496512 DEBUG: Sample Rate : 48000\ 16:20:46 T:139927390496512 DEBUG: Sample Format : AE_FMT_S16NE\ 16:20:46 T:139927390496512 DEBUG: Channel Count : 2\ 16:20:46 T:139927390496512 DEBUG: Channel Layout: RAW,RAW\ 16:20:46 T:139927390496512 DEBUG: Frames : 2400\ 16:20:46 T:139927390496512 DEBUG: Frame Samples : 4800\ 16:20:46 T:139927390496512 DEBUG: Frame Size : 4\ 16:20:46 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:20:46 T:139926097045248 DEBUG: Previous line repeats 1 times.\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:46 T:139926097045248 DEBUG: Previous line repeats 4 times.\ 16:20:46 T:139926097045248 NOTICE: fps: 0.000000, pwidth: 528, pheight: 480, dwidth: 528, dheight: 396\ 16:20:46 T:139926097045248 DEBUG: OutputPicture - change configuration. 528x480. framerate: 0.00. format: VAAPI_NV12\ 16:20:46 T:139926097045248 DEBUG: CXBMCRenderManager::Configure - 4\ 16:20:46 T:139927409432640 NOTICE: Using GL_TEXTURE_2D\ 16:20:46 T:139927409432640 DEBUG: GL: Requested render method: 0\ 16:20:46 T:139927409432640 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:\ #define XBMC_texture_rectangle 0\ #define XBMC_texture_rectangle_hack 0\ #define XBMC_STRETCH 0\ #define XBMC_NV12\ 16:20:46 T:139927409432640 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader\ 16:20:46 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:20:46 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:20:46 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:20:46 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:20:46 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:20:46 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:20:46 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:20:46 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:20:46 T:139927409432640 NOTICE: GL: NPOT texture support detected\ 16:20:46 T:139927409432640 NOTICE: GL: Using GL_ARB_pixel_buffer_object\ 16:20:46 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:20:47 T:139927409432640 DEBUG: Activating window ID: 12005\ 16:20:47 T:139927409432640 DEBUG: ------ Window Deinit (MyPVRChannels.xml) ------\ 16:20:47 T:139927409432640 DEBUG: ------ Window Init (VideoFullScreen.xml) ------\ 16:20:47 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:20:47 T:139926009169664 DEBUG: CDVDPlayer::HandleMessages - player started 2\ 16:20:47 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:20:47 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:20:47 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:20:47 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:20:47 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:20:47 T:139926009169664 DEBUG: set caching from pvr to done. audio (1) = 11. video (1) = 6\ 16:20:47 T:139926009169664 DEBUG: CDVDPlayer::SetCaching - caching state 0\ 16:20:47 T:139926088652544 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:20:47 T:139926088652544 NOTICE: CDVDPlayerAudio::OutputPacket duplicate 3 packets of duration 32\ 16:20:47 T:139926097045248 NOTICE: Previous line repeats 1 times.\ 16:20:47 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:20:47 T:139926097045248 DEBUG: Previous line repeats 1 times.\ 16:20:47 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:20:47 T:139926088652544 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:20:47 T:139926088652544 NOTICE: Previous line repeats 1 times.\ 16:20:47 T:139926088652544 DEBUG: CDVDPlayerAudio::HandleSyncError - average error -11166.320139 below threshold of 50000.000000\ 16:20:47 T:139926088652544 DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:62081655268.789894, should be:62081644103.565758, error:-11165.224136\ 16:20:47 T:139927409432640 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------\ 16:20:52 T:139926097045248 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667\ 16:21:00 T:139927409432640 DEBUG: LIRC: Update - NEW at 34553662:80 0 KEY_STOP devinput (KEY_STOP)\ 16:21:00 T:139927409432640 DEBUG: OnKey: guide (0xe0) pressed, action is Stop\ 16:21:00 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:21:00 T:139926009169664 NOTICE: CDVDPlayer::OnExit()\ 16:21:00 T:139926009169664 NOTICE: Closing stream player 1\ 16:21:00 T:139926009169664 NOTICE: Waiting for audio thread to exit\ 16:21:00 T:139926088652544 NOTICE: thread end: CDVDPlayerAudio::OnExit()\ 16:21:00 T:139926088652544 DEBUG: Thread DVDPlayerAudio 139926088652544 terminating\ 16:21:00 T:139926009169664 NOTICE: Closing audio device\ 16:21:00 T:139927398889216 DEBUG: CActiveAE::DiscardStream - audio stream deleted\ 16:21:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:00 T:139926009169664 NOTICE: Deleting audio codec\ 16:21:00 T:139926009169664 NOTICE: Closing stream player 2\ 16:21:00 T:139926009169664 NOTICE: waiting for video thread to exit\ 16:21:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:00 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:21:00 T:139926097045248 NOTICE: thread end: video_thread\ 16:21:00 T:139926097045248 DEBUG: Thread DVDPlayerVideo 139926097045248 terminating\ 16:21:00 T:139926009169664 NOTICE: deleting video codec\ 16:21:00 T:139926009169664 DEBUG: VAAPI::Release pre-cleanup\ 16:21:00 T:139926025955072 DEBUG: ffmpeg[7F4311126700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:21:00 T:139926025955072 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:21:00 T:139925980976896 DEBUG: Thread FileCache 139925980976896 terminating\ 16:21:00 T:139926009169664 DEBUG: CDVDInputStreamPVRManager::Close - stream closed\ 16:21:00 T:139926009169664 DEBUG: OnPlayBackStopped: play state was 2, starting 0\ 16:21:00 T:139926017562368 DEBUG: DoWork - Saving file state for video item pvr://channels/tv/All channels/pvr.hdhomerun_327066023.pvr\ 16:21:00 T:139926009169664 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc\ 16:21:00 T:139926009169664 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop\ 16:21:00 T:139926009169664 DEBUG: Thread DVDPlayer 139926009169664 terminating\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:21:00 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:00 T:139927409432640 NOTICE: VAAPI::Close\ 16:21:00 T:139926025955072 DEBUG: ffmpeg[7F4311126700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:21:00 T:139926025955072 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:21:00 T:139926025955072 NOTICE: COutput::OnExit: Output Thread terminated\ 16:21:00 T:139926025955072 DEBUG: Thread Vaapi-Output 139926025955072 terminating\ 16:21:00 T:139927409432640 DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces\ 16:21:00 T:139927409432640 NOTICE: VAAPI::Close - closing decoder context\ 16:21:00 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:21:00 T:139927409432640 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------\ 16:21:00 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:21:00 T:139927409432640 DEBUG: ------ Window Init (MyPVRChannels.xml) ------\ 16:21:00 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All channels/)\ 16:21:00 T:139927409432640 DEBUG: ParentPath = [pvr://channels/tv/All channels/]\ 16:21:00 T:139927409432640 DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All channels)\ 16:21:00 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1\ 16:21:00 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1"\ 16:21:00 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"\ 16:21:00 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats\ 16:21:00 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR\ 16:21:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)\ 16:21:00 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:21:00 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:21:00 T:139927390496512 DEBUG: Sample Rate : 44100\ 16:21:00 T:139927390496512 DEBUG: Sample Format : AE_FMT_S32NE\ 16:21:00 T:139927390496512 DEBUG: Channel Count : 2\ 16:21:00 T:139927390496512 DEBUG: Channel Layout: FL,FR\ 16:21:00 T:139927390496512 DEBUG: Frames : 2205\ 16:21:00 T:139927390496512 DEBUG: Frame Samples : 4410\ 16:21:00 T:139927390496512 DEBUG: Frame Size : 8\ 16:21:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:00 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:21:00 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:00 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:21:00 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:21:00 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:00 T:139927409432640 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '500 Barker 6 Quantu' from client '935'\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '500 Barker 6 Quantu' from client '935'\ 16:21:00 T:139927409432640 DEBUG: LIRC: Update - NEW at 34554138:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '997 Barker 5-Mercur' from client '935'\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '131 Barker 3 - FIOS' from client '935'\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '997 Barker 5-Mercur' from client '935'\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '131 Barker 3 - FIOS' from client '935'\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:21:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:21:00 T:139926790629120 DEBUG: CPVRTimers - Update - updating timers\ 16:21:04 T:139927409432640 DEBUG: LIRC: Update - NEW at 34557636:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:21:04 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:21:04 T:139927409432640 DEBUG: Activating window ID: 10000\ 16:21:04 T:139927409432640 DEBUG: ------ Window Deinit (MyPVRChannels.xml) ------\ 16:21:04 T:139927409432640 DEBUG: ------ Window Init (Home.xml) ------\ 16:21:04 T:139927409432640 DEBUG: LIRC: Update - NEW at 34558118:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:21:05 T:139927409432640 DEBUG: LIRC: Update - NEW at 34558631:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:05 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:05 T:139927409432640 DEBUG: LIRC: Update - NEW at 34559003:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:05 T:139927409432640 DEBUG: LIRC: Update - NEW at 34559114:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:05 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:06 T:139927409432640 DEBUG: LIRC: Update - NEW at 34559468:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:06 T:139927409432640 DEBUG: LIRC: Update - NEW at 34559706:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:06 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:06 T:139927409432640 DEBUG: LIRC: Update - NEW at 34560068:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:08 T:139927409432640 DEBUG: LIRC: Update - NEW at 34561567:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:08 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:08 T:139927409432640 DEBUG: LIRC: Update - NEW at 34561972:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:10 T:139927409432640 DEBUG: LIRC: Update - NEW at 34563838:160 0 KEY_OK devinput (KEY_OK)\ 16:21:10 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:10 T:139927409432640 DEBUG: Activating window ID: 10004\ 16:21:10 T:139927409432640 DEBUG: ------ Window Deinit (Home.xml) ------\ 16:21:10 T:139927409432640 DEBUG: ------ Window Init (Settings.xml) ------\ 16:21:10 T:139927409432640 DEBUG: LIRC: Update - NEW at 34564191:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:11 T:139927409432640 DEBUG: LIRC: Update - NEW at 34564707:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:11 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:11 T:139927409432640 DEBUG: LIRC: Update - NEW at 34565122:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34565556:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:12 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34565840:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34565880:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:12 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34566202:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:13 T:139927409432640 DEBUG: LIRC: Update - NEW at 34566441:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:13 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:13 T:139927409432640 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:21:13 T:139927409432640 DEBUG: LIRC: Update - NEW at 34566796:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:14 T:139927409432640 DEBUG: LIRC: Update - NEW at 34567430:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:14 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:14 T:139927409432640 DEBUG: LIRC: Update - NEW at 34567710:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:14 T:139927409432640 DEBUG: LIRC: Update - NEW at 34567750:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:14 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:14 T:139927409432640 DEBUG: LIRC: Update - NEW at 34568067:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34568347:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:15 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34568749:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34568830:160 0 KEY_OK devinput (KEY_OK)\ 16:21:15 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:15 T:139927409432640 DEBUG: Activating window ID: 10040\ 16:21:15 T:139927409432640 DEBUG: ------ Window Deinit (Settings.xml) ------\ 16:21:15 T:139927409432640 DEBUG: ------ Window Init (AddonBrowser.xml) ------\ 16:21:15 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:21:15 T:139927409432640 DEBUG: ParentPath = []\ 16:21:15 T:139926025955072 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:21:15 T:139926025955072 DEBUG: Thread BackgroundLoader 139926025955072 terminating\ 16:21:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34569202:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:17 T:139927409432640 DEBUG: LIRC: Update - NEW at 34570629:67 0 KEY_UP devinput (KEY_UP)\ 16:21:17 T:139927409432640 DEBUG: OnKey: 166 (0xa6) pressed, action is Up\ 16:21:17 T:139927409432640 DEBUG: LIRC: Update - NEW at 34571007:67 0 KEY_UP_UP devinput (KEY_UP_UP)\ 16:21:17 T:139927409432640 DEBUG: LIRC: Update - NEW at 34571047:160 0 KEY_OK devinput (KEY_OK)\ 16:21:17 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:17 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (addons://user/)\ 16:21:17 T:139927409432640 DEBUG: ParentPath = []\ 16:21:17 T:139926025955072 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:21:17 T:139926025955072 DEBUG: Thread BackgroundLoader 139926025955072 terminating\ 16:21:18 T:139927409432640 DEBUG: LIRC: Update - NEW at 34571368:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:18 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572012:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:18 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572368:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572385:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:19 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572634:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572651:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:19 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572868:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34572884:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:19 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34573118:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:19 T:139927409432640 DEBUG: LIRC: Update - NEW at 34573135:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:19 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:20 T:139927409432640 DEBUG: LIRC: Update - NEW at 34573368:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:20 T:139927409432640 DEBUG: LIRC: Update - NEW at 34573385:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:20 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:20 T:139927409432640 DEBUG: LIRC: Update - NEW at 34573751:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:21 T:139927409432640 DEBUG: LIRC: Update - NEW at 34574471:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:21 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:21 T:139927409432640 DEBUG: LIRC: Update - NEW at 34574751:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:21 T:139927409432640 DEBUG: LIRC: Update - NEW at 34574768:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:21 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:21 T:139927409432640 DEBUG: LIRC: Update - NEW at 34575135:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:21 T:139927409432640 DEBUG: LIRC: Update - NEW at 34575152:160 0 KEY_OK devinput (KEY_OK)\ 16:21:21 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:21 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (addons://user/xbmc.addon.video)\ 16:21:21 T:139927409432640 DEBUG: ParentPath = [addons://user/]\ 16:21:21 T:139926025955072 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:21:21 T:139926025955072 DEBUG: Thread BackgroundLoader 139926025955072 terminating\ 16:21:21 T:139926017562368 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:21:21 T:139926017562368 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-x86_64-linux.so\ 16:21:21 T:139926017562368 DEBUG: Caching image '/storage/.kodi/addons/script.hdhomerun.view/icon.png' to 'c/c52c8358.png':\ 16:21:21 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/c/c52c8358.png' size 256x256\ 16:21:22 T:139927409432640 DEBUG: LIRC: Update - NEW at 34575525:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:22 T:139927409432640 DEBUG: LIRC: Update - NEW at 34575893:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:22 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:22 T:139926067918592 DEBUG: Caching image '/storage/.kodi/addons/script.hdhomerun.view/fanart.jpg' to '2/2a661981.jpg':\ 16:21:22 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/2/2a661981.jpg' size 1086x611\ 16:21:22 T:139927409432640 DEBUG: LIRC: Update - NEW at 34576267:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:23 T:139927409432640 DEBUG: LIRC: Update - NEW at 34576300:160 0 KEY_OK devinput (KEY_OK)\ 16:21:23 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:23 T:139927409432640 DEBUG: ------ Window Init (DialogAddonInfo.xml) ------\ 16:21:23 T:139927409432640 DEBUG: LIRC: Update - NEW at 34576685:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:24 T:139927409432640 DEBUG: LIRC: Update - NEW at 34577799:160 0 KEY_OK devinput (KEY_OK)\ 16:21:24 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:24 T:139926025955072 DEBUG: Thread LanguageInvoker start, auto delete: false\ 16:21:24 T:139926025955072 INFO: initializing python engine.\ 16:21:24 T:139926025955072 DEBUG: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): start processing\ 16:21:24 T:139926025955072 DEBUG: -->Python Interpreter Initialized<--\ 16:21:24 T:139926025955072 DEBUG: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): the source file to load is "/storage/.kodi/addons/script.hdhomerun.view/default.py"\ 16:21:24 T:139926025955072 DEBUG: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): setting the Python path to /storage/.kodi/addons/script.hdhomerun.view:/storage/.kodi/addons/script.module.requests/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0\ 16:21:24 T:139926025955072 DEBUG: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): entering source directory /storage/.kodi/addons/script.hdhomerun.view\ 16:21:24 T:139926025955072 DEBUG: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): instantiating addon using automatically obtained id of "script.hdhomerun.view" dependent on version 2.1.0 of the xbmc.python api\ 16:21:24 T:139927409432640 DEBUG: ------ Window Deinit (DialogAddonInfo.xml) ------\ 16:21:24 T:139926025955072 NOTICE: script.hdhomerun.view: Version: 2016.05.09\ 16:21:24 T:139926025955072 NOTICE: script.hdhomerun.view: Current Kodi skin: skin.confluence\ 16:21:24 T:139926025955072 WARNING: CSkinInfo: failed to load skin settings\ 16:21:24 T:139927409432640 DEBUG: Activating window ID: 13000\ 16:21:25 T:139927409432640 DEBUG: ------ Window Deinit (AddonBrowser.xml) ------\ 16:21:25 T:139927409432640 DEBUG: ------ Window Init (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-background.xml) ------\ 16:21:25 T:139927409432640 INFO: Loading skin file: /storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-background.xml, load type: LOAD_ON_GUI_INIT\ 16:21:25 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:21:25 T:139927409432640 DEBUG: ParentPath = []\ 16:21:25 T:139927409432640 DEBUG: LIRC: Update - NEW at 34578347:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:25 T:139926025955072 WARNING: CSkinInfo: failed to load skin settings\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: Cron started\ 16:21:25 T:139927409432640 DEBUG: ------ Window Init (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-overlay.xml) ------\ 16:21:25 T:139927409432640 INFO: Loading skin file: /storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-overlay.xml, load type: LOAD_ON_GUI_INIT\ 16:21:25 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:21:25 T:139927409432640 DEBUG: ParentPath = []\ 16:21:25 T:139927409432640 DEBUG: POParser: loaded 89 strings from file /storage/.kodi/addons/script.hdhomerun.view/resources/language/English/strings.po\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: Touch mode: DISABLED\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: o-> Broadcast Packet(0002000c0104ffffffff0204ffffffff73cc7d8f)\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: o-> Broadcasting to lo: 127.255.255.255\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: o-> Broadcasting to eth0: 192.168.1.255\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: <-o Response Packet[eth0](000300770104000000010204131989562b1850435279756351584751506a6b716961515058382b67774329123c2472b9c4171903e392a89a40f5fcfa0c021001032a15687474703a2f2f3139322e3136382e312e343a38302721687474703a2f2f3139322e3136382e312e343a38302f6c696e6575702e6a736f6e786fec5c)\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: <-o Response Packet[eth0](00030077010400000001020413198a552b1859732b67455543425a675075476958674f337a48414f7662291262cfa01140816603ee1a25e03b7cc700ebdb1001032a15687474703a2f2f3139322e3136382e312e333a38302721687474703a2f2f3139322e3136382e312e333a38302f6c696e6575702e6a736f6ec48c4135)\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: o-> Broadcasting to lo: 127.255.255.255\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: o-> Broadcasting to eth0: 192.168.1.255\ 16:21:25 T:139926025955072 NOTICE: script.hdhomerun.view: <-o Response Packet[eth0](Duplicate)\ 16:21:26 T:139926025955072 NOTICE: Previous line repeats 1 times.\ 16:21:26 T:139926025955072 DEBUG: POParser: loaded 89 strings from file /storage/.kodi/addons/script.hdhomerun.view/resources/language/English/strings.po\ 16:21:26 T:139926025955072 NOTICE: script.hdhomerun.view: \ Device at 192.168.1.3:\ ID: 13198a55\ Type: TunerDevice\ DeviceAuth: Ys+gEUCBZgPuGiXgO3zHAOvb\ URL: http://192.168.1.3:80/lineup.json\ Channels: 397\ 16:21:26 T:139926025955072 NOTICE: script.hdhomerun.view: \ Device at 192.168.1.4:\ ID: 13198956\ Type: TunerDevice\ DeviceAuth: PCRyucQXGQPjkqiaQPX8+gwC\ URL: http://192.168.1.4:80/lineup.json\ Channels: 371\ 16:21:26 T:139926025955072 NOTICE: script.hdhomerun.view: Fetching guide from: http://my.hdhomerun.com/api/guide.php?DeviceAuth=Ys%2BgEUCBZgPuGiXgO3zHAOvbPCRyucQXGQPjkqiaQPX8%2BgwC\ 16:21:27 T:139926025955072 NOTICE: script.hdhomerun.view: Guide data received.\ 16:21:27 T:139926025955072 NOTICE: script.hdhomerun.view: Next guide update: 55 minutes\ 16:21:28 T:139926025955072 NOTICE: script.hdhomerun.view: Found 397 total channels\ 16:21:28 T:139926025955072 NOTICE: script.hdhomerun.view: HDHR video not currently playing. Starting channel...\ 16:21:28 T:139926025955072 NOTICE: script.hdhomerun.view: Playing from source: 13198a55\ 16:21:28 T:139927409432640 INFO: easy_aquire - Created session to http://192.168.1.3\ 16:21:28 T:139927409432640 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v2 -> video/mpeg\ 16:21:28 T:139927409432640 DEBUG: Loading settings for http://192.168.1.3:5004/auto/v2\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers(http://192.168.1.3:5004/auto/v2)\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv\ 16:21:28 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)\ 16:21:28 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players\ 16:21:28 T:139927409432640 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData\ 16:21:28 T:139927409432640 NOTICE: DVDPlayer: Opening: http://192.168.1.3:5004/auto/v2\ 16:21:28 T:139927409432640 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED\ 16:21:28 T:139927409432640 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec\ 16:21:28 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:28 T:139927409432640 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16\ 16:21:28 T:139925909722880 DEBUG: Thread DVDPlayer start, auto delete: false\ 16:21:28 T:139925909722880 NOTICE: Creating InputStream\ 16:21:28 T:139925909722880 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v2 -> video/mpeg\ 16:21:28 T:139925909722880 DEBUG: CFileCache::Open - opening using cache\ 16:21:28 T:139925909722880 DEBUG: CurlFile::Open(0x7f4320866b50) http://192.168.1.3:5004/auto/v2\ 16:21:28 T:139925909722880 NOTICE: Creating Demuxer\ 16:21:28 T:139925943351040 DEBUG: Thread FileCache start, auto delete: false\ 16:21:28 T:139925909722880 DEBUG: Open - probing detected format [mpegts]\ 16:21:28 T:139925909722880 DEBUG: Open - avformat_find_stream_info starting\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:29 T:139927409432640 ERROR: Previous line repeats 3 times.\ 16:21:29 T:139927409432640 DEBUG: ------ Window Init (DialogBusy.xml) ------\ 16:21:29 T:139926067918592 INFO: easy_aquire - Created session to http://usnj-my.hdhomerun.com\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:29 T:139926017562368 INFO: easy_aquire - Created session to http://usnj-my.hdhomerun.com\ 16:21:29 T:139926088652544 DEBUG: Thread JobWorker start, auto delete: true\ 16:21:29 T:139926088652544 INFO: easy_aquire - Created session to http://usnj-my.hdhomerun.com\ 16:21:29 T:139925980976896 DEBUG: Thread JobWorker start, auto delete: true\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:29 T:139925980976896 INFO: easy_aquire - Created session to http://usnj-my.hdhomerun.com\ 16:21:29 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197637/Primary -> image/jpeg\ 16:21:29 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:29 T:139926017562368 DEBUG: CurlFile::Open(0x7f4328bee770) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197637/Primary\ 16:21:29 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/4408/Primary -> image/jpeg\ 16:21:29 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:29 T:139926067918592 DEBUG: CurlFile::Open(0x7f43300120d0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/4408/Primary\ 16:21:29 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/369425/Primary -> image/jpeg\ 16:21:29 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:29 T:139926088652544 DEBUG: CurlFile::Open(0x7f43382e44a0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/369425/Primary\ 16:21:29 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/663544/Primary -> image/jpeg\ 16:21:29 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:29 T:139925980976896 DEBUG: CurlFile::Open(0x7f434421fec0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/663544/Primary\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:29 T:139926009169664 DEBUG: Thread FileCache start, auto delete: false\ 16:21:29 T:139926009169664 DEBUG: Previous line repeats 3 times.\ 16:21:29 T:139926009169664 INFO: CFileCache::Process - Hit eof.\ 16:21:29 T:139926009169664 INFO: Previous line repeats 3 times.\ 16:21:29 T:139926009169664 DEBUG: Thread FileCache 139926009169664 terminating\ 16:21:29 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/4408/Primary' to '0/06231350.jpg':\ 16:21:29 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/0/06231350.jpg' size 400x300\ 16:21:29 T:139925892937472 DEBUG: Thread FileCache 139925892937472 terminating\ 16:21:29 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197637/Primary' to '2/2f379cc7.jpg':\ 16:21:29 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/2/2f379cc7.jpg' size 400x300\ 16:21:29 T:139925884544768 DEBUG: Thread FileCache 139925884544768 terminating\ 16:21:29 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/663544/Primary' to 'b/b8342d03.jpg':\ 16:21:29 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/b/b8342d03.jpg' size 400x300\ 16:21:29 T:139926097045248 DEBUG: Thread FileCache 139926097045248 terminating\ 16:21:29 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/369425/Primary' to 'd/d10bd14e.jpg':\ 16:21:29 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/d/d10bd14e.jpg' size 400x300\ 16:21:29 T:139925909722880 DEBUG: ffmpeg[7F430A24D700]: [mpegts] max_analyze_duration 500000 reached at 512000 microseconds st:1\ 16:21:29 T:139925909722880 DEBUG: Open - av_find_stream_info finished\ 16:21:29 T:139925909722880 INFO: ffmpeg[7F430A24D700]: Input #0, mpegts, http://192.168.1.3:5004/auto/v':\ 16:21:29 T:139925909722880 INFO: ffmpeg[7F430A24D700]: Duration: N/A, start: 398.616478, bitrate: N/A\ 16:21:29 T:139925909722880 INFO: ffmpeg[7F430A24D700]: Program 3143\ 16:21:29 T:139925909722880 INFO: ffmpeg[7F430A24D700]: Stream #0:0[0x471]: Video: mpeg2video (Main) ([2][0][0][0] / 0x0002), yuv420p(tv), Closed Captions, max. 15000 kb/s, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc\ 16:21:29 T:139925909722880 INFO: ffmpeg[7F430A24D700]: Stream #0:1[0x474](eng): Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, stereo, fltp, 192 kb/s\ 16:21:29 T:139925909722880 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:21:29 T:139925909722880 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1\ 16:21:29 T:139925909722880 NOTICE: Opening stream: 0 source: 256\ 16:21:29 T:139925909722880 NOTICE: Creating video codec with codec id: 2\ 16:21:29 T:139925909722880 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Video: - Opening\ 16:21:29 T:139925909722880 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [IMGUTILS] Picture size 0x480 is invalid\ 16:21:29 T:139925909722880 DEBUG: ffmpeg[7F430A24D700]: [mpeg2video] Ignoring invalid width/height values\ 16:21:29 T:139925909722880 ERROR: ffmpeg[7F430A24D700]: [IMGUTILS] Picture size 0x0 is invalid\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:21:29 T:139925909722880 NOTICE: Creating video thread\ 16:21:29 T:139925909722880 NOTICE: Opening stream: 1 source: 256\ 16:21:29 T:139925909722880 NOTICE: Finding audio codec for: 86019\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Audio: passthrough - Opening\ 16:21:29 T:139926097045248 DEBUG: Thread DVDPlayerVideo start, auto delete: false\ 16:21:29 T:139926097045248 NOTICE: running thread: video_thread\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Audio: passthrough - Opened\ 16:21:29 T:139925909722880 NOTICE: Creating audio thread\ 16:21:29 T:139926097045248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:21:29 T:139925884544768 DEBUG: Thread DVDPlayerAudio start, auto delete: false\ 16:21:29 T:139925884544768 NOTICE: running thread: CDVDPlayerAudio::Process()\ 16:21:29 T:139925884544768 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:21:29 T:139925909722880 DEBUG: OnPlayBackStarted: play state was 1, starting 1\ 16:21:29 T:139925909722880 DEBUG: CDVDPlayer::SetCaching - caching state 3\ 16:21:29 T:139925909722880 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(86)\ 16:21:29 T:139925909722880 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:21:29 T:139925909722880 NOTICE: Opening stream: 0 source: 256\ 16:21:29 T:139925909722880 NOTICE: Creating video codec with codec id: 2\ 16:21:29 T:139925909722880 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Video: - Opening\ 16:21:29 T:139925909722880 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:21:29 T:139925909722880 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:21:29 T:139926097045248 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(460933.111111, 1)\ 16:21:29 T:139926097045248 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(704x480)\ 16:21:29 T:139926097045248 NOTICE: (VDPAU) Close\ 16:21:29 T:139925884544768 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(319999.777778, 1)\ 16:21:29 T:139925884544768 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)\ 16:21:29 T:139925884544768 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)\ 16:21:29 T:139925884544768 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback\ 16:21:29 T:139926097045248 DEBUG: VAAPI - open decoder\ 16:21:29 T:139926097045248 DEBUG: VAAPI - initialize version 0.39\ 16:21:29 T:139926097045248 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Sandybridge Mobile - 1.7.0\ 16:21:29 T:139926097045248 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0\ 16:21:29 T:139926097045248 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32\ 16:21:29 T:139926097045248 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0\ 16:21:29 T:139926097045248 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32\ 16:21:29 T:139926097045248 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 0\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 1\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 13\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 6\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 7\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 16\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 8\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 9\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile 10\ 16:21:29 T:139926097045248 DEBUG: VAAPI - profile -1\ 16:21:29 T:139925892937472 DEBUG: Thread Vaapi-Output start, auto delete: false\ 16:21:29 T:139925892937472 NOTICE: COutput::OnStartup: Output Thread created\ 16:21:29 T:139925892937472 DEBUG: VAAPI::COutput::CreateGlxContext - created context\ 16:21:29 T:139925892937472 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created\ 16:21:29 T:139927409432640 DEBUG: PlayFile: OpenFile succeed, play state 2\ 16:21:29 T:139927409432640 DEBUG: OnPlayBackStarted: play state was 2, starting 0\ 16:21:29 T:139925892937472 DEBUG: ffmpeg[7F430924B700]: [src] w:704 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:21:29 T:139925892937472 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:21:29 T:139926097045248 NOTICE: fps: 29.970030, pwidth: 704, pheight: 480, dwidth: 704, dheight: 396\ 16:21:29 T:139926097045248 DEBUG: OutputPicture - change configuration. 704x480. framerate: 29.97. format: VAAPI_NV12\ 16:21:29 T:139926097045248 NOTICE: Display resolution DESKTOP : 1920x1080@ 60.00 - Full Screen (16)\ 16:21:29 T:139926097045248 DEBUG: CXBMCRenderManager::Configure - 4\ 16:21:29 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:21:29 T:139927409432640 DEBUG: CGUIInfoManager::SetCurrentMovie(http://192.168.1.3:5004/auto/v2)\ 16:21:29 T:139927409432640 DEBUG: GetMovieId (http://192.168.1.3:5004/auto/v2), query = select idMovie from movie where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: GetEpisodeId (http://192.168.1.3:5004/auto/v2), query = select idEpisode from episode where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: GetMusicVideoId (http://192.168.1.3:5004/auto/v2), query = select idMVideo from musicvideo where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: GetMovieId (http://192.168.1.3:5004/auto/v2), query = select idMovie from movie where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: GetEpisodeId (http://192.168.1.3:5004/auto/v2), query = select idEpisode from episode where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: GetMusicVideoId (http://192.168.1.3:5004/auto/v2), query = select idMVideo from musicvideo where idFile=3238\ 16:21:29 T:139927409432640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc\ 16:21:29 T:139927409432640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay\ 16:21:29 T:139927409432640 DEBUG: Activating window ID: 12005\ 16:21:29 T:139927409432640 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-background.xml) ------\ 16:21:29 T:139927409432640 DEBUG: ------ Window Init (VideoFullScreen.xml) ------\ 16:21:29 T:139927409432640 NOTICE: Using GL_TEXTURE_2D\ 16:21:29 T:139927409432640 DEBUG: GL: Requested render method: 0\ 16:21:29 T:139927409432640 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:\ #define XBMC_texture_rectangle 0\ #define XBMC_texture_rectangle_hack 0\ #define XBMC_STRETCH 0\ #define XBMC_NV12\ 16:21:29 T:139927409432640 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader\ 16:21:29 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:21:29 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:21:29 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:21:29 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:21:29 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:21:29 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:21:29 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:21:29 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:21:29 T:139927409432640 NOTICE: GL: NPOT texture support detected\ 16:21:29 T:139927409432640 NOTICE: GL: Using GL_ARB_pixel_buffer_object\ 16:21:29 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:21:29 T:139926025955072 NOTICE: script.hdhomerun.view: ON PLAYBACK STARTED\ 16:21:29 T:139925909722880 DEBUG: CDVDPlayer::HandleMessages - player started 2\ 16:21:29 T:139927409432640 DEBUG: ------ Window Deinit (DialogBusy.xml) ------\ 16:21:29 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0\ 16:21:29 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"\ 16:21:29 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW\ 16:21:29 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none)\ 16:21:29 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:21:29 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:21:29 T:139927390496512 DEBUG: Sample Rate : 48000\ 16:21:29 T:139927390496512 DEBUG: Sample Format : AE_FMT_S16NE\ 16:21:29 T:139927390496512 DEBUG: Channel Count : 2\ 16:21:29 T:139927390496512 DEBUG: Channel Layout: RAW,RAW\ 16:21:29 T:139927390496512 DEBUG: Frames : 2400\ 16:21:29 T:139927390496512 DEBUG: Frame Samples : 4800\ 16:21:29 T:139927390496512 DEBUG: Frame Size : 4\ 16:21:29 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:29 T:139925909722880 DEBUG: Previous line repeats 1 times.\ 16:21:29 T:139925909722880 DEBUG: CDVDPlayer::HandleMessages - player started 1\ 16:21:29 T:139925909722880 DEBUG: CDVDPlayer::SetCaching - caching state 0\ 16:21:29 T:139925884544768 NOTICE: CDVDPlayerAudio::OutputPacket duplicate 3 packets of duration 32\ 16:21:29 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:29 T:139926097045248 DEBUG: Previous line repeats 1 times.\ 16:21:29 T:139926097045248 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:21:29 T:139925884544768 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:30 T:139925884544768 NOTICE: Previous line repeats 1 times.\ 16:21:30 T:139925884544768 DEBUG: CDVDPlayerAudio::HandleSyncError - average error 3421.216900 below threshold of 50000.000000\ 16:21:30 T:139927409432640 INFO: CheckIdle - Closing session to http://192.168.1.4 (easy=0x7f43381eb8e0, multi=0x7f433823f590)\ 16:21:31 T:139927409432640 DEBUG: LIRC: Update - NEW at 34584636:207 0 KEY_NUMERIC_7 devinput (KEY_NUMERIC_7)\ 16:21:31 T:139927409432640 DEBUG: OnKey: 200 (0xc8) pressed, action is JumpSMS7\ 16:21:31 T:139927409432640 DEBUG: LIRC: Update - NEW at 34585103:207 0 KEY_NUMERIC_7_UP devinput (KEY_NUMERIC_7_UP)\ 16:21:31 T:139927409432640 DEBUG: LIRC: Update - NEW at 34585170:208 0 KEY_NUMERIC_8 devinput (KEY_NUMERIC_8)\ 16:21:31 T:139927409432640 DEBUG: OnKey: 199 (0xc7) pressed, action is JumpSMS8\ 16:21:32 T:139927409432640 DEBUG: LIRC: Update - NEW at 34585570:208 0 KEY_NUMERIC_8_UP devinput (KEY_NUMERIC_8_UP)\ 16:21:32 T:139927409432640 DEBUG: LIRC: Update - NEW at 34585574:200 0 KEY_NUMERIC_0 devinput (KEY_NUMERIC_0)\ 16:21:32 T:139927409432640 DEBUG: OnKey: 207 (0xcf) pressed, action is Number0\ 16:21:32 T:139926025955072 WARNING: CSkinInfo: failed to load skin settings\ 16:21:32 T:139927409432640 DEBUG: ------ Window Init (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-channel_entry.xml) ------\ 16:21:32 T:139927409432640 INFO: Loading skin file: /storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-channel_entry.xml, load type: LOAD_ON_GUI_INIT\ 16:21:32 T:139927409432640 DEBUG: POParser: loaded 89 strings from file /storage/.kodi/addons/script.hdhomerun.view/resources/language/English/strings.po\ 16:21:32 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:21:32 T:139927409432640 DEBUG: ParentPath = []\ 16:21:32 T:139927409432640 DEBUG: POParser: loaded 89 strings from file /storage/.kodi/addons/script.hdhomerun.view/resources/language/English/strings.po\ 16:21:32 T:139927409432640 DEBUG: LIRC: Update - NEW at 34586070:200 0 KEY_NUMERIC_0_UP devinput (KEY_NUMERIC_0_UP)\ 16:21:34 T:139927409432640 DEBUG: LIRC: Update - NEW at 34587539:207 0 KEY_NUMERIC_7 devinput (KEY_NUMERIC_7)\ 16:21:34 T:139927409432640 DEBUG: OnKey: 200 (0xc8) pressed, action is JumpSMS7\ 16:21:34 T:139926097045248 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667\ 16:21:34 T:139927409432640 DEBUG: LIRC: Update - NEW at 34588040:207 0 KEY_NUMERIC_7_UP devinput (KEY_NUMERIC_7_UP)\ 16:21:35 T:139927409432640 DEBUG: LIRC: Update - NEW at 34588773:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:35 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:35 T:139927409432640 DEBUG: LIRC: Update - NEW at 34589107:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:35 T:139927409432640 DEBUG: LIRC: Update - NEW at 34589111:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:35 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:36 T:139927409432640 DEBUG: LIRC: Update - NEW at 34589507:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:36 T:139927409432640 DEBUG: LIRC: Update - NEW at 34590008:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:36 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:37 T:139927409432640 DEBUG: LIRC: Update - NEW at 34590375:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:38 T:139927409432640 DEBUG: LIRC: Update - NEW at 34591443:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:21:38 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:21:38 T:139927409432640 DEBUG: LIRC: Update - NEW at 34591844:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:21:38 T:139927409432640 DEBUG: LIRC: Update - NEW at 34592110:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:21:38 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:21:38 T:139927409432640 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-channel_entry.xml) ------\ 16:21:39 T:139927409432640 DEBUG: LIRC: Update - NEW at 34592477:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:21:39 T:139927409432640 DEBUG: LIRC: Update - NEW at 34592877:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:21:39 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:21:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34593345:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:21:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34593678:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:40 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:40 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/19787/Primary -> image/jpeg\ 16:21:40 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:40 T:139926017562368 DEBUG: CurlFile::Open(0x7f4328be9360) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/19787/Primary\ 16:21:40 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:40 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:40 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:40 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/19787/Primary' to '3/37aade6f.jpg':\ 16:21:40 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/3/37aade6f.jpg' size 400x300\ 16:21:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34594046:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:40 T:139927409432640 DEBUG: LIRC: Update - NEW at 34594212:67 0 KEY_UP devinput (KEY_UP)\ 16:21:40 T:139927409432640 DEBUG: OnKey: 166 (0xa6) pressed, action is Up\ 16:21:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34594484:67 0 KEY_UP_UP devinput (KEY_UP_UP)\ 16:21:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34594512:67 0 KEY_UP devinput (KEY_UP)\ 16:21:41 T:139927409432640 DEBUG: OnKey: 166 (0xa6) pressed, action is Up\ 16:21:41 T:139927409432640 DEBUG: LIRC: Update - NEW at 34594879:67 0 KEY_UP_UP devinput (KEY_UP_UP)\ 16:21:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34595346:67 0 KEY_UP devinput (KEY_UP)\ 16:21:42 T:139927409432640 DEBUG: OnKey: 166 (0xa6) pressed, action is Up\ 16:21:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34595813:67 0 KEY_UP_UP devinput (KEY_UP_UP)\ 16:21:42 T:139927409432640 DEBUG: LIRC: Update - NEW at 34596147:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:42 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:43 T:139927409432640 DEBUG: LIRC: Update - NEW at 34596481:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:43 T:139927409432640 DEBUG: LIRC: Update - NEW at 34596486:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:43 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:43 T:139927409432640 DEBUG: LIRC: Update - NEW at 34596714:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:43 T:139927409432640 DEBUG: LIRC: Update - NEW at 34596719:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:43 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34597415:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34597420:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:44 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/943/Logo/120x120 -> image/png\ 16:21:44 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926067918592 DEBUG: CurlFile::Open(0x7f43300124c0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/943/Logo/120x120\ 16:21:44 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/136616/Primary -> image/jpeg\ 16:21:44 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/1372/Logo/120x120 -> image/png\ 16:21:44 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285cd8c0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/136616/Primary\ 16:21:44 T:139925980976896 DEBUG: CurlFile::Open(0x7f4344294130) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/1372/Logo/120x120\ 16:21:44 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/419260/Primary -> image/jpeg\ 16:21:44 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926088652544 DEBUG: CurlFile::Open(0x7f433834e730) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/419260/Primary\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925859366656 DEBUG: Previous line repeats 1 times.\ 16:21:44 T:139925859366656 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925842581248 DEBUG: Previous line repeats 1 times.\ 16:21:44 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925859366656 INFO: Previous line repeats 1 times.\ 16:21:44 T:139925859366656 DEBUG: Thread FileCache 139925859366656 terminating\ 16:21:44 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/943/Logo/120x120' to '7/7976d782.png':\ 16:21:44 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7976d782.png' size 120x120\ 16:21:44 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:44 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/1372/Logo/120x120' to '7/7f2f56a5.png':\ 16:21:44 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7f2f56a5.png' size 120x120\ 16:21:44 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2021476/Primary -> image/jpeg\ 16:21:44 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330012440) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2021476/Primary\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:44 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/419260/Primary' to 'b/b01a8914.jpg':\ 16:21:44 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/b/b01a8914.jpg' size 400x300\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:44 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/136616/Primary' to '5/51cdd94e.jpg':\ 16:21:44 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/5/51cdd94e.jpg' size 400x300\ 16:21:44 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1174887/Primary -> image/jpeg\ 16:21:44 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139925980976896 DEBUG: CurlFile::Open(0x7f434421fec0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1174887/Primary\ 16:21:44 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34597683:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:44 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2021476/Primary' to '4/498fc65c.jpg':\ 16:21:44 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/4/498fc65c.jpg' size 400x300\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:44 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1174887/Primary' to '6/6111bf10.jpg':\ 16:21:44 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/6/6111bf10.jpg' size 400x300\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34597783:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:44 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1862/Primary -> image/jpeg\ 16:21:44 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926017562368 DEBUG: CurlFile::Open(0x7f4328af4e30) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1862/Primary\ 16:21:44 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/942/Logo/120x120 -> image/png\ 16:21:44 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926088652544 DEBUG: CurlFile::Open(0x7f433834e730) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/942/Logo/120x120\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139926679058176 DEBUG: Previous line repeats 1 times.\ 16:21:44 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925850973952 INFO: Previous line repeats 1 times.\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:44 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1862/Primary' to '4/467e879f.jpg':\ 16:21:44 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/4/467e879f.jpg' size 400x300\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:44 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/942/Logo/120x120' to '3/3e811943.png':\ 16:21:44 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/3/3e811943.png' size 120x120\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598049:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598054:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:44 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/18009/Primary -> image/jpeg\ 16:21:44 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001eb0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/18009/Primary\ 16:21:44 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33916/Logo/120x120 -> image/png\ 16:21:44 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139925980976896 DEBUG: CurlFile::Open(0x7f43441ef740) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33916/Logo/120x120\ 16:21:44 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/995848/Primary -> image/jpeg\ 16:21:44 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285d2ff0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/995848/Primary\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925850973952 DEBUG: Previous line repeats 1 times.\ 16:21:44 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598183:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:44 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:44 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33916/Logo/120x120' to 'd/d3c7e77c.png':\ 16:21:44 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/d/d3c7e77c.png' size 120x120\ 16:21:44 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598194:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:44 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:44 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/18009/Primary' to '6/69db2166.jpg':\ 16:21:44 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/6/69db2166.jpg' size 400x300\ 16:21:44 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33290/Logo/120x120 -> image/png\ 16:21:44 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926088652544 DEBUG: CurlFile::Open(0x7f433834e730) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33290/Logo/120x120\ 16:21:44 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2005663/Primary -> image/jpeg\ 16:21:44 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139925980976896 DEBUG: CurlFile::Open(0x7f43442a3a20) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2005663/Primary\ 16:21:44 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:44 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/995848/Primary' to '0/0edf7b57.jpg':\ 16:21:44 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/0/0edf7b57.jpg' size 400x300\ 16:21:44 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/448638/Primary -> image/jpeg\ 16:21:44 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:44 T:139926067918592 DEBUG: CurlFile::Open(0x7f43300103f0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/448638/Primary\ 16:21:44 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:44 T:139925850973952 DEBUG: Previous line repeats 1 times.\ 16:21:44 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:44 T:139925842581248 INFO: Previous line repeats 1 times.\ 16:21:44 T:139925842581248 DEBUG: Thread FileCache start, auto delete: false\ 16:21:45 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:45 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:45 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/448638/Primary' to '1/1e79621b.jpg':\ 16:21:45 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/1/1e79621b.jpg' size 400x300\ 16:21:45 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:45 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33290/Logo/120x120' to 'b/bc6b3f46.png':\ 16:21:45 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/b/bc6b3f46.png' size 120x120\ 16:21:45 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:45 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2005663/Primary' to 'c/c3946cd2.jpg':\ 16:21:45 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/c/c3946cd2.jpg' size 400x300\ 16:21:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598455:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598483:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:45 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:45 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/838480/Primary -> image/jpeg\ 16:21:45 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001af0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/838480/Primary\ 16:21:45 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1945788/Primary -> image/jpeg\ 16:21:45 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139925980976896 DEBUG: CurlFile::Open(0x7f4344228ce0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1945788/Primary\ 16:21:45 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33704/Logo/120x120 -> image/png\ 16:21:45 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285c9710) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33704/Logo/120x120\ 16:21:45 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:45 T:139926679058176 DEBUG: Previous line repeats 2 times.\ 16:21:45 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:45 T:139927409432640 INFO: Previous line repeats 2 times.\ 16:21:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598583:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598591:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:45 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:45 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:45 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33704/Logo/120x120' to '1/19eafd77.png':\ 16:21:45 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/1/19eafd77.png' size 120x120\ 16:21:45 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:45 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/838480/Primary' to '4/4a8f091f.jpg':\ 16:21:45 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/4/4a8f091f.jpg' size 400x300\ 16:21:45 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:45 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33692/Logo/120x120 -> image/png\ 16:21:45 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139926088652544 DEBUG: CurlFile::Open(0x7f433834e730) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33692/Logo/120x120\ 16:21:45 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1945788/Primary' to '0/02d35a30.jpg':\ 16:21:45 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/0/02d35a30.jpg' size 400x300\ 16:21:45 T:139925842581248 DEBUG: Thread FileCache start, auto delete: false\ 16:21:45 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:45 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2211314/Primary -> image/jpeg\ 16:21:45 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285c9cc0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2211314/Primary\ 16:21:45 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1051325/Primary -> image/jpeg\ 16:21:45 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:45 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001f70) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1051325/Primary\ 16:21:45 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:45 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:45 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:45 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:45 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33692/Logo/120x120' to '8/8d5a5e2c.png':\ 16:21:45 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/8/8d5a5e2c.png' size 120x120\ 16:21:45 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:45 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:45 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2211314/Primary' to 'f/f068be72.jpg':\ 16:21:45 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/f/f068be72.jpg' size 400x300\ 16:21:45 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:45 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1051325/Primary' to 'c/c4f43464.jpg':\ 16:21:45 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/c/c4f43464.jpg' size 400x300\ 16:21:45 T:139927409432640 DEBUG: LIRC: Update - NEW at 34598950:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:46 T:139927409432640 DEBUG: LIRC: Update - NEW at 34599551:205 0 KEY_NUMERIC_5 devinput (KEY_NUMERIC_5)\ 16:21:46 T:139927409432640 DEBUG: OnKey: 202 (0xca) pressed, action is JumpSMS5\ 16:21:46 T:139927409432640 DEBUG: LIRC: Update - NEW at 34600023:205 0 KEY_NUMERIC_5_UP devinput (KEY_NUMERIC_5_UP)\ 16:21:47 T:139927409432640 DEBUG: LIRC: Update - NEW at 34601152:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:47 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:47 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33699/Logo/120x120 -> image/png\ 16:21:47 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:47 T:139925980976896 DEBUG: CurlFile::Open(0x7f4344228ce0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33699/Logo/120x120\ 16:21:47 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2105382/Primary -> image/jpeg\ 16:21:47 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:47 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285cd8c0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2105382/Primary\ 16:21:47 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2111926/Primary -> image/jpeg\ 16:21:47 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:47 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001af0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2111926/Primary\ 16:21:47 T:139926679058176 DEBUG: Thread FileCache start, auto delete: false\ 16:21:47 T:139926679058176 INFO: CFileCache::Process - Hit eof.\ 16:21:47 T:139925850973952 DEBUG: Thread FileCache start, auto delete: false\ 16:21:47 T:139925850973952 DEBUG: Previous line repeats 1 times.\ 16:21:47 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:47 T:139926679058176 DEBUG: Thread FileCache 139926679058176 terminating\ 16:21:47 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33699/Logo/120x120' to '7/737e4009.png':\ 16:21:47 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/7/737e4009.png' size 120x120\ 16:21:47 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:48 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:48 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2105382/Primary' to '7/7d9f2495.jpg':\ 16:21:48 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7d9f2495.jpg' size 400x300\ 16:21:48 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:48 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/2111926/Primary' to '7/797b244a.jpg':\ 16:21:48 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/7/797b244a.jpg' size 400x300\ 16:21:48 T:139927409432640 DEBUG: LIRC: Update - NEW at 34601553:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:48 T:139927409432640 DEBUG: LIRC: Update - NEW at 34601625:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:48 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:48 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1329151/Primary -> image/jpeg\ 16:21:48 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:48 T:139925980976896 DEBUG: CurlFile::Open(0x7f43441ef740) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1329151/Primary\ 16:21:48 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/37614/Logo/120x120 -> image/png\ 16:21:48 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:48 T:139926088652544 DEBUG: CurlFile::Open(0x7f433834e730) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/37614/Logo/120x120\ 16:21:48 T:139925842581248 DEBUG: Thread FileCache start, auto delete: false\ 16:21:48 T:139925842581248 DEBUG: Previous line repeats 1 times.\ 16:21:48 T:139925842581248 INFO: CFileCache::Process - Hit eof.\ 16:21:48 T:139925850973952 INFO: Previous line repeats 1 times.\ 16:21:48 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:48 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/37614/Logo/120x120' to 'b/b41e664b.png':\ 16:21:48 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/b/b41e664b.png' size 120x120\ 16:21:48 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:48 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1329151/Primary' to '1/1dcd3ca4.jpg':\ 16:21:48 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/1/1dcd3ca4.jpg' size 400x300\ 16:21:48 T:139927409432640 DEBUG: LIRC: Update - NEW at 34602020:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:48 T:139927409432640 DEBUG: LIRC: Update - NEW at 34602120:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:48 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:48 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/920873/Primary -> image/jpeg\ 16:21:48 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:48 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001af0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/920873/Primary\ 16:21:48 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/38781/Logo/120x120 -> image/png\ 16:21:48 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:48 T:139926017562368 DEBUG: CurlFile::Open(0x7f432853cc00) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/38781/Logo/120x120\ 16:21:48 T:139925842581248 DEBUG: Thread FileCache start, auto delete: false\ 16:21:48 T:139925850973952 DEBUG: Previous line repeats 1 times.\ 16:21:48 T:139925850973952 INFO: CFileCache::Process - Hit eof.\ 16:21:48 T:139925850973952 INFO: Previous line repeats 1 times.\ 16:21:48 T:139925850973952 DEBUG: Thread FileCache 139925850973952 terminating\ 16:21:48 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/38781/Logo/120x120' to '3/31168a28.png':\ 16:21:48 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/3/31168a28.png' size 120x120\ 16:21:48 T:139925842581248 DEBUG: Thread FileCache 139925842581248 terminating\ 16:21:48 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/920873/Primary' to '6/69480eea.jpg':\ 16:21:48 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/6/69480eea.jpg' size 400x300\ 16:21:49 T:139927409432640 DEBUG: LIRC: Update - NEW at 34602621:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:49 T:139927409432640 DEBUG: LIRC: Update - NEW at 34602920:160 0 KEY_OK devinput (KEY_OK)\ 16:21:49 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:21:49 T:139926025955072 NOTICE: script.hdhomerun.view: Playing from source: 13198a55\ 16:21:49 T:139927409432640 INFO: easy_aquire - Created session to http://192.168.1.3\ 16:21:49 T:139927409432640 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v18 -> video/mpeg\ 16:21:49 T:139927409432640 DEBUG: DoWork - Saving file state for video item http://192.168.1.3:5004/auto/v2\ 16:21:49 T:139927409432640 DEBUG: DoWork - Marking video item http://192.168.1.3:5004/auto/v2 as watched\ 16:21:49 T:139927409432640 DEBUG: Loading settings for http://192.168.1.3:5004/auto/v18\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers(http://192.168.1.3:5004/auto/v18)\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv\ 16:21:49 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)\ 16:21:49 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players\ 16:21:49 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:21:49 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:21:49 T:139925909722880 NOTICE: CDVDPlayer::OnExit()\ 16:21:49 T:139925909722880 NOTICE: Closing stream player 1\ 16:21:49 T:139925909722880 NOTICE: Waiting for audio thread to exit\ 16:21:49 T:139925884544768 NOTICE: thread end: CDVDPlayerAudio::OnExit()\ 16:21:49 T:139925884544768 DEBUG: Thread DVDPlayerAudio 139925884544768 terminating\ 16:21:49 T:139925909722880 NOTICE: Closing audio device\ 16:21:49 T:139927398889216 DEBUG: CActiveAE::DiscardStream - audio stream deleted\ 16:21:49 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:49 T:139925909722880 NOTICE: Deleting audio codec\ 16:21:49 T:139925909722880 NOTICE: Closing stream player 2\ 16:21:49 T:139925909722880 NOTICE: waiting for video thread to exit\ 16:21:49 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:49 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:21:49 T:139926097045248 NOTICE: thread end: video_thread\ 16:21:49 T:139926097045248 DEBUG: Thread DVDPlayerVideo 139926097045248 terminating\ 16:21:49 T:139925909722880 NOTICE: deleting video codec\ 16:21:49 T:139925909722880 DEBUG: VAAPI::Release pre-cleanup\ 16:21:49 T:139925892937472 DEBUG: ffmpeg[7F430924B700]: [src] w:704 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:21:49 T:139925892937472 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:21:49 T:139925943351040 DEBUG: Thread FileCache 139925943351040 terminating\ 16:21:49 T:139925909722880 DEBUG: OnPlayBackStopped: play state was 2, starting 1\ 16:21:49 T:139925909722880 DEBUG: Thread DVDPlayer 139925909722880 terminating\ 16:21:49 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:21:49 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:49 T:139927409432640 NOTICE: VAAPI::Close\ 16:21:49 T:139925892937472 DEBUG: ffmpeg[7F430924B700]: [src] w:704 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:21:49 T:139925892937472 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:21:49 T:139925892937472 NOTICE: COutput::OnExit: Output Thread terminated\ 16:21:49 T:139925892937472 DEBUG: Thread Vaapi-Output 139925892937472 terminating\ 16:21:49 T:139927409432640 DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces\ 16:21:49 T:139927409432640 NOTICE: VAAPI::Close - closing decoder context\ 16:21:49 T:139927409432640 NOTICE: DVDPlayer: Opening: http://192.168.1.3:5004/auto/v18\ 16:21:49 T:139927409432640 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec\ 16:21:49 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:21:49 T:139927409432640 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16\ 16:21:49 T:139925892937472 DEBUG: Thread DVDPlayer start, auto delete: false\ 16:21:49 T:139925892937472 NOTICE: Creating InputStream\ 16:21:49 T:139925892937472 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v18 -> video/mpeg\ 16:21:49 T:139925892937472 DEBUG: CFileCache::Open - opening using cache\ 16:21:49 T:139925892937472 DEBUG: CurlFile::Open(0x7f4320866b50) http://192.168.1.3:5004/auto/v18\ 16:21:49 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1\ 16:21:49 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1"\ 16:21:49 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"\ 16:21:49 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats\ 16:21:49 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR\ 16:21:49 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)\ 16:21:49 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:21:49 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:21:49 T:139927390496512 DEBUG: Sample Rate : 44100\ 16:21:49 T:139927390496512 DEBUG: Sample Format : AE_FMT_S32NE\ 16:21:49 T:139927390496512 DEBUG: Channel Count : 2\ 16:21:49 T:139927390496512 DEBUG: Channel Layout: FL,FR\ 16:21:49 T:139927390496512 DEBUG: Frames : 2205\ 16:21:49 T:139927390496512 DEBUG: Frame Samples : 4410\ 16:21:49 T:139927390496512 DEBUG: Frame Size : 8\ 16:21:49 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:50 T:139925892937472 NOTICE: Creating Demuxer\ 16:21:50 T:139925867759360 DEBUG: Thread FileCache start, auto delete: false\ 16:21:50 T:139925892937472 DEBUG: Open - probing detected format [mpegts]\ 16:21:50 T:139925892937472 DEBUG: Open - avformat_find_stream_info starting\ 16:21:50 T:139925892937472 ERROR: ffmpeg[7F430924B700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:50 T:139927409432640 ERROR: Previous line repeats 1 times.\ 16:21:50 T:139927409432640 DEBUG: ------ Window Init (DialogBusy.xml) ------\ 16:21:50 T:139925980976896 DEBUG: DoWork - Saving file state for video item http://192.168.1.3:5004/auto/v2\ 16:21:50 T:139927409432640 DEBUG: LIRC: Update - NEW at 34603821:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:21:50 T:139925980976896 DEBUG: DoWork - Marking video item http://192.168.1.3:5004/auto/v2 as watched\ 16:21:50 T:139925892937472 ERROR: ffmpeg[7F430924B700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:21:50 T:139925892937472 ERROR: Previous line repeats 2 times.\ 16:21:50 T:139925892937472 DEBUG: ffmpeg[7F430924B700]: [mpegts] max_analyze_duration 500000 reached at 512000 microseconds st:1\ 16:21:50 T:139925892937472 DEBUG: Open - av_find_stream_info finished\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Input #0, mpegts, http://192.168.1.3:5004/auto/v1':\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Duration: N/A, start: 9902.788967, bitrate: 3754 kb/s\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Program 1612\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Stream #0:0[0x4c1]: Video: mpeg2video (Main) ([2][0][0][0] / 0x0002), yuv420p(tv), 3370 kb/s, 29.97 fps, 59.94 tbr, 90k tbn, 59.94 tbc\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Stream #0:1[0x4c4](eng): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s\ 16:21:50 T:139925892937472 INFO: ffmpeg[7F430924B700]: Stream #0:2[0x4c5](spa): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s\ 16:21:50 T:139925892937472 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:21:50 T:139925892937472 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1\ 16:21:50 T:139925892937472 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2\ 16:21:50 T:139925892937472 NOTICE: Opening stream: 0 source: 256\ 16:21:50 T:139925892937472 NOTICE: Creating video codec with codec id: 2\ 16:21:50 T:139925892937472 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Video: - Opening\ 16:21:50 T:139925892937472 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:21:50 T:139925892937472 ERROR: ffmpeg[7F430924B700]: [IMGUTILS] Picture size 0x480 is invalid\ 16:21:50 T:139925892937472 DEBUG: ffmpeg[7F430924B700]: [mpeg2video] Ignoring invalid width/height values\ 16:21:50 T:139925892937472 ERROR: ffmpeg[7F430924B700]: [IMGUTILS] Picture size 0x0 is invalid\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:21:50 T:139925892937472 NOTICE: Creating video thread\ 16:21:50 T:139925892937472 NOTICE: Opening stream: 1 source: 256\ 16:21:50 T:139925892937472 NOTICE: Finding audio codec for: 86019\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Audio: passthrough - Opening\ 16:21:50 T:139925876152064 DEBUG: Thread DVDPlayerVideo start, auto delete: false\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Audio: passthrough - Opened\ 16:21:50 T:139925892937472 NOTICE: Creating audio thread\ 16:21:50 T:139925876152064 NOTICE: running thread: video_thread\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:21:50 T:139925892937472 DEBUG: ReadEditDecisionLists - Assuming NTSC or 60i interlaced content. Adjusted frames per second from 59.940 (~59.940 fps) to 29.970\ 16:21:50 T:139926009169664 DEBUG: Thread DVDPlayerAudio start, auto delete: false\ 16:21:50 T:139926009169664 NOTICE: running thread: CDVDPlayerAudio::Process()\ 16:21:50 T:139926009169664 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:21:50 T:139925892937472 DEBUG: OnPlayBackStarted: play state was 1, starting 1\ 16:21:50 T:139925892937472 DEBUG: CDVDPlayer::SetCaching - caching state 3\ 16:21:50 T:139925892937472 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(88)\ 16:21:50 T:139925892937472 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:21:50 T:139925892937472 NOTICE: Opening stream: 0 source: 256\ 16:21:50 T:139925892937472 NOTICE: Creating video codec with codec id: 2\ 16:21:50 T:139925892937472 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Video: - Opening\ 16:21:50 T:139925892937472 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:21:50 T:139925892937472 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:21:50 T:139926009169664 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(287999.666667, 1)\ 16:21:50 T:139926009169664 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(512566.333333, 1)\ 16:21:50 T:139926009169664 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:50 T:139925876152064 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(528x480)\ 16:21:50 T:139925876152064 NOTICE: (VDPAU) Close\ 16:21:50 T:139925876152064 DEBUG: VAAPI - open decoder\ 16:21:50 T:139925876152064 DEBUG: VAAPI - initialize version 0.39\ 16:21:50 T:139925876152064 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Sandybridge Mobile - 1.7.0\ 16:21:50 T:139925876152064 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0\ 16:21:50 T:139925876152064 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32\ 16:21:50 T:139925876152064 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0\ 16:21:50 T:139925876152064 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32\ 16:21:50 T:139925876152064 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 0\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 1\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 13\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 6\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 7\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 16\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 8\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 9\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile 10\ 16:21:50 T:139925876152064 DEBUG: VAAPI - profile -1\ 16:21:50 T:139926009169664 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback\ 16:21:50 T:139926009169664 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:50 T:139925892937472 DEBUG: CDVDPlayer::HandleMessages - player started 1\ 16:21:50 T:139926097045248 DEBUG: Thread Vaapi-Output start, auto delete: false\ 16:21:50 T:139926097045248 NOTICE: COutput::OnStartup: Output Thread created\ 16:21:50 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:21:50 T:139926097045248 DEBUG: VAAPI::COutput::CreateGlxContext - created context\ 16:21:50 T:139926097045248 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created\ 16:21:50 T:139927409432640 DEBUG: PlayFile: OpenFile succeed, play state 2\ 16:21:50 T:139927409432640 DEBUG: OnPlayBackStarted: play state was 2, starting 0\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:50 T:139925876152064 DEBUG: Previous line repeats 2 times.\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.769009, Bufferlevel: 0, Gain: 0.033372\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:50 T:139925876152064 DEBUG: Previous line repeats 1 times.\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.769009, Bufferlevel: 0, Gain: 0.033361\ 16:21:50 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:50 T:139925876152064 NOTICE: fps: 59.940060, pwidth: 528, pheight: 480, dwidth: 528, dheight: 396\ 16:21:50 T:139925876152064 DEBUG: OutputPicture - change configuration. 528x480. framerate: 59.94. format: VAAPI\ 16:21:50 T:139925876152064 NOTICE: Display resolution DESKTOP : 1920x1080@ 60.00 - Full Screen (16)\ 16:21:50 T:139925876152064 DEBUG: CXBMCRenderManager::Configure - 4\ 16:21:50 T:139925876152064 DEBUG: CPullupCorrection: pattern lost on diff -19317711.000000, number of losses 1\ 16:21:51 T:139926025955072 NOTICE: script.hdhomerun.view: ON PLAYBACK STARTED\ 16:21:51 T:139927409432640 DEBUG: CGUIInfoManager::SetCurrentMovie(http://192.168.1.3:5004/auto/v18)\ 16:21:51 T:139927409432640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc\ 16:21:51 T:139927409432640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay\ 16:21:51 T:139927409432640 NOTICE: Using GL_TEXTURE_2D\ 16:21:51 T:139927409432640 NOTICE: GL: Using VAAPI render method\ 16:21:51 T:139927409432640 NOTICE: GL: NPOT texture support detected\ 16:21:51 T:139927409432640 NOTICE: GL: Using GL_ARB_pixel_buffer_object\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 1.000000, Bufferlevel: 1, Gain: 0.033372\ 16:21:51 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:21:51 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:21:51 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:21:51 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:21:51 T:139925892937472 DEBUG: CDVDPlayer::HandleMessages - player started 2\ 16:21:51 T:139925892937472 DEBUG: CDVDPlayer::SetCaching - caching state 4\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139926009169664 DEBUG: Previous line repeats 1 times.\ 16:21:51 T:139926009169664 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:51 T:139925876152064 NOTICE: Previous line repeats 1 times.\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139926009169664 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:51 T:139925876152064 NOTICE: Previous line repeats 4 times.\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.122788, Bufferlevel: 0, Gain: 0.100095\ 16:21:51 T:139925876152064 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available\ 16:21:51 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0\ 16:21:51 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.122788, Bufferlevel: 0, Gain: 0.033373\ 16:21:51 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW\ 16:21:51 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none)\ 16:21:51 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:21:51 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:21:51 T:139927390496512 DEBUG: Sample Rate : 48000\ 16:21:51 T:139927390496512 DEBUG: Sample Format : AE_FMT_S16NE\ 16:21:51 T:139927390496512 DEBUG: Channel Count : 2\ 16:21:51 T:139927390496512 DEBUG: Channel Layout: RAW,RAW\ 16:21:51 T:139927390496512 DEBUG: Frames : 2400\ 16:21:51 T:139927390496512 DEBUG: Frame Samples : 4800\ 16:21:51 T:139927390496512 DEBUG: Frame Size : 4\ 16:21:51 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:51 T:139926009169664 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:51 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:21:51 T:139926009169664 NOTICE: CDVDPlayerAudio::OutputPacket skipping a packets of duration 32\ 16:21:51 T:139926009169664 NOTICE: Previous line repeats 2 times.\ 16:21:51 T:139926009169664 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139927409432640 DEBUG: ------ Window Deinit (DialogBusy.xml) ------\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.122788, Bufferlevel: 0, Gain: 0.033362\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:21:51 T:139926009169664 DEBUG: Previous line repeats 1 times.\ 16:21:51 T:139926009169664 DEBUG: CDVDPlayerAudio::HandleSyncError - average error -5220.050990 below threshold of 50000.000000\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048813, Bufferlevel: 3, Gain: 0.033373\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048568, Bufferlevel: 3, Gain: 0.033361\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048839, Bufferlevel: 3, Gain: 0.033372\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049347, Bufferlevel: 3, Gain: 0.033362\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048649, Bufferlevel: 3, Gain: 0.033373\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049048, Bufferlevel: 3, Gain: 0.033362\ 16:21:51 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048699, Bufferlevel: 3, Gain: 0.033373\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048651, Bufferlevel: 3, Gain: 0.033361\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048731, Bufferlevel: 3, Gain: 0.033372\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048958, Bufferlevel: 3, Gain: 0.033362\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049525, Bufferlevel: 3, Gain: 0.033373\ 16:21:52 T:139926009169664 DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:1703262.579002, should be:1717245.918011, error:13983.339009\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048433, Bufferlevel: 3, Gain: 0.033362\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049429, Bufferlevel: 3, Gain: 0.033373\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.035452, Bufferlevel: 3, Gain: 0.033361\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049121, Bufferlevel: 3, Gain: 0.033372\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048492, Bufferlevel: 3, Gain: 0.033361\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049251, Bufferlevel: 3, Gain: 0.033373\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049139, Bufferlevel: 3, Gain: 0.033362\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048494, Bufferlevel: 3, Gain: 0.033373\ 16:21:52 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048518, Bufferlevel: 3, Gain: 0.033361\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048688, Bufferlevel: 3, Gain: 0.033372\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048583, Bufferlevel: 3, Gain: 0.033361\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048351, Bufferlevel: 3, Gain: 0.033373\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049214, Bufferlevel: 3, Gain: 0.033362\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048544, Bufferlevel: 3, Gain: 0.033373\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049241, Bufferlevel: 3, Gain: 0.033361\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049263, Bufferlevel: 3, Gain: 0.033372\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048632, Bufferlevel: 3, Gain: 0.033361\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048460, Bufferlevel: 3, Gain: 0.033373\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048928, Bufferlevel: 3, Gain: 0.033362\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048825, Bufferlevel: 3, Gain: 0.033372\ 16:21:53 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049158, Bufferlevel: 3, Gain: 0.033361\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048403, Bufferlevel: 3, Gain: 0.033372\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048782, Bufferlevel: 3, Gain: 0.033361\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048578, Bufferlevel: 3, Gain: 0.033373\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048985, Bufferlevel: 3, Gain: 0.033362\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048976, Bufferlevel: 3, Gain: 0.033372\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048280, Bufferlevel: 3, Gain: 0.033361\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049368, Bufferlevel: 3, Gain: 0.033372\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048859, Bufferlevel: 3, Gain: 0.033361\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049269, Bufferlevel: 3, Gain: 0.033373\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049085, Bufferlevel: 3, Gain: 0.033362\ 16:21:54 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048778, Bufferlevel: 3, Gain: 0.033372\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048876, Bufferlevel: 3, Gain: 0.033361\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049108, Bufferlevel: 3, Gain: 0.033372\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048366, Bufferlevel: 3, Gain: 0.033361\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048793, Bufferlevel: 3, Gain: 0.033373\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049007, Bufferlevel: 3, Gain: 0.033362\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048721, Bufferlevel: 3, Gain: 0.033372\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049351, Bufferlevel: 3, Gain: 0.033361\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049463, Bufferlevel: 3, Gain: 0.033372\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049196, Bufferlevel: 3, Gain: 0.033361\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049181, Bufferlevel: 3, Gain: 0.033373\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048700, Bufferlevel: 3, Gain: 0.033362\ 16:21:55 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048973, Bufferlevel: 3, Gain: 0.033372\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048831, Bufferlevel: 3, Gain: 0.033361\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048398, Bufferlevel: 3, Gain: 0.033372\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048561, Bufferlevel: 3, Gain: 0.033361\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049380, Bufferlevel: 3, Gain: 0.033373\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.048880, Bufferlevel: 3, Gain: 0.033362\ 16:21:56 T:139925876152064 DEBUG: CPullupCorrection: detected pattern of length 2: 33366.72 50049.95, frameduration: 41708.333333\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.049336, Bufferlevel: 3, Gain: 0.033373\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040727, Bufferlevel: 3, Gain: 0.033361\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040956, Bufferlevel: 3, Gain: 0.033372\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040734, Bufferlevel: 3, Gain: 0.033361\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040878, Bufferlevel: 3, Gain: 0.033373\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040922, Bufferlevel: 3, Gain: 0.033362\ 16:21:56 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040900, Bufferlevel: 3, Gain: 0.033373\ 16:21:56 T:139927409432640 DEBUG: LIRC: Update - NEW at 34610249:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:56 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040443, Bufferlevel: 3, Gain: 0.033361\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040302, Bufferlevel: 3, Gain: 0.033372\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040702, Bufferlevel: 3, Gain: 0.033361\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040061, Bufferlevel: 3, Gain: 0.033373\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040714, Bufferlevel: 3, Gain: 0.033362\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039960, Bufferlevel: 3, Gain: 0.033373\ 16:21:57 T:139927409432640 DEBUG: LIRC: Update - NEW at 34610749:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040764, Bufferlevel: 3, Gain: 0.033361\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040473, Bufferlevel: 3, Gain: 0.033372\ 16:21:57 T:139927409432640 DEBUG: LIRC: Update - NEW at 34610958:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:57 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.032772, Bufferlevel: 3, Gain: 0.033361\ 16:21:57 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33623/Logo/120x120 -> image/png\ 16:21:57 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:57 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330012da0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33623/Logo/120x120\ 16:21:57 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197076/Primary -> image/jpeg\ 16:21:57 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:57 T:139926088652544 DEBUG: CurlFile::Open(0x7f433835b110) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197076/Primary\ 16:21:57 T:139925951739648 DEBUG: Thread FileCache start, auto delete: false\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039761, Bufferlevel: 3, Gain: 0.033373\ 16:21:57 T:139925943346944 DEBUG: Thread FileCache start, auto delete: false\ 16:21:57 T:139925951739648 INFO: CFileCache::Process - Hit eof.\ 16:21:57 T:139925951739648 DEBUG: Thread FileCache 139925951739648 terminating\ 16:21:57 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33623/Logo/120x120' to '5/5b85e8ee.png':\ 16:21:57 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/5/5b85e8ee.png' size 120x120\ 16:21:57 T:139925943346944 INFO: CFileCache::Process - Hit eof.\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040568, Bufferlevel: 3, Gain: 0.033362\ 16:21:57 T:139925943346944 DEBUG: Thread FileCache 139925943346944 terminating\ 16:21:57 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/197076/Primary' to 'c/ca9ea759.jpg':\ 16:21:57 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/c/ca9ea759.jpg' size 400x300\ 16:21:57 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039794, Bufferlevel: 3, Gain: 0.033373\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040745, Bufferlevel: 3, Gain: 0.033361\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040980, Bufferlevel: 3, Gain: 0.033372\ 16:21:58 T:139927409432640 DEBUG: LIRC: Update - NEW at 34611417:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040604, Bufferlevel: 3, Gain: 0.033361\ 16:21:58 T:139927409432640 DEBUG: LIRC: Update - NEW at 34611541:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:58 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.034556, Bufferlevel: 3, Gain: 0.033373\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040181, Bufferlevel: 3, Gain: 0.033362\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039890, Bufferlevel: 3, Gain: 0.033373\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040624, Bufferlevel: 3, Gain: 0.033361\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040367, Bufferlevel: 3, Gain: 0.033372\ 16:21:58 T:139927409432640 DEBUG: LIRC: Update - NEW at 34611917:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040560, Bufferlevel: 3, Gain: 0.033361\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039799, Bufferlevel: 3, Gain: 0.033373\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040601, Bufferlevel: 3, Gain: 0.033362\ 16:21:58 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040935, Bufferlevel: 3, Gain: 0.033373\ 16:21:58 T:139927409432640 DEBUG: LIRC: Update - NEW at 34612251:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:58 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:58 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33866/Logo/120x120 -> image/png\ 16:21:58 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:21:58 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285c9830) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33866/Logo/120x120\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039832, Bufferlevel: 3, Gain: 0.033361\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache start, auto delete: false\ 16:21:59 T:139925943346944 INFO: CFileCache::Process - Hit eof.\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache 139925943346944 terminating\ 16:21:59 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33866/Logo/120x120' to 'e/e6ddc576.png':\ 16:21:59 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/e/e6ddc576.png' size 120x120\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040747, Bufferlevel: 3, Gain: 0.033372\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040244, Bufferlevel: 3, Gain: 0.033362\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039976, Bufferlevel: 3, Gain: 0.033373\ 16:21:59 T:139927409432640 DEBUG: LIRC: Update - NEW at 34612626:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040282, Bufferlevel: 3, Gain: 0.033362\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040978, Bufferlevel: 3, Gain: 0.033373\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040015, Bufferlevel: 3, Gain: 0.033361\ 16:21:59 T:139927409432640 DEBUG: LIRC: Update - NEW at 34612834:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:59 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:21:59 T:139926088652544 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1982284/Primary -> image/jpeg\ 16:21:59 T:139926088652544 DEBUG: CFileCache::Open - opening using cache\ 16:21:59 T:139926088652544 DEBUG: CurlFile::Open(0x7f43382db850) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1982284/Primary\ 16:21:59 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33292/Logo/120x120 -> image/png\ 16:21:59 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:21:59 T:139926067918592 DEBUG: CurlFile::Open(0x7f4330001eb0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33292/Logo/120x120\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040511, Bufferlevel: 3, Gain: 0.033372\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache start, auto delete: false\ 16:21:59 T:139925943346944 DEBUG: Previous line repeats 1 times.\ 16:21:59 T:139925943346944 INFO: CFileCache::Process - Hit eof.\ 16:21:59 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1437826/Primary -> image/jpeg\ 16:21:59 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:21:59 T:139925980976896 DEBUG: CurlFile::Open(0x7f4344228ce0) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1437826/Primary\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache 139925943346944 terminating\ 16:21:59 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/33292/Logo/120x120' to '3/3384a2c4.png':\ 16:21:59 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/3/3384a2c4.png' size 120x120\ 16:21:59 T:139925951739648 INFO: CFileCache::Process - Hit eof.\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache start, auto delete: false\ 16:21:59 T:139925943346944 INFO: CFileCache::Process - Hit eof.\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040109, Bufferlevel: 3, Gain: 0.033362\ 16:21:59 T:139925943346944 DEBUG: Thread FileCache 139925943346944 terminating\ 16:21:59 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1437826/Primary' to '7/7a508e5e.jpg':\ 16:21:59 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7a508e5e.jpg' size 400x223\ 16:21:59 T:139925951739648 DEBUG: Thread FileCache 139925951739648 terminating\ 16:21:59 T:139926088652544 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/1982284/Primary' to 'b/b8fa5f39.jpg':\ 16:21:59 T:139926088652544 DEBUG: cached image 'special://masterprofile/Thumbnails/b/b8fa5f39.jpg' size 267x400\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040414, Bufferlevel: 3, Gain: 0.033373\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040291, Bufferlevel: 3, Gain: 0.033362\ 16:21:59 T:139927409432640 DEBUG: LIRC: Update - NEW at 34613210:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:21:59 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039888, Bufferlevel: 3, Gain: 0.033373\ 16:21:59 T:139927409432640 DEBUG: LIRC: Update - NEW at 34613252:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:21:59 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:22:00 T:139925980976896 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/258978/Primary -> image/jpeg\ 16:22:00 T:139925980976896 DEBUG: CFileCache::Open - opening using cache\ 16:22:00 T:139925980976896 DEBUG: CurlFile::Open(0x7f43442a3a20) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/258978/Primary\ 16:22:00 T:139926067918592 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/152828/Primary -> image/jpeg\ 16:22:00 T:139926067918592 DEBUG: CFileCache::Open - opening using cache\ 16:22:00 T:139926067918592 DEBUG: CurlFile::Open(0x7f433000a940) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/152828/Primary\ 16:22:00 T:139926017562368 DEBUG: CCurlFile::GetMimeType - http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/36376/Logo/120x120 -> image/png\ 16:22:00 T:139926017562368 DEBUG: CFileCache::Open - opening using cache\ 16:22:00 T:139926017562368 DEBUG: CurlFile::Open(0x7f43285c7f50) http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/36376/Logo/120x120\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.037524, Bufferlevel: 3, Gain: 0.033361\ 16:22:00 T:139925951739648 DEBUG: Thread FileCache start, auto delete: false\ 16:22:00 T:139925943346944 DEBUG: Previous line repeats 2 times.\ 16:22:00 T:139925943346944 INFO: CFileCache::Process - Hit eof.\ 16:22:00 T:139925943346944 INFO: Previous line repeats 1 times.\ 16:22:00 T:139925943346944 DEBUG: Thread FileCache 139925943346944 terminating\ 16:22:00 T:139926017562368 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Station.svc/2/36376/Logo/120x120' to '7/7ae9f504.png':\ 16:22:00 T:139926017562368 DEBUG: cached image 'special://masterprofile/Thumbnails/7/7ae9f504.png' size 120x120\ 16:22:00 T:139925951739648 INFO: CFileCache::Process - Hit eof.\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040077, Bufferlevel: 3, Gain: 0.033372\ 16:22:00 T:139925909722880 DEBUG: Thread FileCache 139925909722880 terminating\ 16:22:00 T:139926067918592 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/152828/Primary' to 'd/d5b4e648.jpg':\ 16:22:00 T:139926067918592 DEBUG: cached image 'special://masterprofile/Thumbnails/d/d5b4e648.jpg' size 400x300\ 16:22:00 T:139925951739648 DEBUG: Thread FileCache 139925951739648 terminating\ 16:22:00 T:139925980976896 DEBUG: Caching image 'http://usnj-my.hdhomerun.com/fyimediaservices/v_3_3_6_1/Program.svc/96/258978/Primary' to '5/5f0af64c.jpg':\ 16:22:00 T:139925980976896 DEBUG: cached image 'special://masterprofile/Thumbnails/5/5f0af64c.jpg' size 400x300\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040665, Bufferlevel: 3, Gain: 0.033362\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040310, Bufferlevel: 3, Gain: 0.033373\ 16:22:00 T:139927409432640 DEBUG: LIRC: Update - NEW at 34613627:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040055, Bufferlevel: 3, Gain: 0.033362\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.040115, Bufferlevel: 3, Gain: 0.033373\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.039890, Bufferlevel: 3, Gain: 0.033361\ 16:22:00 T:139927409432640 DEBUG: LIRC: Update - NEW at 34613877:160 0 KEY_OK devinput (KEY_OK)\ 16:22:00 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:22:00 T:139926025955072 NOTICE: script.hdhomerun.view: Playing from source: 13198a55\ 16:22:00 T:139925876152064 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: 0.036153, Bufferlevel: 3, Gain: 0.033372\ 16:22:00 T:139927409432640 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v25 -> video/mpeg\ 16:22:00 T:139927409432640 DEBUG: DoWork - Saving file state for video item http://192.168.1.3:5004/auto/v18\ 16:22:00 T:139927409432640 DEBUG: DoWork - Marking video item http://192.168.1.3:5004/auto/v18 as watched\ 16:22:00 T:139927409432640 DEBUG: Loading settings for http://192.168.1.3:5004/auto/v25\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers(http://192.168.1.3:5004/auto/v25)\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv\ 16:22:00 T:139927409432640 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)\ 16:22:00 T:139927409432640 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players\ 16:22:00 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:22:00 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:22:00 T:139925892937472 NOTICE: CDVDPlayer::OnExit()\ 16:22:00 T:139925892937472 DEBUG: CDVDPlayer::SetCaching - caching state 0\ 16:22:00 T:139925892937472 NOTICE: Closing stream player 1\ 16:22:00 T:139925892937472 NOTICE: Waiting for audio thread to exit\ 16:22:00 T:139926009169664 DEBUG: CDVDPlayerAudio::Process - Abort received, exiting thread\ 16:22:00 T:139926009169664 NOTICE: thread end: CDVDPlayerAudio::OnExit()\ 16:22:00 T:139926009169664 DEBUG: Thread DVDPlayerAudio 139926009169664 terminating\ 16:22:00 T:139925892937472 NOTICE: Closing audio device\ 16:22:00 T:139925892937472 NOTICE: Deleting audio codec\ 16:22:00 T:139927398889216 DEBUG: CActiveAE::DiscardStream - audio stream deleted\ 16:22:00 T:139925892937472 NOTICE: Closing stream player 2\ 16:22:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:00 T:139925892937472 NOTICE: waiting for video thread to exit\ 16:22:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:00 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:22:00 T:139925876152064 NOTICE: thread end: video_thread\ 16:22:00 T:139925876152064 DEBUG: Thread DVDPlayerVideo 139925876152064 terminating\ 16:22:00 T:139925892937472 NOTICE: deleting video codec\ 16:22:00 T:139925892937472 DEBUG: VAAPI::Release pre-cleanup\ 16:22:00 T:139925867759360 DEBUG: Thread FileCache 139925867759360 terminating\ 16:22:00 T:139925892937472 DEBUG: OnPlayBackStopped: play state was 2, starting 1\ 16:22:00 T:139925892937472 DEBUG: Thread DVDPlayer 139925892937472 terminating\ 16:22:00 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:22:00 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:22:00 T:139927409432640 NOTICE: VAAPI::Close\ 16:22:00 T:139926097045248 NOTICE: COutput::OnExit: Output Thread terminated\ 16:22:00 T:139926097045248 DEBUG: Thread Vaapi-Output 139926097045248 terminating\ 16:22:00 T:139927409432640 DEBUG: VAAPI::FiniVAAPIOutput destroying 4 video surfaces\ 16:22:00 T:139927409432640 NOTICE: VAAPI::Close - closing decoder context\ 16:22:00 T:139927409432640 NOTICE: DVDPlayer: Opening: http://192.168.1.3:5004/auto/v25\ 16:22:00 T:139927409432640 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec\ 16:22:00 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:22:00 T:139927409432640 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16\ 16:22:00 T:139926097045248 DEBUG: Thread DVDPlayer start, auto delete: false\ 16:22:00 T:139926097045248 NOTICE: Creating InputStream\ 16:22:00 T:139926097045248 DEBUG: CCurlFile::GetMimeType - http://192.168.1.3:5004/auto/v25 -> video/mpeg\ 16:22:00 T:139926097045248 DEBUG: CFileCache::Open - opening using cache\ 16:22:00 T:139926097045248 DEBUG: CurlFile::Open(0x7f43187c61c0) http://192.168.1.3:5004/auto/v25\ 16:22:00 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1\ 16:22:00 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1"\ 16:22:00 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"\ 16:22:00 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats\ 16:22:00 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR\ 16:22:00 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)\ 16:22:00 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:22:00 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:22:00 T:139927390496512 DEBUG: Sample Rate : 44100\ 16:22:00 T:139927390496512 DEBUG: Sample Format : AE_FMT_S32NE\ 16:22:00 T:139927390496512 DEBUG: Channel Count : 2\ 16:22:00 T:139927390496512 DEBUG: Channel Layout: FL,FR\ 16:22:00 T:139927390496512 DEBUG: Frames : 2205\ 16:22:00 T:139927390496512 DEBUG: Frame Samples : 4410\ 16:22:00 T:139927390496512 DEBUG: Frame Size : 8\ 16:22:00 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:01 T:139926097045248 NOTICE: Creating Demuxer\ 16:22:01 T:139925892937472 DEBUG: Thread FileCache start, auto delete: false\ 16:22:01 T:139926097045248 DEBUG: Open - probing detected format [mpegts]\ 16:22:01 T:139926097045248 DEBUG: Open - avformat_find_stream_info starting\ 16:22:01 T:139926097045248 ERROR: ffmpeg[7F43154F2700]: [mpeg2video] Invalid frame dimensions 0x0.\ 16:22:01 T:139927409432640 ERROR: Previous line repeats 2 times.\ 16:22:01 T:139927409432640 DEBUG: ------ Window Init (DialogBusy.xml) ------\ 16:22:01 T:139926088652544 DEBUG: DoWork - Saving file state for video item http://192.168.1.3:5004/auto/v18\ 16:22:01 T:139927409432640 DEBUG: LIRC: Update - NEW at 34614785:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:22:01 T:139926088652544 DEBUG: DoWork - Marking video item http://192.168.1.3:5004/auto/v18 as watched\ 16:22:01 T:139926097045248 DEBUG: ffmpeg[7F43154F2700]: [mpegts] max_analyze_duration 500000 reached at 512000 microseconds st:1\ 16:22:01 T:139926097045248 DEBUG: Open - av_find_stream_info finished\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Input #0, mpegts, http://192.168.1.3:5004/auto/v2':\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Duration: N/A, start: 60911.207156, bitrate: 3754 kb/s\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Program 1601\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Stream #0:0[0x411]: Video: mpeg2video (Main) ([2][0][0][0] / 0x0002), yuv420p(tv), 3370 kb/s, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Stream #0:1[0x414](eng): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s\ 16:22:01 T:139926097045248 INFO: ffmpeg[7F43154F2700]: Stream #0:2[0x415](spa): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s\ 16:22:01 T:139926097045248 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:22:01 T:139926097045248 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1\ 16:22:01 T:139926097045248 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2\ 16:22:01 T:139926097045248 NOTICE: Opening stream: 0 source: 256\ 16:22:01 T:139926097045248 NOTICE: Creating video codec with codec id: 2\ 16:22:01 T:139926097045248 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Video: - Opening\ 16:22:01 T:139926097045248 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:22:01 T:139926097045248 ERROR: ffmpeg[7F43154F2700]: [IMGUTILS] Picture size 0x480 is invalid\ 16:22:01 T:139926097045248 DEBUG: ffmpeg[7F43154F2700]: [mpeg2video] Ignoring invalid width/height values\ 16:22:01 T:139926097045248 ERROR: ffmpeg[7F43154F2700]: [IMGUTILS] Picture size 0x0 is invalid\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:22:01 T:139926097045248 NOTICE: Creating video thread\ 16:22:01 T:139926097045248 NOTICE: Opening stream: 1 source: 256\ 16:22:01 T:139926097045248 NOTICE: Finding audio codec for: 86019\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Audio: passthrough - Opening\ 16:22:01 T:139925867759360 DEBUG: Thread DVDPlayerVideo start, auto delete: false\ 16:22:01 T:139925867759360 NOTICE: running thread: video_thread\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Audio: passthrough - Opened\ 16:22:01 T:139926097045248 NOTICE: Creating audio thread\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:22:01 T:139925876152064 DEBUG: Thread DVDPlayerAudio start, auto delete: false\ 16:22:01 T:139925876152064 NOTICE: running thread: CDVDPlayerAudio::Process()\ 16:22:01 T:139925876152064 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE\ 16:22:01 T:139926097045248 DEBUG: OnPlayBackStarted: play state was 1, starting 1\ 16:22:01 T:139926097045248 DEBUG: CDVDPlayer::SetCaching - caching state 3\ 16:22:01 T:139926097045248 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(88)\ 16:22:01 T:139926097045248 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0\ 16:22:01 T:139926097045248 NOTICE: Opening stream: 0 source: 256\ 16:22:01 T:139926097045248 NOTICE: Creating video codec with codec id: 2\ 16:22:01 T:139926097045248 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Video: - Opening\ 16:22:01 T:139926097045248 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video\ 16:22:01 T:139926097045248 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(185055.111113, 1)\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:22:01 T:139925867759360 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(528x480)\ 16:22:01 T:139925867759360 NOTICE: (VDPAU) Close\ 16:22:01 T:139925867759360 DEBUG: VAAPI - open decoder\ 16:22:01 T:139925876152064 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(159999.555559, 1)\ 16:22:01 T:139925876152064 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (2 channels, 48000Hz)\ 16:22:01 T:139925867759360 DEBUG: VAAPI - initialize version 0.39\ 16:22:01 T:139925867759360 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel(R) Sandybridge Mobile - 1.7.0\ 16:22:01 T:139925867759360 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0\ 16:22:01 T:139925867759360 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32\ 16:22:01 T:139925867759360 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0\ 16:22:01 T:139925867759360 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32\ 16:22:01 T:139925867759360 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 0\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 1\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 13\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 6\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 7\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 16\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 8\ 16:22:01 T:139925876152064 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 9\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile 10\ 16:22:01 T:139925867759360 DEBUG: VAAPI - profile -1\ 16:22:01 T:139925876152064 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback\ 16:22:01 T:139925876152064 NOTICE: CDVDPlayerAudio::OutputPacket duplicate 3 packets of duration 32\ 16:22:01 T:139926009169664 DEBUG: Thread Vaapi-Output start, auto delete: false\ 16:22:01 T:139926009169664 NOTICE: COutput::OnStartup: Output Thread created\ 16:22:01 T:139926009169664 DEBUG: VAAPI::COutput::CreateGlxContext - created context\ 16:22:01 T:139926009169664 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:22:01 T:139926009169664 DEBUG: Previous line repeats 3 times.\ 16:22:01 T:139926009169664 DEBUG: ffmpeg[7F4310124700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:22:01 T:139926009169664 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:22:01 T:139925867759360 NOTICE: fps: 29.970030, pwidth: 528, pheight: 480, dwidth: 528, dheight: 396\ 16:22:01 T:139925867759360 DEBUG: OutputPicture - change configuration. 528x480. framerate: 29.97. format: VAAPI_NV12\ 16:22:01 T:139925867759360 NOTICE: Display resolution DESKTOP : 1920x1080@ 60.00 - Full Screen (16)\ 16:22:01 T:139925867759360 DEBUG: CXBMCRenderManager::Configure - 4\ 16:22:01 T:139925867759360 DEBUG: CPullupCorrection: pattern lost on diff -9903744.000000, number of losses 1\ 16:22:01 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:22:01 T:139927409432640 DEBUG: PlayFile: OpenFile succeed, play state 2\ 16:22:01 T:139927409432640 DEBUG: OnPlayBackStarted: play state was 2, starting 0\ 16:22:01 T:139927409432640 NOTICE: Using GL_TEXTURE_2D\ 16:22:01 T:139927409432640 DEBUG: GL: Requested render method: 0\ 16:22:01 T:139927409432640 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:\ #define XBMC_texture_rectangle 0\ #define XBMC_texture_rectangle_hack 0\ #define XBMC_STRETCH 0\ #define XBMC_NV12\ 16:22:01 T:139927409432640 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader\ 16:22:01 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:22:01 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:22:01 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:22:01 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:22:01 T:139927409432640 DEBUG: GL: Vertex Shader compilation log:\ 16:22:01 T:139927409432640 DEBUG: GL: Vertex Shader compiled successfully\ 16:22:01 T:139927409432640 DEBUG: GL: Pixel Shader compilation log:\ 16:22:01 T:139927409432640 DEBUG: GL: Fragment Shader compiled successfully\ 16:22:01 T:139927409432640 NOTICE: GL: NPOT texture support detected\ 16:22:01 T:139927409432640 NOTICE: GL: Using GL_ARB_pixel_buffer_object\ 16:22:01 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:22:01 T:139927409432640 DEBUG: CGUIInfoManager::SetCurrentMovie(http://192.168.1.3:5004/auto/v25)\ 16:22:01 T:139926097045248 DEBUG: CDVDPlayer::HandleMessages - player started 2\ 16:22:01 T:139927409432640 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc\ 16:22:01 T:139927409432640 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay\ 16:22:01 T:139926025955072 NOTICE: script.hdhomerun.view: ON PLAYBACK STARTED\ 16:22:02 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0\ 16:22:02 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"\ 16:22:02 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x06,AES1=0x82,AES2=0x00,AES3=0x02"\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: RAW,RAW\ 16:22:02 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: RAW,RAW (ALSA: none)\ 16:22:02 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:22:02 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:22:02 T:139927390496512 DEBUG: Sample Rate : 48000\ 16:22:02 T:139927390496512 DEBUG: Sample Format : AE_FMT_S16NE\ 16:22:02 T:139927390496512 DEBUG: Channel Count : 2\ 16:22:02 T:139927390496512 DEBUG: Channel Layout: RAW,RAW\ 16:22:02 T:139927390496512 DEBUG: Frames : 2400\ 16:22:02 T:139927390496512 DEBUG: Frame Samples : 4800\ 16:22:02 T:139927390496512 DEBUG: Frame Size : 4\ 16:22:02 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:02 T:139926097045248 DEBUG: Previous line repeats 1 times.\ 16:22:02 T:139926097045248 DEBUG: CDVDPlayer::HandleMessages - player started 1\ 16:22:02 T:139926097045248 DEBUG: CDVDPlayer::SetCaching - caching state 0\ 16:22:02 T:139925876152064 NOTICE: CDVDPlayerAudio::OutputPacket duplicate 3 packets of duration 32\ 16:22:02 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0\ 16:22:02 T:139925867759360 DEBUG: Previous line repeats 3 times.\ 16:22:02 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1\ 16:22:02 T:139925867759360 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -0.130131, Bufferlevel: 1, Gain: 0.066733\ 16:22:02 T:139927409432640 DEBUG: ------ Window Deinit (DialogBusy.xml) ------\ 16:22:02 T:139925876152064 DEBUG: CDVDPlayerAudio::HandleSyncError - average error 9928.782100 below threshold of 50000.000000\ 16:22:05 T:139925876152064 DEBUG: CDVDClock::Discontinuity - CDVDPlayerAudio::HandleSyncError2 - was:2996347.386559, should be:2985445.805433, error:-10901.581126\ 16:22:06 T:139925867759360 DEBUG: CPullupCorrection: detected pattern of length 1: 33366.67, frameduration: 33366.666667\ 16:22:07 T:139927409432640 DEBUG: LIRC: Update - NEW at 34620311:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:22:07 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:22:07 T:139927409432640 DEBUG: LIRC: Update - NEW at 34620646:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:22:07 T:139927409432640 DEBUG: LIRC: Update - NEW at 34620652:6c 0 KEY_DOWN devinput (KEY_DOWN)\ 16:22:07 T:139927409432640 DEBUG: OnKey: 167 (0xa7) pressed, action is Down\ 16:22:07 T:139927409432640 DEBUG: LIRC: Update - NEW at 34621046:6c 0 KEY_DOWN_UP devinput (KEY_DOWN_UP)\ 16:22:08 T:139927409432640 DEBUG: LIRC: Update - NEW at 34622213:80 0 KEY_STOP devinput (KEY_STOP)\ 16:22:08 T:139927409432640 DEBUG: OnKey: guide (0xe0) pressed, action is Stop\ 16:22:08 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:22:08 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:22:08 T:139926097045248 NOTICE: CDVDPlayer::OnExit()\ 16:22:08 T:139926097045248 NOTICE: Closing stream player 1\ 16:22:08 T:139926097045248 NOTICE: Waiting for audio thread to exit\ 16:22:08 T:139925876152064 NOTICE: thread end: CDVDPlayerAudio::OnExit()\ 16:22:08 T:139926097045248 NOTICE: Closing audio device\ 16:22:08 T:139925876152064 DEBUG: Thread DVDPlayerAudio 139925876152064 terminating\ 16:22:09 T:139927398889216 DEBUG: CActiveAE::DiscardStream - audio stream deleted\ 16:22:09 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:09 T:139926097045248 NOTICE: Deleting audio codec\ 16:22:09 T:139926097045248 NOTICE: Closing stream player 2\ 16:22:09 T:139926097045248 NOTICE: waiting for video thread to exit\ 16:22:09 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:09 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:22:09 T:139925867759360 NOTICE: thread end: video_thread\ 16:22:09 T:139925867759360 DEBUG: Thread DVDPlayerVideo 139925867759360 terminating\ 16:22:09 T:139926097045248 NOTICE: deleting video codec\ 16:22:09 T:139926097045248 DEBUG: VAAPI::Release pre-cleanup\ 16:22:09 T:139926009169664 DEBUG: ffmpeg[7F4310124700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:22:09 T:139926009169664 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:22:09 T:139925892937472 ERROR: CFileCache::Process - Error 0 seeking. Seek returned -1\ 16:22:09 T:139925892937472 DEBUG: Thread FileCache 139925892937472 terminating\ 16:22:09 T:139926097045248 DEBUG: OnPlayBackStopped: play state was 2, starting 0\ 16:22:09 T:139926097045248 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc\ 16:22:09 T:139926097045248 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop\ 16:22:09 T:139926097045248 DEBUG: Thread DVDPlayer 139926097045248 terminating\ 16:22:09 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:22:09 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:22:09 T:139927409432640 NOTICE: VAAPI::Close\ 16:22:09 T:139926009169664 DEBUG: ffmpeg[7F4310124700]: [src] w:528 h:480 pixfmt:nv12 tb:1/1 fr:0/1 sar:40/33 sws_param:\ 16:22:09 T:139926009169664 DEBUG: CFFmpegPostproc::Init - skip deinterlacing\ 16:22:09 T:139926009169664 NOTICE: COutput::OnExit: Output Thread terminated\ 16:22:09 T:139926009169664 DEBUG: Thread Vaapi-Output 139926009169664 terminating\ 16:22:09 T:139927409432640 DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces\ 16:22:09 T:139927409432640 NOTICE: VAAPI::Close - closing decoder context\ 16:22:09 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:22:09 T:139927409432640 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------\ 16:22:09 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:22:09 T:139927409432640 DEBUG: ------ Window Init (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-background.xml) ------\ 16:22:09 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:22:09 T:139927409432640 DEBUG: ParentPath = []\ 16:22:09 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:22:09 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:22:09 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:22:09 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:22:09 T:139927409432640 NOTICE: CDVDPlayer::CloseFile()\ 16:22:09 T:139927409432640 NOTICE: DVDPlayer: waiting for threads to exit\ 16:22:09 T:139927409432640 NOTICE: DVDPlayer: finished waiting\ 16:22:09 T:139927409432640 DEBUG: LinuxRendererGL: Cleaning up GL resources\ 16:22:09 T:139927409432640 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData\ 16:22:09 T:139926025955072 NOTICE: script.hdhomerun.view: ON PLAYBACK STOPPED\ 16:22:09 T:139926025955072 WARNING: CSkinInfo: failed to load skin settings\ 16:22:09 T:139927409432640 DEBUG: ------ Window Init (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-options.xml) ------\ 16:22:09 T:139927409432640 INFO: Loading skin file: /storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-options.xml, load type: LOAD_ON_GUI_INIT\ 16:22:09 T:139927409432640 DEBUG: POParser: loaded 89 strings from file /storage/.kodi/addons/script.hdhomerun.view/resources/language/English/strings.po\ 16:22:09 T:139927409432640 DEBUG: Previous line repeats 3 times.\ 16:22:09 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:22:09 T:139927409432640 DEBUG: ParentPath = []\ 16:22:09 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1\ 16:22:09 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1"\ 16:22:09 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"\ 16:22:09 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats\ 16:22:09 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR\ 16:22:09 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)\ 16:22:09 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:22:09 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:22:09 T:139927390496512 DEBUG: Sample Rate : 44100\ 16:22:09 T:139927390496512 DEBUG: Sample Format : AE_FMT_S32NE\ 16:22:09 T:139927390496512 DEBUG: Channel Count : 2\ 16:22:09 T:139927390496512 DEBUG: Channel Layout: FL,FR\ 16:22:09 T:139927390496512 DEBUG: Frames : 2205\ 16:22:09 T:139927390496512 DEBUG: Frame Samples : 4410\ 16:22:09 T:139927390496512 DEBUG: Frame Size : 8\ 16:22:09 T:139926017562368 DEBUG: DoWork - Saving file state for video item http://192.168.1.3:5004/auto/v25\ 16:22:09 T:139926017562368 DEBUG: DoWork - Marking video item http://192.168.1.3:5004/auto/v25 as watched\ 16:22:09 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:22:09 T:139927409432640 DEBUG: LIRC: Update - NEW at 34622583:80 0 KEY_STOP_UP devinput (KEY_STOP_UP)\ 16:22:10 T:139927409432640 DEBUG: LIRC: Update - NEW at 34623641:6a 0 KEY_RIGHT devinput (KEY_RIGHT)\ 16:22:10 T:139927409432640 DEBUG: OnKey: 168 (0xa8) pressed, action is Right\ 16:22:10 T:139927409432640 DEBUG: LIRC: Update - NEW at 34624141:6a 0 KEY_RIGHT_UP devinput (KEY_RIGHT_UP)\ 16:22:10 T:139927409432640 DEBUG: LIRC: Update - NEW at 34624181:160 0 KEY_OK devinput (KEY_OK)\ 16:22:10 T:139927409432640 DEBUG: OnKey: 11 (0x0b) pressed, action is Select\ 16:22:10 T:139927409432640 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-options.xml) ------\ 16:22:10 T:139927409432640 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-overlay.xml) ------\ 16:22:10 T:139926025955072 NOTICE: script.hdhomerun.view: Shutting down...\ 16:22:10 T:139926025955072 NOTICE: script.hdhomerun.view: Overlay timer done\ 16:22:10 T:139926025955072 NOTICE: script.hdhomerun.view: Details timer done\ 16:22:10 T:139925901330176 NOTICE: script.hdhomerun.view: Cron stopped\ 16:22:10 T:139927409432640 DEBUG: Activating window ID: 10040\ 16:22:10 T:139927409432640 DEBUG: ------ Window Deinit (/storage/.kodi/addons/script.hdhomerun.view/resources/skins/Main/1080i/script-hdhomerun-view-background.xml) ------\ 16:22:10 T:139927409432640 DEBUG: ------ Window Init (AddonBrowser.xml) ------\ 16:22:10 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (addons://user/xbmc.addon.video)\ 16:22:10 T:139927409432640 DEBUG: ParentPath = [addons://user/xbmc.addon.video]\ 16:22:10 T:139925901330176 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:22:10 T:139925901330176 DEBUG: Thread BackgroundLoader 139925901330176 terminating\ 16:22:11 T:139926025955072 NOTICE: script.hdhomerun.view: Finished\ 16:22:11 T:139926025955072 INFO: CPythonInvoker(4, /storage/.kodi/addons/script.hdhomerun.view/default.py): script successfully run\ 16:22:11 T:139926025955072 INFO: Python script stopped\ 16:22:11 T:139926025955072 DEBUG: Thread LanguageInvoker 139926025955072 terminating\ 16:22:11 T:139927409432640 DEBUG: LIRC: Update - NEW at 34624568:160 0 KEY_OK_UP devinput (KEY_OK_UP)\ 16:22:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34625473:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:12 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:22:12 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory (addons://user/)\ 16:22:12 T:139927409432640 DEBUG: ParentPath = []\ 16:22:12 T:139926025955072 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:22:12 T:139926025955072 DEBUG: Thread BackgroundLoader 139926025955072 terminating\ 16:22:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34625835:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:12 T:139927409432640 DEBUG: LIRC: Update - NEW at 34626242:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:12 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:22:12 T:139927409432640 DEBUG: CGUIMediaWindow::GetDirectory ()\ 16:22:12 T:139927409432640 DEBUG: ParentPath = []\ 16:22:12 T:139926025955072 DEBUG: Thread BackgroundLoader start, auto delete: false\ 16:22:12 T:139926025955072 DEBUG: Thread BackgroundLoader 139926025955072 terminating\ 16:22:13 T:139927409432640 DEBUG: LIRC: Update - NEW at 34626635:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:13 T:139927409432640 DEBUG: LIRC: Update - NEW at 34626868:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:13 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:22:13 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:22:13 T:139927409432640 DEBUG: ------ Window Deinit (AddonBrowser.xml) ------\ 16:22:13 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:22:13 T:139927409432640 DEBUG: ------ Window Init (Settings.xml) ------\ 16:22:13 T:139927409432640 DEBUG: LIRC: Update - NEW at 34627267:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:14 T:139927409432640 DEBUG: LIRC: Update - NEW at 34628077:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:14 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu\ 16:22:14 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:22:15 T:139927409432640 DEBUG: ------ Window Deinit (Settings.xml) ------\ 16:22:15 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Activate new\ 16:22:15 T:139927409432640 DEBUG: ------ Window Init (Home.xml) ------\ 16:22:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34628434:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:15 T:139927409432640 INFO: CheckIdle - Closing session to http://usnj-my.hdhomerun.com (easy=0x7f43442568c0, multi=0x7f4344294800)\ 16:22:15 T:139927409432640 DEBUG: LIRC: Update - NEW at 34629058:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:15 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:22:15 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:22:15 T:139927409432640 ERROR: Unable to activate the previous window\ 16:22:15 T:139927409432640 DEBUG: Activating window ID: 10000\ 16:22:15 T:139927409432640 DEBUG: ------ Window Init (Home.xml) ------\ 16:22:16 T:139927409432640 DEBUG: LIRC: Update - NEW at 34629447:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:16 T:139927409432640 DEBUG: LIRC: Update - NEW at 34629751:ae 0 KEY_EXIT devinput (KEY_EXIT)\ 16:22:16 T:139927409432640 DEBUG: OnKey: menu (0xd8) pressed, action is Back\ 16:22:16 T:139927409432640 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate\ 16:22:16 T:139927409432640 DEBUG: LIRC: Update - NEW at 34630141:ae 0 KEY_EXIT_UP devinput (KEY_EXIT_UP)\ 16:22:19 T:139927409432640 DEBUG: NotifyXRREvent - notify display reset event\ 16:22:19 T:139927409432640 ERROR: WinSystemX11::RefreshWindow - failed to query xrandr\ 16:22:30 T:139927409432640 INFO: CheckIdle - Closing session to http://usnj-my.hdhomerun.com (easy=0x7f4330ff0810, multi=0x7f43300069c0)\ 16:22:30 T:139927409432640 INFO: CheckIdle - Closing session to http://usnj-my.hdhomerun.com (easy=0x7f432839c1a0, multi=0x7f4328badae0)\ 16:22:30 T:139927409432640 INFO: CheckIdle - Closing session to http://usnj-my.hdhomerun.com (easy=0x7f4338205a40, multi=0x7f433832b650)\ 16:22:30 T:139927409432640 INFO: CheckIdle - Closing session to http://192.168.1.3 (easy=0x3303c00, multi=(nil))\ 16:22:39 T:139927409432640 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)\ 16:22:39 T:139927409432640 INFO: CheckIdle - Closing session to http://192.168.1.3 (easy=0x329b360, multi=0x7f4320868490)\ 16:22:41 T:139927409432640 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:22:43 T:139926067918592 DEBUG: Thread JobWorker 139926067918592 terminating (autodelete)\ 16:22:43 T:139925980976896 DEBUG: Thread JobWorker 139925980976896 terminating (autodelete)\ 16:22:43 T:139926017562368 DEBUG: Thread JobWorker 139926017562368 terminating (autodelete)\ 16:22:43 T:139926088652544 DEBUG: Thread JobWorker 139926088652544 terminating (autodelete)\ 16:24:06 T:139927409432640 DEBUG: NotifyXRREvent - notify display reset event\ 16:24:06 T:139927409432640 INFO: Available videomodes (xrandr):\ 16:24:06 T:139927409432640 INFO: Output 'HDMI2' has 13 modes\ 16:24:06 T:139927409432640 INFO: ID:0x49 Name:1920x1080i Refresh:30.000000 Width:1920 Height:1080\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd2 Name:1920x1080i Refresh:29.970100 Width:1920 Height:1080\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd0 Name:1920x1080 Refresh:60.000000 Width:1920 Height:1080\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd1 Name:1920x1080 Refresh:59.940201 Width:1920 Height:1080\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd3 Name:1280x720 Refresh:60.000000 Width:1280 Height:720\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd4 Name:1280x720 Refresh:59.940201 Width:1280 Height:720\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.000086\ 16:24:06 T:139927409432640 INFO: ID:0xd5 Name:1440x480i Refresh:29.970030 Width:1440 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 0.592644\ 16:24:06 T:139927409432640 INFO: ID:0xd6 Name:720x480 Refresh:60.000000 Width:720 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.185287\ 16:24:06 T:139927409432640 INFO: ID:0xd7 Name:720x480 Refresh:59.940060 Width:720 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.185287\ 16:24:06 T:139927409432640 INFO: ID:0xd8 Name:720x480i Refresh:30.001110 Width:720 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.185287\ 16:24:06 T:139927409432640 INFO: ID:0xd9 Name:720x480i Refresh:29.970030 Width:720 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.185287\ 16:24:06 T:139927409432640 INFO: ID:0xda Name:640x480 Refresh:60.000000 Width:640 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.333448\ 16:24:06 T:139927409432640 INFO: ID:0xdb Name:640x480 Refresh:59.940479 Width:640 Height:480\ 16:24:06 T:139927409432640 INFO: Pixel Ratio: 1.333448\ 16:24:06 T:139927409432640 DEBUG: RecreateWindow - current output: HDMI2, mode: 0xd0, refresh: 60.000\ 16:24:06 T:139927409432640 DEBUG: OnLostDevice - notify display change event\ 16:24:06 T:139927409432640 DEBUG: Flush - flushing renderer\ 16:24:06 T:139927409432640 DEBUG: Window Manager Name: Fluxbox\ 16:24:06 T:139927409432640 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:24:06 T:139927409432640 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-x86_64-linux.so\ 16:24:06 T:139927409432640 DEBUG: CWinSystemX11::RefreshGlxContext: refreshing context\ 16:24:06 T:139927398889216 DEBUG: CActiveAE - display reset event\ 16:24:06 T:139927390496512 INFO: CActiveAESink::OpenSink - initialize sink\ 16:24:06 T:139927390496512 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=1\ 16:24:06 T:139927390496512 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=1"\ 16:24:06 T:139927390496512 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=1,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"\ 16:24:06 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats\ 16:24:06 T:139927390496512 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820\ 16:24:06 T:139927409432640 INFO: GL: Maximum texture width: 8192\ 16:24:06 T:139927409432640 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR\ 16:24:06 T:139927390496512 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR)\ 16:24:06 T:139927390496512 DEBUG: CActiveAESink::OpenSink - ALSA Initialized:\ 16:24:06 T:139927390496512 DEBUG: Output Device : HDA Intel PCH\ 16:24:06 T:139927390496512 DEBUG: Sample Rate : 44100\ 16:24:06 T:139927390496512 DEBUG: Sample Format : AE_FMT_S32NE\ 16:24:06 T:139927390496512 DEBUG: Channel Count : 2\ 16:24:06 T:139927390496512 DEBUG: Channel Layout: FL,FR\ 16:24:06 T:139927390496512 DEBUG: Frames : 2205\ 16:24:06 T:139927390496512 DEBUG: Frame Samples : 4410\ 16:24:06 T:139927390496512 DEBUG: Frame Size : 8\ 16:24:06 T:139927398889216 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted\ 16:24:36 T:139927409432640 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so)\ 16:25:16 T:139927409432640 DEBUG: CAnnouncementManager - Announcement: OnScreensaverActivated from xbmc\ 16:25:16 T:139927409432640 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnScreensaverActivated\ 16:25:16 T:139927409432640 DEBUG: ------ Window Init () ------\ 16:25:16 T:139927048652544 DEBUG: service.libraryautoupdate-0.9.8 : screen saver on\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '500 Barker 6 Quantu' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '500 Barker 6 Quantu' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '997 Barker 5-Mercur' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '131 Barker 3 - FIOS' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '997 Barker 5-Mercur' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926042740480 DEBUG: EPG - UpdateFromScraper - updating EPG for channel '131 Barker 3 - FIOS' from client '935'\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents\ 16:26:00 T:139926042740480 DEBUG: EPGContainer - UpdateEpgEvents - 0 item(s) updated\ 16:26:00 T:139926790629120 DEBUG: CPVRTimers - Update - updating timers\ \ }