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