1. 00:02:12 T:1075179520 NOTICE: -----------------------------------------------------------------------
  2. 00:02:12 T:1075179520 NOTICE: Starting XBMC (12.0-ALPHA3 Git:Unknown), Platform: Linux (6.0.5, 3.1.9-test-12-06 armv6l). Built on Jun 20 2012
  3. 00:02:12 T:1075179520 NOTICE: special://xbmc/ is mapped to: /opt/xbmc-bcm/xbmc-bin/share/xbmc
  4. 00:02:12 T:1075179520 NOTICE: special://xbmcbin/ is mapped to: /opt/xbmc-bcm/xbmc-bin/lib/xbmc
  5. 00:02:12 T:1075179520 NOTICE: special://masterprofile/ is mapped to: /home/pi/.xbmc/userdata
  6. 00:02:12 T:1075179520 NOTICE: special://home/ is mapped to: /home/pi/.xbmc
  7. 00:02:12 T:1075179520 NOTICE: special://temp/ is mapped to: /home/pi/.xbmc/temp
  8. 00:02:12 T:1075179520 NOTICE: The executable running is: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/xbmc.bin
  9. 00:02:12 T:1075179520 NOTICE: Local hostname: raspbmc
  10. 00:02:12 T:1075179520 NOTICE: Log File is located: /home/pi/.xbmc/temp/xbmc.log
  11. 00:02:12 T:1075179520 NOTICE: -----------------------------------------------------------------------
  12. 00:02:13 T:1075179520 NOTICE: load settings...
  13. 00:02:13 T:1075179520 NOTICE: special://profile/ is mapped to: special://masterprofile/
  14. 00:02:13 T:1075179520 NOTICE: loading special://masterprofile/guisettings.xml
  15. 00:02:13 T:1075179520 NOTICE: Getting hardware information now...
  16. 00:02:13 T:1075179520 NOTICE: Checking resolution 16
  17. 18:02:13 T:1075179520 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
  18. 18:02:13 T:1075179520 NOTICE: Loaded playercorefactory configuration
  19. 18:02:13 T:1075179520 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
  20. 18:02:13 T:1075179520 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
  21. 18:02:13 T:1075179520 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
  22. 18:02:13 T:1075179520 NOTICE: Loaded settings file from special://profile/advancedsettings.xml
  23. 18:02:13 T:1075179520 NOTICE: Contents of special://profile/advancedsettings.xml are...
  24. <advancedsettings>
  25. <splash>false</splash>
  26. <network>
  27. <cachemembuffersize>5282880</cachemembuffersize>
  28. </network>
  29. <fanartheight>540</fanartheight>
  30. <thumbsize>256</thumbsize>
  31. <gui>
  32. <algorithmdirtyregions>3</algorithmdirtyregions>
  33. <nofliptimeout>0</nofliptimeout>
  34. </gui>
  35. <lookandfeel>
  36. <enablerssfeeds>false</enablerssfeeds>
  37. <webserver>true</webserver>
  38. <webserverpassword />
  39. <webserverusername />
  40. </lookandfeel>
  41. <bginfoloadermaxthreads>2</bginfoloadermaxthreads>
  42. </advancedsettings>
  43. 18:02:13 T:1075179520 NOTICE: Getting hardware information now...
  44. 18:02:13 T:1075179520 NOTICE: Checking resolution 16
  45. 18:02:13 T:1075179520 NOTICE: Default DVD Player: omxplayer
  46. 18:02:13 T:1075179520 NOTICE: Default Video Player: omxplayer
  47. 18:02:13 T:1075179520 NOTICE: Default Audio Player: omxplayer
  48. 18:02:13 T:1075179520 NOTICE: Enabled debug logging due to GUI setting (2)
  49. 18:02:13 T:1075179520 NOTICE: Log level changed to 2
  50. 18:02:13 T:1075179520 NOTICE: Loading media sources from special://masterprofile/sources.xml
  51. 18:02:13 T:1075179520 INFO: creating subdirectories
  52. 18:02:13 T:1075179520 INFO: userdata folder: special://masterprofile/
  53. 18:02:13 T:1075179520 INFO: recording folder:
  54. 18:02:13 T:1075179520 INFO: screenshots folder:
  55. 18:02:13 T:1075179520 INFO: thumbnails folder: special://masterprofile/Thumbnails
  56. 18:02:13 T:1075179520 INFO: load language info file: special://xbmc/language/English/langinfo.xml
  57. 18:02:13 T:1075179520 DEBUG: trying to set locale to en_US.UTF-8
  58. 18:02:13 T:1075179520 INFO: global locale set to C
  59. 18:02:13 T:1075179520 INFO: load English language file, from path: special://xbmc/language/
  60. 18:02:13 T:1075179520 DEBUG: POParser: loaded 2238 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/language/English/strings.po
  61. 18:02:13 T:1075179520 INFO: CSoftAE::InternalOpenSink - sink incompatible, re-starting
  62. 18:02:13 T:1075179520 INFO: CSoftAE::InternalOpenSink - NULL Initialized:
  63. 18:02:13 T:1075179520 INFO: Output Device :
  64. 18:02:13 T:1075179520 INFO: Sample Rate : 44100
  65. 18:02:13 T:1075179520 INFO: Sample Format : AE_FMT_FLOAT
  66. 18:02:13 T:1075179520 INFO: Channel Count : 2
  67. 18:02:13 T:1075179520 INFO: Channel Layout: FL,FR
  68. 18:02:13 T:1075179520 INFO: Frames : 22000
  69. 18:02:13 T:1075179520 INFO: Frame Samples : 2
  70. 18:02:13 T:1075179520 INFO: Frame Size : 8
  71. 18:02:13 T:1075179520 DEBUG: CSoftAE::Initialize - Using speaker layout: 2.0
  72. 18:02:13 T:1164948544 NOTICE: Thread CSoftAE start, auto delete: false
  73. 18:02:13 T:1075179520 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so)
  74. 18:02:13 T:1075179520 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/libcpluff-arm.so
  75. 18:02:13 T:1164948544 INFO: CSoftAE::Run - Thread Started
  76. 18:02:13 T:1075179520 INFO: ADDON: cpluff: 'Could not read plug-in directory /opt/xbmc-bcm/xbmc-bin/lib/xbmc/addons: No such file or directory'
  77. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in repository.googlecode.anarchintosh-projects has been installed.'
  78. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
  79. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.games.rom.collection.browser has been installed.'
  80. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.raspbmc.settings has been installed.'
  81. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.albums.allmusic.com has been installed.'
  82. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.reddit.bc has been installed.'
  83. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
  84. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
  85. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
  86. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.'
  87. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in repository.bluecop.xbmc-plugins has been installed.'
  88. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.demjson has been installed.'
  89. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.yahoo.music.videos has been installed.'
  90. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.giantbomb has been installed.'
  91. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.'
  92. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in weather.wunderground has been installed.'
  93. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
  94. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
  95. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
  96. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in service.libraryautoupdate has been installed.'
  97. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.allmusic.com has been installed.'
  98. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
  99. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
  100. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.'
  101. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.'
  102. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
  103. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
  104. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.'
  105. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.common.plugin.cache has been installed.'
  106. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.free.cable has been installed.'
  107. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.'
  108. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.simple.downloader has been installed.'
  109. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
  110. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.youtube has been installed.'
  111. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.'
  112. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.slideshow has been installed.'
  113. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
  114. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in service.rom.collection.browser has been installed.'
  115. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
  116. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.yahoomusic.com has been installed.'
  117. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
  118. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
  119. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
  120. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.parsedom has been installed.'
  121. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
  122. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
  123. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.elementtree has been installed.'
  124. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.'
  125. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
  126. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.'
  127. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in script.module.pysqlite has been installed.'
  128. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in visualization.itunes has been installed.'
  129. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.'
  130. 18:02:13 T:1075179520 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
  131. 18:02:13 T:1075179520 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
  132. 18:02:13 T:1075179520 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
  133. 18:02:13 T:1075179520 INFO: LIRC Initialize: using: /dev/lircd
  134. 18:02:13 T:1075179520 INFO: LIRC Initialize: connect failed: No such file or directory
  135. 18:02:13 T:1075179520 DEBUG: Failed to connect to LIRC. Retry in 10s.
  136. 18:02:13 T:1075179520 DEBUG: Selected UDisks as storage provider
  137. 18:02:13 T:1075179520 DEBUG: UDisks: DaemonVersion 1
  138. 18:02:13 T:1075179520 DEBUG: UDisks: Querying available devices
  139. 18:02:13 T:1075179520 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0p2: IsFileSystem false HasFileSystem IsSystemInternal false IsMounted false IsRemovable false IsPartition true IsOptical false
  140. 18:02:13 T:1075179520 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0p3: IsFileSystem true HasFileSystem ext4 IsSystemInternal false IsMounted true IsRemovable false IsPartition true IsOptical false
  141. 18:02:13 T:1075179520 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
  142. 18:02:13 T:1075179520 DEBUG: UDisks: Mounting /org/freedesktop/UDisks/devices/mmcblk0p1
  143. 18:02:13 T:1075179520 ERROR: DBus: Error org.freedesktop.UDisks.Error.Failed - Error mounting: mount exited with exit code 1: helper failed with:
  144. mount: according to mtab, /dev/mmcblk0p1 is already mounted on /boot
  145. mount failed
  146. 18:02:13 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::GetSupportedModes (1) = 10, prefer_group=1, prefer_mode=10
  147. 18:02:13 T:1075179520 NOTICE: 0: 640x480@60 :1
  148. 18:02:13 T:1075179520 NOTICE: 1: 720x480@60 :2
  149. 18:02:13 T:1075179520 NOTICE: 2: 720x480@60 :3
  150. 18:02:13 T:1075179520 NOTICE: 3: 1280x720@60 :4
  151. 18:02:13 T:1075179520 NOTICE: 4: 1920x1080@60 I:5
  152. 18:02:13 T:1075179520 NOTICE: 5: 720x480@60 I:6
  153. 18:02:13 T:1075179520 NOTICE: 6: 720x480@60 I:7
  154. 18:02:13 T:1075179520 NOTICE: 7: 1920x1080@60 N:10
  155. 18:02:13 T:1075179520 NOTICE: 8: 1920x1080@24 :20
  156. 18:02:13 T:1075179520 NOTICE: 9: 1920x1080@30 :22
  157. 18:02:13 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::GetSupportedModes (2) = 1, prefer_group=1, prefer_mode=10
  158. 18:02:13 T:1075179520 NOTICE: 0: 640x480@60 :4
  159. 18:02:13 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::GetSupportedModes (3) = 0, prefer_group=1, prefer_mode=10
  160. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 640 x 480 @ 60.000000 Hz
  161. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 720 x 480 @ 60.000000 Hz
  162. 18:02:13 T:1075179520 NOTICE: Previous line repeats 1 times.
  163. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 1280 x 720 @ 60.000000 Hz
  164. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 1920 x 1080 @ 60.000000 Hz
  165. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 720 x 480 @ 60.000000 Hz
  166. 18:02:13 T:1075179520 NOTICE: Previous line repeats 1 times.
  167. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 1920 x 1080 @ 60.000000 Hz
  168. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 1920 x 1080 @ 24.000000 Hz
  169. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 1920 x 1080 @ 30.000000 Hz
  170. 18:02:13 T:1075179520 NOTICE: Found resolution for display 0 with 640 x 480 @ 60.000000 Hz
  171. 18:02:13 T:1075179520 NOTICE: Found ([email protected]) at 23, setting to RES_DESKTOP at 16
  172. 18:02:13 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  173. 18:02:13 T:1127330880 DEBUG: tvservice_callback(8,1,16)
  174. 18:02:13 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::SetDisplayResolution set HDMI mode (2,1,16)=0
  175. 18:02:13 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 1920x1080->1920x1080
  176. 18:02:13 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(1920x1080) (1920x1080)
  177. 18:02:14 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  178. 18:02:14 T:1075179520 NOTICE: EGL window and context creation complete
  179. 18:02:14 T:1075179520 NOTICE: GL_VENDOR = Broadcom
  180. 18:02:14 T:1075179520 NOTICE: GL_RENDERER = VideoCore IV HW
  181. 18:02:14 T:1075179520 NOTICE: GL_VERSION = OpenGL ES 2.0
  182. 18:02:14 T:1075179520 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
  183. 18:02:14 T:1075179520 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
  184. 18:02:14 T:1075179520 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
  185. 18:02:14 T:1075179520 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
  186. 18:02:14 T:1075179520 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
  187. 18:02:14 T:1075179520 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
  188. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  189. 18:02:14 T:1075179520 DEBUG: Compiled
  190. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  191. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  192. 18:02:14 T:1075179520 DEBUG: Compiled
  193. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  194. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x1c60b78
  195. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  196. 18:02:14 T:1075179520 DEBUG: Compiled
  197. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  198. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  199. 18:02:14 T:1075179520 DEBUG: Compiled
  200. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  201. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x1cabfd0
  202. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  203. 18:02:14 T:1075179520 DEBUG: Compiled
  204. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  205. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  206. 18:02:14 T:1075179520 DEBUG: Compiled
  207. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  208. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x1c95130
  209. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  210. 18:02:14 T:1075179520 DEBUG: Compiled
  211. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  212. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  213. 18:02:14 T:1075179520 DEBUG: Compiled
  214. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  215. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x1c951a8
  216. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  217. 18:02:14 T:1075179520 DEBUG: Compiled
  218. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  219. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  220. 18:02:14 T:1075179520 DEBUG: Compiled
  221. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  222. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x1cfb7a8
  223. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  224. 18:02:14 T:1075179520 DEBUG: Compiled
  225. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  226. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  227. 18:02:14 T:1075179520 DEBUG: Compiled
  228. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  229. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x1c94bc0
  230. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  231. 18:02:14 T:1075179520 DEBUG: Compiled
  232. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  233. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  234. 18:02:14 T:1075179520 DEBUG: Compiled
  235. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  236. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x1c94f60
  237. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compilation log:
  238. 18:02:14 T:1075179520 DEBUG: Compiled
  239. 18:02:14 T:1075179520 DEBUG: GL: Vertex Shader compiled successfully
  240. 18:02:14 T:1075179520 DEBUG: GL: Pixel Shader compilation log:
  241. 18:02:14 T:1075179520 DEBUG: Compiled
  242. 18:02:14 T:1075179520 DEBUG: GL: Fragment Shader compiled successfully
  243. 18:02:14 T:1075179520 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x1c95048
  244. 18:02:14 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  245. 18:02:14 T:1075179520 DEBUG: CWinSystemGLES::CreateNewWindow: No need to create a new window
  246. 18:02:14 T:1075179520 INFO: GLES: Maximum texture width: 2048
  247. 18:02:14 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  248. 18:02:14 T:1075179520 INFO: load keymapping
  249. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
  250. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
  251. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
  252. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
  253. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
  254. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
  255. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
  256. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
  257. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
  258. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
  259. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
  260. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
  261. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/mouse.xml
  262. 18:02:14 T:1075179520 INFO: Loading special://xbmc/system/keymaps/remote.xml
  263. 18:02:15 T:1075179520 INFO: Loading special://masterprofile/keymaps/keyboard.xml
  264. 18:02:15 T:1075179520 INFO: Loading special://profile/keymaps/keyboard.xml
  265. 18:02:15 T:1075179520 INFO: Loading special://xbmc/system/Lircmap.xml
  266. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'mceusb'
  267. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'XboxDVDDongle'
  268. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
  269. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
  270. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'anysee'
  271. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'iMON-PAD'
  272. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
  273. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'MCE_via_iMON'
  274. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'TwinHanRemote'
  275. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'linux-input-layer'
  276. 18:02:15 T:1075179520 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
  277. 18:02:15 T:1075179520 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput'
  278. 18:02:15 T:1075179520 INFO: * Adding remote mapping for device 'mediacenter'
  279. 18:02:15 T:1075179520 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
  280. 18:02:15 T:1075179520 INFO: GUI format 1920x1080 1920x1080 @ 60.00 - Full Screen
  281. 18:02:15 T:1075179520 DEBUG: guilib: Fill viewport on change for solving rendering passes
  282. 18:02:15 T:1075179520 DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4)
  283. 18:02:15 T:1075179520 DEBUG: Loading: libcurl-gnutls.so.4
  284. 18:02:15 T:1075179520 NOTICE: initializing playlistplayer
  285. 18:02:15 T:1075179520 NOTICE: DONE initializing playlistplayer
  286. 18:02:15 T:1075179520 INFO: DPMS: not supported on this platform
  287. 18:02:15 T:1075179520 INFO: Unloading old skin ...
  288. 18:02:15 T:1075179520 INFO: load skin from: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence
  289. 18:02:15 T:1075179520 INFO: load fonts for skin...
  290. 18:02:15 T:1075179520 INFO: Loading fonts from /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/Font.xml
  291. 18:02:15 T:1075179520 DEBUG: POParser: loaded 119 strings from file /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/language/English/strings.po
  292. 18:02:15 T:1075179520 INFO: Loading skin includes from /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p/includes.xml
  293. 18:02:17 T:1075179520 INFO: load new skin...
  294. 18:02:17 T:1075179520 INFO: Loading skin file: Home.xml
  295. 18:02:18 T:1075179520 INFO: Loading user windows, path /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/720p
  296. 18:02:18 T:1075179520 DEBUG: Load Skin XML: 1337.44ms
  297. 18:02:18 T:1075179520 INFO: initialize new skin...
  298. 18:02:18 T:1075179520 DEBUG: guilib: Fill viewport on change for solving rendering passes
  299. 18:02:18 T:1075179520 INFO: Loading skin file: Pointer.xml
  300. 18:02:18 T:1075179520 INFO: Loading skin file: DialogVolumeBar.xml
  301. 18:02:18 T:1075179520 INFO: Loading skin file: DialogKaiToast.xml
  302. 18:02:18 T:1075179520 INFO: Loading skin file: DialogMuteBug.xml
  303. 18:02:18 T:1075179520 INFO: Loading skin file: DialogSeekBar.xml
  304. 18:02:18 T:1075179520 INFO: Loading skin file: DialogBusy.xml
  305. 18:02:18 T:1075179520 INFO: Loading /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/sounds.xml
  306. 18:02:18 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/cursor.wav
  307. 18:02:18 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  308. 18:02:18 T:1075179520 INFO: ==[Downmix Matrix]==
  309. 18:02:18 T:1075179520 INFO: FL = FL(1)
  310. 18:02:18 T:1075179520 INFO: FR = FR(1)
  311. 18:02:18 T:1075179520 INFO: ====================
  312. 18:02:20 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/click.wav
  313. 18:02:20 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  314. 18:02:20 T:1075179520 INFO: ==[Downmix Matrix]==
  315. 18:02:20 T:1075179520 INFO: FL = FL(1)
  316. 18:02:20 T:1075179520 INFO: FR = FR(1)
  317. 18:02:20 T:1075179520 INFO: ====================
  318. 18:02:20 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/back.wav
  319. 18:02:20 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  320. 18:02:20 T:1075179520 INFO: ==[Downmix Matrix]==
  321. 18:02:20 T:1075179520 INFO: FL = FC(0.707107)
  322. 18:02:20 T:1075179520 INFO: FR = FC(0.707107)
  323. 18:02:20 T:1075179520 INFO: ====================
  324. 18:02:21 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/shutter.wav
  325. 18:02:21 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  326. 18:02:21 T:1075179520 INFO: ==[Downmix Matrix]==
  327. 18:02:21 T:1075179520 INFO: FL = FL(1)
  328. 18:02:21 T:1075179520 INFO: FR = FR(1)
  329. 18:02:21 T:1075179520 INFO: ====================
  330. 18:02:25 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/notify.wav
  331. 18:02:25 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  332. 18:02:25 T:1075179520 INFO: ==[Downmix Matrix]==
  333. 18:02:25 T:1075179520 INFO: FL = FC(0.707107)
  334. 18:02:25 T:1075179520 INFO: FR = FC(0.707107)
  335. 18:02:25 T:1075179520 INFO: ====================
  336. 18:02:25 T:1075179520 INFO: CAEWAVLoader::Initialize - Sound Loaded: /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/sounds/out.wav
  337. 18:02:25 T:1075179520 DEBUG: AERemap: Downmix normalization is disabled
  338. 18:02:25 T:1075179520 INFO: ==[Downmix Matrix]==
  339. 18:02:25 T:1075179520 INFO: FL = FC(0.707107)
  340. 18:02:25 T:1075179520 INFO: FR = FC(0.707107)
  341. 18:02:25 T:1075179520 INFO: ====================
  342. 18:02:25 T:1075179520 INFO: skin loaded...
  343. 18:02:25 T:1075179520 INFO: JSONRPC: Sucessfully initialized
  344. 18:02:25 T:1075179520 DEBUG: ADDON: Starting service addons.
  345. 18:02:25 T:1075179520 INFO: initializing python engine.
  346. 18:02:26 T:1075179520 DEBUG: new python thread created. id=1
  347. 18:02:26 T:1075179520 INFO: initializing python engine.
  348. 18:02:26 T:1075179520 DEBUG: new python thread created. id=2
  349. 18:02:26 T:1075179520 INFO: initializing python engine.
  350. 18:02:26 T:1075179520 DEBUG: new python thread created. id=3
  351. 18:02:26 T:1075179520 INFO: initializing python engine.
  352. 18:02:26 T:1075179520 DEBUG: new python thread created. id=4
  353. 18:02:26 T:1075179520 DEBUG: Activating window ID: 12999
  354. 18:02:26 T:1185649728 NOTICE: Thread XBPyThread start, auto delete: false
  355. 18:02:26 T:1185649728 DEBUG: Python thread: start processing
  356. 18:02:26 T:1194816576 NOTICE: Thread XBPyThread start, auto delete: false
  357. 18:02:26 T:1194816576 DEBUG: Python thread: start processing
  358. 18:02:26 T:1203795008 NOTICE: Thread XBPyThread start, auto delete: false
  359. 18:02:26 T:1203795008 DEBUG: Python thread: start processing
  360. 18:02:26 T:1212183616 NOTICE: Thread XBPyThread start, auto delete: false
  361. 18:02:26 T:1212183616 DEBUG: Python thread: start processing
  362. 18:02:26 T:1075179520 DEBUG: ------ Window Init (Startup.xml) ------
  363. 18:02:26 T:1075179520 INFO: Loading skin file: Startup.xml
  364. 18:02:26 T:1075179520 INFO: removing tempfiles
  365. 18:02:26 T:1075179520 DEBUG: ADDON: Starting service addons.
  366. 18:02:26 T:1075179520 INFO: initializing python engine.
  367. 18:02:26 T:1075179520 DEBUG: new python thread created. id=5
  368. 18:02:26 T:1075179520 NOTICE: initialize done
  369. 18:02:26 T:1075179520 NOTICE: Running the application...
  370. 18:02:26 T:1075179520 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home)
  371. 18:02:26 T:1075179520 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home)
  372. 18:02:26 T:1075179520 DEBUG: Activating window ID: 10000
  373. 18:02:26 T:1220572224 NOTICE: Thread XBPyThread start, auto delete: false
  374. 18:02:26 T:1220572224 DEBUG: Python thread: start processing
  375. 18:02:26 T:1075179520 DEBUG: OpenBundle - Opened bundle /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/skin.confluence/media/Textures.xbt
  376. 18:02:26 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  377. 18:02:26 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  378. 18:02:26 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  379. 18:02:26 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
  380. 18:02:27 T:1185649728 NOTICE: -->Python Interpreter Initialized<--
  381. 18:02:27 T:1185649728 DEBUG: Process - The source file to load is /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings/autostart.py
  382. 18:02:27 T:1075179520 DEBUG: ------ Window Init () ------
  383. 18:02:27 T:1075179520 DEBUG: ------ Window Init (Pointer.xml) ------
  384. 18:02:27 T:1075179520 DEBUG: ------ Window Deinit (Startup.xml) ------
  385. 18:02:27 T:1194816576 NOTICE: -->Python Interpreter Initialized<--
  386. 18:02:27 T:1194816576 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/service.libraryautoupdate/default.py
  387. 18:02:27 T:1075179520 DEBUG: ------ Window Init (Home.xml) ------
  388. 18:02:27 T:1228960832 NOTICE: Thread Jobworker start, auto delete: true
  389. 18:02:27 T:1220572224 NOTICE: -->Python Interpreter Initialized<--
  390. 18:02:27 T:1220572224 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/script.common.plugin.cache/default.py
  391. 18:02:27 T:1185649728 DEBUG: Process - Setting the Python path to /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  392. 18:02:27 T:1185649728 DEBUG: Process - Entering source directory /opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.raspbmc.settings
  393. 18:02:27 T:1185649728 DEBUG: Instantiating addon using automatically obtained id of "script.raspbmc.settings" dependent on version 2.0 of the xbmc.python api
  394. 18:02:27 T:1194816576 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/service.libraryautoupdate:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  395. 18:02:27 T:1194816576 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/service.libraryautoupdate
  396. 18:02:27 T:1194816576 DEBUG: Instantiating addon using automatically obtained id of "service.libraryautoupdate" dependent on version 2.0 of the xbmc.python api
  397. 18:02:28 T:1228960832 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
  398. 18:02:28 T:1075179520 DEBUG: Process - no profile autoexec.py (/home/pi/.xbmc/userdata/autoexec.py) found, skipping
  399. 18:02:28 T:1075179520 DEBUG: NetworkMessage - Starting network services
  400. 18:02:28 T:1075179520 NOTICE: Webserver: Starting...
  401. 18:02:28 T:1075179520 NOTICE: WebServer: Started the webserver
  402. 18:02:28 T:1075179520 INFO: CZeroconfAvahi::clientCallback: avahi server not available. But may become later...
  403. 18:02:28 T:1075179520 NOTICE: starting upnp renderer
  404. 18:02:28 T:1228960832 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
  405. 18:02:28 T:1075179520 NOTICE: ES: Starting event server
  406. 18:02:28 T:1075179520 INFO: JSONRPC Server: Successfully initialized
  407. 18:02:28 T:1075179520 NOTICE: starting zeroconf publishing
  408. 18:02:28 T:1075179520 DEBUG: CLastfmScrobbler: Clearing session.
  409. 18:02:28 T:1075179520 DEBUG: CLibrefmScrobbler: Clearing session.
  410. 18:02:28 T:1075179520 DEBUG: UPower: Recieved an unknown signal NameAcquired
  411. 18:02:28 T:1075179520 INFO: LIRC Initialize: using: /dev/lircd
  412. 18:02:28 T:1075179520 DEBUG: Failed to connect to LIRC. Retry in 20s.
  413. 18:02:28 T:1309537344 NOTICE: Thread CEventServer start, auto delete: false
  414. 18:02:28 T:1309537344 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
  415. 18:02:28 T:1309537344 NOTICE: UDP: Listening on port 9777
  416. 18:02:28 T:1318814784 NOTICE: Thread CTCPServer start, auto delete: false
  417. 18:02:28 T:1327629376 NOTICE: Thread Jobworker start, auto delete: true
  418. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:XBMC (raspbmc) port:8080
  419. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
  420. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC (raspbmc) port:9090
  421. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
  422. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webapi type: _xbmc-web._tcp name:XBMC (raspbmc) port:8080
  423. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
  424. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:XBMC (raspbmc) port:8080
  425. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
  426. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC (raspbmc) port:9777
  427. 18:02:28 T:1327629376 DEBUG: CZeroconfAvahi::doPublishService: client not running, queued for publishing
  428. 18:02:28 T:1194816576 NOTICE: Update Library Service starting...
  429. 18:02:28 T:1203795008 NOTICE: -->Python Interpreter Initialized<--
  430. 18:02:28 T:1203795008 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/script.module.simple.downloader/default.py
  431. 18:02:28 T:1212183616 NOTICE: -->Python Interpreter Initialized<--
  432. 18:02:28 T:1212183616 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/service.rom.collection.browser/default.py
  433. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event0
  434. 18:02:28 T:1075179520 INFO: Found input device /dev/input/event0
  435. 18:02:28 T:1075179520 INFO: opened device 'Motorola Motorola NYXboard, 2.4GHz device' (file name /dev/input/event0), m_bSkipNonKeyEvents 0
  436. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event1
  437. 18:02:28 T:1075179520 INFO: Found input device /dev/input/event1
  438. 18:02:28 T:1075179520 INFO: opened device 'Motorola Motorola NYXboard, 2.4GHz device' (file name /dev/input/event1), m_bSkipNonKeyEvents 0
  439. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event2
  440. 18:02:28 T:1075179520 INFO: Found input device /dev/input/event2
  441. 18:02:28 T:1075179520 INFO: opened device 'Motorola Motorola NYXboard, 2.4GHz device' (file name /dev/input/event2), m_bSkipNonKeyEvents 0
  442. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event3
  443. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event4
  444. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event5
  445. 18:02:28 T:1220572224 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/script.common.plugin.cache:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  446. 18:02:28 T:1220572224 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/script.common.plugin.cache
  447. 18:02:28 T:1203795008 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/script.module.simple.downloader:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  448. 18:02:28 T:1203795008 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/script.module.simple.downloader
  449. 18:02:28 T:1203795008 DEBUG: Instantiating addon using automatically obtained id of "script.module.simple.downloader" dependent on version 2.0 of the xbmc.python api
  450. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event6
  451. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event7
  452. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event8
  453. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event9
  454. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event10
  455. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event11
  456. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event12
  457. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event13
  458. 18:02:28 T:1212183616 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/service.rom.collection.browser:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  459. 18:02:28 T:1212183616 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/service.rom.collection.browser
  460. 18:02:28 T:1220572224 DEBUG: Instantiating addon using automatically obtained id of "script.common.plugin.cache" dependent on version 2.0 of the xbmc.python api
  461. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event14
  462. 18:02:28 T:1075179520 DEBUG: Checking device: /dev/input/event15
  463. 18:02:28 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  464. 18:02:28 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  465. 18:02:28 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  466. 18:02:28 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
  467. 18:02:28 T:1212183616 DEBUG: Instantiating addon using automatically obtained id of "service.rom.collection.browser" dependent on version 2.0 of the xbmc.python api
  468. 18:02:28 T:1203795008 NOTICE: downloader ARGV: ['']
  469. 18:02:28 T:1203795008 INFO: Scriptresult: Success
  470. 18:02:28 T:1212183616 NOTICE: RCB Service: Start
  471. 18:02:28 T:1212183616 NOTICE: RCB Service: launch RCB on startup =
  472. 18:02:28 T:1212183616 NOTICE: RCB Service: scrape games on startup = true
  473. 18:02:28 T:1212183616 NOTICE: RCB Service: Starting DB Update/home/pi/.xbmc/addons/script.games.rom.collection.browser/dbUpLauncher.py
  474. 18:02:28 T:1212183616 NOTICE: RCB Service: Done
  475. 18:02:28 T:1212183616 INFO: Scriptresult: Success
  476. 18:02:28 T:1203795008 INFO: Python script stopped
  477. 18:02:28 T:1203795008 DEBUG: Thread XBPyThread 1203795008 terminating
  478. 18:02:29 T:1212183616 INFO: Python script stopped
  479. 18:02:29 T:1212183616 DEBUG: Thread XBPyThread 1212183616 terminating
  480. 18:02:29 T:1194816576 NOTICE: service.libraryautoupdate: update timers
  481. 18:02:29 T:1327629376 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
  482. 18:02:29 T:1327629376 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/ImageLib-arm.so
  483. 18:02:29 T:1075179520 DEBUG: ------ Window Deinit (Pointer.xml) ------
  484. 18:02:30 T:1075179520 DEBUG: waiting for python thread 3 to stop
  485. 18:02:30 T:1075179520 DEBUG: python thread 3 destructed
  486. 18:02:30 T:1075179520 DEBUG: waiting for python thread 4 to stop
  487. 18:02:30 T:1075179520 DEBUG: python thread 4 destructed
  488. 18:02:30 T:1075179520 INFO: initializing python engine.
  489. 18:02:30 T:1075179520 DEBUG: new python thread created. id=6
  490. 18:02:30 T:1212183616 NOTICE: Thread XBPyThread start, auto delete: false
  491. 18:02:30 T:1212183616 DEBUG: Python thread: start processing
  492. 18:02:30 T:1327629376 DEBUG: DoWork - took 370 ms to load special://masterprofile/Thumbnails/7/7f8bcb0c.png
  493. 18:02:31 T:1228960832 DEBUG: GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10
  494. 18:02:32 T:1228960832 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
  495. 18:02:32 T:1220572224 NOTICE: StorageServer Module loaded RUN
  496. 18:02:32 T:1220572224 NOTICE: StorageClient-0.9.2 Starting server
  497. 18:02:32 T:1220572224 NOTICE: StorageServer-0.9.2 Storage Server starting /home/pi/.xbmc/userdata/Database/commoncache.db
  498. 18:02:33 T:1075179520 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0
  499. 18:02:33 T:1075179520 DEBUG: OnKey: return (f00d) pressed, action is Select
  500. 18:02:33 T:1075179520 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(Programs,Addons,return)
  501. 18:02:33 T:1075179520 DEBUG: ExecuteXBMCAction : To ActivateWindow(Programs,Addons,return)
  502. 18:02:33 T:1075179520 DEBUG: Activating window ID: 10001
  503. 18:02:33 T:1228960832 DEBUG: RunQuery took 1545 ms for 10 items query: select * from movieview ORDER BY dateAdded desc, idMovie desc LIMIT 10
  504. 18:02:34 T:1075179520 DEBUG: ------ Window Deinit (Home.xml) ------
  505. 18:02:34 T:1075179520 DEBUG: ------ Window Init (MyPrograms.xml) ------
  506. 18:02:34 T:1075179520 INFO: Loading skin file: MyPrograms.xml
  507. 18:02:34 T:1212183616 NOTICE: -->Python Interpreter Initialized<--
  508. 18:02:34 T:1212183616 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/script.games.rom.collection.browser/dbUpLauncher.py
  509. 18:02:35 T:1212183616 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/script.games.rom.collection.browser:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  510. 18:02:35 T:1212183616 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/script.games.rom.collection.browser
  511. 18:02:36 T:1075179520 DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/executable/)
  512. 18:02:36 T:1075179520 DEBUG: ParentPath = [addons://sources/executable/]
  513. 18:02:36 T:1212183616 NOTICE: RCB_INFO: Loading sqlite3 as DB engine
  514. 18:02:36 T:1212183616 NOTICE: RCB: init log level
  515. 18:02:36 T:1212183616 NOTICE: RCB: current log level: 2
  516. 18:02:36 T:1212183616 NOTICE: RCB_INFO: Loading sqlite3 as DB engine
  517. 18:02:36 T:1203795008 NOTICE: Thread Background Loader start, auto delete: false
  518. 18:02:36 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  519. 18:02:36 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  520. 18:02:36 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  521. 18:02:36 T:1203795008 WARNING: CreateLoader - Unsupported protocol(addons) in addons://more/executable.tbn
  522. 18:02:36 T:1203795008 DEBUG: Thread Background Loader 1203795008 terminating
  523. 18:02:36 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
  524. 18:02:38 T:1185649728 INFO: Scriptresult: Success
  525. 18:02:38 T:1228960832 DEBUG: RunQuery took 2403 ms for 10 items query: select * from episodeview ORDER BY dateAdded desc, idEpisode desc LIMIT 10
  526. 18:02:39 T:1185649728 INFO: Python script stopped
  527. 18:02:39 T:1185649728 DEBUG: Thread XBPyThread 1185649728 terminating
  528. 18:02:39 T:1327629376 DEBUG: DoWork - trying to extract filestream details from video file smb://ONEADVENT-DESKTO/tv shows/Pawn Stars/Pawn Stars - 3x41 - Silver Linings.avi
  529. 18:02:40 T:1075179520 DEBUG: waiting for python thread 1 to stop
  530. 18:02:40 T:1075179520 DEBUG: python thread 1 destructed
  531. 18:02:42 T:1075179520 DEBUG: UDisks: Is not able to mount DeviceUDI /org/freedesktop/UDisks/devices/mmcblk0p2: IsFileSystem false HasFileSystem IsSystemInternal false IsMounted false IsRemovable false IsPartition true IsOptical false
  532. 18:02:42 T:1228960832 DEBUG: GetMusicVideosByWhere query = select * from musicvideoview ORDER BY dateAdded desc, idMVideo desc LIMIT 10
  533. 18:02:42 T:1075179520 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
  534. 18:02:42 T:1228960832 DEBUG: GetMusicVideosByWhere time for actual SQL query = 61
  535. 18:02:42 T:1327629376 DEBUG: CSmbFile::Open - opened tv shows/Pawn Stars/Pawn Stars - 3x41 - Silver Linings.avi, fd=10000
  536. 18:02:42 T:1327629376 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-51-arm.so)
  537. 18:02:42 T:1327629376 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avutil-51-arm.so
  538. 18:02:42 T:1327629376 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-53-arm.so)
  539. 18:02:42 T:1327629376 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avcodec-53-arm.so
  540. 18:02:42 T:1327629376 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avformat-53-arm.so)
  541. 18:02:42 T:1327629376 DEBUG: Loading: /opt/xbmc-bcm/xbmc-bin/lib/xbmc/system/players/dvdplayer/avformat-53-arm.so
  542. 18:02:43 T:1327629376 DEBUG: Open - probing detected format [avi]
  543. 18:02:43 T:1212183616 NOTICE: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/MyGames.db
  544. 18:02:43 T:1228960832 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
  545. 18:02:43 T:1212183616 NOTICE: RCB_INFO: Begin readXml
  546. 18:02:43 T:1212183616 NOTICE: RCB_INFO: Path to configuration file: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/config.xml
  547. 18:02:44 T:1212183616 NOTICE: RCB_INFO: Begin readRomCollections
  548. 18:02:44 T:1212183616 NOTICE: RCB_INFO: current Rom Collection: Game Boy
  549. 18:02:44 T:1212183616 NOTICE: RCB_INFO: Rom path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/*.gb
  550. 18:02:44 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/boxfront/%GAME%.*
  551. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/boxback/%GAME%.*
  552. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/cartridge/%GAME%.*
  553. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/screenshot/%GAME%.*
  554. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/fanart/%GAME%.*
  555. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper site: thegamesdb.net
  556. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper Site: thegamesdb.net
  557. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  558. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper site: archive.vg
  559. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper Site: archive.vg
  560. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  561. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper site: mobygames.com
  562. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper Site: mobygames.com
  563. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  564. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Image Placing name: gameinfobig
  565. 18:02:45 T:1327629376 DEBUG: Open - avformat_find_stream_info starting
  566. 18:02:45 T:1212183616 NOTICE: RCB_INFO: Image Placing name: gameinfosmall
  567. 18:02:45 T:1327629376 DEBUG: Open - av_find_stream_info finished
  568. 18:02:45 T:1327629376 INFO: ffmpeg[4F220440]: Input #0, avi, from 'smb://ONEADVENT-DESKTO/tv shows/Pawn Stars/Pawn Stars - 3x41 - Silver Linings.avi':
  569. 18:02:45 T:1327629376 INFO: ffmpeg[4F220440]: Duration: 00:21:59.48, start: 0.000000, bitrate: 1112 kb/s
  570. 18:02:45 T:1327629376 INFO: ffmpeg[4F220440]: Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 624x352 [SAR 1:1 DAR 39:22], 23.98 tbr, 23.98 tbn, 23.98 tbc
  571. 18:02:45 T:1327629376 INFO: ffmpeg[4F220440]: Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16, 128 kb/s
  572. 18:02:45 T:1327629376 DEBUG: CSmbFile::Close closing fd 10000
  573. 18:02:45 T:1185649728 ERROR: SetThreadInfo: error Permission denied
  574. 18:02:45 T:1185649728 NOTICE: Thread Jobworker start, auto delete: true
  575. 18:02:45 T:1327629376 DEBUG: DoWork - trying to extract filestream details from video file smb://ONEADVENT-DESKTO/tv shows/Teen Mom 2/Teen Mom 2 - 1x06 - Taking Sides.avi
  576. 18:02:45 T:1327629376 DEBUG: CSmbFile::Open - opened tv shows/Teen Mom 2/Teen Mom 2 - 1x06 - Taking Sides.avi, fd=10000
  577. 18:02:45 T:1327629376 DEBUG: Open - probing detected format [avi]
  578. 18:02:46 T:1212183616 NOTICE: RCB_INFO: emulatorCmd: /home/pi/emulator/gcat-gameboy-1.0/gameboy
  579. 18:02:46 T:1212183616 NOTICE: RCB_INFO: emulatorParams: "%ROM%"
  580. 18:02:46 T:1212183616 NOTICE: RCB_INFO: ignoreOnScan: False
  581. 18:02:46 T:1212183616 NOTICE: RCB_INFO: allowUpdate: True
  582. 18:02:46 T:1212183616 NOTICE: RCB_INFO: useEmuSolo: False
  583. 18:02:46 T:1212183616 NOTICE: RCB_INFO: autoplayVideoMain: True
  584. 18:02:46 T:1212183616 NOTICE: RCB_INFO: autoplayVideoInfo: True
  585. 18:02:46 T:1212183616 NOTICE: RCB_INFO: useFoldernameAsGamename: False
  586. 18:02:46 T:1212183616 NOTICE: RCB_INFO: maxFolderDepth: 99
  587. 18:02:46 T:1212183616 NOTICE: RCB_INFO: doNotExtractZipFiles: False
  588. 18:02:46 T:1212183616 NOTICE: RCB_INFO: diskPrefix: _Disk
  589. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: local nfo
  590. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  591. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: thegamesdb.net
  592. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  593. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: giantbomb.com
  594. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  595. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: mobygames.com
  596. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  597. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: archive.vg
  598. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  599. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper Site: maws.mameworld.info
  600. 18:02:46 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  601. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Start Update DB
  602. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Iterating Rom Collections
  603. 18:02:47 T:1212183616 NOTICE: RCB_INFO: current Rom Collection: Game Boy
  604. 18:02:47 T:1212183616 NOTICE: RCB_INFO: ignoreOnScan: False
  605. 18:02:47 T:1212183616 NOTICE: RCB_INFO: update is allowed for current rom collection: True
  606. 18:02:47 T:1212183616 NOTICE: RCB_INFO: max folder depth: 99
  607. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Rom path: ['/home/pi/emulator/gcat-gameboy-1.0/ROMS/*.gb']
  608. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Reading rom files
  609. 18:02:47 T:1212183616 NOTICE: RCB_INFO: walkDownPath romPath: /home/pi/emulator/gcat-gameboy-1.0/ROMS/*.gb
  610. 18:02:47 T:1212183616 NOTICE: RCB_INFO: dirname: /home/pi/emulator/gcat-gameboy-1.0/ROMS
  611. 18:02:47 T:1212183616 NOTICE: RCB_INFO: basename: *.gb
  612. 18:02:47 T:1212183616 NOTICE: RCB_INFO: checking sub directories
  613. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Files read: []
  614. 18:02:47 T:1212183616 NOTICE: RCB_INFO: Update finished
  615. 18:02:47 T:1075179520 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
  616. 18:02:47 T:1212183616 INFO: Scriptresult: Success
  617. 18:02:48 T:1212183616 INFO: Python script stopped
  618. 18:02:48 T:1212183616 DEBUG: Thread XBPyThread 1212183616 terminating
  619. 18:02:48 T:1075179520 DEBUG: waiting for python thread 6 to stop
  620. 18:02:48 T:1075179520 DEBUG: python thread 6 destructed
  621. 18:02:48 T:1075179520 INFO: LIRC Initialize: using: /dev/lircd
  622. 18:02:48 T:1075179520 DEBUG: Failed to connect to LIRC. Retry in 40s.
  623. 18:02:48 T:1327629376 DEBUG: Open - avformat_find_stream_info starting
  624. 18:02:48 T:1327629376 DEBUG: ffmpeg[4F220440]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
  625. 18:02:48 T:1327629376 DEBUG: Open - av_find_stream_info finished
  626. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: Input #0, avi, from 'smb://ONEADVENT-DESKTO/tv shows/Teen Mom 2/Teen Mom 2 - 1x06 - Taking Sides.avi':
  627. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: Metadata:
  628. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: encoder : VirtualDubMod 1.5.10.2 (build 2540/release)
  629. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: Duration: 00:41:19.24, start: 0.000000, bitrate: 1180 kb/s
  630. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 624x352 [SAR 1:1 DAR 39:22], 29.97 tbr, 29.97 tbn, 29.98 tbc
  631. 18:02:48 T:1327629376 INFO: ffmpeg[4F220440]: Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16, 224 kb/s
  632. 18:02:48 T:1327629376 DEBUG: CSmbFile::Close closing fd 10000
  633. 18:02:48 T:1185649728 DEBUG: DoWork - trying to extract filestream details from video file smb://ONEADVENT-DESKTO/tv shows/Teen Mom 2/Teen Mom 2 - 1x09 - Slippery Slope.avi
  634. 18:02:48 T:1185649728 DEBUG: CSmbFile::Open - opened tv shows/Teen Mom 2/Teen Mom 2 - 1x09 - Slippery Slope.avi, fd=10000
  635. 18:02:48 T:1185649728 DEBUG: Open - probing detected format [avi]
  636. 18:02:50 T:1185649728 DEBUG: Open - avformat_find_stream_info starting
  637. 18:02:50 T:1185649728 DEBUG: ffmpeg[46AB9440]: [mpeg4] Invalid and inefficient vfw-avi packed B frames detected
  638. 18:02:50 T:1185649728 DEBUG: Open - av_find_stream_info finished
  639. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: Input #0, avi, from 'smb://ONEADVENT-DESKTO/tv shows/Teen Mom 2/Teen Mom 2 - 1x09 - Slippery Slope.avi':
  640. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: Metadata:
  641. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: encoder : VirtualDubMod 1.5.10.2 (build 2540/release)
  642. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: Duration: 00:41:31.63, start: 0.000000, bitrate: 1175 kb/s
  643. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: Stream #0:0: Video: mpeg4 (Advanced Simple Profile) (XVID / 0x44495658), yuv420p, 624x352 [SAR 1:1 DAR 39:22], 29.97 tbr, 29.97 tbn, 29.98 tbc
  644. 18:02:50 T:1185649728 INFO: ffmpeg[46AB9440]: Stream #0:1: Audio: mp3 (U[0][0][0] / 0x0055), 48000 Hz, stereo, s16, 96 kb/s
  645. 18:02:50 T:1185649728 DEBUG: CSmbFile::Close closing fd 10000
  646. 18:02:51 T:1075179520 DEBUG: Keyboard: scancode: 6c, sym: 0112, unicode: 0000, modifier: 0
  647. 18:02:51 T:1075179520 DEBUG: OnKey: down (f081) pressed, action is Down
  648. 18:02:51 T:1075179520 DEBUG: Keyboard: scancode: 6c, sym: 0112, unicode: 0000, modifier: 0
  649. 18:02:51 T:1075179520 DEBUG: OnKey: down (f081) pressed, action is Down
  650. 18:02:51 T:1228960832 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  651. 18:02:51 T:1228960832 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  652. 18:02:51 T:1228960832 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  653. 18:02:51 T:1228960832 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(262144) nBufferAlignmen(16)
  654. 18:02:51 T:1228960832 DEBUG: DoWork - took 126 ms to load special://masterprofile/Thumbnails/a/ab968fd8.jpg
  655. 18:02:52 T:1075179520 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0
  656. 18:02:52 T:1075179520 DEBUG: OnKey: return (f00d) pressed, action is Select
  657. 18:02:52 T:1075179520 INFO: initializing python engine.
  658. 18:02:52 T:1075179520 DEBUG: new python thread created. id=7
  659. 18:02:52 T:1212183616 NOTICE: Thread XBPyThread start, auto delete: false
  660. 18:02:52 T:1212183616 DEBUG: Python thread: start processing
  661. 18:02:52 T:1212183616 NOTICE: -->Python Interpreter Initialized<--
  662. 18:02:52 T:1212183616 DEBUG: Process - The source file to load is /home/pi/.xbmc/addons/script.games.rom.collection.browser/default.py
  663. 18:02:52 T:1212183616 DEBUG: Process - Setting the Python path to /home/pi/.xbmc/addons/script.games.rom.collection.browser:/home/pi/.xbmc/addons/script.module.demjson/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.simplejson/lib:/home/pi/.xbmc/addons/script.module.beautifulsoup/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pil/lib:/home/pi/.xbmc/addons/script.common.plugin.cache/lib:/home/pi/.xbmc/addons/script.module.simple.downloader/lib:/home/pi/.xbmc/addons/script.module.parsedom/lib:/home/pi/.xbmc/addons/script.module.elementtree/lib:/opt/xbmc-bcm/xbmc-bin/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.6:/usr/lib/python2.6/plat-linux2:/usr/lib/python2.6/lib-tk:/usr/lib/python2.6/lib-old:/usr/lib/python2.6/lib-dynload:/usr/local/lib/python2.6/dist-packages:/usr/lib/python2.6/dist-packages:/usr/lib/pymodules/python2.6:/usr/lib/pymodules/python2.6/gtk-2.0:
  664. 18:02:52 T:1212183616 DEBUG: Process - Entering source directory /home/pi/.xbmc/addons/script.games.rom.collection.browser
  665. 18:02:52 T:1212183616 DEBUG: Instantiating addon using automatically obtained id of "script.games.rom.collection.browser" dependent on version 2.0 of the xbmc.python api
  666. 18:02:53 T:1212183616 NOTICE: RCB_INFO: Loading sqlite3 as DB engine
  667. 18:02:54 T:1212183616 NOTICE: RCB: init log level
  668. 18:02:54 T:1212183616 NOTICE: RCB: current log level: 2
  669. 18:02:54 T:1212183616 NOTICE: RCB_INFO: Loading sqlite3 as DB engine
  670. 18:02:56 T:1212183616 NOTICE: RCB_INFO: Path to configuration file: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/config.xml
  671. 18:02:56 T:1212183616 NOTICE: RCB_INFO: XBMC version: Assuming we are on Eden
  672. 18:02:56 T:1212183616 NOTICE: RCB_INFO: Init Rom Collection Browser: /home/pi/.xbmc/addons/script.games.rom.collection.browser
  673. 18:02:56 T:1212183616 NOTICE: RCB_INFO: RCB version: 1.0.1
  674. 18:02:56 T:1212183616 NOTICE: RCB_INFO: RCB service addon: <xbmcaddon.Addon object at 0x433654d0>
  675. 18:02:56 T:1212183616 NOTICE: RCB_INFO: checkUpdateInProgress
  676. 18:02:56 T:1212183616 NOTICE: RCB_INFO: scrapeOnStartupAction = nothing
  677. 18:02:57 T:1212183616 NOTICE: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/MyGames.db
  678. 18:02:57 T:1212183616 NOTICE: RCB_INFO: Path to configuration file: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/config.xml
  679. 18:02:57 T:1212183616 NOTICE: RCB_INFO: Reading config version from config.xml: 0.9.5
  680. 18:02:57 T:1212183616 NOTICE: RCB_INFO: Config file is up to date
  681. 18:02:57 T:1212183616 NOTICE: RCB_INFO: Begin readXml
  682. 18:02:57 T:1212183616 NOTICE: RCB_INFO: Path to configuration file: /home/pi/.xbmc/userdata/addon_data/script.games.rom.collection.browser/config.xml
  683. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Begin readRomCollections
  684. 18:02:58 T:1212183616 NOTICE: RCB_INFO: current Rom Collection: Game Boy
  685. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Rom path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/*.gb
  686. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/boxfront/%GAME%.*
  687. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/boxback/%GAME%.*
  688. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/cartridge/%GAME%.*
  689. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/screenshot/%GAME%.*
  690. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Media path: /home/pi/emulator/gcat-gameboy-1.0/ROMS/fanart/%GAME%.*
  691. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper site: thegamesdb.net
  692. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: thegamesdb.net
  693. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  694. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper site: archive.vg
  695. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: archive.vg
  696. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  697. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper site: mobygames.com
  698. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: mobygames.com
  699. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  700. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Image Placing name: gameinfobig
  701. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Image Placing name: gameinfosmall
  702. 18:02:58 T:1212183616 NOTICE: RCB_INFO: emulatorCmd: /home/pi/emulator/gcat-gameboy-1.0/gameboy
  703. 18:02:58 T:1212183616 NOTICE: RCB_INFO: emulatorParams: "%ROM%"
  704. 18:02:58 T:1212183616 NOTICE: RCB_INFO: ignoreOnScan: False
  705. 18:02:58 T:1212183616 NOTICE: RCB_INFO: allowUpdate: True
  706. 18:02:58 T:1212183616 NOTICE: RCB_INFO: useEmuSolo: False
  707. 18:02:58 T:1212183616 NOTICE: RCB_INFO: autoplayVideoMain: True
  708. 18:02:58 T:1212183616 NOTICE: RCB_INFO: autoplayVideoInfo: True
  709. 18:02:58 T:1212183616 NOTICE: RCB_INFO: useFoldernameAsGamename: False
  710. 18:02:58 T:1212183616 NOTICE: RCB_INFO: maxFolderDepth: 99
  711. 18:02:58 T:1212183616 NOTICE: RCB_INFO: doNotExtractZipFiles: False
  712. 18:02:58 T:1212183616 NOTICE: RCB_INFO: diskPrefix: _Disk
  713. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: local nfo
  714. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  715. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: thegamesdb.net
  716. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  717. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: giantbomb.com
  718. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  719. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: mobygames.com
  720. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  721. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: archive.vg
  722. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  723. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper Site: maws.mameworld.info
  724. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Scraper descFilePerGame: True
  725. 18:02:58 T:1212183616 NOTICE: RCB_INFO: Begin cacheItems
  726. 18:02:58 T:1212183616 NOTICE: RCB_INFO: End cacheItems
  727. 18:02:58 T:1075179520 DEBUG: Activating window ID: 13000
  728. 18:02:58 T:1075179520 DEBUG: ------ Window Deinit (MyPrograms.xml) ------
  729. 18:02:59 T:1075179520 DEBUG: ------ Window Init (/home/pi/.xbmc/addons/script.games.rom.collection.browser/resources/skins/Default/720p/script-Rom_Collection_Browser-main.xml) ------
  730. 18:02:59 T:1075179520 INFO: Loading skin file: /home/pi/.xbmc/addons/script.games.rom.collection.browser/resources/skins/Default/720p/script-Rom_Collection_Browser-main.xml
  731. 18:02:59 T:1075179520 DEBUG: LocalizeStrings: no strings.po file exist at /home/pi/.xbmc/addons/script.games.rom.collection.browser/resources/language/English, fallback to strings.xml
  732. 18:02:59 T:1075179520 WARNING: Trying to add unsupported control type 1
  733. 18:02:59 T:1075179520 WARNING: Previous line repeats 1 times.
  734. 18:02:59 T:1075179520 ERROR: Control 500 in window 13000 has been asked to focus, but it can't
  735. 18:02:59 T:1212183616 NOTICE: RCB_INFO: Begin onInit
  736. 18:02:59 T:1212183616 NOTICE: RCB_INFO: Begin loadViewState
  737. 18:02:59 T:1212183616 NOTICE: RCB_INFO: Begin showConsoles
  738. 18:02:59 T:1212183616 NOTICE: RCB_INFO: begin showFilterControl: 500
  739. 18:03:00 T:1212183616 NOTICE: RCB_INFO: End showConsoles
  740. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Begin showGenre
  741. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Selected Console: 0
  742. 18:03:00 T:1212183616 NOTICE: RCB_INFO: begin showFilterControl: 600
  743. 18:03:00 T:1212183616 NOTICE: RCB_INFO: End showGenre
  744. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Begin showYear
  745. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Selected Console: 0
  746. 18:03:00 T:1212183616 NOTICE: RCB_INFO: begin showFilterControl: 700
  747. 18:03:00 T:1212183616 NOTICE: RCB_INFO: End showYear
  748. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Begin showPublisher
  749. 18:03:00 T:1212183616 NOTICE: RCB_INFO: Selected Console: 0
  750. 18:03:00 T:1212183616 NOTICE: RCB_INFO: begin showFilterControl: 800
  751. 18:03:00 T:1212183616 NOTICE: RCB_INFO: End showPublisher
  752. 18:03:01 T:1212183616 NOTICE: RCB_INFO: Begin showCharacterFilter
  753. 18:03:01 T:1212183616 NOTICE: RCB_INFO: End showCharacterFilter
  754. 18:03:01 T:1212183616 NOTICE: RCB_INFO: Begin showGames
  755. 18:03:01 T:1212183616 NOTICE: RCB_INFO: helper.buildLikeStatement
  756. 18:03:01 T:1212183616 NOTICE: RCB_INFO: searching games with args: romCollectionId = 0, genreId = 0, yearId = 0, publisherId = 0, isFavorite = 0, characterFilter = 0 = 0
  757. 18:03:01 T:1212183616 NOTICE: showGames: load games from db in 30 ms
  758. 18:03:01 T:1212183616 NOTICE: showGames: load 1 games to list in 39 ms
  759. 18:03:01 T:1212183616 NOTICE: RCB_INFO: End showGames
  760. 18:03:01 T:1212183616 NOTICE: RCB_INFO: Begin showGameInfo
  761. 18:03:01 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  762. 18:03:01 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  763. 18:03:01 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  764. 18:03:01 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(1638400) nBufferAlignmen(16)
  765. 18:03:01 T:1212183616 NOTICE: RCB_INFO: End showGameInfo
  766. 18:03:01 T:1212183616 NOTICE: RCB_INFO: End loadViewState
  767. 18:03:01 T:1212183616 NOTICE: RCB_INFO: End onInit
  768. 18:03:02 T:1075179520 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0
  769. 18:03:02 T:1075179520 DEBUG: OnKey: return (f00d) pressed, action is Select
  770. 18:03:02 T:1212183616 NOTICE: RCB_INFO: Begin launchEmu
  771. 18:03:02 T:1212183616 NOTICE: RCB_INFO: launching game with id: 1
  772. 18:03:02 T:1212183616 NOTICE: RCB_INFO: Begin launcher.launchEmu
  773. 18:03:02 T:1212183616 NOTICE: RCB_INFO: files for current game: [(u'/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb',)]
  774. 18:03:02 T:1212183616 NOTICE: RCB_INFO: launcher.buildCmd
  775. 18:03:02 T:1212183616 NOTICE: RCB_INFO: emuCommandLine: /home/pi/emulator/gcat-gameboy-1.0/gameboy
  776. 18:03:02 T:1212183616 NOTICE: RCB_INFO: emuParams: "%ROM%"
  777. 18:03:02 T:1212183616 NOTICE: RCB_INFO: rom: /home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb
  778. 18:03:03 T:1212183616 NOTICE: RCB_INFO: cmd: "/home/pi/emulator/gcat-gameboy-1.0/gameboy" "/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb"
  779. 18:03:03 T:1212183616 NOTICE: RCB_INFO: launchEmu on non-xbox
  780. 18:03:03 T:1075179520 DEBUG: HttpApi Start command: GetSystemInfoByName paras: GetSystemInfoByName; system.screenmode
  781. 18:03:03 T:1212183616 NOTICE: RCB_INFO: screenMode: 1920x1080 @ 60.00 - Full Screen
  782. 18:03:03 T:1212183616 NOTICE: RCB_INFO: Toggle to Windowed mode
  783. 18:03:03 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  784. 18:03:03 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  785. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  786. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 720x480->720x480
  787. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(720x480) (720x480)
  788. 18:03:03 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  789. 18:03:03 T:1075179520 NOTICE: EGL window and context creation complete
  790. 18:03:03 T:1075179520 INFO: GLES: Maximum texture width: 2048
  791. 18:03:03 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  792. 18:03:03 T:1212183616 NOTICE: RCB_INFO: launch emu
  793. 18:03:03 T:1212183616 NOTICE: RCB_INFO: launch emu done
  794. 18:03:03 T:1212183616 NOTICE: RCB_INFO: Toggle to Full Screen mode
  795. 18:03:03 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  796. 18:03:03 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  797. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  798. 18:03:03 T:1127330880 DEBUG: tvservice_callback(8,1,16)
  799. 18:03:03 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::SetDisplayResolution set HDMI mode (2,1,16)=0
  800. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 1920x1080->1920x1080
  801. 18:03:03 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(1920x1080) (1920x1080)
  802. 18:03:03 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  803. 18:03:03 T:1075179520 NOTICE: EGL window and context creation complete
  804. 18:03:03 T:1075179520 INFO: GLES: Maximum texture width: 2048
  805. 18:03:03 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  806. 18:03:03 T:1212183616 NOTICE: RCB_INFO: End launcher.launchEmu
  807. 18:03:03 T:1212183616 NOTICE: RCB_INFO: End launchEmu
  808. 18:03:03 T:1212183616 NOTICE: RCB_INFO: onAction: 7
  809. 18:03:05 T:1075179520 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0
  810. 18:03:05 T:1075179520 DEBUG: OnKey: return (f00d) pressed, action is Select
  811. 18:03:05 T:1212183616 NOTICE: RCB_INFO: Begin launchEmu
  812. 18:03:05 T:1212183616 NOTICE: RCB_INFO: launching game with id: 1
  813. 18:03:05 T:1212183616 NOTICE: RCB_INFO: Begin launcher.launchEmu
  814. 18:03:05 T:1212183616 NOTICE: RCB_INFO: files for current game: [(u'/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb',)]
  815. 18:03:05 T:1212183616 NOTICE: RCB_INFO: launcher.buildCmd
  816. 18:03:05 T:1212183616 NOTICE: RCB_INFO: emuCommandLine: /home/pi/emulator/gcat-gameboy-1.0/gameboy
  817. 18:03:05 T:1212183616 NOTICE: RCB_INFO: emuParams: "%ROM%"
  818. 18:03:05 T:1212183616 NOTICE: RCB_INFO: rom: /home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb
  819. 18:03:07 T:1212183616 NOTICE: RCB_INFO: cmd: "/home/pi/emulator/gcat-gameboy-1.0/gameboy" "/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb"
  820. 18:03:07 T:1212183616 NOTICE: RCB_INFO: launchEmu on non-xbox
  821. 18:03:07 T:1075179520 DEBUG: HttpApi Start command: GetSystemInfoByName paras: GetSystemInfoByName; system.screenmode
  822. 18:03:07 T:1212183616 NOTICE: RCB_INFO: screenMode: 1920x1080 @ 60.00 - Full Screen
  823. 18:03:07 T:1212183616 NOTICE: RCB_INFO: Toggle to Windowed mode
  824. 18:03:07 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  825. 18:03:07 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  826. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  827. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 720x480->720x480
  828. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(720x480) (720x480)
  829. 18:03:07 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  830. 18:03:07 T:1075179520 NOTICE: EGL window and context creation complete
  831. 18:03:07 T:1075179520 INFO: GLES: Maximum texture width: 2048
  832. 18:03:07 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  833. 18:03:07 T:1212183616 NOTICE: RCB_INFO: launch emu
  834. 18:03:07 T:1212183616 NOTICE: RCB_INFO: launch emu done
  835. 18:03:07 T:1212183616 NOTICE: RCB_INFO: Toggle to Full Screen mode
  836. 18:03:07 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  837. 18:03:07 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  838. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  839. 18:03:07 T:1127330880 DEBUG: tvservice_callback(8,1,16)
  840. 18:03:07 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::SetDisplayResolution set HDMI mode (2,1,16)=0
  841. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 1920x1080->1920x1080
  842. 18:03:07 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(1920x1080) (1920x1080)
  843. 18:03:07 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  844. 18:03:07 T:1075179520 NOTICE: EGL window and context creation complete
  845. 18:03:07 T:1075179520 INFO: GLES: Maximum texture width: 2048
  846. 18:03:07 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  847. 18:03:07 T:1212183616 NOTICE: RCB_INFO: End launcher.launchEmu
  848. 18:03:07 T:1212183616 NOTICE: RCB_INFO: End launchEmu
  849. 18:03:07 T:1212183616 NOTICE: RCB_INFO: onAction: 7
  850. 18:03:09 T:1075179520 DEBUG: Keyboard: scancode: 1c, sym: 000d, unicode: 0000, modifier: 0
  851. 18:03:09 T:1075179520 DEBUG: OnKey: return (f00d) pressed, action is Select
  852. 18:03:09 T:1212183616 NOTICE: RCB_INFO: Begin launchEmu
  853. 18:03:09 T:1212183616 NOTICE: RCB_INFO: launching game with id: 1
  854. 18:03:09 T:1212183616 NOTICE: RCB_INFO: Begin launcher.launchEmu
  855. 18:03:09 T:1212183616 NOTICE: RCB_INFO: files for current game: [(u'/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb',)]
  856. 18:03:09 T:1212183616 NOTICE: RCB_INFO: launcher.buildCmd
  857. 18:03:09 T:1212183616 NOTICE: RCB_INFO: emuCommandLine: /home/pi/emulator/gcat-gameboy-1.0/gameboy
  858. 18:03:09 T:1212183616 NOTICE: RCB_INFO: emuParams: "%ROM%"
  859. 18:03:09 T:1212183616 NOTICE: RCB_INFO: rom: /home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb
  860. 18:03:09 T:1212183616 NOTICE: RCB_INFO: cmd: "/home/pi/emulator/gcat-gameboy-1.0/gameboy" "/home/pi/emulator/gcat-gameboy-1.0/ROMS/Super Mario Land.gb"
  861. 18:03:09 T:1212183616 NOTICE: RCB_INFO: launchEmu on non-xbox
  862. 18:03:09 T:1075179520 DEBUG: HttpApi Start command: GetSystemInfoByName paras: GetSystemInfoByName; system.screenmode
  863. 18:03:09 T:1212183616 NOTICE: RCB_INFO: screenMode: 1920x1080 @ 60.00 - Full Screen
  864. 18:03:09 T:1212183616 NOTICE: RCB_INFO: Toggle to Windowed mode
  865. 18:03:09 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  866. 18:03:09 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  867. 18:03:09 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  868. 18:03:09 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 720x480->720x480
  869. 18:03:09 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(720x480) (720x480)
  870. 18:03:09 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  871. 18:03:09 T:1075179520 NOTICE: EGL window and context creation complete
  872. 18:03:09 T:1075179520 INFO: GLES: Maximum texture width: 2048
  873. 18:03:09 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  874. 18:03:09 T:1212183616 NOTICE: RCB_INFO: launch emu
  875. 18:03:09 T:1212183616 NOTICE: RCB_INFO: launch emu done
  876. 18:03:09 T:1212183616 NOTICE: RCB_INFO: Toggle to Full Screen mode
  877. 18:03:09 T:1075179520 DEBUG: HttpApi Start command: Action paras: Action; 199
  878. 18:03:09 T:1075179520 DEBUG: CWinSystemGLES::SetFullScreen
  879. 18:03:09 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::DestroyWindow()
  880. 18:03:09 T:1127330880 DEBUG: tvservice_callback(8,1,16)
  881. 18:03:09 T:1075179520 NOTICE: CWinEGLPlatformRaspberryPI::SetDisplayResolution set HDMI mode (2,1,16)=0
  882. 18:03:09 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution 1920x1080->1920x1080
  883. 18:03:10 T:1075179520 DEBUG: CWinEGLPlatformRaspberryPI::SetDisplayResolution(1920x1080) (1920x1080)
  884. 18:03:10 T:1075179520 DEBUG: EGL extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_KHR_vg_parent_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_lock_surface
  885. 18:03:10 T:1075179520 NOTICE: EGL window and context creation complete
  886. 18:03:10 T:1075179520 INFO: GLES: Maximum texture width: 2048
  887. 18:03:10 T:1075179520 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  888. 18:03:10 T:1212183616 NOTICE: RCB_INFO: End launcher.launchEmu
  889. 18:03:10 T:1212183616 NOTICE: RCB_INFO: End launchEmu
  890. 18:03:10 T:1212183616 NOTICE: RCB_INFO: onAction: 7
  891. 18:03:12 T:1075179520 DEBUG: Keyboard: scancode: 0e, sym: 0008, unicode: 0008, modifier: 0
  892. 18:03:12 T:1075179520 DEBUG: OnKey: backspace (f008) pressed, action is Back
  893. 18:03:12 T:1212183616 NOTICE: RCB_INFO: onAction: 92
  894. 18:03:12 T:1212183616 NOTICE: RCB_INFO: onAction: ACTION_CANCEL_DIALOG
  895. 18:03:12 T:1212183616 NOTICE: RCB_INFO: exit
  896. 18:03:12 T:1212183616 NOTICE: RCB_INFO: Begin saveViewState
  897. 18:03:12 T:1212183616 NOTICE: RCB_INFO: Begin saveViewMode
  898. 18:03:12 T:1212183616 NOTICE: RCB_INFO: End saveViewMode
  899. 18:03:12 T:1212183616 NOTICE: RCB_INFO: Begin helper.saveViewState
  900. 18:03:13 T:1212183616 NOTICE: RCB_INFO: End helper.saveViewState
  901. 18:03:13 T:1212183616 NOTICE: RCB_INFO: End saveViewState
  902. 18:03:13 T:1212183616 NOTICE: close Connection
  903. 18:03:13 T:1075179520 DEBUG: Activating window ID: 10001
  904. 18:03:13 T:1075179520 DEBUG: ------ Window Deinit (/home/pi/.xbmc/addons/script.games.rom.collection.browser/resources/skins/Default/720p/script-Rom_Collection_Browser-main.xml) ------
  905. 18:03:13 T:1075179520 DEBUG: ------ Window Init (MyPrograms.xml) ------
  906. 18:03:13 T:1075179520 INFO: Loading skin file: MyPrograms.xml
  907. 18:03:14 T:1075179520 DEBUG: CGUIMediaWindow::GetDirectory (addons://sources/executable/)
  908. 18:03:14 T:1075179520 DEBUG: ParentPath = [addons://sources/executable/]
  909. 18:03:14 T:1212183616 INFO: Scriptresult: Success
  910. 18:03:14 T:1203795008 NOTICE: Thread Background Loader start, auto delete: false
  911. 18:03:14 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  912. 18:03:14 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  913. 18:03:14 T:1203795008 WARNING: CreateLoader - Unsupported protocol(addons) in addons://more/executable.tbn
  914. 18:03:14 T:1203795008 DEBUG: Thread Background Loader 1203795008 terminating
  915. 18:03:14 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  916. 18:03:14 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
  917. 18:03:14 T:1212183616 INFO: Python script stopped
  918. 18:03:14 T:1212183616 DEBUG: Thread XBPyThread 1212183616 terminating
  919. 18:03:15 T:1185649728 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  920. 18:03:15 T:1185649728 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  921. 18:03:15 T:1185649728 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  922. 18:03:15 T:1185649728 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(262144) nBufferAlignmen(16)
  923. 18:03:15 T:1185649728 DEBUG: DoWork - took 118 ms to load special://masterprofile/Thumbnails/a/ab968fd8.jpg
  924. 18:03:15 T:1075179520 DEBUG: waiting for python thread 7 to stop
  925. 18:03:15 T:1075179520 DEBUG: python thread 7 destructed
  926. 18:03:16 T:1075179520 DEBUG: Keyboard: scancode: 0e, sym: 0008, unicode: 0008, modifier: 0
  927. 18:03:16 T:1075179520 DEBUG: OnKey: backspace (f008) pressed, action is Back
  928. 18:03:16 T:1075179520 DEBUG: CGUIMediaWindow::GetDirectory ()
  929. 18:03:16 T:1075179520 DEBUG: ParentPath = []
  930. 18:03:16 T:1212183616 NOTICE: Thread Background Loader start, auto delete: false
  931. 18:03:16 T:1212183616 DEBUG: Thread Background Loader 1212183616 terminating
  932. 18:03:17 T:1075179520 DEBUG: Keyboard: scancode: 0e, sym: 0008, unicode: 0008, modifier: 0
  933. 18:03:17 T:1075179520 DEBUG: OnKey: backspace (f008) pressed, action is Back
  934. 18:03:17 T:1075179520 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
  935. 18:03:17 T:1075179520 DEBUG: ------ Window Deinit (MyPrograms.xml) ------
  936. 18:03:17 T:1075179520 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
  937. 18:03:17 T:1075179520 DEBUG: ------ Window Init (Home.xml) ------
  938. 18:03:17 T:1075179520 INFO: Loading skin file: Home.xml
  939. 18:03:18 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321
  940. 18:03:18 T:1075179520 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.resize input port 60 output port 61
  941. 18:03:18 T:1075179520 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(3), nBufferSize(81920), nBufferAlignmen(16)
  942. 18:03:18 T:1075179520 DEBUG: COMXCoreComponent::AllocOutputBuffers component(OMX.broadcom.resize) - port(61), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(8355840) nBufferAlignmen(16)
  943. 18:03:20 T:1075179520 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avutil-51-arm.so)
  944. 18:03:20 T:1075179520 DEBUG: Unloading: avutil-51-arm.so
  945. 18:03:21 T:1075179520 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avcodec-53-arm.so)
  946. 18:03:21 T:1075179520 DEBUG: Unloading: avcodec-53-arm.so
  947. 18:03:21 T:1075179520 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/players/dvdplayer/avformat-53-arm.so)
  948. 18:03:21 T:1075179520 DEBUG: Unloading: avformat-53-arm.so
  949. 18:03:28 T:1075179520 INFO: LIRC Initialize: using: /dev/lircd
  950. 18:03:28 T:1075179520 DEBUG: Failed to connect to LIRC. Giving up.
  951. 18:03:48 T:1228960832 DEBUG: Thread Jobworker 1228960832 terminating (autodelete)
  952. 18:03:48 T:1185649728 DEBUG: Thread Jobworker 1185649728 terminating (autodelete)
  953. 18:03:48 T:1327629376 DEBUG: Thread Jobworker 1327629376 terminating (autodelete)
  954. 18:03:49 T:1075179520 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
  955. 18:03:49 T:1075179520 DEBUG: Unloading: ImageLib-arm.so
  956. 18:04:30 T:1075179520 NOTICE: Samba is idle. Closing the remaining connections