Login at Kodi Home

albertbope · 2014-01-29, 15:24

I've got XBMC 12.3 in a Mac Mini from 2011 with Snow Leopard

When I play a mkv stops at 1:05, don t know why

This is the log I got

It s seems to me it's about audio or something, not sure

Much apreciate if you could help me guys

21:56:51 T:2684835136 NOTICE: -----------------------------------------------------------------------
21:56:51 T:2684835136 NOTICE: Starting XBMC (12.3 Git:20131212-9ed3e58), Platform: Darwin OSX (10.8.0 i386, Version 10.6.8 (Build 10K549)). Built on Dec 23 2013
21:56:51 T:2684835136 NOTICE: special://xbmc/ is mapped to: /Applications/XBMC.app/Contents/Resources/XBMC
21:56:51 T:2684835136 NOTICE: special://xbmcbin/ is mapped to: /Applications/XBMC.app/Contents/Resources/XBMC
21:56:51 T:2684835136 NOTICE: special://masterprofile/ is mapped to: /Users/albertperez/Library/Application Support/XBMC/userdata
21:56:51 T:2684835136 NOTICE: special://home/ is mapped to: /Users/albertperez/Library/Application Support/XBMC
21:56:51 T:2684835136 NOTICE: special://temp/ is mapped to: /Users/albertperez/.xbmc/temp
21:56:51 T:2684835136 NOTICE: special://frameworks/ is mapped to: /Applications/XBMC.app/Contents/Frameworks
21:56:51 T:2684835136 NOTICE: The executable running is: /Applications/XBMC.app/Contents/MacOS/XBMC
21:56:51 T:2684835136 NOTICE: Local hostname: Mac-mini-de-Albert-Perez.local
21:56:51 T:2684835136 NOTICE: Log File is located: /Users/albertperez/Library/Logs/xbmc.log
21:56:51 T:2684835136 NOTICE: -----------------------------------------------------------------------
21:56:51 T:2684835136 NOTICE: load settings...
21:56:51 T:2684835136 ERROR: Unable to load libcrystalhd.dylib, reason: dlopen(libcrystalhd.dylib, 1): image not found
21:56:51 T:2684835136 NOTICE: special://profile/ is mapped to: special://masterprofile/
21:56:51 T:2684835136 NOTICE: loading special://masterprofile/guisettings.xml
21:56:51 T:2684835136 NOTICE: Getting hardware information now...
21:56:51 T:2684835136 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
21:56:51 T:2684835136 NOTICE: Loaded playercorefactory configuration
21:56:51 T:2684835136 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
21:56:51 T:2684835136 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
21:56:51 T:2684835136 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
21:56:51 T:2684835136 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
21:56:51 T:2684835136 NOTICE: Default DVD Player: dvdplayer
21:56:51 T:2684835136 NOTICE: Default Video Player: dvdplayer
21:56:51 T:2684835136 NOTICE: Default Audio Player: paplayer
21:56:51 T:2684835136 NOTICE: Disabled debug logging due to GUI setting. Level 0.
21:56:51 T:2684835136 NOTICE: Log level changed to 0
21:56:51 T:2684835136 NOTICE: Loading media sources from special://masterprofile/sources.xml
21:56:51 T:2684835136 ERROR: CCoreAudioDevice::SupportsMixing: Device mixing support : 'No'.
21:56:52 T:2684835136 NOTICE: Running database version Addons15
21:56:54 T:2956550144 NOTICE: Thread XBMC Peripherals start, auto delete: false
21:56:54 T:2684835136 NOTICE: Setup SDL
21:56:55 T:2684835136 NOTICE: Display 0 has name Panasonic-TV
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1920 x 1080 @ 50.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1920 x 1080 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1920 x 1080 @ 24.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1280 x 720 @ 50.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1280 x 720 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 720 x 576 @ 50.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 720 x 480 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 640 x 480 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 800 x 600 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1024 x 768 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1024 x 576 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1280 x 960 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1344 x 1008 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1344 x 756 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Found possible resolution for display 0 with 1600 x 900 @ 60.000000 Hz
21:56:55 T:2684835136 NOTICE: Checking resolution 16
21:56:55 T:2684835136 NOTICE: GL_VENDOR = NVIDIA Corporation
21:56:55 T:2684835136 NOTICE: GL_RENDERER = NVIDIA GeForce 320M OpenGL Engine
21:56:55 T:2684835136 NOTICE: GL_VERSION = 2.1 NVIDIA-1.6.36
21:56:55 T:2684835136 NOTICE: GL_SHADING_LANGUAGE_VERSION = 1.20
21:56:55 T:2684835136 NOTICE: GL_EXTENSIONS = GL_ARB_transpose_matrix GL_ARB_vertex_program GL_ARB_vertex_blend GL_ARB_window_pos GL_ARB_shader_objects GL_ARB_vertex_shader GL_ARB_shading_language_100 GL_EXT_multi_draw_arrays GL_EXT_clip_volume_hint GL_EXT_rescale_normal GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_gpu_program_parameters GL_EXT_geometry_shader4 GL_EXT_transform_feedback GL_APPLE_client_storage GL_APPLE_specular_vector GL_APPLE_transform_hint GL_APPLE_packed_pixels GL_APPLE_fence GL_APPLE_vertex_array_object GL_APPLE_vertex_program_evaluators GL_APPLE_element_array GL_APPLE_flush_render GL_APPLE_aux_depth_stencil GL_NV_texgen_reflection GL_NV_light_max_exponent GL_IBM_rasterpos_clip GL_SGIS_generate_mipmap GL_ARB_imaging GL_ARB_point_parameters GL_ARB_texture_env_crossbar GL_ARB_texture_border_clamp GL_ARB_multitexture GL_ARB_texture_env_add GL_ARB_texture_cube_map GL_ARB_texture_env_dot3 GL_ARB_multisample GL_ARB_texture_env_combine GL_ARB_texture_compression GL_ARB_texture_mirrored_repeat GL_ARB_shadow GL_ARB_depth_texture GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_occlusion_query GL_ARB_point_sprite GL_ARB_texture_non_power_of_two GL_ARB_vertex_buffer_object GL_ARB_pixel_buffer_object GL_ARB_draw_buffers GL_ARB_shader_texture_lod GL_ARB_color_buffer_float GL_ARB_half_float_vertex GL_ARB_texture_rg GL_ARB_texture_compression_rgtc GL_ARB_framebuffer_object GL_EXT_compiled_vertex_array GL_EXT_draw_buffers2 GL_EXT_framebuffer_object GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXT_texture_rectangle GL_ARB_texture_rectangle GL_EXT_texture_env_add GL_EXT_blend_color GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_texture_lod_bias GL_EXT_abgr GL_EXT_bgra GL_EXT_stencil_wrap GL_EXT_texture_filter_anisotropic GL_EXT_secondary_color GL_EXT_blend_func_separate GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_depth_bounds_test GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1 GL_EXT_texture_sRGB GL_EXT_blend_equation_separate GL_EXT_texture_mirror_clamp GL_EXT_packed_depth_stencil GL_EXT_bindable_uniform GL_EXT_texture_integer GL_EXT_gpu_shader4 GL_EXT_framebuffer_sRGB GL_EXT_provoking_vertex GL_APPLE_flush_buffer_range GL_APPLE_ycbcr_422 GL_APPLE_rgb_422 GL_APPLE_vertex_array_range GL_APPLE_texture_range GL_APPLE_float_pixels GL_ATI_texture_float GL_ARB_texture_float GL_ARB_half_float_pixel GL_APPLE_pixel_buffer GL_APPLE_object_purgeable GL_NV_point_sprite GL_NV_blend_square GL_NV_fog_distance GL_NV_depth_clamp GL_NV_multisample_filter_hint GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NV_conditional_render GL_ATI_texture_mirror_once GL_ATI_texture_env_combine3 GL_ATI_separate_stencil GL_SGIS_texture_edge_clamp GL_SGIS_texture_lod GL_EXT_texture_array GL_EXT_vertex_array_bgra GL_ARB_instanced_arrays GL_ARB_depth_buffer_float GL_EXT_packed_float GL_EXT_texture_shared_exponent
21:56:57 T:2684835136 NOTICE: Running database version Addons15
21:56:57 T:2684835136 NOTICE: Running database version ViewModes4
21:56:57 T:2684835136 NOTICE: Running database version Textures13
21:56:57 T:2684835136 NOTICE: Running database version MyMusic32
21:56:57 T:2684835136 NOTICE: Running database version MyVideos75
21:56:57 T:2684835136 NOTICE: Running database version TV22
21:56:57 T:2684835136 NOTICE: Running database version Epg7
21:56:57 T:2684835136 NOTICE: start dvd mediatype detection
21:56:57 T:2684835136 NOTICE: initializing playlistplayer
21:56:57 T:2684835136 NOTICE: DONE initializing playlistplayer
21:56:57 T:2953961472 NOTICE: Thread CDetectDVDMedia start, auto delete: false
21:56:57 T:2953961472 WARNING: Filesystem is not supported
21:56:59 T:2957082624 NOTICE: Thread XBPyThread start, auto delete: false
21:56:59 T:2957082624 NOTICE: Previous line repeats 1 times.
21:56:59 T:2957082624 NOTICE: -->Python Interpreter Initialized<--
21:56:59 T:2957615104 NOTICE: -->Python Interpreter Initialized<--
21:56:59 T:2684835136 NOTICE: initialize done
21:56:59 T:2958147584 NOTICE: Thread XBPyThread start, auto delete: false
21:56:59 T:2684835136 NOTICE: Running the application...
21:57:00 T:2958680064 NOTICE: Thread Jobworker start, auto delete: true
21:57:06 T:2957082624 NOTICE: service.libraryautoupdate: Update Library Service starting...
21:57:06 T:2957082624 NOTICE: service.libraryautoupdate: update timers
21:57:06 T:2958147584 NOTICE: -->Python Interpreter Initialized<--
21:57:08 T:2684835136 NOTICE: ES: Starting event server
21:57:08 T:2684835136 NOTICE: starting zeroconf publishing
21:57:08 T:2959745024 NOTICE: Thread Jobworker start, auto delete: true
21:57:08 T:2959212544 NOTICE: Thread CTCPServer start, auto delete: false
21:57:08 T:2953850880 NOTICE: Thread CEventServer start, auto delete: false
21:57:08 T:2953850880 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
21:57:08 T:2953850880 NOTICE: UDP: Listening on port 9777
21:57:08 T:2960277504 NOTICE: Thread Jobworker start, auto delete: true
21:57:08 T:2954104832 NOTICE: Thread CRssReader start, auto delete: false
21:57:19 T:2956017664 NOTICE: Thread Background Loader start, auto delete: false
21:58:20 T:2960809984 NOTICE: Previous line repeats 5 times.
21:58:20 T:2960809984 NOTICE: Thread CFileCache start, auto delete: false
21:58:21 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
21:59:09 T:2684835136 NOTICE: Previous line repeats 1 times.
21:59:09 T:2684835136 NOTICE: ES: Starting event server
21:59:13 T:2684835136 NOTICE: Log level changed to 2
21:59:13 T:2684835136 NOTICE: Enabled debug logging due to GUI setting. Level 2.
21:59:13 T:2684835136 DEBUG: ------ Window Init () ------
21:59:17 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
21:59:18 T:2684835136 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
21:59:18 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
21:59:18 T:2684835136 DEBUG: ------ Window Init (Settings.xml) ------
21:59:20 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
21:59:20 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
21:59:24 T:2684835136 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(SystemSettings)
21:59:24 T:2684835136 DEBUG: ExecuteXBMCAction : To ActivateWindow(SystemSettings)
21:59:24 T:2684835136 DEBUG: Activating window ID: 10016
21:59:24 T:2684835136 DEBUG: ------ Window Deinit (Settings.xml) ------
21:59:24 T:2684835136 DEBUG: ------ Window Init (SettingsCategory.xml) ------
21:59:24 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
21:59:30 T:2684835136 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86-osx.so)
21:59:30 T:2684835136 DEBUG: Unloading: ImageLib-x86-osx.so
21:59:30 T:2684835136 INFO: CheckIdle - Closing session to http://mirrors.xbmc.org (easy=0x436ae00, multi=0x39a86e0)
21:59:34 T:2684835136 INFO: Loading skin file: FileBrowser.xml, load type: KEEP_IN_MEMORY
21:59:34 T:2684835136 DEBUG: ------ Window Init (FileBrowser.xml) ------
21:59:35 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
21:59:35 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
21:59:40 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
21:59:40 T:2684835136 DEBUG: ------ Window Deinit (FileBrowser.xml) ------
21:59:41 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
21:59:42 T:2684835136 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
21:59:42 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
21:59:42 T:2684835136 DEBUG: ------ Window Init (Settings.xml) ------
21:59:43 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
21:59:43 T:2684835136 DEBUG: ------ Window Deinit (Settings.xml) ------
21:59:43 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
21:59:43 T:2684835136 DEBUG: ------ Window Init (Home.xml) ------
21:59:43 T:2684835136 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
21:59:47 T:2684835136 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Videos,Files,return)
21:59:47 T:2684835136 DEBUG: ExecuteXBMCAction : To ActivateWindow(Videos,Files,return)
21:59:47 T:2684835136 DEBUG: Activating window ID: 10025
21:59:48 T:2684835136 DEBUG: ------ Window Deinit (Home.xml) ------
21:59:48 T:2684835136 DEBUG: ExecuteXBMCAction : Translating SetProperty(VideosDirectLink,True)
21:59:48 T:2684835136 DEBUG: ExecuteXBMCAction : To SetProperty(VideosDirectLink,True)
21:59:48 T:2684835136 DEBUG: ------ Window Init (MyVideoNav.xml) ------
21:59:48 T:2684835136 INFO: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
21:59:48 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
21:59:48 T:2684835136 DEBUG: ParentPath = [sources://video/]
21:59:48 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
21:59:48 T:2960809984 DEBUG: Thread Background Loader 2960809984 terminating
21:59:51 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/)
21:59:51 T:2684835136 DEBUG: ParentPath = [sources://video/]
21:59:51 T:2684835136 DEBUG: RunQuery took 24 ms for 37 items query: SELECT * FROM tvshowview WHERE c17=11
21:59:51 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
21:59:51 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
21:59:51 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
21:59:51 T:2960809984 DEBUG: Thread Background Loader 2960809984 terminating
21:59:53 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
21:59:54 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/Boardwalk Empire/)
21:59:54 T:2684835136 DEBUG: ParentPath = [/Volumes/My Book 3/Series/Series/]
21:59:54 T:2684835136 DEBUG: RunQuery took 21 ms for 0 items query: SELECT * FROM tvshowview WHERE c17=142
21:59:54 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
21:59:54 T:2960809984 DEBUG: Thread Background Loader 2960809984 terminating
21:59:56 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/)
21:59:56 T:2684835136 DEBUG: ParentPath = [/Volumes/My Book 3/Series/Series/Boardwalk Empire/]
21:59:56 T:2684835136 DEBUG: RunQuery took 36 ms for 12 items query: select * from episodeview WHERE c19=160
21:59:56 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
21:59:56 T:2960809984 DEBUG: Thread Background Loader 2960809984 terminating
21:59:58 T:2684835136 DEBUG: GetEpisodeId (/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv), query = select idEpisode from episode where idFile=6809
21:59:58 T:2684835136 DEBUG: ------ Window Init (DialogVideoInfo.xml) ------
21:59:58 T:2684835136 INFO: Loading skin file: DialogVideoInfo.xml, load type: KEEP_IN_MEMORY
22:00:00 T:2684835136 DEBUG: ------ Window Deinit (DialogVideoInfo.xml) ------
22:00:00 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
22:00:00 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
22:00:00 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
22:00:00 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers(/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv)
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
22:00:00 T:2684835136 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
22:00:00 T:2684835136 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
22:00:00 T:2684835136 NOTICE: DVDPlayer: Opening: /Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv
22:00:00 T:2684835136 WARNING: CDVDMessageQueue(player): Tongue

ut MSGQ_NOT_INITIALIZED
22:00:00 T:2684835136 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
22:00:00 T:2684835136 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:00:00 T:2684835136 DEBUG: CLinuxRendererGL: Tongue

reInit - precision of luminance 16 is 16
22:00:00 T:2962051072 NOTICE: Thread CDVDPlayer start, auto delete: false
22:00:00 T:2962051072 NOTICE: Creating InputStream
22:00:00 T:2962051072 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
22:00:00 T:2962051072 INFO: ScanForExternalSubtitles: found subtitle file /Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.srt
22:00:00 T:2962051072 DEBUG: ScanForExternalSubtitles: END (total time: 4 ms)
22:00:00 T:2962051072 NOTICE: Creating Demuxer
22:00:00 T:2684835136 DEBUG: ------ Window Init (DialogBusy.xml) ------
22:00:00 T:2962051072 DEBUG: Open - probing detected format [matroska,webm]
22:00:00 T:2962051072 DEBUG: Open - avformat_find_stream_info starting
22:00:00 T:2962051072 DEBUG: Open - av_find_stream_info finished
22:00:00 T:2962051072 INFO: ffmpeg[B08D5000]: Input #0, matroska,webm, from '/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv':
22:00:00 T:2962051072 INFO: ffmpeg[B08D5000]: Duration: 01:00:01.31, start: 0.000000, bitrate: 2964 kb/s
22:00:00 T:2962051072 INFO: ffmpeg[B08D5000]: Stream #0:0(eng): Video: h264 (High), yuv420p, 1280x720, SAR 1:1 DAR 16:9, 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc (default)
22:00:00 T:2962051072 INFO: ffmpeg[B08D5000]: Stream #0:1: Audio: ac3, 48000 Hz, stereo, s16, 384 kb/s (default)
22:00:00 T:2962051072 NOTICE: Opening video stream: 0 source: 256
22:00:00 T:2962051072 NOTICE: Creating video codec with codec id: 28
22:00:00 T:2962051072 DEBUG: CDVDFactoryCodec: compiled in hardware support: VDADecoder:yes VideoToolBoxDecoder:no CrystalHD:yes OpenMax:no
22:00:00 T:2962051072 DEBUG: FactoryCodec - Video: - Opening
22:00:00 T:2962051072 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
22:00:00 T:2962051072 DEBUG: FactoryCodec - Video: ff-h264 - Opened
22:00:00 T:2965176320 NOTICE: Thread CVideoReferenceClock start, auto delete: false
22:00:00 T:2965176320 DEBUG: CVideoReferenceClock: setting up Cocoa
22:00:00 T:2965176320 DEBUG: CVideoReferenceClock: Detected refreshrate: 60 hertz
22:00:00 T:2962051072 NOTICE: Creating video thread
22:00:00 T:2966241280 NOTICE: Thread CDVDPlayerVideo start, auto delete: false
22:00:00 T:2966241280 NOTICE: running thread: video_thread
22:00:00 T:2962051072 NOTICE: Opening audio stream: 1 source: 256
22:00:00 T:2962051072 NOTICE: Finding audio codec for: 86019
22:00:00 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening
22:00:00 T:2962051072 DEBUG: Error: Requested setting (audiooutput.passthroughaac) was not found. It must be case-sensitive
22:00:00 T:2962051072 ERROR: CDVDAudioCodecPassthroughFFmpeg::Open - FFmpeg SPDIF muxer does not support this codec
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: passthrough - Opening
22:00:00 T:2962051072 DEBUG: Error: Requested setting (audiooutput.truehdpassthrough) was not found. It must be case-sensitive
22:00:00 T:2962051072 DEBUG: Error: Requested setting (audiooutput.dtshdpassthrough) was not found. It must be case-sensitive
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: passthrough - Failed
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
22:00:00 T:2962051072 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
22:00:00 T:2962051072 NOTICE: Creating audio thread
22:00:00 T:2966773760 NOTICE: Thread CDVDPlayerAudio start, auto delete: false
22:00:00 T:2966773760 NOTICE: running thread: CDVDPlayerAudio: Tongue

rocess()
22:00:00 T:2962051072 NOTICE: Opening Subtitle stream: 0 source: 1024
22:00:00 T:2965708800 DEBUG: CVideoReferenceClock: Detected refreshrate: 24.000000 hertz, rounding to 24 hertz
22:00:01 T:2962051072 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv
22:00:01 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 3
22:00:01 T:2966773760 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through)
22:00:01 T:2966773760 INFO: CCoreAudioAE::MakeStream - AE_FMT_S16NE, 48000, 48000, FL,FR
22:00:01 T:2966241280 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
22:00:01 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(42000.000000, 0)
22:00:01 T:2966241280 INFO: ffmpeg[B0CD4000]: [h264] Cannot parallelize deblocking type 1, decoding such frames in sequential order
22:00:01 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 0
22:00:01 T:2966241280 NOTICE: fps: 23.976024, pwidth: 1280, pheight: 720, dwidth: 1280, dheight: 720
22:00:01 T:2966241280 DEBUG: OutputPicture - change configuration. 1280x720. framerate: 23.98. format: YV12
22:00:01 T:2966241280 NOTICE: Display resolution ADJUST : 1920x1080 @ 24.00 - Full Screen (16) (weight: 0.001)
22:00:01 T:2966241280 DEBUG: CVideoReferenceClock: Clock speed 100.100000%
22:00:01 T:2684835136 NOTICE: Using GL_TEXTURE_2D
22:00:01 T:2684835136 DEBUG: GL: Requested render method: 0
22:00:01 T:2684835136 DEBUG: GL: BaseYUV2RGBGLSLShader: defines:
#define XBMC_texture_rectangle 0
#define XBMC_texture_rectangle_hack 0
#define XBMC_STRETCH 0
#define XBMC_YV12
22:00:01 T:2684835136 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
22:00:01 T:2966773760 DEBUG: CCoreAudioHardware::FindAudioDevice: Searching for device - HDMI.
22:00:01 T:2966773760 ERROR: CCoreAudioDevice::SupportsMixing: Device mixing support : 'No'.
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::CreateMixMap: Source Stream Layout: [Left] [Right]
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::CreateMixMap: User-Configured Speaker Layout: [Left] [Right]
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::CreateMixMap: Output Device Layout: [Left] [Right]
22:00:01 T:2966773760 DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 0 = [1, 0, ]
22:00:01 T:2966773760 DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 1 = [0, 1, ]
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: Mixer Output Format: 2 channels, 48.0 kHz, 32 bits, 4 bytes per frame
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: audioUnit, Input Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: audioUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (44100Hz)
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: mixerUnit, Input Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: mixerUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: inputUnit, Input Stream Format [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: inputUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
22:00:01 T:2966773760 INFO: CCoreAudioAE::Initialize:
22:00:01 T:2966773760 INFO: Output Device : HDMI
22:00:01 T:2966773760 INFO: Sample Rate : 48000
22:00:01 T:2966773760 INFO: Sample Format : AE_FMT_FLOAT
22:00:01 T:2966773760 INFO: Channel Count : 2
22:00:01 T:2966773760 INFO: Channel Layout: FL,FR
22:00:01 T:2966773760 INFO: Frame Size : 8
22:00:01 T:2966773760 INFO: Volume Level : 0.116591
22:00:01 T:2966773760 INFO: Passthrough : 0
22:00:01 T:2684835136 DEBUG: GL: Vertex Shader compilation log:
22:00:01 T:2684835136 DEBUG: GL: Vertex Shader compiled successfully
22:00:01 T:2684835136 DEBUG: GL: Pixel Shader compilation log:
22:00:01 T:2684835136 DEBUG: GL: Fragment Shader compiled successfully
22:00:01 T:2684835136 NOTICE: GL: NPOT texture support detected
22:00:01 T:2684835136 NOTICE: GL: Using GL_ARB_pixel_buffer_object
22:00:01 T:2684835136 DEBUG: CGUIInfoManager::SetCurrentMovie(/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv)
22:00:01 T:2684835136 DEBUG: GetMovieId (/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv), query = select idMovie from movie where idFile=6809
22:00:01 T:2684835136 DEBUG: GetEpisodeId (/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv), query = select idEpisode from episode where idFile=6809
22:00:01 T:2966773760 DEBUG: AERemap: Downmix normalization is disabled
22:00:01 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
22:00:01 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
22:00:01 T:2684835136 DEBUG: Activating window ID: 12005
22:00:01 T:2684835136 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
22:00:01 T:2684835136 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
22:00:01 T:2684835136 INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
22:00:01 T:2966773760 DEBUG: AERemap: Downmix normalization is disabled
22:00:01 T:2966773760 DEBUG: Previous line repeats 2 times.
22:00:01 T:2966773760 DEBUG: CCoreAudioAEStream::CCoreAudioAEStream - Converting from AE_FMT_S16NE to AE_FMT_FLOAT
22:00:01 T:2966773760 DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 0 = [1, 0, ]
22:00:01 T:2966773760 DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 1 = [0, 1, ]
22:00:01 T:2966773760 DEBUG: CCoreAudioGraph::Open: Mixer Output Format: 2 channels, 48.0 kHz, 32 bits, 4 bytes per frame
22:00:01 T:2966773760 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample
22:00:01 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
22:00:01 T:2966773760 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:-11888.199667, should be:-11886.277667, error:1.922000
22:00:01 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 1
22:00:01 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 2
22:00:01 T:2684835136 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
22:00:04 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 3
22:00:04 T:2962051072 DEBUG: demuxer seek to: 32685
22:00:04 T:2962051072 DEBUG: SeekTime - seek ended up on time 33617
22:00:04 T:2962051072 DEBUG: demuxer seek to: 32685, success
22:00:04 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
22:00:04 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
22:00:04 T:2684835136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
22:00:04 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:04 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:04 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(33617000.000000, 1)
22:00:04 T:2966241280 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
22:00:04 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(33792000.000000, 0)
22:00:04 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 2
22:00:06 T:2966773760 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
22:00:06 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 1
22:00:06 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 0
22:00:06 T:2966773760 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:33618304.464000, should be:33792000.000000, error:173695.536000
22:00:07 T:2684835136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
22:00:07 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 3
22:00:07 T:2962051072 DEBUG: demuxer seek to: 65193
22:00:07 T:2962051072 DEBUG: SeekTime - seek ended up on time 65816
22:00:07 T:2962051072 DEBUG: demuxer seek to: 65193, success
22:00:07 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
22:00:07 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
22:00:07 T:2684835136 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
22:00:07 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:07 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:07 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(65816000.000000, 1)
22:00:07 T:2966241280 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
22:00:07 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(66048000.000000, 0)
22:00:07 T:2962051072 INFO: ffmpeg[B08D5000]: [matroska,webm] Unknown entry 0x3C2498
22:00:07 T:2962051072 INFO: ffmpeg[B08D5000]: [matroska,webm] Unknown entry 0x2BCBBC
22:00:07 T:2962051072 INFO: ffmpeg[B08D5000]: [matroska,webm] Unknown entry 0xF4
22:00:07 T:2962051072 DEBUG: CDVDPlayer::SetCaching - caching state 0
22:00:07 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 2
22:00:09 T:2966241280 INFO: ffmpeg[B0CD4000]: [h264] concealing 1705 DC, 1705 AC, 1705 MV errors
22:00:09 T:2966241280 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available
22:00:09 T:2966773760 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
22:00:09 T:2966773760 DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:67815491.589000, should be:66048000.000000, error:-1767491.589000
22:00:09 T:2962051072 DEBUG: CDVDPlayer::HandleMessages - player started 1
22:00:09 T:2966241280 INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 23.976024 fps
22:00:10 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_EOF
22:00:10 T:2966773760 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available
22:00:10 T:2962051072 NOTICE: CDVDPlayer::OnExit()
22:00:10 T:2962051072 NOTICE: DVDPlayer: eof, waiting for queues to empty
22:00:10 T:2962051072 NOTICE: DVDPlayer: closing audio stream
22:00:10 T:2962051072 NOTICE: Closing audio stream
22:00:10 T:2962051072 NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
22:00:10 T:2966773760 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:10 T:2962051072 NOTICE: Waiting for audio thread to exit
22:00:10 T:2966773760 DEBUG: CDVDPlayerAudio: Tongue

rocess - Abort received, exiting thread
22:00:10 T:2966773760 NOTICE: thread end: CDVDPlayerAudio::OnExit()
22:00:10 T:2966773760 DEBUG: Thread CDVDPlayerAudio 2966773760 terminating
22:00:10 T:2962051072 NOTICE: Closing audio device
22:00:10 T:2962051072 DEBUG: CCoreAudioAEStream::~CCoreAudioAEStream - Destructed
22:00:10 T:2962051072 NOTICE: Deleting audio codec
22:00:10 T:2962051072 NOTICE: DVDPlayer: closing video stream
22:00:10 T:2962051072 NOTICE: Closing video stream
22:00:10 T:2962051072 NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
22:00:10 T:2966241280 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
22:00:10 T:2962051072 NOTICE: waiting for video thread to exit
22:00:10 T:2966241280 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
22:00:10 T:2966241280 NOTICE: thread end: video_thread
22:00:10 T:2966241280 DEBUG: Thread CDVDPlayerVideo 2966241280 terminating
22:00:10 T:2962051072 NOTICE: deleting video codec
22:00:10 T:2962051072 NOTICE: DVDPlayer: closing subtitle stream
22:00:10 T:2962051072 NOTICE: Closing subtitle stream
22:00:10 T:2962051072 NOTICE: CDVDPlayer::OnExit() deleting demuxer
22:00:10 T:2962051072 NOTICE: CDVDPlayer::OnExit() deleting input stream
22:00:10 T:2962051072 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
22:00:10 T:2962051072 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
22:00:10 T:2962051072 DEBUG: Thread CDVDPlayer 2962051072 terminating
22:00:10 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
22:00:10 T:2684835136 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
22:00:10 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
22:00:10 T:2684835136 DEBUG: ------ Window Init (MyVideoNav.xml) ------
22:00:10 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/)
22:00:10 T:2684835136 DEBUG: ParentPath = [/Volumes/My Book 3/Series/Series/Boardwalk Empire/4/]
22:00:10 T:2684835136 DEBUG: RunQuery took 1 ms for 12 items query: select * from episodeview WHERE c19=160
22:00:10 T:2960809984 NOTICE: Thread Background Loader start, auto delete: false
22:00:10 T:2960809984 DEBUG: Thread Background Loader 2960809984 terminating
22:00:10 T:2684835136 NOTICE: CDVDPlayer::CloseFile()
22:00:10 T:2684835136 WARNING: CDVDMessageQueue(player): Tongue

ut MSGQ_NOT_INITIALIZED
22:00:10 T:2684835136 NOTICE: DVDPlayer: waiting for threads to exit
22:00:10 T:2684835136 NOTICE: DVDPlayer: finished waiting
22:00:10 T:2684835136 DEBUG: LinuxRendererGL: Cleaning up GL resources
22:00:10 T:2965176320 DEBUG: CVideoReferenceClock: cleaning up Cocoa
22:00:10 T:2965176320 DEBUG: Thread CVideoReferenceClock 2965176320 terminating
22:00:10 T:2958680064 DEBUG: DoWork - Saving file state for video item /Volumes/My Book 3/Series/Series/Boardwalk Empire/4/Boardwalk Empire S04E05 Erlkönig.mkv
22:00:10 T:2684835136 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
22:00:10 T:2958680064 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
22:00:10 T:2958680064 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetTVShows", "params": {"properties": ["title", "studio", "mpaa", "file", "art"], "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}, "limits": {"end": 20}}, "id": 1}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.gettvshows
22:00:11 T:2957615104 DEBUG: RunQuery took 43 ms for 4 items query: SELECT * FROM tvshowview WHERE (tvshowview.idShow IN (select tvshowview.idShow from tvshowview where (watchedcount > 0 AND watchedcount < totalCount) OR (watchedcount = 0 AND tvshowview.idShow IN (select episodeview.idShow from episodeview WHERE episodeview.idShow = tvshowview.idShow AND episodeview.resumeTimeInSeconds > 0))))
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 38, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.getepisodes
22:00:11 T:2957615104 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=38) order by idShow
22:00:11 T:2957615104 DEBUG: RunQuery took 3 ms for 43 items query: select * from episodeview WHERE (episodeview.idShow = 38) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
22:00:11 T:2957615104 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 37, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.getepisodes
22:00:11 T:2957615104 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=37) order by idShow
22:00:11 T:2957615104 DEBUG: RunQuery took 1 ms for 12 items query: select * from episodeview WHERE (episodeview.idShow = 37) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
22:00:11 T:2957615104 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 32, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.getepisodes
22:00:11 T:2957615104 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=32) order by idShow
22:00:11 T:2957615104 DEBUG: RunQuery took 3 ms for 70 items query: select * from episodeview WHERE (episodeview.idShow = 32) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
22:00:11 T:2957615104 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "VideoLibrary.GetEpisodes", "params": {"tvshowid": 10, "properties": ["title", "playcount", "plot", "season", "episode", "showtitle", "file", "lastplayed", "rating", "resume", "art", "streamdetails", "firstaired", "runtime"], "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "is", "value": "0"}, "limits": {"end": 1}}, "id": 1}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.getepisodes
22:00:11 T:2957615104 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=10) order by idShow
22:00:11 T:2957615104 DEBUG: RunQuery took 1 ms for 16 items query: select * from episodeview WHERE (episodeview.idShow = 10) AND (((episodeview.playCount IS NULL OR episodeview.playCount = 0)))
22:00:11 T:2957615104 DEBUG: DatabaseUtils::GetSortFieldList: unknown field 23
22:00:11 T:2957615104 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": { "properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "dateadded"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}}}
22:00:11 T:2957615104 DEBUG: JSONRPC: Calling videolibrary.getepisodes
22:00:11 T:2957615104 DEBUG: RunQuery took 65 ms for 1320 items query: select * from episodeview WHERE ((episodeview.playCount IS NULL OR episodeview.playCount < 1))
22:00:21 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:00:21 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:00:40 T:2960277504 DEBUG: Thread Jobworker 2960277504 terminating (autodelete)
22:00:40 T:2959745024 DEBUG: Thread Jobworker 2959745024 terminating (autodelete)
22:00:40 T:2958680064 DEBUG: Thread Jobworker 2958680064 terminating (autodelete)
22:00:43 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/Boardwalk Empire/)
22:00:43 T:2684835136 DEBUG: ParentPath = [/Volumes/My Book 3/Series/Series/]
22:00:43 T:2956017664 NOTICE: Thread Jobworker start, auto delete: true
22:00:43 T:2684835136 DEBUG: RunQuery took 23 ms for 0 items query: SELECT * FROM tvshowview WHERE c17=142
22:00:43 T:2958680064 NOTICE: Thread Background Loader start, auto delete: false
22:00:43 T:2958680064 DEBUG: Thread Background Loader 2958680064 terminating
22:00:43 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:00:44 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (/Volumes/My Book 3/Series/Series/)
22:00:44 T:2684835136 DEBUG: ParentPath = [sources://video/]
22:00:44 T:2684835136 DEBUG: RunQuery took 23 ms for 37 items query: SELECT * FROM tvshowview WHERE c17=11
22:00:44 T:2959745024 NOTICE: Thread Background Loader start, auto delete: false
22:00:44 T:2959745024 DEBUG: Thread Background Loader 2959745024 terminating
22:00:45 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (sources://video/)
22:00:45 T:2684835136 DEBUG: ParentPath = []
22:00:45 T:2959745024 NOTICE: Thread Background Loader start, auto delete: false
22:00:45 T:2959745024 DEBUG: Thread Background Loader 2959745024 terminating
22:00:47 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
22:00:47 T:2684835136 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
22:00:47 T:2684835136 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
22:00:47 T:2684835136 DEBUG: ------ Window Init (Home.xml) ------
22:00:55 T:2959745024 NOTICE: Thread Jobworker start, auto delete: true
22:00:56 T:2684835136 NOTICE: Previous line repeats 2 times.
22:00:56 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:00:56 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:00:57 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:00:58 T:2684835136 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Programs,Addons,return)
22:00:58 T:2684835136 DEBUG: ExecuteXBMCAction : To ActivateWindow(Programs,Addons,return)
22:00:58 T:2684835136 DEBUG: Activating window ID: 10001
22:00:58 T:2684835136 DEBUG: ------ Window Deinit (Home.xml) ------
22:00:58 T:2684835136 DEBUG: ------ Window Init (MyPrograms.xml) ------
22:00:58 T:2684835136 INFO: Loading skin file: MyPrograms.xml, load type: KEEP_IN_MEMORY
22:00:59 T:2684835136 DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/executable/)
22:00:59 T:2684835136 DEBUG: ParentPath = [addons://sources/executable/]
22:00:59 T:2962051072 NOTICE: Thread Background Loader start, auto delete: false
22:00:59 T:2962051072 NOTICE: Previous line repeats 1 times.
22:00:59 T:2962051072 DEBUG: Thread Background Loader 2962051072 terminating
22:00:59 T:2962583552 DEBUG: Thread Background Loader 2962583552 terminating
22:00:59 T:2956017664 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-x86-osx.so)
22:00:59 T:2956017664 DEBUG: Loading: /Applications/XBMC.app/Contents/Resources/XBMC/system/ImageLib-x86-osx.so
22:00:59 T:2956017664 DEBUG: Caching image '/Users/albertperez/Library/Application Support/XBMC/addons/script.xbmc.debug.log/icon.png' to '5/5496c1e0.jpg':
22:00:59 T:2956017664 DEBUG: cached image 'special://masterprofile/Thumbnails/5/5496c1e0.jpg' size 256x256
22:01:02 T:2684835136 INFO: initializing python engine.
22:01:02 T:2684835136 DEBUG: new python thread created. id=4
22:01:02 T:2962051072 NOTICE: Thread XBPyThread start, auto delete: false
22:01:02 T:2962051072 DEBUG: Python thread: start processing
22:01:02 T:2962051072 NOTICE: -->Python Interpreter Initialized<--
22:01:02 T:2962051072 DEBUG: Process - The source file to load is /Users/albertperez/Library/Application Support/XBMC/addons/script.xbmc.debug.log/addon.py
22:01:02 T:2962051072 DEBUG: Process - Setting the Python path to /Users/albertperez/Library/Application Support/XBMC/addons/script.xbmc.debug.log:/Users/albertperez/Library/Application Support/XBMC/addons/script.module.simplejson/lib:/Applications/XBMC.app/Contents/Resources/XBMC/addons/script.module.pil/lib:/Users/albertperez/Library/Application Support/XBMC/addons/script.module.xbmcswift2/lib:/Applications/XBMC.app/Contents/Frameworks:/Applications/XBMC.app/Contents/Frameworks/lib/python26.zip:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/plat-darwin:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/plat-mac:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/plat-mac/lib-scriptpackages:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/lib-tk:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/lib-old:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/lib-dynload:/Applications/XBMC.app/Contents/Frameworks/lib/python2.6/site-packages:
22:01:02 T:2962051072 DEBUG: Process - Entering source directory /Users/albertperez/Library/Application Support/XBMC/addons/script.xbmc.debug.log
22:01:02 T:2962051072 DEBUG: Instantiating addon using automatically obtained id of "script.xbmc.debug.log" dependent on version 2.1.0 of the xbmc.python api
22:01:02 T:2684835136 DEBUG: ------ Window Init (DialogAddonSettings.xml) ------
22:01:02 T:2684835136 INFO: Loading skin file: DialogAddonSettings.xml, load type: KEEP_IN_MEMORY
22:01:02 T:2684835136 DEBUG: LocalizeStrings: no strings.po file exist at /Users/albertperez/Library/Application Support/XBMC/addons/script.xbmc.debug.log/resources/language/English, fallback to strings.xml
22:01:08 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:01:08 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:01:09 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:01:30 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:01:30 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:01:31 T:2684835136 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86-osx.so)
22:01:31 T:2684835136 DEBUG: Unloading: ImageLib-x86-osx.so
22:01:31 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:01:31 T:2959745024 DEBUG: Thread Jobworker 2959745024 terminating (autodelete)
22:01:31 T:2960277504 DEBUG: Thread Jobworker 2960277504 terminating (autodelete)
22:01:31 T:2956017664 DEBUG: Thread Jobworker 2956017664 terminating (autodelete)
22:01:31 T:2960809984 DEBUG: Thread Jobworker 2960809984 terminating (autodelete)
22:01:32 T:2684835136 INFO: Loading skin file: DialogKeyboard.xml, load type: KEEP_IN_MEMORY
22:01:32 T:2684835136 DEBUG: ------ Window Init (DialogKeyboard.xml) ------
22:01:32 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnInputRequested from xbmc
22:01:32 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 128, from xbmc, message OnInputRequested
22:01:51 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:01:52 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:01:52 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:02:19 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:02:19 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:02:19 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:02:39 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:02:39 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:02:40 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:03:01 T:2684835136 DEBUG: CCoreAudioAE::Suspend - Suspending AE processing
22:03:01 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Release CA HAL.
22:03:02 T:2684835136 DEBUG: ------ Window Deinit (DialogKeyboard.xml) ------
22:03:02 T:2684835136 DEBUG: CAnnouncementManager - Announcement: OnInputFinished from xbmc
22:03:02 T:2684835136 DEBUG: GOT ANNOUNCEMENT, type: 128, from xbmc, message OnInputFinished
22:03:02 T:2684835136 DEBUG: CCoreAudioAE::GarbageCollect - Acquire CA HAL.
22:03:06 T:2684835136 DEBUG: ------ Window Deinit (DialogAddonSettings.xml) ------
22:03:06 T:2962051072 NOTICE: XBMC Log Uploader: started
22:03:06 T:2962051072 NOTICE: XBMC Log Uploader: settings: len(email)=20
22:03:06 T:2962051072 NOTICE: XBMC Log Uploader: settings: skip_oldlog=True
22:03:06 T:2684835136 DEBUG: ------ Window Init (DialogYesNo.xml) ------
22:03:06 T:2684835136 INFO: Loading skin file: DialogYesNo.xml, load type: KEEP_IN_MEMORY
22:03:11 T:2684835136 DEBUG: ------ Window Deinit (DialogYesNo.xml) ------
22:03:11 T:2962051072 NOTICE: XBMC Log Uploader: reading log...