2013-02-13, 23:03
Have one problem with HDMIC CEC > on my ONKYO TX-NR414
the device is brand new with the latest Firmware
can't get CEC working on it
CEC ist activatecd on the device < i have also reseted several times the device and the pi
still not working
can someone help me? Logs are below
the device is brand new with the latest Firmware
can't get CEC working on it
CEC ist activatecd on the device < i have also reseted several times the device and the pi
still not working
can someone help me? Logs are below
Code:
00:02:03 T:3038150656 NOTICE: -----------------------------------------------------------------------
00:02:04 T:3038150656 NOTICE: Starting XBMC (12.0 Git:Unknown), Platform: Linux (Debian GNU/Linux 7.0 (wheezy), 3.6.11 armv6l). Built on Feb 3 2013
00:02:04 T:3038150656 NOTICE: special://xbmc/ is mapped to: /opt/xbmc-bcm/xbmc-bin/share/xbmc
00:02:04 T:3038150656 NOTICE: special://xbmcbin/ is mapped to: /opt/xbmc-bcm/xbmc-bin/lib/xbmc
00:02:04 T:3038150656 NOTICE: special://masterprofile/ is mapped to: /home/pi/.xbmc/userdata
00:02:04 T:3038150656 NOTICE: special://home/ is mapped to: /home/pi/.xbmc
00:02:04 T:3038150656 NOTICE: special://temp/ is mapped to: /home/pi/.xbmc/temp
00:02:04 T:3038150656 NOTICE: The executable running is: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/xbmc.bin
00:02:04 T:3038150656 NOTICE: Local hostname: raspbmc
00:02:04 T:3038150656 NOTICE: Log File is located: /home/pi/.xbmc/temp/xbmc.log
00:02:04 T:3038150656 NOTICE: -----------------------------------------------------------------------
00:02:05 T:3038150656 NOTICE: load settings...
00:02:05 T:3038150656 NOTICE: special://profile/ is mapped to: special://masterprofile/
00:02:05 T:3038150656 NOTICE: loading special://masterprofile/guisettings.xml
00:02:06 T:3038150656 NOTICE: Getting hardware information now...
00:02:06 T:3038150656 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
00:02:06 T:3038150656 NOTICE: Loaded playercorefactory configuration
00:02:06 T:3038150656 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
00:02:06 T:3038150656 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
00:02:06 T:3038150656 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
00:02:06 T:3038150656 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
<advancedsettings>
<cputempcommand>/opt/vc/bin/vcgencmd measure_temp | sed -e 's/temp=\([0-9]*\).*/\1 C/'</cputempcommand>
<gputempcommand>/opt/vc/bin/vcgencmd measure_temp | sed -e 's/temp=\([0-9]*\).*/\1 C/'</gputempcommand>
<network>
<cachemembuffersize>10485760</cachemembuffersize>
</network>
<imageres>540</imageres>
<fanartres>720</fanartres>
<video>
<defaultplayer>omxplayer</defaultplayer>
<defaultdvdplayer>omxplayer</defaultdvdplayer>
</video>
<audio>
<defaultplayer>paplayer</defaultplayer>
<streamsilence>false</streamsilence>
</audio>
</advancedsettings>
00:02:06 T:3038150656 NOTICE: Getting hardware information now...
00:02:06 T:3038150656 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
00:02:06 T:3038150656 NOTICE: Default DVD Player: omxplayer
00:02:06 T:3038150656 NOTICE: Default Video Player: omxplayer
00:02:06 T:3038150656 NOTICE: Default Audio Player: paplayer
00:02:06 T:3038150656 NOTICE: Enabled debug logging due to GUI setting (2)
00:02:06 T:3038150656 NOTICE: Log level changed to 2
00:02:06 T:3038150656 NOTICE: Loading media sources from special://masterprofile/sources.xml
00:02:06 T:3038150656 INFO: creating subdirectories
00:02:06 T:3038150656 INFO: userdata folder: special://masterprofile/
00:02:06 T:3038150656 INFO: recording folder:
00:02:06 T:3038150656 INFO: screenshots folder:
00:02:06 T:3038150656 INFO: thumbnails folder: special://masterprofile/Thumbnails
00:02:06 T:3038150656 INFO: load language info file: special://xbmc/language/German/langinfo.xml
00:02:06 T:3038150656 DEBUG: trying to set locale to de_BE.UTF-8
00:02:06 T:3038150656 INFO: global locale set to C
00:02:06 T:3038150656 INFO: load German language file, from path: special://xbmc/language/
00:02:06 T:3038150656 DEBUG: POParser: loaded 2686 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/language/German/strings.po
00:02:06 T:3038150656 DEBUG: POParser: loaded 0 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/language/English/strings.po
00:02:06 T:3038150656 ERROR: Error: Requested setting (audiooutput.guisoundmode) was not found. It must be case-sensitive
00:02:06 T:3038150656 NOTICE: Running database version Addons15
00:02:06 T:3038150656 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so)
00:02:06 T:3038150656 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/libcpluff-arm.so
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in script.raspbmc.settings has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.argustv has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx32.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.mediaportal.tvserver has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in weather.wunderground has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-ios.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.amazon.de has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.vuplus has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.demo has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.njoy has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.nextpvr has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-android.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.dvbviewer has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-win32.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv.cmyth has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in repository.pvr-osx64.xbmc.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in script.module.pysqlite has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
00:02:07 T:3038150656 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
00:02:07 T:3038150656 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
00:02:07 T:3038150656 DEBUG: LoadMappings - loaded node "Raspberry Pi CEC Adapter"
00:02:07 T:3038150656 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
00:02:07 T:3038150656 DEBUG: LoadMappings - loaded node "iMON HID device"
00:02:07 T:3038150656 DEBUG: CPeripheralBusUSB - initialised udev monitor
00:02:07 T:3038150656 DEBUG: GetMappingForDevice - device (2708:1001) mapped to Raspberry Pi CEC Adapter (type = cec)
00:02:07 T:3038150656 DEBUG: SECTION:LoadDLL(libcec.so.2)
00:02:07 T:3038150656 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/libcec.so.2
00:02:07 T:3038150656 DEBUG: InitialiseFeature - using libCEC v2.0.5
00:02:07 T:3038150656 DEBUG: Initialise - initialised peripheral on 'RPI' with 2 features and 0 sub devices
00:02:07 T:3038150656 NOTICE: Register - new cec device registered on rpi->RPI: Raspberry Pi CEC Adapter (2708:1001)
00:02:07 T:3028935744 NOTICE: Thread CEC Adapter start, auto delete: false
00:02:07 T:3028935744 DEBUG: GetComPort - autodetect com port 'RPI'
00:02:07 T:3028935744 DEBUG: OpenConnection - opening a connection to the CEC adapter: RPI
00:02:07 T:3028935744 DEBUG: CecLogMessage - unregistering all CEC clients
00:02:07 T:3028935744 DEBUG: CecLogMessage - Broadcast (F): osd name set to 'Broadcast'
00:02:07 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchiq_initialise succeeded
00:02:07 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchi_initialise succeeded
00:02:07 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchi_connect succeeded
00:02:07 T:3020547136 NOTICE: Thread XBMC Peripherals start, auto delete: false
00:02:07 T:3038150656 INFO: LIRC Initialize: using: /var/run/lirc/lircd
00:02:07 T:3038150656 INFO: LIRC Initialize: sucessfully started
00:02:07 T:3038150656 DEBUG: Selected UDisks as storage provider
00:02:07 T:3038150656 DEBUG: UDisks: DaemonVersion 1
00:02:07 T:3038150656 DEBUG: UDisks: Querying available devices
00:02:08 T:3038150656 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0p2: IsFileSystem true HasFileSystem ext4 IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
00:02:08 T:3038150656 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/zram0: IsFileSystem true HasFileSystem ext2 IsSystemInternal true IsMounted true IsRemovable false IsPartition false IsOptical false
00:02:08 T:3038150656 INFO: UDisks: Added /tmp
00:02:08 T:3038150656 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/zram1: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition false IsOptical false
00:02:08 T:3038150656 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0: IsFileSystem false HasFileSystem IsSystemInternal false IsMounted false IsRemovable false IsPartition false IsOptical false
00:02:08 T:3038150656 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0p1: IsFileSystem true HasFileSystem vfat IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
00:02:08 T:3038150656 DEBUG: DllBcm: Using omx system library
00:02:08 T:3038150656 DEBUG: SECTION:LoadDLL(libopenmaxil.so)
00:02:08 T:3038150656 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/libopenmaxil.so
00:02:08 T:3038150656 NOTICE: Raspberry PI firmware version: Jan 30 2013 12:19:11
Copyright (c) 2012 Broadcom
version 366105 (release)
00:02:08 T:3038150656 DEBUG: DllBcm: Using omx system library
00:02:08 T:3038150656 NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL initial desktop resolution 1920x1080 @ 60.00 - Full Screen (1.00)
00:02:08 T:3038150656 DEBUG: EGL get supported modes (1) = 16, prefer_group=1, prefer_mode=10
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 0: 640x480 @ 60.00 - Full Screen (1.00) :1
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 1: 720x480 @ 60.00 - Full Screen (0.89) :2
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 2: 720x480 @ 60.00 - Full Screen (1.19) :3
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 3: 1280x720 @ 60.00 - Full Screen (1.00) :4
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 4: 1920x1080 @ 60.00i - Full Screen (1.00) I:5
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 5: 720x480 @ 60.00i - Full Screen (1.19) I:7
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 6: 1920x1080 @ 60.00 - Full Screen (1.00) N:10
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 7: 720x576 @ 50.00 - Full Screen (1.07) :11
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 8: 720x576 @ 50.00 - Full Screen (1.42) :12
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 9: 1280x720 @ 50.00 - Full Screen (1.00) :13
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 10: 1920x1080 @ 50.00i - Full Screen (1.00) I:14
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 11: 720x576 @ 50.00i - Full Screen (1.42) I:16
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 12: 1920x1080 @ 50.00 - Full Screen (1.00) :1f
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 13: 1920x1080 @ 24.00 - Full Screen (1.00) :20
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 14: 1920x1080 @ 25.00 - Full Screen (1.00) :21
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 15: 1920x1080 @ 30.00 - Full Screen (1.00) :22
00:02:08 T:3038150656 DEBUG: EGL get supported modes (2) = 19, prefer_group=1, prefer_mode=10
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 0: 640x480 @ 60.00 - Full Screen (1.00) :4
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 1: 640x480 @ 72.00 - Full Screen (1.00) :5
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 2: 640x480 @ 75.00 - Full Screen (1.00) :6
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 3: 640x480 @ 85.00 - Full Screen (1.00) :7
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 4: 800x600 @ 56.00 - Full Screen (1.00) :8
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 5: 800x600 @ 60.00 - Full Screen (1.00) :9
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 6: 800x600 @ 72.00 - Full Screen (1.00) :a
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 7: 800x600 @ 75.00 - Full Screen (1.00) :b
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 8: 800x600 @ 85.00 - Full Screen (1.00) :c
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 9: 1024x768 @ 60.00 - Full Screen (1.00) :10
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 10: 1024x768 @ 70.00 - Full Screen (1.00) :11
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 11: 1024x768 @ 75.00 - Full Screen (1.00) :12
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 12: 1024x768 @ 85.00 - Full Screen (1.00) :13
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 13: 1152x864 @ 75.00 - Full Screen (1.00) :15
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 14: 1280x960 @ 60.00 - Full Screen (1.00) :20
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 15: 1280x960 @ 85.00 - Full Screen (1.00) :21
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 16: 1280x1024 @ 60.00 - Full Screen (1.00) :23
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 17: 1280x1024 @ 75.00 - Full Screen (1.00) :24
00:02:08 T:3038150656 DEBUG: Clamped to 1280*720
00:02:08 T:3038150656 DEBUG: EGL mode 18: 1600x1200 @ 60.00 - Full Screen (1.00) :33
00:02:08 T:3038150656 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 50.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 24.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 25.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 1280 x 720 for display 0 with 1920 x 1080 @ 30.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 72.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 75.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 85.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 56.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 72.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 75.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 800 x 600 for display 0 with 800 x 600 @ 85.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 70.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 75.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1024 x 768 @ 85.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1152 x 864 @ 75.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1280 x 960 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1280 x 960 @ 85.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 900 x 720 for display 0 with 1280 x 1024 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 900 x 720 for display 0 with 1280 x 1024 @ 75.000000 Hz
00:02:08 T:3038150656 NOTICE: Found resolution 960 x 720 for display 0 with 1600 x 1200 @ 60.000000 Hz
00:02:08 T:3038150656 NOTICE: Found ([email protected]) at 19, setting to RES_DESKTOP at 16
00:02:08 T:3038150656 NOTICE: Checking resolution 16
00:02:08 T:3003769920 DEBUG: EGL tv_service_callback (8,1,16)
00:02:08 T:3038150656 DEBUG: EGL set HDMI mode (1,16)=0
00:02:08 T:3038150656 DEBUG: EGL set resolution 1280x720 -> 1920x1080 @ 60.00 fps (1,16) flags:10010004 aspect:1.00
00:02:08 T:3038150656 DEBUG: CreateWindow: Created surface of size 1280x720
00:02:08 T:3038150656 NOTICE: GL_VENDOR = Broadcom
00:02:08 T:3038150656 NOTICE: GL_RENDERER = VideoCore IV HW
00:02:08 T:3038150656 NOTICE: GL_VERSION = OpenGL ES 2.0
00:02:08 T:3038150656 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
00:02:08 T:3038150656 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
00:02:08 T:3038150656 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
00:02:08 T:3038150656 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
00:02:08 T:3038150656 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
00:02:08 T:3038150656 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x2f90e50
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x2f92e30
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x2ebd270
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x2f92bd0
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x2f90db0
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x2ebf528
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x2f8e4c0
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Vertex Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GL: Pixel Shader compilation log:
00:02:08 T:3038150656 DEBUG: Compiled
00:02:08 T:3038150656 DEBUG: GL: Fragment Shader compiled successfully
00:02:08 T:3038150656 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x2f922c0
00:02:08 T:3038150656 DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
00:02:08 T:3038150656 INFO: GLES: Maximum texture width: 2048
00:02:08 T:3038150656 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
00:02:08 T:3038150656 INFO: load default splash image: /opt/xbmc-bcm/xbmc-bin/share/xbmc/media/Splash.png
00:02:08 T:3038150656 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
00:02:08 T:3038150656 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/ImageLib-arm.so
00:02:08 T:3038150656 INFO: load keymapping
00:02:08 T:3038150656 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
00:02:08 T:3038150656 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/hama_mce.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
00:02:09 T:3038150656 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
00:02:10 T:3038150656 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
00:02:10 T:3038150656 INFO: Loading special://xbmc/system/keymaps/mouse.xml
00:02:10 T:3038150656 INFO: Loading special://xbmc/system/keymaps/remote.xml
00:02:10 T:3038150656 INFO: Loading special://xbmc/system/Lircmap.xml
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'mceusb'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'XboxDVDDongle'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'anysee'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'iMON-PAD'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'MCE_via_iMON'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'TwinHanRemote'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'linux-input-layer'
00:02:10 T:3038150656 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
00:02:10 T:3038150656 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput'
00:02:10 T:3038150656 INFO: * Adding remote mapping for device 'mediacenter'
00:02:10 T:3038150656 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
00:02:10 T:3038150656 INFO: GUI format 1280x720, Display 1920x1080 @ 60.00 - Full Screen
00:02:10 T:3038150656 DEBUG: guilib: Fill viewport on change for solving rendering passes
00:02:10 T:3038150656 DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4)
00:02:10 T:3038150656 DEBUG: Loading: libcurl-gnutls.so.4
00:02:11 T:3038150656 NOTICE: Running database version Addons15
00:02:11 T:3038150656 DEBUG: Initialize, updating databases...
00:02:11 T:3038150656 NOTICE: Running database version ViewModes4
00:02:11 T:3038150656 NOTICE: Running database version Textures13
00:02:11 T:3038150656 NOTICE: Running database version MyMusic32
00:02:11 T:3038150656 NOTICE: Running database version MyVideos75
00:02:11 T:3028935744 ERROR: CecLogMessage - failed to release the previous LA
00:02:11 T:3028935744 ERROR: CecLogMessage - could not open a connection (try 1)
00:02:11 T:3038150656 NOTICE: Running database version TV22
00:02:11 T:3038150656 NOTICE: Running database version Epg7
00:02:11 T:3038150656 DEBUG: Initialize, updating databases... DONE
00:02:11 T:3038150656 NOTICE: start dvd mediatype detection
00:02:11 T:3038150656 NOTICE: initializing playlistplayer
00:02:11 T:3038150656 NOTICE: DONE initializing playlistplayer
00:02:11 T:3038150656 INFO: DPMS: not supported on this platform
00:02:11 T:2968618048 NOTICE: Thread CDetectDVDMedia start, auto delete: false
00:02:11 T:2968618048 DEBUG: Compiled with libcdio Version 0.83
00:02:11 T:2968618048 DEBUG: Thread CDetectDVDMedia 2968618048 terminating
00:02:11 T:3038150656 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
00:02:11 T:3038150656 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
00:02:11 T:3038150656 INFO: Unloading old skin ...
00:02:11 T:3038150656 INFO: load skin from: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence (version: 2.1.3)
00:02:11 T:3038150656 INFO: load fonts for skin...
00:02:11 T:3038150656 INFO: Loading fonts from /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/Font.xml
00:02:11 T:3038150656 DEBUG: POParser: loaded 131 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/language/German/strings.po
00:02:11 T:3038150656 DEBUG: POParser: loaded 0 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/language/English/strings.po
00:02:11 T:3038150656 INFO: Loading skin includes from /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/includes.xml
00:02:12 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchiq_initialise succeeded
00:02:12 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchi_initialise succeeded
00:02:12 T:3028935744 DEBUG: CecLogMessage - InitHostCEC - vchi_connect succeeded
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Broadcast (f)
00:02:12 T:3028935744 DEBUG: CecLogMessage - RegisterLogicalAddress - registering address e
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Playback 1 (4)
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Free use (e)
00:02:12 T:3028935744 DEBUG: CecLogMessage - Open - vc_cec initialised
00:02:12 T:3028935744 DEBUG: CecLogMessage - connection opened
00:02:12 T:3028935744 DEBUG: CecLogMessage - << Broadcast (F) -> TV (0): POLL
00:02:12 T:3028935744 DEBUG: CecLogMessage - initiator 'Broadcast' is not supported by the CEC adapter. using 'Free use' instead
00:02:12 T:3028935744 DEBUG: CecLogMessage - << e0
00:02:12 T:2908898368 DEBUG: CecLogMessage - processor thread started
00:02:12 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:12 T:3028935744 DEBUG: CecLogMessage - initiator 'Broadcast' is not supported by the CEC adapter. using 'Free use' instead
00:02:12 T:3028935744 DEBUG: CecLogMessage - << e0
00:02:12 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:12 T:3028935744 DEBUG: CecLogMessage - >> POLL not sent
00:02:12 T:3028935744 DEBUG: CecLogMessage - TV (0): device status changed into 'not present'
00:02:12 T:3028935744 DEBUG: CecLogMessage - registering new CEC client - v2.0.0
00:02:12 T:3028935744 DEBUG: CecLogMessage - detecting logical address for type 'recording device'
00:02:12 T:3028935744 DEBUG: CecLogMessage - trying logical address 'Recorder 1'
00:02:12 T:3028935744 DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 1 (1): POLL
00:02:12 T:3028935744 DEBUG: CecLogMessage - << 11
00:02:12 T:3028935744 DEBUG: CecLogMessage - UnregisterLogicalAddress - releasing previous logical address
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Broadcast (f)
00:02:12 T:3028935744 DEBUG: CecLogMessage - RegisterLogicalAddress - registering address 1
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Free use (e)
00:02:12 T:2995381312 DEBUG: CecLogMessage - logical address changed to Recorder 1 (1)
00:02:12 T:3028935744 DEBUG: CecLogMessage - << 11
00:02:12 T:3028935744 DEBUG: CecLogMessage - >> POLL not sent
00:02:12 T:3028935744 DEBUG: CecLogMessage - using logical address 'Recorder 1'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'handled by libCEC'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): power status changed from 'unknown' to 'on'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): CEC version 1.4
00:02:12 T:3028935744 DEBUG: CecLogMessage - AllocateLogicalAddresses - device '0', type 'recording device', LA '1'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): osd name set to 'XBMC'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): menu language set to 'eng'
00:02:12 T:3028935744 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 2100
00:02:12 T:3028935744 DEBUG: CecLogMessage - AutodetectPhysicalAddress - autodetected physical address '2100'
00:02:12 T:3028935744 DEBUG: CecLogMessage - Recorder 1 (1): physical address changed from ffff to 2100
00:02:12 T:3028935744 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical adddress 2100
00:02:12 T:3028935744 DEBUG: CecLogMessage - << 1f:84:21:00:01
00:02:12 T:3028935744 DEBUG: CecLogMessage - making Recorder 1 (1) the active source
00:02:12 T:3028935744 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
00:02:12 T:3028935744 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
00:02:12 T:3028935744 DEBUG: CecLogMessage - scheduling active source message for 'Recorder 1'
00:02:12 T:3028935744 DEBUG: CecLogMessage - CEC client registered: libCEC version = 2.0.5, client version = 2.0.0, firmware version = 1, logical address(es) = Recorder 1 (1) , physical address: 2.1.0.0, host: arm-bcm2708hardfp-linux-gnueabi, features: 'P8 USB' 'P8 USB detect' 'RPi', git revision: d4a56bb, compiled on: Thu Jan 31 01:16:46 UTC 2013 by root@ on Linux 3.2.0-35-generic (x86_64)
00:02:12 T:3028935744 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'XBMC'
00:02:12 T:3028935744 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:12 T:3028935744 DEBUG: CecLogMessage - << 10
00:02:13 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:13 T:3028935744 DEBUG: CecLogMessage - << 10
00:02:13 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:13 T:3028935744 DEBUG: CecLogMessage - >> POLL not sent
00:02:13 T:3028935744 DEBUG: CecLogMessage - not sending command 'set osd name': destination device 'TV' marked as not present
00:02:13 T:3028935744 DEBUG: CecLogMessage - << requesting power status of 'TV' (0)
00:02:13 T:3028935744 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:13 T:3028935744 DEBUG: CecLogMessage - << 10
00:02:13 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:13 T:3028935744 DEBUG: CecLogMessage - << 10
00:02:13 T:3028935744 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:13 T:3028935744 DEBUG: CecLogMessage - >> POLL not sent
00:02:13 T:3028935744 DEBUG: CecLogMessage - not sending command 'give device power status': destination device 'TV' marked as not present
00:02:13 T:3028935744 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'unknown'
00:02:13 T:3028935744 DEBUG: OpenConnection - connection to the CEC adapter opened
00:02:13 T:3038150656 INFO: load new skin...
00:02:13 T:3038150656 INFO: Loading user windows, path /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p
00:02:13 T:3038150656 DEBUG: Load Skin XML: 50.61ms
00:02:13 T:3038150656 INFO: initialize new skin...
00:02:13 T:3038150656 DEBUG: guilib: Fill viewport on change for solving rendering passes
00:02:13 T:3038150656 INFO: Loading skin file: Pointer.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 DEBUG: OpenBundle - Opened bundle /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/media/Textures.xbt
00:02:13 T:3038150656 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
00:02:13 T:3038150656 INFO: Loading /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/sounds.xml
00:02:13 T:3038150656 INFO: skin loaded...
00:02:14 T:2899309632 NOTICE: Thread CEC Adapter Update Thread start, auto delete: false
00:02:14 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:14 T:2899309632 DEBUG: CecLogMessage - << powering on 'TV' (0)
00:02:14 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:3038150656 INFO: JSONRPC v6.0.0: Successfully initialized
00:02:14 T:3038150656 DEBUG: ADDON: Starting service addons.
00:02:14 T:3038150656 INFO: initializing python engine.
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:14 T:2899309632 DEBUG: CecLogMessage - not sending command 'image view on': destination device 'TV' marked as not present
00:02:14 T:2899309632 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
00:02:14 T:2899309632 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
00:02:14 T:2899309632 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
00:02:14 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2908898368 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:14 T:2908898368 DEBUG: CecLogMessage - << 10
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2908898368 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2908898368 DEBUG: CecLogMessage - << 10
00:02:14 T:3038150656 DEBUG: new python thread created. id=1
00:02:14 T:3038150656 DEBUG: Activating window ID: 12999
00:02:14 T:3038150656 DEBUG: ------ Window Init (Startup.xml) ------
00:02:14 T:3038150656 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
00:02:14 T:2890122304 NOTICE: Thread XBPyThread start, auto delete: false
00:02:14 T:2890122304 DEBUG: Python thread: start processing
00:02:14 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:14 T:2899309632 DEBUG: CecLogMessage - TV not present, not sending 'image view on'
00:02:14 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (2100)
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 1f:82:21:00
00:02:14 T:3038150656 INFO: removing tempfiles
00:02:14 T:3038150656 DEBUG: ADDON: Starting service addons.
00:02:14 T:3038150656 NOTICE: initialize done
00:02:14 T:3038150656 NOTICE: Running the application...
00:02:14 T:3038150656 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
00:02:14 T:3038150656 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
00:02:14 T:3038150656 DEBUG: Activating window ID: 10000
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0x03908de8 dllopen : 1
00:02:14 T:2908898368 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:14 T:2908898368 DEBUG: CecLogMessage - >> POLL not sent
00:02:14 T:2908898368 DEBUG: CecLogMessage - TV not present, not sending 'image view on'
00:02:14 T:2908898368 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (2100)
00:02:14 T:2908898368 DEBUG: CecLogMessage - << 1f:82:21:00
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0x0391bfd0 dllopen : 1
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
00:02:14 T:3038150656 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
00:02:14 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:14 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:14 T:2890122304 NOTICE: -->Python Interpreter Initialized<--
00:02:14 T:2890122304 DEBUG: Process - The source file to load is /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings/autostart.py
00:02:14 T:2890122304 DEBUG: Process - Setting the Python path to /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/weather.wunderground/resources/lib/wunderground:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/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/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:
00:02:14 T:2890122304 DEBUG: Process - Entering source directory /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings
00:02:14 T:2890122304 DEBUG: Instantiating addon using automatically obtained id of "script.raspbmc.settings" dependent on version 2.0 of the xbmc.python api
00:02:15 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:15 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:15 T:3038150656 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0x0391bfd0 dllopen : 1
00:02:15 T:3038150656 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x03908de8 dllopen : 1
00:02:15 T:3038150656 DEBUG: ------ Window Init () ------
00:02:15 T:3038150656 DEBUG: ------ Window Init (Pointer.xml) ------
00:02:15 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:15 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:15 T:3038150656 DEBUG: ------ Window Deinit (Startup.xml) ------
00:02:15 T:3038150656 DEBUG: ------ Window Init (Home.xml) ------
00:02:15 T:3038150656 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
00:02:16 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:16 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:16 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:16 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:16 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:16 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:16 T:2890122304 NOTICE: services: {'sys.service.avahi': 'true', 'sys.service.remote': 'true', 'sys.service.ftp': 'true', 'sys.service.samba': 'true', 'sys.service.ssh': 'true', 'sys.service.cron': 'false', 'sys.service.tvh': 'false'}
00:02:16 T:2890122304 NOTICE: enable service: avahi-daemon
00:02:16 T:2890122304 NOTICE: enable service: eventlircd
00:02:17 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:17 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:17 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:17 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:17 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:17 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:18 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:18 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:18 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:18 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:18 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:18 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:19 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:02:19 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:19 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:19 T:2899309632 DEBUG: CecLogMessage - << 10
00:02:19 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:02:19 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:02:19 T:2869425216 NOTICE: Thread Jobworker start, auto delete: true
00:02:20 T:2890122304 NOTICE: enable service: ftp
00:00:00 T:2869425216 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
00:00:00 T:3038150656 DEBUG: Process - no profile autoexec.py (/home/pi/.xbmc/userdata/autoexec.py) found, skipping
00:00:00 T:3038150656 DEBUG: NetworkMessage - Starting network services
00:00:00 T:3038150656 NOTICE: Webserver: Starting...
00:00:00 T:3038150656 NOTICE: WebServer: Started the webserver
00:00:00 T:3038150656 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
00:00:00 T:3038150656 NOTICE: starting upnp server
00:00:00 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:00 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:00 T:2869425216 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
00:00:00 T:2869425216 DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
00:00:00 T:2861036608 DEBUG: webserver: request received for /pi/.xbmc/temp/xbmc.log
00:00:00 T:2869425216 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
00:00:00 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:00 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:00 T:2869425216 DEBUG: RunQuery took 6 ms for 0 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
00:00:00 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:00 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:00 T:2869425216 DEBUG: RunQuery took 196 ms for 0 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
00:00:00 T:3038150656 NOTICE: ES: Starting event server
00:00:00 T:2869425216 DEBUG: RunQuery took 27 ms for 0 items query: select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
00:00:00 T:2793927744 NOTICE: Thread CEventServer start, auto delete: false
00:00:00 T:2793927744 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
00:00:00 T:2793927744 NOTICE: UDP: Listening on port 9777
00:00:00 T:3038150656 INFO: JSONRPC Server: Successfully initialized
00:00:00 T:3038150656 NOTICE: starting zeroconf publishing
00:00:00 T:3038150656 INFO: AIRPLAY Server: Successfully initialized
00:00:00 T:3038150656 DEBUG: SECTION:LoadDLL(libshairport.so.0)
00:00:00 T:3038150656 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/libshairport.so.0
00:00:00 T:2768761920 NOTICE: Thread AirPlayServer start, auto delete: false
00:00:00 T:2777150528 NOTICE: Thread Jobworker start, auto delete: true
00:00:00 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC (raspbmc) port:9777
00:00:00 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: XBMC (raspbmc) type: _xbmc-events._udp port:9777
00:00:00 T:2785539136 NOTICE: Thread CTCPServer start, auto delete: false
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:XBMC (raspbmc) port:80
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: XBMC (raspbmc) type: _xbmc-jsonrpc-h._tcp port:80
00:00:01 T:2869425216 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC (raspbmc) port:9090
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: XBMC (raspbmc) type: _xbmc-jsonrpc._tcp port:9090
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:XBMC (raspbmc) port:80
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: XBMC (raspbmc) type: _http._tcp port:80
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:XBMC (raspbmc) port:36667
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: XBMC (raspbmc) type: _airplay._tcp port:36667
00:00:01 T:2743510080 NOTICE: Thread AirTunesServer start, auto delete: false
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:801F0284F9C1@XBMC (raspbmc) port:36666
00:00:01 T:2777150528 DEBUG: CZeroconfAvahi::addService() named: 801F0284F9C1@XBMC (raspbmc) type: _raop._tcp port:36666
00:00:01 T:3038150656 DEBUG: CLastfmScrobbler: Clearing session.
00:00:01 T:3038150656 DEBUG: CLibrefmScrobbler: Clearing session.
00:00:01 T:3038150656 DEBUG: UPower: Recieved an unknown signal NameAcquired
00:00:01 T:3038150656 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event0
00:00:01 T:3038150656 INFO: Found input device /dev/input/event0
00:00:01 T:3038150656 INFO: opened device 'Dell Dell USB Keyboard' (file name /dev/input/event0), m_bSkipNonKeyEvents 0
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event1
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event2
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event3
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event4
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event5
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event6
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event7
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event8
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event9
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event10
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event11
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event12
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event13
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event14
00:00:01 T:3038150656 DEBUG: Checking device: /dev/input/event15
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.image_decode handle 0xacd612a8 dllopen : 1
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Initialize : OMX.broadcom.resize handle 0xacdfc758 dllopen : 1
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
00:00:01 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:01 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(3686400) nBufferAlignmen(16)
00:00:01 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:01 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:01 T:2827482176 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
00:00:01 T:2899309632 DEBUG: Previous line repeats 4 times.
00:00:01 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:01 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.resize handle 0xacdfc758 dllopen : 1
00:00:01 T:3038150656 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0xacd612a8 dllopen : 1
00:00:02 T:2890122304 NOTICE: enable service: samba
00:00:02 T:2827482176 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
00:00:02 T:3038150656 DEBUG: ------ Window Deinit (Pointer.xml) ------
00:00:02 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:02 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:02 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:02 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:03 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:03 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:04 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:04 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:04 T:2890122304 NOTICE: enable service: ssh
00:00:04 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:04 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:04 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:04 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:04 T:2890122304 NOTICE: disable service: cron
00:00:04 T:2890122304 NOTICE: disable service: tvheadend
00:00:05 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:05 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:05 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:05 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:05 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:05 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:06 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:06 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:06 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:06 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:06 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:06 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:07 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:07 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:07 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:07 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:07 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:07 T:2899309632 DEBUG: CecLogMessage - >> POLL not sent
00:00:07 T:3038150656 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
00:00:08 T:3038150656 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
00:00:08 T:2890122304 NOTICE: system settings from addon: {'sys.config.freq.arm': 800, 'sys.config.freq.gpu': 250, 'sys.config.freq.sdram': 400, 'sys.config.freq.core': 250, 'sys.config.freq.overvolt': 0, 'sys.config.decode.wvc1': '', 'sys.config.disable.overscan': '0', 'sys.config.decode.ac3': '', 'sys.config.freq.isp': 250, 'sys.config.decode.mpg2': '', 'sys.config.decode.dts': ''}
00:00:08 T:2890122304 NOTICE: system settings from /boot/config.txt: {'sys.config.freq.arm': 800, 'sys.config.freq.gpu': 250, 'sys.config.freq.sdram': 400, 'sys.config.freq.core': 250, 'sys.config.freq.overvolt': 0, 'sys.config.decode.wvc1': '', 'sys.config.disable.overscan': '0', 'sys.config.decode.ac3': '', 'sys.config.freq.isp': 250, 'sys.config.decode.mpg2': '', 'sys.config.decode.dts': ''}
00:00:08 T:2890122304 INFO: Scriptresult: Success
00:00:08 T:2890122304 INFO: Python script stopped
00:00:08 T:2890122304 DEBUG: Thread XBPyThread 2890122304 terminating
00:00:08 T:3038150656 DEBUG: waiting for python thread 1 (/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings/autostart.py) to stop
00:00:08 T:3038150656 DEBUG: python thread 1 (/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings/autostart.py) destructed
00:00:08 T:2899309632 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): POLL
00:00:08 T:2899309632 DEBUG: CecLogMessage - << 10
00:00:08 T:2899309632 DEBUG: CecLogMessage - command 'POLL' was not acked by the controller
00:00:08 T:2899309632 DEBUG: CecLogMessage - << 10