12:05:58 T:3078719360 M:1391034368 DEBUG: CPlayerCoreFactory::GetPlayers(/media/Volume/qom-q.xvid.avi) 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 12:05:58 T:3078719360 M:1390907392 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 12:05:58 T:3078719360 M:1389637632 NOTICE: DVDPlayer: Opening: /media/Volume/qom-q.xvid.avi 12:05:58 T:3078719360 M:1389637632 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 12:05:58 T:2876050288 M:1389637632 DEBUG: thread start, auto delete: 0 12:05:58 T:2876050288 M:1389637632 NOTICE: Creating InputStream 12:05:58 T:2876050288 M:1389637632 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 12:05:58 T:2876050288 M:1389510656 DEBUG: CacheSubtitles: START 12:05:58 T:2876050288 M:1389510656 DEBUG: CacheSubtitles: Checking for common subdirs... 12:05:58 T:2876050288 M:1389383680 DEBUG: CacheSubtitles: Done (time: 3 ms) 12:05:58 T:2876050288 M:1389383680 DEBUG: CacheSubtitles: Searching for subtitles... 12:05:58 T:3078719360 M:1389129728 DEBUG: ------ Window Init (DialogBusy.xml) ------ 12:05:58 T:3078719360 M:1389129728 DEBUG: Alloc resources: 0.01ms (0.00 ms skin load) 12:05:58 T:2876050288 M:1389002752 DEBUG: CacheSubtitles: Done (time: 304 ms) 12:05:58 T:2876050288 M:1388875776 DEBUG: CacheSubtitles: END (total time: 309 ms) 12:05:58 T:2876050288 M:1388875776 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 12:05:58 T:2876050288 M:1388875776 NOTICE: Creating Demuxer 12:06:24 T:3001826160 M:101109760 DEBUG: Thread 3001826160 terminating (autodelete) 12:06:25 T:2903165808 M: 99237888 DEBUG: Thread 2903165808 terminating (autodelete) 12:07:36 T:2892675952 M: 73981952 DEBUG: ffmpeg[AC6ABB70]: [mp3] Could not find codec parameters (Audio: mp3, 0 channels, s16) 12:07:36 T:2892675952 M: 74182656 DEBUG: ffmpeg[AC6ABB70]: [mp3] Estimating duration from bitrate, this may be inaccurate 12:07:36 T:2892675952 M: 73469952 WARNING: could not find codec parameters for /media/Volume/sow-niko.720p.mkv 12:07:36 T:2892675952 M:1992392704 ERROR: ExtractThumb - Error creating demuxer 12:07:36 T:2876050288 M:1992486912 DEBUG: Open - probing detected format [avi] 12:07:37 T:2876050288 M:1985605632 DEBUG: Open - av_find_stream_info starting 12:07:37 T:2876050288 M:1984987136 DEBUG: Open - av_find_stream_info finished 12:07:37 T:2876050288 M:1984987136 INFO: ffmpeg[AB6D0B70]: Input #0, avi, from '/media/Volume/qom-q.xvid.avi': 12:07:37 T:2876050288 M:1984987136 INFO: ffmpeg[AB6D0B70]: Metadata: 12:07:37 T:2876050288 M:1984991232 INFO: ffmpeg[AB6D0B70]: ISFT : VirtualDubMod 1.5.10.2 (build 2540/release) 12:07:37 T:2876050288 M:1984995328 INFO: ffmpeg[AB6D0B70]: Duration: 01:27:31.28, start: 0.000000, bitrate: 2149 kb/s 12:07:37 T:2876050288 M:1985122304 INFO: ffmpeg[AB6D0B70]: Stream #0.0: Video: mpeg4, yuv420p, 704x304 [PAR 1:1 DAR 44:19], 25 tbr, 25 tbn, 25 tbc 12:07:37 T:2876050288 M:1985122304 INFO: ffmpeg[AB6D0B70]: Stream #0.1: Audio: ac3, 48000 Hz, 5.1, s16, 384 kb/s 12:07:37 T:2876050288 M:1984995328 NOTICE: Opening video stream: 0 source: 256 12:07:37 T:2876050288 M:1984995328 NOTICE: Creating video codec with codec id: 13 12:07:37 T:2876050288 M:1984995328 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:no OpenMax:no VDPAU:yes VAAPI:no 12:07:37 T:2876050288 M:1984995328 DEBUG: FactoryCodec - Video: - Opening 12:07:37 T:2876050288 M:1984995328 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-4 part 2 12:07:37 T:2876050288 M:1984995328 DEBUG: FactoryCodec - Video: ff-mpeg4 - Opened 12:07:37 T:2991336304 M:1984995328 DEBUG: thread start, auto delete: 0 12:07:37 T:2991336304 M:1984995328 DEBUG: CVideoReferenceClock: Setting up GLX 12:07:37 T:2876050288 M:1984995328 NOTICE: Creating video thread 12:07:37 T:2876050288 M:1984995328 NOTICE: Opening audio stream: 1 source: 256 12:07:37 T:2876050288 M:1984995328 NOTICE: Finding audio codec for: 86020 12:07:37 T:2942303088 M:1984966656 DEBUG: thread start, auto delete: 0 12:07:37 T:2876050288 M:1984679936 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 12:07:37 T:2876050288 M:1984679936 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 12:07:37 T:2876050288 M:1984679936 NOTICE: Creating audio thread 12:07:37 T:2921323376 M:1984679936 DEBUG: thread start, auto delete: 0 12:07:37 T:2921323376 M:1984552960 NOTICE: running thread: CDVDPlayerAudio::Process() 12:07:37 T:2942303088 M:1984684032 DEBUG: LinuxRendererGL: Cleaning up GL resources 12:07:37 T:2942303088 M:1984684032 NOTICE: running thread: video_thread 12:07:37 T:2876050288 M:1984528384 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /media/Volume/qom-q.xvid.avi 12:07:37 T:2876050288 M:1984581632 DEBUG: CDVDPlayer::SetCaching - caching state 2 12:07:37 T:2921323376 M:1984589824 ERROR: ffmpeg[AE1FDB70]: [ac3] frame sync error 12:07:37 T:2921323376 M:1984438272 ERROR: CDVDPlayerAudio::DecodeFrame - Decode Error. Skipping audio packet 12:07:37 T:2942303088 M:1984446464 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 12:07:37 T:2921323376 M:1984446464 DEBUG: CDVDPlayerAudio::Process - Decode Error 12:07:37 T:2921323376 M:1984446464 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1) 12:07:37 T:2921323376 M:1982287872 NOTICE: Creating audio device with codec id: 86020, channels: 6, sample rate: 48000, no pass-through 12:07:37 T:2921323376 M:1981693952 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 12:07:37 T:2921323376 M:1981566976 INFO: CPCMRemap: Configured speaker layout: 2.0 12:07:37 T:2921323376 M:1981566976 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 12:07:37 T:2921323376 M:1981566976 DEBUG: CPCMRemap: Forcing side channel map to back channels 12:07:37 T:2921323376 M:1981440000 INFO: CPCMRemap: O channel map: FL,FR 12:07:37 T:2921323376 M:1981440000 DEBUG: CPCMRemap: Downmix normalization is disabled 12:07:37 T:2921323376 M:1981440000 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 12:07:37 T:2921323376 M:1981440000 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 12:07:37 T:2921323376 M:1981440000 DEBUG: CPulseAudioDirectSound::CPulseAudioDirectSound - Requested channels changed from 6 to 2 12:07:37 T:2921323376 M:1981440000 DEBUG: RemoveActiveDevice - Removing device 1 12:07:37 T:2921323376 M:1981313024 DEBUG: CGUIAudioManager::DeInitialize 12:07:37 T:2942303088 M:1980493824 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(40000.000000, 0) 12:07:37 T:2942303088 M:1979568128 NOTICE: fps: 25.000000, pwidth: 704, pheight: 304, dwidth: 704, dheight: 304 12:07:37 T:2942303088 M:1979568128 DEBUG: OutputPicture - change configuration. 704x304. framerate: 25.00. format: YV12 12:07:37 T:2942303088 M:1979568128 NOTICE: Display resolution ADJUST : 1920x1080 @ 50.00 - Full Screen (12) 12:07:37 T:2876050288 M:1979568128 DEBUG: CDVDPlayer::HandleMessages - player started 2 12:07:37 T:2921323376 M:1979895808 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 12:07:37 T:2921323376 M:1979912192 DEBUG: RemoveActiveDevice - Removing device 0 12:07:38 T:2921323376 M:1978421248 DEBUG: PulseAudio: Context failed 12:07:38 T:2921323376 M:1978421248 ERROR: PulseAudio: Waited for the Context but it failed 12:07:38 T:2921323376 M:1978421248 ERROR: PulseAudio: Failed to create context 12:07:38 T:2921323376 M:1978548224 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 12:07:38 T:2921323376 M:1978548224 DEBUG: RemoveActiveDevice - Removing device 2 12:07:38 T:2921323376 M:1978548224 DEBUG: CGUIAudioManager::Initialize 12:07:38 T:2921323376 M:1977565184 INFO: CPCMRemap: Configured speaker layout: 2.0 12:07:38 T:2921323376 M:1977565184 INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR 12:07:38 T:2921323376 M:1977565184 DEBUG: CPCMRemap: Forcing side channel map to back channels 12:07:38 T:2921323376 M:1977565184 INFO: CPCMRemap: O channel map: FL,FR 12:07:38 T:2921323376 M:1977565184 DEBUG: CPCMRemap: Downmix normalization is disabled 12:07:38 T:2921323376 M:1977565184 DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000) 12:07:38 T:2921323376 M:1977565184 DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000) 12:07:38 T:2921323376 M:1977565184 DEBUG: CALSADirectSound::CALSADirectSound - Requested channels changed from 6 to 2 12:07:38 T:2921323376 M:1977565184 DEBUG: RemoveActiveDevice - Removing device 1 12:07:38 T:2921323376 M:1977565184 DEBUG: CGUIAudioManager::DeInitialize 12:07:38 T:3078719360 M:1977221120 NOTICE: Using GL_TEXTURE_2D 12:07:38 T:2921323376 M:1977221120 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 12:07:38 T:2921323376 M:1977221120 DEBUG: RemoveActiveDevice - Removing device 0 12:07:38 T:3078719360 M:1977221120 DEBUG: GL: Requested render method: 0 12:07:38 T:2921323376 M:1977233408 DEBUG: Initialize - using alsa device default 12:07:38 T:2921323376 M:1977233408 DEBUG: CALSADirectSound::Initialize - packet size:2048, packet count:16, buffer size:8192 12:07:38 T:3078719360 M:1977233408 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 12:07:38 T:2921323376 M:1977233408 DEBUG: CALSADirectSound::Initialize - Channels: 6 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: default 12:07:38 T:2921323376 M:1977233408 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 12:07:38 T:2876050288 M:1977360384 DEBUG: CDVDPlayer::HandleMessages - player started 1 12:07:38 T:2876050288 M:1977360384 DEBUG: CDVDPlayer::SetCaching - caching state 3 12:07:38 T:2876050288 M:1977360384 DEBUG: CDVDPlayer::SetCaching - caching state 0 12:07:38 T:2921323376 M:1977491456 DEBUG: CDVDPlayerAudio:: Discontinuity - was:2076.931000, should be:32000.000000, error:29923.069000 12:07:38 T:3078719360 M:1977491456 DEBUG: GL: Vertex Shader compilation log: 12:07:38 T:3078719360 M:1977491456 DEBUG: GL: Vertex Shader compiled successfully 12:07:38 T:3078719360 M:1977491456 DEBUG: GL: Pixel Shader compilation log: 12:07:38 T:3078719360 M:1977491456 DEBUG: GL: Fragment Shader compiled successfully 12:07:38 T:3078719360 M:1977188352 NOTICE: GL: NPOT texture support detected 12:07:38 T:3078719360 M:1977188352 NOTICE: GL: Using GL_ARB_pixel_buffer_object 12:07:38 T:3078719360 M:1977049088 DEBUG: Activating window ID: 12005 12:07:38 T:3078719360 M:1977049088 DEBUG: Checking if window ID 12005 is locked. 12:07:38 T:3078719360 M:1977155584 DEBUG: ------ Window Deinit (MyVideo.xml) ------ 12:07:38 T:3078719360 M:1977044992 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 12:07:38 T:3078719360 M:1977044992 INFO: Loading skin file: VideoFullScreen.xml 12:07:38 T:2921323376 M:1977188352 DEBUG: CDVDPlayerAudio:: Discontinuity - was:368981.119000, should be:542040.496031, error:173059.377031 12:07:38 T:3078719360 M:1977315328 DEBUG: Load VideoFullScreen.xml: 191.66ms 12:07:39 T:3078719360 M:1977446400 DEBUG: Alloc resources: 228.05ms (191.90 ms skin load) 12:07:39 T:3078719360 M:1977446400 INFO: Loading skin file: VideoOSD.xml 12:07:39 T:3078719360 M:1977450496 DEBUG: Load VideoOSD.xml: 32.25ms 12:07:39 T:3078719360 M:1976766464 DEBUG: Alloc resources: 303.32ms (0.00 ms skin load) 12:07:39 T:3078719360 M:1976766464 INFO: Loading skin file: VideoOSDSettings.xml 12:07:39 T:3078719360 M:1976770560 DEBUG: Load VideoOSDSettings.xml: 2.92ms 12:07:39 T:3078719360 M:1976008704 DEBUG: Alloc resources: 6.91ms (0.00 ms skin load) 12:07:39 T:3078719360 M:1976008704 INFO: Loading skin file: VideoOSDSettings.xml 12:07:39 T:3078719360 M:1975881728 DEBUG: Load VideoOSDSettings.xml: 2.73ms 12:07:39 T:3078719360 M:1975881728 DEBUG: Alloc resources: 0.19ms (0.00 ms skin load) 12:07:39 T:3078719360 M:1975881728 INFO: Loading skin file: DialogFullScreenInfo.xml 12:07:39 T:3078719360 M:1976139776 DEBUG: Load DialogFullScreenInfo.xml: 23.19ms 12:07:39 T:3078719360 M:1976139776 DEBUG: Alloc resources: 17.14ms (0.00 ms skin load) 12:07:39 T:3078719360 M:1975767040 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 12:07:39 T:2942303088 M:1972158464 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete 12:07:39 T:3078719360 M:1972158464 DEBUG: CGUIInfoManager::SetCurrentMovie(/media/Volume/qom-q.xvid.avi) 12:07:39 T:2942303088 M:1968230400 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 12:07:40 T:3078719360 M:1967976448 DEBUG: GetMovieId (/media/Volume/qom-q.xvid.avi), query = select idMovie from movie where idFile=2 12:07:40 T:3078719360 M:1967976448 DEBUG: GetEpisodeId (/media/Volume/qom-q.xvid.avi), query = select idEpisode from episode where idFile=2 12:07:40 T:2942303088 M:1961881600 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 12:07:40 T:3078719360 M:1961349120 DEBUG: GetMusicVideoId (/media/Volume/qom-q.xvid.avi), query = select idMVideo from musicvideo where idFile=2 12:07:40 T:2942303088 M:1961349120 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 12:07:40 T:3078719360 M:1961349120 DEBUG: OnKey: 11 pressed, action is Select 12:07:40 T:3078719360 M:1961271296 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 12:07:40 T:2991336304 M:1979097088 DEBUG: CVideoReferenceClock: output of nvidia-settings -nt -q RefreshRate3: 50,000 Hz 12:07:40 T:2991336304 M:1979097088 DEBUG: CVideoReferenceClock: Detected refreshrate by nvidia-settings: 50.000000 hertz, rounding to 50 hertz 12:07:40 T:2921323376 M:1979207680 DEBUG: CDVDPlayerAudio:: synctype set to 2: resample 12:07:40 T:2921323376 M:1979207680 DEBUG: CDVDPlayerResampler: resized buffers to hold 15360 frames 12:07:40 T:2942303088 M:1979277312 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00