API tools faq
paste
Advertisement
kartana

CEC problems

kartana
Mar 12th, 2016
252
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
text 214.00 KB | None | 0 0
raw download clone embed print report
  1. 01:00:07 7.405226 T:1967267840 NOTICE: special://profile/ is mapped to: special://masterprofile/
  2. 01:00:07 7.405469 T:1967267840 NOTICE: -----------------------------------------------------------------------
  3. 01:00:07 7.405568 T:1967267840 NOTICE: Starting Kodi (15.2 Git:02e7013). Platform: Linux ARM 32-bit
  4. 01:00:07 7.405640 T:1967267840 NOTICE: Using Release Kodi x32 build (version for Raspberry Pi)
  5. 01:00:07 7.405720 T:1967267840 NOTICE: Kodi compiled Mar 1 2016 by GCC 4.9.3 for Linux ARM 32-bit version 4.1.18 (262418)
  6. 01:00:07 7.406110 T:1967267840 NOTICE: Running on OpenELEC (official) - Version: 6.0.3, kernel: Linux ARM 32-bit version 4.1.18
  7. 01:00:07 7.406197 T:1967267840 NOTICE: FFmpeg version: 2.6.4
  8. 01:00:07 7.406258 T:1967267840 NOTICE: WARNING: unsupported ffmpeg version detected
  9. 01:00:07 7.406321 T:1967267840 NOTICE: Host CPU: ARMv7 Processor rev 5 (v7l), 4 cores available
  10. 01:00:07 7.406377 T:1967267840 NOTICE: ARM Features: Neon enabled
  11. 01:00:07 7.406439 T:1967267840 NOTICE: special://xbmc/ is mapped to: /usr/share/kodi
  12. 01:00:07 7.406511 T:1967267840 NOTICE: special://xbmcbin/ is mapped to: /usr/lib/kodi
  13. 01:00:07 7.406572 T:1967267840 NOTICE: special://masterprofile/ is mapped to: /storage/.kodi/userdata
  14. 01:00:07 7.406632 T:1967267840 NOTICE: special://home/ is mapped to: /storage/.kodi
  15. 01:00:07 7.406758 T:1967267840 NOTICE: special://temp/ is mapped to: /storage/.kodi/temp
  16. 01:00:07 7.406929 T:1967267840 NOTICE: The executable running is: /usr/lib/kodi/kodi.bin
  17. 01:00:07 7.407049 T:1967267840 NOTICE: Local hostname: OpenELEC
  18. 01:00:07 7.407124 T:1967267840 NOTICE: Log File is located: /storage/.kodi/temp/kodi.log
  19. 01:00:07 7.413983 T:1967267840 NOTICE: -----------------------------------------------------------------------
  20. 01:00:07 7.543297 T:1967267840 ERROR: DBus: Error org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UPower was not provided by any .service files
  21. 01:00:07 7.556007 T:1967267840 NOTICE: load settings...
  22. 01:00:07 7.666866 T:1967252544 NOTICE: Thread FDEventMonitor start, auto delete: false
  23. 01:00:07 7.706229 T:1967267840 NOTICE: Found 1 Lists of Devices
  24. 01:00:07 7.706400 T:1967267840 NOTICE: Enumerated PI devices:
  25. 01:00:07 7.706469 T:1967267840 NOTICE: Device 1
  26. 01:00:07 7.707103 T:1967267840 NOTICE: m_deviceName : HDMI
  27. 01:00:07 7.707209 T:1967267840 NOTICE: m_displayName : HDMI
  28. 01:00:07 7.707273 T:1967267840 NOTICE: m_displayNameExtra:
  29. 01:00:07 7.707335 T:1967267840 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
  30. 01:00:07 7.707395 T:1967267840 NOTICE: m_channels : FL,FR
  31. 01:00:07 7.707459 T:1967267840 NOTICE: m_sampleRates : 8000,11025,16000,22050,24000,32000,44100,48000,88200,96000,176400,192000
  32. 01:00:07 7.707523 T:1967267840 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3
  33. 01:00:07 7.707614 T:1967267840 NOTICE: Device 2
  34. 01:00:07 7.707754 T:1967267840 NOTICE: m_deviceName : Analogue
  35. 01:00:07 7.707819 T:1967267840 NOTICE: m_displayName : Analogue
  36. 01:00:07 7.707880 T:1967267840 NOTICE: m_displayNameExtra:
  37. 01:00:07 7.707941 T:1967267840 NOTICE: m_deviceType : AE_DEVTYPE_PCM
  38. 01:00:07 7.708000 T:1967267840 NOTICE: m_channels : FL,FR
  39. 01:00:07 7.708059 T:1967267840 NOTICE: m_sampleRates : 48000
  40. 01:00:07 7.708121 T:1967267840 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
  41. 01:00:07 7.708182 T:1967267840 NOTICE: Device 3
  42. 01:00:07 7.708289 T:1967267840 NOTICE: m_deviceName : Both
  43. 01:00:07 7.708352 T:1967267840 NOTICE: m_displayName : HDMI and Analogue
  44. 01:00:07 7.708411 T:1967267840 NOTICE: m_displayNameExtra:
  45. 01:00:07 7.708468 T:1967267840 NOTICE: m_deviceType : AE_DEVTYPE_PCM
  46. 01:00:07 7.708566 T:1967267840 NOTICE: m_channels : FL,FR
  47. 01:00:07 7.708627 T:1967267840 NOTICE: m_sampleRates : 48000
  48. 01:00:07 7.708689 T:1967267840 NOTICE: m_dataFormats : AE_FMT_FLOAT,AE_FMT_S32LE,AE_FMT_S16LE,AE_FMT_FLOATP,AE_FMT_S32NEP,AE_FMT_S16NEP
  49. 01:00:07 7.742054 T:1967267840 NOTICE: Loaded settings file from special://xbmc/system/advancedsettings.xml
  50. 01:00:07 7.742475 T:1967267840 NOTICE: Contents of special://xbmc/system/advancedsettings.xml are...
  51. <?xml version="1.0" encoding="UTF-8" ?>
  52. <advancedsettings>
  53. <showexitbutton>false</showexitbutton>
  54. <fanartres>720</fanartres>
  55. <imageres>540</imageres>
  56. <video>
  57. <busydialogdelayms>750</busydialogdelayms>
  58. </video>
  59. <samba>
  60. <clienttimeout>30</clienttimeout>
  61. </samba>
  62. <network>
  63. <readbufferfactor>4.0</readbufferfactor>
  64. </network>
  65. </advancedsettings>
  66. 01:00:07 7.745075 T:1967267840 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
  67. 01:00:07 7.745182 T:1967267840 NOTICE: Default DVD Player: dvdplayer
  68. 01:00:07 7.745246 T:1967267840 NOTICE: Default Video Player: dvdplayer
  69. 01:00:07 7.745305 T:1967267840 NOTICE: Default Audio Player: paplayer
  70. 01:00:07 7.745379 T:1967267840 NOTICE: Enabled debug logging due to GUI setting (2)
  71. 01:00:07 7.745440 T:1967267840 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
  72. 01:00:07 7.745752 T:1967267840 NOTICE: CMediaSourceSettings: loading media sources from special://masterprofile/sources.xml
  73. 01:00:07 7.748656 T:1967267840 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
  74. 01:00:07 7.751075 T:1967267840 DEBUG: CPlayerCoreConfig::<ctor>: created player DVDPlayer for core 1
  75. 01:00:07 7.751225 T:1967267840 DEBUG: CPlayerCoreConfig::<ctor>: created player oldmplayercore for core 1
  76. 01:00:07 7.751299 T:1967267840 DEBUG: CPlayerCoreConfig::<ctor>: created player PAPlayer for core 3
  77. 01:00:07 7.751373 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules
  78. 01:00:07 7.751443 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/mms/udp
  79. 01:00:07 7.751517 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout
  80. 01:00:07 7.751589 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtmp
  81. 01:00:07 7.751660 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp
  82. 01:00:07 7.751746 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams
  83. 01:00:07 7.751814 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: aacp/sdp
  84. 01:00:07 7.751884 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2
  85. 01:00:07 7.751958 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd
  86. 01:00:07 7.752027 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage
  87. 01:00:07 7.752094 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf
  88. 01:00:07 7.752163 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv
  89. 01:00:07 7.752235 T:1967267840 DEBUG: CPlayerSelectionRule::Initialize: creating rule: radio
  90. 01:00:07 7.752306 T:1967267840 NOTICE: Loaded playercorefactory configuration
  91. 01:00:07 7.752733 T:1967267840 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
  92. 01:00:07 7.753035 T:1967267840 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
  93. 01:00:07 7.778594 T:1967267840 INFO: creating subdirectories
  94. 01:00:07 7.778764 T:1967267840 INFO: userdata folder: special://masterprofile/
  95. 01:00:07 7.778859 T:1967267840 INFO: recording folder:
  96. 01:00:07 7.778934 T:1967267840 INFO: screenshots folder: /storage/screenshots/
  97. 01:00:07 7.805789 T:1957688384 NOTICE: Thread ActiveAE start, auto delete: false
  98. 01:00:07 7.806227 T:1947202624 NOTICE: Thread AESink start, auto delete: false
  99. 01:00:07 7.806641 T:1947202624 INFO: CActiveAESink::OpenSink - initialize sink
  100. 01:00:07 7.806824 T:1947202624 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
  101. 01:00:07 7.806936 T:1947202624 DEBUG: DllBcm: Using omx system library
  102. 01:00:07 7.809163 T:1947202624 DEBUG: SECTION:LoadDLL(libopenmaxil.so)
  103. 01:00:07 7.812108 T:1947202624 DEBUG: Loading: libopenmaxil.so
  104. 01:00:07 7.817366 T:1886385216 NOTICE: Thread CRBPWorker start, auto delete: false
  105. 01:00:07 7.817560 T:1947202624 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
  106. 01:00:07 7.817682 T:1947202624 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
  107. 01:00:07 7.819631 T:1947202624 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x7420bad8
  108. 01:00:07 7.821320 T:1947202624 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignmen(16)
  109. 01:00:07 7.821850 T:1947202624 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
  110. 01:00:07 7.821926 T:1947202624 DEBUG: Output Device : HDMI
  111. 01:00:07 7.821987 T:1947202624 DEBUG: Sample Rate : 44100
  112. 01:00:07 7.822047 T:1947202624 DEBUG: Sample Format : AE_FMT_FLOAT
  113. 01:00:07 7.822105 T:1947202624 DEBUG: Channel Count : 2
  114. 01:00:07 7.822181 T:1947202624 DEBUG: Channel Layout: FL,FR
  115. 01:00:07 7.822241 T:1947202624 DEBUG: Frames : 2205
  116. 01:00:07 7.822298 T:1947202624 DEBUG: Frame Samples : 4410
  117. 01:00:07 7.822355 T:1947202624 DEBUG: Frame Size : 8
  118. 01:00:07 7.921793 T:1967267840 NOTICE: Running database version Addons19
  119. 01:00:07 7.922726 T:1967267840 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-arm.so)
  120. 01:00:07 7.922967 T:1967267840 DEBUG: Loading: /usr/lib/kodi/system/libcpluff-arm.so
  121. 11:28:16 8.272399 T:1967267840 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/lib/kodi/addons: No such file or directory'
  122. 11:28:16 8.272685 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ca_es has been installed.'
  123. 11:28:16 8.272826 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.'
  124. 11:28:16 8.272914 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.tr_tr has been installed.'
  125. 11:28:16 8.273013 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.mt_mt has been installed.'
  126. 11:28:16 8.273092 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.az_az has been installed.'
  127. 11:28:16 8.273171 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.vi_vn has been installed.'
  128. 11:28:16 8.273247 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ja_jp has been installed.'
  129. 11:28:16 8.273321 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.eo has been installed.'
  130. 11:28:16 8.273449 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.fanart.tv has been installed.'
  131. 11:28:16 8.273524 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.audioencoder has been installed.'
  132. 11:28:16 8.273600 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.pl_pl has been installed.'
  133. 11:28:16 8.273679 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.'
  134. 11:28:16 8.273755 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.th_th has been installed.'
  135. 11:28:16 8.273869 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sk_sk has been installed.'
  136. 11:28:16 8.273950 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.pt_br has been installed.'
  137. 11:28:16 8.274027 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.id_id has been installed.'
  138. 11:28:16 8.274101 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fr_ca has been installed.'
  139. 11:28:16 8.274178 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_au has been installed.'
  140. 11:28:16 8.274248 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in kodi.audiodecoder has been installed.'
  141. 11:28:16 8.274325 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.'
  142. 11:28:16 8.274402 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in visualization.vortex has been installed.'
  143. 11:28:16 8.274482 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in service.openelec.settings has been installed.'
  144. 11:28:16 8.274566 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.'
  145. 11:28:16 8.274641 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sl_si has been installed.'
  146. 11:28:16 8.274715 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.am_et has been installed.'
  147. 11:28:16 8.274785 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in kodi.guilib has been installed.'
  148. 11:28:16 8.274858 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fr_fr has been installed.'
  149. 11:28:16 8.274934 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.es_ar has been installed.'
  150. 11:28:16 8.275010 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.wma has been installed.'
  151. 11:28:16 8.275084 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.eu_es has been installed.'
  152. 11:28:16 8.275159 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sr_rs@latin has been installed.'
  153. 11:28:16 8.275240 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sr_rs has been installed.'
  154. 11:28:16 8.275315 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.bs_ba has been installed.'
  155. 11:28:16 8.275391 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.cs_cz has been installed.'
  156. 11:28:16 8.275467 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in skin.conq has been installed.'
  157. 11:28:16 8.275542 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.'
  158. 11:28:16 8.275616 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.'
  159. 11:28:16 8.275691 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.mi has been installed.'
  160. 11:28:16 8.275766 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in script.favourites has been installed.'
  161. 11:28:16 8.275840 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sq_al has been installed.'
  162. 11:28:16 8.275914 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.hi_in has been installed.'
  163. 11:28:16 8.275993 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fa_af has been installed.'
  164. 11:28:16 8.276068 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.el_gr has been installed.'
  165. 11:28:16 8.276144 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.'
  166. 11:28:16 8.276226 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.'
  167. 11:28:16 8.276301 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ml_in has been installed.'
  168. 11:28:16 8.276376 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.hu_hu has been installed.'
  169. 11:28:16 8.276450 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.bg_bg has been installed.'
  170. 11:28:16 8.276523 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.'
  171. 11:28:16 8.276597 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.pt_pt has been installed.'
  172. 11:28:16 8.276723 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.mk_mk has been installed.'
  173. 11:28:16 8.276810 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.da_dk has been installed.'
  174. 11:28:16 8.276888 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.nb_no has been installed.'
  175. 11:28:16 8.276965 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.cy_gb has been installed.'
  176. 11:28:16 8.277040 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.sv_se has been installed.'
  177. 11:28:16 8.277116 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ms_my has been installed.'
  178. 11:28:16 8.277192 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.is_is has been installed.'
  179. 11:28:16 8.277267 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.af_za has been installed.'
  180. 11:28:16 8.277342 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in kodi.resource has been installed.'
  181. 11:28:16 8.277419 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.'
  182. 11:28:16 8.277492 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.'
  183. 11:28:16 8.277565 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.uk_ua has been installed.'
  184. 11:28:16 8.277640 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ru_ru has been installed.'
  185. 11:28:16 8.277714 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in service.skin.widgets has been installed.'
  186. 11:28:16 8.277787 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in repository.openelec.tv has been installed.'
  187. 11:28:16 8.277858 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in os.openelec.tv has been installed.'
  188. 11:28:16 8.277932 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.os_os has been installed.'
  189. 11:28:16 8.278008 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.hy_am has been installed.'
  190. 11:28:16 8.278082 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in script.skinshortcuts has been installed.'
  191. 11:28:16 8.278161 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in script.module.unidecode has been installed.'
  192. 11:28:16 8.278236 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.si_lk has been installed.'
  193. 11:28:16 8.278310 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.nl_nl has been installed.'
  194. 11:28:16 8.278384 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.lt_lt has been installed.'
  195. 11:28:16 8.278458 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.it_it has been installed.'
  196. 11:28:16 8.278533 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.es_mx has been installed.'
  197. 11:28:16 8.278610 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.musicvideos.theaudiodb.com has been installed.'
  198. 11:28:16 8.278680 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.'
  199. 11:28:16 8.278754 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ta_in has been installed.'
  200. 11:28:16 8.278835 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.mn_mn has been installed.'
  201. 11:28:16 8.278912 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ar_sa has been installed.'
  202. 11:28:16 8.278987 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.gl_es has been installed.'
  203. 11:28:16 8.279092 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.lv_lv has been installed.'
  204. 11:28:16 8.279190 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.te_in has been installed.'
  205. 11:28:16 8.279264 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.pvr has been installed.'
  206. 11:28:16 8.279339 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ko_kr has been installed.'
  207. 11:28:16 8.279415 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fa_ir has been installed.'
  208. 11:28:16 8.279490 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.uz_uz has been installed.'
  209. 11:28:16 8.279592 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.tg_tj has been installed.'
  210. 11:28:16 8.279669 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.et_ee has been installed.'
  211. 11:28:16 8.279745 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in audioencoder.xbmc.builtin.aac has been installed.'
  212. 11:28:16 8.279822 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.album.universal has been installed.'
  213. 11:28:16 8.279897 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.my_mm has been installed.'
  214. 11:28:16 8.279974 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.'
  215. 11:28:16 8.280078 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_gb has been installed.'
  216. 11:28:16 8.280156 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.zh_tw has been installed.'
  217. 11:28:16 8.280231 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.hr_hr has been installed.'
  218. 11:28:16 8.280306 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.zh_cn has been installed.'
  219. 11:28:16 8.280388 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fo_fo has been installed.'
  220. 11:28:16 8.280535 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.musicbrainz.org has been installed.'
  221. 11:28:16 8.280625 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.'
  222. 11:28:16 8.280704 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.'
  223. 11:28:16 8.280821 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.local has been installed.'
  224. 11:28:16 8.280930 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.webinterface has been installed.'
  225. 11:28:16 8.281034 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.uisounds.confluence has been installed.'
  226. 11:28:16 8.281115 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.ro_ro has been installed.'
  227. 11:28:16 8.281193 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.'
  228. 11:28:16 8.281281 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.fi_fi has been installed.'
  229. 11:28:16 8.281359 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.de_de has been installed.'
  230. 11:28:16 8.281436 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.'
  231. 11:28:16 8.281513 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.theaudiodb.com has been installed.'
  232. 11:28:16 8.281588 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.universal has been installed.'
  233. 11:28:16 8.281668 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.'
  234. 11:28:16 8.281743 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.he_il has been installed.'
  235. 11:28:16 8.281818 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.'
  236. 11:28:16 8.281901 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_nz has been installed.'
  237. 11:28:16 8.282010 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.'
  238. 11:28:16 8.282098 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in xbmc.codec has been installed.'
  239. 11:28:16 8.282176 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.szl has been installed.'
  240. 11:28:16 8.282251 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.en_us has been installed.'
  241. 11:28:16 8.282328 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.es_es has been installed.'
  242. 11:28:16 8.282404 T:1967267840 DEBUG: ADDON: cpluff: 'Plug-in resource.language.be_by has been installed.'
  243. 11:28:16 8.282469 T:1967267840 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.'
  244. 11:28:16 8.285532 T:1967267840 NOTICE: ADDONS: Using repository repository.xbmc.org
  245. 11:28:16 8.285651 T:1967267840 NOTICE: ADDONS: Using repository repository.openelec.tv
  246. 11:28:16 8.286002 T:1875899456 NOTICE: Thread RemoteControl start, auto delete: false
  247. 11:28:16 8.286146 T:1875899456 INFO: LIRC Process: using: /run/lirc/lircd
  248. 11:28:16 8.286529 T:1875899456 INFO: LIRC Connect: successfully started
  249. 11:28:16 8.286643 T:1875899456 DEBUG: Thread RemoteControl 1875899456 terminating
  250. 11:28:16 8.304607 T:1967267840 INFO: CKeyboardLayoutManager: loading keyboard layouts from special://xbmc/system/keyboardlayouts...
  251. 11:28:16 8.311959 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Arabic QWERTY" successfully loaded
  252. 11:28:16 8.314270 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian ЯВЕРТЪ" successfully loaded
  253. 11:28:16 8.315185 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Bulgarian АБВ" successfully loaded
  254. 11:28:16 8.316907 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Danish QWERTY" successfully loaded
  255. 11:28:16 8.319229 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "English QWERTY" successfully loaded
  256. 11:28:16 8.320118 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "English AZERTY" successfully loaded
  257. 11:28:16 8.320962 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "English ABC" successfully loaded
  258. 11:28:16 8.322682 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "German QWERTZ" successfully loaded
  259. 11:28:16 8.324360 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Greek QWERTY" successfully loaded
  260. 11:28:16 8.326294 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew QWERTY" successfully loaded
  261. 11:28:16 8.327200 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Hebrew ABC" successfully loaded
  262. 11:28:16 8.329002 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Hungarian QWERTZ" successfully loaded
  263. 11:28:16 8.331206 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian AZERTY" successfully loaded
  264. 11:28:16 8.332193 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Lithuanian QWERTY" successfully loaded
  265. 11:28:16 8.333983 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Norwegian QWERTY" successfully loaded
  266. 11:28:16 8.335791 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Polish QWERTY" successfully loaded
  267. 11:28:16 8.337650 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Portuguese (Portugal) QWERTY" successfully loaded
  268. 11:28:16 8.339342 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Romanian QWERTY" successfully loaded
  269. 11:28:16 8.341372 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian ЙЦУКЕН" successfully loaded
  270. 11:28:16 8.342268 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Russian АБВ" successfully loaded
  271. 11:28:16 8.344213 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Spanish QWERTY" successfully loaded
  272. 11:28:16 8.345872 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Swedish QWERTY" successfully loaded
  273. 11:28:16 8.347567 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Turkish QWERTY" successfully loaded
  274. 11:28:16 8.349598 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian ЙЦУКЕН" successfully loaded
  275. 11:28:16 8.350534 T:1967267840 DEBUG: CKeyboardLayoutManager: keyboard layout "Ukrainian АБВ" successfully loaded
  276. 11:28:16 8.352304 T:1967267840 DEBUG: UDisks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.UDisks was not provided by any .service files
  277. 11:28:16 8.353220 T:1967267840 DEBUG: DeviceKit.Disks: org.freedesktop.DBus.Error.ServiceUnknown - The name org.freedesktop.DeviceKit.Disks was not provided by any .service files
  278. 11:28:16 8.357456 T:1967267840 DEBUG: Selected UDev as storage provider
  279. 11:28:16 8.358253 T:1967267840 NOTICE: Raspberry PI firmware version: Feb 19 2016 19:53:38
  280. Copyright (c) 2012 Broadcom
  281. version 573126451d3fb80e6de5ffb7d1fa117d814417b8 (clean) (release)
  282. 11:28:16 8.358418 T:1967267840 NOTICE: ARM mem: 752MB GPU mem: 256MB MPG2:0 WVC1:0
  283. 11:28:16 8.358486 T:1967267840 NOTICE: cacheMemBufferSize: 20MB
  284. 11:28:16 8.358945 T:1967267840 NOTICE: Config:
  285. arm_freq=900
  286. audio_pwm_mode=1
  287. audio_sdm_mod_order=2
  288. config_hdmi_boost=5
  289. disable_commandline_tags=2
  290. disable_l2cache=1
  291. emmc_pll_core=1
  292. force_eeprom_read=1
  293. force_pwm_open=1
  294. framebuffer_ignore_alpha=1
  295. framebuffer_swap=1
  296. hdmi_channel_map=8
  297. hdmi_force_cec_address=65535
  298. hdmi_ignore_cec_init=1
  299. hdmi_pixel_encoding=3
  300. init_uart_clock=0x2dc6c0
  301. lcd_framerate=60
  302. over_voltage_avs=0x1b774
  303. overscan_bottom=48
  304. overscan_left=48
  305. overscan_right=48
  306. overscan_top=48
  307. pause_burst_frames=1
  308. program_serial_random=1
  309. sdram_freq=450
  310. temp_limit=85
  311. 11:28:16 8.359170 T:1967267840 NOTICE: Config:
  312. device_tree=-
  313. 11:28:16 8.359303 T:1967267840 DEBUG: DllBcm: Using omx system library
  314. 11:28:16 8.359378 T:1967267840 NOTICE: InitWindowSystem: Using EGL Implementation: raspberrypi
  315. 11:28:16 8.360880 T:1967267840 DEBUG: EGL initial desktop resolution 1920x1080 (1920x1080) @ 60.00 - Full Screen (1.00)
  316. 11:28:16 8.361402 T:1967267840 DEBUG: EGL get supported modes (1) = 14, prefer_group=1, prefer_mode=10
  317. 11:28:16 8.361510 T:1967267840 DEBUG: EGL mode 0: 640x480 (640x480) @ 60.00 - Full Screen (1.00) :1
  318. 11:28:16 8.361609 T:1967267840 DEBUG: EGL mode 1: 720x480 (720x480) @ 60.00 - Full Screen (0.89) :2
  319. 11:28:16 8.361698 T:1967267840 DEBUG: EGL mode 2: 720x480 (720x480) @ 60.00 - Full Screen (1.19) :3
  320. 11:28:16 8.361791 T:1967267840 DEBUG: EGL mode 3: 1280x720 (1280x720) @ 60.00 - Full Screen (1.00) :4
  321. 11:28:16 8.361877 T:1967267840 DEBUG: EGL mode 5: 1920x1080 (1920x1080) @ 60.00 - Full Screen (1.00) N:10
  322. 11:28:16 8.361969 T:1967267840 DEBUG: EGL mode 6: 720x576 (720x576) @ 50.00 - Full Screen (1.07) :11
  323. 11:28:16 8.362048 T:1967267840 DEBUG: EGL mode 7: 720x576 (720x576) @ 50.00 - Full Screen (1.42) :12
  324. 11:28:16 8.362138 T:1967267840 DEBUG: EGL mode 8: 1280x720 (1280x720) @ 50.00 - Full Screen (1.00) :13
  325. 11:28:16 8.362215 T:1967267840 DEBUG: EGL mode 10: 1920x1080 (1920x1080) @ 50.00 - Full Screen (1.00) :1f
  326. 11:28:16 8.362292 T:1967267840 DEBUG: EGL mode 11: 1920x1080 (1920x1080) @ 24.00 - Full Screen (1.00) :20
  327. 11:28:16 8.362380 T:1967267840 DEBUG: EGL mode 12: 1920x1080 (1920x1080) @ 25.00 - Full Screen (1.00) :21
  328. 11:28:16 8.362456 T:1967267840 DEBUG: EGL mode 13: 1920x1080 (1920x1080) @ 30.00 - Full Screen (1.00) :22
  329. 11:28:16 8.362784 T:1967267840 DEBUG: EGL get supported modes (2) = 1, prefer_group=1, prefer_mode=10
  330. 11:28:16 8.362871 T:1967267840 DEBUG: EGL mode 0: 640x480 (640x480) @ 60.00 - Full Screen (1.00) :4
  331. 11:28:16 8.362961 T:1967267840 DEBUG: EGL probe resolution 1920x1080 (1920x1080) @ 60.00 - Full Screen:10010004
  332. 11:28:16 8.363032 T:1967267840 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 60.000000 Hz
  333. 11:28:16 8.363154 T:1967267840 NOTICE: Found resolution 640 x 480 for display 0 with 640 x 480 @ 59.940063 Hz
  334. 11:28:16 8.363228 T:1967267840 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 60.000000 Hz
  335. 11:28:16 8.363297 T:1967267840 NOTICE: Found resolution 720 x 480 for display 0 with 720 x 480 @ 59.940063 Hz
  336. 11:28:16 8.363400 T:1967267840 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 60.000000 Hz
  337. 11:28:16 8.363480 T:1967267840 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 59.940063 Hz
  338. 11:28:16 8.363552 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 60.000000 Hz
  339. 11:28:16 8.363623 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 59.940063 Hz
  340. 11:28:16 8.363693 T:1967267840 NOTICE: Found resolution 720 x 576 for display 0 with 720 x 576 @ 50.000000 Hz
  341. 11:28:16 8.363763 T:1967267840 NOTICE: Found resolution 1280 x 720 for display 0 with 1280 x 720 @ 50.000000 Hz
  342. 11:28:16 8.363840 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 50.000000 Hz
  343. 11:28:16 8.363911 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 24.000000 Hz
  344. 11:28:16 8.363982 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 23.976025 Hz
  345. 11:28:16 8.364052 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 25.000000 Hz
  346. 11:28:16 8.364122 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 30.000000 Hz
  347. 11:28:16 8.364193 T:1967267840 NOTICE: Found resolution 1920 x 1080 for display 0 with 1920 x 1080 @ 29.970032 Hz
  348. 11:28:16 8.364260 T:1967267840 NOTICE: Found (1920x1080@60.000000) at 22, setting to RES_DESKTOP at 16
  349. 11:28:16 8.364353 T:1967267840 NOTICE: Checking resolution 16
  350. 11:28:16 8.377435 T:1919939648 DEBUG: EGL tv_service_callback (8,1,16)
  351. 11:28:16 8.377634 T:1967267840 DEBUG: EGL set HDMI mode (1,16)=0 off
  352. 11:28:16 8.378635 T:1967267840 DEBUG: EGL set resolution 1920x1080 -> 1920x1080 @ 60.00 fps (1,16) flags:10010004 aspect:1.00
  353. 11:28:16 8.403979 T:1967267840 DEBUG: CreateWindow: Created surface of size 1920x1080
  354. 11:28:16 8.404749 T:1967267840 NOTICE: GL_VENDOR = Broadcom
  355. 11:28:16 8.404842 T:1967267840 NOTICE: GL_RENDERER = VideoCore IV HW
  356. 11:28:16 8.404904 T:1967267840 NOTICE: GL_VERSION = OpenGL ES 2.0
  357. 11:28:16 8.404966 T:1967267840 NOTICE: GL_SHADING_LANGUAGE_VERSION = OpenGL ES GLSL ES 1.00
  358. 11:28:16 8.405047 T:1967267840 DEBUG: GLES: Extension Support Test - GL_NVX_gpu_memory_info NO
  359. 11:28:16 8.405116 T:1967267840 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
  360. 11:28:16 8.405217 T:1967267840 DEBUG: GLES: Extension Support Test - GL_EXT_texture_format_BGRA8888 YES
  361. 11:28:16 8.405292 T:1967267840 DEBUG: GLES: Extension Support Test - GL_IMG_texture_format_BGRA8888 NO
  362. 11:28:16 8.405365 T:1967267840 DEBUG: GLES: Extension Support Test - GL_APPLE_texture_format_BGRA8888 NO
  363. 11:28:16 8.408543 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  364. 11:28:16 8.408826 T:1967267840 DEBUG: Compiled
  365. 11:28:16 8.408906 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  366. 11:28:16 8.409376 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  367. 11:28:16 8.409541 T:1967267840 DEBUG: Compiled
  368. 11:28:16 8.409606 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  369. 11:28:16 8.427224 T:1967267840 DEBUG: GUI Shader [guishader_frag_default.glsl]- Initialise successful : 0x18f8c78
  370. 11:28:16 8.428400 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  371. 11:28:16 8.428588 T:1967267840 DEBUG: Compiled
  372. 11:28:16 8.428658 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  373. 11:28:16 8.429181 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  374. 11:28:16 8.429346 T:1967267840 DEBUG: Compiled
  375. 11:28:16 8.429410 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  376. 11:28:16 8.449936 T:1967267840 DEBUG: GUI Shader [guishader_frag_texture.glsl]- Initialise successful : 0x18fbd78
  377. 11:28:16 8.451050 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  378. 11:28:16 8.451233 T:1967267840 DEBUG: Compiled
  379. 11:28:16 8.451298 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  380. 11:28:16 8.451805 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  381. 11:28:16 8.451960 T:1967267840 DEBUG: Compiled
  382. 11:28:16 8.452023 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  383. 11:28:16 8.471748 T:1967267840 DEBUG: GUI Shader [guishader_frag_multi.glsl]- Initialise successful : 0x18fa910
  384. 11:28:16 8.472816 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  385. 11:28:16 8.473003 T:1967267840 DEBUG: Compiled
  386. 11:28:16 8.473082 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  387. 11:28:16 8.473616 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  388. 11:28:16 8.473780 T:1967267840 DEBUG: Compiled
  389. 11:28:16 8.473845 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  390. 11:28:16 8.494562 T:1967267840 DEBUG: GUI Shader [guishader_frag_fonts.glsl]- Initialise successful : 0x18fb1a8
  391. 11:28:16 8.495680 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  392. 11:28:16 8.495858 T:1967267840 DEBUG: Compiled
  393. 11:28:16 8.495922 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  394. 11:28:16 8.496391 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  395. 11:28:16 8.496546 T:1967267840 DEBUG: Compiled
  396. 11:28:16 8.496611 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  397. 11:28:16 8.515553 T:1967267840 DEBUG: GUI Shader [guishader_frag_texture_noblend.glsl]- Initialise successful : 0x18fb220
  398. 11:28:16 8.516719 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  399. 11:28:16 8.516919 T:1967267840 DEBUG: Compiled
  400. 11:28:16 8.516986 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  401. 11:28:16 8.517474 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  402. 11:28:16 8.517625 T:1967267840 DEBUG: Compiled
  403. 11:28:16 8.517688 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  404. 11:28:16 8.538897 T:1967267840 DEBUG: GUI Shader [guishader_frag_multi_blendcolor.glsl]- Initialise successful : 0x18d5680
  405. 11:28:16 8.540047 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  406. 11:28:16 8.540234 T:1967267840 DEBUG: Compiled
  407. 11:28:16 8.540301 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  408. 11:28:16 8.540832 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  409. 11:28:16 8.540988 T:1967267840 DEBUG: Compiled
  410. 11:28:16 8.541052 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  411. 11:28:16 8.560735 T:1967267840 DEBUG: GUI Shader [guishader_frag_rgba.glsl]- Initialise successful : 0x18d3bd0
  412. 11:28:16 8.560834 T:1967267840 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES
  413. 11:28:16 8.561893 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  414. 11:28:16 8.562081 T:1967267840 DEBUG: Compiled
  415. 11:28:16 8.562143 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  416. 11:28:16 8.562658 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  417. 11:28:16 8.562814 T:1967267840 DEBUG: Compiled
  418. 11:28:16 8.562875 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  419. 11:28:16 8.585830 T:1967267840 DEBUG: GUI Shader [guishader_frag_rgba_oes.glsl]- Initialise successful : 0x18d3c48
  420. 11:28:16 8.586896 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  421. 11:28:16 8.587078 T:1967267840 DEBUG: Compiled
  422. 11:28:16 8.587142 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  423. 11:28:16 8.587645 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  424. 11:28:16 8.587805 T:1967267840 DEBUG: Compiled
  425. 11:28:16 8.587867 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  426. 11:28:16 8.607633 T:1967267840 DEBUG: GUI Shader [guishader_frag_rgba_blendcolor.glsl]- Initialise successful : 0x18d47c0
  427. 11:28:16 8.608691 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  428. 11:28:16 8.608865 T:1967267840 DEBUG: Compiled
  429. 11:28:16 8.608928 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  430. 11:28:16 8.609536 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  431. 11:28:16 8.609690 T:1967267840 DEBUG: Compiled
  432. 11:28:16 8.609751 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  433. 11:28:16 8.634846 T:1967267840 DEBUG: GUI Shader [guishader_frag_rgba_bob.glsl]- Initialise successful : 0x18d4838
  434. 11:28:16 8.634942 T:1967267840 DEBUG: GLES: Extension Support Test - GL_OES_EGL_image_external YES
  435. 11:28:16 8.635996 T:1967267840 DEBUG: GL: Vertex Shader compilation log:
  436. 11:28:16 8.636172 T:1967267840 DEBUG: Compiled
  437. 11:28:16 8.636236 T:1967267840 DEBUG: GL: Vertex Shader compiled successfully
  438. 11:28:16 8.636862 T:1967267840 DEBUG: GL: Pixel Shader compilation log:
  439. 11:28:16 8.637023 T:1967267840 DEBUG: Compiled
  440. 11:28:16 8.637086 T:1967267840 DEBUG: GL: Fragment Shader compiled successfully
  441. 11:28:17 8.663916 T:1967267840 DEBUG: GUI Shader [guishader_frag_rgba_bob_oes.glsl]- Initialise successful : 0x18d5220
  442. 11:28:17 8.664031 T:1967267840 DEBUG: CWinSystemEGL::CreateNewWindow: No need to create a new window
  443. 11:28:17 8.679158 T:1919939648 DEBUG: EGL tv_service_callback (8,1,16)
  444. 11:28:17 8.679357 T:1967267840 DEBUG: EGL set HDMI mode (1,16)=0 off
  445. 11:28:17 8.679773 T:1967267840 DEBUG: EGL set resolution 1920x1080 -> 1920x1080 @ 60.00 fps (1,16) flags:10010004 aspect:1.00
  446. 11:28:17 8.702922 T:1967267840 DEBUG: CreateWindow: Created surface of size 1920x1080
  447. 11:28:17 8.703060 T:1967267840 INFO: GLES: Maximum texture width: 2048
  448. 11:28:17 8.703312 T:1967267840 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
  449. 11:28:17 8.711938 T:1967267840 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
  450. 11:28:17 8.712281 T:1967267840 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-arm.so
  451. 11:28:17 8.809872 T:1967267840 INFO: load keymapping
  452. 11:28:17 8.821615 T:1967267840 INFO: Loading special://xbmc/system/keymaps/appcommand.xml
  453. 11:28:17 8.823500 T:1967267840 INFO: Loading special://xbmc/system/keymaps/gamepad.xml
  454. 11:28:17 8.834802 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml
  455. 11:28:17 8.837689 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml
  456. 11:28:17 8.844101 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml
  457. 11:28:17 8.857589 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml
  458. 11:28:17 8.860285 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml
  459. 11:28:17 8.866220 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml
  460. 11:28:17 8.889188 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml
  461. 11:28:17 8.903826 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Nintendo.Wii.U.Pro.Controller.xml
  462. 11:28:17 8.906291 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Ouya.Controller.xml
  463. 11:28:17 8.914805 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml
  464. 11:28:17 8.919795 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.PS4.Controller.xml
  465. 11:28:17 8.927916 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml
  466. 11:28:17 8.946104 T:1967267840 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml
  467. 11:28:17 8.952636 T:1967267840 INFO: Loading special://xbmc/system/keymaps/keyboard.xml
  468. 11:28:17 8.980552 T:1967267840 INFO: Loading special://xbmc/system/keymaps/mouse.xml
  469. 11:28:17 8.983196 T:1967267840 INFO: Loading special://xbmc/system/keymaps/remote.xml
  470. 11:28:17 9.005616 T:1967267840 INFO: Loading special://xbmc/system/keymaps/touchscreen.xml
  471. 11:28:17 9.011162 T:1967267840 INFO: Loading special://masterprofile/keymaps/remote.xml
  472. 11:28:17 9.014768 T:1967267840 INFO: Loading special://profile/keymaps/remote.xml
  473. 11:28:17 9.016198 T:1967267840 INFO: Loading special://xbmc/system/Lircmap.xml
  474. 11:28:17 9.027070 T:1967267840 INFO: * Adding remote mapping for device 'mceusb'
  475. 11:28:17 9.027458 T:1967267840 INFO: * Adding remote mapping for device 'XboxDVDDongle'
  476. 11:28:17 9.027610 T:1967267840 INFO: * Adding remote mapping for device 'Microsoft_Xbox'
  477. 11:28:17 9.027750 T:1967267840 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote'
  478. 11:28:17 9.027899 T:1967267840 INFO: * Adding remote mapping for device 'anysee'
  479. 11:28:17 9.028051 T:1967267840 INFO: * Adding remote mapping for device 'iMON-PAD'
  480. 11:28:17 9.028232 T:1967267840 INFO: * Adding remote mapping for device 'Antec_Veris_RM200'
  481. 11:28:17 9.028406 T:1967267840 INFO: * Adding remote mapping for device 'MCE_via_iMON'
  482. 11:28:17 9.028584 T:1967267840 INFO: * Adding remote mapping for device 'TwinHanRemote'
  483. 11:28:17 9.028715 T:1967267840 INFO: * Adding remote mapping for device 'linux-input-layer'
  484. 11:28:17 9.028975 T:1967267840 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote'
  485. 11:28:17 9.029041 T:1967267840 INFO: * Adding remote mapping for device 'mediacenter'
  486. 11:28:17 9.029190 T:1967267840 INFO: * Adding remote mapping for device 'devinput'
  487. 11:28:17 9.031399 T:1967267840 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping
  488. 11:28:17 9.031543 T:1967267840 INFO: GUI format 1920x1080, Display 1920x1080 (1920x1080) @ 60.00 - Full Screen
  489. 11:28:17 9.031617 T:1967267840 DEBUG: guilib: Fill viewport on change for solving rendering passes
  490. 11:28:17 9.032276 T:1967267840 INFO: CLangInfo: loading resource.language.en_gb language information...
  491. 11:28:17 9.037571 T:1967267840 DEBUG: trying to set locale to en_DE.UTF-8
  492. 11:28:17 9.054258 T:1967267840 INFO: global locale set to C
  493. 11:28:17 9.054963 T:1967267840 INFO: CLangInfo: loading resource.language.en_gb language strings...
  494. 11:28:17 9.119365 T:1967267840 DEBUG: POParser: loaded 3331 strings from file resource://resource.language.en_gb/strings.po
  495. 11:28:17 9.123222 T:1967267840 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid"
  496. 11:28:17 9.123928 T:1967267840 DEBUG: LoadMappings - loaded node "CEC Adapter"
  497. 11:28:17 9.124086 T:1967267840 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
  498. 11:28:17 9.124423 T:1967267840 DEBUG: LoadMappings - loaded node "iMON HID device"
  499. 11:28:17 9.125068 T:1967267840 DEBUG: CPeripheralBusUSB - initialised udev monitor
  500. 11:28:17 9.125184 T:1967267840 DEBUG: SECTION:LoadDLL(libcec.so.3.0)
  501. 11:28:17 9.127590 T:1967267840 DEBUG: Loading: libcec.so.3.0
  502. 11:28:17 9.191337 T:1967267840 DEBUG: GetMappingForDevice - device (2708:1001) mapped to CEC Adapter (type = cec)
  503. 11:28:17 9.195551 T:1967267840 DEBUG: InitialiseFeature - using libCEC v3.0.1
  504. 11:28:17 9.195786 T:1967267840 DEBUG: Initialise - initialised peripheral on 'RPI' with 2 features and 0 sub devices
  505. 11:28:17 9.195892 T:1856586816 NOTICE: Thread CECAdapter start, auto delete: false
  506. 11:28:17 9.195989 T:1967267840 NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
  507. 11:28:17 9.196067 T:1856586816 DEBUG: OpenConnection - opening a connection to the CEC adapter: RPI
  508. 11:28:17 9.235300 T:1831420992 NOTICE: Thread PeripBusUSBUdev start, auto delete: false
  509. 11:28:17 9.235563 T:1967267840 DEBUG: SECTION:LoadDLL(libcurl.so.4)
  510. 11:28:17 9.238108 T:1967267840 DEBUG: Loading: libcurl.so.4
  511. 11:28:17 9.265952 T:1967267840 NOTICE: Running database version Addons19
  512. 11:28:17 9.266580 T:1967267840 DEBUG: Initialize, updating databases...
  513. 11:28:17 9.272939 T:1967267840 NOTICE: Running database version ViewModes6
  514. 11:28:17 9.291540 T:1967267840 NOTICE: Running database version Textures13
  515. 11:28:17 9.313780 T:1967267840 NOTICE: Running database version MyMusic52
  516. 11:28:17 9.417830 T:1967267840 NOTICE: Running database version MyVideos93
  517. 11:28:17 9.426414 T:1967267840 NOTICE: Running database version TV29
  518. 11:28:17 9.434048 T:1967267840 NOTICE: Running database version Epg10
  519. 11:28:17 9.434487 T:1967267840 DEBUG: Initialize, updating databases... DONE
  520. 11:28:17 9.434576 T:1967267840 NOTICE: start dvd mediatype detection
  521. 11:28:17 9.434850 T:1967267840 INFO: DPMS: not supported on this platform
  522. 11:28:17 9.434964 T:1822667840 NOTICE: Thread DetectDVDMedia start, auto delete: false
  523. 11:28:17 9.435056 T:1822667840 DEBUG: Compiled with libcdio Version 0.93
  524. 11:28:17 9.437684 T:1822667840 DEBUG: Thread DetectDVDMedia 1822667840 terminating
  525. 11:28:17 9.491508 T:1967267840 DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
  526. 11:28:17 9.491774 T:1967267840 DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
  527. 11:28:17 9.491984 T:1967267840 DEBUG: Activating window ID: 12997
  528. 11:28:17 9.492085 T:1967267840 DEBUG: ------ Window Init () ------
  529. 11:28:17 9.492416 T:1967267840 INFO: load splash image: /usr/share/kodi/media/Splash.png
  530. 11:28:17 9.494720 T:1967267840 INFO: Unloading old skin ...
  531. 11:28:17 9.495402 T:1967267840 INFO: load skin from: /storage/.kodi/addons/skin.conq (version: 2.2.0)
  532. 11:28:17 9.495510 T:1967267840 INFO: load fonts for skin...
  533. 11:28:17 9.505790 T:1967267840 INFO: Loading fonts from /storage/.kodi/addons/skin.conq/16x9/Font.xml
  534. 11:28:17 9.602755 T:1967267840 DEBUG: POParser: loaded 57 strings from file /storage/.kodi/addons/skin.conq/language/English/strings.po
  535. 11:28:17 9.604930 T:1967267840 INFO: Loading skin includes from /storage/.kodi/addons/skin.conq/16x9/Includes.xml
  536. 11:28:18 9.953101 T:1967267840 INFO: load new skin...
  537. 11:28:18 9.953543 T:1967267840 INFO: Loading user windows, path /storage/.kodi/addons/skin.conq/16x9
  538. 11:28:18 9.983438 T:1967267840 DEBUG: Load Skin XML: 30.37ms
  539. 11:28:18 9.983638 T:1967267840 INFO: initialize new skin...
  540. 11:28:18 9.983731 T:1967267840 DEBUG: guilib: Fill viewport on change for solving rendering passes
  541. 11:28:18 9.983834 T:1967267840 INFO: Loading skin file: DialogVolumeBar.xml, load type: LOAD_ON_GUI_INIT
  542. 11:28:18 9.990327 T:1967267840 INFO: Loading skin file: DialogKaiToast.xml, load type: LOAD_ON_GUI_INIT
  543. 11:28:18 9.994678 T:1967267840 INFO: Loading skin file: DialogMuteBug.xml, load type: LOAD_ON_GUI_INIT
  544. 11:28:18 9.999469 T:1967267840 INFO: Loading skin file: DialogSeekBar.xml, load type: LOAD_ON_GUI_INIT
  545. 11:28:18 10.037196 T:1967267840 DEBUG: OpenBundle - Opened bundle /storage/.kodi/addons/skin.conq/media/Textures.xbt
  546. 11:28:18 10.047998 T:1967267840 INFO: Loading skin file: VideoOSDSettings.xml, load type: LOAD_ON_GUI_INIT
  547. 11:28:18 10.068183 T:1967267840 INFO: Previous line repeats 1 times.
  548. 11:28:18 10.068370 T:1967267840 INFO: Loading skin file: DialogBusy.xml, load type: LOAD_ON_GUI_INIT
  549. 11:28:18 10.074449 T:1967267840 INFO: Loading skin file: DialogExtendedProgressBar.xml, load type: LOAD_ON_GUI_INIT
  550. 11:28:18 10.078925 T:1967267840 INFO: Loading skin file: DialogSubtitles.xml, load type: LOAD_ON_GUI_INIT
  551. 11:28:18 10.105331 T:1967267840 INFO: Loading skin file: DialogPVRChannelsOSD.xml, load type: LOAD_ON_GUI_INIT
  552. 11:28:18 10.118502 T:1967267840 INFO: Loading skin file: DialogPVRGuideOSD.xml, load type: LOAD_ON_GUI_INIT
  553. 11:28:18 10.128096 T:1967267840 INFO: Loading skin file: VideoOSD.xml, load type: LOAD_ON_GUI_INIT
  554. 11:28:18 10.183967 T:1967267840 INFO: skin loaded...
  555. 11:28:18 10.184211 T:1967267840 DEBUG: Activating window ID: 12997
  556. 11:28:18 10.184337 T:1967267840 DEBUG: ------ Window Init () ------
  557. 11:28:18 10.184680 T:1967267840 INFO: load splash image: /usr/share/kodi/media/Splash.png
  558. 11:28:18 10.223751 T:1967267840 DEBUG: JSONRPC: JSON schema type broadcastnext references an unknown type PVR.Details.Broadcast
  559. 11:28:18 10.223990 T:1967267840 WARNING: JSONRPC: Could not parse type "PVR.Details.Channel"
  560. 11:28:18 10.224079 T:1967267840 INFO: JSONRPC: Adding type "PVR.Details.Channel" to list of incomplete definitions (waiting for "PVR.Details.Broadcast")
  561. 11:28:18 10.224684 T:1967267840 DEBUG: JSONRPC: JSON schema type references an unknown type PVR.Details.Channel
  562. 11:28:18 10.224758 T:1967267840 DEBUG: Invalid item definition in "items" for type channels
  563. 11:28:18 10.224827 T:1967267840 WARNING: JSONRPC: Could not parse type "PVR.Details.ChannelGroup.Extended"
  564. 11:28:18 10.224895 T:1967267840 INFO: JSONRPC: Adding type "PVR.Details.ChannelGroup.Extended" to list of incomplete definitions (waiting for "PVR.Details.Channel")
  565. 11:28:18 10.226153 T:1967267840 INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Broadcast
  566. 11:28:18 10.226600 T:1967267840 INFO: JSONRPC: Resolving incomplete types/methods referencing PVR.Details.Channel
  567. 11:28:18 10.251696 T:1967267840 DEBUG: JSONRPC: JSON schema type stereoscopicmode references an unknown type GUI.Stereoscopy.Mode
  568. 11:28:18 10.251856 T:1967267840 WARNING: JSONRPC: Could not parse type "GUI.Property.Value"
  569. 11:28:18 10.251937 T:1967267840 INFO: JSONRPC: Adding type "GUI.Property.Value" to list of incomplete definitions (waiting for "GUI.Stereoscopy.Mode")
  570. 11:28:18 10.252344 T:1967267840 INFO: JSONRPC: Resolving incomplete types/methods referencing GUI.Stereoscopy.Mode
  571. 11:28:18 10.264074 T:1967267840 DEBUG: JSONRPC: JSON schema type definition references an unknown type Setting.Details.Setting
  572. 11:28:18 10.264179 T:1967267840 WARNING: JSONRPC: Could not parse type "Setting.Details.SettingList"
  573. 11:28:18 10.264254 T:1967267840 INFO: JSONRPC: Adding type "Setting.Details.SettingList" to list of incomplete definitions (waiting for "Setting.Details.Setting")
  574. 11:28:18 10.265279 T:1967267840 INFO: JSONRPC: Resolving incomplete types/methods referencing Setting.Details.Setting
  575. 11:28:18 10.385680 T:1967267840 INFO: JSONRPC v6.25.2: Successfully initialized
  576. 11:28:18 10.385901 T:1967267840 DEBUG: ADDON: Starting service addons.
  577. 11:28:18 10.387825 T:1822602304 NOTICE: Thread LanguageInvoker start, auto delete: false
  578. 11:28:18 10.388078 T:1822602304 INFO: initializing python engine.
  579. 11:28:18 10.388196 T:1967267840 DEBUG: Activating window ID: 12999
  580. 11:28:18 10.388330 T:1967267840 DEBUG: ------ Window Init (Startup.xml) ------
  581. 11:28:18 10.388428 T:1967267840 INFO: Loading skin file: Startup.xml, load type: LOAD_EVERY_TIME
  582. 11:28:18 10.412216 T:1967267840 DEBUG: Activating window ID: 10000
  583. 11:28:18 10.412433 T:1967267840 DEBUG: ------ Window Deinit (Startup.xml) ------
  584. 11:28:18 10.412553 T:1967267840 DEBUG: ------ Window Init (Home.xml) ------
  585. 11:28:18 10.412638 T:1967267840 INFO: Loading skin file: Home.xml, load type: KEEP_IN_MEMORY
  586. 11:28:18 10.502509 T:1967267840 DEBUG: POParser: loaded 115 strings from file /storage/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
  587. 11:28:18 10.551346 T:1822602304 DEBUG: Previous line repeats 10 times.
  588. 11:28:18 10.551553 T:1822602304 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.skin.widgets/default.py): start processing
  589. 11:28:18 10.553127 T:1967267840 DEBUG: POParser: loaded 115 strings from file /storage/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
  590. 11:28:19 10.704177 T:1822602304 DEBUG: Previous line repeats 10 times.
  591. 11:28:19 10.704416 T:1822602304 NOTICE: -->Python Interpreter Initialized<--
  592. 11:28:19 10.704719 T:1822602304 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.skin.widgets/default.py): the source file to load is "/storage/.kodi/addons/service.skin.widgets/default.py"
  593. 11:28:19 10.705423 T:1822602304 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.skin.widgets/default.py): setting the Python path to /storage/.kodi/addons/service.skin.widgets:/storage/.kodi/addons/script.module.simplejson/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
  594. 11:28:19 10.705656 T:1822602304 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.skin.widgets/default.py): entering source directory /storage/.kodi/addons/service.skin.widgets
  595. 11:28:19 10.705943 T:1822602304 DEBUG: CPythonInvoker(0, /storage/.kodi/addons/service.skin.widgets/default.py): instantiating addon using automatically obtained id of "service.skin.widgets" dependent on version 2.14.0 of the xbmc.python api
  596. 11:28:19 10.759093 T:1967267840 DEBUG: POParser: loaded 115 strings from file /storage/.kodi/addons/script.skinshortcuts/resources/language/English/strings.po
  597. 11:28:19 10.904129 T:1808790592 DEBUG: Previous line repeats 20 times.
  598. 11:28:19 10.904355 T:1808790592 NOTICE: Thread JobWorker start, auto delete: true
  599. 11:28:19 10.910295 T:1792013376 NOTICE: Previous line repeats 1 times.
  600. 11:28:19 10.910664 T:1792013376 NOTICE: Thread LanguageInvoker start, auto delete: false
  601. 11:28:19 10.911185 T:1792013376 INFO: initializing python engine.
  602. 11:28:19 10.911403 T:1792013376 DEBUG: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): start processing
  603. 11:28:19 10.914723 T:1783624768 NOTICE: Thread LanguageInvoker start, auto delete: false
  604. 11:28:19 10.915973 T:1783624768 INFO: initializing python engine.
  605. 11:28:19 10.916088 T:1967267840 INFO: removing tempfiles
  606. 11:28:19 10.916188 T:1783624768 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): start processing
  607. 11:28:19 10.919209 T:1967267840 DEBUG: ADDON: Starting service addons.
  608. 11:28:19 10.922427 T:1800401984 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update
  609. 11:28:19 10.925799 T:1775047744 NOTICE: Thread LanguageInvoker start, auto delete: false
  610. 11:28:19 10.926726 T:1967267840 NOTICE: initialize done
  611. 11:28:19 10.926901 T:1967267840 NOTICE: Running the application...
  612. 11:28:19 10.928058 T:1967267840 DEBUG: no profile autoexec.py (/storage/.kodi/userdata/autoexec.py) found, skipping
  613. 11:28:19 10.928236 T:1967267840 DEBUG: NetworkMessage - Starting network services
  614. 11:28:19 10.930302 T:1775047744 INFO: initializing python engine.
  615. 11:28:19 10.930518 T:1775047744 DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/service.openelec.settings/service.py): start processing
  616. 11:28:19 10.943255 T:1967267840 DEBUG: CZeroconfAvahi::clientCallback: client is up and running
  617. 11:28:19 10.943878 T:1967267840 NOTICE: starting zeroconf publishing
  618. 11:28:19 10.944267 T:1967267840 NOTICE: Webserver: Starting...
  619. 11:28:19 10.945872 T:1856586816 DEBUG: OpenConnection - connection to the CEC adapter opened
  620. 11:28:19 10.946160 T:1856586816 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
  621. 11:28:19 10.949286 T:1756361792 NOTICE: Thread CECAdapterUpdate start, auto delete: false
  622. 11:28:19 10.949617 T:1756361792 DEBUG: UpdateMenuLanguage - using TV menu language is disabled
  623. 11:28:19 10.950701 T:1967267840 NOTICE: WebServer: Started the webserver
  624. 11:28:19 10.951049 T:1808790592 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.webserver type: _http._tcp name:Kodi (OpenELEC) port:80
  625. 11:28:19 10.951245 T:1808790592 DEBUG: CZeroconfAvahi::addService() named: Kodi (OpenELEC) type: _http._tcp port:80
  626. 11:28:19 10.957220 T:1967267840 NOTICE: starting upnp client
  627. 11:28:19 10.957688 T:1800401984 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
  628. 11:28:19 10.962441 T:1800401984 DEBUG: GetRecentlyAddedAlbums query: select * from albumview where strAlbum != '' order by idAlbum desc limit 10
  629. 11:28:19 10.962681 T:1731195968 NOTICE: Thread JobWorker start, auto delete: true
  630. 11:28:19 10.962898 T:1731195968 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-http type: _xbmc-jsonrpc-h._tcp name:Kodi (OpenELEC) port:80
  631. 11:28:19 10.963614 T:1731195968 DEBUG: CZeroconfAvahi::addService() named: Kodi (OpenELEC) type: _xbmc-jsonrpc-h._tcp port:80
  632. 11:28:19 10.965090 T:1967267840 NOTICE: ES: Starting event server
  633. 11:28:19 10.965764 T:1967267840 INFO: JSONRPC Server: Successfully initialized
  634. 11:28:19 10.966611 T:1808790592 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:Kodi (OpenELEC) port:9090
  635. 11:28:19 10.967050 T:1967267840 DEBUG: SECTION:LoadDLL(libshairplay.so.0)
  636. 11:28:19 10.968094 T:1689252928 NOTICE: Thread EventServer start, auto delete: false
  637. 11:28:19 10.968358 T:1689252928 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
  638. 11:28:19 10.968552 T:1689252928 NOTICE: UDP: Listening on port 9777
  639. 11:28:19 10.970579 T:1680864320 NOTICE: Thread TCPServer start, auto delete: false
  640. 11:28:19 10.971868 T:1800401984 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update
  641. 11:28:19 10.975525 T:1731195968 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:Kodi (OpenELEC) port:9777
  642. 11:28:19 10.975782 T:1731195968 DEBUG: CZeroconfAvahi::addService() named: Kodi (OpenELEC) type: _xbmc-events._udp port:9777
  643. 11:28:19 10.977838 T:1967267840 DEBUG: Loading: libshairplay.so.0
  644. 11:28:19 10.985554 T:1967267840 INFO: AIRPLAY: Cleaning up photoassetcache
  645. 11:28:19 10.987025 T:1808790592 DEBUG: CZeroconfAvahi::addService() named: Kodi (OpenELEC) type: _xbmc-jsonrpc._tcp port:9090
  646. 11:28:19 10.988442 T:1731195968 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airtunes type: _raop._tcp name:B827EB2412A7@Kodi (OpenELEC) port:36666
  647. 11:28:19 10.989067 T:1967267840 INFO: AIRPLAY Server: Successfully initialized
  648. 11:28:19 10.992044 T:1645671488 NOTICE: Thread AirPlayServer start, auto delete: false
  649. 11:28:19 11.000139 T:1808790592 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.airplay type: _airplay._tcp name:Kodi (OpenELEC) port:36667
  650. 11:28:19 11.000532 T:1808790592 DEBUG: CZeroconfAvahi::addService() named: Kodi (OpenELEC) type: _airplay._tcp port:36667
  651. 11:28:19 11.002717 T:1967267840 WARNING: COMXImageFile::GetCodingType progressive images not supported by decoder
  652. 11:28:19 11.002929 T:1967267840 DEBUG: COMXImageFile::ReadFile special://skin/extras/backgrounds/1_green.jpg GetCodingType=0x7fffffff (1280x2d0)
  653. 11:28:19 11.003025 T:1967267840 NOTICE: LoadJpeg: unable to load special://skin/extras/backgrounds/1_green.jpg
  654. 11:28:19 11.014153 T:1731195968 DEBUG: CZeroconfAvahi::addService() named: B827EB2412A7@Kodi (OpenELEC) type: _raop._tcp port:36666
  655. 11:28:19 11.038555 T:1800401984 DEBUG: RunQuery took 19 ms for 10 items query: select * from movie_view ORDER BY dateAdded desc, idMovie desc LIMIT 10
  656. 11:28:19 11.089354 T:1800401984 DEBUG: RunQuery took 19 ms for 10 items query: select * from episode_view ORDER BY dateAdded desc, idEpisode desc LIMIT 10
  657. 11:28:19 11.133990 T:1800401984 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view ORDER BY dateAdded desc, idMVideo desc LIMIT 10
  658. 11:28:19 11.137731 T:1822602304 DEBUG: Skin Widgets: script version 0.0.32 started
  659. 11:28:19 11.139644 T:1800401984 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update
  660. 11:28:19 11.154486 T:1967267840 DEBUG: ------ Window Init () ------
  661. 11:28:19 11.175341 T:1967267840 DEBUG: ------ Window Init (Custom_Overlay_Debug.xml) ------
  662. 11:28:19 11.175574 T:1967267840 INFO: Loading skin file: Custom_Overlay_Debug.xml, load type: KEEP_IN_MEMORY
  663. 11:28:19 11.268382 T:1967267840 INFO: GLES: Enabling VSYNC
  664. 11:28:19 11.268620 T:1967267840 INFO: GLES: Selected vsync mode 10
  665. 11:28:19 11.446564 T:1967267840 DEBUG: LogindUPowerSyscall - Received unknown signal NameAcquired
  666. 11:28:19 11.457904 T:1783624768 NOTICE: -->Python Interpreter Initialized<--
  667. 11:28:19 11.458673 T:1783624768 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): the source file to load is "/storage/.kodi/addons/script.favourites/default.py"
  668. 11:28:19 11.461407 T:1783624768 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): setting the Python path to /storage/.kodi/addons/script.favourites:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
  669. 11:28:19 11.461603 T:1783624768 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): entering source directory /storage/.kodi/addons/script.favourites
  670. 11:28:19 11.461821 T:1783624768 DEBUG: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): instantiating addon using automatically obtained id of "script.favourites" dependent on version 2.19.0 of the xbmc.python api
  671. 11:28:19 11.465145 T:1967267840 DEBUG: ------ Window Init (DialogKaiToast.xml) ------
  672. 11:28:19 11.482108 T:1775047744 NOTICE: -->Python Interpreter Initialized<--
  673. 11:28:19 11.482591 T:1775047744 DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/service.openelec.settings/service.py): the source file to load is "/usr/share/kodi/addons/service.openelec.settings/service.py"
  674. 11:28:19 11.483245 T:1775047744 DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/service.openelec.settings/service.py): setting the Python path to /usr/share/kodi/addons/service.openelec.settings:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
  675. 11:28:19 11.483503 T:1775047744 DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/service.openelec.settings/service.py): entering source directory /usr/share/kodi/addons/service.openelec.settings
  676. 11:28:19 11.494500 T:1822602304 DEBUG: RunQuery took 14 ms for 54 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
  677. 11:28:19 11.495609 T:1775047744 DEBUG: CPythonInvoker(3, /usr/share/kodi/addons/service.openelec.settings/service.py): instantiating addon using automatically obtained id of "service.openelec.settings" dependent on version 2.1.0 of the xbmc.python api
  678. 11:28:20 11.817504 T:1764750400 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established
  679. 11:28:20 12.235987 T:1775047744 DEBUG: Previous line repeats 5 times.
  680. 11:28:20 12.236208 T:1775047744 NOTICE: ## OpenELEC Addon ## 0.6.12
  681. 11:28:20 12.272850 T:1792013376 NOTICE: -->Python Interpreter Initialized<--
  682. 11:28:20 12.273291 T:1792013376 DEBUG: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): the source file to load is "/storage/.kodi/addons/script.skinshortcuts/default.py"
  683. 11:28:20 12.279417 T:1792013376 DEBUG: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): setting the Python path to /storage/.kodi/addons/script.skinshortcuts:/storage/.kodi/addons/script.module.simplejson/lib:/storage/.kodi/addons/script.module.unidecode/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
  684. 11:28:20 12.279860 T:1792013376 DEBUG: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): entering source directory /storage/.kodi/addons/script.skinshortcuts
  685. 11:28:20 12.282168 T:1783624768 DEBUG: script.favourites: script version 6.0.3 started
  686. 11:28:20 12.282845 T:1783624768 DEBUG: script.favourites: ### params: {}
  687. 11:28:20 12.283621 T:1792013376 DEBUG: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): instantiating addon using automatically obtained id of "script.skinshortcuts" dependent on version 2.20.0 of the xbmc.python api
  688. 11:28:20 12.340746 T:1783624768 DEBUG: script.favourites: script stopped
  689. 11:28:20 12.341247 T:1783624768 INFO: CPythonInvoker(2, /storage/.kodi/addons/script.favourites/default.py): script successfully run
  690. 11:28:20 12.517706 T:1783624768 INFO: Python script stopped
  691. 11:28:20 12.518153 T:1783624768 DEBUG: Thread LanguageInvoker 1783624768 terminating
  692. 11:28:22 13.689116 T:1792013376 INFO: CPythonInvoker(1, /storage/.kodi/addons/script.skinshortcuts/default.py): script successfully run
  693. 11:28:22 13.779987 T:1792013376 INFO: Python script stopped
  694. 11:28:22 13.780375 T:1792013376 DEBUG: Thread LanguageInvoker 1792013376 terminating
  695. 11:28:22 13.805889 T:1756361792 DEBUG: UpdateAudioSystemStatus - CEC capable amplifier found (HT-XT1). volume will be controlled on the amp
  696. 11:28:22 13.806347 T:1756361792 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc
  697. 11:28:22 13.806981 T:1756361792 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged
  698. 11:28:22 13.932168 T:1822602304 DEBUG: RunQuery took 32 ms for 167 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
  699. 11:28:22 14.256653 T:1822602304 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
  700. 11:28:22 14.270403 T:1822602304 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
  701. 11:28:22 14.271631 T:1822602304 DEBUG: GetAlbumsByWhere - query took 1 ms
  702. 11:28:22 14.285086 T:1822602304 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist) OR artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
  703. 11:28:22 14.286544 T:1822602304 DEBUG: Time to retrieve artists from dataset = 2
  704. 11:28:22 14.304055 T:1822602304 DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
  705. 11:28:22 14.562116 T:1822602304 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:03.115307
  706. 11:28:22 14.589362 T:1822602304 DEBUG: RunQuery took 3 ms for 0 items query: select * from movie_view WHERE (movie_view.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
  707. 11:28:22 14.628280 T:1822602304 DEBUG: RunQuery took 13 ms for 0 items query: SELECT * FROM tvshow_view WHERE ( ((tvshow_view.watchedcount > 0 AND tvshow_view.watchedcount < tvshow_view.totalCount) OR (tvshow_view.watchedcount = 0 AND EXISTS (SELECT 1 FROM episode_view WHERE episode_view.idShow = tvshow_view.idShow AND episode_view.resumeTimeInSeconds > 0))))
  708. 11:28:22 14.641303 T:1822602304 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
  709. 11:28:22 14.642430 T:1822602304 DEBUG: GetAlbumsByWhere - query took 1 ms
  710. 11:28:23 14.662728 T:1822602304 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view
  711. 11:28:23 14.665442 T:1822602304 DEBUG: Skin Widgets: Total time needed to request recommended queries: 0:00:00.102894
  712. 11:28:23 14.697775 T:1822602304 DEBUG: RunQuery took 9 ms for 54 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount = 0))
  713. 11:28:23 15.140891 T:1822602304 DEBUG: RunQuery took 18 ms for 167 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
  714. 11:28:23 15.446959 T:1822602304 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view
  715. 11:28:23 15.459870 T:1822602304 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
  716. 11:28:23 15.461067 T:1822602304 DEBUG: GetAlbumsByWhere - query took 1 ms
  717. 11:28:23 15.462930 T:1822602304 DEBUG: Skin Widgets: Total time needed to request recent items queries: 0:00:00.797123
  718. 11:28:23 15.463221 T:1822602304 DEBUG: Skin Widgets: Total time needed for all queries: 0:00:04.016575
  719. 11:28:27 19.000635 T:1967267840 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------
  720. 11:28:47 39.046623 T:1967267840 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
  721. 11:28:47 39.047127 T:1967267840 DEBUG: Unloading: /ImageLib-arm.so
  722. 11:28:49 41.466263 T:1808790592 DEBUG: Thread JobWorker 1808790592 terminating (autodelete)
  723. 11:28:49 41.466755 T:1731195968 DEBUG: Thread JobWorker 1731195968 terminating (autodelete)
  724. 11:28:49 41.466938 T:1800401984 DEBUG: Thread JobWorker 1800401984 terminating (autodelete)
  725. 11:29:16 67.930763 T:1947202624 DEBUG: CAESinkPi:Drain delay:99ms now:0ms
  726. 11:29:26 77.931221 T:1947202624 DEBUG: CAESinkPi:Deinitialize
  727. 11:29:26 77.931976 T:1947202624 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
  728. 11:29:26 77.944534 T:1947202624 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x7420bad8
  729. 11:31:50 221.657135 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  730. 11:31:50 221.657440 T:1864975424 DEBUG: PushCecKeypress - added key a7
  731. 11:31:50 221.658478 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  732. 11:31:50 221.910538 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  733. 11:31:50 221.910782 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  734. 11:31:50 222.187454 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  735. 11:31:50 222.187744 T:1864975424 DEBUG: PushCecKeypress - added key a7
  736. 11:31:50 222.189011 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  737. 11:31:50 222.331268 T:1800401984 NOTICE: Thread JobWorker start, auto delete: true
  738. 11:31:50 222.340073 T:1800401984 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-arm.so)
  739. 11:31:50 222.340515 T:1800401984 DEBUG: Loading: /usr/lib/kodi/system/ImageLib-arm.so
  740. 11:31:50 222.441528 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  741. 11:31:50 222.441757 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  742. 11:31:51 222.773041 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  743. 11:31:51 222.773270 T:1864975424 DEBUG: PushCecKeypress - added key a7
  744. 11:31:51 222.803848 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  745. 11:31:51 223.027100 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  746. 11:31:51 223.027328 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  747. 11:32:16 248.214203 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  748. 11:32:16 248.214447 T:1864975424 DEBUG: PushCecKeypress - added key b
  749. 11:32:16 248.243195 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  750. 11:32:16 248.243683 T:1967267840 DEBUG: Activating window ID: 10004
  751. 11:32:16 248.450821 T:1967267840 DEBUG: ------ Window Deinit (Home.xml) ------
  752. 11:32:16 248.462265 T:1967267840 DEBUG: ------ Window Init (Settings.xml) ------
  753. 11:32:16 248.462433 T:1967267840 INFO: Loading skin file: Settings.xml, load type: KEEP_IN_MEMORY
  754. 11:32:16 248.468094 T:1864975424 DEBUG: PushCecKeypress - received key b duration 253 (rep:0 size:0)
  755. 11:32:16 248.468307 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  756. 11:32:17 249.202820 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  757. 11:32:17 249.203003 T:1864975424 DEBUG: PushCecKeypress - added key a7
  758. 11:32:17 249.237503 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  759. 11:32:17 249.456375 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  760. 11:32:17 249.456543 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  761. 11:32:18 249.733932 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  762. 11:32:18 249.734100 T:1864975424 DEBUG: PushCecKeypress - added key a7
  763. 11:32:18 249.738922 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  764. 11:32:18 249.987442 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  765. 11:32:18 249.987686 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  766. 11:32:18 250.264923 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  767. 11:32:18 250.265076 T:1864975424 DEBUG: PushCecKeypress - added key a7
  768. 11:32:18 250.294235 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  769. 11:32:18 250.518021 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  770. 11:32:18 250.518188 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  771. 11:32:19 250.795532 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  772. 11:32:19 250.795685 T:1864975424 DEBUG: PushCecKeypress - added key a7
  773. 11:32:19 250.797485 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  774. 11:32:19 251.048676 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  775. 11:32:19 251.048859 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  776. 11:32:19 251.326706 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  777. 11:32:19 251.326920 T:1864975424 DEBUG: PushCecKeypress - added key a7
  778. 11:32:19 251.328979 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  779. 11:32:19 251.579620 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  780. 11:32:19 251.579803 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  781. 11:32:20 251.856781 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  782. 11:32:20 251.856995 T:1864975424 DEBUG: PushCecKeypress - added key b
  783. 11:32:20 251.885986 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  784. 11:32:20 251.886276 T:1967267840 DEBUG: Activating window ID: 10016
  785. 11:32:20 251.886398 T:1967267840 DEBUG: ------ Window Deinit (Settings.xml) ------
  786. 11:32:20 251.886627 T:1967267840 DEBUG: ------ Window Init (SettingsCategory.xml) ------
  787. 11:32:20 251.886765 T:1967267840 INFO: Loading skin file: SettingsCategory.xml, load type: KEEP_IN_MEMORY
  788. 11:32:20 252.110367 T:1864975424 DEBUG: PushCecKeypress - received key b duration 254 (rep:0 size:0)
  789. 11:32:20 252.110550 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  790. 11:32:20 252.377579 T:1800401984 DEBUG: Thread JobWorker 1800401984 terminating (autodelete)
  791. 11:32:21 252.658875 T:1967267840 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-arm.so)
  792. 11:32:21 252.659134 T:1967267840 DEBUG: Unloading: /ImageLib-arm.so
  793. 11:32:21 253.337860 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  794. 11:32:21 253.338089 T:1864975424 DEBUG: PushCecKeypress - added key a7
  795. 11:32:21 253.353928 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  796. 11:32:21 253.592743 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 255 (rep:0 size:0)
  797. 11:32:21 253.592957 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  798. 11:32:22 253.869232 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  799. 11:32:22 253.869446 T:1864975424 DEBUG: PushCecKeypress - added key a7
  800. 11:32:22 253.869751 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  801. 11:32:22 254.122391 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  802. 11:32:22 254.122620 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  803. 11:32:22 254.399506 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  804. 11:32:22 254.399719 T:1864975424 DEBUG: PushCecKeypress - added key a7
  805. 11:32:22 254.402786 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  806. 11:32:22 254.653061 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  807. 11:32:23 254.653290 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  808. 11:32:23 254.931076 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  809. 11:32:23 254.931335 T:1864975424 DEBUG: PushCecKeypress - added key a7
  810. 11:32:23 254.932861 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  811. 11:32:23 255.184982 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  812. 11:32:23 255.185287 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  813. 11:32:23 255.462189 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  814. 11:32:23 255.462387 T:1864975424 DEBUG: PushCecKeypress - added key a7
  815. 11:32:23 255.470367 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  816. 11:32:24 255.716782 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  817. 11:32:24 255.717010 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  818. 11:32:24 255.994476 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  819. 11:32:24 255.994720 T:1864975424 DEBUG: PushCecKeypress - added key b
  820. 11:32:24 256.018494 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  821. 11:32:24 256.248169 T:1864975424 DEBUG: PushCecKeypress - received key b duration 253 (rep:0 size:0)
  822. 11:32:24 256.248505 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  823. 11:32:25 257.135956 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  824. 11:32:25 257.136322 T:1864975424 DEBUG: PushCecKeypress - added key a7
  825. 11:32:25 257.145782 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  826. 11:32:25 257.389465 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  827. 11:32:25 257.389709 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  828. 11:32:26 257.667023 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  829. 11:32:26 257.667328 T:1864975424 DEBUG: PushCecKeypress - added key a7
  830. 11:32:26 257.679108 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  831. 11:32:26 257.920898 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  832. 11:32:26 257.921143 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  833. 11:32:27 259.173126 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  834. 11:32:27 259.173492 T:1864975424 DEBUG: PushCecKeypress - added key b
  835. 11:32:27 259.179108 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  836. 11:32:27 259.179779 T:1967267840 DEBUG: ------ Window Init (DialogSelect.xml) ------
  837. 11:32:27 259.179962 T:1967267840 INFO: Loading skin file: DialogSelect.xml, load type: KEEP_IN_MEMORY
  838. 11:32:27 259.427032 T:1864975424 DEBUG: PushCecKeypress - received key b duration 253 (rep:0 size:0)
  839. 11:32:27 259.427246 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  840. 11:32:29 260.653351 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  841. 11:32:29 260.653564 T:1864975424 DEBUG: PushCecKeypress - added key a6
  842. 11:32:29 260.662537 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  843. 11:32:29 260.907532 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 254 (rep:0 size:0)
  844. 11:32:29 260.907745 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  845. 11:32:29 261.184998 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  846. 11:32:29 261.185181 T:1864975424 DEBUG: PushCecKeypress - added key a7
  847. 11:32:29 261.195648 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  848. 11:32:29 261.439117 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  849. 11:32:29 261.439301 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  850. 11:32:30 261.717224 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  851. 11:32:30 261.717438 T:1864975424 DEBUG: PushCecKeypress - added key a7
  852. 11:32:30 261.745636 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  853. 11:32:30 261.970337 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  854. 11:32:30 261.970551 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  855. 11:32:30 262.247467 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  856. 11:32:30 262.247650 T:1864975424 DEBUG: PushCecKeypress - added key a7
  857. 11:32:30 262.262299 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  858. 11:32:30 262.501373 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  859. 11:32:30 262.501770 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  860. 11:32:31 262.780273 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  861. 11:32:31 262.780548 T:1864975424 DEBUG: PushCecKeypress - added key a7
  862. 11:32:31 262.795776 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  863. 11:32:31 263.032562 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  864. 11:32:31 263.032867 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  865. 11:32:31 263.310547 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  866. 11:32:31 263.310791 T:1864975424 DEBUG: PushCecKeypress - added key a7
  867. 11:32:31 263.312408 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  868. 11:32:31 263.563873 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  869. 11:32:31 263.564117 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  870. 11:32:32 263.841827 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  871. 11:32:32 263.842102 T:1864975424 DEBUG: PushCecKeypress - added key a7
  872. 11:32:32 263.845764 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  873. 11:32:32 264.094788 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  874. 11:32:32 264.095032 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  875. 11:32:32 264.373138 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  876. 11:32:32 264.373505 T:1864975424 DEBUG: PushCecKeypress - added key a7
  877. 11:32:32 264.395782 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  878. 11:32:32 264.626038 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  879. 11:32:32 264.626373 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  880. 11:32:33 264.903503 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  881. 11:32:33 264.903748 T:1864975424 DEBUG: PushCecKeypress - added key a7
  882. 11:32:33 264.929108 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  883. 11:32:33 265.156891 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  884. 11:32:33 265.157135 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  885. 11:32:33 265.434265 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  886. 11:32:33 265.434509 T:1864975424 DEBUG: PushCecKeypress - added key a7
  887. 11:32:33 265.445801 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  888. 11:32:34 265.687866 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  889. 11:32:34 265.688141 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  890. 11:32:34 265.965210 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  891. 11:32:34 265.965454 T:1864975424 DEBUG: PushCecKeypress - added key a7
  892. 11:32:34 265.979065 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  893. 11:32:34 266.218231 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  894. 11:32:34 266.218475 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  895. 11:32:34 266.512482 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  896. 11:32:34 266.512634 T:1864975424 DEBUG: PushCecKeypress - added key a7
  897. 11:32:34 266.513062 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  898. 11:32:35 266.766602 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  899. 11:32:35 266.766907 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  900. 11:32:38 269.777649 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  901. 11:32:38 269.777924 T:1864975424 DEBUG: PushCecKeypress - added key a6
  902. 11:32:38 269.779175 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  903. 11:32:38 270.030792 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 253 (rep:0 size:0)
  904. 11:32:38 270.031067 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  905. 11:32:44 276.189270 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  906. 11:32:44 276.189545 T:1864975424 DEBUG: PushCecKeypress - added key a6
  907. 11:32:44 276.195770 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  908. 11:32:44 276.442474 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 254 (rep:0 size:0)
  909. 11:32:44 276.442719 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  910. 11:32:45 276.995300 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  911. 11:32:45 276.995667 T:1864975424 DEBUG: PushCecKeypress - added key a6
  912. 11:32:45 277.012451 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  913. 11:32:45 277.249237 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 254 (rep:0 size:0)
  914. 11:32:45 277.249481 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  915. 11:32:46 277.808594 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  916. 11:32:46 277.808868 T:1864975424 DEBUG: PushCecKeypress - added key a6
  917. 11:32:46 277.812439 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  918. 11:32:46 278.061646 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 253 (rep:0 size:0)
  919. 11:32:46 278.061920 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  920. 11:32:46 278.453094 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  921. 11:32:46 278.453461 T:1864975424 DEBUG: PushCecKeypress - added key a7
  922. 11:32:46 278.462433 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  923. 11:32:47 278.706238 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  924. 11:32:47 278.706512 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  925. 11:32:47 278.984253 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  926. 11:32:47 278.984558 T:1864975424 DEBUG: PushCecKeypress - added key a7
  927. 11:32:47 278.995789 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  928. 11:32:47 279.238129 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  929. 11:32:47 279.238464 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  930. 11:32:47 279.516724 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  931. 11:32:47 279.517029 T:1864975424 DEBUG: PushCecKeypress - added key a7
  932. 11:32:47 279.529144 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  933. 11:32:48 279.770172 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  934. 11:32:48 279.770447 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  935. 11:32:48 280.071411 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  936. 11:32:48 280.071716 T:1864975424 DEBUG: PushCecKeypress - added key b
  937. 11:32:48 280.079102 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  938. 11:32:48 280.324524 T:1864975424 DEBUG: PushCecKeypress - received key b duration 254 (rep:0 size:0)
  939. 11:32:48 280.324860 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  940. 11:32:49 280.955688 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 0 (rep:0 size:0)
  941. 11:32:49 280.956024 T:1864975424 DEBUG: PushCecKeypress - added key a6
  942. 11:32:49 280.962433 T:1967267840 DEBUG: OnKey: 166 (0xa6) pressed, action is Up
  943. 11:32:49 281.209198 T:1864975424 DEBUG: PushCecKeypress - received key a6 duration 253 (rep:0 size:0)
  944. 11:32:49 281.209503 T:1864975424 DEBUG: PushCecKeypress - ignored key a6
  945. 11:32:49 281.592712 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  946. 11:32:49 281.592987 T:1864975424 DEBUG: PushCecKeypress - added key a7
  947. 11:32:49 281.595764 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  948. 11:32:50 281.846100 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 253 (rep:0 size:0)
  949. 11:32:50 281.846375 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  950. 11:32:50 282.124329 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 0 (rep:0 size:0)
  951. 11:32:50 282.124603 T:1864975424 DEBUG: PushCecKeypress - added key a7
  952. 11:32:50 282.129089 T:1967267840 DEBUG: OnKey: 167 (0xa7) pressed, action is Down
  953. 11:32:50 282.377899 T:1864975424 DEBUG: PushCecKeypress - received key a7 duration 254 (rep:0 size:0)
  954. 11:32:50 282.378143 T:1864975424 DEBUG: PushCecKeypress - ignored key a7
  955. 11:32:52 284.513489 T:1864975424 DEBUG: PushCecKeypress - received key b duration 0 (rep:0 size:0)
  956. 11:32:52 284.513794 T:1864975424 DEBUG: PushCecKeypress - added key b
  957. 11:32:52 284.529144 T:1967267840 DEBUG: OnKey: 11 (0x0b) pressed, action is Select
  958. 11:32:53 284.661743 T:1967267840 DEBUG: ------ Window Deinit (DialogSelect.xml) ------
  959. 11:32:53 284.766602 T:1864975424 DEBUG: CecLogMessage - >> 01:8b:00
  960. 11:32:53 284.766968 T:1864975424 DEBUG: CecLogMessage - key released: select (0) D:253ms
  961. 11:32:53 284.767090 T:1864975424 DEBUG: PushCecKeypress - received key b duration 253 (rep:0 size:0)
  962. 11:32:53 284.767151 T:1864975424 DEBUG: PushCecKeypress - ignored key b
  963. 11:32:53 284.767212 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B)
  964. 11:32:59 291.364960 T:1864975424 DEBUG: CecLogMessage - >> 01:44:0d
  965. 11:32:59 291.365295 T:1864975424 DEBUG: CecLogMessage - SetCurrentButton exit (d) D:0ms cur:d
  966. 11:32:59 291.365448 T:1864975424 DEBUG: CecLogMessage - key pressed: exit (d) current(ff) duration(0)
  967. 11:32:59 291.365570 T:1864975424 DEBUG: CecLogMessage - Changed key exit (d) D:0ms cur:ff
  968. 11:32:59 291.365692 T:1864975424 DEBUG: CecLogMessage - key pressed: exit (d, 0)
  969. 11:32:59 291.365814 T:1864975424 DEBUG: PushCecKeypress - received key d8 duration 0 (rep:0 size:0)
  970. 11:32:59 291.365936 T:1864975424 DEBUG: PushCecKeypress - added key d8
  971. 11:32:59 291.366058 T:1864975424 DEBUG: CecLogMessage - CheckKeypressTimeout T:291.364
  972. 11:32:59 291.366150 T:1864975424 DEBUG: CecLogMessage - Key exit: idle (duration:0) (d) timeout:500ms (rel:500,rep:0,prs:1,rel:0)
  973. 11:32:59 291.366272 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
  974. 11:32:59 291.379120 T:1967267840 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu
  975. 11:32:59 291.409943 T:1967267840 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
  976. 11:32:59 291.410339 T:1967267840 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
  977. 11:32:59 291.411377 T:1967267840 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
  978. 11:32:59 291.411530 T:1967267840 DEBUG: ------ Window Init (Settings.xml) ------
  979. 11:32:59 291.618713 T:1864975424 DEBUG: CecLogMessage - >> 01:8b:0d
  980. 11:32:59 291.618927 T:1864975424 DEBUG: CecLogMessage - key released: exit (d) D:254ms
  981. 11:32:59 291.619019 T:1864975424 DEBUG: PushCecKeypress - received key d8 duration 254 (rep:0 size:0)
  982. 11:32:59 291.619110 T:1864975424 DEBUG: PushCecKeypress - ignored key d8
  983. 11:32:59 291.619202 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B)
  984. 11:33:35 326.722839 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  985. 11:33:35 326.723175 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  986. 11:33:35 326.723358 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  987. 11:33:35 326.723480 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  988. 11:33:35 326.723633 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  989. 11:33:35 326.723724 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  990. 11:33:35 326.723846 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  991. 11:33:35 327.023499 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  992. 11:33:35 327.023834 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  993. 11:33:35 327.023987 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  994. 11:33:35 327.024109 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  995. 11:33:35 327.024292 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  996. 11:33:35 327.024506 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  997. 11:33:35 327.024658 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  998. 11:33:35 327.371857 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  999. 11:33:35 327.372162 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1000. 11:33:35 327.372284 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1001. 11:33:35 327.372406 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1002. 11:33:35 327.372528 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1003. 11:33:35 327.372620 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1004. 11:33:35 327.462372 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1005. 11:33:35 327.462555 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1006. 11:33:35 327.462677 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1007. 11:33:35 327.462799 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1008. 11:33:35 327.612854 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1009. 11:33:35 327.613068 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1010. 11:33:36 327.733643 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1011. 11:33:36 327.733917 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1012. 11:33:36 327.734039 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1013. 11:33:36 327.734192 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1014. 11:33:36 327.777069 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1015. 11:33:36 327.777313 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1016. 11:33:36 327.777466 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1017. 11:33:36 327.777557 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1018. 11:33:36 328.078888 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1019. 11:33:36 328.079132 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1020. 11:33:36 328.079254 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1021. 11:33:36 328.079346 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1022. 11:33:36 328.079468 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1023. 11:33:36 328.079590 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1024. 11:33:36 328.079681 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1025. 11:33:45 337.469940 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1026. 11:33:45 337.470367 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1027. 11:33:45 337.470520 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1028. 11:33:45 337.470612 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1029. 11:33:45 337.470764 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1030. 11:33:45 337.470886 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1031. 11:33:45 337.471039 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1032. 11:33:46 337.770477 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1033. 11:33:46 337.770782 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1034. 11:33:46 337.770935 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1035. 11:33:46 337.771057 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1036. 11:33:46 337.771240 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1037. 11:33:46 337.771332 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1038. 11:33:46 337.771454 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1039. 11:33:46 338.119141 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1040. 11:33:46 338.119507 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1041. 11:33:46 338.119659 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1042. 11:33:46 338.119812 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1043. 11:33:46 338.120148 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1044. 11:33:46 338.120331 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1045. 11:33:46 338.209686 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1046. 11:33:46 338.209900 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1047. 11:33:46 338.210114 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1048. 11:33:46 338.210236 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1049. 11:33:46 338.360260 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1050. 11:33:46 338.360504 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1051. 11:33:46 338.480835 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1052. 11:33:46 338.481079 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1053. 11:33:46 338.481201 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1054. 11:33:46 338.481354 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1055. 11:33:46 338.524353 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1056. 11:33:46 338.524658 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1057. 11:33:46 338.524811 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1058. 11:33:46 338.524902 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1059. 11:33:47 338.826233 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1060. 11:33:47 338.826508 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1061. 11:33:47 338.826721 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1062. 11:33:47 338.826843 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1063. 11:33:47 338.826965 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1064. 11:33:47 338.827057 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1065. 11:33:47 338.827179 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1066. 11:34:22 373.894714 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:40:00
  1067. 11:34:22 373.895020 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 4000
  1068. 11:34:22 373.895172 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1069. 11:34:22 374.242920 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:40:00
  1070. 11:34:22 374.243195 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1071. 11:34:36 388.411865 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1072. 11:34:36 388.412201 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1073. 11:34:36 388.412323 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1074. 11:34:36 388.412415 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1075. 11:34:36 388.412567 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1076. 11:34:36 388.412659 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1077. 11:34:36 388.412750 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1078. 11:34:37 388.730225 T:1864975424 DEBUG: CecLogMessage - >> 5f:a0:08:00:46:00:0a:00
  1079. 11:34:37 388.730530 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): vendor command with id (A0)
  1080. 11:34:37 388.839844 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1081. 11:34:37 388.840179 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1082. 11:34:37 388.840424 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1083. 11:34:37 388.840546 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1084. 11:34:37 388.840668 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1085. 11:34:37 388.840790 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1086. 11:34:37 388.840881 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1087. 11:34:37 389.188324 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:40:00:12:00
  1088. 11:34:37 389.188599 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1089. 11:34:37 389.188721 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1090. 11:34:37 389.188873 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1091. 11:34:37 389.189087 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1092. 11:34:37 389.189240 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1093. 11:34:37 389.278839 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1094. 11:34:37 389.279022 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1095. 11:34:37 389.279144 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1096. 11:34:37 389.279266 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1097. 11:34:37 389.429138 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1098. 11:34:37 389.429382 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1099. 11:34:37 389.580109 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1100. 11:34:37 389.580353 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1101. 11:34:37 389.580444 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1102. 11:34:37 389.580597 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1103. 11:34:37 389.580719 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1104. 11:34:37 389.580872 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1105. 11:34:38 389.670502 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1106. 11:34:38 389.670776 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1107. 11:34:38 389.795807 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1108. 11:34:38 389.796112 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1109. 11:34:38 389.796265 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1110. 11:34:38 389.796387 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1111. 11:34:38 389.796570 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1112. 11:34:38 389.796814 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1113. 11:34:38 389.796997 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1114. 11:35:24 435.780487 T:1864975424 DEBUG: CecLogMessage - >> 0f:36
  1115. 11:35:24 435.780853 T:1864975424 DEBUG: CecLogMessage - TV (0): power status changed from 'on' to 'standby'
  1116. 11:35:24 435.780975 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36)
  1117. 11:35:24 435.929260 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:00
  1118. 11:35:24 435.929535 T:1864975424 DEBUG: CecLogMessage - >> Audio (5): system audio mode status changed from on to off
  1119. 11:35:24 435.929657 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  1120. 11:35:26 437.681152 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1121. 11:35:26 437.681427 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1122. 11:35:26 437.681549 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1123. 11:35:34 445.833832 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1124. 11:35:34 445.834106 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1125. 11:35:34 445.834290 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1126. 11:37:24 556.449219 T:1864975424 DEBUG: CecLogMessage - >> 4f:84:11:00:04
  1127. 11:37:24 556.449585 T:1864975424 DEBUG: CecLogMessage - Playback 1 (4): physical address changed from ffff to 1100
  1128. 11:37:24 556.449707 T:1864975424 DEBUG: CecLogMessage - device Playback 1 (4) status changed to present after command report physical address
  1129. 11:37:24 556.449829 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): report physical address (84)
  1130. 11:37:28 560.309692 T:1864975424 DEBUG: CecLogMessage - >> 0f:84:00:00:00
  1131. 11:37:28 560.310242 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical adddress 1200
  1132. 11:37:28 560.310425 T:1864975424 DEBUG: CecLogMessage - << 1f:84:12:00:01
  1133. 11:37:28 560.310486 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): report physical address (84)
  1134. 11:37:29 560.739868 T:1864975424 DEBUG: CecLogMessage - >> 41:83
  1135. 11:37:29 560.740295 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical adddress 1200
  1136. 11:37:29 560.740417 T:1864975424 DEBUG: CecLogMessage - << 1f:84:12:00:01
  1137. 11:37:29 560.740540 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Recorder 1 (1): give physical address (83)
  1138. 11:37:29 560.959900 T:1864975424 DEBUG: CecLogMessage - >> 41:9f
  1139. 11:37:29 560.960205 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> Playback 1 (4): cec version 1.4
  1140. 11:37:29 560.960327 T:1864975424 DEBUG: CecLogMessage - << 14:9e:05
  1141. 11:37:29 560.960449 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Recorder 1 (1): get cec version (9F)
  1142. 11:37:29 561.254944 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1143. 11:37:29 561.255249 T:1864975424 DEBUG: CecLogMessage - Audio (5): physical address changed from ffff to 1000
  1144. 11:37:29 561.255371 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1145. 11:38:24 616.310059 T:1822602304 DEBUG: RunQuery took 13 ms for 54 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1))
  1146. 11:38:25 616.809448 T:1822602304 DEBUG: RunQuery took 18 ms for 167 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1))
  1147. 11:38:25 617.108154 T:1822602304 DEBUG: RunQuery took 1 ms for 0 items query: select * from musicvideo_view
  1148. 11:38:25 617.122192 T:1822602304 DEBUG: GetAlbumsByWhere query: SELECT albumview.* FROM albumview WHERE albumview.strReleaseType = 'album'
  1149. 11:38:25 617.123718 T:1822602304 DEBUG: GetAlbumsByWhere - query took 1 ms
  1150. 11:38:25 617.137268 T:1822602304 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist) OR artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist)) and artistview.strArtist != '' and artistview.strArtist <> 'Various artists'
  1151. 11:38:25 617.138672 T:1822602304 DEBUG: Time to retrieve artists from dataset = 2
  1152. 11:38:25 617.156494 T:1822602304 DEBUG: GetSongsByWhere query = SELECT songview.* FROM songview WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1))
  1153. 11:38:25 617.391968 T:1822602304 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:01.130351
  1154. 11:41:38 810.600342 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1155. 11:41:38 810.600708 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1156. 11:41:39 810.740356 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1157. 11:41:39 810.740662 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1158. 11:41:39 811.102722 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1159. 11:41:39 811.102966 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1160. 11:41:39 811.103088 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1161. 11:41:42 814.354309 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1162. 11:41:42 814.354675 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1163. 11:41:42 814.354858 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1164. 11:41:43 815.175903 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1165. 11:41:43 815.176208 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1166. 11:41:43 815.316284 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1167. 11:41:43 815.316528 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1168. 11:41:44 816.054138 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1169. 11:41:44 816.054443 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1170. 11:41:44 816.054565 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1171. 11:41:48 819.909912 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1172. 11:41:48 819.910278 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1173. 11:41:48 820.076355 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:10:00
  1174. 11:41:48 820.076660 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
  1175. 11:41:48 820.076843 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1176. 11:41:48 820.425232 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:10:00
  1177. 11:41:48 820.425537 T:1864975424 DEBUG: CecLogMessage - TV (0): power status changed from 'standby' to 'on'
  1178. 11:41:48 820.425659 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1179. 11:41:48 820.536560 T:1864975424 DEBUG: CecLogMessage - >> 5f:81:12:00
  1180. 11:41:48 820.536865 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1181. 11:41:48 820.536987 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1182. 11:41:48 820.537109 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1183. 11:41:48 820.537292 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1184. 11:41:48 820.537354 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81)
  1185. 11:41:48 820.627136 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1186. 11:41:48 820.627380 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1187. 11:41:48 820.627502 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1188. 11:41:48 820.627686 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1189. 11:41:49 820.777710 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1190. 11:41:49 820.777954 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1191. 11:41:49 820.928406 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1192. 11:41:49 820.928589 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1193. 11:41:49 820.928711 T:1864975424 DEBUG: CecLogMessage - >> 51:90:01
  1194. 11:41:49 820.928894 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'on' to 'standby'
  1195. 11:41:49 820.929016 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1196. 11:41:49 821.119995 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1197. 11:41:49 821.120300 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1198. 11:41:49 821.332031 T:1864975424 DEBUG: CecLogMessage - >> 5f:a0:08:00:46:00:0a:00
  1199. 11:41:49 821.332336 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): vendor command with id (A0)
  1200. 11:41:50 821.874146 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:10:00:12:00
  1201. 11:41:50 821.874512 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1202. 11:41:50 821.874634 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1203. 11:41:50 821.874756 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1204. 11:41:50 821.874878 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1205. 11:41:50 821.875061 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1206. 11:41:50 821.875183 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1207. 11:41:50 821.964600 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1208. 11:41:50 822.055176 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1209. 11:41:50 822.055481 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1210. 11:41:50 822.055603 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1211. 11:41:50 822.265747 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1212. 11:41:50 822.265991 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1213. 11:41:50 822.446533 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1214. 11:41:50 822.446838 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1215. 11:41:50 822.446960 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1216. 11:41:50 822.447144 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1217. 11:41:50 822.447266 T:1864975424 DEBUG: CecLogMessage - Playback 1 (4): power status changed from 'unknown' to 'on'
  1218. 11:41:50 822.447388 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1219. 11:41:50 822.447449 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1220. 11:41:50 822.447571 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1221. 11:41:51 823.055298 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1222. 11:41:51 823.055542 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1223. 11:41:51 823.055664 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1224. 11:41:52 824.088196 T:1864975424 DEBUG: CecLogMessage - >> 01:83
  1225. 11:41:52 824.088501 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical adddress 1200
  1226. 11:41:52 824.088623 T:1864975424 DEBUG: CecLogMessage - << 1f:84:12:00:01
  1227. 11:41:52 824.088745 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
  1228. 11:41:52 824.475891 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:01
  1229. 11:41:52 824.476379 T:1864975424 DEBUG: CecLogMessage - >> Audio (5): system audio mode status changed from off to on
  1230. 11:41:52 824.476501 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  1231. 11:41:53 824.687866 T:1864975424 DEBUG: CecLogMessage - >> 5f:a0:08:00:46:00:0a:00
  1232. 11:41:53 824.688232 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): vendor command with id (A0)
  1233. 11:41:53 824.749817 T:1864975424 DEBUG: CecLogMessage - >> 01:8c
  1234. 11:41:53 824.750122 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Philips (903e)
  1235. 11:41:53 824.750244 T:1864975424 DEBUG: CecLogMessage - << 1f:87:00:15:82
  1236. 11:41:53 824.750366 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
  1237. 11:41:53 825.094910 T:1864975424 DEBUG: CecLogMessage - >> 0f:87:00:90:3e
  1238. 11:41:53 825.095215 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Philips (903e)
  1239. 11:41:53 825.095337 T:1864975424 DEBUG: CecLogMessage - << 1f:87:00:15:82
  1240. 11:41:53 825.095459 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87)
  1241. 11:41:53 825.348328 T:1864975424 DEBUG: CecLogMessage - >> 01:46
  1242. 11:41:53 825.348572 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
  1243. 11:41:53 825.348694 T:1864975424 DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
  1244. 11:41:53 825.348816 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
  1245. 11:41:58 829.885132 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1246. 11:41:58 829.885437 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1247. 11:41:58 829.885620 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1248. 11:41:58 829.885803 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1249. 11:41:58 829.885864 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1250. 11:41:58 830.298279 T:1864975424 DEBUG: CecLogMessage - >> 4f:84:11:00:04
  1251. 11:41:58 830.298584 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): report physical address (84)
  1252. 11:41:58 830.590942 T:1864975424 DEBUG: CecLogMessage - >> 01:83
  1253. 11:41:58 830.591187 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical adddress 1200
  1254. 11:41:58 830.591309 T:1864975424 DEBUG: CecLogMessage - << 1f:84:12:00:01
  1255. 11:41:58 830.591431 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
  1256. 11:41:59 830.807190 T:1864975424 DEBUG: CecLogMessage - >> 01:46
  1257. 11:41:59 830.807495 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
  1258. 11:41:59 830.807617 T:1864975424 DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
  1259. 11:41:59 830.807739 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
  1260. 11:41:59 831.047791 T:1864975424 DEBUG: CecLogMessage - >> 01:8c
  1261. 11:41:59 831.048035 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Philips (903e)
  1262. 11:41:59 831.048157 T:1864975424 DEBUG: CecLogMessage - << 1f:87:00:15:82
  1263. 11:41:59 831.048279 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
  1264. 11:41:59 831.410645 T:1864975424 DEBUG: CecLogMessage - >> 4f:84:11:00:04
  1265. 11:41:59 831.410950 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): report physical address (84)
  1266. 11:42:04 836.050781 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1267. 11:42:04 836.051086 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1268. 11:42:04 836.051208 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1269. 11:42:04 836.051331 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1270. 11:42:04 836.051453 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1271. 11:42:04 836.630493 T:1864975424 DEBUG: CecLogMessage - >> 4f:87:00:1a:11
  1272. 11:42:04 836.630737 T:1864975424 DEBUG: CecLogMessage - Playback 1 (4): vendor = Google (001a11)
  1273. 11:42:04 836.630859 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): device vendor id (87)
  1274. 11:42:11 843.080688 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1275. 11:42:11 843.081055 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1276. 11:42:11 843.081177 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1277. 11:42:11 843.081299 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1278. 11:42:11 843.081421 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1279. 11:42:18 850.091248 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1280. 11:42:18 850.091614 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1281. 11:42:18 850.091736 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1282. 11:42:18 850.091858 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1283. 11:42:18 850.091980 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1284. 11:42:18 850.528748 T:1864975424 DEBUG: CecLogMessage - >> 4f:84:11:00:04
  1285. 11:42:18 850.529053 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): report physical address (84)
  1286. 11:42:19 850.782715 T:1864975424 DEBUG: CecLogMessage - >> 4f:87:00:1a:11
  1287. 11:42:19 850.783020 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): device vendor id (87)
  1288. 11:42:25 857.120544 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1289. 11:42:25 857.120911 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1290. 11:42:25 857.121033 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1291. 11:42:25 857.121155 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1292. 11:42:25 857.121338 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1293. 11:42:32 864.140930 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1294. 11:42:32 864.141296 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1295. 11:42:32 864.141418 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1296. 11:42:32 864.141479 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1297. 11:42:32 864.141602 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1298. 11:42:39 871.160339 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1299. 11:42:39 871.160645 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1300. 11:42:39 871.160767 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1301. 11:42:39 871.160889 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1302. 11:42:39 871.161011 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1303. 11:42:40 872.086182 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1304. 11:42:40 872.086487 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1305. 11:42:40 872.086792 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1306. 11:42:40 872.086914 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1307. 11:42:40 872.087036 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1308. 11:42:40 872.087158 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1309. 11:42:40 872.087280 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1310. 11:42:40 872.387268 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1311. 11:42:40 872.387634 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1312. 11:42:40 872.387756 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1313. 11:42:40 872.387878 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1314. 11:42:40 872.388062 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1315. 11:42:40 872.388184 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1316. 11:42:40 872.388245 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1317. 11:42:41 872.735535 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1318. 11:42:41 872.735840 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1319. 11:42:41 872.735962 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1320. 11:42:41 872.736084 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1321. 11:42:41 872.736206 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1322. 11:42:41 872.736328 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1323. 11:42:41 872.826111 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1324. 11:42:41 872.826355 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1325. 11:42:41 872.826477 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1326. 11:42:41 872.826782 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1327. 11:42:41 872.976807 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1328. 11:42:41 872.977051 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1329. 11:42:41 872.977173 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1330. 11:42:41 873.127197 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1331. 11:42:41 873.217773 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1332. 11:42:41 873.218018 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1333. 11:42:41 873.218140 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1334. 11:42:41 873.278320 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1335. 11:42:41 873.278625 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1336. 11:42:41 873.489319 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1337. 11:42:41 873.489563 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1338. 11:42:41 873.489685 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1339. 11:42:41 873.489868 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'standby' to 'on'
  1340. 11:42:41 873.489990 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1341. 11:42:41 873.490173 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:00
  1342. 11:42:41 873.490295 T:1864975424 DEBUG: CecLogMessage - >> Audio (5): system audio mode status changed from on to off
  1343. 11:42:41 873.490417 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1344. 11:42:41 873.490601 T:1864975424 DEBUG: Previous line repeats 1 times.
  1345. 11:42:41 873.490723 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  1346. 11:42:43 875.065430 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1347. 11:42:43 875.065796 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1348. 11:42:43 875.065918 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1349. 11:42:46 878.160339 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1350. 11:42:46 878.160706 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1351. 11:42:46 878.160828 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1352. 11:42:46 878.160950 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1353. 11:42:46 878.161072 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1354. 11:42:53 885.150452 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1355. 11:42:53 885.150757 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1356. 11:42:53 885.150940 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1357. 11:42:53 885.151062 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1358. 11:42:53 885.151184 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1359. 11:43:00 892.169861 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1360. 11:43:00 892.170288 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1361. 11:43:00 892.170471 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1362. 11:43:00 892.170532 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1363. 11:43:00 892.170654 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1364. 11:43:07 899.191162 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1365. 11:43:07 899.191467 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1366. 11:43:07 899.191589 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1367. 11:43:07 899.191711 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1368. 11:43:07 899.191833 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1369. 11:43:14 906.209900 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1370. 11:43:14 906.210266 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1371. 11:43:14 906.210388 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1372. 11:43:14 906.210510 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1373. 11:43:14 906.210632 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1374. 11:43:21 913.229553 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1375. 11:43:21 913.229858 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1376. 11:43:21 913.229980 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1377. 11:43:21 913.230042 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1378. 11:43:21 913.230103 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1379. 11:43:28 920.249512 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1380. 11:43:28 920.250000 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1381. 11:43:28 920.250122 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1382. 11:43:28 920.250244 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1383. 11:43:28 920.250305 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1384. 11:43:35 927.249451 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1385. 11:43:35 927.249695 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1386. 11:43:35 927.249817 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1387. 11:43:35 927.249878 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1388. 11:43:35 927.250122 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1389. 11:43:42 934.279541 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1390. 11:43:42 934.279846 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1391. 11:43:42 934.280090 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1392. 11:43:42 934.280212 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1393. 11:43:42 934.280334 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1394. 11:43:49 941.289307 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1395. 11:43:49 941.289612 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1396. 11:43:49 941.289734 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1397. 11:43:49 941.289856 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1398. 11:43:49 941.290039 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1399. 11:43:56 948.309143 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1400. 11:43:56 948.309448 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1401. 11:43:56 948.309570 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1402. 11:43:56 948.309753 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1403. 11:43:56 948.309814 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1404. 11:44:00 952.137817 T:1864975424 DEBUG: CecLogMessage - >> 0f:36
  1405. 11:44:00 952.138123 T:1864975424 DEBUG: CecLogMessage - TV (0): power status changed from 'on' to 'standby'
  1406. 11:44:00 952.138245 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36)
  1407. 11:44:03 955.330994 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1408. 11:44:03 955.331299 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1409. 11:44:03 955.331421 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1410. 11:44:03 955.331543 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1411. 11:44:03 955.331604 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1412. 11:44:10 962.184998 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1413. 11:44:10 962.185242 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1414. 11:44:10 962.185425 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1415. 11:44:13 965.588867 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1416. 11:44:13 965.589172 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1417. 11:44:13 965.589294 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1418. 11:44:13 965.589417 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1419. 11:44:13 965.589478 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1420. 11:44:14 966.345764 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1421. 11:44:14 966.346008 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1422. 11:44:14 966.486084 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1423. 11:44:14 966.486389 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1424. 11:44:15 967.034729 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1425. 11:44:15 967.035034 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1426. 11:44:15 967.035156 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1427. 11:44:18 969.986877 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1428. 11:44:18 969.987183 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1429. 11:44:18 969.987305 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1430. 11:44:19 970.840149 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1431. 11:44:19 970.840454 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1432. 11:44:19 970.980347 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1433. 11:44:19 970.980652 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1434. 11:44:19 971.485718 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1435. 11:44:19 971.485962 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1436. 11:44:19 971.486084 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1437. 11:44:24 975.835327 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1438. 11:44:24 975.835693 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1439. 11:44:24 975.835815 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1440. 11:44:24 975.835938 T:1864975424 DEBUG: CecLogMessage - TV (0): power status changed from 'standby' to 'in transition from standby to on'
  1441. 11:44:24 975.836060 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1442. 11:44:24 975.836182 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1443. 11:44:24 975.836304 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1444. 11:44:24 975.836365 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1445. 11:44:24 976.209106 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1446. 11:44:24 976.209412 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1447. 11:44:24 976.209534 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1448. 11:44:24 976.209656 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1449. 11:44:24 976.209778 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1450. 11:44:24 976.209900 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1451. 11:44:24 976.210144 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1452. 11:44:24 976.557312 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1453. 11:44:24 976.557617 T:1864975424 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
  1454. 11:44:24 976.557739 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1455. 11:44:24 976.557861 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1456. 11:44:24 976.557983 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1457. 11:44:24 976.558105 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1458. 11:44:24 976.558228 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1459. 11:44:24 976.647827 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1460. 11:44:24 976.648010 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1461. 11:44:24 976.648132 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1462. 11:44:24 976.648254 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1463. 11:44:25 976.798340 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1464. 11:44:25 976.798584 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1465. 11:44:25 976.949158 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1466. 11:44:25 976.949463 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1467. 11:44:25 976.949585 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1468. 11:44:25 976.949768 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1469. 11:44:25 976.949890 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1470. 11:44:25 976.950073 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1471. 11:44:25 977.039795 T:1864975424 DEBUG: CecLogMessage - >> 51:90:01
  1472. 11:44:25 977.040161 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'on' to 'standby'
  1473. 11:44:25 977.040283 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1474. 11:44:25 977.147095 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1475. 11:44:25 977.147400 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1476. 11:44:25 977.447998 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1477. 11:44:25 977.448303 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1478. 11:44:26 977.968506 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1479. 11:44:26 977.968811 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1480. 11:44:26 977.968994 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1481. 11:44:26 977.969116 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1482. 11:44:26 977.969238 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1483. 11:44:27 979.288208 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1484. 11:44:27 979.288452 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1485. 11:44:27 979.288574 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1486. 11:44:31 982.728699 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1487. 11:44:31 982.729004 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1488. 11:44:31 982.729248 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1489. 11:44:31 982.729370 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1490. 11:44:31 982.729431 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1491. 11:44:31 983.459229 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1492. 11:44:31 983.459473 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1493. 11:44:31 983.599426 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1494. 11:44:31 983.599609 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1495. 11:44:32 984.238037 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1496. 11:44:32 984.238220 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1497. 11:44:32 984.238281 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1498. 11:44:35 987.189697 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1499. 11:44:35 987.190063 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1500. 11:44:35 987.190186 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1501. 11:44:36 988.271301 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1502. 11:44:36 988.271606 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1503. 11:44:36 988.410889 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1504. 11:44:36 988.411194 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1505. 11:44:36 988.588989 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1506. 11:44:36 988.589233 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1507. 11:44:36 988.589355 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1508. 11:44:41 993.024170 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1509. 11:44:41 993.024414 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1510. 11:44:41 993.024536 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1511. 11:44:41 993.024658 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1512. 11:44:41 993.024719 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1513. 11:44:41 993.024780 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1514. 11:44:41 993.024902 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1515. 11:44:41 993.325195 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1516. 11:44:41 993.325439 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1517. 11:44:41 993.325562 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1518. 11:44:41 993.325623 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1519. 11:44:41 993.325684 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1520. 11:44:41 993.325806 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1521. 11:44:41 993.325867 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1522. 11:44:42 993.673767 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1523. 11:44:42 993.674011 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1524. 11:44:42 993.674133 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1525. 11:44:42 993.674194 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1526. 11:44:42 993.674316 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1527. 11:44:42 993.674377 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1528. 11:44:42 993.674438 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1529. 11:44:42 993.764160 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1530. 11:44:42 993.854553 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1531. 11:44:42 993.854675 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1532. 11:44:42 993.854797 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1533. 11:44:42 993.914917 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1534. 11:44:42 993.915100 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1535. 11:44:42 994.035461 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1536. 11:44:42 994.035706 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1537. 11:44:42 994.086914 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1538. 11:44:42 994.087097 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1539. 11:44:42 994.087219 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1540. 11:44:42 994.087280 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1541. 11:44:42 994.416443 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1542. 11:44:42 994.416687 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1543. 11:44:43 994.890320 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1544. 11:44:43 994.890503 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1545. 11:44:43 994.890625 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1546. 11:44:44 996.341431 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1547. 11:44:44 996.341675 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1548. 11:44:44 996.341797 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1549. 11:44:50 1002.058350 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1550. 11:44:50 1002.058655 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1551. 11:44:50 1002.058838 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1552. 11:44:50 1002.058899 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1553. 11:44:50 1002.059021 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1554. 11:44:51 1003.081238 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1555. 11:44:51 1003.081482 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1556. 11:44:51 1003.222107 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1557. 11:44:51 1003.222412 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1558. 11:44:51 1003.542053 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1559. 11:44:51 1003.542236 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1560. 11:44:51 1003.542358 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1561. 11:44:54 1006.543823 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1562. 11:44:54 1006.544128 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1563. 11:44:54 1006.544250 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1564. 11:44:55 1007.368347 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1565. 11:44:55 1007.368652 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1566. 11:44:55 1007.508118 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1567. 11:44:55 1007.508423 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1568. 11:44:56 1008.093201 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1569. 11:44:56 1008.093506 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1570. 11:44:56 1008.093628 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1571. 11:45:00 1012.192566 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1572. 11:45:00 1012.192932 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1573. 11:45:00 1012.193054 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1574. 11:45:00 1012.193176 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1575. 11:45:00 1012.193542 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1576. 11:45:00 1012.193665 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1577. 11:45:00 1012.193787 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1578. 11:45:00 1012.546143 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1579. 11:45:00 1012.546448 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1580. 11:45:00 1012.547119 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1581. 11:45:00 1012.551880 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1582. 11:45:00 1012.552063 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1583. 11:45:00 1012.552185 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1584. 11:45:00 1012.552246 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1585. 11:45:01 1012.894226 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1586. 11:45:01 1012.894531 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1587. 11:45:01 1012.894653 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1588. 11:45:01 1012.894775 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1589. 11:45:01 1012.894897 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1590. 11:45:01 1012.895020 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1591. 11:45:01 1012.984741 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1592. 11:45:01 1012.984985 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1593. 11:45:01 1012.985107 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1594. 11:45:01 1012.985229 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1595. 11:45:01 1013.135315 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1596. 11:45:01 1013.135620 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1597. 11:45:01 1013.135742 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1598. 11:45:01 1013.285828 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1599. 11:45:01 1013.436462 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1600. 11:45:01 1013.436768 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1601. 11:45:01 1013.436890 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1602. 11:45:01 1013.587097 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1603. 11:45:01 1013.587280 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1604. 11:45:02 1013.797913 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1605. 11:45:02 1013.798218 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1606. 11:45:02 1013.798279 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1607. 11:45:02 1013.798462 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1608. 11:45:02 1013.798584 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1609. 11:45:02 1013.798706 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1610. 11:45:02 1013.918762 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1611. 11:45:02 1013.919067 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1612. 11:45:02 1013.919250 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'standby' to 'on'
  1613. 11:45:02 1013.919373 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1614. 11:45:02 1013.919495 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:00
  1615. 11:45:02 1013.919617 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1616. 11:45:02 1013.919739 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1617. 11:45:02 1013.919861 T:1864975424 DEBUG: Previous line repeats 1 times.
  1618. 11:45:02 1013.920044 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  1619. 11:45:02 1014.015015 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1620. 11:45:02 1014.015320 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1621. 11:45:05 1016.895447 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1622. 11:45:05 1016.895691 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1623. 11:45:05 1016.895813 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1624. 11:45:09 1021.098145 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1625. 11:45:09 1021.098511 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1626. 11:45:09 1021.098694 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1627. 11:45:09 1021.098816 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1628. 11:45:09 1021.098877 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1629. 11:45:10 1022.266968 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1630. 11:45:10 1022.267334 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1631. 11:45:10 1022.407227 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1632. 11:45:10 1022.407471 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1633. 11:45:10 1022.595520 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1634. 11:45:10 1022.595703 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1635. 11:45:10 1022.595825 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1636. 11:45:13 1025.547119 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1637. 11:45:13 1025.547363 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1638. 11:45:13 1025.547485 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1639. 11:45:14 1026.622192 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1640. 11:45:14 1026.622559 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1641. 11:45:15 1026.762207 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1642. 11:45:15 1026.762451 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1643. 11:45:15 1027.246460 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1644. 11:45:15 1027.246704 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1645. 11:45:15 1027.246826 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1646. 11:45:19 1031.337036 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1647. 11:45:19 1031.337280 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1648. 11:45:19 1031.337402 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1649. 11:45:19 1031.337524 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1650. 11:45:19 1031.337646 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1651. 11:45:19 1031.337891 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1652. 11:45:19 1031.337891 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1653. 11:45:20 1031.691162 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1654. 11:45:20 1031.691528 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1655. 11:45:20 1031.691650 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1656. 11:45:20 1031.691772 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1657. 11:45:20 1031.691895 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1658. 11:45:20 1031.692017 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1659. 11:45:20 1031.692139 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1660. 11:45:20 1032.039429 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1661. 11:45:20 1032.039795 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1662. 11:45:20 1032.040161 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1663. 11:45:20 1032.040283 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1664. 11:45:20 1032.040405 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1665. 11:45:20 1032.040527 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1666. 11:45:20 1032.040649 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1667. 11:45:20 1032.130005 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1668. 11:45:20 1032.130249 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1669. 11:45:20 1032.250488 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1670. 11:45:20 1032.250732 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1671. 11:45:20 1032.353638 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1672. 11:45:20 1032.354004 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1673. 11:45:20 1032.611572 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1674. 11:45:20 1032.611816 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1675. 11:45:20 1032.611938 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1676. 11:45:20 1032.612061 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1677. 11:45:21 1032.912598 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1678. 11:45:21 1032.912842 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1679. 11:45:21 1033.563110 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1680. 11:45:21 1033.563599 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1681. 11:45:21 1033.563721 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1682. 11:45:21 1033.563843 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1683. 11:45:21 1033.563843 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1684. 11:45:22 1034.647827 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1685. 11:45:22 1034.648071 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1686. 11:45:22 1034.648193 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1687. 11:45:26 1038.115112 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1688. 11:45:26 1038.115356 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1689. 11:45:26 1038.115479 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1690. 11:45:26 1038.115601 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1691. 11:45:26 1038.115723 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1692. 11:45:27 1039.087891 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1693. 11:45:27 1039.088257 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1694. 11:45:27 1039.227905 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1695. 11:45:27 1039.228271 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1696. 11:45:27 1039.595459 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1697. 11:45:27 1039.595703 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1698. 11:45:27 1039.595825 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1699. 11:45:30 1042.496216 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1700. 11:45:30 1042.496460 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1701. 11:45:30 1042.496704 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1702. 11:45:31 1043.272827 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1703. 11:45:31 1043.273193 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1704. 11:45:31 1043.413086 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1705. 11:45:31 1043.413574 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1706. 11:45:32 1044.145508 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1707. 11:45:32 1044.145752 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1708. 11:45:32 1044.145874 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1709. 11:45:36 1048.470337 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1710. 11:45:36 1048.470703 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1711. 11:45:36 1048.470947 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1712. 11:45:36 1048.470947 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1713. 11:45:36 1048.471191 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1714. 11:45:36 1048.471191 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1715. 11:45:36 1048.471436 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1716. 11:45:37 1048.772095 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1717. 11:45:37 1048.772461 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1718. 11:45:37 1048.772583 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1719. 11:45:37 1048.772583 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1720. 11:45:37 1048.772827 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1721. 11:45:37 1048.772949 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1722. 11:45:37 1048.773071 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1723. 11:45:37 1049.120483 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1724. 11:45:37 1049.120850 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1725. 11:45:37 1049.120972 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1726. 11:45:37 1049.121216 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1727. 11:45:37 1049.121338 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1728. 11:45:37 1049.121460 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1729. 11:45:37 1049.211060 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1730. 11:45:37 1049.211304 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1731. 11:45:37 1049.211426 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1732. 11:45:37 1049.211548 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1733. 11:45:37 1049.361450 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1734. 11:45:37 1049.361694 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1735. 11:45:37 1049.512573 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1736. 11:45:37 1049.512817 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1737. 11:45:37 1049.512939 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1738. 11:45:37 1049.513062 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1739. 11:45:37 1049.513184 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1740. 11:45:37 1049.513428 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1741. 11:45:37 1049.602905 T:1864975424 DEBUG: CecLogMessage - >> 51:90:01
  1742. 11:45:37 1049.603271 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'on' to 'standby'
  1743. 11:45:37 1049.603394 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1744. 11:45:38 1049.709106 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1745. 11:45:38 1049.709351 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1746. 11:45:38 1050.010132 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1747. 11:45:38 1050.010376 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1748. 11:45:38 1050.540649 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1749. 11:45:38 1050.540894 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1750. 11:45:38 1050.541016 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1751. 11:45:38 1050.541138 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1752. 11:45:38 1050.541260 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1753. 11:45:40 1051.698120 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1754. 11:45:40 1051.698486 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1755. 11:45:40 1051.698608 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1756. 11:45:43 1055.135132 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1757. 11:45:43 1055.135498 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1758. 11:45:43 1055.135620 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1759. 11:45:43 1055.135742 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1760. 11:45:43 1055.135864 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1761. 11:45:44 1055.872192 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1762. 11:45:44 1055.872559 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1763. 11:45:44 1056.012451 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1764. 11:45:44 1056.012695 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1765. 11:45:44 1056.648071 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1766. 11:45:44 1056.648315 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1767. 11:45:44 1056.648438 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1768. 11:45:47 1059.549683 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1769. 11:45:47 1059.550171 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1770. 11:45:47 1059.550293 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1771. 11:45:48 1060.136719 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1772. 11:45:48 1060.136963 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1773. 11:45:48 1060.276855 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1774. 11:45:48 1060.277100 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1775. 11:45:49 1060.999023 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1776. 11:45:49 1060.999268 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1777. 11:45:49 1060.999390 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1778. 11:45:53 1065.403687 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1779. 11:45:53 1065.404053 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1780. 11:45:53 1065.404175 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1781. 11:45:53 1065.404297 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1782. 11:45:53 1065.404419 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1783. 11:45:53 1065.404541 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1784. 11:45:53 1065.404663 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1785. 11:45:54 1065.752319 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1786. 11:45:54 1065.752686 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1787. 11:45:54 1065.752808 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1788. 11:45:54 1065.752930 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1789. 11:45:54 1065.753052 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1790. 11:45:54 1065.753174 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1791. 11:45:54 1065.753296 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1792. 11:45:54 1065.842651 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1793. 11:45:54 1065.933228 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1794. 11:45:54 1065.933472 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1795. 11:45:54 1065.933594 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1796. 11:45:54 1065.993652 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1797. 11:45:54 1065.993896 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1798. 11:45:54 1066.114136 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1799. 11:45:54 1066.114380 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1800. 11:45:54 1066.213135 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1801. 11:45:54 1066.213379 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1802. 11:45:54 1066.498779 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1803. 11:45:54 1066.499023 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1804. 11:45:54 1066.499146 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1805. 11:45:54 1066.499268 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1806. 11:45:55 1066.950439 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1807. 11:45:55 1066.950684 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1808. 11:45:55 1066.950806 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1809. 11:45:55 1067.100952 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1810. 11:45:55 1067.101196 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1811. 11:45:56 1068.601440 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1812. 11:45:56 1068.601685 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1813. 11:45:56 1068.601929 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1814. 11:46:02 1074.122803 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1815. 11:46:02 1074.123169 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1816. 11:46:02 1074.123413 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1817. 11:46:02 1074.123535 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1818. 11:46:02 1074.123657 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1819. 11:46:03 1074.854248 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1820. 11:46:03 1074.854492 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1821. 11:46:03 1074.994019 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1822. 11:46:03 1074.994263 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1823. 11:46:03 1075.602661 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1824. 11:46:03 1075.603027 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1825. 11:46:03 1075.603271 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1826. 11:46:07 1079.153564 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1827. 11:46:07 1079.153809 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1828. 11:46:07 1079.154053 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1829. 11:46:07 1079.651489 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1830. 11:46:07 1079.651733 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1831. 11:46:08 1079.791992 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1832. 11:46:08 1079.792236 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1833. 11:46:08 1080.552979 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1834. 11:46:08 1080.553223 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1835. 11:46:08 1080.553345 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1836. 11:46:12 1084.458130 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1837. 11:46:12 1084.458374 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1838. 11:46:12 1084.458496 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1839. 11:46:12 1084.458618 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1840. 11:46:12 1084.458740 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1841. 11:46:12 1084.458862 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1842. 11:46:12 1084.458984 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1843. 11:46:13 1084.777344 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1844. 11:46:13 1084.777588 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1845. 11:46:13 1084.777832 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1846. 11:46:13 1084.777954 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1847. 11:46:13 1084.778076 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1848. 11:46:13 1084.778198 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1849. 11:46:13 1084.778198 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1850. 11:46:13 1085.125244 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1851. 11:46:13 1085.125610 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1852. 11:46:13 1085.125732 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1853. 11:46:13 1085.125854 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1854. 11:46:13 1085.125977 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1855. 11:46:13 1085.126099 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1856. 11:46:13 1085.215820 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1857. 11:46:13 1085.216064 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  1858. 11:46:13 1085.216187 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1859. 11:46:13 1085.216309 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1860. 11:46:13 1085.366455 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1861. 11:46:13 1085.366821 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  1862. 11:46:13 1085.366943 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  1863. 11:46:13 1085.516968 T:1864975424 DEBUG: CecLogMessage - << 15:45
  1864. 11:46:14 1085.667603 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1865. 11:46:14 1085.667847 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1866. 11:46:14 1085.667969 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1867. 11:46:14 1085.818115 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1868. 11:46:14 1085.818359 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1869. 11:46:14 1086.029053 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1870. 11:46:14 1086.029419 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1871. 11:46:14 1086.029541 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1872. 11:46:14 1086.029663 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1873. 11:46:14 1086.029785 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1874. 11:46:14 1086.029907 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1875. 11:46:14 1086.150024 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1876. 11:46:14 1086.150269 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1877. 11:46:14 1086.150391 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'standby' to 'on'
  1878. 11:46:14 1086.150513 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  1879. 11:46:14 1086.150635 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:00
  1880. 11:46:14 1086.150879 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1881. 11:46:14 1086.150879 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  1882. 11:46:14 1086.151001 T:1864975424 DEBUG: Previous line repeats 1 times.
  1883. 11:46:14 1086.151123 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  1884. 11:46:14 1086.245239 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1885. 11:46:14 1086.245483 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1886. 11:46:17 1089.055176 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1887. 11:46:17 1089.055420 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1888. 11:46:17 1089.055542 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1889. 11:46:21 1093.383057 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1890. 11:46:21 1093.383423 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1891. 11:46:21 1093.383545 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1892. 11:46:21 1093.383667 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1893. 11:46:21 1093.383789 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1894. 11:46:22 1094.122437 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1895. 11:46:22 1094.122803 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1896. 11:46:22 1094.262451 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1897. 11:46:22 1094.262695 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1898. 11:46:23 1094.855347 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1899. 11:46:23 1094.855591 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1900. 11:46:23 1094.855835 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1901. 11:46:26 1098.307007 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1902. 11:46:26 1098.307251 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1903. 11:46:26 1098.307373 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1904. 11:46:27 1098.875732 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1905. 11:46:27 1098.876099 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1906. 11:46:27 1099.015869 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1907. 11:46:27 1099.016235 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1908. 11:46:28 1099.656250 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1909. 11:46:28 1099.656494 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1910. 11:46:28 1099.656738 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1911. 11:46:32 1104.012817 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1912. 11:46:32 1104.013184 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1913. 11:46:32 1104.013428 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1914. 11:46:32 1104.013550 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1915. 11:46:32 1104.013794 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1916. 11:46:32 1104.013794 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1917. 11:46:32 1104.013916 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1918. 11:46:32 1104.313599 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1919. 11:46:32 1104.313843 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1920. 11:46:32 1104.313965 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1921. 11:46:32 1104.314209 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1922. 11:46:32 1104.314331 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1923. 11:46:32 1104.314453 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1924. 11:46:32 1104.314453 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1925. 11:46:33 1104.661865 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1926. 11:46:33 1104.662231 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1927. 11:46:33 1104.662354 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1928. 11:46:33 1104.662476 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1929. 11:46:33 1104.662598 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1930. 11:46:33 1104.662598 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1931. 11:46:33 1104.662842 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1932. 11:46:33 1104.752197 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1933. 11:46:33 1104.752563 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1934. 11:46:33 1104.872925 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  1935. 11:46:33 1104.873169 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  1936. 11:46:33 1104.976196 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  1937. 11:46:33 1104.976440 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1938. 11:46:34 1105.938232 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1939. 11:46:34 1105.938477 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1940. 11:46:34 1105.938599 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1941. 11:46:34 1105.938721 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1942. 11:46:34 1105.938965 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1943. 11:46:35 1107.408936 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1944. 11:46:35 1107.409180 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1945. 11:46:35 1107.409302 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1946. 11:46:42 1113.862671 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  1947. 11:46:42 1113.863037 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  1948. 11:46:42 1113.863159 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1949. 11:46:42 1113.863159 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1950. 11:46:42 1113.863525 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  1951. 11:46:43 1114.776367 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1952. 11:46:43 1114.776733 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1953. 11:46:43 1114.916626 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1954. 11:46:43 1114.916870 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1955. 11:46:43 1115.359375 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1956. 11:46:43 1115.359619 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1957. 11:46:43 1115.359741 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1958. 11:46:44 1116.188599 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  1959. 11:46:44 1116.188965 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  1960. 11:46:44 1116.189087 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  1961. 11:46:44 1116.189209 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  1962. 11:46:44 1116.397705 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  1963. 11:46:44 1116.397949 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  1964. 11:46:46 1118.261475 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1965. 11:46:46 1118.261841 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1966. 11:46:46 1118.261963 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1967. 11:46:47 1119.110474 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  1968. 11:46:47 1119.110840 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  1969. 11:46:47 1119.250732 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  1970. 11:46:47 1119.250977 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  1971. 11:46:48 1119.760132 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  1972. 11:46:48 1119.760376 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  1973. 11:46:48 1119.760498 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  1974. 11:46:52 1124.173340 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1975. 11:46:52 1124.173706 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1976. 11:46:52 1124.173828 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1977. 11:46:52 1124.173950 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1978. 11:46:52 1124.174072 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1979. 11:46:52 1124.174194 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1980. 11:46:52 1124.174316 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1981. 11:46:52 1124.537964 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  1982. 11:46:52 1124.538330 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  1983. 11:46:52 1124.538452 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1984. 11:46:52 1124.538452 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1985. 11:46:52 1124.538696 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1986. 11:46:52 1124.538818 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  1987. 11:46:52 1124.538940 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  1988. 11:46:53 1124.886230 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  1989. 11:46:53 1124.886475 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  1990. 11:46:53 1124.886841 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  1991. 11:46:53 1124.886963 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  1992. 11:46:53 1124.887085 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  1993. 11:46:53 1124.887207 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  1994. 11:46:53 1124.976807 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  1995. 11:46:53 1124.977051 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  1996. 11:46:53 1124.977173 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  1997. 11:46:53 1124.977295 T:1864975424 DEBUG: CecLogMessage - << 10:04
  1998. 11:46:53 1125.127441 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  1999. 11:46:53 1125.127686 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2000. 11:46:53 1125.278198 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  2001. 11:46:53 1125.278442 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  2002. 11:46:53 1125.278564 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  2003. 11:46:53 1125.278687 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  2004. 11:46:53 1125.278809 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  2005. 11:46:53 1125.278931 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  2006. 11:46:53 1125.368652 T:1864975424 DEBUG: CecLogMessage - >> 51:90:01
  2007. 11:46:53 1125.369019 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'on' to 'standby'
  2008. 11:46:53 1125.369141 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  2009. 11:46:53 1125.476196 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  2010. 11:46:53 1125.476440 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2011. 11:46:54 1125.777100 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  2012. 11:46:54 1125.777466 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2013. 11:46:54 1126.498169 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  2014. 11:46:54 1126.498535 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  2015. 11:46:54 1126.498657 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2016. 11:46:54 1126.498657 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2017. 11:46:54 1126.498779 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  2018. 11:46:56 1128.063354 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2019. 11:46:56 1128.063599 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2020. 11:46:56 1128.063721 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2021. 11:47:02 1134.543091 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  2022. 11:47:02 1134.543457 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  2023. 11:47:02 1134.543701 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2024. 11:47:02 1134.543701 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2025. 11:47:02 1134.543823 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  2026. 11:47:03 1135.407227 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2027. 11:47:03 1135.407471 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2028. 11:47:03 1135.546997 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2029. 11:47:03 1135.547241 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2030. 11:47:04 1135.963745 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2031. 11:47:04 1135.963989 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2032. 11:47:04 1135.964111 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2033. 11:47:07 1139.014893 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2034. 11:47:07 1139.015137 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2035. 11:47:07 1139.015259 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2036. 11:47:08 1140.230103 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2037. 11:47:08 1140.230347 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2038. 11:47:08 1140.370850 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2039. 11:47:08 1140.371094 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2040. 11:47:09 1140.864258 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2041. 11:47:09 1140.864502 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2042. 11:47:09 1140.864624 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2043. 11:47:13 1144.865723 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  2044. 11:47:13 1144.866089 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  2045. 11:47:13 1144.866211 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2046. 11:47:13 1144.866333 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2047. 11:47:13 1144.866455 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2048. 11:47:13 1144.866699 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2049. 11:47:13 1144.866821 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  2050. 11:47:13 1145.166748 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  2051. 11:47:13 1145.166992 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  2052. 11:47:13 1145.167114 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2053. 11:47:13 1145.167236 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2054. 11:47:13 1145.167358 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2055. 11:47:13 1145.167480 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2056. 11:47:13 1145.167603 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  2057. 11:47:13 1145.515015 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  2058. 11:47:13 1145.515381 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2059. 11:47:13 1145.515503 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2060. 11:47:13 1145.515625 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  2061. 11:47:13 1145.515747 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  2062. 11:47:13 1145.515869 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  2063. 11:47:13 1145.515991 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  2064. 11:47:13 1145.605469 T:1864975424 DEBUG: CecLogMessage - << 15:45
  2065. 11:47:14 1145.696045 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  2066. 11:47:14 1145.696167 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  2067. 11:47:14 1145.696289 T:1864975424 DEBUG: CecLogMessage - << 10:04
  2068. 11:47:14 1145.756470 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2069. 11:47:14 1145.756714 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2070. 11:47:14 1145.937378 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  2071. 11:47:14 1145.937622 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  2072. 11:47:14 1145.937866 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  2073. 11:47:14 1145.937988 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  2074. 11:47:14 1145.938110 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  2075. 11:47:14 1145.938232 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  2076. 11:47:14 1146.183594 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  2077. 11:47:14 1146.183960 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2078. 11:47:15 1146.715698 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2079. 11:47:15 1146.715942 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2080. 11:47:15 1146.716064 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2081. 11:47:17 1148.766846 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2082. 11:47:17 1148.767090 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2083. 11:47:17 1148.767212 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2084. 11:47:22 1154.276123 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  2085. 11:47:22 1154.276489 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  2086. 11:47:22 1154.276611 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2087. 11:47:22 1154.276733 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2088. 11:47:22 1154.276855 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  2089. 11:47:23 1155.342285 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2090. 11:47:23 1155.342529 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2091. 11:47:23 1155.482788 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2092. 11:47:23 1155.483154 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2093. 11:47:24 1155.767822 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2094. 11:47:24 1155.768188 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2095. 11:47:24 1155.768311 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2096. 11:47:24 1156.647583 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  2097. 11:47:24 1156.647827 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2098. 11:47:27 1158.718750 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2099. 11:47:27 1158.718994 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2100. 11:47:27 1158.719116 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2101. 11:47:27 1159.543945 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2102. 11:47:27 1159.544189 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2103. 11:47:28 1159.684326 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2104. 11:47:28 1159.684692 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2105. 11:47:28 1160.068604 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2106. 11:47:28 1160.068970 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2107. 11:47:28 1160.069092 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2108. 11:47:32 1164.529907 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  2109. 11:47:32 1164.530273 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  2110. 11:47:32 1164.530396 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2111. 11:47:32 1164.530518 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2112. 11:47:32 1164.530640 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2113. 11:47:32 1164.530762 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2114. 11:47:32 1164.530762 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  2115. 11:47:33 1164.878052 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  2116. 11:47:33 1164.878418 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2117. 11:47:33 1164.880859 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2118. 11:47:33 1164.880981 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  2119. 11:47:33 1164.881104 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  2120. 11:47:33 1164.881226 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  2121. 11:47:33 1164.968384 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  2122. 11:47:33 1164.968628 T:1864975424 DEBUG: CecLogMessage - powering up the AVR
  2123. 11:47:33 1164.968750 T:1864975424 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5)
  2124. 11:47:33 1164.968872 T:1864975424 DEBUG: CecLogMessage - << 15:8f
  2125. 11:47:33 1165.119385 T:1864975424 DEBUG: CecLogMessage - expected response received (90: report power status)
  2126. 11:47:33 1165.119629 T:1864975424 DEBUG: CecLogMessage - << powering on 'Audio' (5)
  2127. 11:47:33 1165.119751 T:1864975424 DEBUG: CecLogMessage - << 15:44:40
  2128. 11:47:33 1165.270020 T:1864975424 DEBUG: CecLogMessage - << 15:45
  2129. 11:47:33 1165.360474 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  2130. 11:47:33 1165.360718 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  2131. 11:47:33 1165.360840 T:1864975424 DEBUG: CecLogMessage - << 10:04
  2132. 11:47:33 1165.420898 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2133. 11:47:33 1165.421143 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2134. 11:47:33 1165.601685 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  2135. 11:47:33 1165.601929 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  2136. 11:47:33 1165.602051 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  2137. 11:47:33 1165.602173 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  2138. 11:47:33 1165.602295 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  2139. 11:47:33 1165.602417 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  2140. 11:47:34 1165.902588 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  2141. 11:47:34 1165.902832 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  2142. 11:47:34 1165.902954 T:1864975424 DEBUG: CecLogMessage - Audio (5): power status changed from 'standby' to 'on'
  2143. 11:47:34 1165.903076 T:1864975424 DEBUG: CecLogMessage - >> 51:90:00
  2144. 11:47:34 1165.903320 T:1864975424 DEBUG: CecLogMessage - >> 5f:72:00
  2145. 11:47:34 1165.903564 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2146. 11:47:34 1165.903687 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Recorder 1 (1): report power status (90)
  2147. 11:47:34 1165.903809 T:1864975424 DEBUG: Previous line repeats 1 times.
  2148. 11:47:34 1165.903931 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): set system audio mode (72)
  2149. 11:47:34 1166.088989 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  2150. 11:47:34 1166.089233 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2151. 11:47:35 1167.169434 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2152. 11:47:35 1167.169678 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2153. 11:47:35 1167.169800 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2154. 11:47:39 1170.871094 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2155. 11:47:39 1170.871338 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2156. 11:47:39 1170.871460 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2157. 11:47:42 1173.751831 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  2158. 11:47:42 1173.752075 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  2159. 11:47:42 1173.752319 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2160. 11:47:42 1173.752441 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2161. 11:47:42 1173.752563 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  2162. 11:47:42 1174.565674 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2163. 11:47:42 1174.566040 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2164. 11:47:43 1174.705566 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2165. 11:47:43 1174.705933 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2166. 11:47:43 1175.270874 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2167. 11:47:43 1175.271118 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2168. 11:47:43 1175.271240 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2169. 11:47:46 1178.222900 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2170. 11:47:46 1178.223389 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2171. 11:47:46 1178.223511 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2172. 11:47:47 1179.017700 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2173. 11:47:47 1179.018066 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2174. 11:47:47 1179.157959 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2175. 11:47:47 1179.158203 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2176. 11:47:47 1179.571655 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2177. 11:47:47 1179.571899 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2178. 11:47:47 1179.572021 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2179. 11:47:52 1184.083374 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  2180. 11:47:52 1184.100464 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  2181. 11:47:52 1184.100586 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2182. 11:47:52 1184.100708 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2183. 11:47:52 1184.100830 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2184. 11:47:52 1184.100952 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2185. 11:47:52 1184.101074 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  2186. 11:47:52 1184.383789 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:12:00
  2187. 11:47:52 1184.384033 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200
  2188. 11:47:52 1184.384155 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2189. 11:47:52 1184.384155 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2190. 11:47:52 1184.384277 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2191. 11:47:52 1184.384399 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2192. 11:47:52 1184.384399 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
  2193. 11:47:53 1184.732178 T:1864975424 DEBUG: CecLogMessage - >> 0f:80:12:00:12:00
  2194. 11:47:53 1184.732422 T:1864975424 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
  2195. 11:47:53 1184.732422 T:1864975424 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
  2196. 11:47:53 1184.732544 T:1864975424 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
  2197. 11:47:53 1184.732666 T:1864975424 DEBUG: CecLogMessage - << powering on 'TV' (0)
  2198. 11:47:53 1184.732666 T:1864975424 DEBUG: CecLogMessage - << 10:04
  2199. 11:47:53 1184.732788 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80)
  2200. 11:47:53 1184.822510 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2201. 11:47:53 1184.822754 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2202. 11:47:53 1184.942871 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
  2203. 11:47:53 1184.943115 T:1864975424 DEBUG: CecLogMessage - 'menu status' is marked as unsupported feature for device 'TV'
  2204. 11:47:53 1185.046387 T:1864975424 DEBUG: CecLogMessage - >> 01:00:04:01
  2205. 11:47:53 1185.046875 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2206. 11:47:53 1185.299927 T:1864975424 DEBUG: CecLogMessage - >> 01:8f
  2207. 11:47:53 1185.300171 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
  2208. 11:47:53 1185.300293 T:1864975424 DEBUG: CecLogMessage - << 10:90:00
  2209. 11:47:53 1185.300415 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
  2210. 11:47:53 1185.601074 T:1864975424 DEBUG: CecLogMessage - >> 01:00:90:01
  2211. 11:47:53 1185.601318 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): feature abort ( 0)
  2212. 11:47:54 1186.177002 T:1864975424 DEBUG: CecLogMessage - >> 4f:85
  2213. 11:47:54 1186.177490 T:1864975424 DEBUG: CecLogMessage - >> 4 requests active source
  2214. 11:47:54 1186.177612 T:1864975424 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1200)
  2215. 11:47:54 1186.177734 T:1864975424 DEBUG: CecLogMessage - << 1f:82:12:00
  2216. 11:47:54 1186.177856 T:1864975424 DEBUG: CecLogMessage - >> Playback 1 (4) -> Broadcast (F): request active source (85)
  2217. 11:47:56 1187.974243 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2218. 11:47:56 1187.974487 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2219. 11:47:56 1187.974609 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2220. 11:48:00 1192.381348 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2221. 11:48:00 1192.381592 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2222. 11:48:00 1192.521606 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2223. 11:48:00 1192.521851 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2224. 11:48:01 1192.874023 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2225. 11:48:01 1192.874268 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2226. 11:48:01 1192.874268 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2227. 11:48:04 1196.225830 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2228. 11:48:04 1196.226074 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2229. 11:48:04 1196.226196 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2230. 11:48:05 1197.075439 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2231. 11:48:05 1197.075684 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2232. 11:48:05 1197.214966 T:1864975424 DEBUG: CecLogMessage - >> 5f:87:08:00:46
  2233. 11:48:05 1197.215210 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87)
  2234. 11:48:06 1197.724976 T:1864975424 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1200
  2235. 11:48:06 1197.725220 T:1864975424 DEBUG: CecLogMessage - physical address changed to 1200
  2236. 11:48:06 1197.725342 T:1864975424 DEBUG: CecLogMessage - physical address unchanged (1200)
  2237. 11:48:10 1201.782959 T:1864975424 DEBUG: CecLogMessage - >> 5f:84:10:00:05
  2238. 11:48:10 1201.783325 T:1864975424 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84)
  2239. 11:48:10 1201.949463 T:1864975424 DEBUG: CecLogMessage - >> 0f:86:10:00
  2240. 11:48:10 1201.949829 T:1864975424 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
  2241. 11:48:10 1201.949951 T:1864975424 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!