Fr Mär 3 11:52:35 2017 Feb 27 2017 XionHDF/skin.xml 2017-02-26 5.5 ultimo bmem=192M@64M console=ttyS0,1000000 ubi.mtd=rootfs root=ubi0:dreambox-rootfs rootfstype=ubifs rw NIM Socket 0: dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 11:51:03.3320 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 11:51:03.3332 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok unhandled DVBAPI Video Event 32 11:51:03.3348 [ ] dvb/pmt.cpp:187 PATready PATready 11:51:03.3350 [ ] dvb/pmt.cpp:198 PATready PAT TSID: 0x000a (10) 11:51:03.3352 [ ] dvb/pmt.cpp:227 PATready use pmtpid 006c for service_id 003f 11:51:03.4825 [ ] service/servicedvb.cpp:1218 serviceEvent eventNewProgramInfo 0 0 11:51:03.4952 < > service/servicedvb.cpp:2788 updateDecoder have 1 video stream(s) (0cff), and 1 audio stream(s) (0d00), and the pcr pid is 0cff, and the text pid is 002c 11:51:03.4960 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=3327, apid=3328 11:51:03.5193 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 11:51:03.5197 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 50 11:51:03.5201 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 11:51:03.5778 [ ] dvb_ci/dvbci.cpp:867 gotPMT [eDVBCIInterfaces] gotPMT 11:51:03.6464 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 11:51:03.6686 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok unhandled DVBAPI Video Event 32 11:51:03.7596 [ ] dvb/pmt.cpp:737 SDTScanEvent sdt update done! 11:51:03.8262 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: Setting EPG language: de_DE 11:51:03.9787 [ ] dvb/dvbtime.cpp:416 updateTime [eDVBLocalTimerHandler] Transponder time is 03.03.2017 11:51:03 11:51:03.9790 [ ] dvb/dvbtime.cpp:429 updateTime [eDVBLocalTimerHandler] diff is 0 11:51:03.9791 [ ] dvb/dvbtime.cpp:432 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time 11:51:03.9792 [ ] dvb/dvbtime.cpp:493 updateTime [eDVBLocalTimerHandler] not changed 11:51:04.0754 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 11:51:04.0784 { } Components/AVSwitch.py:335 setPolicy169 [VideoMode] setting policy2: scale 11:51:04.1055 < > dvb/decoder.cpp:540 video_event VIDEO_GET_EVENT - ok 11:51:04.2815 [ ] dvb/pmt.cpp:237 AITready AITready 11:51:05.0525 [ ] dvb/epgcache.cpp:1558 startEPG [EPGC] start caching events 11:51:05.3698 { } Plugins/Extensions/HbbTV/aitreader.py:96 doDump Name : DMAX 11:51:05.3703 { } Plugins/Extensions/HbbTV/aitreader.py:97 doDump URL : http://cdn.hbbtv.smartclip.net/loader.html?channel=dmax.hbbtv.x.de.smartclip 11:51:05.3707 { } Plugins/Extensions/HbbTV/aitreader.py:98 doDump OrgID : 33 11:51:05.3712 { } Plugins/Extensions/HbbTV/aitreader.py:99 doDump AppID : 39 11:51:05.3716 { } Plugins/Extensions/HbbTV/aitreader.py:100 doDump Control Code : 1 11:51:05.3720 { } Plugins/Extensions/HbbTV/aitreader.py:101 doDump Profile Code : 0 11:51:05.3723 { } Plugins/Extensions/HbbTV/aitreader.py:102 doDump 11:51:05.4011 { } Components/AVSwitch.py:328 setPolicy43 [VideoMode] setting policy: panscan 11:51:05.4037 { } Components/AVSwitch.py:335 setPolicy169 [VideoMode] setting policy2: scale 11:51:05.5202 { } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:54 [VLC] Checking for buildin servicets ... no 11:51:05.5222 { E } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:59 [VLC] Checking for existing and usable servicets.so ... No module named servicets 11:51:05.5230 { } Plugins/Extensions/EnhancedMovieCenter/VlcPluginInterface.py:62 [VLC] Checking for usable gstreamer service ... yes 11:51:05.7907 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: Next trashcan cleanup in 789 minutes 11:51:06.2450 { } Plugins/Extensions/PermanentTimeshift/plugin.py:1223 ptsCleanTimeshiftFolder PTS-Plugin: Erasing stranded timeshift pts_livebuffer.1 11:51:06.2471 [ ] base/ioprio.cpp:83 setIoPrio setIoPrio best-effort level 7 ok 11:51:06.2474 [ ] components/file_eraser.cpp:85 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift//pts_livebuffer.1.del' 11:51:07.0977 [ ] components/file_eraser.cpp:85 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift//pts_livebuffer.1.meta.del' 11:51:07.7933 { } Plugins/Extensions/EnhancedMovieCenter/EMCFileCache.py:214 delPathFromCache EMC delPathFromCache /media/hdd/movie/trashcan 11:51:07.7945 { } Plugins/Extensions/EnhancedMovieCenter/EMCTasker.py:43 emcDebugOut EMC: [EMCMS] trashcan cleanup: nothing to delete... 11:51:08.2470 { } skin.py:971 readSkin [SKIN] Parsing embedded skin 11:51:08.2521 { } skin.py:1158 readSkin [SKIN] processing screen : 11:51:08.2908 [ ] service/servicedvb.cpp:2405 startTimeshift Start timeshift! 11:51:08.2966 [ ] service/servicedvb.cpp:2436 startTimeshift recording to /media/hdd/timeshift/timeshift.cP5yZw 11:51:08.2988 [ ] base/ioprio.cpp:83 setIoPrio setIoPrio realtime level 7 ok 11:51:08.2991 [ ] base/filepush.cpp:372 thread [eFilePushThreadRecorder] THREAD START 11:51:08.3154 { } Plugins/Extensions/PermanentTimeshift/plugin.py:1425 ptsCreateEITFile PTS Plugin: /usr/lib/enigma2/python/Plugins/Extensions/PermanentTimeshift/eitsave.so: undefined symbol: _ZN9eEPGCache10cache_lockE 11:51:09.5062 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:12.0587 [ ] dvb/epgcache.cpp:1748 abortNonAvail [EPGC] abort non avail schedule other reading 11:51:12.0591 [ ] dvb/epgcache.cpp:1772 abortNonAvail [EPGC] abort non avail netmed schedule reading 11:51:12.0594 [ ] dvb/epgcache.cpp:1779 abortNonAvail [EPGC] abort non avail netmed schedule other reading 11:51:12.0596 [ ] dvb/epgcache.cpp:1788 abortNonAvail [EPGC] abort non avail FreeSat schedule_other reading 11:51:12.0598 [ ] dvb/epgcache.cpp:1799 abortNonAvail [EPGC] abort non avail viasat reading 11:51:12.0853 < > dvb/epgcache.cpp:2046 readData [EPGC] nownext 11:51:12.0855 [ ] dvb/epgcache.cpp:2087 readData finished(1488538272) 11:51:17.3397 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:51:17.4327 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:24.6508 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:31.0010 { } Plugins/Extensions/EPGRefresh/EPGRefreshTimer.py:71 activate [EPGRefresh] Not in timespan, ending timer 11:51:32.4781 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:51:32.5712 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:33.2248 < > dvb/epgcache.cpp:2046 readData [EPGC] schedule 11:51:33.2250 [ ] dvb/epgcache.cpp:2087 readData finished(1488538293) 11:51:33.2254 [ ] dvb/epgcache.cpp:1537 finishEPG [EPGC] stop caching events 11:51:33.2255 [ ] dvb/epgcache.cpp:1539 finishEPG [EPGC] next update in 60 min 11:51:39.7808 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:47.6118 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:51:47.7058 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:51:54.9180 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:02.7464 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:52:02.8395 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:10.0575 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:17.8882 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:52:17.9817 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:25.2024 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:33.0453 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 00 11:52:33.1377 < > driver/hdmi_cec.cpp:373 hdmiEvent eHdmiCEC: received message 89 01 11:52:35.7751 [ ] driver/rcinput.cpp:23 handleCode 1 a5 1 11:52:35.7771 { } Components/ActionMap.py:46 action action -> InfobarTimeshiftActivateActions timeshiftActivateEnd 11:52:35.7780 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=-1, apid=-1 11:52:35.7781 < > dvb/decoder.cpp:723 stop DEMUX_STOP - pcr - ok 11:52:35.7789 < > dvb/decoder.cpp:425 stop DEMUX_STOP - video - ok 11:52:35.7812 < > dvb/decoder.cpp:434 stop VIDEO_STOP - ok 11:52:35.7823 < > dvb/decoder.cpp:159 stop AUDIO_STOP - ok 11:52:35.8026 < > dvb/decoder.cpp:167 stop DEMUX_STOP - audio - ok 11:52:35.8058 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 11:52:35.8062 < > dvb/decoder.cpp:800 stop DEMUX_STOP - ttx - ok 11:52:35.8175 [ ] dvb/pmt.cpp:806 tuneExt alloc PVR 11:52:35.8177 [ ] dvb/dvb.cpp:1038 allocateDemux allocate demux 11:52:35.8182 [ ] dvb/tstools.cpp:96 setSource loading streaminfo for /media/hdd/timeshift/timeshift.cP5yZw 11:52:35.8185 [ E ] base/cfile.h:17 CFile error /media/hdd/timeshift/timeshift.cP5yZw.ap [No such file or directory] 11:52:35.8193 [ ] base/ioprio.cpp:83 setIoPrio setIoPrio best-effort level 0 ok 11:52:35.8193 [ ] dvb/pmt.cpp:67 channelStateChanged ok ... now we start!! 11:52:35.8195 [ ] base/filepush.cpp:60 thread FILEPUSH THREAD START 11:52:35.8196 [ ] service/servicedvb.cpp:1254 serviceEventTimeshift eventNewProgramInfo TS 11:52:35.8197 < > service/servicedvb.cpp:2788 updateDecoder have 1 video stream(s) (0cff), and 1 audio stream(s) (0d00), and the pcr pid is 0cff, and the text pid is 002c 11:52:35.8204 [ ] dvb/dvb.cpp:1994 getNextSourceSpan seeking relative to end. len=7869600, seek = -1000 11:52:35.8208 [ ] dvb/tstools.cpp:651 takeSamples samples step 41942988, pts begin 1322049066, pts end 1329918666, offs begin 2274, offs end 46354909: 11:52:35.8216 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=2274 pts=1322049066 11:52:35.8219 [ ] dvb/tstools.cpp:694 takeSample adding sample 2274: pts 0 -> pos 2274 (diff 0 bytes) 11:52:35.8223 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8226 [ ] dvb/tstools.cpp:694 takeSample adding sample 41945262: pts 6318000 -> pos 38559758 (diff -3385504 bytes) 11:52:35.8227 [ ] dvb/tstools.cpp:411 getOffset using: 6318000:7869600 -> 38559758:46354909 11:52:35.8230 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8232 [ ] dvb/tstools.cpp:694 takeSample adding sample 46349708: pts 6318000 -> pos 38559758 (diff -7789950 bytes) 11:52:35.8233 [ ] dvb/tstools.cpp:444 getOffset calculated diff -17228 ms 11:52:35.8234 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 11:52:35.8234 [ ] dvb/tstools.cpp:411 getOffset using: 6318000:7869600 -> 38559758:46354909 11:52:35.8236 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8237 [ ] dvb/tstools.cpp:694 takeSample adding sample 46349708: pts 6318000 -> pos 38559758 (diff -7789950 bytes) 11:52:35.8238 [ ] dvb/tstools.cpp:444 getOffset calculated diff -17228 ms 11:52:35.8239 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 11:52:35.8241 [ ] dvb/tstools.cpp:411 getOffset using: 6318000:7869600 -> 38559758:46354909 11:52:35.8242 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8243 [ ] dvb/tstools.cpp:694 takeSample adding sample 46349708: pts 6318000 -> pos 38559758 (diff -7789950 bytes) 11:52:35.8244 [ ] dvb/tstools.cpp:444 getOffset calculated diff -17228 ms 11:52:35.8245 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 11:52:35.8246 [ ] dvb/tstools.cpp:411 getOffset using: 6318000:7869600 -> 38559758:46354909 11:52:35.8247 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8249 [ ] dvb/tstools.cpp:694 takeSample adding sample 46349708: pts 6318000 -> pos 38559758 (diff -7789950 bytes) 11:52:35.8251 [ ] dvb/tstools.cpp:444 getOffset calculated diff -17228 ms 11:52:35.8252 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 11:52:35.8254 [ ] dvb/tstools.cpp:411 getOffset using: 6318000:7869600 -> 38559758:46354909 11:52:35.8256 [ ] dvb/tstools.cpp:124 getPTS eDVBTSTools::getPTS got it from sc file offset=38559758 pts=1328367066 11:52:35.8257 [ ] dvb/tstools.cpp:694 takeSample adding sample 46349708: pts 6318000 -> pos 38559758 (diff -7789950 bytes) 11:52:35.8258 [ ] dvb/tstools.cpp:444 getOffset calculated diff -17228 ms 11:52:35.8260 [ ] dvb/tstools.cpp:447 getOffset diff to big, refining 11:52:35.8261 [ ] dvb/tstools.cpp:461 getOffset aborting. Taking 46349708 as offset for 6318000 11:52:35.8264 [ ] dvb/dvb.cpp:2040 getNextSourceSpan ok, resolved skip (rel: 0, diff 6318000), now at 02c33d8c 11:52:35.8296 [ ] dvb/decoder.cpp:829 setState decoder state: play, vpid=3327, apid=3328 11:52:35.8306 < > dvb/decoder.cpp:84 startPid DMX_SET_PES_FILTER(0xd00) - audio - ok 11:52:35.8308 < > dvb/decoder.cpp:92 startPid DEMUX_START - audio - ok 11:52:35.8312 < > dvb/decoder.cpp:138 startPid AUDIO_SET_BYPASS(1) - ok 11:52:35.8349 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 11:52:35.8352 < > dvb/decoder.cpp:146 startPid AUDIO_PLAY - ok 11:52:35.8378 [ ] dvb/decoder.cpp:267 eDVBVideo Video Device: /dev/dvb/adapter0/video0 11:52:35.8380 [ ] dvb/decoder.cpp:274 eDVBVideo demux device: /dev/dvb/adapter0/demux4 11:52:35.8391 < > dvb/decoder.cpp:354 startPid VIDEO_SET_STREAMTYPE 0 - ok 11:52:35.8393 < > dvb/decoder.cpp:389 startPid DMX_SET_PES_FILTER(0xcff) - video - ok 11:52:35.8395 < > dvb/decoder.cpp:397 startPid DEMUX_START - video - ok 11:52:35.8411 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 11:52:35.8413 < > dvb/decoder.cpp:412 startPid VIDEO_PLAY - ok 11:52:35.8488 < > dvb/decoder.cpp:779 startPid DMX_SET_PES_FILTER(0x2c) - ttx - ok 11:52:35.8490 < > dvb/decoder.cpp:787 startPid DEMUX_START - ttx - ok 11:52:35.8544 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 11:52:35.8547 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 11:52:35.8548 < > dvb/decoder.cpp:470 unfreeze VIDEO_CONTINUE - ok 11:52:35.8550 < > dvb/decoder.cpp:203 unfreeze AUDIO_CONTINUE - ok 11:52:35.8552 [ ] dvb/teletext.cpp:640 setPageAndMagazine disable teletext subtitles page ffffffffffffffff (und) 11:52:35.8761 { } Plugins/Extensions/Volume_adjust/plugin.py:469 __evUpdatedInfo [Volume Adjust] Update Info 11:52:35.8765 { } Plugins/Extensions/Volume_adjust/plugin.py:473 __evUpdatedInfo [Volume Adjust] Volume = 50 11:52:35.8769 { } Plugins/Extensions/Volume_adjust/plugin.py:447 loadXML [Volume Adjust] load xml... 11:52:35.8791 [ ] service/servicedvb.cpp:2771 switchToTimeshift eDVBServicePlay::switchToTimeshift, in pause mode now. 11:52:35.8792 [ ] service/servicedvb.cpp:1624 pause eDVBServicePlay::pause 11:52:35.8793 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=3327, apid=3328 11:52:35.8794 < > dvb/decoder.cpp:482 setSlowMotion VIDEO_SLOWMOTION(0) - ok 11:52:35.8795 < > dvb/decoder.cpp:497 setFastForward VIDEO_FAST_FORWARD(0) - ok 11:52:35.8797 < > dvb/decoder.cpp:458 freeze VIDEO_FREEZE - ok 11:52:35.8798 < > dvb/decoder.cpp:191 freeze AUDIO_PAUSE - ok 11:52:35.8812 < > service/servicedvb.cpp:2788 updateDecoder have 1 video stream(s) (0cff), and 1 audio stream(s) (0d00), and the pcr pid is 0cff, and the text pid is 002c 11:52:35.8820 [ ] dvb/decoder.cpp:829 setState decoder state: pause, vpid=3327, apid=3328 11:52:35.8878 [ ] service/servicedvb.cpp:1624 pause eDVBServicePlay::pause 11:52:35.8898 [ E ] python/python.cpp:209 call (PyObject_CallObject(>,('InfobarTimeshiftActivateActions', 'timeshiftActivateEnd')) failed) 11:52:36.0776 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 11:52:36.3362 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 11:52:36.5906 [ ] base/filepush.cpp:159 thread wait for driver eof timeout 11:52:36.8424 [ ] base/filepush.cpp:159 thread wait for driver eof timeout ]]>