19:09:45 T:3043858272 M:171917312 DEBUG: Time for actual SQL query = 5 19:09:45 T:3043858272 M:171917312 DEBUG: Time to retrieve episodes from dataset = 12 19:09:45 T:3043858272 M:171917312 DEBUG: WindowVideoNav::GetDirectory 19:09:45 T:3043858272 M:171917312 DEBUG: Saving fileitems [videodb://2/2/1/2/] 19:09:45 T:3043858272 M:171945984 DEBUG: -- items: 11, sort method: 21, ascending: true 19:09:45 T:3043858272 M:171945984 WARNING: GetLabel - Unknown nodetype requested 12 19:09:45 T:2962086800 M:171933696 DEBUG: thread start, auto delete: 0 19:09:45 T:2918181776 M:171925504 DEBUG: thread start, auto delete: 1 19:09:45 T:2918181776 M:171921408 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-i486-linux.so) 19:09:45 T:2918181776 M:171921408 DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-i486-linux.so 19:09:45 T:2909789072 M:169443328 DEBUG: thread start, auto delete: 1 19:09:46 T:3043858272 M:169328640 NOTICE: CDVDPlayer::CloseFile() 19:09:46 T:3043858272 M:169328640 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 19:09:46 T:3043858272 M:169328640 NOTICE: DVDPlayer: waiting for threads to exit 19:09:46 T:3043858272 M:169328640 NOTICE: DVDPlayer: finished waiting 19:09:46 T:3043858272 M:169328640 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:09:46 T:2926574480 M:170168320 DEBUG: thread start, auto delete: 1 19:09:46 T:3043858272 M:170229760 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------ 19:09:46 T:2926574480 M:171782144 DEBUG: DoWork - Saving file state for video item /net/atacama/Video/English/Television/Top Gear/Season 02/Top Gear - [02x01] - 2003.05.11 [BBC Prime].avi 19:09:46 T:2926574480 M:171134976 DEBUG: SQLite collision 19:09:46 T:2962086800 M:169074688 DEBUG: Previous line repeats 2 times. 19:09:46 T:2962086800 M:169074688 DEBUG: Thread 2962086800 terminating 19:09:47 T:2918181776 M:166064128 DEBUG: DoWork - took 1825 ms to load special://skin/backgrounds/videos.jpg 19:09:48 T:2909789072 M:168206336 DEBUG: DoWork - took 2377 ms to load special://masterprofile/Thumbnails/Video/Fanart/d9369038.tbn 19:09:58 T:3043858272 M:167665664 INFO: Loading skin file: DialogContextMenu.xml 19:09:58 T:3043858272 M:167727104 DEBUG: Load DialogContextMenu.xml: 4.62ms 19:09:58 T:3043858272 M:167727104 DEBUG: ------ Window Init (DialogContextMenu.xml) ------ 19:09:58 T:3043858272 M:167727104 DEBUG: Alloc resources: 0.57ms (0.01 ms skin load) 19:09:59 T:3043858272 M:167727104 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------ 19:10:00 T:3043858272 M:167911424 DEBUG: new file set audiostream:0 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers(/net/atacama/Video/English/Television/Top Gear/Season 02/Top Gear - [02x01] - 2003.05.11 [BBC Prime].avi) 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1) 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1) 19:10:00 T:3043858272 M:167911424 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players 19:10:00 T:3043858272 M:166928384 NOTICE: DVDPlayer: Opening: /net/atacama/Video/English/Television/Top Gear/Season 02/Top Gear - [02x01] - 2003.05.11 [BBC Prime].avi 19:10:00 T:3043858272 M:166928384 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 19:10:00 T:2876705680 M:166928384 DEBUG: thread start, auto delete: 0 19:10:00 T:2876705680 M:166928384 NOTICE: Creating InputStream 19:10:00 T:2876705680 M:166866944 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 19:10:00 T:2876705680 M:166866944 DEBUG: CacheSubtitles: START 19:10:00 T:2876705680 M:166866944 DEBUG: CacheSubtitles: Checking for common subdirs... 19:10:00 T:2876705680 M:166866944 DEBUG: CacheSubtitles: Done (time: 3 ms) 19:10:00 T:2876705680 M:166866944 DEBUG: CacheSubtitles: Searching for subtitles... 19:10:00 T:2876705680 M:166871040 DEBUG: CacheSubtitles: Done (time: 16 ms) 19:10:00 T:2876705680 M:166871040 DEBUG: CacheSubtitles: END (total time: 27 ms) 19:10:00 T:2876705680 M:166871040 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 19:10:00 T:2876705680 M:166871040 NOTICE: Creating Demuxer 19:10:00 T:2876705680 M:166871040 DEBUG: Open - probing detected format [avi] 19:10:00 T:3043858272 M:165212160 DEBUG: ------ Window Init (DialogBusy.xml) ------ 19:10:00 T:3043858272 M:165212160 DEBUG: Alloc resources: 0.01ms (0.01 ms skin load) 19:10:00 T:2876705680 M:163246080 DEBUG: Open - av_find_stream_info starting 19:10:00 T:2876705680 M:163246080 DEBUG: ffmpeg[AB770B90]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected 19:10:00 T:2876705680 M:163246080 DEBUG: Open - av_find_stream_info finished 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: Input #0, avi, from '/net/atacama/Video/English/Television/Top Gear/Season 02/Top Gear - [02x01] - 2003.05.11 [BBC Prime].avi': 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: Metadata: 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: ISFT : VirtualDubMod 1.5.10.1 (build 2366/release) 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: Duration: 00:46:43.32, start: 0.000000, bitrate: 1048 kb/s 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: Stream #0.0: Video: mpeg4, yuv420p, 512x328 [PAR 1:1 DAR 64:41], 25 fps, 25 tbr, 25 tbn, 30k tbc 19:10:00 T:2876705680 M:163246080 INFO: ffmpeg[AB770B90]: Stream #0.1: Audio: mp3, 32000 Hz, 2 channels, s16, 128 kb/s 19:10:00 T:2876705680 M:163246080 NOTICE: Opening video stream: 0 source: 256 19:10:00 T:2876705680 M:163246080 NOTICE: Creating video codec with codec id: 13 19:10:00 T:2876705680 M:163246080 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:no 19:22:10 T:3043858272 M:154001408 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 19:22:10 T:2918181776 M:154001408 DEBUG: thread start, auto delete: 0 19:22:10 T:2918181776 M:154001408 NOTICE: Creating InputStream 19:22:10 T:2918181776 M:154001408 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles 19:22:10 T:2918181776 M:154001408 DEBUG: CacheSubtitles: START 19:22:10 T:2918181776 M:154001408 DEBUG: CacheSubtitles: Checking for common subdirs... 19:22:10 T:2918181776 M:154001408 DEBUG: CacheSubtitles: Done (time: 5 ms) 19:22:10 T:2918181776 M:154001408 DEBUG: CacheSubtitles: Searching for subtitles... 19:22:10 T:2918181776 M:154030080 DEBUG: CacheSubtitles: Done (time: 7 ms) 19:22:10 T:2918181776 M:154030080 DEBUG: CacheSubtitles: END (total time: 20 ms) 19:22:10 T:2918181776 M:154030080 INFO: CDVDFactorySubtitle::GetSubtitles, searching subtitles done 19:22:10 T:2918181776 M:154030080 NOTICE: Creating Demuxer 19:22:10 T:2918181776 M:153980928 DEBUG: Open - probing detected format [matroska] 19:22:10 T:2918181776 M:153980928 DEBUG: Open - av_find_stream_info starting 19:22:10 T:3043858272 M:153985024 DEBUG: ------ Window Init (DialogBusy.xml) ------ 19:22:10 T:3043858272 M:153985024 DEBUG: Alloc resources: 0.01ms (0.01 ms skin load) 19:22:12 T:2918181776 M:147857408 DEBUG: Open - av_find_stream_info finished 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: Input #0, matroska, from '/net/atacama/Video/English/Television/Top Gear/Specials/Top Gear - Polar Special.S00E109.mkv': 19:22:12 T:2918181776 M:147857408 INFO: ffmpeg[ADEFEB90]: Duration: 01:02:03.14, start: 0.000000, bitrate: 16327 kb/s 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: Stream #0.0(eng): Video: h264, yuv420p, 1440x1080 [PAR 15:11 DAR 20:11], PAR 4:3 DAR 16:9, 25 tbr, 1k tbn, 2k tbc 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: Metadata: 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: title : BBC-HD H.264 1440x1080 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: Stream #0.1(eng): Audio: ac3, 48000 Hz, stereo, s16, 384 kb/s 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: Metadata: 19:22:12 T:2918181776 M:147918848 INFO: ffmpeg[ADEFEB90]: title : English AC3 2.0 19:22:12 T:2918181776 M:147935232 NOTICE: Opening video stream: 0 source: 256 19:22:12 T:2918181776 M:147935232 NOTICE: Creating video codec with codec id: 28 19:22:12 T:2918181776 M:147935232 DEBUG: CDVDFactoryCodec: compiled in hardware support: CrystalHD:yes OpenMax:no VDPAU:yes VAAPI:no 19:22:12 T:2918181776 M:147935232 INFO: Trying Broadcom Crystal HD Decoder... 19:22:12 T:2918181776 M:147935232 DEBUG: FactoryCodec - Video: - Opening 19:22:13 T:2918181776 M:113881088 DEBUG: CrystalHD(new API): device opened 19:22:13 T:2918181776 M:113942528 DEBUG: CrystalHD: profile 640028 19:22:13 T:2918181776 M:113942528 DEBUG: CrystalHD: nal size 4 19:22:13 T:2918181776 M:113942528 DEBUG: CrystalHD: num sps 1 19:22:13 T:2918181776 M:113942528 DEBUG: CrystalHD: data size at end = 0 19:22:13 T:2813807504 M:113971200 DEBUG: thread start, auto delete: 0 19:22:13 T:2813807504 M:113971200 DEBUG: CrystalHD: Output Thread Started... 19:22:13 T:2918181776 M:113971200 INFO: DVDVideoCodecCrystalHD: Opened Broadcom Crystal HD Codec 19:22:13 T:2918181776 M:113971200 DEBUG: FactoryCodec - Video: chd-h264 - Opened 19:22:13 T:2918181776 M:113971200 NOTICE: Creating video thread 19:22:13 T:2805414800 M:113971200 DEBUG: thread start, auto delete: 0 19:22:13 T:2918181776 M:113971200 NOTICE: Opening audio stream: 1 source: 256 19:22:13 T:2918181776 M:113971200 NOTICE: Finding audio codec for: 86020 19:22:13 T:2918181776 M:113971200 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Opening 19:22:13 T:2918181776 M:113971200 INFO: CDVDAudioCodecPassthroughFFmpeg::Open - Won't transcode for HDMI 19:22:13 T:2918181776 M:113971200 DEBUG: FactoryCodec - Audio: PassthroughFFmpeg - Failed 19:22:13 T:2918181776 M:113971200 DEBUG: FactoryCodec - Audio: FFmpeg - Opening 19:22:13 T:2918181776 M:113971200 DEBUG: FactoryCodec - Audio: FFmpeg - Opened 19:22:13 T:2918181776 M:113971200 NOTICE: Creating audio thread 19:22:13 T:2797022096 M:113946624 DEBUG: thread start, auto delete: 0 19:22:13 T:2797022096 M:113946624 NOTICE: running thread: CDVDPlayerAudio::Process() 19:22:13 T:2918181776 M:113946624 DEBUG: ReadEditDecisionLists - Adjusting detected frame rate by half assuming 1080i (interlaced): 25.000 19:22:13 T:2918181776 M:113946624 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: /net/atacama/Video/English/Television/Top Gear/Specials/Top Gear - Polar Special.S00E109.mkv 19:22:13 T:2805414800 M:113946624 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:22:13 T:2805414800 M:113946624 NOTICE: running thread: video_thread 19:22:13 T:2918181776 M:112791552 DEBUG: Process - Start position set to last stopped position: 1243000 19:22:13 T:2918181776 M:117284864 DEBUG: SeekTime - seek ended up on time 1243589 19:22:13 T:2918181776 M:117325824 DEBUG: Process - starting demuxer from: 1243000 19:22:13 T:2918181776 M:117325824 DEBUG: CDVDPlayer::SetCaching - caching state 2 19:22:13 T:3043858272 M:114900992 DEBUG: CGUIAudioManager::DeInitialize 19:22:13 T:2797022096 M:111411200 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1243904000.000000, 1) 19:22:13 T:2797022096 M:111411200 NOTICE: Creating audio device with codec id: 86020, channels: 2, sample rate: 48000, no pass-through 19:22:13 T:2797022096 M:111411200 INFO: AudioRendererFactory: not a explicit device, trying to autodetect. 19:22:13 T:2797022096 M:111411200 INFO: CPCMRemap: Configured speaker layout: 2.0 19:22:13 T:2797022096 M:111411200 INFO: CPCMRemap: I channel map: FL,FR 19:22:13 T:2797022096 M:111411200 INFO: CPCMRemap: O channel map: FL,FR 19:22:13 T:2797022096 M:111411200 DEBUG: CPCMRemap: Downmix normalization is disabled 19:22:13 T:2797022096 M:111411200 DEBUG: CPCMRemap: FL = FL(1.000000*) 19:22:13 T:2797022096 M:111411200 DEBUG: CPCMRemap: FR = FR(1.000000*) 19:22:13 T:2797022096 M:111411200 DEBUG: RemoveActiveDevice - Removing device 1 19:22:13 T:2805414800 M:111411200 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE 19:22:13 T:2805414800 M:111411200 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1243909000.000000, 0) 19:22:13 T:2918181776 M:111288320 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1243829000.000000 to get to start point at 1243909000.000000 19:22:13 T:2918181776 M:111288320 DEBUG: CheckPlayerInit - dropping packet type:2 dts:1243869000.000000 to get to start point at 1243909000.000000 19:22:13 T:2797022096 M:110084096 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 19:22:13 T:2797022096 M:110084096 DEBUG: RemoveActiveDevice - Removing device 0 19:22:13 T:3043858272 M:110022656 DEBUG: CAnnouncementManager - Announcement: PlaybackStarted from xbmc 19:22:14 T:3043858272 M:108232704 DEBUG: CGUIInfoManager::SetCurrentMovie(/net/atacama/Video/English/Television/Top Gear/Specials/Top Gear - Polar Special.S00E109.mkv) 19:22:14 T:2805414800 M:107229184 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available 19:22:14 T:2813807504 M:107323392 DEBUG: CrystalHD: Format Change Detected. Flags: 0x00030000 19:22:14 T:2813807504 M:107323392 DEBUG: ---------------------------------- 19:22:14 T:2813807504 M:107323392 DEBUG: TimeStamp: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Picture Number: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Width: 1440 19:22:14 T:2813807504 M:107323392 DEBUG: Height: 1080 19:22:14 T:2813807504 M:107323392 DEBUG: Chroma: 0x001 19:22:14 T:2813807504 M:107323392 DEBUG: Pulldown: 3 19:22:14 T:2813807504 M:107323392 DEBUG: Flags: 0x00000068 19:22:14 T:2813807504 M:107323392 DEBUG: Frame Rate/Res: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Aspect Ratio: 11 19:22:14 T:2813807504 M:107323392 DEBUG: Color Primaries: 33686016 19:22:14 T:2813807504 M:107323392 DEBUG: MetaData: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Session Number: 0 19:22:14 T:2813807504 M:107323392 DEBUG: TimeStamp: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Custom Aspect: 0 19:22:14 T:2813807504 M:107323392 DEBUG: Frames to Drop: 0 19:22:14 T:2813807504 M:107323392 DEBUG: H264 Valid Fields: 0x00000000 19:22:14 T:2813807504 M:107323392 DEBUG: CrystalHD: dec_par x = 15, dec_par y = 11 19:22:14 T:2813807504 M:107323392 DEBUG: CrystalHD: resolution = 0 interlace = 0 19:22:14 T:3043858272 M: 97189888 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 19:22:14 T:2797022096 M: 91430912 DEBUG: PulseAudio: Context failed 19:22:14 T:2797022096 M: 91422720 ERROR: PulseAudio: Waited for the Context but it failed 19:22:14 T:2797022096 M: 91422720 ERROR: PulseAudio: Failed to create context 19:22:14 T:2797022096 M: 91422720 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 19:22:14 T:2797022096 M: 91422720 DEBUG: RemoveActiveDevice - Removing device 2 19:22:14 T:2797022096 M: 91422720 DEBUG: CGUIAudioManager::Initialize 19:22:14 T:2797022096 M: 86994944 INFO: CPCMRemap: Configured speaker layout: 2.0 19:22:14 T:2797022096 M: 86999040 INFO: CPCMRemap: I channel map: FL,FR 19:22:14 T:2797022096 M: 86999040 INFO: CPCMRemap: O channel map: FL,FR 19:22:14 T:2797022096 M: 86863872 DEBUG: CPCMRemap: Downmix normalization is disabled 19:22:14 T:2797022096 M: 86515712 DEBUG: CPCMRemap: FL = FL(1.000000*) 19:22:14 T:2797022096 M: 86515712 DEBUG: CPCMRemap: FR = FR(1.000000*) 19:22:14 T:2797022096 M: 86474752 DEBUG: RemoveActiveDevice - Removing device 1 19:22:14 T:2797022096 M: 86454272 DEBUG: CGUIAudioManager::DeInitialize 19:22:14 T:2805414800 M: 83496960 NOTICE: fps: 25.000000, pwidth: 1440, pheight: 1080, dwidth: 1920, dheight: 1080 19:22:14 T:2805414800 M: 83496960 DEBUG: OutputPicture - change configuration. 1440x1080. framerate: 25.00. format: YUY2 19:22:14 T:2805414800 M: 77246464 WARNING: CRenderManager::Configure - timeout waiting for previous frame 19:22:14 T:2805414800 M: 77185024 NOTICE: Display resolution DESKTOP : 1024x768 @ 60.00 - Full Screen (12) 19:22:14 T:3043858272 M: 76984320 DEBUG: Activating window ID: 12005 19:22:14 T:3043858272 M: 76984320 DEBUG: Checking if window ID 12005 is locked. 19:22:14 T:2797022096 M: 73932800 DEBUG: SetActiveDevice - SetActiveDevice from 0 to 2 19:22:14 T:2797022096 M: 73932800 DEBUG: RemoveActiveDevice - Removing device 0 19:22:14 T:2797022096 M: 73871360 DEBUG: Initialize - using alsa device plughw:0,3 19:22:14 T:3043858272 M: 73879552 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------ 19:22:14 T:3043858272 M: 73879552 DEBUG: ------ Window Init (VideoFullScreen.xml) ------ 19:22:14 T:3043858272 M: 73879552 INFO: Loading skin file: VideoFullScreen.xml 19:22:14 T:2797022096 M: 73949184 DEBUG: CALSADirectSound::Initialize - packet size:2048, packet count:16, buffer size:8192 19:22:14 T:2797022096 M: 71958528 DEBUG: CALSADirectSound::Initialize - Channels: 2 - SampleRate: 48000 - SampleBit: 16 - Resample false - IsMusic false - IsPassthrough false - audioDevice: plughw:0,3 19:22:14 T:2797022096 M: 71958528 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback 19:22:14 T:3043858272 M: 64438272 DEBUG: Load VideoFullScreen.xml: 50.01ms 19:22:14 T:3043858272 M: 64438272 DEBUG: Alloc resources: 50.52ms (50.52 ms skin load) 19:22:14 T:3043858272 M: 40857600 NOTICE: Using GL_TEXTURE_2D 19:22:14 T:3043858272 M: 40796160 DEBUG: GL: Requested render method: 0 19:22:14 T:2918181776 M: 40157184 DEBUG: CDVDPlayer::HandleMessages - player started 2 19:22:14 T:2918181776 M: 40157184 DEBUG: CDVDPlayer::HandleMessages - player started 1 19:22:14 T:2918181776 M: 40157184 DEBUG: CDVDPlayer::SetCaching - caching state 3 19:22:14 T:2797022096 M: 40157184 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1243524985.192000, should be:1243937029.195509, error:412044.003509 19:22:14 T:2918181776 M: 40157184 DEBUG: CDVDPlayer::SetCaching - caching state 0 19:22:14 T:3043858272 M: 39907328 DEBUG: GL: BaseYUV2RGBGLSLShader: defines: #define XBMC_texture_rectangle 0 #define XBMC_texture_rectangle_hack 0 #define XBMC_STRETCH 0 #define XBMC_YUY2 19:22:14 T:3043858272 M: 39886848 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader 19:22:14 T:3043858272 M: 39886848 DEBUG: GL: Vertex Shader compilation log: 19:22:14 T:3043858272 M: 39886848 DEBUG: GL: Vertex Shader compiled successfully 19:22:15 T:3043858272 M: 37371904 DEBUG: GL: Pixel Shader compilation log: 19:22:15 T:3043858272 M: 37371904 DEBUG: GL: Fragment Shader compiled successfully 19:22:15 T:3043858272 M: 26783744 NOTICE: GL: NPOT texture support detected 19:22:15 T:3043858272 M: 25993216 NOTICE: GL: Using GL_ARB_pixel_buffer_object 19:22:15 T:2918181776 M: 16494592 INFO: ffmpeg[ADEFEB90]: [matroska] Unknown entry 0x1654AE6B 19:22:17 T:3043858272 M: 25735168 DEBUG: SECTION:UnloadDelayed(DLL: libmad.so.0) 19:22:17 T:3043858272 M: 25735168 DEBUG: Unloading: libmad.so.0 19:22:18 T:2797022096 M: 26914816 WARNING: CALSADirectSound::GetSpace - get space failed. err: -32 (Broken pipe) 19:22:19 T:2797022096 M: 26984448 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1248019626.715509, should be:1247935293.489799, error:-84333.225709 19:22:21 T:3043858272 M: 22274048 INFO: CheckIdle - Closing session to http://xoap.weather.com (easy=0xb33a0f00, multi=0xb0bf2ef0) 19:22:23 T:2797022096 M: 24686592 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1252032201.465800, should be:1252022171.675435, error:-10029.790364 19:22:23 T:2918181776 M: 24637440 INFO: ffmpeg[ADEFEB90]: [matroska] Unknown entry 0x1654AE6B 19:22:26 T:2805414800 M: 29569024 DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00 19:22:31 T:3043858272 M: 34189312 DEBUG: ------ Window Init (VideoOSD.xml) ------ 19:22:31 T:3043858272 M: 34111488 INFO: Loading skin file: VideoOSD.xml 19:22:32 T:2805414800 M: 34889728 WARNING: CRenderManager::FlipPage - timeout waiting for flip to complete 19:22:32 T:2805414800 M: 34844672 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:32 T:2918181776 M: 34656256 WARNING: Previous line repeats 4 times. 19:22:32 T:2918181776 M: 34656256 INFO: ffmpeg[ADEFEB90]: [matroska] Unknown entry 0x1654AE6B 19:22:32 T:2805414800 M: 34791424 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:33 T:3043858272 M: 34697216 WARNING: Previous line repeats 4 times. 19:22:33 T:3043858272 M: 34697216 DEBUG: Load VideoOSD.xml: 2154.89ms 19:22:33 T:2805414800 M: 34717696 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:34 T:3043858272 M: 34324480 WARNING: Previous line repeats 7 times. 19:22:34 T:3043858272 M: 34324480 DEBUG: Alloc resources: 3022.84ms (2178.54 ms skin load) 19:22:34 T:2805414800 M: 34193408 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:34 T:3043858272 M: 34156544 WARNING: Previous line repeats 1 times. 19:22:34 T:3043858272 M: 34156544 DEBUG: SDLKeyboard: scancode: 53, sym: 120, unicode: 120, modifier: 0 19:22:34 T:3043858272 M: 34181120 DEBUG: OnKey: 61528 pressed, action is Stop 19:22:34 T:3043858272 M: 34181120 NOTICE: CDVDPlayer::CloseFile() 19:22:34 T:2918181776 M: 34177024 DEBUG: CAnnouncementManager - Announcement: PlaybackSpeedChanged from xbmc 19:22:34 T:2805414800 M: 34119680 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:34 T:3043858272 M: 34111488 NOTICE: DVDPlayer: waiting for threads to exit 19:22:34 T:2918181776 M: 34111488 NOTICE: CDVDPlayer::OnExit() 19:22:34 T:2918181776 M: 34111488 NOTICE: DVDPlayer: closing audio stream 19:22:34 T:2918181776 M: 34111488 NOTICE: Closing audio stream 19:22:34 T:2918181776 M: 34111488 NOTICE: Waiting for audio thread to exit 19:22:34 T:2797022096 M: 34111488 DEBUG: CDVDPlayerAudio:: Discontinuity - was:1245046481.102435, should be:1263492197.700699, error:18445716.598264 19:22:34 T:2797022096 M: 33964032 NOTICE: thread end: CDVDPlayerAudio::OnExit() 19:22:34 T:2797022096 M: 33927168 DEBUG: Thread 2797022096 terminating 19:22:34 T:2805414800 M: 33898496 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:34 T:2918181776 M: 33746944 WARNING: Previous line repeats 1 times. 19:22:34 T:2918181776 M: 33746944 NOTICE: Closing audio device 19:22:34 T:2918181776 M: 33869824 DEBUG: SetActiveDevice - SetActiveDevice from 2 to 1 19:22:34 T:2918181776 M: 33869824 DEBUG: RemoveActiveDevice - Removing device 2 19:22:34 T:2918181776 M: 33869824 DEBUG: CGUIAudioManager::Initialize 19:22:34 T:2805414800 M: 33857536 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:35 T:2942303120 M: 33263616 WARNING: Previous line repeats 8 times. 19:22:35 T:2942303120 M: 33263616 DEBUG: Thread 2942303120 terminating (autodelete) 19:22:35 T:2805414800 M: 33296384 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:36 T:2868312976 M: 33251328 WARNING: Previous line repeats 5 times. 19:22:36 T:2868312976 M: 33251328 DEBUG: Thread 2868312976 terminating (autodelete) 19:22:36 T:2805414800 M: 33263616 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:36 T:2876705680 M: 33312768 WARNING: Previous line repeats 3 times. 19:22:36 T:2876705680 M: 33312768 DEBUG: Thread 2876705680 terminating (autodelete) 19:22:37 T:2805414800 M: 37580800 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:37 T:2926574480 M: 36782080 WARNING: Previous line repeats 5 times. 19:22:37 T:2926574480 M: 36782080 DEBUG: Thread 2926574480 terminating (autodelete) 19:22:37 T:2918181776 M: 36786176 NOTICE: Deleting audio codec 19:22:37 T:2805414800 M: 36782080 WARNING: CRenderManager::FlipPage - timeout waiting for previous frame 19:22:37 T:2918181776 M: 36679680 WARNING: Previous line repeats 1 times. 19:22:37 T:2918181776 M: 36679680 NOTICE: DVDPlayer: closing video stream 19:22:37 T:2918181776 M: 36679680 NOTICE: Closing video stream 19:22:37 T:2918181776 M: 36679680 NOTICE: waiting for video thread to exit 19:22:37 T:2805414800 M: 36622336 NOTICE: thread end: video_thread 19:22:37 T:2805414800 M: 36622336 DEBUG: Thread 2805414800 terminating 19:22:37 T:2918181776 M: 36634624 NOTICE: deleting video codec 19:22:37 T:2813807504 M: 36634624 DEBUG: CrystalHD: Output Thread Stopped... 19:22:37 T:2813807504 M: 36634624 DEBUG: Thread 2813807504 terminating 19:22:38 T:2918181776 M:162471936 DEBUG: CrystalHD: device closed 19:22:38 T:2918181776 M:162533376 NOTICE: CDVDPlayer::OnExit() deleting demuxer 19:22:38 T:2918181776 M:183029760 NOTICE: CDVDPlayer::OnExit() deleting input stream 19:22:38 T:2918181776 M:183115776 DEBUG: CAnnouncementManager - Announcement: PlaybackStopped from xbmc 19:22:38 T:2918181776 M:183091200 DEBUG: Thread 2918181776 terminating 19:22:38 T:3043858272 M:183119872 NOTICE: DVDPlayer: finished waiting 19:22:38 T:3043858272 M:183119872 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:22:39 T:3043858272 M:190926848 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 19:22:39 T:3043858272 M:190976000 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------ 19:22:39 T:3043858272 M:190976000 DEBUG: ------ Window Deinit (VideoOSD.xml) ------ 19:22:39 T:3043858272 M:190275584 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 19:22:40 T:3043858272 M:190189568 DEBUG: ------ Window Init (MyVideoNav.xml) ------ 19:22:40 T:3043858272 M:190189568 INFO: Loading skin file: MyVideoNav.xml 19:22:41 T:3043858272 M:187641856 DEBUG: Load MyVideoNav.xml: 1782.49ms 19:22:41 T:3043858272 M:187609088 DEBUG: Alloc resources: 1943.95ms (1786.84 ms skin load) 19:22:41 T:3043858272 M:187609088 DEBUG: CGUIMediaWindow::GetDirectory (videodb://2/2/1/0/) 19:22:41 T:3043858272 M:187609088 DEBUG: ParentPath = [videodb://2/2/1/0/] 19:22:41 T:3043858272 M:187645952 DEBUG: GetStackedTvShowList query: select idShow from tvshow where c00 like (select c00 from tvshow where idShow=1) order by idShow 19:22:41 T:3043858272 M:187645952 DEBUG: GetEpisodesByWhere query: select * from episodeview where idShow IN (1,37) and c12=0 19:22:42 T:3043858272 M:187633664 DEBUG: Time for actual SQL query = 98 19:22:42 T:3043858272 M:187613184 DEBUG: Time to retrieve episodes from dataset = 108 19:22:42 T:3043858272 M:187613184 DEBUG: WindowVideoNav::GetDirectory 19:22:42 T:3043858272 M:187617280 DEBUG: Saving fileitems [videodb://2/2/1/0/] 19:22:42 T:3043858272 M:187645952 DEBUG: -- items: 8, sort method: 21, ascending: true 19:22:42 T:3043858272 M:187658240 WARNING: GetLabel - Unknown nodetype requested 12 19:22:42 T:2962086800 M:187592704 DEBUG: thread start, auto delete: 0 19:22:42 T:2918181776 M:186621952 DEBUG: thread start, auto delete: 1 19:22:43 T:2962086800 M:187441152 DEBUG: Thread 2962086800 terminating 19:22:43 T:2826398608 M:185942016 DEBUG: thread start, auto delete: 1 19:22:44 T:3043858272 M:178978816 NOTICE: CDVDPlayer::CloseFile() 19:22:44 T:3043858272 M:178978816 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED 19:22:44 T:3043858272 M:178978816 NOTICE: DVDPlayer: waiting for threads to exit 19:22:44 T:3043858272 M:178978816 NOTICE: DVDPlayer: finished waiting 19:22:44 T:3043858272 M:178978816 DEBUG: LinuxRendererGL: Cleaning up GL resources 19:22:44 T:2918181776 M:179646464 DEBUG: DoWork - took 2233 ms to load special://skin/backgrounds/videos.jpg 19:22:44 T:2813807504 M:180064256 DEBUG: thread start, auto delete: 1 19:22:44 T:2813807504 M:180105216 DEBUG: DoWork - Saving file state for video item /net/atacama/Video/English/Television/Top Gear/Specials/Top Gear - Polar Special.S00E109.mkv 19:22:45 T:3043858272 M:180072448 DEBUG: SECTION:UnloadDelayed(SECTION: LCODE) 19:22:45 T:2918181776 M:179281920 DEBUG: DoWork - took 312 ms to load special://masterprofile/Thumbnails/Video/3/3275a268.tbn 19:22:46 T:2826398608 M:179310592 DEBUG: DoWork - took 2622 ms to load special://masterprofile/Thumbnails/Video/Fanart/d9369038.tbn 19:22:51 T:3043858272 M:177905664 DEBUG: SECTION:UnloadDll(libcurl-gnutls.so.4) 19:22:51 T:3043858272 M:177905664 DEBUG: Unloading: libcurl-gnutls.so.4 19:23:09 T:3043858272 M:177741824 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avformat-52-i486-linux.so) 19:23:09 T:3043858272 M:177741824 DEBUG: Unloading: avformat-52-i486-linux.so 19:23:15 T:2813807504 M:177586176 DEBUG: Thread 2813807504 terminating (autodelete) 19:23:15 T:2918181776 M:177586176 DEBUG: Thread 2918181776 terminating (autodelete) 19:23:16 T:2826398608 M:177602560 DEBUG: Thread 2826398608 terminating (autodelete) 19:23:16 T:3043858272 M:177602560 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-i486-linux.so) 19:23:16 T:3043858272 M:177602560 DEBUG: Unloading: ImageLib-i486-linux.so