rendered paste body19: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