Fr Jan 20 09:07:49 2017 Jan 19 2017 XionHDF/skin.xml 2017-01-17 5.5 hd1500 ubi.mtd=rootfs rootfstype=ubifs root=ubi0:rootfs bmem=192M@64M console=null NIM Socket 0: driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 04 09:04:58.4361 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 82 10 00 09:04:58.4366 < > driver/hdmi_cec.cpp:526 sendMessage eHdmiCEC: send message 8E 00 09:04:58.4519 [ E ] service/servicedvb.cpp:1783 timeshift statfs failed! 09:04:58.4771 [ ] dvb/frontend.cpp:1871 tuneLoopInt [SEC] set static current limiting 09:04:58.4788 [ ] dvb/frontend.cpp:1540 tuneLoopInt [SEC] tuner 0 setVoltage 1 09:04:58.4794 [ ] dvb/frontend.cpp:1531 tuneLoopInt [SEC] tuner 0 sleep 200ms 09:04:58.6796 [ ] dvb/frontend.cpp:1820 tuneLoopInt [SEC] invalidate current switch params 09:04:58.6799 < > dvb/frontend.cpp:1588 tuneLoopInt [SEC] tuner 0 sendDiseqc: e00000(DiSEqC reset) 09:04:58.6801 < > dvb/frontend.cpp:1599 tuneLoopInt [SEC] diseqc ioctl duration: 0 ms -> extra guard delay 52 ms 09:04:58.7322 [ ] dvb/frontend.cpp:1531 tuneLoopInt [SEC] tuner 0 sleep 50ms 09:04:58.7824 < > dvb/frontend.cpp:1588 tuneLoopInt [SEC] tuner 0 sendDiseqc: e00003(DiSEqC peripherial power on) 09:04:58.7826 < > dvb/frontend.cpp:1599 tuneLoopInt [SEC] diseqc ioctl duration: 0 ms -> extra guard delay 52 ms 09:04:58.8348 [ ] dvb/frontend.cpp:1531 tuneLoopInt [SEC] tuner 0 sleep 150ms 09:04:58.9850 < > dvb/frontend.cpp:1588 tuneLoopInt [SEC] tuner 0 sendDiseqc: e01038f5 09:04:58.9853 < > dvb/frontend.cpp:1599 tuneLoopInt [SEC] diseqc ioctl duration: 0 ms -> extra guard delay 66 ms 09:04:59.0514 [ ] dvb/frontend.cpp:1531 tuneLoopInt [SEC] tuner 0 sleep 100ms 09:04:59.1517 [ ] dvb/frontend.cpp:1830 tuneLoopInt [SEC] update current switch params 09:04:59.1519 [ ] dvb/frontend.cpp:1642 tuneLoopInt [SEC] tuner 0 startTuneTimeout 5000 09:04:59.1521 [ ] dvb/frontend.cpp:1635 tuneLoopInt [SEC] tuner 0 setFrontend: events enabled 09:04:59.1522 [ ] dvb/frontend.cpp:2042 setFrontend setting frontend 0 events: on 09:04:59.1644 [ ] dvb/frontend.cpp:808 feEvent (0)fe event: status 0, inversion off, m_tuning 1 09:04:59.1646 [ ] dvb/frontend.cpp:1531 tuneLoopInt [SEC] tuner 0 sleep 500ms 09:04:59.2640 [ ] dvb/frontend.cpp:808 feEvent (0)fe event: status 1f, inversion off, m_tuning 2 09:04:59.2642 [ ] dvb/dvb.cpp:1718 frontendStateChanged OURSTATE: tuner 0 ok 09:04:59.2643 [ ] dvb/dvbtime.cpp:588 DVBChannelStateChanged [eDVBLocalTimerHandler] channel 0x1441510 running 09:04:59.2651 [ ] dvb/epgcache.cpp:644 DVBChannelStateChanged [eEPGCache] channel 0x1441510 running 09:04:59.2658 [ ] dvb/dvb.cpp:1333 DVBChannelStateChanged stop release channel timer 09:04:59.2660 [ ] dvb/pmt.cpp:67 channelStateChanged ok ... now we start!! 09:04:59.2661 [ ] service/servicedvb.cpp:1218 serviceEvent eventNewProgramInfo 0 0 09:04:59.2662 < > service/servicedvb.cpp:2788 updateDecoder have 1 video stream(s) (012d), and 1 audio stream(s) (0137), and the pcr pid is 012d, and the text pid is ffffffff 09:04:59.2664 [ ] dvb/dvb.cpp:1038 allocateDemux allocate demux 09:04:59.2672 [ ] dvb/epgcache.cpp:1854 startChannel [EPGC] next update in 2 sec 09:04:59.2738 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=301, apid=311 09:04:59.2741 < > dvb/decoder.cpp:702 startPid DMX_SET_PES_FILTER(0x12d) - pcr - ok 09:04:59.2742 < > dvb/decoder.cpp:710 startPid DEMUX_START - pcr - ok 09:04:59.2746 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0x137) - audio - ok 09:04:59.2747 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 09:04:59.2750 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 09:04:59.2751 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 09:04:59.2752 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 09:04:59.4061 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 09:04:59.4064 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux0 09:04:59.4066 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 1 - ok 09:04:59.4067 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0x12d) - video - ok 09:04:59.4068 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 09:04:59.4072 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 09:04:59.4073 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 09:04:59.4156 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 09:04:59.4157 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 09:04:59.4159 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 09:04:59.4160 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 09:04:59.4162 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 09:04:59.4265 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 09:04:59.4270 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 37 09:04:59.4274 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 09:04:59.4377 [ ] dvb/cahandler.cpp:263 registerService [eDVBCAService] new service 1:0:1:C1D:1E78:71:820000:0:0:0: 09:04:59.4380 [ ] dvb/cahandler.cpp:281 registerService [eDVBCAService] add demux 0 to slot 0 service 1:0:1:C1D:1E78:71:820000:0:0:0: 09:04:59.4973 { E } Tools/Notifications.py:36 RemovePopup RemovePopup, id = ZapError 09:04:59.5185 [ ] dvb/dvbtime.cpp:416 updateTime [eDVBLocalTimerHandler] Transponder time is 20.01.2017 09:04:59 09:04:59.5187 [ ] dvb/dvbtime.cpp:429 updateTime [eDVBLocalTimerHandler] diff is 0 09:04:59.5188 [ ] dvb/dvbtime.cpp:432 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time 09:04:59.5200 [ ] dvb/dvbtime.cpp:493 updateTime [eDVBLocalTimerHandler] not changed 09:04:59.5245 [ ] dvb/pmt.cpp:187 PATready PATready 09:04:59.5253 [ ] dvb/pmt.cpp:198 PATready PAT TSID: 0x1e78 (7800) 09:04:59.5261 [ ] dvb/pmt.cpp:227 PATready use pmtpid 0032 for service_id 0c1d 09:04:59.6650 [ ] dvb/frontend.cpp:1871 tuneLoopInt [SEC] set dynamic current limiting 09:04:59.9300 [ ] service/servicedvb.cpp:1218 serviceEvent eventNewProgramInfo 0 0 09:04:59.9330 < > service/servicedvb.cpp:2788 updateDecoder have 1 video stream(s) (012d), and 1 audio stream(s) (0137), and the pcr pid is 012d, and the text pid is ffffffff 09:04:59.9337 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=301, apid=311 09:04:59.9338 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 09:04:59.9340 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 09:04:59.9341 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 09:04:59.9342 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 09:04:59.9495 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 09:04:59.9500 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 37 09:04:59.9503 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 09:04:59.9604 [ ] dvb_ci/dvbci.cpp:867 gotPMT [eDVBCIInterfaces] gotPMT 09:04:59.9606 [ ] dvb/cahandler.cpp:578 buildCAPMT [eDVBCAService] don't build/send the same CA PMT twice 09:05:00.1263 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 09:05:00.1275 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 09:05:00.1367 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 09:05:00.2220 [ ] dvb/pmt.cpp:737 SDTScanEvent sdt update done! 09:05:00.5414 { } Components/AVSwitch.py:322 setWss [VideoMode] setting wss: auto 09:05:00.5426 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: auto 09:05:00.5438 { } Components/AVSwitch.py:335 setPolicy169 [VideoMode] setting policy2: scale 09:05:01.2759 [ ] dvb/epgcache.cpp:1558 startEPG [EPGC] start caching events 09:05:04.8023 { } Components/ActionMap.py:46 action action -> InfobarMenuActions mainMenu 09:05:04.8309 { } skin.py:1158 readSkin [SKIN] processing screen menu_mainmenu: 09:05:04.8884 { } skin.py:1158 readSkin [SKIN] processing screen MenuSummary: 09:05:07.7382 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:05:08.2813 [ ] dvb/epgcache.cpp:1772 abortNonAvail [EPGC] abort non avail netmed schedule reading 09:05:08.2821 [ ] dvb/epgcache.cpp:1779 abortNonAvail [EPGC] abort non avail netmed schedule other reading 09:05:08.2824 [ ] dvb/epgcache.cpp:1788 abortNonAvail [EPGC] abort non avail FreeSat schedule_other reading 09:05:08.2828 [ ] dvb/epgcache.cpp:1799 abortNonAvail [EPGC] abort non avail viasat reading 09:05:10.3291 < > dvb/epgcache.cpp:2046 readData [EPGC] nownext 09:05:10.3293 [ ] dvb/epgcache.cpp:2087 readData finished(1484899510) 09:05:12.0541 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 09:05:15.0647 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:05:23.4086 { } Components/ActionMap.py:46 action action -> InfobarMenuActions mainMenu 09:05:23.4379 { } skin.py:1158 readSkin [SKIN] processing screen menu_mainmenu: 09:05:23.4744 { } skin.py:1158 readSkin [SKIN] processing screen MenuSummary: 09:05:26.2695 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:05:40.0676 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:05:43.5364 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:05:47.4445 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:11.6616 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:12.5084 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 09:06:12.5227 { } skin.py:1158 readSkin [SKIN] processing screen menu_information: 09:06:12.5548 { } skin.py:1158 readSkin [SKIN] processing screen MenuSummary: 09:06:15.3886 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:18.8915 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 09:06:19.1587 { E } Tools/StbHardware.py:22 getFPVersion getFPVersion failed! 09:06:19.1635 { } skin.py:1158 readSkin [SKIN] processing screen AboutOE: 09:06:19.1694 { E } skin.py:1126 process_screen [Skin] SKIN ERROR in screen 'AboutOE' widget 'widget': {XionHDF/skin.xml}: source 'lab3' was not found in screen 'AboutOE'!. Please contact the skin's author! 09:06:19.2043 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element key_green in 09:06:19.2058 { W } skin.py:374 applyAll [Skin] Attribute not implemented: scrollbarMode value: showNever 09:06:19.2308 { W } skin.py:374 applyAll [Skin] Attribute not implemented: render value: Label 09:06:19.2465 { } skin.py:987 readSkin [SKIN] No skin to read... 09:06:19.2483 { } skin.py:1158 readSkin [SKIN] processing screen : 09:06:22.1406 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:38.3895 < > dvb/epgcache.cpp:2046 readData [EPGC] schedule other 09:06:38.3898 [ ] dvb/epgcache.cpp:2087 readData finished(1484899598) 09:06:39.5879 < > dvb/epgcache.cpp:2046 readData [EPGC] schedule 09:06:39.5881 [ ] dvb/epgcache.cpp:2087 readData finished(1484899599) 09:06:39.5886 [ ] dvb/epgcache.cpp:1537 finishEPG [EPGC] stop caching events 09:06:39.5888 [ ] dvb/epgcache.cpp:1539 finishEPG [EPGC] next update in 60 min 09:06:46.5620 { } Components/ActionMap.py:46 action action -> SetupActions cancel 09:06:49.4065 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:55.5894 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:06:59.1991 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 09:06:59.2074 { } skin.py:1158 readSkin [SKIN] processing screen Devices: 09:06:59.2307 { W } skin.py:374 applyAll [Skin] Attribute not implemented: enableWrapAround value: 0 09:06:59.2338 { W } skin.py:374 applyAll [Skin] Attribute not implemented: enableWrapAround value: 0 09:06:59.2371 { W } skin.py:374 applyAll [Skin] Attribute not implemented: enableWrapAround value: 0 09:06:59.2577 { } skin.py:987 readSkin [SKIN] No skin to read... 09:06:59.2593 { } skin.py:1158 readSkin [SKIN] processing screen : 09:06:59.3785 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 69(16) 09:07:02.0943 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:06.0588 { } Components/ActionMap.py:46 action action -> SetupActions cancel 09:07:08.8756 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:11.8317 { } Components/ActionMap.py:46 action action -> OkCancelActions cancel 09:07:14.6622 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:22.5535 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:23.5702 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 09:07:23.5810 { } skin.py:1158 readSkin [SKIN] processing screen PluginBrowser: 09:07:23.6077 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element blue in 09:07:23.6113 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element green in 09:07:23.6125 { W } Components/GUISkin.py:35 createGUIScreen warning, skin is missing element red in 09:07:23.6413 { } skin.py:1158 readSkin [SKIN] processing screen PluginBrowserSummary: 09:07:26.6423 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:30.4376 { } Components/ActionMap.py:46 action action -> SetupActions cancel 09:07:30.4382 { W } Components/ActionMap.py:53 action unknown action SetupActions/cancel! typo in keymap? 09:07:30.4385 { } Components/ActionMap.py:46 action action -> WizardActions back 09:07:33.2706 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:38.4447 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:40.0913 { } Components/ActionMap.py:46 action action -> OkCancelActions ok 09:07:40.1025 { } skin.py:1158 readSkin [SKIN] processing screen menu_setup: 09:07:40.1356 { } skin.py:1158 readSkin [SKIN] processing screen MenuSummary: 09:07:42.9995 [ ] base/ebase.cpp:290 processOneEvent poll: unhandled POLLERR/HUP/NVAL for fd 68(16) 09:07:49.2570 { E } e2reactor.py:40 poll Traceback (most recent call last): 09:07:49.2574 { E } e2reactor.py:40 poll File "/usr/lib/python2.7/site-packages/twisted/web/server.py", line 220, in write 09:07:49.2584 { E } e2reactor.py:40 poll File "/usr/lib/python2.7/site-packages/twisted/web/http.py", line 955, in write 09:07:49.2593 { E } e2reactor.py:40 poll AttributeError: 'NoneType' object has no attribute 'writeSequence' 09:07:49.2872 [ ] python/python.cpp:209 call (PyObject_CallObject(>,('\x9d\xb5\xd0\x97\xcf\x9ae\x89\x80\xc7\xdb\x08\xde8>\x9d\xb1\x8c\xfb\xd7\xe6\xad\x87\xed]\xf1\xf7K\xbc\x8e\xfe\xc7\xc7\xfb&\x8c\xe5\x1f\xfb.\xd0\xe3\x8ct1b\xba=7\xf6\xf2\xfd\xa0-\x99\x8e\xaf}\xa4\xea\xa4\xc7\xb77\xbahO\x9b\xb3\xfe\xe4\xc7\xc8\x1czs\xd3\xbd+\x0e\xfd\x99\xfa\x05\'\xc4\xdb\x18@\xb8\xb7\xb4\xd4\xd2`\xd9\x91\xe3\xf1=\xcf\xce1\x8cm\xdd\x81\xdb\x91\xe9\xefXz\xaf\xc7\x9f\x88\xb6\x93,\xfaw\xc5\xbdn\xd6\x18\xdbs[\xcd \xbb\x8d\xd7<+\x19_\x8fN\x879\xaf\x94\xb4_\xdb\x87\xc3\x1a\xccSG\xe2\xab+\xab;\x96\x97\xfd\x16{k\x10bX\x82\xaf\x0c<\xc6m\xc5\xb7\xf4\x04`\xaf\xbdtz\xaf\xc4\xfd2\xf6(\xb5\x08\xae$\xb9W\x89\x1de\x10m\x1eY9<\x1c\x1c\xed\xc9\x1cw\x14J0\x9a\xe5\x92\xbac\x84\xaaS\x97<\x1d\x9a\xea\x8f\xa8|?\xfb`\xea\xde\x1a\x85$\xf1\xbf\x83\x13\xc5\xf6\xf2\x10\xb9\xd1Z(\xae \x04\x93\xe6\xc8\xb1B?\x87\x83\x96\xc6{\xf7\xafa\xf0G\x88\xfc\r\xf1_\xc2\x0f\x ]]>