13:25:40 T:13420352 NOTICE: ----------------------------------------------------------------------- 13:25:40 T:13420352 NOTICE: Starting XBMC (11.0-PRE Git:20120316-45a316b), Platform: Linux (Ubuntu 11.04, 2.6.38-11-generic i686). Built on Mar 20 2012 13:25:40 T:13420352 NOTICE: special://xbmc/ is mapped to: /usr/local/share/xbmc 13:25:40 T:13420352 NOTICE: special://xbmcbin/ is mapped to: /usr/local/lib/xbmc 13:25:40 T:13420352 NOTICE: special://masterprofile/ is mapped to: /home/mark/.xbmc/userdata 13:25:40 T:13420352 NOTICE: special://home/ is mapped to: /home/mark/.xbmc 13:25:40 T:13420352 NOTICE: special://temp/ is mapped to: /home/mark/.xbmc/temp 13:25:40 T:13420352 NOTICE: The executable running is: /usr/local/lib/xbmc/xbmc.bin 13:25:40 T:13420352 NOTICE: Local hostname: mark-ion 13:25:40 T:13420352 NOTICE: Log File is located: /home/mark/.xbmc/temp/xbmc.log 13:25:40 T:13420352 NOTICE: ----------------------------------------------------------------------- 13:25:40 T:13420352 NOTICE: Setup SDL 13:25:40 T:13420352 INFO: Available videomodes (xrandr): 13:25:40 T:13420352 INFO: Number of connected outputs: 1 13:25:40 T:13420352 INFO: Output 'default' has 112 modes 13:25:40 T:13420352 INFO: ID:0x12b Name:1920x1080 Refresh:50.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x12c Name:1920x1080 Refresh:51.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x12d Name:1920x1080 Refresh:52.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x12e Name:1920x1080 Refresh:53.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x12f Name:1920x1080 Refresh:54.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x130 Name:1920x1080 Refresh:55.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x131 Name:1920x1080 Refresh:56.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x132 Name:1920x1080 Refresh:57.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x133 Name:1920x1080 Refresh:58.000000 Width:1920 Height:1080 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x134 Name:1680x1050 Refresh:59.000000 Width:1680 Height:1050 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x135 Name:1680x1050 Refresh:60.000000 Width:1680 Height:1050 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x136 Name:1600x1024 Refresh:61.000000 Width:1600 Height:1024 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x137 Name:1440x900 Refresh:62.000000 Width:1440 Height:900 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x138 Name:1400x1050 Refresh:63.000000 Width:1400 Height:1050 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x139 Name:1400x1050 Refresh:64.000000 Width:1400 Height:1050 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13a Name:1360x768 Refresh:65.000000 Width:1360 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13b Name:1360x768 Refresh:66.000000 Width:1360 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13c Name:1360x765 Refresh:67.000000 Width:1360 Height:765 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13d Name:1280x1024 Refresh:68.000000 Width:1280 Height:1024 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13e Name:1280x1024 Refresh:69.000000 Width:1280 Height:1024 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x13f Name:1280x960 Refresh:70.000000 Width:1280 Height:960 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x140 Name:1280x960 Refresh:71.000000 Width:1280 Height:960 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x141 Name:1280x720 Refresh:72.000000 Width:1280 Height:720 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x142 Name:1280x720 Refresh:73.000000 Width:1280 Height:720 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x143 Name:1152x864 Refresh:74.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x144 Name:1152x864 Refresh:75.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x145 Name:1152x864 Refresh:76.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x146 Name:1152x864 Refresh:77.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x147 Name:1152x864 Refresh:78.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x148 Name:1152x864 Refresh:79.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x149 Name:1152x864 Refresh:80.000000 Width:1152 Height:864 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14a Name:1024x768 Refresh:81.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14b Name:1024x768 Refresh:82.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14c Name:1024x768 Refresh:83.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14d Name:1024x768 Refresh:84.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14e Name:1024x768 Refresh:85.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x14f Name:1024x768 Refresh:86.000000 Width:1024 Height:768 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x150 Name:960x720 Refresh:87.000000 Width:960 Height:720 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x151 Name:960x720 Refresh:88.000000 Width:960 Height:720 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x152 Name:960x600 Refresh:89.000000 Width:960 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x153 Name:960x540 Refresh:90.000000 Width:960 Height:540 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x154 Name:928x696 Refresh:91.000000 Width:928 Height:696 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x155 Name:928x696 Refresh:92.000000 Width:928 Height:696 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x156 Name:896x672 Refresh:93.000000 Width:896 Height:672 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x157 Name:896x672 Refresh:94.000000 Width:896 Height:672 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x158 Name:840x525 Refresh:95.000000 Width:840 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x159 Name:840x525 Refresh:96.000000 Width:840 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15a Name:840x525 Refresh:97.000000 Width:840 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15b Name:840x525 Refresh:98.000000 Width:840 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15c Name:840x525 Refresh:99.000000 Width:840 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15d Name:832x624 Refresh:100.000000 Width:832 Height:624 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15e Name:800x600 Refresh:101.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x15f Name:800x600 Refresh:102.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x160 Name:800x600 Refresh:103.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x161 Name:800x600 Refresh:104.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x162 Name:800x600 Refresh:105.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x163 Name:800x600 Refresh:106.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x164 Name:800x600 Refresh:107.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x165 Name:800x600 Refresh:108.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x166 Name:800x600 Refresh:109.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x167 Name:800x600 Refresh:110.000000 Width:800 Height:600 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x168 Name:800x512 Refresh:111.000000 Width:800 Height:512 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x169 Name:720x576 Refresh:112.000000 Width:720 Height:576 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16a Name:720x576 Refresh:113.000000 Width:720 Height:576 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16b Name:720x480 Refresh:114.000000 Width:720 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16c Name:720x480 Refresh:115.000000 Width:720 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16d Name:720x450 Refresh:116.000000 Width:720 Height:450 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16e Name:720x400 Refresh:117.000000 Width:720 Height:400 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x16f Name:700x525 Refresh:118.000000 Width:700 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x170 Name:700x525 Refresh:119.000000 Width:700 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x171 Name:700x525 Refresh:120.000000 Width:700 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x172 Name:700x525 Refresh:121.000000 Width:700 Height:525 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x173 Name:680x384 Refresh:122.000000 Width:680 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x174 Name:680x384 Refresh:123.000000 Width:680 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x175 Name:640x512 Refresh:124.000000 Width:640 Height:512 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x176 Name:640x512 Refresh:125.000000 Width:640 Height:512 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x177 Name:640x512 Refresh:126.000000 Width:640 Height:512 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x178 Name:640x480 Refresh:127.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x179 Name:640x480 Refresh:128.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17a Name:640x480 Refresh:129.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17b Name:640x480 Refresh:130.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17c Name:640x480 Refresh:131.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17d Name:640x480 Refresh:132.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17e Name:640x480 Refresh:133.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x17f Name:640x480 Refresh:134.000000 Width:640 Height:480 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x180 Name:640x400 Refresh:135.000000 Width:640 Height:400 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x181 Name:640x350 Refresh:136.000000 Width:640 Height:350 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x182 Name:576x432 Refresh:137.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x183 Name:576x432 Refresh:138.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x184 Name:576x432 Refresh:139.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x185 Name:576x432 Refresh:140.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x186 Name:576x432 Refresh:141.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x187 Name:576x432 Refresh:142.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x188 Name:576x432 Refresh:143.000000 Width:576 Height:432 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x189 Name:512x384 Refresh:144.000000 Width:512 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18a Name:512x384 Refresh:145.000000 Width:512 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18b Name:512x384 Refresh:146.000000 Width:512 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18c Name:512x384 Refresh:147.000000 Width:512 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18d Name:512x384 Refresh:148.000000 Width:512 Height:384 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18e Name:416x312 Refresh:149.000000 Width:416 Height:312 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x18f Name:400x300 Refresh:150.000000 Width:400 Height:300 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x190 Name:400x300 Refresh:151.000000 Width:400 Height:300 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x191 Name:400x300 Refresh:152.000000 Width:400 Height:300 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x192 Name:400x300 Refresh:153.000000 Width:400 Height:300 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x193 Name:400x300 Refresh:154.000000 Width:400 Height:300 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x194 Name:360x200 Refresh:155.000000 Width:360 Height:200 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x195 Name:320x240 Refresh:156.000000 Width:320 Height:240 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x196 Name:320x240 Refresh:157.000000 Width:320 Height:240 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x197 Name:320x240 Refresh:158.000000 Width:320 Height:240 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x198 Name:320x240 Refresh:159.000000 Width:320 Height:240 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x199 Name:320x200 Refresh:160.000000 Width:320 Height:200 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 INFO: ID:0x19a Name:320x175 Refresh:161.000000 Width:320 Height:175 13:25:40 T:13420352 INFO: Pixel Ratio: 1.000000 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.UPower and method EnumerateDevices 13:25:40 T:13420352 INFO: Selected UPower and ConsoleKit as PowerSyscall 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanStop 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.ConsoleKit on /org/freedesktop/ConsoleKit/Manager with interface org.freedesktop.ConsoleKit.Manager and method CanRestart 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.DBus.Properties and method Get 13:25:40 T:13420352 DEBUG: Previous line repeats 1 times. 13:25:40 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.UPower and method EnumerateDevices 13:25:40 T:13420352 NOTICE: load settings... 13:25:40 T:13420352 DEBUG: SECTION:LoadDLL(libcrystalhd.so.3) 13:25:40 T:13420352 DEBUG: Loading: libcrystalhd.so.3 13:26:02 T:13420352 DEBUG: CrystalHD: device open failed , returning(0xa) 13:26:02 T:13420352 DEBUG: CrystalHD: broadcom crystal hd not found 13:26:02 T:13420352 NOTICE: special://profile/ is mapped to: special://masterprofile/ 13:26:02 T:13420352 NOTICE: loading special://masterprofile/guisettings.xml 13:26:03 T:13420352 NOTICE: Getting hardware information now... 13:26:03 T:13420352 INFO: Using digital output 13:26:03 T:13420352 INFO: AC3 pass through is disabled 13:26:03 T:13420352 INFO: DTS pass through is disabled 13:26:03 T:13420352 INFO: AAC pass through is disabled 13:26:03 T:13420352 INFO: MP1 pass through is disabled 13:26:03 T:13420352 INFO: MP2 pass through is disabled 13:26:03 T:13420352 INFO: MP3 pass through is disabled 13:26:03 T:13420352 NOTICE: Checking resolution 12 13:26:03 T:13420352 NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml. 13:26:03 T:13420352 DEBUG: CPlayerCoreConfig::: created player DVDPlayer for core 1 13:26:03 T:13420352 DEBUG: CPlayerCoreConfig::: created player oldmplayercore for core 1 13:26:03 T:13420352 DEBUG: CPlayerCoreConfig::: created player PAPlayer for core 3 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: system rules 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtv 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: hdhomerun/myth/rtmp/mms/udp 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: lastfm/shout 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: rtsp 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: streams 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: flv/aacp/sdp 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: mp2 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvd 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdfile 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: dvdimage 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: sdp/asf 13:26:03 T:13420352 DEBUG: CPlayerSelectionRule::Initialize: creating rule: nsv 13:26:03 T:13420352 NOTICE: Loaded playercorefactory configuration 13:26:03 T:13420352 NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml. 13:26:03 T:13420352 NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping. 13:26:03 T:13420352 NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml) 13:26:03 T:13420352 NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml) 13:26:03 T:13420352 NOTICE: Default DVD Player: dvdplayer 13:26:03 T:13420352 NOTICE: Default Video Player: dvdplayer 13:26:03 T:13420352 NOTICE: Default Audio Player: paplayer 13:26:03 T:13420352 NOTICE: Disabled debug logging due to GUI setting. Level 1. 13:26:03 T:13420352 NOTICE: Log level changed to 1 13:26:03 T:13420352 NOTICE: Loading media sources from special://masterprofile/sources.xml 13:26:03 T:13420352 INFO: creating subdirectories 13:26:03 T:13420352 INFO: userdata folder: special://masterprofile/ 13:26:03 T:13420352 INFO: recording folder: 13:26:03 T:13420352 INFO: screenshots folder: 13:26:03 T:13420352 INFO: thumbnails folder: special://masterprofile/Thumbnails 13:26:03 T:13420352 INFO: load language info file: special://xbmc/language/English/langinfo.xml 13:26:03 T:13420352 DEBUG: trying to set locale to en_AU.UTF-8 13:26:03 T:13420352 INFO: global locale set to en_AU.UTF-8 13:26:03 T:13420352 INFO: load language file:special://xbmc/language/English/strings.xml 13:26:03 T:13420352 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libcpluff-i486-linux.so) 13:26:03 T:13420352 DEBUG: Loading: /usr/local/lib/xbmc/system/libcpluff-i486-linux.so 13:26:03 T:13420352 INFO: ADDON: cpluff: 'Could not read plug-in directory /usr/local/lib/xbmc/addons: No such file or directory' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.xbmcswift has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.json has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.albums.allmusic.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in repository.xbmc.org has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in webinterface.default has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.icecast has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in pvr.vdr.vnsi has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.black has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.euphoria has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in plugin.video.ted.talks has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.projectm has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.simplejson has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in weather.wunderground has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.dim has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.themoviedb.org has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.artists.allmusic.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.metadata has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in plugin.audio.radio_de has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in skin.confluence has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.glspectrum has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.beautifulsoup has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.pil has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.gui has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.milkdrop has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.dxspectrum has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in pvr.mythtv has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.core has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in pvr.demo has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.solarwinds has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.xbmc.builtin.slideshow has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.htbackdrops.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in pvr.team-mediaportal.tvserver has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.tvdb.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.yahoomusic.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.allmusic.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.last.fm has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.themoviedb.org has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.python has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.imdb.com has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.elementtree has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.waveform has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in screensaver.rsxs.plasma has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in pvr.hts has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in xbmc.addon has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in script.module.pysqlite has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in visualization.itunes has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Plug-in metadata.common.hdtrailers.net has been installed.' 13:26:03 T:13420352 DEBUG: ADDON: cpluff: 'Not all directories were successfully scanned.' 13:26:03 T:13420352 DEBUG: LoadMappings - loaded node "Motorola Nyxboard Hybrid" 13:26:03 T:13420352 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter" 13:26:03 T:13420352 DEBUG: CPeripheralBusUSB - initialised udev monitor: 13 13:26:03 T:13420352 DEBUG: GetMappingForDevice - device (2548:1001) mapped to Pulse-Eight CEC Adapter (type = cec) 13:26:03 T:13420352 DEBUG: SECTION:LoadDLL(libcec.so.1) 13:26:03 T:13420352 DEBUG: Loading: libcec.so.1 13:26:03 T:13420352 DEBUG: InitialiseFeature - using libCEC vUnknown 13:26:03 T:13420352 DEBUG: Initialise - initialised peripheral on '/sys/devices/pci0000:00/0000:00:06.0/usb4/4-4' with 2 features and 0 sub devices 13:26:03 T:13420352 NOTICE: Register - new nic device registered on usb->/sys/devices/pci0000:00/0000:00:06.0/usb4/4-4: Pulse-Eight CEC Adapter (2548:1001) 13:26:03 T:35609456 DEBUG: Thread CEC Adapter start, auto delete: 0 13:26:03 T:3079535472 DEBUG: Thread XBMC Peripherals start, auto delete: 0 13:26:03 T:13420352 INFO: LIRC Initialize: using: /dev/lircd 13:26:03 T:13420352 INFO: LIRC Initialize: connect failed: No such file or directory 13:26:03 T:13420352 DEBUG: Failed to connect to LIRC. Retry in 10s. 13:26:03 T:35609456 DEBUG: CecLogMessage - trying to autodetect the com port for device path '/sys/devices/pci0000:00/0000:00:06.0/usb4/4-4' 13:26:03 T:13420352 DEBUG: OnLostDevice - notify display change event 13:26:03 T:13420352 INFO: XRANDR: /usr/local/lib/xbmc/xbmc-xrandr --output default --mode 0x12c 13:26:03 T:35609456 DEBUG: GetComPort - autodetect com port '/dev/ttyACM0' 13:26:03 T:35609456 DEBUG: OpenConnection - opening a connection to the CEC adapter: /dev/ttyACM0 13:26:03 T:35609456 DEBUG: CecLogMessage - connection opened, clearing any previous input and waiting for active transmissions to end before starting 13:26:03 T:35609456 DEBUG: CecLogMessage - sending ping 13:26:03 T:35609456 DEBUG: CecLogMessage - command sent 13:26:03 T:35609456 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:03 T:35609456 DEBUG: CecLogMessage - requesting the firmware version 13:26:03 T:35609456 DEBUG: CecLogMessage - command sent 13:26:03 T:13420352 NOTICE: Using visual 0x42 13:26:03 T:13420352 INFO: GL: Maximum texture width: 8192 13:26:03 T:13420352 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/ImageLib-i486-linux.so) 13:26:03 T:13420352 DEBUG: Loading: /usr/local/lib/xbmc/system/ImageLib-i486-linux.so 13:26:03 T:13420352 DEBUG: GLX_EXTENSIONS: GLX_EXT_visual_info GLX_EXT_visual_rating GLX_SGIX_fbconfig GLX_SGIX_pbuffer GLX_SGI_video_sync GLX_SGI_swap_control GLX_EXT_swap_control GLX_EXT_texture_from_pixmap GLX_ARB_create_context GLX_ARB_create_context_profile GLX_EXT_create_context_es2_profile GLX_ARB_create_context_robustness GLX_ARB_multisample GLX_NV_float_buffer GLX_ARB_fbconfig_float GLX_EXT_framebuffer_sRGB GLX_ARB_get_proc_address 13:26:03 T:13420352 NOTICE: GL_VENDOR = NVIDIA Corporation 13:26:03 T:13420352 NOTICE: GL_RENDERER = ION/PCI/SSE2 13:26:03 T:13420352 NOTICE: GL_VERSION = 3.3.0 NVIDIA 270.41.06 13:26:03 T:13420352 NOTICE: GL_SHADING_LANGUAGE_VERSION = 3.30 NVIDIA via Cg compiler 13:26:03 T:13420352 NOTICE: GL_GPU_MEMORY_INFO_TOTAL_AVAILABLE_MEMORY_NVX = 524288 13:26:03 T:13420352 NOTICE: GL_GPU_MEMORY_INFO_DEDICATED_VIDMEM_NVX = 262144 13:26:03 T:13420352 NOTICE: GL_EXTENSIONS = GL_ARB_blend_func_extended GL_ARB_color_buffer_float GL_ARB_compatibility GL_ARB_copy_buffer GL_ARB_depth_buffer_float GL_ARB_depth_clamp GL_ARB_depth_texture GL_ARB_draw_buffers GL_ARB_draw_elements_base_vertex GL_ARB_draw_instanced GL_ARB_ES2_compatibility GL_ARB_explicit_attrib_location GL_ARB_fragment_coord_conventions GL_ARB_fragment_program GL_ARB_fragment_program_shadow GL_ARB_fragment_shader GL_ARB_framebuffer_object GL_ARB_framebuffer_sRGB GL_ARB_geometry_shader4 GL_ARB_get_program_binary GL_ARB_half_float_pixel GL_ARB_half_float_vertex GL_ARB_imaging GL_ARB_instanced_arrays GL_ARB_map_buffer_range GL_ARB_multisample GL_ARB_multitexture GL_ARB_occlusion_query GL_ARB_occlusion_query2 GL_ARB_pixel_buffer_object GL_ARB_point_parameters GL_ARB_point_sprite GL_ARB_provoking_vertex GL_ARB_robustness GL_ARB_sampler_objects GL_ARB_seamless_cube_map GL_ARB_separate_shader_objects GL_ARB_shader_bit_encoding GL_ARB_shader_objects GL_ARB_shading_language_100 GL_ARB_shading_language_include GL_ARB_shadow GL_ARB_sync GL_ARB_texture_border_clamp GL_ARB_texture_buffer_object GL_ARB_texture_compression GL_ARB_texture_compression_rgtc GL_ARB_texture_cube_map GL_ARB_texture_env_add GL_ARB_texture_env_combine GL_ARB_texture_env_crossbar GL_ARB_texture_env_dot3 GL_ARB_texture_float GL_ARB_texture_mirrored_repeat GL_ARB_texture_multisample GL_ARB_texture_non_power_of_two GL_ARB_texture_rectangle GL_ARB_texture_rg GL_ARB_texture_rgb10_a2ui GL_ARB_texture_swizzle GL_ARB_timer_query GL_ARB_transform_feedback2 GL_ARB_transpose_matrix GL_ARB_uniform_buffer_object GL_ARB_vertex_array_bgra GL_ARB_vertex_array_object GL_ARB_vertex_buffer_object GL_ARB_vertex_program GL_ARB_vertex_shader GL_ARB_vertex_type_2_10_10_10_rev GL_ARB_viewport_array GL_ARB_window_pos GL_ATI_draw_buffers GL_ATI_texture_float GL_ATI_texture_mirror_once GL_S3_s3tc GL_EXT_texture_env_add GL_EXT_abgr GL_EXT_bgra GL_EXT_bindable_uniform GL_EXT_blend_color GL_EXT_blend_equation_separate GL_EXT_blend_func_separate GL_EXT_blend_minmax GL_EXT_blend_subtract GL_EXT_compiled_vertex_array GL_EXT_Cg_shader GL_EXT_depth_bounds_test GL_EXT_direct_state_access GL_EXT_draw_buffers2 GL_EXT_draw_instanced GL_EXT_draw_range_elements GL_EXT_fog_coord GL_EXT_framebuffer_blit GL_EXT_framebuffer_multisample GL_EXTX_framebuffer_mixed_formats GL_EXT_framebuffer_object GL_EXT_framebuffer_sRGB GL_EXT_geometry_shader4 GL_EXT_gpu_program_parameters GL_EXT_gpu_shader4 GL_EXT_multi_draw_arrays GL_EXT_packed_depth_stencil GL_EXT_packed_float GL_EXT_packed_pixels GL_EXT_pixel_buffer_object GL_EXT_point_parameters GL_EXT_provoking_vertex GL_EXT_rescale_normal GL_EXT_secondary_color GL_EXT_separate_shader_objects GL_EXT_separate_specular_color GL_EXT_shadow_funcs GL_EXT_stencil_two_side GL_EXT_stencil_wrap GL_EXT_texture3D GL_EXT_texture_array GL_EXT_texture_buffer_object GL_EXT_texture_compression_dxt1 GL_EXT_texture_compression_latc GL_EXT_texture_compression_rgtc GL_EXT_texture_compression_s3tc GL_EXT_texture_cube_map GL_EXT_texture_edge_clamp GL_EXT_texture_env_combine GL_EXT_texture_env_dot3 GL_EXT_texture_filter_anisotropic GL_EXT_texture_format_BGRA8888 GL_EXT_texture_integer GL_EXT_texture_lod GL_EXT_texture_lod_bias GL_EXT_texture_mirror_clamp GL_EXT_texture_object GL_EXT_texture_shared_exponent GL_EXT_texture_sRGB GL_EXT_texture_swizzle GL_EXT_texture_type_2_10_10_10_REV GL_EXT_timer_query GL_EXT_transform_feedback2 GL_EXT_vertex_array GL_EXT_vertex_array_bgra GL_IBM_rasterpos_clip GL_IBM_texture_mirrored_repeat GL_KTX_buffer_region GL_NV_alpha_test GL_NV_blend_minmax GL_NV_blend_square GL_NV_complex_primitives GL_NV_conditional_render GL_NV_copy_depth_to_color GL_NV_copy_image GL_NV_depth_buffer_float GL_NV_depth_clamp GL_NV_explicit_multisample GL_NV_fbo_color_attachments GL_NV_fence GL_NV_float_buffer GL_NV_fog_distance GL_NV_fragdepth GL_NV_fragment_program GL_NV_fragment_program_option GL_NV_fragment_program2 GL_NV_framebuffer_multisample_coverage GL_NV_geometry_shader4 GL_NV_gpu_program4 GL_NV_half_float GL_NV_light_max_exponent GL_NV_multisample_coverage GL_NV_multisample_filter_hint GL_NV_occlusion_query GL_NV_packed_depth_stencil GL_NV_parameter_buffer_object GL_NV_parameter_buffer_object2 GL_NV_pixel_data_range GL_NV_point_sprite GL_NV_primitive_restart GL_NV_register_combiners GL_NV_register_combiners2 GL_NV_shader_buffer_load GL_NV_texgen_reflection GL_NV_texture_barrier GL_NV_texture_compression_vtc GL_NV_texture_env_combine4 GL_NV_texture_expand_normal GL_NV_texture_lod_clamp GL_NV_texture_multisample GL_NV_texture_rectangle GL_NV_texture_shader GL_NV_texture_shader2 GL_NV_texture_shader3 GL_NV_transform_feedback GL_NV_transform_feedback2 GL_NV_vdpau_interop GL_NV_vertex_array_range GL_NV_vertex_array_range2 GL_NV_vertex_buffer_unified_memory GL_NV_vertex_program GL_NV_vertex_program1_1 GL_NV_vertex_program2 GL_NV_vertex_program2_option GL_NV_vertex_program3 GL_NVX_conditional_render GL_NVX_gpu_memory_info GL_OES_depth24 GL_OES_depth32 GL_OES_depth_texture GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_get_program_binary GL_OES_mapbuffer GL_OES_packed_depth_stencil GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_array_object GL_OES_vertex_half_float GL_SGIS_generate_mipmap GL_SGIS_texture_lod GL_SGIX_depth_texture GL_SGIX_shadow GL_SUN_slice_accum 13:26:03 T:13420352 DEBUG: OnLostDevice - notify display change event 13:26:03 T:13420352 ERROR: GLX: Same window as before, refreshing context 13:26:03 T:13420352 INFO: GL: Maximum texture width: 8192 13:26:03 T:13420352 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec 13:26:03 T:13420352 INFO: load default splash image: /usr/local/share/xbmc/media/Splash.png 13:26:03 T:35609456 DEBUG: CecLogMessage - firmware version 2 13:26:03 T:35609456 DEBUG: CecLogMessage - turning controlled mode on 13:26:03 T:35609456 DEBUG: CecLogMessage - command sent 13:26:03 T:35609456 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:03 T:35609456 DEBUG: CecLogMessage - communication thread started 13:26:03 T:35609456 DEBUG: CecLogMessage - connected to the CEC adapter. firmware version = 2, client version = 1.5.1 13:26:03 T:35609456 DEBUG: CecLogMessage - requesting device type setting 13:26:03 T:35609456 DEBUG: CecLogMessage - command sent 13:26:03 T:13420352 DEBUG: CheckDisplayEvents: Received RandR event 123 13:26:03 T:13420352 DEBUG: CheckDisplayEvents - notify display reset event 13:26:03 T:13420352 INFO: load keymapping 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/appcommand.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/gamepad.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Alienware.Dual.Compatible.Controller.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.AppleRemote.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Harmony.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Interact.AxisPad.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Logitech.RumblePad.2.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.360.Controller.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Microsoft.Xbox.Controller.S.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.PS3.Remote.Keyboard.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.Sony.PLAYSTATION(R)3.Controller.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/joystick.WiiRemote.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/keyboard.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/mouse.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/keymaps/remote.xml 13:26:03 T:13420352 INFO: Loading special://xbmc/system/Lircmap.xml 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'mceusb' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'XboxDVDDongle' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'Microsoft_Xbox' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'PinnacleSysPCTVRemote' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'anysee' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'iMON-PAD' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'Antec_Veris_RM200' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'MCE_via_iMON' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'TwinHanRemote' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'linux-input-layer' 13:26:03 T:13420352 INFO: * Linking remote mapping for 'linux-input-layer' to 'cx23885_remote' 13:26:03 T:13420352 INFO: * Linking remote mapping for 'linux-input-layer' to 'devinput' 13:26:03 T:13420352 INFO: * Adding remote mapping for device 'mediacenter' 13:26:03 T:13420352 DEBUG: CButtonTranslator::Load - no userdata Lircmap.xml found, skipping 13:26:03 T:13420352 INFO: GUI format 1920x1080 1920x1080 @ 51.00 - Full Screen 13:26:03 T:13420352 DEBUG: guilib: Fill viewport always for solving rendering passes 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks with interface org.freedesktop.UDisks and method EnumerateDevices 13:26:03 T:13420352 DEBUG: Selected UDisks as storage provider 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks with interface org.freedesktop.DBus.Properties and method Get 13:26:03 T:13420352 DEBUG: UDisks: DaemonVersion 1 13:26:03 T:13420352 DEBUG: UDisks: Querying available devices 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks with interface org.freedesktop.UDisks and method EnumerateDevices 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded (/org/freedesktop/UDisks/devices/sda) 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda with interface org.freedesktop.DBus.Properties and method GetAll 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded - DeviceUDI /org/freedesktop/UDisks/devices/sda: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition false IsOptical false 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded (/org/freedesktop/UDisks/devices/sda1) 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda1 with interface org.freedesktop.DBus.Properties and method GetAll 13:26:03 T:35609456 DEBUG: CecLogMessage - using persisted device type setting playback device 13:26:03 T:35609456 DEBUG: CecLogMessage - requesting physical address setting 13:26:03 T:35609456 DEBUG: CecLogMessage - command sent 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda with interface org.freedesktop.DBus.Properties and method Get 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded - DeviceUDI /org/freedesktop/UDisks/devices/sda1: IsFileSystem true HasFileSystem ext4 IsSystemInternal true IsMounted true IsRemovable false IsPartition true IsOptical false 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded (/org/freedesktop/UDisks/devices/sda2) 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda2 with interface org.freedesktop.DBus.Properties and method GetAll 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda with interface org.freedesktop.DBus.Properties and method Get 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded - DeviceUDI /org/freedesktop/UDisks/devices/sda2: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition true IsOptical false 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded (/org/freedesktop/UDisks/devices/sda5) 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda5 with interface org.freedesktop.DBus.Properties and method GetAll 13:26:03 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UDisks on /org/freedesktop/UDisks/devices/sda with interface org.freedesktop.DBus.Properties and method Get 13:26:03 T:13420352 DEBUG: UDisks: DeviceAdded - DeviceUDI /org/freedesktop/UDisks/devices/sda5: IsFileSystem false HasFileSystem IsSystemInternal true IsMounted false IsRemovable false IsPartition true IsOptical false 13:26:03 T:13420352 DEBUG: SECTION:LoadDLL(libcurl-gnutls.so.4) 13:26:03 T:13420352 DEBUG: Loading: libcurl-gnutls.so.4 13:26:03 T:13420352 NOTICE: start dvd mediatype detection 13:26:03 T:13420352 NOTICE: initializing playlistplayer 13:26:03 T:13420352 NOTICE: DONE initializing playlistplayer 13:26:03 T:37415792 DEBUG: Thread CDetectDVDMedia start, auto delete: 0 13:26:03 T:37415792 DEBUG: Compiled with libcdio Version 0.81 13:26:03 T:13420352 DEBUG: DPMS: supported power-saving modes: SUSPEND OFF STANDBY 13:26:03 T:37415792 DEBUG: Thread CDetectDVDMedia 37415792 terminating 13:26:03 T:13420352 INFO: Unloading old skin ... 13:26:03 T:13420352 INFO: load skin from:/usr/local/share/xbmc/addons/skin.confluence 13:26:03 T:13420352 INFO: load fonts for skin... 13:26:03 T:13420352 INFO: Loading fonts from /usr/local/share/xbmc/addons/skin.confluence/720p/Font.xml 13:26:03 T:13420352 INFO: Loading skin includes from /usr/local/share/xbmc/addons/skin.confluence/720p/includes.xml 13:26:04 T:35609456 DEBUG: CecLogMessage - using persisted physical address setting 0 13:26:04 T:35609456 DEBUG: CecLogMessage - requesting OSD name setting 13:26:04 T:35609456 DEBUG: CecLogMessage - command sent 13:26:04 T:13420352 INFO: load new skin... 13:26:04 T:13420352 INFO: Loading skin file: Home.xml 13:26:04 T:13420352 DEBUG: Load Home.xml: 89.59ms 13:26:04 T:13420352 INFO: Loading user windows, path /usr/local/share/xbmc/addons/skin.confluence/720p 13:26:04 T:13420352 DEBUG: Load Skin XML: 115.04ms 13:26:04 T:13420352 INFO: initialize new skin... 13:26:04 T:13420352 DEBUG: guilib: Fill viewport always for solving rendering passes 13:26:04 T:13420352 INFO: Loading skin file: Pointer.xml 13:26:04 T:13420352 DEBUG: Load Pointer.xml: 3.06ms 13:26:04 T:13420352 INFO: Loading skin file: DialogVolumeBar.xml 13:26:04 T:13420352 DEBUG: Load DialogVolumeBar.xml: 4.24ms 13:26:04 T:13420352 INFO: Loading skin file: DialogKaiToast.xml 13:26:04 T:13420352 DEBUG: Load DialogKaiToast.xml: 4.33ms 13:26:04 T:13420352 INFO: Loading skin file: DialogMuteBug.xml 13:26:04 T:13420352 DEBUG: Load DialogMuteBug.xml: 2.86ms 13:26:04 T:13420352 INFO: Loading skin file: DialogSeekBar.xml 13:26:04 T:13420352 DEBUG: Load DialogSeekBar.xml: 12.40ms 13:26:04 T:13420352 INFO: Loading skin file: DialogBusy.xml 13:26:04 T:13420352 DEBUG: Load DialogBusy.xml: 3.12ms 13:26:04 T:13420352 DEBUG: CGUIAudioManager::Initialize 13:26:04 T:13420352 INFO: Loading /usr/local/share/xbmc/addons/skin.confluence/sounds/sounds.xml 13:26:04 T:13420352 INFO: skin loaded... 13:26:04 T:13420352 INFO: JSONRPC: Sucessfully initialized 13:26:04 T:13420352 DEBUG: ADDON: Starting service addons. 13:26:04 T:13420352 DEBUG: Activating window ID: 12999 13:26:04 T:13420352 DEBUG: ------ Window Init (Startup.xml) ------ 13:26:04 T:13420352 INFO: Loading skin file: Startup.xml 13:26:04 T:13420352 DEBUG: Load Startup.xml: 3.65ms 13:26:04 T:13420352 DEBUG: Alloc resources: 3.78ms (3.78 ms skin load) 13:26:04 T:13420352 INFO: removing tempfiles 13:26:04 T:13420352 DEBUG: ADDON: Starting service addons. 13:26:04 T:13420352 NOTICE: initialize done 13:26:04 T:13420352 NOTICE: Running the application... 13:26:04 T:13420352 DEBUG: ExecuteXBMCAction : Translating ReplaceWindow(Home) 13:26:04 T:13420352 DEBUG: ExecuteXBMCAction : To ReplaceWindow(Home) 13:26:04 T:13420352 DEBUG: Activating window ID: 10000 13:26:04 T:13420352 DEBUG: OpenBundle - Opened bundle /usr/local/share/xbmc/addons/skin.confluence/media/Textures.xbt 13:26:04 T:3037723504 DEBUG: Thread Jobworker start, auto delete: 1 13:26:04 T:13420352 DEBUG: ------ Window Init (Pointer.xml) ------ 13:26:04 T:13420352 DEBUG: Alloc resources: 0.54ms (0.01 ms skin load) 13:26:04 T:13420352 INFO: GL: Enabling VSYNC 13:26:04 T:13420352 INFO: GL: Selected vsync mode 2 13:26:04 T:13420352 DEBUG: ------ Window Deinit (Startup.xml) ------ 13:26:04 T:13420352 DEBUG: ------ Window Init (Home.xml) ------ 13:26:04 T:13420352 DEBUG: Alloc resources: 3.70ms (0.01 ms skin load) 13:26:04 T:2955930480 DEBUG: Thread Jobworker start, auto delete: 1 13:26:04 T:2955930480 DEBUG: Previous line repeats 1 times. 13:26:04 T:2955930480 DEBUG: CRecentlyAddedJob::UpdateMusic() - Running RecentlyAdded home screen update 13:26:04 T:13420352 DEBUG: Process - no profile autoexec.py (/home/mark/.xbmc/userdata/autoexec.py) found, skipping 13:26:04 T:13420352 DEBUG: NetworkMessage - Starting network services 13:26:04 T:13420352 NOTICE: ES: Starting event server 13:26:04 T:2939145072 DEBUG: Thread CEventServer start, auto delete: 0 13:26:04 T:2939145072 NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777 13:26:04 T:13420352 INFO: JSONRPC Server: Successfully initialized 13:26:04 T:2939145072 NOTICE: UDP: Listening on port 9777 13:26:04 T:2930752368 DEBUG: Thread JSONRPC::CTCPServer start, auto delete: 0 13:26:04 T:2947537776 INFO: WEATHER: Downloading weather 13:26:04 T:2947537776 INFO: initializing python engine. 13:26:04 T:2955930480 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 13:26:04 T:2955930480 DEBUG: GetRecentlyAddedAlbums query: select * from albumview order by idAlbum desc limit 10 13:26:04 T:2939145072 DEBUG: CZeroconfAvahi::clientCallback: client is up and running 13:26:04 T:2955930480 DEBUG: CRecentlyAddedJob::UpdateVideos() - Running RecentlyAdded home screen update 13:26:04 T:13420352 NOTICE: starting zeroconf publishing 13:26:04 T:13420352 DEBUG: CLastfmScrobbler: Clearing session. 13:26:04 T:13420352 DEBUG: CLibrefmScrobbler: Clearing session. 13:26:04 T:2913966960 DEBUG: Thread Jobworker start, auto delete: 1 13:26:04 T:2955930480 DEBUG: RunQuery took 2 ms for 0 items query: select * from movieview order by idMovie desc limit 10 13:26:04 T:2955930480 DEBUG: RunQuery took 2 ms for 0 items query: select * from episodeview order by idEpisode desc limit 10 13:26:04 T:2955930480 DEBUG: GetMusicVideosByWhere query = select * from musicvideoview order by idMVideo desc limit 10 13:26:04 T:2955930480 DEBUG: GetMusicVideosByWhere time for actual SQL query = 2 13:26:04 T:2955930480 DEBUG: CRecentlyAddedJob::UpdateTotal() - Running RecentlyAdded home screen update 13:26:04 T:2947537776 DEBUG: new python thread created. id=1 13:26:04 T:2905574256 DEBUG: Thread XBPyThread start, auto delete: 0 13:26:04 T:2905574256 DEBUG: Python thread: start processing 13:26:04 T:2905574256 NOTICE: -->Python Interpreter Initialized<-- 13:26:04 T:2905574256 DEBUG: Process - The source file to load is /home/mark/.xbmc/addons/weather.wunderground/default.py 13:26:04 T:2905574256 DEBUG: Process - Setting the Python path to /home/mark/.xbmc/addons/weather.wunderground:/home/mark/.xbmc/addons/script.module.xbmcswift/lib:/usr/local/share/xbmc/addons/script.module.simplejson/lib:/home/mark/.xbmc/addons/script.module.beautifulsoup/lib:/usr/local/share/xbmc/addons/script.module.pil/lib:/home/mark/.xbmc/addons/script.module.elementtree/lib:/usr/local/share/xbmc/addons/script.module.pysqlite/lib:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PIL:/usr/lib/pymodules/python2.7/gtk-2.0:/usr/lib/python2.7/dist-packages/gst-0.10:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/pymodules/python2.7:/usr/lib/pymodules/python2.7/ubuntuone-client:/usr/lib/pymodules/python2.7/ubuntuone-control-panel:/usr/lib/pymodules/python2.7/ubuntuone-storage-protocol: 13:26:04 T:2905574256 DEBUG: Process - Entering source directory /home/mark/.xbmc/addons/weather.wunderground 13:26:04 T:2905574256 DEBUG: Instantiating addon using automatically obtained id of "weather.wunderground" dependent on version 2.0 of the xbmc.python api 13:26:04 T:3037723504 DEBUG: DoWork - took 251 ms to load special://skin/backgrounds/videos.jpg 13:26:04 T:3037723504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.eventserver type: _xbmc-events._udp name:XBMC (mark-ion) port:9777 13:26:04 T:3037723504 DEBUG: CZeroconfAvahi::addService() named: XBMC (mark-ion) type: _xbmc-events._udp port:9777 13:26:04 T:3037723504 DEBUG: CZeroconfAvahi::doPublishService identifier: servers.jsonrpc-tpc type: _xbmc-jsonrpc._tcp name:XBMC (mark-ion) port:9090 13:26:04 T:3037723504 DEBUG: CZeroconfAvahi::addService() named: XBMC (mark-ion) type: _xbmc-jsonrpc._tcp port:9090 13:26:04 T:2913966960 DEBUG: DoWork - took 286 ms to load special://skin/backgrounds/videos.jpg 13:26:04 T:13420352 DEBUG: UPower: Recieved an unknown signal NameAcquired 13:26:05 T:2922359664 DEBUG: CZeroconfAvahi::groupCallback: Service successfully established 13:26:06 T:35609456 DEBUG: Previous line repeats 1 times. 13:26:06 T:35609456 DEBUG: CecLogMessage - using persisted device name setting CXBMC 13:26:06 T:2893491056 DEBUG: CecLogMessage - processor thread started 13:26:06 T:35609456 DEBUG: CecLogMessage - FindLogicalAddresses - device 0: type 4 13:26:06 T:35609456 DEBUG: CecLogMessage - detecting logical address for type 'playback device' 13:26:06 T:35609456 DEBUG: CecLogMessage - trying logical address 'Playback 1' 13:26:06 T:35609456 DEBUG: CecLogMessage - setting ackmask to 0 13:26:06 T:35609456 DEBUG: CecLogMessage - command sent 13:26:06 T:35609456 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:06 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> Playback 1 (4): POLL 13:26:06 T:35609456 DEBUG: CecLogMessage - << 44 13:26:06 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:06 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_FAILED_ACK 13:26:06 T:3067083632 DEBUG: CecLogMessage - did not receive ack 13:26:06 T:35609456 DEBUG: CecLogMessage - command was not acked 13:26:06 T:35609456 DEBUG: CecLogMessage - << 44 13:26:06 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:06 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:06 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_FAILED_ACK 13:26:06 T:3067083632 DEBUG: CecLogMessage - did not receive ack 13:26:06 T:35609456 DEBUG: CecLogMessage - command was not acked 13:26:07 T:35609456 DEBUG: CecLogMessage - >> POLL not sent 13:26:07 T:35609456 DEBUG: CecLogMessage - using logical address 'Playback 1' 13:26:07 T:35609456 DEBUG: CecLogMessage - device status of Playback 1 changed into 'handled by libCEC' 13:26:07 T:35609456 DEBUG: CecLogMessage - setting ackmask to 10 13:26:07 T:35609456 DEBUG: CecLogMessage - command sent 13:26:07 T:35609456 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): POLL 13:26:07 T:35609456 DEBUG: CecLogMessage - << 40 13:26:07 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:07 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:07 T:35609456 DEBUG: CecLogMessage - >> POLL sent 13:26:07 T:35609456 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0) 13:26:07 T:35609456 DEBUG: CecLogMessage - << 40:8c 13:26:07 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:07 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:07 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:f ack:high 13:26:07 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 87 13:26:07 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:07 T:3067083632 DEBUG: Previous line repeats 1 times. 13:26:07 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 39 eom 13:26:07 T:3058690928 DEBUG: CecLogMessage - >> 0f:87:00:00:39 13:26:07 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 13:26:07 T:3058690928 DEBUG: CecLogMessage - TV (0): vendor = Toshiba (000039) 13:26:07 T:35609456 DEBUG: CecLogMessage - expected response received (87: device vendor id) 13:26:07 T:35609456 DEBUG: CecLogMessage - setting HDMI port to 1 on device TV (0) 13:26:07 T:35609456 DEBUG: CecLogMessage - setting physical address to '1000' 13:26:07 T:35609456 DEBUG: CecLogMessage - marking Playback 1 (4) as inactive source 13:26:07 T:35609456 DEBUG: CecLogMessage - >> Playback 1 (4): power status changed from 'in transition from standby to on' to 'standby' 13:26:07 T:35609456 DEBUG: CecLogMessage - >> Playback 1 (4): physical address changed from ffff to 1000 13:26:07 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): physical adddress 1000 13:26:07 T:35609456 DEBUG: CecLogMessage - << 4f:84:10:00:04 13:26:07 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 5 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:07 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:07 T:35609456 WARNING: CecLogMessage - deprecated method SetActiveView called 13:26:07 T:35609456 DEBUG: CecLogMessage - making Playback 1 (4) the active source 13:26:07 T:35609456 DEBUG: CecLogMessage - >> Playback 1 (4): power status changed from 'standby' to 'on' 13:26:07 T:35609456 DEBUG: CecLogMessage - << 40:04 13:26:07 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:2905574256 INFO: Scriptresult: Success 13:26:07 T:2905574256 INFO: Python script stopped 13:26:07 T:2905574256 DEBUG: Thread XBPyThread 2905574256 terminating 13:26:07 T:2947537776 DEBUG: GetZipList - Processing zip://special%3a%2f%2fxbmc%2fmedia%2fweather%2ezip/ 13:26:07 T:13420352 DEBUG: waiting for python thread 1 to stop 13:26:07 T:13420352 DEBUG: python thread 1 destructed 13:26:07 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:07 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:07 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (1000) 13:26:07 T:35609456 DEBUG: CecLogMessage - << 4f:82:10:00 13:26:07 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:07 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:8e:00 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:04 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (1000) 13:26:08 T:35609456 DEBUG: CecLogMessage - << 4f:82:10:00 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:8e:00 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - processor thread started 13:26:08 T:35609456 DEBUG: OpenConnection - connection to the CEC adapter opened 13:26:08 T:35609456 DEBUG: CecLogMessage - << requesting power status of 'TV' (0) 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:8f 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 8e 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:08 T:3058690928 DEBUG: CecLogMessage - >> 04:00:8e:00 13:26:08 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0) 13:26:08 T:3058690928 DEBUG: CecLogMessage - marking opcode 'menu status' as unsupported feature for device 'TV' 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 90 13:26:08 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:08 T:3058690928 DEBUG: CecLogMessage - >> 04:90:00 13:26:08 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): report power status (90) 13:26:08 T:3058690928 DEBUG: CecLogMessage - >> TV (0): power status changed from 'unknown' to 'on' 13:26:08 T:35609456 DEBUG: CecLogMessage - expected response received (90: report power status) 13:26:08 T:35609456 DEBUG: CecLogMessage - 'TV' (0) is already 'on' 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:04 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (1000) 13:26:08 T:35609456 DEBUG: CecLogMessage - << 4f:82:10:00 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:35609456 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 13:26:08 T:35609456 DEBUG: CecLogMessage - << 40:8e:00 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:08 T:35609456 DEBUG: CecLogMessage - command transmitted 13:26:08 T:2905574256 DEBUG: Thread CEC Adapter Update Thread start, auto delete: 0 13:26:08 T:2905574256 DEBUG: CecLogMessage - 'TV' (0) is already 'on' 13:26:08 T:2905574256 DEBUG: CecLogMessage - << 40:04 13:26:08 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:08 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:09 T:2905574256 DEBUG: CecLogMessage - command transmitted 13:26:09 T:2905574256 DEBUG: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (1000) 13:26:09 T:2905574256 DEBUG: CecLogMessage - << 4f:82:10:00 13:26:09 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:09 T:2905574256 DEBUG: CecLogMessage - command transmitted 13:26:09 T:2905574256 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 13:26:09 T:2905574256 DEBUG: CecLogMessage - << 40:8e:00 13:26:09 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:09 T:2905574256 DEBUG: CecLogMessage - command transmitted 13:26:09 T:2905574256 DEBUG: CecLogMessage - << requesting menu language of 'TV' (0) 13:26:09 T:2905574256 DEBUG: CecLogMessage - << 40:91 13:26:09 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:09 T:2905574256 DEBUG: CecLogMessage - command transmitted 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:8 destination:0 ack:low eom 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> 80 13:26:09 T:3058690928 DEBUG: CecLogMessage - << POLL: Playback 2 (8) -> Playback 2 (8) 13:26:09 T:3058690928 DEBUG: CecLogMessage - device Playback 2 (8) status changed to present after command feature abort 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:09 T:13420352 DEBUG: ------ Window Deinit (Pointer.xml) ------ 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA a0 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:09 T:3067083632 DEBUG: Previous line repeats 1 times. 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 39 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> 04:a0:00:00:39:02 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 13:26:09 T:3058690928 DEBUG: CecLogMessage - << transmitting abort message 13:26:09 T:3058690928 DEBUG: CecLogMessage - << 40:00:a0:04 13:26:09 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:09 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 8e 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> 04:00:8e:00 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0) 13:26:09 T:3058690928 DEBUG: CecLogMessage - marking opcode 'menu status' as unsupported feature for device 'TV' 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:8 ack:low 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA a0 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:09 T:3067083632 DEBUG: Previous line repeats 1 times. 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 39 13:26:09 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> 08:a0:00:00:39:02 13:26:09 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): vendor command with id (A0) 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:8 destination:0 ack:low 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA a0 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 04 eom 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> 80:00:a0:04 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> Playback 2 (8) -> TV (0): feature abort ( 0) 13:26:10 T:3058690928 DEBUG: CecLogMessage - marking opcode 'vendor command with id' as unsupported feature for device 'Playback 2' 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 8e 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> 04:00:8e:00 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0) 13:26:10 T:3058690928 DEBUG: CecLogMessage - marking opcode 'menu status' as unsupported feature for device 'TV' 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 8e 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> 04:00:8e:00 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0) 13:26:10 T:3058690928 DEBUG: CecLogMessage - marking opcode 'menu status' as unsupported feature for device 'TV' 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:f ack:high 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 32 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 65 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 6e 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 67 eom 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> 0f:32:65:6e:67 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set menu language (32) 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> TV (0): menu language set to 'eng' 13:26:10 T:2905574256 DEBUG: CecLogMessage - expected response received (32: set menu language) 13:26:10 T:2905574256 DEBUG: SetMenuLanguage - language set to 'English' 13:26:10 T:2905574256 DEBUG: CecLogMessage - << Playback 1 (4) -> Audio (5): POLL 13:26:10 T:2905574256 DEBUG: CecLogMessage - << 45 13:26:10 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:10 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:10 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:10 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:10 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:10 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:10 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_FAILED_ACK 13:26:10 T:3067083632 DEBUG: CecLogMessage - did not receive ack 13:26:10 T:2905574256 DEBUG: CecLogMessage - command was not acked 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:10 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 46 eom 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> 04:46 13:26:10 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): give osd name (46) 13:26:10 T:3058690928 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): OSD name 'CXBMC' 13:26:11 T:2905574256 DEBUG: CecLogMessage - << 45 13:26:11 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:11 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:11 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:11 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_FAILED_ACK 13:26:11 T:3067083632 DEBUG: CecLogMessage - did not receive ack 13:26:11 T:2905574256 DEBUG: CecLogMessage - command was not acked 13:26:11 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:8 ack:low 13:26:11 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 46 eom 13:26:11 T:2905574256 DEBUG: CecLogMessage - >> POLL not sent 13:26:11 T:3058690928 DEBUG: CecLogMessage - << 40:47:43:58:42:4d:43 13:26:11 T:2905574256 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): display OSD message 'Connected' 13:26:11 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:11 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:11 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 7 more 13:26:11 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 6 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 5 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:12 T:3058690928 DEBUG: CecLogMessage - command transmitted 13:26:12 T:2905574256 DEBUG: CecLogMessage - << 40:64:00:43:6f:6e:6e:65:63:74:65:64 13:26:12 T:3058690928 DEBUG: CecLogMessage - >> 08:46 13:26:12 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): give osd name (46) 13:26:12 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=8 opcode=46 13:26:12 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:12 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 12 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 11 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 10 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 9 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 8 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 7 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 6 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 5 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:12 T:3067083632 DEBUG: CecLogMessage - TRANSMIT_SUCCEEDED 13:26:12 T:2905574256 DEBUG: CecLogMessage - command transmitted 13:26:12 T:13420352 DEBUG: ------ Window Init (DialogKaiToast.xml) ------ 13:26:12 T:13420352 DEBUG: Alloc resources: 0.01ms (0.01 ms skin load) 13:26:12 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:12 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 13:26:12 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 64 13:26:12 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:12 T:3058690928 DEBUG: CecLogMessage - >> 04:00:64:00 13:26:12 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): feature abort ( 0) 13:26:12 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=00 13:26:12 T:3058690928 DEBUG: CecLogMessage - marking opcode 'set osd string' as unsupported feature for device 'TV' 13:26:13 T:13420352 INFO: LIRC Initialize: using: /dev/lircd 13:26:13 T:13420352 DEBUG: Failed to connect to LIRC. Retry in 20s. 13:26:14 T:13420352 DEBUG: SDLKeyboard: scancode: 74, sym: 0112, unicode: 0000, modifier: 0 13:26:14 T:13420352 DEBUG: OnKey: down (f081) pressed, action is Down 13:26:17 T:13420352 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------ 13:26:17 T:13420352 INFO: Python, unloading python shared library because no scripts are running anymore 13:26:17 T:13420352 DEBUG: UnloadExtensionLibs, clearing python extension libraries 13:26:18 T:3067083632 DEBUG: CecLogMessage - sending ping 13:26:18 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:18 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:21 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:21 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:21 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:21 T:3058690928 DEBUG: CecLogMessage - >> 04:44:02 13:26:21 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:21 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:21 T:3058690928 DEBUG: CecLogMessage - key pressed: 2 13:26:21 T:13420352 DEBUG: GetNextKey - received key 2 13:26:21 T:13420352 DEBUG: OnKey: 167 (a7) pressed, action is Down 13:26:21 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:21 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:21 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:21 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:21 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:21 T:3058690928 DEBUG: CecLogMessage - key released: 2 13:26:22 T:13420352 DEBUG: GetNextKey - received key 2 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low eom 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> 04 13:26:22 T:3058690928 DEBUG: CecLogMessage - << POLL: TV (0) -> TV (0) 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:8 ack:low eom 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> 08 13:26:22 T:3058690928 DEBUG: CecLogMessage - << POLL: TV (0) -> TV (0) 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> 04:44:02 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:22 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:22 T:3058690928 DEBUG: CecLogMessage - key pressed: 2 13:26:22 T:13420352 DEBUG: GetNextKey - received key 2 13:26:22 T:13420352 DEBUG: OnKey: 167 (a7) pressed, action is Down 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:22 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:22 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:22 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:22 T:3058690928 DEBUG: CecLogMessage - key released: 2 13:26:22 T:13420352 DEBUG: GetNextKey - received key 2 13:26:23 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:23 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:23 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:23 T:3058690928 DEBUG: CecLogMessage - >> 04:44:00 13:26:23 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:23 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:23 T:3058690928 DEBUG: CecLogMessage - key pressed: 0 13:26:23 T:13420352 DEBUG: GetNextKey - received key 0 13:26:23 T:13420352 DEBUG: OnKey: 11 (0b) pressed, action is Select 13:26:23 T:13420352 DEBUG: ExecuteXBMCAction : Translating ActivateWindow(ShutdownMenu) 13:26:23 T:13420352 DEBUG: ExecuteXBMCAction : To ActivateWindow(ShutdownMenu) 13:26:23 T:13420352 DEBUG: Activating window ID: 10111 13:26:23 T:13420352 DEBUG: ------ Window Init (DialogButtonMenu.xml) ------ 13:26:23 T:13420352 INFO: Loading skin file: DialogButtonMenu.xml 13:26:23 T:13420352 DEBUG: Load DialogButtonMenu.xml: 11.57ms 13:26:23 T:13420352 DEBUG: Alloc resources: 12.78ms (11.82 ms skin load) 13:26:23 T:13420352 DEBUG: ExecuteXBMCAction : Translating down 13:26:23 T:13420352 DEBUG: ExecuteXBMCAction : To down 13:26:23 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:23 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:23 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:23 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:23 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:23 T:3058690928 DEBUG: CecLogMessage - key released: 0 13:26:23 T:13420352 DEBUG: GetNextKey - received key 0 13:26:24 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:24 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:24 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:24 T:3058690928 DEBUG: CecLogMessage - >> 04:44:02 13:26:24 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:24 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:24 T:3058690928 DEBUG: CecLogMessage - key pressed: 2 13:26:24 T:13420352 DEBUG: GetNextKey - received key 2 13:26:24 T:13420352 DEBUG: OnKey: 167 (a7) pressed, action is Down 13:26:24 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:24 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:24 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:24 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:24 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:24 T:3058690928 DEBUG: CecLogMessage - key released: 2 13:26:24 T:13420352 DEBUG: GetNextKey - received key 2 13:26:25 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:25 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:25 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:25 T:3058690928 DEBUG: CecLogMessage - >> 04:44:02 13:26:25 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:25 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:25 T:3058690928 DEBUG: CecLogMessage - key pressed: 2 13:26:25 T:13420352 DEBUG: GetNextKey - received key 2 13:26:25 T:13420352 DEBUG: OnKey: 167 (a7) pressed, action is Down 13:26:25 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:25 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:25 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:25 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:25 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:25 T:3058690928 DEBUG: CecLogMessage - key released: 2 13:26:25 T:13420352 DEBUG: GetNextKey - received key 2 13:26:26 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:26 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:26 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 02 eom 13:26:26 T:3058690928 DEBUG: CecLogMessage - >> 04:44:02 13:26:26 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:26 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:26 T:3058690928 DEBUG: CecLogMessage - key pressed: 2 13:26:26 T:13420352 DEBUG: GetNextKey - received key 2 13:26:26 T:13420352 DEBUG: OnKey: 167 (a7) pressed, action is Down 13:26:26 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:26 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 45 eom 13:26:26 T:3058690928 DEBUG: CecLogMessage - >> 04:45 13:26:26 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 13:26:26 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=45 13:26:26 T:3058690928 DEBUG: CecLogMessage - key released: 2 13:26:26 T:13420352 DEBUG: GetNextKey - received key 2 13:26:32 T:3067083632 DEBUG: CecLogMessage - FRAME_START initiator:0 destination:4 ack:low 13:26:32 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 44 13:26:32 T:3067083632 DEBUG: CecLogMessage - FRAME_DATA 00 eom 13:26:32 T:3058690928 DEBUG: CecLogMessage - >> 04:44:00 13:26:32 T:3058690928 DEBUG: CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 13:26:32 T:3058690928 DEBUG: CecCommand - processing command: initiator=0 destination=4 opcode=44 13:26:32 T:3058690928 DEBUG: CecLogMessage - key pressed: 0 13:26:32 T:13420352 DEBUG: GetNextKey - received key 0 13:26:32 T:13420352 DEBUG: OnKey: 11 (0b) pressed, action is Select 13:26:32 T:13420352 DEBUG: ExecuteXBMCAction : Translating XBMC.Suspend() 13:26:32 T:13420352 DEBUG: ExecuteXBMCAction : To XBMC.Suspend() 13:26:32 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.UPower and method AboutToSleep 13:26:32 T:13420352 DEBUG: DBus: Creating message to org.freedesktop.UPower on /org/freedesktop/UPower with interface org.freedesktop.UPower and method Suspend 13:26:32 T:13420352 DEBUG: ------ Window Deinit (DialogButtonMenu.xml) ------ 13:26:32 T:13420352 DEBUG: CAnnouncementManager - Announcement: OnSleep from xbmc 13:26:32 T:13420352 DEBUG: GOT ANNOUNCEMENT, type: 4, from xbmc, message OnSleep 13:26:32 T:2905574256 DEBUG: CecLogMessage - SetConfiguration - using physical address ' 0' 13:26:32 T:2905574256 DEBUG: CecLogMessage - SetConfiguration - using base device '0' 13:26:32 T:2905574256 DEBUG: CecLogMessage - SetConfiguration - using HDMI port '1' 13:26:32 T:2905574256 DEBUG: CecLogMessage - >> Playback 1 (4): osd name set to 'XBMC' 13:26:32 T:2905574256 DEBUG: CecLogMessage - << Playback 1 (4) -> TV (0): OSD name 'XBMC' 13:26:32 T:2905574256 DEBUG: CecLogMessage - << 40:47:58:42:4d:43 13:26:32 T:3067083632 DEBUG: CecLogMessage - setting the line timeout to 3 13:26:32 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 0 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - command sent 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 6 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 5 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 4 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 3 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 2 more 13:26:32 T:3067083632 DEBUG: CecLogMessage - COMMAND_ACCEPTED - waiting for 1 more 13:26:32 T:2893491056 DEBUG: CecLogMessage - key released: 0 13:26:34 T:2955930480 DEBUG: Thread Jobworker 2955930480 terminating (autodelete) 13:26:34 T:3037723504 DEBUG: Thread Jobworker 3037723504 terminating (autodelete) 13:26:34 T:2913966960 DEBUG: Thread Jobworker 2913966960 terminating (autodelete) 13:27:29 T:2947537776 DEBUG: Thread Jobworker 2947537776 terminating (autodelete)