Project

General

Profile

Download (141 KB)

Crash when switch live tv channel with tvheadend » xbmc01.old.txt

terence chill, 2014-10-15 23:17

 
1 
21:46:52 T:3059708448  NOTICE: special://profile/ is mapped to: special://masterprofile/
2 
21:46:52 T:3059708448  NOTICE: -----------------------------------------------------------------------
3 
21:46:52 T:3059708448  NOTICE: Starting XBMC (13.2 Git:0f3db05). Platform: ARM Linux 32-bit
4 
21:46:52 T:3059708448  NOTICE: Using Release XBMC x32 build, compiled Oct  4 2014 by GCC 4.9.1 for ARM Linux 32-bit 3.16.3
5 
21:46:52 T:3059708448  NOTICE: Running on Linux 32-bit (OpenELEC (official) - Version: 4.2.1, 3.16.3 armv6l)
6 
21:46:52 T:3059708448  NOTICE: Host CPU: ARMv6-compatible processor rev 7 (v6l), 1 core available
7 
21:46:52 T:3059708448  NOTICE: ARM Features: Neon disabled
8 
21:46:52 T:3059708448  NOTICE: special://xbmc/ is mapped to: /usr/share/xbmc
9 
21:46:52 T:3059708448  NOTICE: special://xbmcbin/ is mapped to: /usr/lib/xbmc
10 
21:46:52 T:3059708448  NOTICE: special://masterprofile/ is mapped to: /storage/.xbmc/userdata
11 
21:46:52 T:3059708448  NOTICE: special://home/ is mapped to: /storage/.xbmc
12 
21:46:52 T:3059708448  NOTICE: special://temp/ is mapped to: /storage/.xbmc/temp
13 
21:46:52 T:3059708448  NOTICE: The executable running is: /usr/lib/xbmc/xbmc.bin
14 
21:46:52 T:3059708448  NOTICE: Local hostname: OpenELEC
15 
21:46:52 T:3059708448  NOTICE: Log File is located: /storage/.xbmc/temp/xbmc.log
16 
21:46:52 T:3059708448  NOTICE: -----------------------------------------------------------------------
17 
21:46:52 T:3059708448   ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
18 
21:46:52 T:3059708448  NOTICE: load settings...
19 
21:46:52 T:3059708448  NOTICE: Found 1 Lists of Devices
20 
21:46:52 T:3059708448  NOTICE: Enumerated PI devices:
21 
21:46:52 T:3059708448  NOTICE:     Device 1
22 
21:46:52 T:3059708448  NOTICE:         m_deviceName      : HDMI
23 
21:46:52 T:3059708448  NOTICE:         m_displayName     : HDMI
24 
21:46:52 T:3059708448  NOTICE:         m_displayNameExtra:
25 
21:46:52 T:3059708448  NOTICE:         m_deviceType      : AE_DEVTYPE_HDMI
26 
21:46:52 T:3059708448  NOTICE:         m_channels        : FL,FR
27 
21:46:52 T:3059708448  NOTICE:         m_sampleRates     : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
28 
21:46:52 T:3059708448  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
29 
21:46:52 T:3059708448  NOTICE:     Device 2
30 
21:46:52 T:3059708448  NOTICE:         m_deviceName      : Analogue
31 
21:46:52 T:3059708448  NOTICE:         m_displayName     : Analogue
32 
21:46:52 T:3059708448  NOTICE:         m_displayNameExtra:
33 
21:46:52 T:3059708448  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
34 
21:46:52 T:3059708448  NOTICE:         m_channels        : FL,FR
35 
21:46:52 T:3059708448  NOTICE:         m_sampleRates     : 48000
36 
21:46:52 T:3059708448  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE
37 
21:46:52 T:3059708448  NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
38 
21:46:52 T:3059708448  NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
39 
                                            <?xml version="1.0" encoding="UTF-8" ?>
40 
                                            <advancedsettings>
41 
                                              <showexitbutton>false</showexitbutton>
42 
                                              <fanartres>720</fanartres>
43 
                                              <imageres>540</imageres>
44 
                                              <video>
45 
                                                <defaultplayer>omxplayer</defaultplayer>
46 
                                                <defaultdvdplayer>omxplayer</defaultdvdplayer>
47 
                                                <busydialogdelayms>750</busydialogdelayms>
48 
                                              </video>
49 
                                              <network>
50 
                                                <cachemembuffersize>20971520</cachemembuffersize>
51 
                                              </network>
52 
                                              <samba>
53 
                                                <clienttimeout>30</clienttimeout>
54 
                                              </samba>
55 
                                              <network>
56 
                                                <readbufferfactor>4.0</readbufferfactor>
57 
                                              </network>
58 
                                            </advancedsettings>
59 
21:46:52 T:3059708448  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
60 
21:46:52 T:3059708448  NOTICE: Default DVD Player: omxplayer
61 
21:46:52 T:3059708448  NOTICE: Default Video Player: omxplayer
62 
21:46:52 T:3059708448  NOTICE: Default Audio Player: paplayer
63 
21:46:52 T:3059708448  NOTICE: Enabled debug logging due to GUI setting (2)
64 
21:46:52 T:3059708448  NOTICE: Log level changed to 2
65 
21:46:52 T:3059708448  NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
66 
21:46:52 T:3059708448  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
67 
21:46:52 T:3059708448   DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
68 
21:46:52 T:3059708448   DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
69 
21:46:52 T:3059708448   DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
70 
21:46:52 T:3059708448   DEBUG: CPlayerCoreConfig::<ctor>: created player OMXPlayer for core 4
71 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
72 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv
73 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/mms/udp
74 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
75 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
76 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
77 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
78 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
79 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
80 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
81 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile
82 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
83 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
84 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
85 
21:46:52 T:3059708448   DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
86 
21:46:52 T:3059708448  NOTICE: Loaded playercorefactory configuration
87 
21:46:52 T:3059708448  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
88 
21:46:52 T:3059708448  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
89 
21:46:52 T:3059708448    INFO: creating subdirectories
90 
21:46:52 T:3059708448    INFO: userdata folder: special://masterprofile/
91 
21:46:52 T:3059708448    INFO: recording folder:
92 
21:46:52 T:3059708448    INFO: screenshots folder: /storage/screenshots/
93 
21:46:53 T:3059708448    INFO: load language info file: special://xbmc/language/English/langinfo.xml
94 
21:46:53 T:3059708448   DEBUG: trying to set locale to en_DE.UTF-8
95 
21:46:53 T:3059708448    INFO: global locale set to C
96 
21:46:53 T:3059708448    INFO: load English language file, from path: special://xbmc/language/
97 
21:46:53 T:3059708448   DEBUG: POParser: loaded 3166 strings from file /usr/share/xbmc/language/English/strings.po
98 
21:46:53 T:3059692624  NOTICE: Thread ActiveAE start, auto delete: false
99 
21:46:53 T:3049256016  NOTICE: Thread AESink start, auto delete: false
100 
21:46:53 T:3049256016    INFO: CActiveAESink::OpenSink - initialize sink
101 
21:46:53 T:3049256016   DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
102 
21:46:53 T:3049256016   DEBUG: DllBcm: Using omx system library
103 
21:46:53 T:3049256016   DEBUG: SECTION:LoadDLL(libopenmaxil.so)
104 
21:46:53 T:3049256016   DEBUG: Loading: libopenmaxil.so
105 
21:46:53 T:3049256016   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
106 
21:46:53 T:3049256016   DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00
107 
21:46:53 T:3049256016   DEBUG: CAESinkPi:Initialize
108 
21:46:53 T:2988438608  NOTICE: Thread CRBPWorker start, auto delete: false
109 
21:46:53 T:3049256016   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xb5201440
110 
21:46:53 T:3049256016   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17640), nBufferAlignmen(16)
111 
21:46:53 T:3049256016   DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
112 
21:46:53 T:3049256016   DEBUG:   Output Device : HDMI
113 
21:46:53 T:3049256016   DEBUG:   Sample Rate   : 44100
114 
21:46:53 T:3049256016   DEBUG:   Sample Format : AE_FMT_FLOAT
115 
21:46:53 T:3049256016   DEBUG:   Channel Count : 2
116 
21:46:53 T:3049256016   DEBUG:   Channel Layout: FL,FR
117 
21:46:53 T:3049256016   DEBUG:   Frames        : 2205
118 
21:46:53 T:3049256016   DEBUG:   Frame Samples : 4410
119 
21:46:53 T:3049256016   DEBUG:   Frame Size    : 8
120 
21:46:53 T:3059708448  NOTICE: Running database version Addons16
121 
21:46:53 T:3059708448   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so)
122 
21:46:53 T:3059708448   DEBUG: Loading: /usr/lib/xbmc/system/libcpluff-arm.so
123 
21:46:54 T:3059708448    INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/xbmc/addons: No such file or directory'
124 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
125 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
126 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.image.flickr has been installed.'
127 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
128 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
129 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.wmc-linux-arm has been installed.'
130 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
131 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in service.openelec.settings has been installed.'
132 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
133 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.'
134 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.argustv has been installed.'
135 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.mediaportal.tvserver has been installed.'
136 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
137 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
138 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
139 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
140 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvblink has been installed.'
141 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
142 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
143 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.gameone has been installed.'
144 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
145 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
146 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
147 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
148 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
149 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in repository.openelec.tv has been installed.'
150 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in os.openelec.tv has been installed.'
151 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.cinemassacre has been installed.'
152 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.vuplus has been installed.'
153 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
154 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
155 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
156 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
157 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.demo has been installed.'
158 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.iptvsimple has been installed.'
159 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
160 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.xvdr has been installed.'
161 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.njoy has been installed.'
162 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.atv_at has been installed.'
163 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.nextpvr has been installed.'
164 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.mtv_de has been installed.'
165 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
166 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
167 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.image.google has been installed.'
168 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.dvbviewer has been installed.'
169 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv.cmyth has been installed.'
170 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
171 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
172 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
173 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
174 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
175 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
176 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in plugin.video.tlc_de has been installed.'
177 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.debug has been installed.'
178 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
179 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
180 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
181 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
182 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
183 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in script.module.elementtree has been installed.'
184 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
185 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
186 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.'
187 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
188 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
189 
21:46:54 T:3059708448   DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
190 
21:46:54 T:3059708448  NOTICE: ADDONS: Using repository repository.xbmc.org
191 
21:46:54 T:3059708448  NOTICE: ADDONS: Using repository repository.openelec.tv
192 
21:46:54 T:3059708448    INFO: LIRC Initialize: using: /run/lirc/lircd
193 
21:46:54 T:3059708448    INFO: LIRC Initialize: successfully started
194 
21:46:54 T:3059708448   DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
195 
21:46:54 T:3059708448   DEBUG: LoadMappings - loaded node "CEC Adapter"
196 
21:46:54 T:3059708448   DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
197 
21:46:54 T:3059708448   DEBUG: LoadMappings - loaded node "iMON HID device"
198 
21:46:54 T:3059708448   DEBUG: CPeripheralBusUSB - initialised udev monitor
199 
21:46:54 T:3059708448   DEBUG: SECTION:LoadDLL(libcec.so.2)
200 
21:46:54 T:3059708448   DEBUG: Loading: libcec.so.2
201 
21:46:54 T:3059708448   DEBUG: GetMappingForDevice - device (2708:1001) mapped to CEC Adapter (type = cec)
202 
21:46:54 T:3059708448   DEBUG: InitialiseFeature - using libCEC v2.1.4
203 
21:46:54 T:3059708448   DEBUG: Initialise - initialised peripheral on 'RPI' with 2 features and 0 sub devices
204 
21:46:54 T:3059708448  NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
205 
21:46:54 T:2971661392  NOTICE: Thread PeripBusCEC start, auto delete: false
206 
21:46:54 T:2980050000  NOTICE: Thread CECAdapter start, auto delete: false
207 
21:46:54 T:2980050000   DEBUG: OpenConnection - opening a connection to the CEC adapter: RPI
208 
21:46:54 T:2980050000   DEBUG: CecLogMessage - unregistering all CEC clients
209 
21:46:54 T:2980050000   DEBUG: CecLogMessage - Broadcast (F): osd name set to 'Broadcast'
210 
21:46:54 T:2980050000   DEBUG: CecLogMessage - InitHostCEC - vchiq_initialise succeeded
211 
21:46:54 T:2980050000   DEBUG: CecLogMessage - InitHostCEC - vchi_initialise succeeded
212 
21:46:54 T:2980050000   DEBUG: CecLogMessage - InitHostCEC - vchi_connect succeeded
213 
21:46:54 T:2980050000   DEBUG: CecLogMessage - Open - vc_cec initialised
214 
21:46:54 T:3013604432   DEBUG: CecLogMessage - logical address changed to Free use (e)
215 
21:46:54 T:2980050000   DEBUG: CecLogMessage - connection opened
216 
21:46:54 T:2963272784   DEBUG: CecLogMessage - processor thread started
217 
21:46:54 T:2980050000   DEBUG: CecLogMessage - << Broadcast (F) -> TV (0): POLL
218 
21:46:54 T:2980050000   DEBUG: CecLogMessage - initiator 'Broadcast' is not supported by the CEC adapter. using 'Free use' instead
219 
21:46:54 T:2980050000   DEBUG: CecLogMessage - << e0
220 
21:46:54 T:2980050000   DEBUG: CecLogMessage - >> POLL sent
221 
21:46:54 T:2980050000   DEBUG: CecLogMessage - TV (0): device status changed into 'present'
222 
21:46:54 T:2980050000   DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
223 
21:46:54 T:2980050000   DEBUG: CecLogMessage - << e0:8c
224 
21:46:55 T:2946495568  NOTICE: Thread PeripBusUSBUdev start, auto delete: false
225 
21:46:55 T:3059708448   DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks with interface org.freedesktop.UDisks and method EnumerateDevices
226 
21:46:55 T:3059708448   DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
227 
21:46:55 T:3059708448   DEBUG: DBus: Creating message to org.freedesktop.DeviceKit.Disks on /org/freedesktop/DeviceKit/Disks with interface org.freedesktop.DeviceKit.Disks and method EnumerateDevices
228 
21:46:55 T:3059708448   DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
229 
21:46:55 T:3059708448   DEBUG: Selected UDev as storage provider
230 
21:46:55 T:3059708448  NOTICE: Raspberry PI firmware version: Oct  1 2014 22:37:42
231 
                                            Copyright (c) 2012 Broadcom
232 
                                            version 93c98148caed4bc6e4a741944c2717318874e387 (clean) (release)
233 
21:46:55 T:3059708448  NOTICE: ARM mem: 384MB GPU mem: 128MB MPG2:1 WVC1:1
234 
21:46:55 T:3059708448  NOTICE: cacheMemBufferSize: 20MB
235 
21:46:55 T:3059708448  NOTICE: Config:
236 
                                            hdmi_channel_map=8
237 
                                            program_serial_random=1
238 
                                            config_hdmi_boost=5
239 
                                            emmc_pll_core=1
240 
                                            hdmi_ignore_cec_init=1
241 
                                            hdmi_force_cec_address=65535
242 
                                            temp_limit=85
243 
                                            force_pwm_open=1
244 
                                            pause_burst_frames=1
245 
                                            avoid_fix_ts=1
246 
21:46:55 T:3059708448  NOTICE: Config:
247 
                                            decode_MPG2=0x4a55cee3
248 
                                            decode_WVC1=0xed3558b
249 
21:46:55 T:3059708448   DEBUG: DllBcm: Using omx system library
250 
21:46:55 T:3059708448  NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
251 
21:46:55 T:3059708448   DEBUG: EGL initial desktop resolution 1920x1080 (1920x1080) @ 50.00 - Full Screen (1.00)
252 
21:46:55 T:3059708448   DEBUG: EGL get supported modes (1) = 16, prefer_group=1, prefer_mode=1f
253 
21:46:55 T:3059708448   DEBUG: EGL mode 0: 640x480 (640x480) @ 60.00 - Full Screen (1.00) :1
254 
21:46:55 T:3059708448   DEBUG: EGL mode 1: 720x480 (720x480) @ 60.00 - Full Screen (0.89) :2
255 
21:46:55 T:3059708448   DEBUG: EGL mode 2: 720x480 (720x480) @ 60.00 - Full Screen (1.19) :3
256 
21:46:55 T:3059708448   DEBUG: EGL mode 2: 720x480 (720x480) @ 60.00 3DSBS - Full Screen (1.19)
257 
21:46:55 T:3059708448   DEBUG: EGL mode 2: 720x480 (720x480) @ 60.00 3DTB - Full Screen (1.19)
258 
21:46:55 T:3059708448   DEBUG: EGL mode 3: 1280x720 (1280x720) @ 60.00 - Full Screen (1.00) :4
259 
21:46:55 T:3059708448   DEBUG: EGL mode 3: 1280x720 (1280x720) @ 60.00 3DSBS - Full Screen (1.00)
260 
21:46:55 T:3059708448   DEBUG: EGL mode 3: 1280x720 (1280x720) @ 60.00 3DTB - Full Screen (1.00)
261 
21:46:55 T:3059708448   DEBUG: EGL mode 4: 1920x1080 (1920x1080) @ 60.00i - Full Screen (1.00) I:5
262 
21:46:55 T:3059708448   DEBUG: EGL mode 4: 1920x1080 (1920x1080) @ 60.00i 3DSBS - Full Screen (1.00)
263 
21:46:55 T:3059708448   DEBUG: EGL mode 4: 1920x1080 (1920x1080) @ 60.00i 3DTB - Full Screen (1.00)
264 
21:46:55 T:3059708448   DEBUG: EGL mode 5: 720x480 (720x480) @ 60.00i - Full Screen (1.19) I:7
265 
21:46:55 T:3059708448   DEBUG: EGL mode 6: 1920x1080 (1920x1080) @ 60.00 - Full Screen (1.00) N:10
266 
21:46:55 T:3059708448   DEBUG: EGL mode 6: 1920x1080 (1920x1080) @ 60.00 3DSBS - Full Screen (1.00)
267 
21:46:55 T:3059708448   DEBUG: EGL mode 6: 1920x1080 (1920x1080) @ 60.00 3DTB - Full Screen (1.00)
268 
21:46:55 T:3059708448   DEBUG: EGL mode 7: 720x576 (720x576) @ 50.00 - Full Screen (1.07) :11
269 
21:46:55 T:3059708448   DEBUG: EGL mode 8: 720x576 (720x576) @ 50.00 - Full Screen (1.42) :12
270 
21:46:55 T:3059708448   DEBUG: EGL mode 8: 720x576 (720x576) @ 50.00 3DSBS - Full Screen (1.42)
271 
21:46:55 T:3059708448   DEBUG: EGL mode 8: 720x576 (720x576) @ 50.00 3DTB - Full Screen (1.42)
272 
21:46:55 T:3059708448   DEBUG: EGL mode 9: 1280x720 (1280x720) @ 50.00 - Full Screen (1.00) :13
273 
21:46:55 T:3059708448   DEBUG: EGL mode 9: 1280x720 (1280x720) @ 50.00 3DSBS - Full Screen (1.00)
274 
21:46:55 T:3059708448   DEBUG: EGL mode 9: 1280x720 (1280x720) @ 50.00 3DTB - Full Screen (1.00)
275 
21:46:55 T:3059708448   DEBUG: EGL mode 10: 1920x1080 (1920x1080) @ 50.00i - Full Screen (1.00) I:14
276 
21:46:55 T:3059708448   DEBUG: EGL mode 10: 1920x1080 (1920x1080) @ 50.00i 3DSBS - Full Screen (1.00)
277 
21:46:55 T:3059708448   DEBUG: EGL mode 10: 1920x1080 (1920x1080) @ 50.00i 3DTB - Full Screen (1.00)
278 
21:46:55 T:3059708448   DEBUG: EGL mode 11: 720x576 (720x576) @ 50.00i - Full Screen (1.42) I:16
279 
21:46:55 T:3059708448   DEBUG: EGL mode 12: 1920x1080 (1920x1080) @ 50.00 - Full Screen (1.00) N:1f
280 
21:46:55 T:3059708448   DEBUG: EGL mode 12: 1920x1080 (1920x1080) @ 50.00 3DSBS - Full Screen (1.00)
281 
21:46:55 T:3059708448   DEBUG: EGL mode 12: 1920x1080 (1920x1080) @ 50.00 3DTB - Full Screen (1.00)
282 
21:46:55 T:3059708448   DEBUG: EGL mode 13: 1920x1080 (1920x1080) @ 24.00 - Full Screen (1.00) :20
283 
21:46:55 T:3059708448   DEBUG: EGL mode 13: 1920x1080 (1920x1080) @ 24.00 3DSBS - Full Screen (1.00)
284 
21:46:55 T:3059708448   DEBUG: EGL mode 13: 1920x1080 (1920x1080) @ 24.00 3DTB - Full Screen (1.00)
285 
21:46:55 T:3059708448   DEBUG: EGL mode 14: 1920x1080 (1920x1080) @ 25.00 - Full Screen (1.00) :21
286 
21:46:55 T:3059708448   DEBUG: EGL mode 15: 1920x1080 (1920x1080) @ 30.00 - Full Screen (1.00) :22
287 
21:46:55 T:3059708448   DEBUG: EGL get supported modes (2) = 5, prefer_group=1, prefer_mode=1f
288 
21:46:55 T:3059708448   DEBUG: EGL mode 0: 640x480 (640x480) @ 60.00 - Full Screen (1.00) :4
289 
21:46:55 T:3059708448   DEBUG: EGL mode 1: 800x600 (800x600) @ 60.00 - Full Screen (1.00) :9
290 
21:46:55 T:3059708448   DEBUG: EGL mode 2: 1024x768 (1024x768) @ 60.00 - Full Screen (1.00) :10
291 
21:46:55 T:3059708448   DEBUG: EGL mode 3: 1280x1024 (1280x1024) @ 60.00 - Full Screen (1.00) :23
292 
21:46:55 T:3059708448   DEBUG: EGL mode 4: 1366x768 (1366x768) @ 60.00 - Full Screen (1.00) :51
293 
21:46:55 T:3059708448  NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz
294 
21:46:55 T:3059708448  NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 59.940063 Hz
295 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
296 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 59.940063 Hz
297 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
298 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 59.940063 Hz
299 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
300 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 59.940063 Hz
301 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
302 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 59.940063 Hz
303 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
304 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 59.940063 Hz
305 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
306 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 59.940063 Hz
307 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz
308 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 59.940063 Hz
309 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz
310 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 59.940063 Hz
311 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz
312 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 59.940063 Hz
313 
21:46:55 T:3059708448  NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz
314 
21:46:55 T:3059708448  NOTICE: Previous line repeats 2 times.
315 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
316 
21:46:55 T:3059708448  NOTICE: Previous line repeats 2 times.
317 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 50.000000 Hz
318 
21:46:55 T:3059708448  NOTICE: Previous line repeats 2 times.
319 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz
320 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 23.976025 Hz
321 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz
322 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 23.976025 Hz
323 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz
324 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 23.976025 Hz
325 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 25.000000 Hz
326 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 30.000000 Hz
327 
21:46:55 T:3059708448  NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 29.970032 Hz
328 
21:46:55 T:3059708448  NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 60.000000 Hz
329 
21:46:55 T:3059708448  NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 59.940063 Hz
330 
21:46:55 T:3059708448  NOTICE: Found resolution 1024 x 768 for display 0 with 1024 x 768 @ 60.000000 Hz
331 
21:46:55 T:3059708448  NOTICE: Found resolution 1024 x 768 for display 0 with 1024 x 768 @ 59.940063 Hz
332 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 1024 for display 0 with 1280 x 1024 @ 60.000000 Hz
333 
21:46:55 T:3059708448  NOTICE: Found resolution 1280 x 1024 for display 0 with 1280 x 1024 @ 59.940063 Hz
334 
21:46:55 T:3059708448  NOTICE: Found resolution 1366 x 768 for display 0 with 1366 x 768 @ 60.000000 Hz
335 
21:46:55 T:3059708448  NOTICE: Found resolution 1366 x 768 for display 0 with 1366 x 768 @ 59.940063 Hz
336 
21:46:55 T:3059708448  NOTICE: Found ([email protected]) at 42, setting to RES_DESKTOP at 16
337 
21:46:55 T:3059708448  NOTICE: Checking resolution 16
338 
21:46:55 T:3059708448   DEBUG: EGL set HDMI mode (1,31)=0
339 
21:46:55 T:2963272784   DEBUG: CecLogMessage - >> 0f:87:00:80:45
340 
21:46:55 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87)
341 
21:46:55 T:2963272784   DEBUG: CecLogMessage - TV (0): vendor = Panasonic (008045)
342 
21:46:55 T:2980050000   DEBUG: CecLogMessage - expected response received (87: device vendor id)
343 
21:46:55 T:2980050000   DEBUG: CecLogMessage - replacing the command handler for device 'TV' (0)
344 
21:46:55 T:2980050000   DEBUG: CecLogMessage - registering new CEC client - v2.1.4
345 
21:46:55 T:2980050000   DEBUG: CecLogMessage - detecting logical address for type 'playback device'
346 
21:46:55 T:2980050000   DEBUG: CecLogMessage - trying logical address 'Playback 1'
347 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << Playback 1 (4) -> Playback 1 (4): POLL
348 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << 44
349 
21:46:55 T:3021993040   DEBUG: EGL tv_service_callback (8,1,31)
350 
21:46:55 T:3059708448   DEBUG: EGL set resolution 1920x1080 -> 1920x1080 @ 50.00 fps (1,31) flags:1f010004 aspect:1.00
351 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << 44
352 
21:46:55 T:3059708448   DEBUG: CreateWindow: Created surface of size 1920x1080
353 
21:46:55 T:3059708448  NOTICE: GL_VENDOR = Broadcom
354 
21:46:55 T:3059708448  NOTICE: GL_RENDERER = VideoCore IV HW
355 
21:46:55 T:3059708448  NOTICE: GL_VERSION = OpenGL ES 2.0
356 
21:46:55 T:3059708448  NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
357 
21:46:55 T:3059708448   DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
358 
21:46:55 T:3059708448  NOTICE: GL_EXTENSIONS = GL_OES_compressed_ETC1_RGB8_texture GL_OES_compressed_paletted_texture GL_OES_texture_npot GL_OES_depth24 GL_OES_vertex_half_float GL_OES_EGL_image GL_OES_EGL_image_external GL_EXT_discard_framebuffer GL_OES_rgb8_rgba8 GL_OES_depth32 GL_OES_mapbuffer GL_EXT_texture_format_BGRA8888 GL_APPLE_rgb_422 GL_EXT_debug_marker
359 
21:46:55 T:3059708448   DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
360 
21:46:55 T:3059708448   DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
361 
21:46:55 T:3059708448   DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
362 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
363 
21:46:55 T:3059708448   DEBUG: Compiled
364 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
365 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
366 
21:46:55 T:3059708448   DEBUG: Compiled
367 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
368 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x2c027c0
369 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
370 
21:46:55 T:3059708448   DEBUG: Compiled
371 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
372 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
373 
21:46:55 T:3059708448   DEBUG: Compiled
374 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
375 
21:46:55 T:2980050000   DEBUG: CecLogMessage - >> POLL not sent
376 
21:46:55 T:2980050000   DEBUG: CecLogMessage - using logical address 'Playback 1'
377 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): device status changed into 'handled by libCEC'
378 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): power status changed from 'unknown' to 'on'
379 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): vendor = Pulse Eight (001582)
380 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): CEC version 1.4
381 
21:46:55 T:2980050000   DEBUG: CecLogMessage - AllocateLogicalAddresses - device '0', type 'playback device', LA '4'
382 
21:46:55 T:3013604432   DEBUG: CecLogMessage - logical address changed to Playback 1 (4)
383 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): osd name set to 'XBMC'
384 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): menu language set to 'eng'
385 
21:46:55 T:2980050000   DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 3000
386 
21:46:55 T:2980050000   DEBUG: CecLogMessage - AutodetectPhysicalAddress - autodetected physical address '3000'
387 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): physical address changed from ffff to 3000
388 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): physical adddress 3000
389 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << 4f:84:30:00:04
390 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x2c0acc8
391 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
392 
21:46:55 T:3059708448   DEBUG: Compiled
393 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
394 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
395 
21:46:55 T:3059708448   DEBUG: Compiled
396 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
397 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x2c0a0f8
398 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
399 
21:46:55 T:3059708448   DEBUG: Compiled
400 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
401 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
402 
21:46:55 T:3059708448   DEBUG: Compiled
403 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
404 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x2c0a1b0
405 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
406 
21:46:55 T:3059708448   DEBUG: Compiled
407 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
408 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
409 
21:46:55 T:3059708448   DEBUG: Compiled
410 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
411 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x2b43cf0
412 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
413 
21:46:55 T:3059708448   DEBUG: Compiled
414 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
415 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
416 
21:46:55 T:3059708448   DEBUG: Compiled
417 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
418 
21:46:55 T:2980050000   DEBUG: CecLogMessage - making Playback 1 (4) the active source
419 
21:46:55 T:2980050000   DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
420 
21:46:55 T:2980050000   DEBUG: CecLogMessage - >> source activated: Playback 1 (4)
421 
21:46:55 T:2980050000   DEBUG: CecLogMessage - scheduling active source message for 'Playback 1'
422 
21:46:55 T:2980050000   DEBUG: CecLogMessage - CEC client registered: libCEC version = 2.1.4, client version = 2.1.4, firmware version = 1, logical address(es) = Playback 1 (4) , physical address: 3.0.0.0, host: armv6zk-openelec-linux-gnueabi, features: 'P8 USB' 'P8 USB detect' 'RPi', git revision: d8f20f6, compiled on: Sat Oct  4 07:39:24 UTC 2014 by [email protected] on Linux 3.15.10-200.fc20.x86_64 (x86_64)
423 
21:46:55 T:2980050000   DEBUG: CecLogMessage - Playback 1 (4): vendor = Panasonic (008045)
424 
21:46:55 T:2980050000   DEBUG: CecLogMessage - replacing the command handler for device 'Playback 1' (4)
425 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): OSD name 'XBMC'
426 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << 40:47:58:42:4d:43
427 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x2b43de0
428 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
429 
21:46:55 T:3059708448   DEBUG: Compiled
430 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
431 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
432 
21:46:55 T:3059708448   DEBUG: Compiled
433 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
434 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x2c03698
435 
21:46:55 T:3059708448   DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES
436 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
437 
21:46:55 T:3059708448   DEBUG: Compiled
438 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
439 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
440 
21:46:55 T:3059708448   DEBUG: Compiled
441 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
442 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x2c09ca8
443 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compilation log:
444 
21:46:55 T:3059708448   DEBUG: Compiled
445 
21:46:55 T:3059708448   DEBUG: GL: Vertex Shader compiled successfully
446 
21:46:55 T:3059708448   DEBUG: GL: Pixel Shader compilation log:
447 
21:46:55 T:3059708448   DEBUG: Compiled
448 
21:46:55 T:3059708448   DEBUG: GL: Fragment Shader compiled successfully
449 
21:46:55 T:3059708448   DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x2c121e8
450 
21:46:55 T:3059708448   DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
451 
21:46:55 T:3059708448    INFO: GLES: Maximum texture width: 2048
452 
21:46:55 T:3059708448   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
453 
21:46:55 T:3059708448    INFO: load default splash image: /usr/share/xbmc/media/Splash.png
454 
21:46:55 T:3059708448   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
455 
21:46:55 T:3059708448   DEBUG: Loading: /usr/lib/xbmc/system/ImageLib-arm.so
456 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << requesting power status of 'TV' (0)
457 
21:46:55 T:2980050000   DEBUG: CecLogMessage - << 40:8f
458 
21:46:55 T:2963272784   DEBUG: CecLogMessage - >> 04:00:47:00
459 
21:46:55 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0)
460 
21:46:55 T:2963272784   DEBUG: CecLogMessage - marking opcode 'set osd name' as unsupported feature for device 'TV'
461 
21:46:55 T:3059708448    INFO: load keymapping
462 
21:46:55 T:3059708448    INFO: Loading special://xbmc/system/keymaps/appcommand.xml
463 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/gamepad.xml
464 
21:46:56 T:2963272784   DEBUG: CecLogMessage - >> 04:90:00
465 
21:46:56 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): report power status (90)
466 
21:46:56 T:2963272784   DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
467 
21:46:56 T:2980050000   DEBUG: CecLogMessage - expected response received (90: report power status)
468 
21:46:56 T:2980050000   DEBUG: OpenConnection - connection to the CEC adapter opened
469 
21:46:56 T:2936968272  NOTICE: Thread CECAdapterUpdate start, auto delete: false
470 
21:46:56 T:2936968272   DEBUG: CecLogMessage - Playback 1 (4) was already marked as active source
471 
21:46:56 T:2936968272   DEBUG: CecLogMessage - >> source activated: Playback 1 (4)
472 
21:46:56 T:2936968272   DEBUG: CecLogMessage - sending active source message for 'Playback 1'
473 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32
474 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
475 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
476 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
477 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
478 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
479 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
480 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
481 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << powering on 'TV' (0)
482 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << 40:04
483 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Ouya.Controller.xml
484 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
485 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (3000)
486 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << 4f:82:30:00
487 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.PS4.Controller.xml
488 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
489 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
490 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/joystick.xml
491 
21:46:56 T:3059708448  NOTICE: No Joystick name specified, loading default map
492 
21:46:56 T:2936968272  NOTICE: Previous line repeats 33 times.
493 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated'
494 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << 40:8e:00
495 
21:46:56 T:3059708448  NOTICE: No Joystick name specified, loading default map
496 
21:46:56 T:3059708448  NOTICE: Previous line repeats 1 times.
497 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/keyboard.xml
498 
21:46:56 T:2936968272   DEBUG: UpdateMenuLanguage - requesting the menu language of the TV
499 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << requesting menu language of 'TV' (0)
500 
21:46:56 T:2936968272   DEBUG: CecLogMessage - << 40:91
501 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/mouse.xml
502 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/remote.xml
503 
21:46:56 T:2963272784   DEBUG: CecLogMessage - >> 04:8c
504 
21:46:56 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C)
505 
21:46:56 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045)
506 
21:46:56 T:2963272784   DEBUG: CecLogMessage - << 4f:87:00:80:45
507 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
508 
21:46:56 T:3059708448    INFO: Loading special://xbmc/system/Lircmap.xml
509 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'mceusb'
510 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'XboxDVDDongle'
511 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'Microsoft_Xbox'
512 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
513 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'anysee'
514 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'iMON-PAD'
515 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
516 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'MCE_via_iMON'
517 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'TwinHanRemote'
518 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'linux-input-layer'
519 
21:46:56 T:3059708448    INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
520 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'mediacenter'
521 
21:46:56 T:3059708448    INFO: * Adding remote mapping for device 'devinput'
522 
21:46:56 T:3059708448   DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
523 
21:46:56 T:3059708448    INFO: GUI format 1920x1080, Display 1920x1080 (1920x1080) @ 50.00 - Full Screen
524 
21:46:56 T:3059708448   DEBUG: guilib: Fill viewport on change for solving rendering passes
525 
21:46:56 T:3059708448   DEBUG: SECTION:LoadDLL(libcurl.so.4)
526 
21:46:56 T:3059708448   DEBUG: Loading: libcurl.so.4
527 
21:46:57 T:3059708448  NOTICE: Running database version Addons16
528 
21:46:57 T:3059708448   DEBUG: Initialize, updating databases...
529 
21:46:57 T:3059708448  NOTICE: Running database version ViewModes6
530 
21:46:57 T:3059708448  NOTICE: Running database version Textures13
531 
21:46:57 T:3059708448  NOTICE: Running database version MyMusic46
532 
21:46:57 T:2963272784   DEBUG: CecLogMessage - >> 04:89:10:01:05
533 
21:46:57 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89)
534 
21:46:57 T:2963272784   DEBUG: CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32
535 
21:46:57 T:3059708448  NOTICE: Running database version MyVideos78
536 
21:46:57 T:3059708448  NOTICE: Running database version TV22
537 
21:46:57 T:2963272784   DEBUG: CecLogMessage - Playback 1 (4) was already marked as active source
538 
21:46:57 T:2963272784   DEBUG: CecLogMessage - >> source activated: Playback 1 (4)
539 
21:46:57 T:2963272784   DEBUG: CecLogMessage - scheduling active source message for 'Playback 1'
540 
21:46:57 T:2936968272   DEBUG: CecLogMessage - expected response not received (32: set menu language)
541 
21:46:57 T:2936968272   DEBUG: CecLogMessage - << 40:91
542 
21:46:57 T:2963272784   DEBUG: CecLogMessage - >> 04:a0:00:80:45:06:05
543 
21:46:57 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
544 
21:46:57 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (3000)
545 
21:46:57 T:2963272784   DEBUG: CecLogMessage - << 4f:82:30:00
546 
21:46:57 T:3059708448  NOTICE: Running database version Epg7
547 
21:46:57 T:3059708448   DEBUG: Initialize, updating databases... DONE
548 
21:46:57 T:3059708448  NOTICE: start dvd mediatype detection
549 
21:46:57 T:3059708448    INFO: DPMS: not supported on this platform
550 
21:46:57 T:3039941712  NOTICE: Thread DetectDVDMedia start, auto delete: false
551 
21:46:57 T:3039941712   DEBUG: Compiled with libcdio Version 0.92
552 
21:46:57 T:3039941712   DEBUG: Thread DetectDVDMedia 3039941712 terminating
553 
21:46:58 T:3059708448   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
554 
21:46:58 T:3059708448   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
555 
21:46:58 T:3059708448    INFO: Unloading old skin ...
556 
21:46:58 T:3059708448    INFO:   load skin from: /usr/share/xbmc/addons/skin.confluence (version: 2.2.20)
557 
21:46:58 T:3059708448    INFO:   load fonts for skin...
558 
21:46:58 T:3059708448    INFO: Loading fonts from /usr/share/xbmc/addons/skin.confluence/720p/Font.xml
559 
21:46:58 T:2963272784   DEBUG: CecLogMessage - >> 0f:32:65:6e:67
560 
21:46:58 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set menu language (32)
561 
21:46:58 T:2963272784   DEBUG: CecLogMessage - TV (0): menu language set to 'eng'
562 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << powering on 'TV' (0)
563 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << 40:04
564 
21:46:58 T:2936968272   DEBUG: CecLogMessage - expected response received (32: set menu language)
565 
21:46:58 T:2936968272   DEBUG: SetMenuLanguage - language set to 'English'
566 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (3000)
567 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << 4f:82:30:00
568 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated'
569 
21:46:58 T:2963272784   DEBUG: CecLogMessage - << 40:8e:00
570 
21:46:58 T:3059708448   DEBUG: POParser: loaded 148 strings from file /usr/share/xbmc/addons/skin.confluence/language/English/strings.po
571 
21:46:58 T:3059708448    INFO: Loading skin includes from /usr/share/xbmc/addons/skin.confluence/720p/includes.xml
572 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Recorder 1 (1): POLL
573 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << 41
574 
21:46:58 T:2936968272   DEBUG: Previous line repeats 1 times.
575 
21:46:58 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
576 
21:46:58 T:2936968272   DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'not present'
577 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Recorder 2 (2): POLL
578 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << 42
579 
21:46:58 T:2936968272   DEBUG: Previous line repeats 1 times.
580 
21:46:58 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
581 
21:46:58 T:2936968272   DEBUG: CecLogMessage - Recorder 2 (2): device status changed into 'not present'
582 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Tuner 1 (3): POLL
583 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << 43
584 
21:46:58 T:2936968272   DEBUG: Previous line repeats 1 times.
585 
21:46:58 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
586 
21:46:58 T:2936968272   DEBUG: CecLogMessage - Tuner 1 (3): device status changed into 'not present'
587 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Audio (5): POLL
588 
21:46:58 T:2936968272   DEBUG: CecLogMessage - << 45
589 
21:46:59 T:2963272784   DEBUG: Previous line repeats 1 times.
590 
21:46:59 T:2963272784   DEBUG: CecLogMessage - >> 04:a0:00:80:45:06:05
591 
21:46:59 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
592 
21:46:59 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
593 
21:46:59 T:2936968272   DEBUG: CecLogMessage - Audio (5): device status changed into 'not present'
594 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Tuner 2 (6): POLL
595 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << 46
596 
21:46:59 T:2936968272   DEBUG: Previous line repeats 1 times.
597 
21:46:59 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
598 
21:46:59 T:2936968272   DEBUG: CecLogMessage - Tuner 2 (6): device status changed into 'not present'
599 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Tuner 3 (7): POLL
600 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << 47
601 
21:46:59 T:2936968272   DEBUG: Previous line repeats 1 times.
602 
21:46:59 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
603 
21:46:59 T:2936968272   DEBUG: CecLogMessage - Tuner 3 (7): device status changed into 'not present'
604 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Playback 2 (8): POLL
605 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << 48
606 
21:46:59 T:3059708448   DEBUG: Previous line repeats 1 times.
607 
21:46:59 T:3059708448    INFO:   load new skin...
608 
21:46:59 T:3059708448    INFO: Loading user windows, path /usr/share/xbmc/addons/skin.confluence/720p
609 
21:46:59 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
610 
21:46:59 T:2936968272   DEBUG: CecLogMessage - Playback 2 (8): device status changed into 'not present'
611 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Recorder 3 (9): POLL
612 
21:46:59 T:2936968272   DEBUG: CecLogMessage - << 49
613 
21:46:59 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (3000)
614 
21:46:59 T:2963272784   DEBUG: CecLogMessage - << 4f:82:30:00
615 
21:46:59 T:3059708448   DEBUG: Load Skin XML: 59.74ms
616 
21:46:59 T:3059708448    INFO:   initialize new skin...
617 
21:46:59 T:3059708448   DEBUG: guilib: Fill viewport on change for solving rendering passes
618 
21:46:59 T:3059708448    INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
619 
21:46:59 T:3059708448   DEBUG: OpenBundle - Opened bundle /usr/share/xbmc/addons/skin.confluence/media/Textures.xbt
620 
21:47:00 T:3059708448    INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
621 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 49
622 
21:47:00 T:3059708448    INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
623 
21:47:00 T:3059708448    INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
624 
21:47:00 T:3059708448    INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
625 
21:47:00 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
626 
21:47:00 T:2936968272   DEBUG: CecLogMessage - Recorder 3 (9): device status changed into 'not present'
627 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Tuner 4 (A): POLL
628 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4a
629 
21:47:00 T:2936968272   DEBUG: Previous line repeats 1 times.
630 
21:47:00 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
631 
21:47:00 T:2936968272   DEBUG: CecLogMessage - Tuner 4 (A): device status changed into 'not present'
632 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Playback 3 (B): POLL
633 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4b
634 
21:47:00 T:3059708448    INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
635 
21:47:00 T:3059708448    INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
636 
21:47:00 T:3059708448    INFO: Loading /usr/share/xbmc/addons/skin.confluence/sounds/sounds.xml
637 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4b
638 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
639 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 44
640 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 1402
641 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
642 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:2->2 rate:22050->44100 format:0->3 bits:0->32 norm:1 upmix:0
643 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    1.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
644 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   1.00   0.00   0.00   0.00   0.00   0.00   0.00
645 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
646 
21:47:00 T:3059692624    INFO: Previous line repeats 5 times.
647 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c2ce88
648 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
649 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 44
650 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
651 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
652 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 9842
653 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
654 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
655 
21:47:00 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
656 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 44
657 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 4572
658 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
659 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 44
660 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:4
661 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:3
662 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:2
663 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:1
664 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c2ce88
665 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
666 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:2->2 rate:22050->44100 format:0->3 bits:0->32 norm:1 upmix:0
667 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    1.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
668 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   1.00   0.00   0.00   0.00   0.00   0.00   0.00
669 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
670 
21:47:00 T:2936968272    INFO: Previous line repeats 5 times.
671 
21:47:00 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
672 
21:47:00 T:2936968272   DEBUG: CecLogMessage - Playback 3 (B): device status changed into 'not present'
673 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Reserved 1 (C): POLL
674 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4c
675 
21:47:00 T:2963272784   DEBUG: CecLogMessage - >> 04:a0:00:80:45:06:05
676 
21:47:00 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
677 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c00660
678 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probed stream 0
679 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
680 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
681 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 20780
682 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
683 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
684 
21:47:00 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
685 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 46
686 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:4
687 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:3
688 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:2
689 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:1
690 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4c
691 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probed stream 0
692 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c00660
693 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
694 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:1->2 rate:22050->44100 format:0->3 bits:0->32 norm:1 upmix:0
695 
21:47:00 T:3059692624   DEBUG: ffmpeg[B65F3450]: 0.707107
696 
21:47:00 T:3059692624   DEBUG: Previous line repeats 1 times.
697 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.71   0.00   0.00   0.00   0.00   0.00   0.00   0.00
698 
21:47:00 T:3059692624    INFO: Previous line repeats 1 times.
699 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
700 
21:47:00 T:3059692624    INFO: Previous line repeats 5 times.
701 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c2dcd8
702 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 43822
703 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
704 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
705 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] Format wav probed with size=2048 and score=99
706 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position before avformat_find_stream_info() is 46
707 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:4
708 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
709 
21:47:00 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
710 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:3
711 
21:47:00 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
712 
21:47:00 T:2936968272   DEBUG: CecLogMessage - Reserved 1 (C): device status changed into 'not present'
713 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Reserved 2 (D): POLL
714 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4d
715 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:2
716 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probing stream 0 pp:1
717 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] probed stream 0
718 
21:47:00 T:3059708448   DEBUG: ffmpeg[B65F7220]: [wav] File position after avformat_find_stream_info() is 18478
719 
21:47:00 T:3059708448    INFO:   skin loaded...
720 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c2dcd8
721 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
722 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:2->2 rate:22050->44100 format:1->3 bits:0->32 norm:1 upmix:0
723 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    1.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
724 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   1.00   0.00   0.00   0.00   0.00   0.00   0.00
725 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
726 
21:47:00 T:3059692624    INFO: Previous line repeats 5 times.
727 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c43a48
728 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
729 
21:47:00 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
730 
21:47:00 T:2936968272   DEBUG: CecLogMessage - << 4d
731 
21:47:00 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
732 
21:47:00 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
733 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c43a48
734 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
735 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:1->2 rate:32000->44100 format:1->3 bits:0->32 norm:1 upmix:0
736 
21:47:01 T:3059692624   DEBUG: ffmpeg[B65F3450]: 0.707107
737 
21:47:01 T:3059692624   DEBUG: Previous line repeats 1 times.
738 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init    0.71   0.00   0.00   0.00   0.00   0.00   0.00   0.00
739 
21:47:01 T:3059692624    INFO: Previous line repeats 1 times.
740 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
741 
21:47:01 T:2936968272    INFO: Previous line repeats 5 times.
742 
21:47:01 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
743 
21:47:01 T:2936968272   DEBUG: CecLogMessage - Reserved 2 (D): device status changed into 'not present'
744 
21:47:01 T:2936968272   DEBUG: CecLogMessage - << Playback 1 (4) -> Free use (E): POLL
745 
21:47:01 T:2936968272   DEBUG: CecLogMessage - << 4e
746 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c2dbd8
747 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
748 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
749 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
750 
21:47:01 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
751 
21:47:01 T:2936968272   DEBUG: CecLogMessage - << 4e
752 
21:47:01 T:3059708448   DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
753 
21:47:01 T:3059708448 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
754 
21:47:01 T:3059708448    INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
755 
21:47:01 T:3059708448    INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
756 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c2dbd8
757 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::CActiveAEResample
758 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init remap:(nil) chan:1->2 rate:44100->44100 format:1->3 bits:0->32 norm:1 upmix:0
759 
21:47:01 T:3059692624   DEBUG: ffmpeg[B65F3450]: 0.707107
760 
21:47:01 T:3059692624   DEBUG: Previous line repeats 1 times.
761 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init    0.71   0.00   0.00   0.00   0.00   0.00   0.00   0.00
762 
21:47:01 T:3059692624    INFO: Previous line repeats 1 times.
763 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::Init    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
764 
21:47:01 T:3059692624    INFO: Previous line repeats 5 times.
765 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb5c2dc98
766 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_mixer) - port(232), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288), nBufferAlignmen(32)
767 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.audio_mixer) - port(231), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(524288) nBufferAlignmen(32)
768 
21:47:01 T:3059708448   DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
769 
21:47:01 T:3059708448 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
770 
21:47:01 T:3059708448    INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
771 
21:47:01 T:3059708448    INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
772 
21:47:01 T:2936968272   DEBUG: CecLogMessage - >> POLL not sent
773 
21:47:01 T:2936968272   DEBUG: CecLogMessage - Free use (E): device status changed into 'not present'
774 
21:47:01 T:2936968272   DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found
775 
21:47:01 T:2963272784   DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (3000)
776 
21:47:01 T:2963272784   DEBUG: CecLogMessage - << 4f:82:30:00
777 
21:47:01 T:3059692624    INFO: CActiveAEResamplePi::~CActiveAEResample
778 
21:47:01 T:3059692624   DEBUG: CActiveAEResamplePi:DeInit
779 
21:47:01 T:3059692624   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0xb5c2dc98
780 
21:47:01 T:3059708448    INFO: JSONRPC v6.14.3: Successfully initialized
781 
21:47:01 T:3059708448   DEBUG: ADDON: Starting service addons.
782 
21:47:01 T:3059708448   DEBUG: Activating window ID: 12999
783 
21:47:01 T:3059708448   DEBUG: ------ Window Init (Startup.xml) ------
784 
21:47:01 T:3059708448    INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
785 
21:47:01 T:2918130768  NOTICE: Thread JobWorker start, auto delete: true
786 
21:47:01 T:2926519376  NOTICE: Thread LanguageInvoker start, auto delete: false
787 
21:47:01 T:2926519376    INFO: initializing python engine.
788 
21:47:01 T:2918130768  NOTICE: PVRManager - starting up
789 
21:47:01 T:2909742160  NOTICE: Thread PVRManager start, auto delete: false
790 
21:47:01 T:3059708448    INFO: removing tempfiles
791 
21:47:01 T:2901353552  NOTICE: Thread PVRClient start, auto delete: false
792 
21:47:01 T:3059708448   DEBUG: ADDON: Starting service addons.
793 
21:47:01 T:3059708448  NOTICE: initialize done
794 
21:47:01 T:3059708448  NOTICE: Running the application...
795 
21:47:01 T:2890921040  NOTICE: Thread LanguageInvoker start, auto delete: false
796 
21:47:01 T:2890921040    INFO: initializing python engine.
797 
21:47:01 T:2882532432  NOTICE: Thread LanguageInvoker start, auto delete: false
798 
21:47:01 T:2882532432    INFO: initializing python engine.
799 
21:47:01 T:3059708448   DEBUG: Activating window ID: 10000
800 
21:47:01 T:2901353552   DEBUG: RegisterClient - registering add-on 'Tvheadend HTSP Client'
801 
21:47:01 T:2901353552   DEBUG: PVR - Create - creating PVR add-on instance 'Tvheadend HTSP Client'
802 
21:47:01 T:2901353552   DEBUG: ADDON: Dll Initializing - Tvheadend HTSP Client
803 
21:47:01 T:2901353552   DEBUG: SECTION:LoadDLL(/usr/lib/xbmc/addons/pvr.hts/XBMC_Tvheadend.pvr)
804 
21:47:01 T:2901353552   DEBUG: Loading: /usr/lib/xbmc/addons/pvr.hts/XBMC_Tvheadend.pvr
805 
21:47:01 T:3059708448   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x344a9f8
806 
21:47:01 T:3059708448   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(125120), nBufferAlignmen(16)
807 
21:47:01 T:3059708448   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61 m_handle 0x34634f8
808 
21:47:01 T:3059708448   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x344c410
809 
21:47:01 T:3059708448   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
810 
21:47:01 T:2901353552   DEBUG: AddOnLog: Tvheadend HTSP Client: ADDON_Create - Creating Tvheadend PVR-Client
811 
21:47:01 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'host'
812 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'user'
813 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'pass'
814 
21:47:02 T:3059708448   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x344a9f8
815 
21:47:02 T:3059708448   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x34634f8
816 
21:47:02 T:3059708448   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x344c410
817 
21:47:02 T:3059708448   DEBUG: ------ Window Init () ------
818 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'htsp_port'
819 
21:47:02 T:3059708448   DEBUG: ------ Window Init (Pointer.xml) ------
820 
21:47:02 T:3059708448    INFO: GLES: Enabling VSYNC
821 
21:47:02 T:3059708448    INFO: GLES: Selected vsync mode 10
822 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'http_port'
823 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'connect_timeout'
824 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'response_timeout'
825 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'transcode'
826 
21:47:02 T:3059708448   DEBUG: ------ Window Deinit (Startup.xml) ------
827 
21:47:02 T:3059708448   DEBUG: ------ Window Init (Home.xml) ------
828 
21:47:02 T:3059708448    INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
829 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'audio_codec_name'
830 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'video_codec_name'
831 
21:47:02 T:2901353552   DEBUG: CAddonCallbacksAddon - GetAddonSetting - add-on 'Tvheadend HTSP Client' requests setting 'resolution'
832 
21:47:02 T:2901353552   DEBUG: AddOnLog: Tvheadend HTSP Client: OpenSocket - connecting to '192.168.1.10', port '9982'
833 
21:47:02 T:2901353552   DEBUG: AddOnLog: Tvheadend HTSP Client: OpenSocket - connected to '192.168.1.10', port '9982'
834 
21:47:02 T:2901353552  NOTICE: AddOnLog: Tvheadend HTSP Client: CHTSPConnection - SendGreeting - connection opened to 'HTS Tvheadend 3.4.28~geb79aee~trusty', protocol v10
835 
21:47:02 T:2901353552   DEBUG: AddOnLog: Tvheadend HTSP Client: CHTSPConnection - Auth - authenticating as user 'schusch' with a password
836 
21:47:03 T:2909742160   DEBUG: PVRManager - Load - active clients found. continue to start
837 
21:47:03 T:2890921040   DEBUG: CPythonInvoker(1, /usr/share/xbmc/addons/service.openelec.settings/service.py): start processing
838 
21:47:03 T:2882532432   DEBUG: CPythonInvoker(2, /storage/.xbmc/addons/script.common.plugin.cache/default.py): start processing
839 
21:47:03 T:2926519376   DEBUG: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): start processing
840 
21:47:04 T:2918130768   DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
841 
21:47:05 T:2918130768   DEBUG: GetRecentlyAddedAlbumSongs() query: SELECT songview.* FROM (SELECT idAlbum FROM albumview ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN songview ON songview.idAlbum=recentalbums.idAlbum
842 
21:47:05 T:2918130768   DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
843 
21:47:05 T:2918130768   DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
844 
21:47:05 T:3059708448   DEBUG: no profile autoexec.py (/storage/.xbmc/userdata/autoexec.py) found, skipping
845 
21:47:05 T:3059708448   DEBUG: NetworkMessage - Starting network services
846 
21:47:05 T:3059708448   DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
847 
21:47:05 T:2909742160   DEBUG: PVR - Load - loading all radio channel groups
848 
21:47:05 T:3059708448   DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
849 
21:47:05 T:3059708448   DEBUG: ------ Window Init (DialogKaiToast.xml) ------
850 
21:47:05 T:3039941712  NOTICE: Thread RSSReader start, auto delete: false
851 
21:47:05 T:3039941712   DEBUG: CurlFile::Open(0xb531ccf8) http://feeds.openelec.tv/news
852 
21:47:05 T:3039941712    INFO: easy_aquire - Created session to http://feeds.openelec.tv
853 
21:47:05 T:3039941712   DEBUG: Curl::Debug - TEXT: Hostname was NOT found in DNS cache
854 
21:47:05 T:3059708448   DEBUG: ------ Window Deinit (Pointer.xml) ------
855 
21:47:06 T:2918130768   DEBUG: RunQuery took 45 ms for 0 items query: select * from movieview  ORDER BY dateAdded desc, idMovie desc LIMIT 10
856 
21:47:06 T:2918130768   DEBUG: RunQuery took 45 ms for 0 items query: select * from episodeview  ORDER BY dateAdded desc, idEpisode desc LIMIT 10
857 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT:   Trying 82.220.2.33...
858 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.openelec.tv (82.220.2.33) port 80 (#0)
859 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /news HTTP/1.1
860 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
861 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
862 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.openelec.tv
863 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
864 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
865 
21:47:06 T:2918130768   DEBUG: RunQuery took 27 ms for 0 items query: select * from musicvideoview  ORDER BY dateAdded desc, idMVideo desc LIMIT 10
866 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 301 Moved Permanently
867 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:06 GMT
868 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Server Apache is not blacklisted
869 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: Apache
870 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Location: http://feeds.openelec.tv/news/
871 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Vary: Accept-Encoding
872 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Length: 238
873 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Connection: close
874 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/html; charset=iso-8859-1
875 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Closing connection 0
876 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Issue another request to this URL: 'http://feeds.openelec.tv/news/'
877 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Hostname was found in DNS cache
878 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT:   Trying 82.220.2.33...
879 
21:47:06 T:2918130768   DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
880 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.openelec.tv (82.220.2.33) port 80 (#1)
881 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /news/ HTTP/1.1
882 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
883 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
884 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.openelec.tv
885 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
886 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Referer: http://feeds.openelec.tv/news
887 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
888 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 301 Moved Permanently
889 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:06 GMT
890 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Server Apache is not blacklisted
891 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: Apache
892 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Location: http://feeds.feedburner.com/OpenELEC-News
893 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Vary: Accept-Encoding
894 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Length: 249
895 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Connection: close
896 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/html; charset=iso-8859-1
897 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Closing connection 1
898 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Issue another request to this URL: 'http://feeds.feedburner.com/OpenELEC-News'
899 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Hostname was NOT found in DNS cache
900 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT:   Trying 85.239.127.123...
901 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.feedburner.com (85.239.127.123) port 80 (#2)
902 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /OpenELEC-News HTTP/1.1
903 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
904 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
905 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.feedburner.com
906 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
907 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Referer: http://feeds.openelec.tv/news/
908 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
909 
21:47:06 T:2909742160   DEBUG: PVRChannelGroup - Load - 87 channels loaded from the database for group 'All radio channels'
910 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 200 OK
911 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/xml; charset=UTF-8
912 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: ETag: pTJl5Bij/NvZrOfNil+86t3pPUI
913 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Last-Modified: Wed, 15 Oct 2014 19:18:06 GMT
914 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:06 GMT
915 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Expires: Wed, 15 Oct 2014 19:47:06 GMT
916 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: private, max-age=0
917 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-Content-Type-Options: nosniff
918 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-XSS-Protection: 1; mode=block
919 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Server GSE is not blacklisted
920 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: GSE
921 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Alternate-Protocol: 80:quic,p=0.002
922 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Transfer-Encoding: chunked
923 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Connection #2 to host feeds.feedburner.com left intact
924 
21:47:06 T:3039941712   DEBUG: Got rss feed: http://feeds.openelec.tv/news
925 
21:47:06 T:3039941712   DEBUG: RSS feed encoding: UTF-8
926 
21:47:06 T:2909742160   DEBUG: PVR - Get - group 'All radio channels' loaded from the database
927 
21:47:06 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - 0 user defined radio channel groups fetched from the database
928 
21:47:06 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - 0 new user defined radio channel groups fetched from clients
929 
21:47:06 T:2909742160   DEBUG: PVR - PersistAll - persisting all changes in channel groups
930 
21:47:06 T:2909742160   DEBUG: PVR - Load - 1 radio channel groups loaded
931 
21:47:06 T:2909742160   DEBUG: PVR - Load - loading all TV channel groups
932 
21:47:06 T:3039941712   DEBUG: Parsed rss feed: http://feeds.openelec.tv/news
933 
21:47:06 T:3039941712   DEBUG: CurlFile::Open(0xb531ccf8) http://feeds.xbmc.org/xbmc
934 
21:47:06 T:3039941712    INFO: easy_aquire - Created session to http://feeds.xbmc.org
935 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Hostname was NOT found in DNS cache
936 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT:   Trying 173.194.65.121...
937 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.xbmc.org (173.194.65.121) port 80 (#0)
938 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /xbmc HTTP/1.1
939 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
940 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
941 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.xbmc.org
942 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
943 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
944 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 200 OK
945 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/xml; charset=UTF-8
946 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: ETag: E3GF0Y6aVoW3dq1iB83cyzGKoRc
947 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Last-Modified: Wed, 15 Oct 2014 19:35:49 GMT
948 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:06 GMT
949 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Expires: Wed, 15 Oct 2014 19:47:06 GMT
950 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: private, max-age=0
951 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-Content-Type-Options: nosniff
952 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-XSS-Protection: 1; mode=block
953 
21:47:06 T:3039941712   DEBUG: Curl::Debug - TEXT: Server GSE is not blacklisted
954 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: GSE
955 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Alternate-Protocol: 80:quic,p=0.01,80:quic,p=0.01
956 
21:47:06 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Transfer-Encoding: chunked
957 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Connection #0 to host feeds.xbmc.org left intact
958 
21:47:07 T:3039941712   DEBUG: Got rss feed: http://feeds.xbmc.org/xbmc
959 
21:47:07 T:3039941712   DEBUG: RSS feed encoding: UTF-8
960 
21:47:07 T:3039941712   DEBUG: Parsed rss feed: http://feeds.xbmc.org/xbmc
961 
21:47:07 T:3039941712   DEBUG: CurlFile::Open(0xb531ccf8) http://feeds.xbmc.org/latest_xbmc_addons
962 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Found bundle for host feeds.xbmc.org: 0xaab44030
963 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Re-using existing connection! (#0) with host feeds.xbmc.org
964 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.xbmc.org (173.194.65.121) port 80 (#0)
965 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /latest_xbmc_addons HTTP/1.1
966 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
967 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
968 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.xbmc.org
969 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
970 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
971 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 200 OK
972 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/xml; charset=UTF-8
973 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: ETag: wvuzx/TO0EybcOEAPiikAyQeH4E
974 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Last-Modified: Wed, 15 Oct 2014 19:45:49 GMT
975 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:07 GMT
976 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Expires: Wed, 15 Oct 2014 19:47:07 GMT
977 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: private, max-age=0
978 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-Content-Type-Options: nosniff
979 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-XSS-Protection: 1; mode=block
980 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Server GSE is not blacklisted
981 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: GSE
982 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Alternate-Protocol: 80:quic,p=0.01,80:quic,p=0.01
983 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Transfer-Encoding: chunked
984 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Connection #0 to host feeds.xbmc.org left intact
985 
21:47:07 T:3039941712   DEBUG: Got rss feed: http://feeds.xbmc.org/latest_xbmc_addons
986 
21:47:07 T:3039941712   DEBUG: RSS feed encoding: UTF-8
987 
21:47:07 T:3039941712   DEBUG: Parsed rss feed: http://feeds.xbmc.org/latest_xbmc_addons
988 
21:47:07 T:3039941712   DEBUG: CurlFile::Open(0xb531ccf8) http://feeds.xbmc.org/updated_xbmc_addons
989 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Found bundle for host feeds.xbmc.org: 0xaab44030
990 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Re-using existing connection! (#0) with host feeds.xbmc.org
991 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Connected to feeds.xbmc.org (173.194.65.121) port 80 (#0)
992 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: GET /updated_xbmc_addons HTTP/1.1
993 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Range: bytes=0-
994 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: User-Agent: XBMC/13.2 Git:0f3db05 (Linux; OpenELEC (official) - Version: 4.2.1; 3.16.3 armv6l; http://xbmc.org)
995 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Host: feeds.xbmc.org
996 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept: */*
997 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_OUT: Accept-Charset: UTF-8,*;q=0.8
998 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: HTTP/1.1 200 OK
999 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Content-Type: text/xml; charset=UTF-8
1000 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: ETag: 7VQvmdh4+H+hiuZqa3/VAWYN67c
1001 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Last-Modified: Wed, 15 Oct 2014 19:25:38 GMT
1002 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Date: Wed, 15 Oct 2014 19:47:07 GMT
1003 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Expires: Wed, 15 Oct 2014 19:47:07 GMT
1004 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Cache-Control: private, max-age=0
1005 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-Content-Type-Options: nosniff
1006 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: X-XSS-Protection: 1; mode=block
1007 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Server GSE is not blacklisted
1008 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Server: GSE
1009 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Alternate-Protocol: 80:quic,p=0.01,80:quic,p=0.01
1010 
21:47:07 T:3039941712   DEBUG: Curl::Debug - HEADER_IN: Transfer-Encoding: chunked
1011 
21:47:07 T:3039941712   DEBUG: Curl::Debug - TEXT: Connection #0 to host feeds.xbmc.org left intact
1012 
21:47:07 T:3039941712   DEBUG: Got rss feed: http://feeds.xbmc.org/updated_xbmc_addons
1013 
21:47:07 T:2909742160   DEBUG: PVRChannelGroup - Load - 153 channels loaded from the database for group 'All TV channels'
1014 
21:47:07 T:3039941712   DEBUG: RSS feed encoding: UTF-8
1015 
21:47:07 T:3039941712   DEBUG: Parsed rss feed: http://feeds.xbmc.org/updated_xbmc_addons
1016 
21:47:07 T:3039941712   DEBUG: Thread RSSReader 3039941712 terminating
1017 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'All TV channels' loaded from the database
1018 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'TV channels' loaded from the database
1019 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'SDTV' loaded from the database
1020 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'ARD' loaded from the database
1021 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'HDTV' loaded from the database
1022 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'ZDFvision' loaded from the database
1023 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'ProSiebenSat.1' loaded from the database
1024 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'BetaDigital' loaded from the database
1025 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'SES ASTRA' loaded from the database
1026 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'MEDIA BROADCAST' loaded from the database
1027 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'Globecast' loaded from the database
1028 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'RTL World' loaded from the database
1029 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'RTL' loaded from the database
1030 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'MTV Networks Europe' loaded from the database
1031 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'Arqiva' loaded from the database
1032 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'BBC' loaded from the database
1033 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'CNBC' loaded from the database
1034 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'ServusTV' loaded from the database
1035 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'Eutelsat' loaded from the database
1036 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'RRSat' loaded from the database
1037 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'KabelKiosk' loaded from the database
1038 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'Telenorba' loaded from the database
1039 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'SmartCast' loaded from the database
1040 
21:47:08 T:2909742160   DEBUG: PVR - Get - group 'SKY' loaded from the database
1041 
21:47:08 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - 23 user defined TV channel groups fetched from the database
1042 
21:47:08 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - 10 new user defined TV channel groups fetched from clients
1043 
21:47:08 T:2909742160   DEBUG: PVRChannelGroup - Load - 153 channels loaded from the database for group 'TV channels'
1044 
21:47:08 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'TV channels' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1045 
21:47:08 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'TV channels'
1046 
21:47:08 T:2909742160   DEBUG: PVRChannelGroup - Load - 112 channels loaded from the database for group 'SDTV'
1047 
21:47:08 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SDTV' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1048 
21:47:08 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'SDTV'
1049 
21:47:08 T:2890921040  NOTICE: -->Python Interpreter Initialized<--
1050 
21:47:08 T:2890921040   DEBUG: CPythonInvoker(1, /usr/share/xbmc/addons/service.openelec.settings/service.py): the source file to load is "/usr/share/xbmc/addons/service.openelec.settings/service.py"
1051 
21:47:08 T:2882532432  NOTICE: -->Python Interpreter Initialized<--
1052 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 68 channels loaded from the database for group 'ARD'
1053 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1054 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD'
1055 
21:47:09 T:2882532432   DEBUG: CPythonInvoker(2, /storage/.xbmc/addons/script.common.plugin.cache/default.py): the source file to load is "/storage/.xbmc/addons/script.common.plugin.cache/default.py"
1056 
21:47:09 T:2890921040   DEBUG: CPythonInvoker(1, /usr/share/xbmc/addons/service.openelec.settings/service.py): setting the Python path to /usr/share/xbmc/addons/service.openelec.settings:/storage/.xbmc/addons/script.module.simplejson/lib:/storage/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/storage/.xbmc/addons/script.common.plugin.cache/lib:/storage/.xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/storage/.xbmc/addons/script.module.parsedom/lib:/storage/.xbmc/addons/script.module.elementtree/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/PIL:/usr/lib/python2.7/site-packages/gtk-2.0
1057 
21:47:09 T:2890921040   DEBUG: CPythonInvoker(1, /usr/share/xbmc/addons/service.openelec.settings/service.py): entering source directory /usr/share/xbmc/addons/service.openelec.settings
1058 
21:47:09 T:2890921040   DEBUG: CPythonInvoker(1, /usr/share/xbmc/addons/service.openelec.settings/service.py): instantiating addon using automatically obtained id of "service.openelec.settings" dependent on version 2.1.0 of the xbmc.python api
1059 
21:47:09 T:2926519376  NOTICE: -->Python Interpreter Initialized<--
1060 
21:47:09 T:2926519376   DEBUG: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): the source file to load is "/storage/.xbmc/addons/script.module.simple.downloader/default.py"
1061 
21:47:09 T:2882532432   DEBUG: CPythonInvoker(2, /storage/.xbmc/addons/script.common.plugin.cache/default.py): setting the Python path to /storage/.xbmc/addons/script.common.plugin.cache:/storage/.xbmc/addons/script.module.simplejson/lib:/storage/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/storage/.xbmc/addons/script.common.plugin.cache/lib:/storage/.xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/storage/.xbmc/addons/script.module.parsedom/lib:/storage/.xbmc/addons/script.module.elementtree/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/PIL:/usr/lib/python2.7/site-packages/gtk-2.0
1062 
21:47:09 T:2882532432   DEBUG: CPythonInvoker(2, /storage/.xbmc/addons/script.common.plugin.cache/default.py): entering source directory /storage/.xbmc/addons/script.common.plugin.cache
1063 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 41 channels loaded from the database for group 'HDTV'
1064 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'HDTV' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1065 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'HDTV'
1066 
21:47:09 T:2882532432   DEBUG: CPythonInvoker(2, /storage/.xbmc/addons/script.common.plugin.cache/default.py): instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.1.0 of the xbmc.python api
1067 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 12 channels loaded from the database for group 'ZDFvision'
1068 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ZDFvision' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1069 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ZDFvision'
1070 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 7 channels loaded from the database for group 'ProSiebenSat.1'
1071 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ProSiebenSat.1' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1072 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ProSiebenSat.1'
1073 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 33 channels loaded from the database for group 'BetaDigital'
1074 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'BetaDigital' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1075 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'BetaDigital'
1076 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 3 channels loaded from the database for group 'SES ASTRA'
1077 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SES ASTRA' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1078 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'SES ASTRA'
1079 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 5 channels loaded from the database for group 'MEDIA BROADCAST'
1080 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'MEDIA BROADCAST' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1081 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'MEDIA BROADCAST'
1082 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'Globecast'
1083 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Globecast' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1084 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'Globecast'
1085 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 6 channels loaded from the database for group 'RTL World'
1086 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'RTL World' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1087 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'RTL World'
1088 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'RTL'
1089 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'RTL' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1090 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'RTL'
1091 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 2 channels loaded from the database for group 'MTV Networks Europe'
1092 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'MTV Networks Europe' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1093 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'MTV Networks Europe'
1094 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'Arqiva'
1095 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Arqiva' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1096 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'Arqiva'
1097 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'BBC'
1098 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'BBC' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1099 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'BBC'
1100 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'CNBC'
1101 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'CNBC' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1102 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'CNBC'
1103 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'ServusTV'
1104 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ServusTV' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1105 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ServusTV'
1106 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 2 channels loaded from the database for group 'Eutelsat'
1107 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Eutelsat' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1108 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'Eutelsat'
1109 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 2 channels loaded from the database for group 'RRSat'
1110 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'RRSat' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1111 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'RRSat'
1112 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'KabelKiosk'
1113 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'KabelKiosk' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1114 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'KabelKiosk'
1115 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'Telenorba'
1116 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Telenorba' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1117 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'Telenorba'
1118 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 2 channels loaded from the database for group 'SmartCast'
1119 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SmartCast' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1120 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'SmartCast'
1121 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 1 channels loaded from the database for group 'SKY'
1122 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SKY' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1123 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'SKY'
1124 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD MDR'
1125 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD MDR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1126 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD MDR'
1127 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD HR'
1128 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD HR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1129 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD HR'
1130 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD BR'
1131 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD BR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1132 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD BR'
1133 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD WDR'
1134 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD WDR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1135 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD WDR'
1136 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD SWR'
1137 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD SWR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1138 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD SWR'
1139 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD SR'
1140 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD SR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1141 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD SR'
1142 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD rbb'
1143 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD rbb' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1144 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD rbb'
1145 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD RB'
1146 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD RB' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1147 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD RB'
1148 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'ARD NDR'
1149 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD NDR' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1150 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'ARD NDR'
1151 
21:47:09 T:2909742160   DEBUG: PVRChannelGroup - Load - 0 channels loaded from the database for group 'Eurociel'
1152 
21:47:09 T:2909742160   DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Eurociel' from add-on 'HTS Tvheadend:192.168.1.10:9982'
1153 
21:47:09 T:2909742160   DEBUG: AddOnLog: Tvheadend HTSP Client: GetChannelGroupMembers - group 'Eurociel'
1154 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD MDR'
1155 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD HR'
1156 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD BR'
1157 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD WDR'
1158 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD SWR'
1159 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD SR'
1160 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD rbb'
1161 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD RB'
1162 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'ARD NDR'
1163 
21:47:09 T:2909742160   DEBUG: PVR - LoadUserDefinedChannelGroups - deleting empty group 'Eurociel'
1164 
21:47:09 T:2909742160   DEBUG: PVR - PersistAll - persisting all changes in channel groups
1165 
21:47:09 T:2909742160   DEBUG: PVR - Load - 24 TV channel groups loaded
1166 
21:47:09 T:2909742160   DEBUG: CPVRTimers - Update - updating timers
1167 
21:47:09 T:2909742160   DEBUG: CPVRRecordings - Update - updating recordings
1168 
21:47:09 T:2862507088  NOTICE: Thread PVRGUIInfo start, auto delete: false
1169 
21:47:09 T:2909742160   DEBUG: PVRManager - Process - entering main loop
1170 
21:47:09 T:3059708448   DEBUG: ------ Window Init (DialogExtendedProgressBar.xml) ------
1171 
21:47:12 T:3059708448   DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
1172 
21:47:14 T:2890921040  NOTICE: ## OpenELEC Addon ## 0.3.20
1173 
21:47:14 T:2926519376   DEBUG: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): setting the Python path to /storage/.xbmc/addons/script.module.simple.downloader:/storage/.xbmc/addons/script.module.simplejson/lib:/storage/.xbmc/addons/script.module.beautifulsoup/lib:/usr/share/xbmc/addons/script.module.pil/lib:/storage/.xbmc/addons/script.common.plugin.cache/lib:/storage/.xbmc/addons/script.module.simple.downloader/lib:/usr/share/xbmc/addons/xbmc.debug/:/storage/.xbmc/addons/script.module.parsedom/lib:/storage/.xbmc/addons/script.module.elementtree/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/PIL:/usr/lib/python2.7/site-packages/gtk-2.0
1174 
21:47:14 T:2926519376   DEBUG: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): entering source directory /storage/.xbmc/addons/script.module.simple.downloader
1175 
21:47:14 T:2926519376   DEBUG: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): instantiating addon using automatically obtained id of "script.module.simple.downloader" dependent on version 2.1.0 of the xbmc.python api
1176 
21:47:24 T:2926519376    INFO: CPythonInvoker(0, /storage/.xbmc/addons/script.module.simple.downloader/default.py): script successfully run
1177 
21:47:25 T:2926519376    INFO: Python script stopped
1178 
21:47:25 T:2926519376   DEBUG: Thread LanguageInvoker 2926519376 terminating
1179 
21:47:25 T:2882532432  NOTICE:  StorageServer Module loaded RUN
1180 
21:47:25 T:2882532432  NOTICE: StorageClient-2.5.4 Starting server
1181 
21:47:26 T:3059708448   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
1182 
21:47:26 T:3059708448   DEBUG: Unloading: ImageLib-arm.so
1183 
21:47:37 T:2918130768   DEBUG: Thread JobWorker 2918130768 terminating (autodelete)
1184 
21:47:37 T:3059708448    INFO: CheckIdle - Closing session to http://feeds.openelec.tv (easy=0xaabc1470, multi=0xac5cf038)
1185 
21:47:38 T:3059708448    INFO: CheckIdle - Closing session to http://feeds.xbmc.org (easy=0xaa007fe0, multi=0xaabd8c90)
1186 
21:47:49 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'ANTENNE BAYERN'.
1187 
21:47:49 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'sunshine live'.
1188 
21:47:49 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'ERF Plus'.
1189 
21:47:49 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'ROCK ANTENNE'.
1190 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Radio Regenbogen'.
1191 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'harmony.fm'.
1192 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'planet radio'.
1193 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'HIT RADIO FFH'.
1194 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Radio Gloria'.
1195 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Radio Paloma'.
1196 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'ffn digital'.
1197 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'radio top40'.
1198 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Inselradio'.
1199 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'JAM FM'.
1200 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'HOPE Channel Radio'.
1201 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Klassik Radio'.
1202 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'egoFM'.
1203 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'domradio'.
1204 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Radio Kara'.
1205 
21:47:50 T:2909742160   DEBUG: EPG - Load - no database entries found for table 'Radio Lome'.
1206 
21:47:53 T:2963272784   DEBUG: CecLogMessage - >> 04:44:02
1207 
21:47:53 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1208 
21:47:53 T:2963272784   DEBUG: CecLogMessage - key pressed: down (2)
1209 
21:47:53 T:2963272784   DEBUG: PushCecKeypress - received key a7 duration 0
1210 
21:47:53 T:3059708448   DEBUG: OnKey: 167 (0xa7) pressed, action is Down
1211 
21:47:53 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:02
1212 
21:47:53 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1213 
21:47:53 T:2963272784   DEBUG: CecLogMessage - key released: down (2)
1214 
21:47:53 T:2963272784   DEBUG: PushCecKeypress - received key a7 duration 276
1215 
21:47:54 T:2963272784   DEBUG: CecLogMessage - >> 04:44:01
1216 
21:47:54 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1217 
21:47:54 T:2963272784   DEBUG: CecLogMessage - key pressed: up (1)
1218 
21:47:54 T:2963272784   DEBUG: PushCecKeypress - received key a6 duration 0
1219 
21:47:54 T:3059708448   DEBUG: OnKey: 166 (0xa6) pressed, action is Up
1220 
21:47:54 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:01
1221 
21:47:54 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1222 
21:47:54 T:2963272784   DEBUG: CecLogMessage - key released: up (1)
1223 
21:47:54 T:2963272784   DEBUG: PushCecKeypress - received key a6 duration 124
1224 
21:47:55 T:2963272784   DEBUG: CecLogMessage - >> 04:44:03
1225 
21:47:55 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1226 
21:47:55 T:2963272784   DEBUG: CecLogMessage - key pressed: left (3)
1227 
21:47:55 T:2963272784   DEBUG: PushCecKeypress - received key a9 duration 0
1228 
21:47:55 T:3059708448   DEBUG: OnKey: 169 (0xa9) pressed, action is Left
1229 
21:47:55 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:03
1230 
21:47:55 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1231 
21:47:55 T:2963272784   DEBUG: CecLogMessage - key released: left (3)
1232 
21:47:55 T:2963272784   DEBUG: PushCecKeypress - received key a9 duration 125
1233 
21:47:56 T:2963272784   DEBUG: CecLogMessage - >> 04:44:02
1234 
21:47:56 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1235 
21:47:56 T:2963272784   DEBUG: CecLogMessage - key pressed: down (2)
1236 
21:47:56 T:2963272784   DEBUG: PushCecKeypress - received key a7 duration 0
1237 
21:47:56 T:3059708448   DEBUG: OnKey: 167 (0xa7) pressed, action is Down
1238 
21:47:56 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:02
1239 
21:47:56 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1240 
21:47:56 T:2963272784   DEBUG: CecLogMessage - key released: down (2)
1241 
21:47:56 T:2963272784   DEBUG: PushCecKeypress - received key a7 duration 275
1242 
21:47:57 T:2963272784   DEBUG: CecLogMessage - >> 04:44:00
1243 
21:47:57 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1244 
21:47:57 T:2963272784   DEBUG: CecLogMessage - key pressed: select (0)
1245 
21:47:57 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 0
1246 
21:47:57 T:3059708448   DEBUG: OnKey: 11 (0x0b) pressed, action is Select
1247 
21:47:57 T:3059708448   DEBUG: Activating window ID: 10601
1248 
21:47:57 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:00
1249 
21:47:57 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1250 
21:47:57 T:2963272784   DEBUG: CecLogMessage - key released: select (0)
1251 
21:47:57 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 276
1252 
21:47:57 T:3059708448   DEBUG: ------ Window Deinit (Home.xml) ------
1253 
21:47:57 T:3059708448   DEBUG: ------ Window Init (MyPVR.xml) ------
1254 
21:47:57 T:3059708448    INFO: Loading skin file: MyPVR.xml, load type: LOAD_EVERY_TIME
1255 
21:47:59 T:3059708448   DEBUG: CGUIMediaWindow::GetDirectory ()
1256 
21:47:59 T:3059708448   DEBUG:   ParentPath = []
1257 
21:47:59 T:3059708448   DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv'
1258 
21:47:59 T:3059708448   DEBUG: CGUIWindowPVRChannels - UpdateData - update window 'tv'. set view to 11
1259 
21:47:59 T:3059708448   DEBUG: CGUIMediaWindow::GetDirectory (pvr://channels/tv/All TV channels/)
1260 
21:47:59 T:3059708448   DEBUG:   ParentPath = []
1261 
21:47:59 T:2918130768  NOTICE: Thread JobWorker start, auto delete: true
1262 
21:47:59 T:2918130768   DEBUG: CPVRDirectory::GetDirectory(pvr://channels/tv/All TV channels)
1263 
21:48:00 T:3059708448   DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv'
1264 
21:48:00 T:3059708448   ERROR: Control 32 in window 10151 has been asked to focus, but it can't
1265 
21:48:00 T:3059708448   DEBUG: CGUIWindowPVRCommon - OnMessageFocus - focus set to window 'tv'
1266 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x3c05618
1267 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(276912), nBufferAlignmen(16)
1268 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x3c059c8
1269 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
1270 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x3c05618
1271 
21:48:00 T:3059708448   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x3c059c8
1272 
21:48:03 T:2963272784   DEBUG: CecLogMessage - >> 04:44:00
1273 
21:48:03 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1274 
21:48:03 T:2963272784   DEBUG: CecLogMessage - key pressed: select (0)
1275 
21:48:03 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 0
1276 
21:48:03 T:3059708448   DEBUG: OnKey: 11 (0x0b) pressed, action is Select
1277 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All TV channels/0.pvr)
1278 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
1279 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
1280 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv
1281 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp
1282 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
1283 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
1284 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
1285 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
1286 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
1287 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile
1288 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
1289 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
1290 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
1291 
21:48:03 T:3059708448   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
1292 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
1293 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (4)
1294 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
1295 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
1296 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
1297 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: OMXPlayer (4)
1298 
21:48:03 T:3059708448   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
1299 
21:48:03 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:00
1300 
21:48:03 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1301 
21:48:03 T:2963272784   DEBUG: CecLogMessage - key released: select (0)
1302 
21:48:03 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 275
1303 
21:48:03 T:3049256016   DEBUG: CAESinkPi:Drain delay:99ms now:0ms
1304 
21:48:03 T:3049256016   DEBUG: CAESinkPi:Deinitialize
1305 
21:48:03 T:3049256016   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
1306 
21:48:03 T:3049256016   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xb5201440
1307 
21:48:03 T:3059708448  NOTICE: COMXPlayer: Opening: pvr://channels/tv/All TV channels/0.pvr
1308 
21:48:03 T:3059708448 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
1309 
21:48:03 T:3059708448   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
1310 
21:48:03 T:3059708448   DEBUG: LinuxRendererGL: Cleaning up GL resources
1311 
21:48:03 T:2813121616  NOTICE: Thread OMXPlayer start, auto delete: false
1312 
21:48:03 T:2813121616  NOTICE: Creating InputStream
1313 
21:48:03 T:2813121616   DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'VOX'
1314 
21:48:03 T:2813121616   DEBUG: opening live stream for channel 'VOX'
1315 
21:48:03 T:2813121616    INFO: AddOnLog: Tvheadend HTSP Client: SendSubscribe - subscribe to channel '170', subscription 1
1316 
21:48:03 T:2813121616   DEBUG: AddOnLog: Tvheadend HTSP Client: SendSubscribe - new subscription for channel 170 (1)
1317 
21:48:03 T:2813121616   DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All TV channels/0.pvr
1318 
21:48:03 T:2813121616   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All TV channels/0.pvr
1319 
21:48:03 T:2813121616  NOTICE: Creating Demuxer
1320 
21:48:04 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: MPEG2VIDEO, codec: 2
1321 
21:48:04 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: MPEG2AUDIO, codec: 86016
1322 
21:48:04 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: TELETEXT, codec: 94215
1323 
21:48:04 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 4, type: DVBSUB, codec: 94209
1324 
21:48:04 T:2873140304    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter Philips TDA10023 DVB-C, mux GSS: 626,000 kHz, network GSS, provider RTL World, service VOX
1325 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 2
1326 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86016
1327 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 94215
1328 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 3:4 with codec_id 94209
1329 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81 m_handle 0xaabf05f0
1330 
21:48:04 T:2813121616   DEBUG: OMXClock::OMXStop
1331 
21:48:04 T:2813121616   DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
1332 
21:48:04 T:2813121616  NOTICE: Opening video stream: 0 source: 256
1333 
21:48:04 T:2813121616   DEBUG: DllBcm: Using omx system library
1334 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0xac5123a8
1335 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
1336 
21:48:04 T:2813121616   DEBUG: COMXVideo::Open - decoder_component(0x0xac5123a8), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0
1337 
21:48:04 T:2813121616    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg2 width 720 height 576 profile -99 fps 25.000000
1338 
21:48:04 T:2813121616  NOTICE: Creating video thread
1339 
21:48:04 T:2792354896  NOTICE: Thread OMXPlayerVideo start, auto delete: false
1340 
21:48:04 T:2813121616  NOTICE: Opening audio stream: 1 source: 256
1341 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1342 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
1343 
21:48:04 T:2813121616  NOTICE: Creating audio thread
1344 
21:48:04 T:2783966288  NOTICE: Thread OMXPlayerAudio start, auto delete: false
1345 
21:48:04 T:2813121616  NOTICE: Opening Subtitle stream: 3 source: 256
1346 
21:48:04 T:2813121616   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening
1347 
21:48:04 T:2813121616   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened
1348 
21:48:04 T:2813121616  NOTICE: Opening teletext stream: 2 source: 256
1349 
21:48:04 T:2813121616  NOTICE: Creating teletext data thread
1350 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1351 
21:48:04 T:2813121616   DEBUG: COMXPlayer::SetCaching - caching state 2
1352 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
1353 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1354 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
1355 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1356 
21:48:04 T:2813121616   DEBUG: OnPlayBackStarted : play state was 1, starting 1
1357 
21:48:04 T:3059708448   DEBUG: PlayFile : OpenFile succeed, play state 2
1358 
21:48:04 T:3059708448   DEBUG: OnPlayBackStarted : play state was 2, starting 0
1359 
21:48:04 T:2775577680  NOTICE: Thread DVDTeletextData start, auto delete: false
1360 
21:48:04 T:2775577680  NOTICE: running thread: CDVDTeletextData
1361 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 2
1362 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86016
1363 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 94215
1364 
21:48:04 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 3:4 with codec_id 94209
1365 
21:48:04 T:2813121616  NOTICE: Opening video stream: 0 source: 256
1366 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1367 
21:48:04 T:2813121616  NOTICE: Opening audio stream: 1 source: 256
1368 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1369 
21:48:04 T:2813121616  NOTICE: Opening Subtitle stream: 3 source: 256
1370 
21:48:04 T:2813121616  NOTICE: Opening teletext stream: 2 source: 256
1371 
21:48:04 T:2792354896   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 89540100211.000000, 1)
1372 
21:48:04 T:2792354896    INFO: COMXPlayerVideo - Stillframe left, switching to normal playback
1373 
21:48:04 T:2792354896   DEBUG: OMXVideo::Decode VDec : setStartTime 89540.100211
1374 
21:48:04 T:2792354896    INFO: Output - renderer not started
1375 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started 2 (tpa:0,a:0,v:1)
1376 
21:48:04 T:2813121616   DEBUG: ParsePacket - {1} iChannels changed from 0 to 2
1377 
21:48:04 T:2813121616   DEBUG: ParsePacket - {1} iSampleRate changed from 0 to 48000
1378 
21:48:04 T:2813121616  NOTICE: Opening audio stream: 1 source: 256
1379 
21:48:04 T:3059708448   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
1380 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_STREAMCHANGE
1381 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1382 
21:48:04 T:2783966288   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-4503599627370496.000000, 0)
1383 
21:48:04 T:2783966288   DEBUG: COMXAudioCodecOMX::Decode(0xb52661d0,576) format=6(8) chan=2 samples=1152 size=2304 data=0xb526ce40,0xb526d740,(nil),(nil),(nil),(nil),(nil),(nil)
1384 
21:48:04 T:2783966288   DEBUG: COMXAudioCodecOMX::GetData size=4608/9216 line=2304/4608 buf=0xb5243548, desired=65536
1385 
21:48:04 T:2813121616   DEBUG: Resume 89539.95,89540.10 (A:01 V:01) EOF:0 FULL:0 T:0.20
1386 
21:48:04 T:2813121616   DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
1387 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started RESET
1388 
21:48:04 T:2813121616  NOTICE: OMXClock using audio as reference
1389 
21:48:04 T:2813121616   DEBUG: OMXClock::OMXReset audio / video : 1 / 1 wait mask 0->3 state : 2->1
1390 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started 1 (tpa:0,a:1,v:1)
1391 
21:48:04 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
1392 
21:48:04 T:2813121616  NOTICE: Opening video stream: 0 source: 256
1393 
21:48:04 T:2813121616   DEBUG: DllBcm: Using omx system library
1394 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0xac5123a8
1395 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0xac5e6390
1396 
21:48:04 T:2813121616   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
1397 
21:48:04 T:2813121616   DEBUG: COMXVideo::Open - decoder_component(0x0xac5e6390), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0
1398 
21:48:04 T:2792354896   DEBUG: OMXVideo::Decode VDec : setStartTime 89540.140211
1399 
21:48:04 T:2813121616    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg2 width 720 height 576 profile -99 fps 25.000000
1400 
21:48:04 T:2792354896    INFO: Output - renderer not started
1401 
21:48:04 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 1
1402 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started RESET
1403 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started 2 (tpa:0,a:1,v:1)
1404 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started RESET
1405 
21:48:04 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started 2 (tpa:0,a:1,v:1)
1406 
21:48:04 T:3059708448   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All TV channels/0.pvr)
1407 
21:48:05 T:3059708448   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
1408 
21:48:05 T:3059708448   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
1409 
21:48:05 T:2813121616   DEBUG: set caching from pvr to done. audio (1) = 11. video (1) = 11
1410 
21:48:05 T:2813121616   DEBUG: COMXPlayer::SetCaching - caching state 0
1411 
21:48:05 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 1000
1412 
21:48:05 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 1000
1413 
21:48:05 T:2792354896   DEBUG: COMXVideo::PortSettingsChanged - [email protected] interlace:3 deinterlace:0
1414 
21:48:05 T:2792354896   DEBUG: ResolutionUpdateCallBack - change configuration. video:1920x1080. framerate: 25.00. 1024x576 format: BYPASS
1415 
21:48:05 T:2792354896  NOTICE: Display resolution DESKTOP : 1920x1080 (1920x1080) @ 50.00 - Full Screen (16)
1416 
21:48:05 T:2792354896   DEBUG: CXBMCRenderManager::Configure - 5
1417 
21:48:05 T:3021993040   DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 3000
1418 
21:48:05 T:3021993040   DEBUG: CecLogMessage - physical address changed to 3000
1419 
21:48:05 T:3021993040   DEBUG: CecLogMessage - physical address unchanged (3000)
1420 
21:48:05 T:2792354896   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90 m_handle 0x3dcea58
1421 
21:48:05 T:2783966288   DEBUG: COMXAudio::SetCodingType OMX_AUDIO_CodingPCM
1422 
21:48:05 T:2792354896   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11 m_handle 0x3daf5e8
1423 
21:48:05 T:2783966288   DEBUG: COMXAudio:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
1424 
21:48:05 T:2783966288    INFO: COMXAudio::Initialize remap:(nil) chan:2->2 norm:1 upmix:0 3:3
1425 
21:48:05 T:2783966288    INFO: COMXAudio::Initialize    1.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
1426 
21:48:05 T:2813121616   ERROR: Get - failed to get stream
1427 
21:48:05 T:2783966288    INFO: COMXAudio::Initialize    0.00   1.00   0.00   0.00   0.00   0.00   0.00   0.00
1428 
21:48:05 T:2783966288    INFO: COMXAudio::Initialize    0.00   0.00   0.00   0.00   0.00   0.00   0.00   0.00
1429 
21:48:05 T:2783966288    INFO: Previous line repeats 5 times.
1430 
21:48:05 T:2783966288   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_decode input port 120 output port 121 m_handle 0xb524b238
1431 
21:48:05 T:2783966288   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_decode) - port(120), nBufferCountMin(4), nBufferCountActual(16), nBufferSize(65536), nBufferAlignmen(16)
1432 
21:48:05 T:2783966288   DEBUG: COMXAudio::Initialize Input bps 32 samplerate 48000 channels 2 buffer size 576000 bytes per second 384000
1433 
21:48:05 T:2783966288   DEBUG: pcm->direction      : input
1434 
21:48:05 T:2783966288   DEBUG: pcm->nPortIndex     : 0
1435 
21:48:05 T:2783966288   DEBUG: pcm->eNumData       : 0
1436 
21:48:05 T:2783966288   DEBUG: pcm->eEndian        : 1
1437 
21:48:05 T:2783966288   DEBUG: pcm->bInterleaved   : 1
1438 
21:48:05 T:2783966288   DEBUG: pcm->nBitPerSample  : 32
1439 
21:48:05 T:2783966288   DEBUG: pcm->ePCMMode       : 0
1440 
21:48:05 T:2783966288   DEBUG: pcm->nChannels      : 2
1441 
21:48:05 T:2783966288   DEBUG: pcm->nSamplingRate  : 48000
1442 
21:48:05 T:2783966288   DEBUG: COMXAudio::Initialize device passthrough 0 hwdecode 0
1443 
21:48:05 T:2783966288    INFO: Audio codec  channels 2 samplerate 48000 bitspersample 16
1444 
21:48:05 T:2783966288   DEBUG: COMXAudio::Decode ADec : setStartTime 89539.953125
1445 
21:48:05 T:2783966288   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_mixer input port 232 output port 231 m_handle 0xb524a8a8
1446 
21:48:05 T:2783966288   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xb526a198
1447 
21:48:05 T:2783966288    INFO: COMXAudio::ApplyVolume - Volume=1.00 (* 1.00 * 1.00)
1448 
21:48:05 T:2783966288   DEBUG: COMXAudio::PortSettingsChanged - Output bps 16 samplerate 48000 channels 2 buffer size 576000 bytes per second 192000
1449 
21:48:05 T:2783966288   DEBUG: pcm->direction      : output
1450 
21:48:05 T:2783966288   DEBUG: pcm->nPortIndex     : 231
1451 
21:48:05 T:2783966288   DEBUG: pcm->eNumData       : 0
1452 
21:48:05 T:2783966288   DEBUG: pcm->eEndian        : 1
1453 
21:48:05 T:2783966288   DEBUG: pcm->bInterleaved   : 1
1454 
21:48:05 T:2783966288   DEBUG: pcm->nBitPerSample  : 16
1455 
21:48:05 T:2783966288   DEBUG: pcm->ePCMMode       : 0
1456 
21:48:05 T:2783966288   DEBUG: pcm->nChannels      : 2
1457 
21:48:05 T:2783966288   DEBUG: pcm->nSamplingRate  : 48000
1458 
21:48:05 T:2783966288   DEBUG: COMXAudio::PortSettingsChanged - bits:32 mode:0 channels:2 srate:48000 nopassthrough
1459 
21:48:05 T:2783966288    INFO: COMXPlayerAudio - Switching to normal playback
1460 
21:48:05 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started RESET
1461 
21:48:05 T:2813121616   DEBUG: COMXPlayer::HandleMessages - player started 1 (tpa:0,a:1,v:1)
1462 
21:48:05 T:3059708448  NOTICE: Using GL_TEXTURE_2D
1463 
21:48:05 T:3059708448   DEBUG: GL: Requested render method: 0
1464 
21:48:05 T:3059708448  NOTICE: GL: Using BYPASS render method
1465 
21:48:05 T:3059708448  NOTICE: GL: NPOT texture support detected
1466 
21:48:05 T:3059708448   DEBUG: CLinuxRendererGLES: Reorder drawpoints due to method change from 1 to 256
1467 
21:48:05 T:3059708448   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
1468 
21:48:05 T:3059708448   DEBUG: OMXPlayerVideo::SetVideoRect 0,0,719,576 -> 808,146,1795,702 (0,0,0,0,mono)
1469 
21:48:05 T:3059708448   DEBUG: COMXVideo::SetVideoRect 0,0,720,576 -> 808,146,988,556 mode:1
1470 
21:48:06 T:2963272784   DEBUG: CecLogMessage - >> 04:44:00
1471 
21:48:06 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1472 
21:48:06 T:2963272784   DEBUG: CecLogMessage - key pressed: select (0)
1473 
21:48:06 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 0
1474 
21:48:06 T:3059708448   DEBUG: OnKey: 11 (0x0b) pressed, action is Select
1475 
21:48:06 T:3059708448   DEBUG: Activating window ID: 12005
1476 
21:48:06 T:3059708448   DEBUG: ------ Window Deinit (MyPVR.xml) ------
1477 
21:48:06 T:3059708448   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
1478 
21:48:06 T:3059708448    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
1479 
21:48:06 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:00
1480 
21:48:06 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1481 
21:48:06 T:2963272784   DEBUG: CecLogMessage - key released: select (0)
1482 
21:48:06 T:2963272784   DEBUG: PushCecKeypress - received key  b duration 272
1483 
21:48:07 T:3059708448   DEBUG: OMXPlayerVideo::SetVideoRect 0,0,720,576 -> 31,18,1890,1064 (0,0,0,0,mono)
1484 
21:48:07 T:3059708448   DEBUG: COMXVideo::SetVideoRect 0,0,720,576 -> 31,18,1859,1046 mode:1
1485 
21:48:09 T:2963272784   DEBUG: CecLogMessage - >> 04:44:30
1486 
21:48:09 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1487 
21:48:09 T:2963272784   DEBUG: CecLogMessage - key pressed: channel up (30)
1488 
21:48:09 T:2963272784   DEBUG: PushCecKeypress - received key d2 duration 0
1489 
21:48:09 T:3059708448   DEBUG: OnKey: leftshift (0xd2) pressed, action is SkipNext
1490 
21:48:09 T:2813121616  NOTICE: FlushBuffers: q:0 pts:-4503599627370496 a:1
1491 
21:48:09 T:2813121616   DEBUG: OMXClock::OMXStop
1492 
21:48:09 T:2813121616   DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
1493 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_FLUSH
1494 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_FLUSH
1495 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
1496 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
1497 
21:48:09 T:2813121616   DEBUG: COMXPlayer::SetCaching - caching state 3
1498 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
1499 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1500 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_SETSPEED 0
1501 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1502 
21:48:09 T:2813121616   DEBUG: PVRManager - PerformChannelSwitch - switching to channel 'VIVA Germany'
1503 
21:48:09 T:2813121616   DEBUG: PVR - SaveCurrentChannelSettings - no custom channel settings for channel 'VOX'
1504 
21:48:09 T:2813121616    INFO: AddOnLog: Tvheadend HTSP Client: SwitchChannel - changing to channel 'VIVA Germany'
1505 
21:48:09 T:2813121616    INFO: AddOnLog: Tvheadend HTSP Client: SendUnsubscribe - unsubscribe from subscription 1
1506 
21:48:09 T:2873140304    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStop - subscription ended on adapter Philips TDA10023 DVB-C
1507 
21:48:09 T:2813121616    INFO: AddOnLog: Tvheadend HTSP Client: SendSubscribe - subscribe to channel '175', subscription 2
1508 
21:48:09 T:2813121616   DEBUG: AddOnLog: Tvheadend HTSP Client: SendSubscribe - new subscription for channel 170 (2)
1509 
21:48:09 T:2813121616  NOTICE: PVRManager - PerformChannelSwitch - switched to channel 'VIVA Germany'
1510 
21:48:09 T:2813121616  NOTICE: Creating Demuxer
1511 
21:48:09 T:3059708448   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
1512 
21:48:09 T:2963272784   DEBUG: CecLogMessage - >> 04:8b:30
1513 
21:48:09 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor remote button up (8B)
1514 
21:48:09 T:2963272784   DEBUG: CecLogMessage - key released: channel up (30)
1515 
21:48:09 T:2963272784   DEBUG: PushCecKeypress - received key d2 duration 274
1516 
21:48:09 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 1, type: MPEG2VIDEO, codec: 2
1517 
21:48:09 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 2, type: MPEG2AUDIO, codec: 86016
1518 
21:48:09 T:2873140304   DEBUG: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - id: 3, type: TELETEXT, codec: 94215
1519 
21:48:09 T:2873140304    INFO: AddOnLog: Tvheadend HTSP Client: ParseSubscriptionStart - subscription started on adapter Philips TDA10023 DVB-C, mux ASTRA 1: 618,000 kHz, network ASTRA 1, provider MTV Networks Europe, service Comedy Central/VIVA
1520 
21:48:09 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 0:1 with codec_id 2
1521 
21:48:09 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 1:2 with codec_id 86016
1522 
21:48:09 T:2813121616   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added/updated stream 2:3 with codec_id 94215
1523 
21:48:09 T:2813121616  NOTICE: Opening video stream: 0 source: 256
1524 
21:48:09 T:2813121616   DEBUG: DllBcm: Using omx system library
1525 
21:48:09 T:3059708448   DEBUG: OMXPlayerVideo::SetVideoRect 0,0,720,576 -> 31,18,1890,1064 (0,0,0,0,mono)
1526 
21:48:09 T:2813121616   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_scheduler handle 0x3daf5e8
1527 
21:48:09 T:2813121616   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0xac5e6390
1528 
21:48:09 T:2813121616   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_render handle 0x3dcea58
1529 
21:48:09 T:2813121616   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0xaa01ed18
1530 
21:48:09 T:2813121616   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(120), nBufferSize(81920), nBufferAlignmen(16)
1531 
21:48:09 T:2813121616   DEBUG: COMXVideo::Open - decoder_component(0x0xaa01ed18), input_port(0x82), output_port(0x83) deinterlace 0 hdmiclocksync 0
1532 
21:48:09 T:3059708448   DEBUG: COMXVideo::SetVideoRect 0,0,720,576 -> 31,18,1859,1046 mode:1
1533 
21:48:09 T:2813121616    INFO: OMXPlayerVideo::OpenDecoder : Video codec omx-mpeg2 width 720 height 576 profile -99 fps 25.000000
1534 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1535 
21:48:09 T:2813121616  NOTICE: Opening audio stream: 1 source: 256
1536 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::GENERAL_STREAMCHANGE
1537 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1538 
21:48:09 T:2813121616  NOTICE: Closing subtitle stream
1539 
21:48:09 T:2813121616  NOTICE: Opening teletext stream: 2 source: 256
1540 
21:48:09 T:2813121616   DEBUG: COMXPlayer::SetCaching - caching state 2
1541 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_SETSPEED 0
1542 
21:48:09 T:2783966288   DEBUG: COMXPlayerAudio - CDVDMsg::PLAYER_STARTED 0
1543 
21:48:09 T:2792354896   DEBUG: COMXPlayerVideo - CDVDMsg::PLAYER_STARTED 0
1544 
21:48:09 T:2813121616   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All TV channels/1.pvr)
1545 
21:48:10 T:2963272784   DEBUG: CecLogMessage - >> 04:44:30
1546 
21:48:10 T:2963272784   DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44)
1547 
21:48:10 T:2963272784   DEBUG: CecLogMessage - key pressed: channel up (30)
1548 
21:48:10 T:2963272784   DEBUG: PushCecKeypress - received key d2 duration 0
1549 
21:48:10 T:3059708448   DEBUG: OnKey: leftshift (0xd2) pressed, action is SkipNext
(1-1/3)